"./sync.test -test.v -test.timeout 1h0m0s -remote TestMega: -verbose" - Starting (try 1/5) 2022/03/15 05:16:11 DEBUG : Creating backend with remote "TestMega:rclone-test-qugobit1lodelub4noliver0" 2022/03/15 05:16:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/03/15 05:16:20 DEBUG : Creating backend with remote "/tmp/rclone12525426" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.03s) === 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 "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:16:20 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:16:20 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:16:20 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) --- PASS: TestCopyWithDryRun (0.05s) === RUN TestCopy run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:16:20 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:16:20 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:16:21 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (0.92s) === RUN TestCopyMissingDirectory run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:16:21 DEBUG : Creating backend with remote "/non-existing" 2022/03/15 05:16:21 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2022/03/15 05:16:21 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:16:21 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.00s) === RUN TestCopyNoTraverse run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:16:21 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:16:21 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:16:25 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (6.48s) === RUN TestCopyCheckFirst run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:16:27 INFO : mega root 'rclone-test-qugobit1lodelub4noliver0': Running all checks before starting transfers 2022/03/15 05:16:27 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:16:27 INFO : mega root 'rclone-test-qugobit1lodelub4noliver0': Checks finished, now starting transfers 2022/03/15 05:16:27 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:16:28 INFO : sub dir/hello world: Copied (new) run.go:126: removing dir "sub dir" failed - try 1/3: directory not empty --- PASS: TestCopyCheckFirst (2.67s) === RUN TestSyncNoTraverse run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:16:30 ERROR : Ignoring --no-traverse with sync 2022/03/15 05:16:30 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:16:30 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:16:33 INFO : sub dir/hello world: Copied (new) 2022/03/15 05:16:33 DEBUG : Waiting for deletions to finish run.go:126: removing dir "sub dir" failed - try 1/3: directory not empty --- PASS: TestSyncNoTraverse (4.41s) === RUN TestCopyWithDepth run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:16:34 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:16:34 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:16:35 INFO : hello world2: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestCopyWithDepth (7.51s) === RUN TestCopyWithFilesFrom run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:16:42 DEBUG : hello world2: Excluded 2022/03/15 05:16:42 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:16:42 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:16:42 INFO : potato2: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestCopyWithFilesFrom (7.54s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:16:50 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:16:50 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:16:50 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (0.41s) === RUN TestCopyEmptyDirectories run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:16:50 DEBUG : sub dir2: Making directory 2022/03/15 05:16:50 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:16:50 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:16:52 INFO : sub dir/hello world: Copied (new) 2022/03/15 05:16:52 DEBUG : sub dir2: Making directory 2022/03/15 05:16:52 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': copied 1 directories --- PASS: TestCopyEmptyDirectories (5.47s) === RUN TestMoveEmptyDirectories run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:16:55 DEBUG : sub dir2: Making directory 2022/03/15 05:16:55 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:16:55 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:16:56 INFO : sub dir/hello world: Copied (new) 2022/03/15 05:16:56 INFO : sub dir/hello world: Deleted 2022/03/15 05:16:56 DEBUG : sub dir: Making directory 2022/03/15 05:16:56 DEBUG : sub dir2: Making directory 2022/03/15 05:16:58 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': copied 2 directories --- PASS: TestMoveEmptyDirectories (3.68s) === RUN TestSyncEmptyDirectories run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:16:59 DEBUG : sub dir2: Making directory 2022/03/15 05:16:59 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:16:59 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:17:01 INFO : sub dir/hello world: Copied (new) 2022/03/15 05:17:01 DEBUG : sub dir2: Making directory 2022/03/15 05:17:01 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': copied 1 directories 2022/03/15 05:17:01 DEBUG : Waiting for deletions to finish --- PASS: TestSyncEmptyDirectories (2.69s) === RUN TestServerSideCopy run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:17:05 DEBUG : Creating backend with remote "TestMega:rclone-test-wayapug4yipuyok5fakewok1" sync_test.go:275: Server side copy (if possible) mega root 'rclone-test-qugobit1lodelub4noliver0' -> mega root 'rclone-test-wayapug4yipuyok5fakewok1' 2022/03/15 05:17:05 DEBUG : mega root 'rclone-test-wayapug4yipuyok5fakewok1': Waiting for checks to finish 2022/03/15 05:17:05 DEBUG : mega root 'rclone-test-wayapug4yipuyok5fakewok1': Waiting for transfers to finish 2022/03/15 05:17:06 INFO : sub dir/hello world: Copied (new) 2022/03/15 05:17:06 DEBUG : mega root 'rclone-test-wayapug4yipuyok5fakewok1': Purge remote --- PASS: TestServerSideCopy (5.75s) === RUN TestCopyAfterDelete run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:17:08 ERROR : : error listing: directory not found 2022/03/15 05:17:08 DEBUG : Local file system at /tmp/rclone12525426: Making directory 2022/03/15 05:17:08 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:17:08 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish run.go:126: removing dir "sub dir" failed - try 1/3: directory not empty --- PASS: TestCopyAfterDelete (2.63s) === RUN TestCopyRedownload run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:17:13 DEBUG : Local file system at /tmp/rclone12525426: Waiting for checks to finish 2022/03/15 05:17:13 DEBUG : Local file system at /tmp/rclone12525426: Waiting for transfers to finish 2022/03/15 05:17:14 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (3.87s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:17:14 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:17:14 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:17:15 INFO : check sum: Copied (new) 2022/03/15 05:17:15 DEBUG : Waiting for deletions to finish 2022/03/15 05:17:15 NOTICE: mega root 'rclone-test-qugobit1lodelub4noliver0': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2022/03/15 05:17:15 DEBUG : check sum: Size of src and dst objects identical 2022/03/15 05:17:15 DEBUG : check sum: Unchanged skipping 2022/03/15 05:17:15 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:17:15 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:17:15 DEBUG : Waiting for deletions to finish 2022/03/15 05:17:15 INFO : There was nothing to transfer 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:293: Sleeping for 7s just to make sure --- PASS: TestSyncBasedOnCheckSum (10.69s) === RUN TestSyncSizeOnly run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:17:25 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:17:25 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:17:27 INFO : sizeonly: Copied (new) 2022/03/15 05:17:27 DEBUG : Waiting for deletions to finish 2022/03/15 05:17:27 DEBUG : sizeonly: Sizes identical 2022/03/15 05:17:27 DEBUG : sizeonly: Unchanged skipping 2022/03/15 05:17:27 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:17:27 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:17:27 DEBUG : Waiting for deletions to finish 2022/03/15 05:17:27 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncSizeOnly (9.16s) === RUN TestSyncIgnoreSize run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:17:34 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:17:34 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:17:34 INFO : ignore-size: Copied (new) 2022/03/15 05:17:34 DEBUG : Waiting for deletions to finish 2022/03/15 05:17:34 DEBUG : ignore-size: Sizes identical 2022/03/15 05:17:34 DEBUG : ignore-size: Unchanged skipping 2022/03/15 05:17:34 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:17:34 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:17:34 DEBUG : Waiting for deletions to finish 2022/03/15 05:17:34 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncIgnoreSize (7.64s) === RUN TestSyncIgnoreTimes run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:17:42 DEBUG : existing: Sizes identical 2022/03/15 05:17:42 DEBUG : existing: Unchanged skipping 2022/03/15 05:17:42 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:17:42 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:17:42 DEBUG : Waiting for deletions to finish 2022/03/15 05:17:42 INFO : There was nothing to transfer 2022/03/15 05:17:42 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/03/15 05:17:42 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:17:42 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:17:42 INFO : existing: Copied (replaced existing) 2022/03/15 05:17:42 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (3.12s) === RUN TestSyncIgnoreExisting run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:17:45 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:17:45 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:17:45 INFO : existing: Copied (new) 2022/03/15 05:17:45 DEBUG : Waiting for deletions to finish 2022/03/15 05:17:45 DEBUG : existing: Destination exists, skipping 2022/03/15 05:17:45 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:17:45 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:17:45 DEBUG : Waiting for deletions to finish 2022/03/15 05:17:45 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncIgnoreExisting (7.32s) === RUN TestSyncIgnoreErrors run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:17:53 DEBUG : d: Making directory 2022/03/15 05:17:53 DEBUG : c/non empty space: Sizes identical 2022/03/15 05:17:53 DEBUG : c/non empty space: Unchanged skipping 2022/03/15 05:17:53 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:17:53 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:17:54 INFO : a/potato2: Copied (new) 2022/03/15 05:17:54 DEBUG : Waiting for deletions to finish 2022/03/15 05:17:54 INFO : b/potato: Deleted 2022/03/15 05:17:54 INFO : d: Removing directory 2022/03/15 05:17:55 INFO : b: Removing directory 2022/03/15 05:17:55 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': deleted 2 directories --- PASS: TestSyncIgnoreErrors (3.93s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:17:56 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:17:56 DEBUG : empty space: Sizes identical 2022/03/15 05:17:56 DEBUG : empty space: Unchanged skipping 2022/03/15 05:17:56 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:17:56 DEBUG : Waiting for deletions to finish 2022/03/15 05:17:56 INFO : There was nothing to transfer 2022/03/15 05:17:56 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:17:56 DEBUG : empty space: Sizes identical 2022/03/15 05:17:56 DEBUG : empty space: Unchanged skipping 2022/03/15 05:17:56 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:17:56 DEBUG : Waiting for deletions to finish 2022/03/15 05:17:56 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncAfterChangingModtimeOnly (7.54s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" sync_test.go:577: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.00s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" sync_test.go:602: Can't run this test on fs which doesn't support mod time --- SKIP: TestSyncDoesntUpdateModtime (0.00s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:18:04 DEBUG : empty space: Sizes identical 2022/03/15 05:18:04 DEBUG : empty space: Unchanged skipping 2022/03/15 05:18:04 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:18:04 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:18:04 INFO : potato: Copied (new) 2022/03/15 05:18:04 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncAfterAddingAFile (11.73s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:18:15 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/03/15 05:18:15 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:18:15 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:18:16 INFO : potato: Copied (replaced existing) 2022/03/15 05:18:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (0.91s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" sync_test.go:663: ModTimeNotSupported so forcing file to be a different size 2022/03/15 05:18:16 DEBUG : potato: Sizes differ (src 21 vs dst 36) 2022/03/15 05:18:16 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:18:16 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:18:17 INFO : potato: Copied (replaced existing) 2022/03/15 05:18:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (3.43s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:18:20 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/03/15 05:18:20 DEBUG : empty space: Sizes identical 2022/03/15 05:18:20 DEBUG : empty space: Unchanged skipping 2022/03/15 05:18:20 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:18:20 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:18:20 DEBUG : Waiting for deletions to finish 2022/03/15 05:18:20 NOTICE: potato: Skipped delete as --dry-run is set (size 21) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (7.97s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:18:28 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:18:28 DEBUG : empty space: Sizes identical 2022/03/15 05:18:28 DEBUG : empty space: Unchanged skipping 2022/03/15 05:18:28 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:18:29 INFO : potato2: Copied (new) 2022/03/15 05:18:29 DEBUG : Waiting for deletions to finish 2022/03/15 05:18:31 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (3.31s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:18:34 DEBUG : d: Making directory 2022/03/15 05:18:34 DEBUG : d/e: Making directory 2022/03/15 05:18:34 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:18:34 DEBUG : c/non empty space: Sizes identical 2022/03/15 05:18:34 DEBUG : c/non empty space: Unchanged skipping 2022/03/15 05:18:34 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:18:35 INFO : a/potato2: Copied (new) 2022/03/15 05:18:35 DEBUG : Waiting for deletions to finish 2022/03/15 05:18:37 INFO : b/potato: Deleted 2022/03/15 05:18:37 INFO : d/e: Removing directory 2022/03/15 05:18:37 INFO : d: Removing directory 2022/03/15 05:18:37 INFO : b: Removing directory 2022/03/15 05:18:37 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': deleted 3 directories --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (7.69s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:18:40 DEBUG : d: Making directory 2022/03/15 05:18:40 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:18:40 DEBUG : c/non empty space: Sizes identical 2022/03/15 05:18:40 DEBUG : c/non empty space: Unchanged skipping 2022/03/15 05:18:40 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:18:41 INFO : a/potato2: Copied (new) 2022/03/15 05:18:41 ERROR : mega root 'rclone-test-qugobit1lodelub4noliver0': not deleting files as there were IO errors 2022/03/15 05:18:41 ERROR : mega root 'rclone-test-qugobit1lodelub4noliver0': not deleting directories as there were IO errors --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (4.94s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:18:48 DEBUG : Waiting for deletions to finish 2022/03/15 05:18:48 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:18:48 INFO : potato: Deleted 2022/03/15 05:18:48 DEBUG : empty space: Sizes identical 2022/03/15 05:18:48 DEBUG : empty space: Unchanged skipping 2022/03/15 05:18:48 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:18:48 INFO : potato2: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncDeleteDuring (11.84s) === RUN TestSyncDeleteBefore run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:18:56 DEBUG : Waiting for deletions to finish 2022/03/15 05:18:56 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:18:56 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:18:58 INFO : potato: Deleted 2022/03/15 05:18:58 DEBUG : empty space: Sizes identical 2022/03/15 05:18:58 DEBUG : empty space: Unchanged skipping 2022/03/15 05:18:58 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:18:58 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:18:58 INFO : potato2: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncDeleteBefore (10.11s) === RUN TestCopyDeleteBefore run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:19:06 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:19:06 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:19:06 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (2.71s) === RUN TestSyncWithExclude run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:19:09 DEBUG : potato2: Excluded 2022/03/15 05:19:09 DEBUG : enormous: Excluded 2022/03/15 05:19:09 DEBUG : potato2: Excluded 2022/03/15 05:19:09 DEBUG : empty space: Sizes identical 2022/03/15 05:19:09 DEBUG : empty space: Unchanged skipping 2022/03/15 05:19:09 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:19:09 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:19:09 DEBUG : Waiting for deletions to finish 2022/03/15 05:19:09 INFO : There was nothing to transfer 2022/03/15 05:19:09 DEBUG : enormous: Excluded 2022/03/15 05:19:09 DEBUG : potato2: Excluded 2022/03/15 05:19:09 DEBUG : potato2: Excluded 2022/03/15 05:19:09 DEBUG : empty space: Sizes identical 2022/03/15 05:19:09 DEBUG : empty space: Unchanged skipping 2022/03/15 05:19:09 DEBUG : Local file system at /tmp/rclone12525426: Waiting for checks to finish 2022/03/15 05:19:09 DEBUG : Local file system at /tmp/rclone12525426: Waiting for transfers to finish 2022/03/15 05:19:09 DEBUG : Waiting for deletions to finish 2022/03/15 05:19:09 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncWithExclude (7.99s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:19:17 DEBUG : enormous: Excluded 2022/03/15 05:19:17 DEBUG : potato2: Excluded 2022/03/15 05:19:17 DEBUG : empty space: Sizes identical 2022/03/15 05:19:17 DEBUG : empty space: Unchanged skipping 2022/03/15 05:19:17 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:19:17 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:19:17 DEBUG : Waiting for deletions to finish 2022/03/15 05:19:17 INFO : enormous: Deleted 2022/03/15 05:19:19 INFO : potato2: Deleted 2022/03/15 05:19:19 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure 2022/03/15 05:19:26 DEBUG : empty space: Sizes identical 2022/03/15 05:19:26 DEBUG : empty space: Unchanged skipping 2022/03/15 05:19:26 DEBUG : Local file system at /tmp/rclone12525426: Waiting for checks to finish 2022/03/15 05:19:26 DEBUG : Local file system at /tmp/rclone12525426: Waiting for transfers to finish 2022/03/15 05:19:26 DEBUG : Waiting for deletions to finish 2022/03/15 05:19:26 INFO : enormous: Deleted 2022/03/15 05:19:26 INFO : potato2: Deleted 2022/03/15 05:19:26 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (10.47s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" sync_test.go:968: Can't run this test on fs which doesn't support mod time --- SKIP: TestSyncWithUpdateOlder (0.00s) === RUN TestSyncWithMaxDuration sync_test.go:1009: Skipping test on non local remote --- SKIP: TestSyncWithMaxDuration (0.00s) === RUN TestSyncWithTrackRenames run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" sync_test.go:1058: Can track renames: false 2022/03/15 05:19:26 ERROR : mega root 'rclone-test-qugobit1lodelub4noliver0': Ignoring --track-renames as the source and destination do not have a common hash 2022/03/15 05:19:26 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:19:26 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:19:27 INFO : potato: Copied (new) 2022/03/15 05:19:27 INFO : yam: Copied (new) 2022/03/15 05:19:27 DEBUG : Waiting for deletions to finish 2022/03/15 05:19:27 ERROR : mega root 'rclone-test-qugobit1lodelub4noliver0': Ignoring --track-renames as the source and destination do not have a common hash 2022/03/15 05:19:27 DEBUG : potato: Sizes identical 2022/03/15 05:19:27 DEBUG : potato: Unchanged skipping 2022/03/15 05:19:27 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:19:27 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:19:29 INFO : yaml: Copied (new) 2022/03/15 05:19:29 DEBUG : Waiting for deletions to finish 2022/03/15 05:19:29 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (3.38s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" sync_test.go:1127: Can track renames: false 2022/03/15 05:19:30 ERROR : mega root 'rclone-test-qugobit1lodelub4noliver0': Ignoring --track-renames as either the source or destination do not support modtime 2022/03/15 05:19:30 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:19:30 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:19:30 INFO : potato: Copied (new) 2022/03/15 05:19:30 INFO : yam: Copied (new) 2022/03/15 05:19:30 DEBUG : Waiting for deletions to finish 2022/03/15 05:19:30 ERROR : mega root 'rclone-test-qugobit1lodelub4noliver0': Ignoring --track-renames as either the source or destination do not support modtime 2022/03/15 05:19:30 DEBUG : potato: Sizes identical 2022/03/15 05:19:30 DEBUG : potato: Unchanged skipping 2022/03/15 05:19:30 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:19:30 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:19:31 INFO : yaml: Copied (new) 2022/03/15 05:19:31 DEBUG : Waiting for deletions to finish 2022/03/15 05:19:32 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenamesStrategyModtime (2.34s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" sync_test.go:1163: Can track renames: false 2022/03/15 05:19:32 INFO : mega root 'rclone-test-qugobit1lodelub4noliver0': Making map for --track-renames 2022/03/15 05:19:32 INFO : mega root 'rclone-test-qugobit1lodelub4noliver0': Finished making map for --track-renames 2022/03/15 05:19:32 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:19:32 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for renames to finish 2022/03/15 05:19:32 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:19:33 INFO : potato: Copied (new) 2022/03/15 05:19:33 INFO : sub/yam: Copied (new) 2022/03/15 05:19:33 DEBUG : Waiting for deletions to finish 2022/03/15 05:19:33 DEBUG : potato: Sizes identical 2022/03/15 05:19:33 DEBUG : potato: Unchanged skipping 2022/03/15 05:19:33 INFO : mega root 'rclone-test-qugobit1lodelub4noliver0': Making map for --track-renames 2022/03/15 05:19:33 INFO : mega root 'rclone-test-qugobit1lodelub4noliver0': Finished making map for --track-renames 2022/03/15 05:19:33 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:19:33 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for renames to finish 2022/03/15 05:19:33 INFO : sub/yam: Moved (server-side) to: yam 2022/03/15 05:19:33 INFO : yam: Renamed from "sub/yam" 2022/03/15 05:19:33 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:19:33 DEBUG : Waiting for deletions to finish 2022/03/15 05:19:33 INFO : There was nothing to transfer 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:1180 Error: Should be true Test: TestSyncWithTrackRenamesStrategyLeaf Messages: listing wrong, want potato (14), yam (11) got potato (14), yam (11), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1180 Error: Should be true Test: TestSyncWithTrackRenamesStrategyLeaf Messages: Unexpected file "yam" 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 run.go:174 run.go:376 sync_test.go:1187 Error: Should be true Test: TestSyncWithTrackRenamesStrategyLeaf Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1187 Error: Should be true Test: TestSyncWithTrackRenamesStrategyLeaf Messages: Unexpected file "yam" --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (16.14s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:19:48 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:19:48 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:19:51 INFO : sub dir/hello world: Copied (new) 2022/03/15 05:19:51 INFO : sub dir/hello world: Deleted 2022/03/15 05:19:51 INFO : nested/sub dir/file: Copied (new) 2022/03/15 05:19:51 INFO : nested/sub dir/file: Deleted 2022/03/15 05:19:51 INFO : sub dir: Removing directory 2022/03/15 05:19:51 INFO : nested/sub dir: Removing directory 2022/03/15 05:19:51 INFO : nested: Removing directory 2022/03/15 05:19:51 DEBUG : Local file system at /tmp/rclone12525426: deleted 3 directories 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:1285 Error: Should be true Test: TestMoveWithDeleteEmptySrcDirs Messages: listing wrong, want nested/sub dir/file (6), sub dir/hello world (11) got nested/sub dir/file (6), sub dir/hello world (11), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1285 Error: Should be true Test: TestMoveWithDeleteEmptySrcDirs Messages: Unexpected file "yam" 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 run.go:174 run.go:376 sync_test.go:1286 Error: Should be true Test: TestMoveWithDeleteEmptySrcDirs Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1286 Error: Should be true Test: TestMoveWithDeleteEmptySrcDirs Messages: Unexpected file "yam" --- FAIL: TestMoveWithDeleteEmptySrcDirs (23.86s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:20:12 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:20:12 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:20:17 INFO : sub dir/hello world: Copied (new) 2022/03/15 05:20:17 INFO : sub dir/hello world: Deleted 2022/03/15 05:20:19 INFO : nested/sub dir/file: Copied (new) 2022/03/15 05:20:19 INFO : nested/sub dir/file: Deleted 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:1308 Error: Should be true Test: TestMoveWithoutDeleteEmptySrcDirs Messages: listing wrong, want nested/sub dir/file (6), sub dir/hello world (11) got nested/sub dir/file (6), sub dir/hello world (11), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1308 Error: Should be true Test: TestMoveWithoutDeleteEmptySrcDirs Messages: Unexpected file "yam" 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 run.go:174 run.go:376 sync_test.go:1309 Error: Should be true Test: TestMoveWithoutDeleteEmptySrcDirs Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1309 Error: Should be true Test: TestMoveWithoutDeleteEmptySrcDirs Messages: Unexpected file "yam" --- FAIL: TestMoveWithoutDeleteEmptySrcDirs (24.11s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:20:36 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:20:36 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:20:37 INFO : existing: Copied (new) 2022/03/15 05:20:37 INFO : existing: Deleted 2022/03/15 05:20:37 INFO : existing-b: Copied (new) 2022/03/15 05:20:37 INFO : existing-b: Deleted 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 run.go:362 sync_test.go:1329 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: listing wrong, want existing (6), existing-b (6) got existing (6), existing-b (6), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:362 sync_test.go:1329 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: Unexpected file "yam" 2022/03/15 05:20:44 DEBUG : existing: Destination exists, skipping 2022/03/15 05:20:44 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/03/15 05:20:44 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:20:44 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:20:44 INFO : There was nothing to transfer 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 run.go:362 sync_test.go:1352 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: listing wrong, want existing (6), existing-b (6) got existing (6), existing-b (6), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:362 sync_test.go:1352 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: Unexpected file "yam" 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 run.go:174 run.go:376 sync_test.go:1360 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1360 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: Unexpected file "yam" --- FAIL: TestMoveWithIgnoreExisting (22.28s) === RUN TestServerSideMove run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:20:59 DEBUG : Creating backend with remote "TestMega:rclone-test-yeserek4xogatuj9nekanut7" 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:1213 sync_test.go:1367 Error: Should be true Test: TestServerSideMove Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1), potato2 (60), potato3 (68), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1213 sync_test.go:1367 Error: Should be true Test: TestServerSideMove Messages: Unexpected file "yam" sync_test.go:1215: Server side move (if possible) mega root 'rclone-test-qugobit1lodelub4noliver0' -> mega root 'rclone-test-yeserek4xogatuj9nekanut7' 2022/03/15 05:21:10 DEBUG : mega root 'rclone-test-yeserek4xogatuj9nekanut7': Using server-side directory move 2022/03/15 05:21:10 INFO : mega root 'rclone-test-yeserek4xogatuj9nekanut7': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2022/03/15 05:21:10 DEBUG : empty space: Sizes identical 2022/03/15 05:21:10 DEBUG : empty space: Unchanged skipping 2022/03/15 05:21:10 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/03/15 05:21:10 DEBUG : mega root 'rclone-test-yeserek4xogatuj9nekanut7': Waiting for checks to finish 2022/03/15 05:21:10 INFO : potato2: Moved (server-side) 2022/03/15 05:21:11 INFO : yam: Moved (server-side) 2022/03/15 05:21:12 INFO : empty space: Deleted 2022/03/15 05:21:12 DEBUG : mega root 'rclone-test-yeserek4xogatuj9nekanut7': Waiting for transfers to finish 2022/03/15 05:21:12 INFO : potato3: Deleted 2022/03/15 05:21:12 INFO : potato3: Moved (server-side) 2022/03/15 05:21:12 INFO : There was nothing to transfer 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:1230 sync_test.go:1367 Error: Should be true Test: TestServerSideMove Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1230 sync_test.go:1367 Error: Should be true Test: TestServerSideMove Messages: Unexpected file "yam" 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:357 sync_test.go:1237 sync_test.go:1367 Error: Should be true Test: TestServerSideMove Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1), potato2 (60), potato3 (68), yam (11), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:357 sync_test.go:1237 sync_test.go:1367 Error: Should be true Test: TestServerSideMove Messages: Unexpected file "yam" fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:357 sync_test.go:1237 sync_test.go:1367 Error: Should be true Test: TestServerSideMove Messages: Unexpected file "yam" 2022/03/15 05:21:26 DEBUG : Creating backend with remote "TestMega:rclone-test-qalohah5riyavoz5tuhoxas7" 2022/03/15 05:21:26 DEBUG : mega root 'rclone-test-qalohah5riyavoz5tuhoxas7': Using server-side directory move 2022/03/15 05:21:26 INFO : mega root 'rclone-test-qalohah5riyavoz5tuhoxas7': Server side directory move succeeded 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:357 sync_test.go:1258 sync_test.go:1367 Error: Should be true Test: TestServerSideMove Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1), potato2 (60), potato3 (68), yam (11), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:357 sync_test.go:1258 sync_test.go:1367 Error: Should be true Test: TestServerSideMove Messages: Unexpected file "yam" fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:357 sync_test.go:1258 sync_test.go:1367 Error: Should be true Test: TestServerSideMove Messages: Unexpected file "yam" 2022/03/15 05:21:33 ERROR : : error listing: directory not found 2022/03/15 05:21:33 DEBUG : mega root 'rclone-test-qalohah5riyavoz5tuhoxas7': Purge remote 2022/03/15 05:21:34 DEBUG : mega root 'rclone-test-yeserek4xogatuj9nekanut7': Purge remote 2022/03/15 05:21:34 purge failed: directory not found 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 run.go:174 run.go:376 sync_test.go:1368 Error: Should be true Test: TestServerSideMove Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1368 Error: Should be true Test: TestServerSideMove Messages: Unexpected file "yam" --- FAIL: TestServerSideMove (44.32s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:21:43 DEBUG : Creating backend with remote "TestMega:rclone-test-noquhip0gepepib8majamaz0" 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:1213 sync_test.go:1381 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1), potato2 (60), potato3 (68), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1213 sync_test.go:1381 Error: Should be true Test: TestServerSideMoveWithFilter Messages: Unexpected file "yam" sync_test.go:1215: Server side move (if possible) mega root 'rclone-test-qugobit1lodelub4noliver0' -> mega root 'rclone-test-noquhip0gepepib8majamaz0' 2022/03/15 05:21:54 DEBUG : empty space: Excluded 2022/03/15 05:21:54 DEBUG : yam: Excluded 2022/03/15 05:21:54 DEBUG : empty space: Excluded 2022/03/15 05:21:56 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/03/15 05:21:56 DEBUG : mega root 'rclone-test-noquhip0gepepib8majamaz0': Waiting for checks to finish 2022/03/15 05:21:56 DEBUG : mega root 'rclone-test-noquhip0gepepib8majamaz0': Waiting for transfers to finish 2022/03/15 05:21:56 INFO : potato3: Deleted 2022/03/15 05:21:57 INFO : potato2: Moved (server-side) 2022/03/15 05:21:57 INFO : potato3: Moved (server-side) 2022/03/15 05:21:57 INFO : There was nothing to transfer 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:1228 sync_test.go:1381 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want empty space (1) got empty space (1), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1228 sync_test.go:1381 Error: Should be true Test: TestServerSideMoveWithFilter Messages: Unexpected file "yam" 2022/03/15 05:22:04 DEBUG : Creating backend with remote "TestMega:rclone-test-qujolav9hekulof2zuzikis9" 2022/03/15 05:22:04 DEBUG : empty space: Excluded 2022/03/15 05:22:04 DEBUG : mega root 'rclone-test-qujolav9hekulof2zuzikis9': Waiting for checks to finish 2022/03/15 05:22:04 DEBUG : mega root 'rclone-test-qujolav9hekulof2zuzikis9': Waiting for transfers to finish 2022/03/15 05:22:05 INFO : potato2: Moved (server-side) 2022/03/15 05:22:05 INFO : potato3: Moved (server-side) 2022/03/15 05:22:05 INFO : There was nothing to transfer 2022/03/15 05:22:05 DEBUG : mega root 'rclone-test-qujolav9hekulof2zuzikis9': Purge remote 2022/03/15 05:22:05 DEBUG : mega root 'rclone-test-noquhip0gepepib8majamaz0': Purge remote 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 run.go:174 run.go:376 sync_test.go:1382 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1382 Error: Should be true Test: TestServerSideMoveWithFilter Messages: Unexpected file "yam" --- FAIL: TestServerSideMoveWithFilter (29.50s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:22:12 DEBUG : Creating backend with remote "TestMega:rclone-test-tahojex4kekijoc7kanizeh4" 2022/03/15 05:22:13 DEBUG : tomatoDir: Making directory 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:1213 sync_test.go:1389 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1), potato2 (60), potato3 (68), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1213 sync_test.go:1389 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: Unexpected file "yam" sync_test.go:1215: Server side move (if possible) mega root 'rclone-test-qugobit1lodelub4noliver0' -> mega root 'rclone-test-tahojex4kekijoc7kanizeh4' 2022/03/15 05:22:22 DEBUG : mega root 'rclone-test-tahojex4kekijoc7kanizeh4': Using server-side directory move 2022/03/15 05:22:22 INFO : mega root 'rclone-test-tahojex4kekijoc7kanizeh4': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2022/03/15 05:22:22 DEBUG : empty space: Sizes identical 2022/03/15 05:22:22 DEBUG : empty space: Unchanged skipping 2022/03/15 05:22:22 DEBUG : mega root 'rclone-test-tahojex4kekijoc7kanizeh4': Waiting for checks to finish 2022/03/15 05:22:22 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/03/15 05:22:22 INFO : empty space: Deleted 2022/03/15 05:22:22 DEBUG : mega root 'rclone-test-tahojex4kekijoc7kanizeh4': Waiting for transfers to finish 2022/03/15 05:22:23 INFO : potato2: Moved (server-side) 2022/03/15 05:22:23 INFO : potato3: Deleted 2022/03/15 05:22:23 INFO : yam: Moved (server-side) 2022/03/15 05:22:25 INFO : potato3: Moved (server-side) 2022/03/15 05:22:25 INFO : tomatoDir: Removing directory 2022/03/15 05:22:26 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': deleted 1 directories 2022/03/15 05:22:26 INFO : There was nothing to transfer 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:1230 sync_test.go:1389 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1230 sync_test.go:1389 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: Unexpected file "yam" 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 run.go:362 sync_test.go:1234 sync_test.go:1389 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:362 sync_test.go:1234 sync_test.go:1389 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: Unexpected file "yam" 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:357 sync_test.go:1237 sync_test.go:1389 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1), potato2 (60), potato3 (68), yam (11), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:357 sync_test.go:1237 sync_test.go:1389 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: Unexpected file "yam" fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:357 sync_test.go:1237 sync_test.go:1389 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: Unexpected file "yam" 2022/03/15 05:22:47 DEBUG : Creating backend with remote "TestMega:rclone-test-lukazut1behenih3miporiq1" 2022/03/15 05:22:47 DEBUG : tomatoDir: Making directory 2022/03/15 05:22:47 DEBUG : mega root 'rclone-test-lukazut1behenih3miporiq1': Using server-side directory move 2022/03/15 05:22:47 INFO : mega root 'rclone-test-lukazut1behenih3miporiq1': Server side directory move succeeded 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:357 sync_test.go:1258 sync_test.go:1389 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1), potato2 (60), potato3 (68), yam (11), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:357 sync_test.go:1258 sync_test.go:1389 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: Unexpected file "yam" fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:357 sync_test.go:1258 sync_test.go:1389 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: Unexpected file "yam" 2022/03/15 05:22:54 ERROR : : error listing: directory not found 2022/03/15 05:22:54 ERROR : : error listing: directory not found 2022/03/15 05:22:54 DEBUG : mega root 'rclone-test-lukazut1behenih3miporiq1': Purge remote 2022/03/15 05:22:55 DEBUG : mega root 'rclone-test-tahojex4kekijoc7kanizeh4': Purge remote 2022/03/15 05:22:55 purge failed: directory not found 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 run.go:174 run.go:376 sync_test.go:1390 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1390 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: Unexpected file "yam" --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (49.45s) === RUN TestServerSideMoveOverlap run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" sync_test.go:1399: Skipping test as remote supports DirMove 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 run.go:174 run.go:376 panic.go:642 testing.go:858 testing.go:836 sync_test.go:1399 Error: Should be true Test: TestServerSideMoveOverlap Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 panic.go:642 testing.go:858 testing.go:836 sync_test.go:1399 Error: Should be true Test: TestServerSideMoveOverlap Messages: Unexpected file "yam" --- FAIL: TestServerSideMoveOverlap (7.04s) === RUN TestSyncOverlap run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:23:09 DEBUG : Creating backend with remote "TestMega:rclone-test-qugobit1lodelub4noliver0/rclone-sync-test" 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 run.go:174 run.go:376 sync_test.go:1443 Error: Should be true Test: TestSyncOverlap Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1443 Error: Should be true Test: TestSyncOverlap Messages: Unexpected file "yam" --- FAIL: TestSyncOverlap (7.04s) === RUN TestSyncCompareDest run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" 2022/03/15 05:23:16 DEBUG : Creating backend with remote "TestMega:rclone-test-qugobit1lodelub4noliver0/dst" 2022/03/15 05:23:16 DEBUG : Creating backend with remote "TestMega:rclone-test-qugobit1lodelub4noliver0/CompareDest" 2022/03/15 05:23:16 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for checks to finish 2022/03/15 05:23:16 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for transfers to finish 2022/03/15 05:23:17 INFO : one: Copied (new) 2022/03/15 05:23:17 DEBUG : Waiting for deletions to finish 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:1468 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want dst/one (3) got dst/one (3), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1468 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "yam" 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:1472 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want dst/one (3) got dst/one (3), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1472 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "yam" 2022/03/15 05:23:31 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for checks to finish 2022/03/15 05:23:31 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/15 05:23:31 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for transfers to finish 2022/03/15 05:23:33 INFO : one: Copied (replaced existing) 2022/03/15 05:23:33 DEBUG : Waiting for deletions to finish 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:1482 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want dst/one (5) got dst/one (5), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1482 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "yam" 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:1488 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), dst/one (3) got CompareDest/one (5), dst/one (3), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1488 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "yam" 2022/03/15 05:23:48 DEBUG : one: Sizes identical 2022/03/15 05:23:48 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/15 05:23:48 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for checks to finish 2022/03/15 05:23:48 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for transfers to finish 2022/03/15 05:23:48 DEBUG : Waiting for deletions to finish 2022/03/15 05:23:48 INFO : There was nothing to transfer 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:1495 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), dst/one (3) got CompareDest/one (5), dst/one (3), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1495 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "yam" 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:1500 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), CompareDest/two (3), dst/one (3) got CompareDest/one (5), CompareDest/two (3), dst/one (3), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1500 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "yam" 2022/03/15 05:24:02 DEBUG : two: Sizes identical 2022/03/15 05:24:02 DEBUG : two: Destination found in --compare-dest, skipping 2022/03/15 05:24:02 DEBUG : one: Sizes identical 2022/03/15 05:24:02 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/15 05:24:02 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for checks to finish 2022/03/15 05:24:02 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for transfers to finish 2022/03/15 05:24:02 DEBUG : Waiting for deletions to finish 2022/03/15 05:24:02 INFO : There was nothing to transfer 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:1507 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), CompareDest/two (3), dst/one (3) got CompareDest/one (5), CompareDest/two (3), dst/one (3), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1507 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "yam" 2022/03/15 05:24:09 DEBUG : two: Sizes identical 2022/03/15 05:24:09 DEBUG : two: Destination found in --compare-dest, skipping 2022/03/15 05:24:09 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for checks to finish 2022/03/15 05:24:09 DEBUG : one: Sizes identical 2022/03/15 05:24:09 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/15 05:24:09 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for transfers to finish 2022/03/15 05:24:09 DEBUG : Waiting for deletions to finish 2022/03/15 05:24:09 INFO : There was nothing to transfer 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:1514 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), CompareDest/two (3), dst/one (3) got CompareDest/one (5), CompareDest/two (3), dst/one (3), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1514 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "yam" sync_test.go:1543: No hash on uploaded file so skipping compare timestamp test 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:1548 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), CompareDest/two (3), dst/one (3) got CompareDest/one (5), CompareDest/two (3), dst/one (3), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1548 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "yam" 2022/03/15 05:24:23 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/03/15 05:24:23 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for checks to finish 2022/03/15 05:24:23 DEBUG : one: Sizes identical 2022/03/15 05:24:23 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/15 05:24:23 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for transfers to finish 2022/03/15 05:24:24 INFO : two: Copied (new) 2022/03/15 05:24:24 DEBUG : Waiting for deletions to finish 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:1558 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), CompareDest/two (3), dst/one (3), dst/two (5) got CompareDest/one (5), CompareDest/two (3), dst/one (3), dst/two (5), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1558 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "yam" 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 run.go:174 run.go:376 sync_test.go:1559 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1559 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "yam" --- FAIL: TestSyncCompareDest (92.22s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", 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:1579 Error: Should be true Test: TestSyncMultipleCompareDest Messages: listing wrong, want pre-dest1/1 (1), pre-dest2/2 (1) got pre-dest1/1 (1), pre-dest2/2 (1), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1579 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "yam" 2022/03/15 05:24:59 DEBUG : Creating backend with remote "TestMega:rclone-test-qugobit1lodelub4noliver0/dest" 2022/03/15 05:24:59 DEBUG : Creating backend with remote "TestMega:rclone-test-qugobit1lodelub4noliver0/pre-dest1" 2022/03/15 05:24:59 DEBUG : Creating backend with remote "TestMega:rclone-test-qugobit1lodelub4noliver0/pre-dest2" 2022/03/15 05:24:59 DEBUG : 1: Sizes identical 2022/03/15 05:24:59 DEBUG : 1: Destination found in --compare-dest, skipping 2022/03/15 05:24:59 DEBUG : 2: Sizes identical 2022/03/15 05:24:59 DEBUG : 2: Destination found in --compare-dest, skipping 2022/03/15 05:24:59 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dest': Waiting for checks to finish 2022/03/15 05:24:59 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dest': Waiting for transfers to finish 2022/03/15 05:24:59 INFO : 3: Copied (new) 2022/03/15 05:24:59 DEBUG : Waiting for deletions to finish 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:1590 Error: Should be true Test: TestSyncMultipleCompareDest Messages: listing wrong, want dest/3 (1), pre-dest1/1 (1), pre-dest2/2 (1) got dest/3 (1), pre-dest1/1 (1), pre-dest2/2 (1), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1590 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "yam" 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 run.go:174 run.go:376 sync_test.go:1591 Error: Should be true Test: TestSyncMultipleCompareDest Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1591 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "yam" --- FAIL: TestSyncMultipleCompareDest (28.66s) === RUN TestSyncCopyDest run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" sync_test.go:1601: Skipping test as remote does not support server-side copy 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 run.go:174 run.go:376 panic.go:642 testing.go:858 testing.go:836 sync_test.go:1601 Error: Should be true Test: TestSyncCopyDest Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 panic.go:642 testing.go:858 testing.go:836 sync_test.go:1601 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "yam" --- FAIL: TestSyncCopyDest (7.04s) === RUN TestSyncBackupDir run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", 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:1732 sync_test.go:1781 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want dst/one (3), dst/three.txt (5), dst/two (3) got dst/one (3), dst/three.txt (5), dst/two (3), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1732 sync_test.go:1781 Error: Should be true Test: TestSyncBackupDir Messages: Unexpected file "yam" 2022/03/15 05:25:33 DEBUG : Creating backend with remote "TestMega:rclone-test-qugobit1lodelub4noliver0/dst" 2022/03/15 05:25:33 DEBUG : Creating backend with remote "TestMega:rclone-test-qugobit1lodelub4noliver0/backup" 2022/03/15 05:25:33 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/15 05:25:33 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for checks to finish 2022/03/15 05:25:33 DEBUG : two: Sizes identical 2022/03/15 05:25:33 DEBUG : two: Unchanged skipping 2022/03/15 05:25:34 INFO : one: Moved (server-side) 2022/03/15 05:25:34 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for transfers to finish 2022/03/15 05:25:36 INFO : one: Copied (new) 2022/03/15 05:25:36 DEBUG : Waiting for deletions to finish 2022/03/15 05:25:38 INFO : three.txt: Moved (server-side) 2022/03/15 05:25:38 INFO : three.txt: Moved into backup dir 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:1753 sync_test.go:1781 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (3), backup/three.txt (5), dst/one (4), dst/two (3) got backup/one (3), backup/three.txt (5), dst/one (4), dst/two (3), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1753 sync_test.go:1781 Error: Should be true Test: TestSyncBackupDir Messages: Unexpected file "yam" 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:1759 sync_test.go:1781 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 backup/one (3), backup/three.txt (5), dst/one (4), dst/three.txt (6), dst/two (3), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1759 sync_test.go:1781 Error: Should be true Test: TestSyncBackupDir Messages: Unexpected file "yam" 2022/03/15 05:25:52 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/15 05:25:52 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for checks to finish 2022/03/15 05:25:52 INFO : one: Deleted 2022/03/15 05:25:53 DEBUG : two: Sizes identical 2022/03/15 05:25:53 DEBUG : two: Unchanged skipping 2022/03/15 05:25:53 INFO : one: Moved (server-side) 2022/03/15 05:25:53 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0/dst': Waiting for transfers to finish 2022/03/15 05:25:53 INFO : one: Copied (new) 2022/03/15 05:25:53 DEBUG : Waiting for deletions to finish 2022/03/15 05:25:56 INFO : three.txt: Deleted 2022/03/15 05:25:56 INFO : three.txt: Moved (server-side) 2022/03/15 05:25:56 INFO : three.txt: Moved into backup dir 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:1778 sync_test.go:1781 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (4), backup/three.txt (6), dst/one (5), dst/two (3) got backup/one (4), backup/three.txt (6), dst/one (5), dst/two (3), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1778 sync_test.go:1781 Error: Should be true Test: TestSyncBackupDir Messages: Unexpected file "yam" 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 run.go:174 run.go:376 sync_test.go:1779 sync_test.go:1781 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1779 sync_test.go:1781 Error: Should be true Test: TestSyncBackupDir Messages: Unexpected file "yam" --- FAIL: TestSyncBackupDir (49.36s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" run.go:287: Failed to put "dst/one" to "mega root 'rclone-test-qugobit1lodelub4noliver0'": mkdir create node failed: Try again run.go:126: removing file "yam" failed - try 1/3: Remove object failed: Try again run.go:126: removing file "yam" failed - try 2/3: Remove object failed: Try again run.go:126: removing file "yam" failed - try 3/3: Remove object failed: Try again run.go:129: removing file "yam" failed: Remove object failed: Try again 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 run.go:174 run.go:376 panic.go:642 testing.go:756 testing.go:830 run.go:287 run.go:294 sync_test.go:1726 sync_test.go:1784 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 panic.go:642 testing.go:756 testing.go:830 run.go:287 run.go:294 sync_test.go:1726 sync_test.go:1784 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: Unexpected file "yam" --- FAIL: TestSyncBackupDirWithSuffix (52.32s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" run.go:287: Failed to put "dst/one" to "mega root 'rclone-test-qugobit1lodelub4noliver0'": mkdir create node failed: Try again run.go:126: removing file "yam" failed - try 1/3: Remove object failed: Try again run.go:126: removing file "yam" failed - try 2/3: Remove object failed: Try again run.go:126: removing file "yam" failed - try 3/3: Remove object failed: Try again run.go:129: removing file "yam" failed: Remove object failed: Try again 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 run.go:174 run.go:376 panic.go:642 testing.go:756 testing.go:830 run.go:287 run.go:294 sync_test.go:1726 sync_test.go:1787 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 panic.go:642 testing.go:756 testing.go:830 run.go:287 run.go:294 sync_test.go:1726 sync_test.go:1787 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: Unexpected file "yam" --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (53.33s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" run.go:287: Failed to put "dst/one" to "mega root 'rclone-test-qugobit1lodelub4noliver0'": mkdir create node failed: Try again run.go:126: removing file "yam" failed - try 1/3: Remove object failed: Try again run.go:126: removing file "yam" failed - try 2/3: Remove object failed: Try again run.go:126: removing file "yam" failed - try 3/3: Remove object failed: Try again run.go:129: removing file "yam" failed: Remove object failed: Try again 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 run.go:174 run.go:376 panic.go:642 testing.go:756 testing.go:830 run.go:287 run.go:294 sync_test.go:1726 sync_test.go:1790 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 panic.go:642 testing.go:756 testing.go:830 run.go:287 run.go:294 sync_test.go:1726 sync_test.go:1790 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: Unexpected file "yam" --- FAIL: TestSyncBackupDirSuffixOnly (52.60s) === RUN TestSyncSuffix run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" run.go:287: Failed to put "dst/one" to "mega root 'rclone-test-qugobit1lodelub4noliver0'": mkdir create node failed: Try again 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 run.go:174 run.go:376 panic.go:642 testing.go:756 testing.go:830 run.go:287 run.go:294 sync_test.go:1810 sync_test.go:1875 Error: Should be true Test: TestSyncSuffix Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 panic.go:642 testing.go:756 testing.go:830 run.go:287 run.go:294 sync_test.go:1810 sync_test.go:1875 Error: Should be true Test: TestSyncSuffix Messages: Unexpected file "yam" --- FAIL: TestSyncSuffix (23.07s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", 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:1817 sync_test.go:1876 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: listing wrong, want dst/one (3), dst/three.txt (5), dst/two (3) got dst/one (3), dst/three.txt (5), dst/two (3), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1817 sync_test.go:1876 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: Unexpected file "yam" 2022/03/15 05:29:24 DEBUG : Creating backend with remote "TestMega:rclone-test-qugobit1lodelub4noliver0/dst" 2022/03/15 05:29:24 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/15 05:29:25 INFO : one: Moved (server-side) to: one-2019-01-01 2022/03/15 05:29:25 INFO : one: Copied (new) 2022/03/15 05:29:25 DEBUG : two: Sizes identical 2022/03/15 05:29:25 DEBUG : two: Unchanged skipping 2022/03/15 05:29:25 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/03/15 05:29:25 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/03/15 05:29:26 INFO : three.txt: Copied (new) 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:1843 sync_test.go:1876 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: listing wrong, want dst/one (4), dst/one-2019-01-01 (3), dst/three-2019-01-01.txt (5), 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), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1843 sync_test.go:1876 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: Unexpected file "yam" 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:1849 sync_test.go:1876 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: listing wrong, want dst/one (4), dst/one-2019-01-01 (3), dst/three-2019-01-01.txt (5), 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), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1849 sync_test.go:1876 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: Unexpected file "yam" 2022/03/15 05:29:40 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/15 05:29:40 INFO : one-2019-01-01: Deleted 2022/03/15 05:29:40 INFO : one: Moved (server-side) to: one-2019-01-01 2022/03/15 05:29:41 INFO : one: Copied (new) 2022/03/15 05:29:41 DEBUG : two: Sizes identical 2022/03/15 05:29:41 DEBUG : two: Unchanged skipping 2022/03/15 05:29:41 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/03/15 05:29:41 INFO : three-2019-01-01.txt: Deleted 2022/03/15 05:29:42 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/03/15 05:29:42 INFO : three.txt: Copied (new) 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:1873 sync_test.go:1876 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: listing wrong, want dst/one (5), dst/one-2019-01-01 (4), dst/three-2019-01-01.txt (6), dst/three.txt (19), dst/two (3) got dst/one (5), dst/one-2019-01-01 (4), dst/three-2019-01-01.txt (6), dst/three.txt (19), dst/two (3), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1873 sync_test.go:1876 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: Unexpected file "yam" 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 run.go:174 run.go:376 sync_test.go:1874 sync_test.go:1876 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1874 sync_test.go:1876 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: Unexpected file "yam" --- FAIL: TestSyncSuffixKeepExtension (44.90s) === RUN TestSyncUTFNorm run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", 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:1898 Error: Should be true Test: TestSyncUTFNorm Messages: listing wrong, want Testêé (18) got Testêé (18), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1898 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "yam" 2022/03/15 05:30:07 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/03/15 05:30:07 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:30:07 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:30:09 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/03/15 05:30:09 DEBUG : Waiting for deletions to finish 2022/03/15 05:30:09 INFO : yam: Deleted 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:1909 Error: Should be true Test: TestSyncUTFNorm Messages: listing wrong, want Testêé (14) got Testêé (14), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1909 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "yam" 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 run.go:174 run.go:376 sync_test.go:1910 Error: Should be true Test: TestSyncUTFNorm Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1910 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "yam" --- FAIL: TestSyncUTFNorm (23.61s) === RUN TestSyncImmutable run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", 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:1924 Error: Should be true Test: TestSyncImmutable Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1924 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "yam" 2022/03/15 05:30:30 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:30:30 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:30:30 INFO : existing: Copied (new) 2022/03/15 05:30:30 DEBUG : Waiting for deletions to finish 2022/03/15 05:30:30 INFO : yam: Deleted 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:1931 Error: Should be true Test: TestSyncImmutable Messages: listing wrong, want existing (6) got existing (6), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1931 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "yam" 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:1936 Error: Should be true Test: TestSyncImmutable Messages: listing wrong, want existing (6) got existing (6), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1936 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "yam" 2022/03/15 05:30:44 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/03/15 05:30:44 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/03/15 05:30:44 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:30:44 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:30:44 ERROR : mega root 'rclone-test-qugobit1lodelub4noliver0': not deleting files as there were IO errors 2022/03/15 05:30:44 ERROR : mega root 'rclone-test-qugobit1lodelub4noliver0': not deleting directories as there were IO errors 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:1943 Error: Should be true Test: TestSyncImmutable Messages: listing wrong, want existing (6) got existing (6), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1943 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "yam" 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 run.go:174 run.go:376 sync_test.go:1944 Error: Should be true Test: TestSyncImmutable Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1944 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "yam" --- FAIL: TestSyncImmutable (35.58s) === RUN TestSyncIgnoreCase run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", 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:1964 Error: Should be true Test: TestSyncIgnoreCase Messages: listing wrong, want EXISTING (6) got EXISTING (6), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1964 Error: Should be true Test: TestSyncIgnoreCase Messages: Unexpected file "yam" 2022/03/15 05:31:07 DEBUG : existing: Sizes identical 2022/03/15 05:31:07 DEBUG : existing: Unchanged skipping 2022/03/15 05:31:07 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:31:07 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:31:07 DEBUG : Waiting for deletions to finish 2022/03/15 05:31:07 INFO : yam: Deleted 2022/03/15 05:31:07 INFO : There was nothing to transfer 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:1971 Error: Should be true Test: TestSyncIgnoreCase Messages: listing wrong, want EXISTING (6) got EXISTING (6), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1971 Error: Should be true Test: TestSyncIgnoreCase Messages: Unexpected file "yam" 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 run.go:174 run.go:376 sync_test.go:1972 Error: Should be true Test: TestSyncIgnoreCase Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1972 Error: Should be true Test: TestSyncIgnoreCase Messages: Unexpected file "yam" --- FAIL: TestSyncIgnoreCase (22.36s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" sync_test.go:1989: This test only runs on local 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 run.go:174 run.go:376 panic.go:642 testing.go:858 testing.go:836 sync_test.go:1989 sync_test.go:2010 Error: Should be true Test: TestMaxTransfer/Hard Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 panic.go:642 testing.go:858 testing.go:836 sync_test.go:1989 sync_test.go:2010 Error: Should be true Test: TestMaxTransfer/Hard Messages: Unexpected file "yam" === RUN TestMaxTransfer/Soft run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" sync_test.go:1989: This test only runs on local 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 run.go:174 run.go:376 panic.go:642 testing.go:858 testing.go:836 sync_test.go:1989 sync_test.go:2011 Error: Should be true Test: TestMaxTransfer/Soft Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 panic.go:642 testing.go:858 testing.go:836 sync_test.go:1989 sync_test.go:2011 Error: Should be true Test: TestMaxTransfer/Soft Messages: Unexpected file "yam" === RUN TestMaxTransfer/Cautious run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", Modify Window "876000h0m0s" sync_test.go:1989: This test only runs on local 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 run.go:174 run.go:376 panic.go:642 testing.go:858 testing.go:836 sync_test.go:1989 sync_test.go:2012 Error: Should be true Test: TestMaxTransfer/Cautious Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 panic.go:642 testing.go:858 testing.go:836 sync_test.go:1989 sync_test.go:2012 Error: Should be true Test: TestMaxTransfer/Cautious Messages: Unexpected file "yam" --- FAIL: TestMaxTransfer (21.15s) --- FAIL: TestMaxTransfer/Hard (7.06s) --- FAIL: TestMaxTransfer/Soft (7.04s) --- FAIL: TestMaxTransfer/Cautious (7.04s) === RUN TestSyncConcurrentDelete run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", 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 (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), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2050 sync_test.go:2062 Error: Should be true Test: TestSyncConcurrentDelete Messages: Unexpected file "yam" 2022/03/15 05:32:14 DEBUG : both0: Sizes identical 2022/03/15 05:32:14 DEBUG : both0: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both1: Sizes identical 2022/03/15 05:32:14 DEBUG : both1: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both10: Sizes identical 2022/03/15 05:32:14 DEBUG : both10: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both11: Sizes identical 2022/03/15 05:32:14 DEBUG : both11: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both12: Sizes identical 2022/03/15 05:32:14 DEBUG : both12: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both13: Sizes identical 2022/03/15 05:32:14 DEBUG : both13: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both14: Sizes identical 2022/03/15 05:32:14 DEBUG : both14: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both15: Sizes identical 2022/03/15 05:32:14 DEBUG : both15: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both16: Sizes identical 2022/03/15 05:32:14 DEBUG : both16: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both17: Sizes identical 2022/03/15 05:32:14 DEBUG : both17: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both18: Sizes identical 2022/03/15 05:32:14 DEBUG : both18: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both19: Sizes identical 2022/03/15 05:32:14 DEBUG : both19: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both2: Sizes identical 2022/03/15 05:32:14 DEBUG : both2: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both3: Sizes identical 2022/03/15 05:32:14 DEBUG : both3: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both4: Sizes identical 2022/03/15 05:32:14 DEBUG : both4: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both5: Sizes identical 2022/03/15 05:32:14 DEBUG : both5: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both6: Sizes identical 2022/03/15 05:32:14 DEBUG : both6: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both7: Sizes identical 2022/03/15 05:32:14 DEBUG : both7: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both8: Sizes identical 2022/03/15 05:32:14 DEBUG : both8: Unchanged skipping 2022/03/15 05:32:14 DEBUG : both9: Sizes identical 2022/03/15 05:32:14 DEBUG : both9: Unchanged skipping 2022/03/15 05:32:14 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:32:14 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:32:14 DEBUG : Waiting for deletions to finish 2022/03/15 05:32:15 INFO : only16: Deleted 2022/03/15 05:32:15 INFO : yam: Deleted 2022/03/15 05:32:15 INFO : only9: Deleted 2022/03/15 05:32:15 INFO : only8: Deleted 2022/03/15 05:32:15 INFO : only17: Deleted 2022/03/15 05:32:15 INFO : only6: Deleted 2022/03/15 05:32:15 INFO : only12: Deleted 2022/03/15 05:32:16 INFO : only1: Deleted 2022/03/15 05:32:16 INFO : only4: Deleted 2022/03/15 05:32:16 INFO : only13: Deleted 2022/03/15 05:32:17 INFO : only14: Deleted 2022/03/15 05:32:17 INFO : only2: Deleted 2022/03/15 05:32:17 INFO : only5: Deleted 2022/03/15 05:32:17 INFO : only11: Deleted 2022/03/15 05:32:17 INFO : only7: Deleted 2022/03/15 05:32:17 INFO : only0: Deleted 2022/03/15 05:32:17 INFO : only10: Deleted 2022/03/15 05:32:17 INFO : only15: Deleted 2022/03/15 05:32:17 INFO : only3: Deleted 2022/03/15 05:32:17 INFO : only18: Deleted 2022/03/15 05:32:17 INFO : only19: Deleted 2022/03/15 05:32:17 INFO : There was nothing to transfer 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:2058 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) 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), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2058 sync_test.go:2062 Error: Should be true Test: TestSyncConcurrentDelete Messages: Unexpected file "yam" 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 run.go:174 run.go:376 sync_test.go:2059 sync_test.go:2062 Error: Should be true Test: TestSyncConcurrentDelete Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:2059 sync_test.go:2062 Error: Should be true Test: TestSyncConcurrentDelete Messages: Unexpected file "yam" --- FAIL: TestSyncConcurrentDelete (54.94s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "mega root 'rclone-test-qugobit1lodelub4noliver0'", Local "Local file system at /tmp/rclone12525426", 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 (6), only8 (6), only9 (6), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2050 sync_test.go:2066 Error: Should be true Test: TestSyncConcurrentTruncate Messages: Unexpected file "yam" 2022/03/15 05:33:08 DEBUG : both0: Sizes identical 2022/03/15 05:33:08 DEBUG : both0: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both1: Sizes identical 2022/03/15 05:33:08 DEBUG : both1: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both10: Sizes identical 2022/03/15 05:33:08 DEBUG : both10: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both11: Sizes identical 2022/03/15 05:33:08 DEBUG : both11: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both12: Sizes identical 2022/03/15 05:33:08 DEBUG : both12: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both13: Sizes identical 2022/03/15 05:33:08 DEBUG : both13: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both14: Sizes identical 2022/03/15 05:33:08 DEBUG : both14: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both15: Sizes identical 2022/03/15 05:33:08 DEBUG : both15: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both16: Sizes identical 2022/03/15 05:33:08 DEBUG : both16: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both17: Sizes identical 2022/03/15 05:33:08 DEBUG : both17: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both18: Sizes identical 2022/03/15 05:33:08 DEBUG : both18: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both19: Sizes identical 2022/03/15 05:33:08 DEBUG : both19: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both2: Sizes identical 2022/03/15 05:33:08 DEBUG : both2: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both3: Sizes identical 2022/03/15 05:33:08 DEBUG : both3: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both4: Sizes identical 2022/03/15 05:33:08 DEBUG : both4: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both5: Sizes identical 2022/03/15 05:33:08 DEBUG : both5: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both6: Sizes identical 2022/03/15 05:33:08 DEBUG : both6: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both7: Sizes identical 2022/03/15 05:33:08 DEBUG : both7: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both8: Sizes identical 2022/03/15 05:33:08 DEBUG : both8: Unchanged skipping 2022/03/15 05:33:08 DEBUG : both9: Sizes identical 2022/03/15 05:33:08 DEBUG : both9: Unchanged skipping 2022/03/15 05:33:08 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/03/15 05:33:08 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for checks to finish 2022/03/15 05:33:08 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Waiting for transfers to finish 2022/03/15 05:33:08 INFO : only0: Copied (replaced existing) 2022/03/15 05:33:11 INFO : only1: Copied (replaced existing) 2022/03/15 05:33:11 INFO : only10: Copied (replaced existing) 2022/03/15 05:33:12 INFO : only11: Copied (replaced existing) 2022/03/15 05:33:12 INFO : only12: Copied (replaced existing) 2022/03/15 05:33:12 INFO : only13: Copied (replaced existing) 2022/03/15 05:33:14 INFO : only15: Copied (replaced existing) 2022/03/15 05:33:15 INFO : only14: Copied (replaced existing) 2022/03/15 05:33:15 INFO : only17: Copied (replaced existing) 2022/03/15 05:33:15 INFO : only16: Copied (replaced existing) 2022/03/15 05:33:16 INFO : only18: Copied (replaced existing) 2022/03/15 05:33:16 INFO : only19: Copied (replaced existing) 2022/03/15 05:33:18 INFO : only2: Copied (replaced existing) 2022/03/15 05:33:19 INFO : only4: Copied (replaced existing) 2022/03/15 05:33:19 INFO : only5: Copied (replaced existing) 2022/03/15 05:33:20 INFO : only3: Copied (replaced existing) 2022/03/15 05:33:21 INFO : only6: Copied (replaced existing) 2022/03/15 05:33:21 INFO : only7: Copied (replaced existing) 2022/03/15 05:33:21 INFO : only9: Copied (replaced existing) 2022/03/15 05:33:23 INFO : only8: Copied (replaced existing) 2022/03/15 05:33:23 DEBUG : Waiting for deletions to finish 2022/03/15 05:33:23 INFO : yam: Deleted 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:2058 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 (0), only1 (0), only10 (0), only11 (0), only12 (0), only13 (0), only14 (0), only15 (0), only16 (0), only17 (0), only18 (0), only19 (0), only2 (0), only3 (0), only4 (0), only5 (0), only6 (0), only7 (0), only8 (0), only9 (0) 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 (0), only1 (0), only10 (0), only11 (0), only12 (0), only13 (0), only14 (0), only15 (0), only16 (0), only17 (0), only18 (0), only19 (0), only2 (0), only3 (0), only4 (0), only5 (0), only6 (0), only7 (0), only8 (0), only9 (0), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2058 sync_test.go:2066 Error: Should be true Test: TestSyncConcurrentTruncate Messages: Unexpected file "yam" 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 run.go:174 run.go:376 sync_test.go:2059 sync_test.go:2066 Error: Should be true Test: TestSyncConcurrentTruncate Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:2059 sync_test.go:2066 Error: Should be true Test: TestSyncConcurrentTruncate Messages: Unexpected file "yam" --- FAIL: TestSyncConcurrentTruncate (70.41s) FAIL 2022/03/15 05:33:47 DEBUG : mega root 'rclone-test-qugobit1lodelub4noliver0': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestMega: -verbose" - Finished ERROR in 17m36.870577956s (try 1/5): exit status 1: Failed [TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestMoveWithoutDeleteEmptySrcDirs TestMoveWithIgnoreExisting TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestServerSideMoveOverlap TestSyncOverlap TestSyncCompareDest TestSyncMultipleCompareDest TestSyncCopyDest TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension TestSyncUTFNorm TestSyncImmutable TestSyncIgnoreCase TestMaxTransfer/Hard TestMaxTransfer/Soft TestMaxTransfer/Cautious TestSyncConcurrentDelete TestSyncConcurrentTruncate]