"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -fast-list" - Starting (try 1/5) 2025/02/02 05:16:05 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena" 2025/02/02 05:16:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/02 05:16:06 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0" 2025/02/02 05:16:07 DEBUG : Creating backend with remote "/tmp/rclone2748306405" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:08 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:16:08 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:16:08 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/02/02 05:16:08 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:16:08 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:16:08 NOTICE: sub dir: Skipped set directory modification time as --dry-run is set run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestCopyWithDryRun (2.33s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:09 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/02/02 05:16:09 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:16:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:16:09 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:16:09 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:16:12 DEBUG : sub dir/hello world: md5 = dc0dea1c1e197a729f8a6882d572c1e6 OK 2025/02/02 05:16:12 INFO : sub dir/hello world: Copied (new) 2025/02/02 05:16:12 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:14 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestCopy (6.39s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:15 DEBUG : metadata sub dir: Making directory with metadata 2025/02/02 05:16:15 INFO : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/02 05:16:15 DEBUG : empty metadata sub dir: Making directory with metadata 2025/02/02 05:16:15 INFO : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/02 05:16:15 DEBUG : Local file system at /tmp/rclone2748306405: File to upload is small (21 bytes), uploading instead of streaming 2025/02/02 05:16:15 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK 2025/02/02 05:16:15 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical 2025/02/02 05:16:15 INFO : metadata sub dir: Set directory modification time (using DirSetModTime) 2025/02/02 05:16:16 DEBUG : Google drive root 'crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0': Skipping btime metadata as can't update it on an existing file: 2025-02-02T05:16:15.76976958Z 2025/02/02 05:16:16 INFO : empty metadata sub dir: Updated directory metadata 2025/02/02 05:16:17 DEBUG : Google drive root 'crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0': Skipping btime metadata as can't update it on an existing file: 2025-02-02T05:16:15.76976958Z 2025/02/02 05:16:17 INFO : metadata sub dir: Updated directory metadata 2025/02/02 05:16:17 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination 2025/02/02 05:16:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:16:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:16:19 DEBUG : metadata sub dir/hello metadata world: md5 = 93dea72adc5c1447efcf1d865f0fafa4 OK 2025/02/02 05:16:19 INFO : metadata sub dir/hello metadata world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:22 DEBUG : fg45q1rm3kh00nmmkk4m6ihmrcnel1p7551kqhjr9bk2gtd2gbf0: Rmdir: contains trashed file: "dmuvulfij81f693ur0f1vvbvq9v6iscp56daas3m51n9li5e3470" --- PASS: TestCopyMetadata (8.70s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:24 DEBUG : metadata sub dir: Making directory with metadata 2025/02/02 05:16:24 INFO : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/02 05:16:24 DEBUG : empty metadata sub dir: Making directory with metadata 2025/02/02 05:16:24 INFO : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/02 05:16:24 DEBUG : Local file system at /tmp/rclone2748306405: File to upload is small (21 bytes), uploading instead of streaming 2025/02/02 05:16:24 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK 2025/02/02 05:16:24 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical 2025/02/02 05:16:24 INFO : metadata sub dir: Set directory modification time (using DirSetModTime) 2025/02/02 05:16:24 DEBUG : Added delayed dir = "empty metadata sub dir", newDst= 2025/02/02 05:16:24 DEBUG : Added delayed dir = "metadata sub dir", newDst= 2025/02/02 05:16:24 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination 2025/02/02 05:16:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:16:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:16:27 DEBUG : metadata sub dir/hello metadata world: md5 = 6ff6ce55149a0be7c910d311ff2b0b31 OK 2025/02/02 05:16:27 INFO : metadata sub dir/hello metadata world: Copied (new) 2025/02/02 05:16:27 DEBUG : Google drive root 'crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0': Skipping btime metadata as can't update it on an existing file: 2025-02-02T05:16:24.465709718Z 2025/02/02 05:16:27 INFO : metadata sub dir: Updated directory metadata run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:30 DEBUG : fg45q1rm3kh00nmmkk4m6ihmrcnel1p7551kqhjr9bk2gtd2gbf0: Rmdir: contains trashed file: "dmuvulfij81f693ur0f1vvbvq9v6iscp56daas3m51n9li5e3470" --- PASS: TestCopyMetadataNoEmptyDirs (7.57s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:32 DEBUG : Creating backend with remote "/non-existing" 2025/02/02 05:16:32 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/02/02 05:16:32 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:16:32 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestCopyMissingDirectory (1.09s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:33 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:16:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:16:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:16:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:16:35 DEBUG : sub dir/hello world: md5 = e8b5d8e7bc32d1ae3904b20e047677b5 OK 2025/02/02 05:16:35 INFO : sub dir/hello world: Copied (new) 2025/02/02 05:16:36 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:38 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestCopyNoTraverse (6.37s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:39 INFO : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Running all checks before starting transfers 2025/02/02 05:16:39 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:16:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:16:39 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:16:39 INFO : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Checks finished, now starting transfers 2025/02/02 05:16:39 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:16:42 DEBUG : sub dir/hello world: md5 = b2cf854a874c9454051efcc54fd3168b OK 2025/02/02 05:16:42 INFO : sub dir/hello world: Copied (new) 2025/02/02 05:16:42 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:44 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestCopyCheckFirst (6.64s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:46 ERROR : Ignoring --no-traverse with sync 2025/02/02 05:16:46 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:16:46 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:16:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:16:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:16:49 DEBUG : sub dir/hello world: md5 = b31c3d8f34b9c854731212db741a5290 OK 2025/02/02 05:16:49 INFO : sub dir/hello world: Copied (new) 2025/02/02 05:16:49 DEBUG : Waiting for deletions to finish 2025/02/02 05:16:49 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:51 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestSyncNoTraverse (6.80s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:53 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/02/02 05:16:53 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:16:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:16:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:16:54 DEBUG : hello world2: md5 = cbd6f482eb856161bdd7c32e8ddc2340 OK 2025/02/02 05:16:54 INFO : hello world2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestCopyWithDepth (3.61s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:16:56 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/02 05:16:56 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:16:56 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:16:58 DEBUG : potato2: md5 = 475f74f57588e34e96a331cb57fc4a33 OK 2025/02/02 05:16:58 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestCopyWithFilesFrom (3.45s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:17:00 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/02 05:17:00 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:17:00 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:17:01 DEBUG : potato2: md5 = ef4988932dd91d139318f1bd7ec34873 OK 2025/02/02 05:17:01 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestCopyWithFilesFromAndNoTraverse (3.54s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:17:03 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/02/02 05:17:03 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/02 05:17:03 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/02/02 05:17:03 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/02/02 05:17:03 DEBUG : sub dir: Making directory with metadata 2025/02/02 05:17:04 INFO : sub dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/02/02 05:17:04 DEBUG : sub dir2: Making directory with metadata 2025/02/02 05:17:05 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/02 05:17:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:17:05 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/02/02 05:17:06 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/02 05:17:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:17:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:17:07 DEBUG : sub dir/hello world: md5 = 2b29d054cd762a6bcccd23bba62f95b8 OK 2025/02/02 05:17:07 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:17:10 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc: Rmdir: contains trashed file: "cv6a9uf28ngrajena8kt5ijjn0" 2025/02/02 05:17:11 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestCopyEmptyDirectories (9.63s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:17:13 INFO : sub dir2: Making directory 2025/02/02 05:17:13 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/02/02 05:17:13 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/02 05:17:13 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:17:13 DEBUG : Added delayed dir = "sub dir2", newDst= 2025/02/02 05:17:13 DEBUG : Added delayed dir = "sub dir2/sub sub dir2", newDst= 2025/02/02 05:17:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:17:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:17:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:17:15 DEBUG : sub dir/hello world: md5 = 40fb25d58f7df14e5f0f670fec8b07a3 OK 2025/02/02 05:17:15 INFO : sub dir/hello world: Copied (new) 2025/02/02 05:17:16 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/02/02 05:17:18 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestCopyNoEmptyDirectories (5.92s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:17:19 DEBUG : sub dir2: Making directory with metadata 2025/02/02 05:17:19 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/02 05:17:19 DEBUG : sub dir: Making directory with metadata 2025/02/02 05:17:20 INFO : sub dir: Made directory with metadata (mtime=2025-02-02T05:17:19.069333827Z) 2025/02/02 05:17:20 DEBUG : sub dir2: Making directory with metadata 2025/02/02 05:17:21 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/02 05:17:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:17:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:17:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:17:22 DEBUG : sub dir/hello world: md5 = bffefa97c0e73fdb3121e99818084438 OK 2025/02/02 05:17:22 INFO : sub dir/hello world: Copied (new) 2025/02/02 05:17:22 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:17:25 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestMoveEmptyDirectories (8.02s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:17:27 DEBUG : sub dir no update dir modtime: Making directory with metadata 2025/02/02 05:17:27 INFO : sub dir no update dir modtime: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/02/02 05:17:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:17:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:17:28 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncNoUpdateDirModtime (4.25s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:17:31 INFO : sub dir2: Making directory 2025/02/02 05:17:31 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:17:31 DEBUG : Added delayed dir = "sub dir2", newDst= 2025/02/02 05:17:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:17:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:17:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:17:33 DEBUG : sub dir/hello world: md5 = b764ad3b72c82db25ad6336e8681b904 OK 2025/02/02 05:17:33 INFO : sub dir/hello world: Copied (new) 2025/02/02 05:17:33 INFO : sub dir/hello world: Deleted 2025/02/02 05:17:34 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/02/02 05:17:36 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestMoveNoEmptyDirectories (5.80s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:17:37 DEBUG : sub dir2: Making directory with metadata 2025/02/02 05:17:37 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/02 05:17:37 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/02/02 05:17:37 DEBUG : sub dir: Making directory with metadata 2025/02/02 05:17:38 INFO : sub dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/02/02 05:17:38 DEBUG : sub dir2: Making directory with metadata 2025/02/02 05:17:39 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/02 05:17:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:17:39 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:17:39 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:17:40 DEBUG : sub dir/hello world: md5 = 6ead2d61377a243ac4047fc6d7534b4e OK 2025/02/02 05:17:40 INFO : sub dir/hello world: Copied (new) 2025/02/02 05:17:40 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:17:43 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestSyncEmptyDirectories (7.96s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.45s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:17:45 INFO : sub dir2: Making directory 2025/02/02 05:17:45 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:17:45 DEBUG : Added delayed dir = "sub dir2", newDst= 2025/02/02 05:17:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:17:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:17:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:17:48 DEBUG : sub dir/hello world: md5 = 015a288eccf8c0b04dff69041d8e3935 OK 2025/02/02 05:17:48 INFO : sub dir/hello world: Copied (new) 2025/02/02 05:17:48 DEBUG : Waiting for deletions to finish 2025/02/02 05:17:48 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/02/02 05:17:50 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestSyncNoEmptyDirectories (6.14s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:17:54 DEBUG : sub dir/hello world: md5 = b5187e678e6d42fddd18bce7f0cd484d OK 2025/02/02 05:17:54 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-rexadep6mazu" 2025/02/02 05:17:54 DEBUG : Config file has changed externally - reloading 2025/02/02 05:17:54 DEBUG : Creating backend with remote "TestDrive:crypt/619n0fdjh55mbqq972q0v1jogth9eg52dh1l7skpagn07a84jsbg" sync_test.go:590: Server side copy (if possible) Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena' -> Encrypted drive 'TestCryptDrive:rclone-test-rexadep6mazu' 2025/02/02 05:17:56 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:17:56 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:17:56 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-rexadep6mazu': Waiting for checks to finish 2025/02/02 05:17:56 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-rexadep6mazu': Waiting for transfers to finish 2025/02/02 05:17:59 INFO : sub dir/hello world: Copied (server-side copy) 2025/02/02 05:17:59 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:18:00 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-rexadep6mazu': Purge remote 2025/02/02 05:18:02 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestServerSideCopy (11.85s) === RUN TestCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:18:06 DEBUG : sub dir/hello world: md5 = 18cf434e20619d578b502380eb49e411 OK 2025/02/02 05:18:07 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/02/02 05:18:07 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/02/02 05:18:07 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:18:07 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:18:08 DEBUG : sub dir/hello world: md5 = 284a59cd3f06779ef8744681dfde6d04 OK 2025/02/02 05:18:08 INFO : sub dir/hello world: Copied (replaced existing) 2025/02/02 05:18:09 INFO : sub dir: Set directory modification time (using SetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:18:10 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestCopyOverSelf (8.54s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:18:14 DEBUG : sub dir/hello world: md5 = 5567aac6c50d40b7fa966747a6183ba8 OK 2025/02/02 05:18:14 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-sakepiz9wapa" 2025/02/02 05:18:15 DEBUG : Creating backend with remote "TestDrive:crypt/4ee6biq6a6rci2g6ea9grpd9gs5nvtjabucem0ina14s92im865g" sync_test.go:626: Server side copy (if possible) Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena' -> Encrypted drive 'TestCryptDrive:rclone-test-sakepiz9wapa' 2025/02/02 05:18:16 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:18:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:18:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-sakepiz9wapa': Waiting for checks to finish 2025/02/02 05:18:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-sakepiz9wapa': Waiting for transfers to finish 2025/02/02 05:18:19 INFO : sub dir/hello world: Copied (server-side copy) 2025/02/02 05:18:20 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:18:22 DEBUG : sub dir/hello world: md5 = f2033e911823642ce34c895e8742b4f1 OK 2025/02/02 05:18:23 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1ms) 2025/02/02 05:18:23 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/02/02 05:18:23 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/02/02 05:18:23 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-sakepiz9wapa': Waiting for checks to finish 2025/02/02 05:18:23 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-sakepiz9wapa': Waiting for transfers to finish 2025/02/02 05:18:25 INFO : sub dir/hello world: Copied (server-side copy) 2025/02/02 05:18:25 INFO : sub dir: Set directory modification time (using SetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:18:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-sakepiz9wapa': Purge remote 2025/02/02 05:18:27 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestServerSideCopyOverSelf (17.64s) === RUN TestMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:18:32 DEBUG : sub dir/hello world: md5 = 69f674bb6d8d597e80c728ca1069dcfe OK 2025/02/02 05:18:33 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/02/02 05:18:33 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/02/02 05:18:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:18:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:18:34 DEBUG : sub dir/hello world: md5 = 3933d5684cf6461fbb1cb7cb558db28d OK 2025/02/02 05:18:34 INFO : sub dir/hello world: Copied (replaced existing) 2025/02/02 05:18:34 INFO : sub dir/hello world: Deleted 2025/02/02 05:18:35 INFO : sub dir: Set directory modification time (using SetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:18:36 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestMoveOverSelf (8.56s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:18:40 DEBUG : sub dir/hello world: md5 = a8139dd03cc341a541cb7df376245406 OK 2025/02/02 05:18:41 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-dixufuk3kuji" 2025/02/02 05:18:41 DEBUG : Creating backend with remote "TestDrive:crypt/ms6safl9e4i075pc79diohuvaep10jecd4gq28pn9calh9h7m270" sync_test.go:671: Server side copy (if possible) Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena' -> Encrypted drive 'TestCryptDrive:rclone-test-dixufuk3kuji' 2025/02/02 05:18:42 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:18:42 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:18:42 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-dixufuk3kuji': Waiting for checks to finish 2025/02/02 05:18:42 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-dixufuk3kuji': Waiting for transfers to finish 2025/02/02 05:18:46 INFO : sub dir/hello world: Copied (server-side copy) 2025/02/02 05:18:46 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:18:48 DEBUG : sub dir/hello world: md5 = b481d6f4f20cf35c33bb9e489f267949 OK 2025/02/02 05:18:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-dixufuk3kuji': Using server-side directory move 2025/02/02 05:18:49 INFO : Encrypted drive 'TestCryptDrive:rclone-test-dixufuk3kuji': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/02/02 05:18:49 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1ms) 2025/02/02 05:18:49 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/02/02 05:18:49 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/02/02 05:18:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-dixufuk3kuji': Waiting for checks to finish 2025/02/02 05:18:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-dixufuk3kuji': Waiting for transfers to finish 2025/02/02 05:18:50 INFO : sub dir/hello world: Deleted 2025/02/02 05:18:51 INFO : sub dir/hello world: Moved (server-side) 2025/02/02 05:18:51 INFO : sub dir: Set directory modification time (using SetModTime) 2025/02/02 05:18:54 DEBUG : sub dir/hello world: md5 = a40357847ada9bc26c386912c7a87d56 OK 2025/02/02 05:18:54 DEBUG : testing file moves 2025/02/02 05:18:54 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1ms) 2025/02/02 05:18:54 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/02/02 05:18:54 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/02/02 05:18:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-dixufuk3kuji': Waiting for checks to finish 2025/02/02 05:18:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-dixufuk3kuji': Waiting for transfers to finish 2025/02/02 05:18:55 INFO : sub dir/hello world: Deleted 2025/02/02 05:18:56 INFO : sub dir/hello world: Moved (server-side) 2025/02/02 05:18:56 INFO : sub dir: Set directory modification time (using SetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:18:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-dixufuk3kuji': Purge remote --- PASS: TestServerSideMoveOverSelf (22.72s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:03 DEBUG : sub dir/hello world: md5 = 4465efdae969a94d553299fa56886225 OK 2025/02/02 05:19:03 ERROR : : error listing: directory not found 2025/02/02 05:19:04 INFO : Local file system at /tmp/rclone2748306405: Making directory 2025/02/02 05:19:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:19:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:06 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestCopyAfterDelete (6.80s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:10 DEBUG : sub dir/hello world: md5 = 5f328e7962e396823c1bea52336a5578 OK 2025/02/02 05:19:11 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:19:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:19:11 DEBUG : Local file system at /tmp/rclone2748306405: Waiting for checks to finish 2025/02/02 05:19:11 DEBUG : Local file system at /tmp/rclone2748306405: Waiting for transfers to finish 2025/02/02 05:19:12 DEBUG : sub dir/hello world.285f0a30.partial: renamed to: sub dir/hello world 2025/02/02 05:19:12 INFO : sub dir/hello world: Copied (new) 2025/02/02 05:19:12 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:13 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestCopyRedownload (7.01s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:15 DEBUG : check sum: Need to transfer - File not found at Destination 2025/02/02 05:19:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:19:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:19:16 DEBUG : check sum: md5 = d5b68d904e8a43951653b43e3a36dfed OK 2025/02/02 05:19:16 INFO : check sum: Copied (new) 2025/02/02 05:19:16 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/02/02 05:19:17 DEBUG : check sum: Size of src and dst objects identical 2025/02/02 05:19:17 DEBUG : check sum: Unchanged skipping 2025/02/02 05:19:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:19:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:19:17 DEBUG : Waiting for deletions to finish 2025/02/02 05:19:17 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncBasedOnCheckSum (4.47s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:19 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/02/02 05:19:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:19:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:19:21 DEBUG : sizeonly: md5 = 52d310f1985ad8a760b1497b3c942ae1 OK 2025/02/02 05:19:21 INFO : sizeonly: Copied (new) 2025/02/02 05:19:21 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:21 DEBUG : sizeonly: Sizes identical 2025/02/02 05:19:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:19:21 DEBUG : sizeonly: Unchanged skipping 2025/02/02 05:19:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:19:21 DEBUG : Waiting for deletions to finish 2025/02/02 05:19:21 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncSizeOnly (4.84s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:24 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/02/02 05:19:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:19:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:19:26 DEBUG : ignore-size: md5 = eb2ff8c105f05e133e185c5df09be7ae OK 2025/02/02 05:19:26 INFO : ignore-size: Copied (new) 2025/02/02 05:19:26 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:19:26 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:19:26 DEBUG : ignore-size: Unchanged skipping 2025/02/02 05:19:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:19:26 DEBUG : Waiting for deletions to finish 2025/02/02 05:19:26 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncIgnoreSize (4.61s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:30 DEBUG : existing: md5 = 913fbc1a78705980930dcc9daf3e3293 OK 2025/02/02 05:19:30 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:19:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:19:30 DEBUG : existing: Unchanged skipping 2025/02/02 05:19:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:19:30 DEBUG : Waiting for deletions to finish 2025/02/02 05:19:30 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:31 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/02/02 05:19:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:19:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:19:32 DEBUG : existing: md5 = df9eba92782f279d026d3547e7cc0aac OK 2025/02/02 05:19:32 INFO : existing: Copied (replaced existing) 2025/02/02 05:19:32 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncIgnoreTimes (5.86s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:34 DEBUG : existing: Need to transfer - File not found at Destination 2025/02/02 05:19:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:19:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:19:36 DEBUG : existing: md5 = 35b07c7cbd7153360d3ed2b6bacd5399 OK 2025/02/02 05:19:36 INFO : existing: Copied (new) 2025/02/02 05:19:36 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:36 DEBUG : existing: Destination exists, skipping 2025/02/02 05:19:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:19:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:19:36 DEBUG : Waiting for deletions to finish 2025/02/02 05:19:36 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncIgnoreExisting (4.38s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:41 DEBUG : b/potato: md5 = 4b32f3d01f788ebb9ebb7ba73598b777 OK 2025/02/02 05:19:44 DEBUG : c/non empty space: md5 = e29ed2a2989bd8d489001f25a61bcf02 OK 2025/02/02 05:19:44 INFO : d: Making directory 2025/02/02 05:19:46 DEBUG : Added delayed dir = "a", newDst= 2025/02/02 05:19:46 DEBUG : Added delayed dir = "c", newDst=c 2025/02/02 05:19:46 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:19:46 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/02/02 05:19:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:19:46 DEBUG : c/non empty space: Unchanged skipping 2025/02/02 05:19:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:19:48 DEBUG : a/potato2: md5 = 880a3bf5e4db8fe007c6255d07efa5aa OK 2025/02/02 05:19:48 INFO : a/potato2: Copied (new) 2025/02/02 05:19:48 DEBUG : Waiting for deletions to finish 2025/02/02 05:19:49 INFO : b/potato: Deleted 2025/02/02 05:19:49 INFO : a: Set directory modification time (using DirSetModTime) 2025/02/02 05:19:49 INFO : d: Removing directory 2025/02/02 05:19:50 INFO : b: Removing directory 2025/02/02 05:19:50 DEBUG : 32d8ua6196mc1r7qkfog53rkg0: Rmdir: contains trashed file: "2m507n64kafsi59v9q71r281a4" 2025/02/02 05:19:51 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': deleted 2 directories run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:53 DEBUG : 3i9du405pfogq3ncai6pg7ouio: Rmdir: contains trashed file: "982d3pss7t5pn0il16r2foj31s" 2025/02/02 05:19:54 DEBUG : 2bsvlrk2ne1lfitm6tttcu0qt8: Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" --- PASS: TestSyncIgnoreErrors (17.01s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:57 DEBUG : empty space: md5 = 9a84f69259f16447c1f39e32d9cc1bd9 OK 2025/02/02 05:19:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:19:57 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2025/02/02 05:19:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:19:57 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2025/02/02 05:19:57 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:19:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:19:58 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2025/02/02 05:19:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:20:00 DEBUG : empty space: md5 = 35626c3f943ff8c17ea4c3838eb15cf6 OK 2025/02/02 05:20:00 INFO : empty space: Copied (replaced existing) 2025/02/02 05:20:00 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncAfterChangingModtimeOnly (6.28s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" sync_test.go:1016: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.46s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:20:04 DEBUG : foo: md5 = 2c041ecd6e435aee7072cd2c1619d7cd OK 2025/02/02 05:20:04 DEBUG : foo: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2025/02/02 05:20:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:20:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:20:06 DEBUG : foo: md5 = 762275ebc697236dbfaa0b859866007d OK 2025/02/02 05:20:06 INFO : foo: Copied (replaced existing) 2025/02/02 05:20:06 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncDoesntUpdateModtime (5.28s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:20:09 DEBUG : empty space: md5 = e665c3208775ad58780879e27a1ccb18 OK 2025/02/02 05:20:10 DEBUG : potato: Need to transfer - File not found at Destination 2025/02/02 05:20:10 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:20:10 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:20:10 DEBUG : empty space: Unchanged skipping 2025/02/02 05:20:10 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:20:11 DEBUG : potato: md5 = ad594c5efb67a18b55402922da54e30a OK 2025/02/02 05:20:11 INFO : potato: Copied (new) 2025/02/02 05:20:11 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncAfterAddingAFile (6.17s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:20:15 DEBUG : potato: md5 = cd2118f8839842052124f84fb2d99958 OK 2025/02/02 05:20:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:20:16 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/02/02 05:20:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:20:17 DEBUG : potato: md5 = a034531d0b12a8aaf032eaa03b4c258f OK 2025/02/02 05:20:17 INFO : potato: Copied (replaced existing) 2025/02/02 05:20:17 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncAfterChangingFilesSizeOnly (5.27s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:20:20 DEBUG : potato: md5 = dd73951556b37b8f2903f6150043703d OK 2025/02/02 05:20:21 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2025/02/02 05:20:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:20:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:20:22 DEBUG : potato: md5 = 2fccf3f248fdf0575a593c5d209aaf04 OK 2025/02/02 05:20:22 INFO : potato: Copied (replaced existing) 2025/02/02 05:20:22 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncAfterChangingContentsOnly (5.09s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:20:26 DEBUG : potato: md5 = bf80ed1b22bf652753a35bbd08648e2f OK 2025/02/02 05:20:27 DEBUG : empty space: md5 = a6730febae630554673b90d8f4e8b0f5 OK 2025/02/02 05:20:27 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/02 05:20:27 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/02/02 05:20:27 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:20:27 DEBUG : empty space: Unchanged skipping 2025/02/02 05:20:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:20:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:20:27 DEBUG : Waiting for deletions to finish 2025/02/02 05:20:27 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (5.91s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:20:31 DEBUG : potato: md5 = 0464aaeb124111c3a837308d76b54f67 OK 2025/02/02 05:20:33 DEBUG : empty space: md5 = 55676527d0a1f81d6537997bb966edd7 OK 2025/02/02 05:20:33 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/02 05:20:33 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:20:33 DEBUG : empty space: Unchanged skipping 2025/02/02 05:20:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:20:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:20:35 DEBUG : potato2: md5 = f589e5f008fae87606a7e5823ac1122d OK 2025/02/02 05:20:35 INFO : potato2: Copied (new) 2025/02/02 05:20:35 DEBUG : Waiting for deletions to finish 2025/02/02 05:20:36 INFO : potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (7.95s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:20:40 DEBUG : b/potato: md5 = 87c9b24659b7dfbc8576f20417db2aa5 OK 2025/02/02 05:20:43 DEBUG : c/non empty space: md5 = 7c5d527263e14f90cdd72f6965d8e0a2 OK 2025/02/02 05:20:43 INFO : d: Making directory 2025/02/02 05:20:44 INFO : d/e: Making directory 2025/02/02 05:20:46 DEBUG : Added delayed dir = "a", newDst= 2025/02/02 05:20:46 DEBUG : Added delayed dir = "c", newDst=c 2025/02/02 05:20:46 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:20:46 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/02/02 05:20:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:20:46 DEBUG : c/non empty space: Unchanged skipping 2025/02/02 05:20:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:20:49 DEBUG : a/potato2: md5 = 1612e0246b7986ca671c2c49394d130d OK 2025/02/02 05:20:49 INFO : a/potato2: Copied (new) 2025/02/02 05:20:49 DEBUG : Waiting for deletions to finish 2025/02/02 05:20:50 INFO : b/potato: Deleted 2025/02/02 05:20:50 INFO : a: Set directory modification time (using DirSetModTime) 2025/02/02 05:20:50 INFO : d/e: Removing directory 2025/02/02 05:20:51 INFO : d: Removing directory 2025/02/02 05:20:51 DEBUG : ka6id8d20d3s5ktapedlphgta8: Rmdir: contains trashed file: "r8sppajuabeak3sbgj2ak8vuf8" 2025/02/02 05:20:52 INFO : b: Removing directory 2025/02/02 05:20:52 DEBUG : 32d8ua6196mc1r7qkfog53rkg0: Rmdir: contains trashed file: "2m507n64kafsi59v9q71r281a4" 2025/02/02 05:20:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': deleted 3 directories run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:20:55 DEBUG : 3i9du405pfogq3ncai6pg7ouio: Rmdir: contains trashed file: "982d3pss7t5pn0il16r2foj31s" 2025/02/02 05:20:56 DEBUG : 2bsvlrk2ne1lfitm6tttcu0qt8: Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (19.28s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:21:00 DEBUG : b/potato: md5 = 8f213f82685ecaf5af4417db4dc1df85 OK 2025/02/02 05:21:02 DEBUG : c/non empty space: md5 = d5f2fe817eb303bc92c73ac7e9841130 OK 2025/02/02 05:21:02 INFO : d: Making directory 2025/02/02 05:21:04 DEBUG : Added delayed dir = "a", newDst= 2025/02/02 05:21:04 DEBUG : Added delayed dir = "c", newDst=c 2025/02/02 05:21:04 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:21:04 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/02/02 05:21:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:21:04 DEBUG : c/non empty space: Unchanged skipping 2025/02/02 05:21:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:21:07 DEBUG : a/potato2: md5 = 6f328339eef920bb6127869de2bb02aa OK 2025/02/02 05:21:07 INFO : a/potato2: Copied (new) 2025/02/02 05:21:07 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': not deleting files as there were IO errors 2025/02/02 05:21:07 INFO : a: Set directory modification time (using DirSetModTime) 2025/02/02 05:21:07 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:21:11 DEBUG : 3i9du405pfogq3ncai6pg7ouio: Rmdir: contains trashed file: "982d3pss7t5pn0il16r2foj31s" 2025/02/02 05:21:12 DEBUG : 32d8ua6196mc1r7qkfog53rkg0: Rmdir: contains trashed file: "2m507n64kafsi59v9q71r281a4" 2025/02/02 05:21:13 DEBUG : 2bsvlrk2ne1lfitm6tttcu0qt8: Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (17.20s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:21:16 DEBUG : potato: md5 = f4a0b87f1895fa3f67db5a7e884419b2 OK 2025/02/02 05:21:18 DEBUG : empty space: md5 = abb42f75ed21ef2e7cb7fa86516663ec OK 2025/02/02 05:21:18 DEBUG : Waiting for deletions to finish 2025/02/02 05:21:18 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/02 05:21:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:21:18 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:21:18 DEBUG : empty space: Unchanged skipping 2025/02/02 05:21:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:21:19 INFO : potato: Deleted 2025/02/02 05:21:20 DEBUG : potato2: md5 = 521e46ceb5e9bb22f129e43f120c78a3 OK 2025/02/02 05:21:20 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncDeleteDuring (7.57s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:21:24 DEBUG : potato: md5 = c3c96b883758fda376e2960340361803 OK 2025/02/02 05:21:25 DEBUG : empty space: md5 = c1b7edbc0222e5794dfe4d1bb51424e3 OK 2025/02/02 05:21:25 DEBUG : Waiting for deletions to finish 2025/02/02 05:21:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:21:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:21:26 INFO : potato: Deleted 2025/02/02 05:21:26 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/02 05:21:26 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:21:26 DEBUG : empty space: Unchanged skipping 2025/02/02 05:21:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:21:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:21:28 DEBUG : potato2: md5 = d13f6f91e13f8ae410e754b105e6f813 OK 2025/02/02 05:21:28 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncDeleteBefore (8.58s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:21:32 DEBUG : potato: md5 = ecf2a8fb762cb51bc7cdc124215e567a OK 2025/02/02 05:21:33 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/02 05:21:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:21:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:21:34 DEBUG : potato2: md5 = 31bca810e95a21b7c2eda3ec4b24886b OK 2025/02/02 05:21:34 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestCopyDeleteBefore (6.33s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:21:39 DEBUG : potato2: md5 = 42fc0cb9b082aa82619f7dcd79a07add OK 2025/02/02 05:21:40 DEBUG : empty space: md5 = 5c906ca96357d82ef33fa11cc32efaba OK 2025/02/02 05:21:40 DEBUG : enormous: Excluded (Size Filter) 2025/02/02 05:21:40 DEBUG : enormous: Excluded 2025/02/02 05:21:40 DEBUG : potato2: Excluded (Size Filter) 2025/02/02 05:21:40 DEBUG : potato2: Excluded 2025/02/02 05:21:41 DEBUG : potato2: Excluded (Size Filter) 2025/02/02 05:21:41 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:21:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:21:41 DEBUG : empty space: Unchanged skipping 2025/02/02 05:21:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:21:41 DEBUG : Waiting for deletions to finish 2025/02/02 05:21:41 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:21:41 DEBUG : enormous: Excluded (Size Filter) 2025/02/02 05:21:41 DEBUG : enormous: Excluded 2025/02/02 05:21:41 DEBUG : potato2: Excluded (Size Filter) 2025/02/02 05:21:41 DEBUG : potato2: Excluded 2025/02/02 05:21:41 DEBUG : potato2: Excluded (Size Filter) 2025/02/02 05:21:41 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2025/02/02 05:21:41 DEBUG : Local file system at /tmp/rclone2748306405: Waiting for checks to finish 2025/02/02 05:21:41 DEBUG : empty space: Unchanged skipping 2025/02/02 05:21:41 DEBUG : Local file system at /tmp/rclone2748306405: Waiting for transfers to finish 2025/02/02 05:21:41 DEBUG : Waiting for deletions to finish 2025/02/02 05:21:41 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncWithExclude (6.75s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:21:45 DEBUG : potato2: md5 = 54e241bed769a8e1a397db5b40db15b7 OK 2025/02/02 05:21:47 DEBUG : empty space: md5 = 1dd3c96639bd6681714352032f2fa60e OK 2025/02/02 05:21:48 DEBUG : enormous: md5 = e6e7d8cd3a98997407481569dce9517e OK 2025/02/02 05:21:48 DEBUG : enormous: Excluded (Size Filter) 2025/02/02 05:21:48 DEBUG : enormous: Excluded 2025/02/02 05:21:48 DEBUG : potato2: Excluded (Size Filter) 2025/02/02 05:21:48 DEBUG : potato2: Excluded 2025/02/02 05:21:48 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:21:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:21:48 DEBUG : empty space: Unchanged skipping 2025/02/02 05:21:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:21:48 DEBUG : Waiting for deletions to finish 2025/02/02 05:21:49 INFO : enormous: Deleted 2025/02/02 05:21:49 INFO : potato2: Deleted 2025/02/02 05:21:49 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:21:50 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2025/02/02 05:21:50 DEBUG : Local file system at /tmp/rclone2748306405: Waiting for checks to finish 2025/02/02 05:21:50 DEBUG : empty space: Unchanged skipping 2025/02/02 05:21:50 DEBUG : Local file system at /tmp/rclone2748306405: Waiting for transfers to finish 2025/02/02 05:21:50 DEBUG : Waiting for deletions to finish 2025/02/02 05:21:50 INFO : enormous: Deleted 2025/02/02 05:21:50 INFO : potato2: Deleted 2025/02/02 05:21:50 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncWithExcludeAndDeleteExcluded (7.98s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:21:53 DEBUG : one: md5 = cc5f4b83f4e369007685dc009dd7719d OK 2025/02/02 05:21:55 DEBUG : two: md5 = e8368dffcac697a435203069f3568ed3 OK 2025/02/02 05:21:56 DEBUG : three: md5 = 35afb287d2c88dbf9687e73b60be019a OK 2025/02/02 05:21:58 DEBUG : four: md5 = 373eef4f91a500035d00abd11ca9fe88 OK 2025/02/02 05:21:59 DEBUG : five: Need to transfer - File not found at Destination 2025/02/02 05:21:59 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/02/02 05:21:59 DEBUG : one: Destination is newer than source, skipping 2025/02/02 05:21:59 DEBUG : three: Sizes identical 2025/02/02 05:21:59 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:21:59 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/02/02 05:21:59 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/02/02 05:21:59 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:22:00 DEBUG : two: md5 = 1e0fe60c3c163c666ab86650b736c371 OK 2025/02/02 05:22:00 INFO : two: Copied (replaced existing) 2025/02/02 05:22:00 DEBUG : four: md5 = 539584dfe88931ddc3f270800a63bb64 OK 2025/02/02 05:22:00 INFO : four: Copied (replaced existing) 2025/02/02 05:22:00 DEBUG : five: md5 = 7cb5e4a2bce68b8344f8289f87662bb7 OK 2025/02/02 05:22:00 INFO : five: Copied (new) 2025/02/02 05:22:00 DEBUG : Waiting for deletions to finish sync_test.go:1449: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (12.68s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1466: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1466: Skipping test on non local remote --- PASS: TestSyncWithMaxDuration (0.00s) --- SKIP: TestSyncWithMaxDuration/Hard (0.00s) --- SKIP: TestSyncWithMaxDuration/Soft (0.00s) === RUN TestSyncWithTrackRenames run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" sync_test.go:1538: Can track renames: false 2025/02/02 05:22:04 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Ignoring --track-renames as the source and destination do not have a common hash 2025/02/02 05:22:04 DEBUG : potato: Need to transfer - File not found at Destination 2025/02/02 05:22:04 DEBUG : yam: Need to transfer - File not found at Destination 2025/02/02 05:22:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:22:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:22:06 DEBUG : potato: md5 = 6ef47281522fc7619e2dd3a8a252c0bc OK 2025/02/02 05:22:06 INFO : potato: Copied (new) 2025/02/02 05:22:06 DEBUG : yam: md5 = 732e98a666b2c1cc5eb5559f984f6141 OK 2025/02/02 05:22:06 INFO : yam: Copied (new) 2025/02/02 05:22:06 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:22:06 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Ignoring --track-renames as the source and destination do not have a common hash 2025/02/02 05:22:07 DEBUG : yaml: Need to transfer - File not found at Destination 2025/02/02 05:22:07 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:22:07 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:22:07 DEBUG : potato: Unchanged skipping 2025/02/02 05:22:07 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:22:08 DEBUG : yaml: md5 = b0cc3b133e3304c34e7f679e1c680f05 OK 2025/02/02 05:22:08 INFO : yaml: Copied (new) 2025/02/02 05:22:08 DEBUG : Waiting for deletions to finish 2025/02/02 05:22:09 INFO : yam: Deleted run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncWithTrackRenames (7.38s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" sync_test.go:1610: Can track renames: true 2025/02/02 05:22:12 INFO : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Making map for --track-renames 2025/02/02 05:22:12 INFO : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Finished making map for --track-renames 2025/02/02 05:22:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:22:12 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/02/02 05:22:12 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/02/02 05:22:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for renames to finish 2025/02/02 05:22:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:22:13 DEBUG : yam: md5 = 5c6c16db68bbb3c50bad6e3f93d60545 OK 2025/02/02 05:22:13 INFO : yam: Copied (new) 2025/02/02 05:22:13 DEBUG : potato: md5 = af285be482a07c8ef21c90b5edb65872 OK 2025/02/02 05:22:13 INFO : potato: Copied (new) 2025/02/02 05:22:13 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:22:14 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:22:14 INFO : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Making map for --track-renames 2025/02/02 05:22:14 INFO : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Finished making map for --track-renames 2025/02/02 05:22:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:22:14 DEBUG : potato: Unchanged skipping 2025/02/02 05:22:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for renames to finish 2025/02/02 05:22:15 INFO : yam: Moved (server-side) to: yaml 2025/02/02 05:22:15 INFO : yaml: Renamed from "yam" 2025/02/02 05:22:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:22:15 DEBUG : Waiting for deletions to finish 2025/02/02 05:22:15 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncWithTrackRenamesStrategyModtime (5.97s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" sync_test.go:1649: Can track renames: true 2025/02/02 05:22:18 DEBUG : Added delayed dir = "sub", newDst= 2025/02/02 05:22:18 INFO : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Making map for --track-renames 2025/02/02 05:22:18 INFO : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Finished making map for --track-renames 2025/02/02 05:22:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:22:18 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/02/02 05:22:18 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/02/02 05:22:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for renames to finish 2025/02/02 05:22:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:22:21 DEBUG : potato: md5 = 3d6610c4da11b6e0db41d27902e44a57 OK 2025/02/02 05:22:21 INFO : potato: Copied (new) 2025/02/02 05:22:21 DEBUG : sub/yam: md5 = 0e7b1cdc506327cf481d01dc34a301c4 OK 2025/02/02 05:22:21 INFO : sub/yam: Copied (new) 2025/02/02 05:22:21 DEBUG : Waiting for deletions to finish 2025/02/02 05:22:21 INFO : sub: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:22:23 DEBUG : Added delayed dir = "sub", newDst=sub 2025/02/02 05:22:23 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:22:23 DEBUG : potato: Unchanged skipping 2025/02/02 05:22:23 INFO : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Making map for --track-renames 2025/02/02 05:22:23 INFO : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Finished making map for --track-renames 2025/02/02 05:22:23 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:22:23 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for renames to finish 2025/02/02 05:22:24 INFO : sub/yam: Moved (server-side) to: yam 2025/02/02 05:22:24 INFO : yam: Renamed from "sub/yam" 2025/02/02 05:22:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:22:24 DEBUG : Waiting for deletions to finish 2025/02/02 05:22:24 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (10.33s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:22:28 DEBUG : Creating backend with remote "/tmp/rclone2748306405/dir1" 2025/02/02 05:22:28 DEBUG : Creating backend with remote "/tmp/rclone2748306405/dir2" 2025/02/02 05:22:28 DEBUG : Local file system at /tmp/rclone2748306405/dir2: Using server-side directory move 2025/02/02 05:22:28 INFO : Local file system at /tmp/rclone2748306405/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/02/02 05:22:28 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/02/02 05:22:28 INFO : file1.txt: Moved (server-side) 2025/02/02 05:22:28 DEBUG : Local file system at /tmp/rclone2748306405/dir2: Waiting for checks to finish 2025/02/02 05:22:28 DEBUG : Local file system at /tmp/rclone2748306405/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (0.43s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:22:29 DEBUG : Added delayed dir = "nested", newDst= 2025/02/02 05:22:29 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:22:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:22:29 DEBUG : Added delayed dir = "nested/sub dir", newDst= 2025/02/02 05:22:29 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/02/02 05:22:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:22:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:22:31 DEBUG : sub dir/hello world: md5 = 04d58368df4de3d07da064a5a3715741 OK 2025/02/02 05:22:31 INFO : sub dir/hello world: Copied (new) 2025/02/02 05:22:31 INFO : sub dir/hello world: Deleted 2025/02/02 05:22:34 DEBUG : nested/sub dir/file: md5 = bf241962dd8caedc8b71554ad7d56c8a OK 2025/02/02 05:22:34 INFO : nested/sub dir/file: Copied (new) 2025/02/02 05:22:34 INFO : nested/sub dir/file: Deleted 2025/02/02 05:22:35 INFO : nested/sub dir: Set directory modification time (using DirSetModTime) 2025/02/02 05:22:35 INFO : nested: Set directory modification time (using DirSetModTime) 2025/02/02 05:22:35 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/02/02 05:22:35 INFO : sub dir: Removing directory 2025/02/02 05:22:35 INFO : nested/sub dir: Removing directory 2025/02/02 05:22:35 INFO : nested: Removing directory 2025/02/02 05:22:35 DEBUG : Local file system at /tmp/rclone2748306405: deleted 3 directories run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:22:38 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" 2025/02/02 05:22:39 DEBUG : 37qp6d1gohacv723ij3brofbu8/14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "ftp4kuv8se7tqduu6pmmcvjsmk" 2025/02/02 05:22:40 DEBUG : 37qp6d1gohacv723ij3brofbu8: Rmdir: contains trashed file: "14begaa36ih249fgrnfk6nulg0" --- PASS: TestMoveWithDeleteEmptySrcDirs (13.07s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:22:42 DEBUG : Added delayed dir = "nested", newDst= 2025/02/02 05:22:42 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/02 05:22:42 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/02 05:22:42 DEBUG : Added delayed dir = "nested/sub dir", newDst= 2025/02/02 05:22:42 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/02/02 05:22:42 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:22:42 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:22:44 DEBUG : sub dir/hello world: md5 = aa3007713f2330d766c3f02f5504b013 OK 2025/02/02 05:22:44 INFO : sub dir/hello world: Copied (new) 2025/02/02 05:22:44 INFO : sub dir/hello world: Deleted 2025/02/02 05:22:46 DEBUG : nested/sub dir/file: md5 = 00d030a514220749ca881deb96b2f9ba OK 2025/02/02 05:22:46 INFO : nested/sub dir/file: Copied (new) 2025/02/02 05:22:46 INFO : nested/sub dir/file: Deleted 2025/02/02 05:22:47 INFO : nested/sub dir: Set directory modification time (using DirSetModTime) 2025/02/02 05:22:48 INFO : nested: Set directory modification time (using DirSetModTime) 2025/02/02 05:22:48 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:22:51 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" 2025/02/02 05:22:51 DEBUG : 37qp6d1gohacv723ij3brofbu8/14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "ftp4kuv8se7tqduu6pmmcvjsmk" 2025/02/02 05:22:52 DEBUG : 37qp6d1gohacv723ij3brofbu8: Rmdir: contains trashed file: "14begaa36ih249fgrnfk6nulg0" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (12.04s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:22:54 DEBUG : existing: Need to transfer - File not found at Destination 2025/02/02 05:22:54 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/02/02 05:22:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:22:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:22:55 DEBUG : existing-b: md5 = 092a22576189620e9070828b3cd02272 OK 2025/02/02 05:22:55 INFO : existing-b: Copied (new) 2025/02/02 05:22:55 INFO : existing-b: Deleted 2025/02/02 05:22:55 DEBUG : existing: md5 = 120ccdf236e7ffa4009f22b5eeb8bc83 OK 2025/02/02 05:22:55 INFO : existing: Copied (new) 2025/02/02 05:22:55 INFO : existing: Deleted 2025/02/02 05:22:56 DEBUG : existing: Destination exists, skipping 2025/02/02 05:22:56 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/02/02 05:22:56 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:22:56 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:22:56 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestMoveWithIgnoreExisting (4.51s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:22:58 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-lokerop4hife" 2025/02/02 05:22:58 DEBUG : Creating backend with remote "TestDrive:crypt/kij46878r6jjru3auk6mid9efmv81qhl5es91irikr29ov8smk70" 2025/02/02 05:23:01 DEBUG : potato2: md5 = 9290cfdb77552c5925958101681adac7 OK 2025/02/02 05:23:02 DEBUG : empty space: md5 = f5032e031914163af9beaeaaf29ee128 OK 2025/02/02 05:23:04 DEBUG : potato3: md5 = dbd5661b5c8a6502125036c17b97a09f OK sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena' -> Encrypted drive 'TestCryptDrive:rclone-test-lokerop4hife' 2025/02/02 05:23:07 DEBUG : empty space: md5 = a0f432f4c7d0b9a9e75deed561500ad5 OK 2025/02/02 05:23:09 DEBUG : potato3: md5 = 3431dd8a5af878cbd47a3cab2f13b71d OK 2025/02/02 05:23:09 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-lokerop4hife': Using server-side directory move 2025/02/02 05:23:09 INFO : Encrypted drive 'TestCryptDrive:rclone-test-lokerop4hife': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/02/02 05:23:09 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/02 05:23:09 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/02/02 05:23:09 DEBUG : empty space: Unchanged skipping 2025/02/02 05:23:09 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/02/02 05:23:09 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-lokerop4hife': Waiting for checks to finish 2025/02/02 05:23:10 INFO : potato3: Deleted 2025/02/02 05:23:10 INFO : empty space: Deleted 2025/02/02 05:23:10 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-lokerop4hife': Waiting for transfers to finish 2025/02/02 05:23:10 INFO : potato2: Moved (server-side) 2025/02/02 05:23:11 INFO : potato3: Moved (server-side) 2025/02/02 05:23:11 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zayitac3voqo" 2025/02/02 05:23:11 DEBUG : Creating backend with remote "TestDrive:crypt/hd4uvc7vqjternv2mbblkcsqp7ledbeevpae9rlkk472ccrdcda0" 2025/02/02 05:23:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zayitac3voqo': Using server-side directory move 2025/02/02 05:23:13 INFO : Encrypted drive 'TestCryptDrive:rclone-test-zayitac3voqo': Server side directory move succeeded 2025/02/02 05:23:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zayitac3voqo': Purge remote 2025/02/02 05:23:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-lokerop4hife': Purge remote 2025/02/02 05:23:15 NOTICE: purge failed: directory not found --- PASS: TestServerSideMove (17.26s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:23:15 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-vodekus1ligi" 2025/02/02 05:23:15 DEBUG : Creating backend with remote "TestDrive:crypt/aqshnaj1cj2sojr98usli5vqn80j94e7qffnv6srnue78f97t5v0" 2025/02/02 05:23:18 DEBUG : potato2: md5 = b2ec3e3b706a198ffef144cbce769326 OK 2025/02/02 05:23:19 DEBUG : empty space: md5 = afa718bb4febcb3b4446fd47fda64ec1 OK 2025/02/02 05:23:21 DEBUG : potato3: md5 = a5dc3234fbbab4cb37a7f9cf69237e3e OK sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena' -> Encrypted drive 'TestCryptDrive:rclone-test-vodekus1ligi' 2025/02/02 05:23:24 DEBUG : empty space: md5 = 150c1bb547921b6d90af3b9eb69632c6 OK 2025/02/02 05:23:25 DEBUG : potato3: md5 = 147e8843fc1ca59b1b7675fb5f5af2a8 OK 2025/02/02 05:23:26 DEBUG : empty space: Excluded (Size Filter) 2025/02/02 05:23:26 DEBUG : empty space: Excluded (Size Filter) 2025/02/02 05:23:26 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/02 05:23:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vodekus1ligi': Waiting for checks to finish 2025/02/02 05:23:26 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/02/02 05:23:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vodekus1ligi': Waiting for transfers to finish 2025/02/02 05:23:27 INFO : potato3: Deleted 2025/02/02 05:23:27 INFO : potato2: Moved (server-side) 2025/02/02 05:23:27 INFO : potato3: Moved (server-side) 2025/02/02 05:23:28 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-ruzaroh6fuwu" 2025/02/02 05:23:28 DEBUG : Creating backend with remote "TestDrive:crypt/1vi72hvei3870gmeaf5c9rhjef31urjkgapa9rdrdcrd4brn6eq0" 2025/02/02 05:23:29 DEBUG : empty space: Excluded (Size Filter) 2025/02/02 05:23:29 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/02 05:23:29 DEBUG : potato3: Need to transfer - File not found at Destination 2025/02/02 05:23:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-ruzaroh6fuwu': Waiting for checks to finish 2025/02/02 05:23:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-ruzaroh6fuwu': Waiting for transfers to finish 2025/02/02 05:23:31 INFO : potato2: Moved (server-side) 2025/02/02 05:23:32 INFO : potato3: Moved (server-side) 2025/02/02 05:23:32 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-ruzaroh6fuwu': Purge remote 2025/02/02 05:23:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vodekus1ligi': Purge remote --- PASS: TestServerSideMoveWithFilter (19.01s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:23:34 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-karatun8tene" 2025/02/02 05:23:34 DEBUG : Creating backend with remote "TestDrive:crypt/5hl8klmagklds4rbpb3da0jd1e5jd1pnina64j8e37jbiaqqe020" 2025/02/02 05:23:37 DEBUG : potato2: md5 = 307e9eabce385ac3208991ade92b440e OK 2025/02/02 05:23:39 DEBUG : empty space: md5 = 4c0f68cc7ed4de68c8c155027c919794 OK 2025/02/02 05:23:40 DEBUG : potato3: md5 = 313f6cf9c843d145de9e3f7b05b95a42 OK 2025/02/02 05:23:40 INFO : tomatoDir: Making directory sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena' -> Encrypted drive 'TestCryptDrive:rclone-test-karatun8tene' 2025/02/02 05:23:44 DEBUG : empty space: md5 = 6991c442fe1277647a9a6e4e9a3fa391 OK 2025/02/02 05:23:46 DEBUG : potato3: md5 = 79790540dda5c104699a171c0f406c5f OK 2025/02/02 05:23:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-karatun8tene': Using server-side directory move 2025/02/02 05:23:46 INFO : Encrypted drive 'TestCryptDrive:rclone-test-karatun8tene': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/02/02 05:23:46 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/02 05:23:46 DEBUG : Added delayed dir = "tomatoDir", newDst= 2025/02/02 05:23:46 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/02/02 05:23:46 DEBUG : empty space: Unchanged skipping 2025/02/02 05:23:46 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/02/02 05:23:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-karatun8tene': Waiting for checks to finish 2025/02/02 05:23:47 INFO : empty space: Deleted 2025/02/02 05:23:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-karatun8tene': Waiting for transfers to finish 2025/02/02 05:23:47 INFO : potato3: Deleted 2025/02/02 05:23:48 INFO : potato2: Moved (server-side) 2025/02/02 05:23:49 INFO : potato3: Moved (server-side) 2025/02/02 05:23:49 INFO : tomatoDir: Removing directory 2025/02/02 05:23:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': deleted 1 directories 2025/02/02 05:23:51 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fofemac3kopu" 2025/02/02 05:23:51 DEBUG : Creating backend with remote "TestDrive:crypt/fcusmnr2358ib9qp5bbobeq50c6j2p0rsukk01sbfh397nr7hojg" 2025/02/02 05:23:52 INFO : tomatoDir: Making directory 2025/02/02 05:23:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fofemac3kopu': Using server-side directory move 2025/02/02 05:23:53 INFO : Encrypted drive 'TestCryptDrive:rclone-test-fofemac3kopu': Server side directory move succeeded 2025/02/02 05:23:55 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fofemac3kopu': Purge remote 2025/02/02 05:23:55 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-karatun8tene': Purge remote 2025/02/02 05:23:56 NOTICE: purge failed: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (21.85s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" sync_test.go:1910: Skipping test as remote supports DirMove --- SKIP: TestServerSideMoveOverlap (0.45s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:23:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/rclone-sync-test" 2025/02/02 05:23:57 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/i7355aa0sb89uagn75vfjqr6s3e3e1h2et8in57abn1fe8qfego0" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncOverlap (3.66s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:24:00 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/rclone-sync-test" 2025/02/02 05:24:00 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/i7355aa0sb89uagn75vfjqr6s3e3e1h2et8in57abn1fe8qfego0" 2025/02/02 05:24:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/rclone-sync-test-include/layer2" 2025/02/02 05:24:03 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/khf2vlblvnbf9ftevqm0htmss73hj1j5a7q328e4ohrgs7e2loe0/n01jpl9202nn9ec1rb75h0f1c4" 2025/02/02 05:24:06 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/rclone-sync-test-ignore-file" 2025/02/02 05:24:06 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/kmf11jujl9uha3tjganvgpkn45bu778hb5vb5riug1c26b38k7sg" 2025/02/02 05:24:10 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = e6f2bebeaf58c1d5350963e53281291b OK 2025/02/02 05:24:12 DEBUG : Added delayed dir = "rclone-sync-test-include", newDst= 2025/02/02 05:24:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/rclone-sync-test': Waiting for checks to finish 2025/02/02 05:24:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/rclone-sync-test': Waiting for transfers to finish 2025/02/02 05:24:12 DEBUG : Waiting for deletions to finish 2025/02/02 05:24:12 INFO : There was nothing to transfer 2025/02/02 05:24:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:24:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:24:14 DEBUG : Waiting for deletions to finish 2025/02/02 05:24:14 INFO : rclone-sync-test-include: Removing directory 2025/02/02 05:24:14 DEBUG : khf2vlblvnbf9ftevqm0htmss73hj1j5a7q328e4ohrgs7e2loe0: Rmdir: contains file: "n01jpl9202nn9ec1rb75h0f1c4" 2025/02/02 05:24:14 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty 2025/02/02 05:24:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': failed to delete 1 directories 2025/02/02 05:24:14 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:24:16 DEBUG : Added delayed dir = "rclone-sync-test-include", newDst= 2025/02/02 05:24:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/02/02 05:24:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/02/02 05:24:16 DEBUG : Waiting for deletions to finish 2025/02/02 05:24:16 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:24:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:24:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:24:18 DEBUG : Waiting for deletions to finish 2025/02/02 05:24:18 INFO : rclone-sync-test-include: Removing directory 2025/02/02 05:24:18 DEBUG : khf2vlblvnbf9ftevqm0htmss73hj1j5a7q328e4ohrgs7e2loe0: Rmdir: contains file: "n01jpl9202nn9ec1rb75h0f1c4" 2025/02/02 05:24:18 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty 2025/02/02 05:24:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': failed to delete 1 directories 2025/02/02 05:24:18 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:24:20 DEBUG : Added delayed dir = "rclone-sync-test-include", newDst= 2025/02/02 05:24:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/02/02 05:24:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/02/02 05:24:20 DEBUG : Waiting for deletions to finish 2025/02/02 05:24:20 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:24:23 DEBUG : khf2vlblvnbf9ftevqm0htmss73hj1j5a7q328e4ohrgs7e2loe0: Rmdir: contains trashed file: "n01jpl9202nn9ec1rb75h0f1c4" 2025/02/02 05:24:24 DEBUG : kmf11jujl9uha3tjganvgpkn45bu778hb5vb5riug1c26b38k7sg: Rmdir: contains trashed file: "i0t74httmdlljg425j6tg570d8" --- PASS: TestSyncOverlapWithFilter (32.60s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:24:33 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/dst" 2025/02/02 05:24:33 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/31u3jie661vd5p8j7rtc3hgbh0" 2025/02/02 05:24:34 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/CompareDest" 2025/02/02 05:24:34 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/gveqi14airsml4bgu7krj116o8" 2025/02/02 05:24:36 DEBUG : one: Need to transfer - File not found at Destination 2025/02/02 05:24:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:24:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:24:39 DEBUG : one: md5 = cfbfa2651983a1e37a8fe5cb4012b3a1 OK 2025/02/02 05:24:39 INFO : one: Copied (new) 2025/02/02 05:24:39 DEBUG : Waiting for deletions to finish 2025/02/02 05:24:40 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:24:40 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/02 05:24:40 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:24:41 DEBUG : one: md5 = 6f3a3104ce00e16a78df22fe81e1b0e4 OK 2025/02/02 05:24:41 INFO : one: Copied (replaced existing) 2025/02/02 05:24:41 DEBUG : Waiting for deletions to finish 2025/02/02 05:24:44 DEBUG : dst/one: md5 = 941ad2a195cdc22c2a30d8f593687ce2 OK 2025/02/02 05:24:46 DEBUG : CompareDest/one: md5 = eaebaa432a233338549b60819df009d7 OK 2025/02/02 05:24:47 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/02 05:24:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:24:48 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:24:48 DEBUG : one: Destination found in --compare-dest, skipping 2025/02/02 05:24:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:24:48 DEBUG : Waiting for deletions to finish 2025/02/02 05:24:48 INFO : There was nothing to transfer 2025/02/02 05:24:50 DEBUG : CompareDest/two: md5 = ef33820cfde5abfa3c5306f9e9aa5d70 OK 2025/02/02 05:24:51 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:24:51 DEBUG : two: Destination found in --compare-dest, skipping 2025/02/02 05:24:51 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/02 05:24:51 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:24:51 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:24:51 DEBUG : one: Destination found in --compare-dest, skipping 2025/02/02 05:24:51 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:24:51 DEBUG : Waiting for deletions to finish 2025/02/02 05:24:51 INFO : There was nothing to transfer 2025/02/02 05:24:52 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:24:52 DEBUG : two: Destination found in --compare-dest, skipping 2025/02/02 05:24:52 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/02 05:24:52 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:24:52 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:24:52 DEBUG : one: Destination found in --compare-dest, skipping 2025/02/02 05:24:52 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:24:52 DEBUG : Waiting for deletions to finish 2025/02/02 05:24:52 INFO : There was nothing to transfer sync_test.go:2175: No hash on uploaded file so skipping compare timestamp test 2025/02/02 05:24:54 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/02/02 05:24:54 DEBUG : two: Need to transfer - File not found at Destination 2025/02/02 05:24:54 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/02 05:24:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:24:54 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:24:54 DEBUG : one: Destination found in --compare-dest, skipping 2025/02/02 05:24:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:24:55 DEBUG : two: md5 = 4a8110b592b0733472e466f4e3ce66e1 OK 2025/02/02 05:24:55 INFO : two: Copied (new) 2025/02/02 05:24:55 DEBUG : Waiting for deletions to finish 2025/02/02 05:24:59 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2025/02/02 05:24:59 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/02/02 05:25:00 DEBUG : gveqi14airsml4bgu7krj116o8: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/02/02 05:25:00 DEBUG : gveqi14airsml4bgu7krj116o8: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" --- PASS: TestSyncCompareDest (27.72s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:25:03 DEBUG : pre-dest1/1: md5 = 3cfeba9b8f20cfb8d603f1aa8c2cd4e3 OK 2025/02/02 05:25:05 DEBUG : pre-dest2/2: md5 = fe4f90b3590a9c5c3083256f6f8a68fc OK 2025/02/02 05:25:06 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/dest" 2025/02/02 05:25:06 DEBUG : Config file has changed externally - reloading 2025/02/02 05:25:06 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/rg03c1jvnehrrc617i0lnqjddc" 2025/02/02 05:25:07 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/pre-dest1" 2025/02/02 05:25:07 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/bbnblvh6k061ssopqrp18kd7gc" 2025/02/02 05:25:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/pre-dest2" 2025/02/02 05:25:08 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/dgicm1h6b5ejvlltm8eeif0bnk" 2025/02/02 05:25:10 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:25:10 DEBUG : 1: Destination found in --compare-dest, skipping 2025/02/02 05:25:10 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:25:10 DEBUG : 2: Destination found in --compare-dest, skipping 2025/02/02 05:25:11 DEBUG : 3: Need to transfer - File not found at Destination 2025/02/02 05:25:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dest': Waiting for checks to finish 2025/02/02 05:25:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dest': Waiting for transfers to finish 2025/02/02 05:25:13 DEBUG : 3: md5 = 89c0abc4895dae891c71399b8ccb854e OK 2025/02/02 05:25:13 INFO : 3: Copied (new) 2025/02/02 05:25:13 DEBUG : Waiting for deletions to finish 2025/02/02 05:25:16 DEBUG : dgicm1h6b5ejvlltm8eeif0bnk: Rmdir: contains trashed file: "1ghu8geql9vrl726n32nrf65bc" 2025/02/02 05:25:17 DEBUG : bbnblvh6k061ssopqrp18kd7gc: Rmdir: contains trashed file: "skbfko3oil7es0lj68ob574sro" 2025/02/02 05:25:18 DEBUG : rg03c1jvnehrrc617i0lnqjddc: Rmdir: contains trashed file: "g072p7hmjchlnj0gibe64c1epg" --- PASS: TestSyncMultipleCompareDest (18.39s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:25:19 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/dst" 2025/02/02 05:25:19 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/31u3jie661vd5p8j7rtc3hgbh0" 2025/02/02 05:25:20 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/CopyDest" 2025/02/02 05:25:21 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/d09o6po3f7bm6ce32vdgs8h9ls" 2025/02/02 05:25:22 DEBUG : one: Need to transfer - File not found at Destination 2025/02/02 05:25:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:25:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:25:25 DEBUG : one: md5 = 95e433ed770a70fa2f2fbd35e7e216a5 OK 2025/02/02 05:25:25 INFO : one: Copied (new) 2025/02/02 05:25:25 DEBUG : Waiting for deletions to finish 2025/02/02 05:25:26 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/02 05:25:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:25:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:25:28 DEBUG : one: md5 = 31024dd9afb596326899947d75ad91b0 OK 2025/02/02 05:25:28 INFO : one: Copied (replaced existing) 2025/02/02 05:25:28 DEBUG : Waiting for deletions to finish 2025/02/02 05:25:30 DEBUG : dst/one: md5 = 1a02f76bb33e22d7d63fb54808dd9c28 OK 2025/02/02 05:25:32 DEBUG : CopyDest/one: md5 = 45b1d397b77d00927a16b0f56d5b14d0 OK 2025/02/02 05:25:33 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/BackupDir" 2025/02/02 05:25:33 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/s6dbk3lfi7c9kfvo6j7bla9m0g" 2025/02/02 05:25:35 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/02 05:25:35 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:25:35 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:25:35 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/02 05:25:37 INFO : one: Moved (server-side) 2025/02/02 05:25:38 INFO : one: Copied (server-side copy) 2025/02/02 05:25:38 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/02/02 05:25:38 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:25:38 DEBUG : Waiting for deletions to finish 2025/02/02 05:25:41 DEBUG : CopyDest/two: md5 = bc9bd6088f2833bf41717b4519d9f4d9 OK 2025/02/02 05:25:42 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:25:43 INFO : two: Copied (server-side copy) 2025/02/02 05:25:43 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/02/02 05:25:43 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:25:43 DEBUG : one: Unchanged skipping 2025/02/02 05:25:43 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:25:43 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:25:43 DEBUG : Waiting for deletions to finish 2025/02/02 05:25:44 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:25:44 DEBUG : one: Unchanged skipping 2025/02/02 05:25:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:25:44 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:25:44 DEBUG : two: Unchanged skipping 2025/02/02 05:25:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:25:44 DEBUG : Waiting for deletions to finish 2025/02/02 05:25:44 INFO : There was nothing to transfer 2025/02/02 05:25:46 DEBUG : CopyDest/three: md5 = cac56d776fdb79491c3d25a31b7d6459 OK 2025/02/02 05:25:47 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/02/02 05:25:47 DEBUG : three: Destination not found in --copy-dest 2025/02/02 05:25:47 DEBUG : three: Need to transfer - File not found at Destination 2025/02/02 05:25:47 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:25:47 DEBUG : one: Unchanged skipping 2025/02/02 05:25:47 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/02 05:25:47 DEBUG : two: Unchanged skipping 2025/02/02 05:25:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:25:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:25:48 DEBUG : three: md5 = 14d7ce12579e884dc5d85deba8e9abff OK 2025/02/02 05:25:48 INFO : three: Copied (new) 2025/02/02 05:25:48 DEBUG : Waiting for deletions to finish 2025/02/02 05:25:54 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "b6jdmn2b1adrhc8vikh7edr9os" 2025/02/02 05:25:54 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2025/02/02 05:25:54 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/02/02 05:25:54 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/02/02 05:25:54 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "b6jdmn2b1adrhc8vikh7edr9os" 2025/02/02 05:25:54 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2025/02/02 05:25:55 DEBUG : s6dbk3lfi7c9kfvo6j7bla9m0g: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" --- PASS: TestSyncCopyDest (37.38s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:25:59 DEBUG : dst/one: md5 = d5dbd0d3a3449e63c740356e7e419e66 OK 2025/02/02 05:26:00 DEBUG : dst/two: md5 = 38640adc4d70651b7b8dbf444cc9a7ae OK 2025/02/02 05:26:02 DEBUG : dst/three.txt: md5 = 22252e20c4d5bbad42f051f75dc15e0c OK 2025/02/02 05:26:02 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/dst" 2025/02/02 05:26:02 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/31u3jie661vd5p8j7rtc3hgbh0" 2025/02/02 05:26:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/backup" 2025/02/02 05:26:03 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/1nrff024r7pq65ecp72fc28jb0" 2025/02/02 05:26:05 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/02/02 05:26:05 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:26:05 DEBUG : two: Unchanged skipping 2025/02/02 05:26:05 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:26:07 INFO : one: Moved (server-side) 2025/02/02 05:26:07 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:26:09 DEBUG : one: md5 = 7f8a3940b20d150997c377bf714d91d3 OK 2025/02/02 05:26:09 INFO : one: Copied (new) 2025/02/02 05:26:09 DEBUG : Waiting for deletions to finish 2025/02/02 05:26:10 INFO : three.txt: Moved (server-side) 2025/02/02 05:26:10 INFO : three.txt: Moved into backup dir 2025/02/02 05:26:12 DEBUG : dst/three.txt: md5 = f15f8c233b5e2ecaaab3c9a8ab2e6022 OK 2025/02/02 05:26:13 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/02/02 05:26:13 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:26:13 DEBUG : two: Unchanged skipping 2025/02/02 05:26:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:26:14 INFO : one: Deleted 2025/02/02 05:26:15 INFO : one: Moved (server-side) 2025/02/02 05:26:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:26:16 DEBUG : one: md5 = 6812524e782d25b9d4c24c5cd3a3f709 OK 2025/02/02 05:26:16 INFO : one: Copied (new) 2025/02/02 05:26:16 DEBUG : Waiting for deletions to finish 2025/02/02 05:26:17 INFO : three.txt: Deleted 2025/02/02 05:26:18 INFO : three.txt: Moved (server-side) 2025/02/02 05:26:18 INFO : three.txt: Moved into backup dir 2025/02/02 05:26:22 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/02/02 05:26:22 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2025/02/02 05:26:22 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/02/02 05:26:22 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "tr2hj63d80ftlmvm6a952snjcc" 2025/02/02 05:26:22 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/02/02 05:26:22 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "tr2hj63d80ftlmvm6a952snjcc" --- PASS: TestSyncBackupDir (27.02s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:26:26 DEBUG : dst/one: md5 = a132506626335ebbdeea4eabbc7f22ea OK 2025/02/02 05:26:27 DEBUG : dst/two: md5 = 8db6d00a80985eb2547a415fb59d5dd9 OK 2025/02/02 05:26:29 DEBUG : dst/three.txt: md5 = 2c7306316f7019e17464fafd814363a8 OK 2025/02/02 05:26:29 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/dst" 2025/02/02 05:26:29 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/31u3jie661vd5p8j7rtc3hgbh0" 2025/02/02 05:26:30 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/backup" 2025/02/02 05:26:30 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/1nrff024r7pq65ecp72fc28jb0" 2025/02/02 05:26:32 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/02/02 05:26:32 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:26:32 DEBUG : two: Unchanged skipping 2025/02/02 05:26:32 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:26:34 INFO : one: Moved (server-side) to: one.bak 2025/02/02 05:26:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:26:36 DEBUG : one: md5 = fb944581110f2412f079410cde402e75 OK 2025/02/02 05:26:36 INFO : one: Copied (new) 2025/02/02 05:26:36 DEBUG : Waiting for deletions to finish 2025/02/02 05:26:37 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/02 05:26:37 INFO : three.txt: Moved into backup dir 2025/02/02 05:26:39 DEBUG : dst/three.txt: md5 = 32fab8740b6cdc4a4373f0b4a2c22389 OK 2025/02/02 05:26:40 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/02/02 05:26:40 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:26:40 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:26:40 DEBUG : two: Unchanged skipping 2025/02/02 05:26:41 INFO : one.bak: Deleted 2025/02/02 05:26:42 INFO : one: Moved (server-side) to: one.bak 2025/02/02 05:26:42 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:26:43 DEBUG : one: md5 = a27eac9e7ae25c13365f965f97f8d576 OK 2025/02/02 05:26:43 INFO : one: Copied (new) 2025/02/02 05:26:43 DEBUG : Waiting for deletions to finish 2025/02/02 05:26:44 INFO : three.txt.bak: Deleted 2025/02/02 05:26:45 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/02 05:26:45 INFO : three.txt: Moved into backup dir 2025/02/02 05:26:49 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/02/02 05:26:49 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2025/02/02 05:26:49 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "sk91qgsqfn0r271r2ehsaoc20c" 2025/02/02 05:26:49 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "qltcak2uaegtmcoa8vijdppbp8" 2025/02/02 05:26:49 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "sk91qgsqfn0r271r2ehsaoc20c" 2025/02/02 05:26:49 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "qltcak2uaegtmcoa8vijdppbp8" --- PASS: TestSyncBackupDirWithSuffix (26.89s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:26:53 DEBUG : dst/one: md5 = 5d57b1f2c058afb71ad667f26caacd4b OK 2025/02/02 05:26:54 DEBUG : dst/two: md5 = 643d2afe72a49cb581df91183960b76e OK 2025/02/02 05:26:56 DEBUG : dst/three.txt: md5 = 60eac3e9e3839816856f604eef040ab0 OK 2025/02/02 05:26:56 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/dst" 2025/02/02 05:26:56 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/31u3jie661vd5p8j7rtc3hgbh0" 2025/02/02 05:26:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/backup" 2025/02/02 05:26:57 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/1nrff024r7pq65ecp72fc28jb0" 2025/02/02 05:26:59 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/02/02 05:26:59 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:26:59 DEBUG : two: Unchanged skipping 2025/02/02 05:26:59 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:27:01 INFO : one: Moved (server-side) to: one-2019-01-01 2025/02/02 05:27:01 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:27:03 DEBUG : one: md5 = 53a1e8a1cf5ca74ff866675cd9a89f13 OK 2025/02/02 05:27:03 INFO : one: Copied (new) 2025/02/02 05:27:03 DEBUG : Waiting for deletions to finish 2025/02/02 05:27:04 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/02/02 05:27:04 INFO : three.txt: Moved into backup dir 2025/02/02 05:27:06 DEBUG : dst/three.txt: md5 = b57bfb9f7a38cc325c6954d61bcb31c3 OK 2025/02/02 05:27:07 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/02/02 05:27:07 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:27:07 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:27:07 DEBUG : two: Unchanged skipping 2025/02/02 05:27:08 INFO : one-2019-01-01: Deleted 2025/02/02 05:27:08 INFO : one: Moved (server-side) to: one-2019-01-01 2025/02/02 05:27:08 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:27:10 DEBUG : one: md5 = 9d0c22ad1836587dc44628b6731ffbd4 OK 2025/02/02 05:27:10 INFO : one: Copied (new) 2025/02/02 05:27:10 DEBUG : Waiting for deletions to finish 2025/02/02 05:27:11 INFO : three-2019-01-01.txt: Deleted 2025/02/02 05:27:12 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/02/02 05:27:12 INFO : three.txt: Moved into backup dir 2025/02/02 05:27:15 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/02/02 05:27:15 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2025/02/02 05:27:16 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "qtq2f6qq4lgpsmj88vb7147qje3prudmtfdtr2342fth58vo9a50" 2025/02/02 05:27:16 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "jcjl1bv3ep0v8kt35lqql1co90" 2025/02/02 05:27:16 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "jcjl1bv3ep0v8kt35lqql1co90" 2025/02/02 05:27:16 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "qtq2f6qq4lgpsmj88vb7147qje3prudmtfdtr2342fth58vo9a50" --- PASS: TestSyncBackupDirWithSuffixKeepExtension (26.69s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:27:20 DEBUG : dst/one: md5 = 6625df82942180e112334d56ea03a453 OK 2025/02/02 05:27:21 DEBUG : dst/two: md5 = fad8e8b6d8d4cf402cc84c2fd418ad36 OK 2025/02/02 05:27:22 DEBUG : dst/three.txt: md5 = 5a8b3c34ed435156324012c707d4223d OK 2025/02/02 05:27:23 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/dst" 2025/02/02 05:27:23 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/31u3jie661vd5p8j7rtc3hgbh0" 2025/02/02 05:27:24 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/02/02 05:27:24 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:27:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:27:24 DEBUG : two: Unchanged skipping 2025/02/02 05:27:25 INFO : one: Moved (server-side) to: one.bak 2025/02/02 05:27:25 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:27:27 DEBUG : one: md5 = 32563437628551de9de33eac5fb15b5d OK 2025/02/02 05:27:27 INFO : one: Copied (new) 2025/02/02 05:27:27 DEBUG : Waiting for deletions to finish 2025/02/02 05:27:27 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/02 05:27:27 INFO : three.txt: Moved into backup dir 2025/02/02 05:27:29 DEBUG : dst/three.txt: md5 = 7a9527027e400135e621789a9eb81b06 OK 2025/02/02 05:27:30 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/02/02 05:27:30 DEBUG : one.bak: Excluded (Path Filter) 2025/02/02 05:27:30 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/02/02 05:27:30 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:27:30 DEBUG : two: Unchanged skipping 2025/02/02 05:27:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for checks to finish 2025/02/02 05:27:31 INFO : one.bak: Deleted 2025/02/02 05:27:32 INFO : one: Moved (server-side) to: one.bak 2025/02/02 05:27:32 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena/dst': Waiting for transfers to finish 2025/02/02 05:27:33 DEBUG : one: md5 = d281bbecc4eb19c4ca5e51052ad3917a OK 2025/02/02 05:27:33 INFO : one: Copied (new) 2025/02/02 05:27:33 DEBUG : Waiting for deletions to finish 2025/02/02 05:27:34 INFO : three.txt.bak: Deleted 2025/02/02 05:27:35 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/02 05:27:35 INFO : three.txt: Moved into backup dir 2025/02/02 05:27:38 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/02/02 05:27:38 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "sk91qgsqfn0r271r2ehsaoc20c" 2025/02/02 05:27:38 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "qltcak2uaegtmcoa8vijdppbp8" 2025/02/02 05:27:38 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "sk91qgsqfn0r271r2ehsaoc20c" 2025/02/02 05:27:38 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2025/02/02 05:27:38 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "qltcak2uaegtmcoa8vijdppbp8" --- PASS: TestSyncBackupDirSuffixOnly (22.25s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:27:42 DEBUG : dst/one: md5 = 6b150491def50ec06b298ed4c8903c49 OK 2025/02/02 05:27:43 DEBUG : dst/two: md5 = c1f642d126eb9520f410206a3ef7251b OK 2025/02/02 05:27:45 DEBUG : dst/three.txt: md5 = cc031865761d17cd9eac8d0b4491cd83 OK 2025/02/02 05:27:45 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/dst" 2025/02/02 05:27:45 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/31u3jie661vd5p8j7rtc3hgbh0" 2025/02/02 05:27:46 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/02/02 05:27:47 INFO : one: Moved (server-side) to: one.bak 2025/02/02 05:27:49 DEBUG : one: md5 = c379b2de686b85956bccf6abf612a902 OK 2025/02/02 05:27:49 INFO : one: Copied (new) 2025/02/02 05:27:49 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:27:49 DEBUG : two: Unchanged skipping 2025/02/02 05:27:49 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/02/02 05:27:50 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/02 05:27:51 DEBUG : three.txt: md5 = f1a65075920ac7bb55863c8b72fb11d5 OK 2025/02/02 05:27:51 INFO : three.txt: Copied (new) 2025/02/02 05:27:53 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/02/02 05:27:54 INFO : one.bak: Deleted 2025/02/02 05:27:54 INFO : one: Moved (server-side) to: one.bak 2025/02/02 05:27:56 DEBUG : one: md5 = 2ff1e5e6a70fd051b8d774b58d482077 OK 2025/02/02 05:27:56 INFO : one: Copied (new) 2025/02/02 05:27:56 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:27:56 DEBUG : two: Unchanged skipping 2025/02/02 05:27:56 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/02/02 05:27:57 INFO : three.txt.bak: Deleted 2025/02/02 05:27:58 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/02 05:27:59 DEBUG : three.txt: md5 = 2ff023a7f236411912e10eb92136e325 OK 2025/02/02 05:27:59 INFO : three.txt: Copied (new) 2025/02/02 05:28:04 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/02/02 05:28:04 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "tr2hj63d80ftlmvm6a952snjcc" 2025/02/02 05:28:04 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "qltcak2uaegtmcoa8vijdppbp8" 2025/02/02 05:28:04 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "qltcak2uaegtmcoa8vijdppbp8" 2025/02/02 05:28:04 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "sk91qgsqfn0r271r2ehsaoc20c" 2025/02/02 05:28:04 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "sk91qgsqfn0r271r2ehsaoc20c" 2025/02/02 05:28:04 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" --- PASS: TestSyncSuffix (25.54s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:28:08 DEBUG : dst/one: md5 = 5ae51958db81c51e8e44f8287ace1a8e OK 2025/02/02 05:28:09 DEBUG : dst/two: md5 = 905ddbfc0c2d44b916ed2bbdeca64a92 OK 2025/02/02 05:28:11 DEBUG : dst/three.txt: md5 = 6fdf915d2f6e855cefc6eca2f308fd48 OK 2025/02/02 05:28:11 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zijamiv1gena/dst" 2025/02/02 05:28:11 DEBUG : Creating backend with remote "TestDrive:crypt/ggp87fa1tpeu6i8fsp71m2i9nld7rtsge27i27ekkee5e6m7hmu0/31u3jie661vd5p8j7rtc3hgbh0" 2025/02/02 05:28:12 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/02/02 05:28:13 INFO : one: Moved (server-side) to: one-2019-01-01 2025/02/02 05:28:15 DEBUG : one: md5 = 0ec3b5e1905deaee430fda5f7551cea8 OK 2025/02/02 05:28:15 INFO : one: Copied (new) 2025/02/02 05:28:15 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:28:15 DEBUG : two: Unchanged skipping 2025/02/02 05:28:15 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/02/02 05:28:16 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/02/02 05:28:18 DEBUG : three.txt: md5 = 06a35d03900b4a7dce64d71325c824f8 OK 2025/02/02 05:28:18 INFO : three.txt: Copied (new) 2025/02/02 05:28:19 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/02/02 05:28:20 INFO : one-2019-01-01: Deleted 2025/02/02 05:28:21 INFO : one: Moved (server-side) to: one-2019-01-01 2025/02/02 05:28:22 DEBUG : one: md5 = 6d053e1462e18d35f7d7b74ae45a6643 OK 2025/02/02 05:28:22 INFO : one: Copied (new) 2025/02/02 05:28:23 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:28:23 DEBUG : two: Unchanged skipping 2025/02/02 05:28:23 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/02/02 05:28:24 INFO : three-2019-01-01.txt: Deleted 2025/02/02 05:28:24 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/02/02 05:28:26 DEBUG : three.txt: md5 = c65e11445f57ba366d314fae71ddacee OK 2025/02/02 05:28:26 INFO : three.txt: Copied (new) 2025/02/02 05:28:30 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/02/02 05:28:30 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "tr2hj63d80ftlmvm6a952snjcc" 2025/02/02 05:28:30 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "jcjl1bv3ep0v8kt35lqql1co90" 2025/02/02 05:28:30 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "qtq2f6qq4lgpsmj88vb7147qje3prudmtfdtr2342fth58vo9a50" 2025/02/02 05:28:30 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "qtq2f6qq4lgpsmj88vb7147qje3prudmtfdtr2342fth58vo9a50" 2025/02/02 05:28:30 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "jcjl1bv3ep0v8kt35lqql1co90" 2025/02/02 05:28:30 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" --- PASS: TestSyncSuffixKeepExtension (26.36s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:28:33 DEBUG : Testêé: md5 = 324d2c5055895f5a4f6668822cde2b00 OK 2025/02/02 05:28:33 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/02/02 05:28:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:28:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:28:34 DEBUG : Testêé: md5 = 6901b7b1940dc36966098163ae6e2015 OK 2025/02/02 05:28:34 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/02/02 05:28:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (4.77s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:28:36 DEBUG : existing: Need to transfer - File not found at Destination 2025/02/02 05:28:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:28:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:28:38 DEBUG : existing: md5 = 7580a0428e81c0fc528b9c28f672783c OK 2025/02/02 05:28:38 INFO : existing: Copied (new) 2025/02/02 05:28:38 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:28:38 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/02/02 05:28:38 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/02/02 05:28:38 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:28:38 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:28:38 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': not deleting files as there were IO errors 2025/02/02 05:28:38 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncImmutable (4.83s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:28:42 DEBUG : EXISTING: md5 = 6475eeaff513b1f3e92ec86c78031f1b OK 2025/02/02 05:28:43 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:28:43 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:28:43 DEBUG : existing: Unchanged skipping 2025/02/02 05:28:43 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:28:43 DEBUG : Waiting for deletions to finish 2025/02/02 05:28:43 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (3.59s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" sync_test.go:2635: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.43s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" sync_test.go:2674: This test only runs on local --- PASS: TestMaxTransfer (1.35s) --- SKIP: TestMaxTransfer/Hard (0.45s) --- SKIP: TestMaxTransfer/Soft (0.46s) --- SKIP: TestMaxTransfer/Cautious (0.44s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:28:47 DEBUG : both0: md5 = 6559e823037f87a4fb04c6f1d2aad46a OK 2025/02/02 05:28:49 DEBUG : only0: md5 = b426e8dd7fbce386bf1cbb2ceb8423c4 OK 2025/02/02 05:28:51 DEBUG : both1: md5 = c457242aa446f3b3ca48d345baf12be4 OK 2025/02/02 05:28:52 DEBUG : only1: md5 = f94efc68d3ffbf0ab0f3b2c1e51cc827 OK 2025/02/02 05:28:54 DEBUG : both2: md5 = 3b080a7c7033ee961f521ef0a11be4f5 OK 2025/02/02 05:28:56 DEBUG : only2: md5 = 59714470b60f5a82f9ddb750bf782d78 OK 2025/02/02 05:28:57 DEBUG : both3: md5 = 58a2f1c6d03f6688962bd1e0b00a6fc5 OK 2025/02/02 05:28:59 DEBUG : only3: md5 = 7fccb573a17281dfada39d2a2060b4d5 OK 2025/02/02 05:29:00 DEBUG : both4: md5 = 393cc0534dc9fe41ee1cb1f8ddacde53 OK 2025/02/02 05:29:02 DEBUG : only4: md5 = 3859cd6a79126f3c05b89d0e89bd207c OK 2025/02/02 05:29:04 DEBUG : both5: md5 = 79652dfda28c53e5e00609e707581796 OK 2025/02/02 05:29:05 DEBUG : only5: md5 = 8c458207acf3125f5fddf01b0c0bf9aa OK 2025/02/02 05:29:07 DEBUG : both6: md5 = 69d3b21f17cff1268f9edf74e184eff6 OK 2025/02/02 05:29:08 DEBUG : only6: md5 = cb15c4f2c8dc56e1fcb6ccb9f143ba19 OK 2025/02/02 05:29:10 DEBUG : both7: md5 = 5c8c4dd8e33696ee427dff40cf406efd OK 2025/02/02 05:29:12 DEBUG : only7: md5 = f546a6480d60164c83a719cc146c9989 OK 2025/02/02 05:29:13 DEBUG : both8: md5 = 67c886ed36f3a933439d1e126f49536d OK 2025/02/02 05:29:15 DEBUG : only8: md5 = c0e76974d27c1ac82a5c4e961819969c OK 2025/02/02 05:29:17 DEBUG : both9: md5 = 6a70abeb1cff67e67e88a1ab54f032df OK 2025/02/02 05:29:18 DEBUG : only9: md5 = 61fa2c8e12f60a5d9a8d8a0928f7d56b OK 2025/02/02 05:29:20 DEBUG : both10: md5 = 376772853836955c74c09175bb1877af OK 2025/02/02 05:29:21 DEBUG : only10: md5 = 4dbd0db040754acd78f783088e5f560b OK 2025/02/02 05:29:23 DEBUG : both11: md5 = f2166bd15a4cc60d0e7cebc14650a741 OK 2025/02/02 05:29:25 DEBUG : only11: md5 = 8d4197bdc3eb07f69a100db7a7513729 OK 2025/02/02 05:29:26 DEBUG : both12: md5 = 7e518dcf035da0efedab0f216f5e3833 OK 2025/02/02 05:29:28 DEBUG : only12: md5 = 91fb787b8994af8669310c8df2d620d0 OK 2025/02/02 05:29:30 DEBUG : both13: md5 = 9258b5ce4e83ab368b944f378ff0fe9f OK 2025/02/02 05:29:31 DEBUG : only13: md5 = 3fee32525b68fc1882e328c33c6b78c5 OK 2025/02/02 05:29:33 DEBUG : both14: md5 = 2030d4e3efa0dacdbd763a76ce5c35cd OK 2025/02/02 05:29:34 DEBUG : only14: md5 = 5fd7d9859ce0f6b670e90bf2c43aa576 OK 2025/02/02 05:29:36 DEBUG : both15: md5 = 381141df9231872a72c014d98e16d1f9 OK 2025/02/02 05:29:38 DEBUG : only15: md5 = 68ed2339b58cb727798b54510d9d03a3 OK 2025/02/02 05:29:39 DEBUG : both16: md5 = efe79e11eb9164dd55e40f0bbe71503c OK 2025/02/02 05:29:41 DEBUG : only16: md5 = 7c0d58993b2f669914ab5941135707d1 OK 2025/02/02 05:29:43 DEBUG : both17: md5 = 6f5fbef03632d434032995ed5edebf23 OK 2025/02/02 05:29:44 DEBUG : only17: md5 = 4d1853d1e63bbc19583eb8135eaf96dd OK 2025/02/02 05:29:46 DEBUG : both18: md5 = a91385baa474bc833d0da25cc87f3ae3 OK 2025/02/02 05:29:47 DEBUG : only18: md5 = 9d3eb1128651aef9507dac9098bc3c0a OK 2025/02/02 05:29:49 DEBUG : both19: md5 = 7762984de80bdf34ea95f852073460c6 OK 2025/02/02 05:29:51 DEBUG : only19: md5 = 3eca4d977e03927f1819c04099f23ca4 OK 2025/02/02 05:29:51 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:29:51 DEBUG : both0: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both12: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both13: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both14: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both15: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both1: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both17: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both18: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both19: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both2: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both3: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both4: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both5: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both6: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both7: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both8: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:29:51 DEBUG : both9: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both10: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both11: Unchanged skipping 2025/02/02 05:29:51 DEBUG : both16: Unchanged skipping 2025/02/02 05:29:51 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:29:51 DEBUG : Waiting for deletions to finish 2025/02/02 05:29:52 INFO : only0: Deleted 2025/02/02 05:29:52 INFO : only18: Deleted 2025/02/02 05:29:52 INFO : only11: Deleted 2025/02/02 05:29:52 INFO : only10: Deleted 2025/02/02 05:29:52 INFO : only16: Deleted 2025/02/02 05:29:53 INFO : only6: Deleted 2025/02/02 05:29:53 INFO : only1: Deleted 2025/02/02 05:29:53 INFO : only9: Deleted 2025/02/02 05:29:53 INFO : only12: Deleted 2025/02/02 05:29:53 INFO : only17: Deleted 2025/02/02 05:29:53 INFO : only19: Deleted 2025/02/02 05:29:53 INFO : only2: Deleted 2025/02/02 05:29:54 INFO : only7: Deleted 2025/02/02 05:29:54 INFO : only13: Deleted 2025/02/02 05:29:54 INFO : only14: Deleted 2025/02/02 05:29:54 INFO : only15: Deleted 2025/02/02 05:29:54 INFO : only4: Deleted 2025/02/02 05:29:54 INFO : only3: Deleted 2025/02/02 05:29:54 INFO : only5: Deleted 2025/02/02 05:29:54 INFO : only8: Deleted 2025/02/02 05:29:54 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncConcurrentDelete (80.87s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:30:09 DEBUG : both0: md5 = e2b938ed48ee0fa39e661c68b5e98c91 OK 2025/02/02 05:30:10 DEBUG : only0: md5 = 071a146f598ef41f18e57de23cd0adba OK 2025/02/02 05:30:12 DEBUG : both1: md5 = 97cba9ac62ef8e2769e6ff166fd1cdbe OK 2025/02/02 05:30:14 DEBUG : only1: md5 = 3d03024676ee4ac669d9341bac39a44e OK 2025/02/02 05:30:15 DEBUG : both2: md5 = dd80c34774986877af061054c9f9d7a0 OK 2025/02/02 05:30:17 DEBUG : only2: md5 = d59b438a9150b83028f60bc5a8fd79af OK 2025/02/02 05:30:18 DEBUG : both3: md5 = 6fa541b3c27f84482ba324ac64b2b9c3 OK 2025/02/02 05:30:20 DEBUG : only3: md5 = f0c3e8d924fad0fee9a43d1f3b6db22a OK 2025/02/02 05:30:22 DEBUG : both4: md5 = e32c7f721d29c9c8ab33970cb1aee067 OK 2025/02/02 05:30:23 DEBUG : only4: md5 = 7b81d9fa7255e0f4c1ad22f960876f3c OK 2025/02/02 05:30:25 DEBUG : both5: md5 = 7181b848dea7b7b77899ac2d3241d4b2 OK 2025/02/02 05:30:27 DEBUG : only5: md5 = 720eec1e12d6297856ec7efc8eeb1001 OK 2025/02/02 05:30:28 DEBUG : both6: md5 = c1cf63f54931589173387e22df3cb32d OK 2025/02/02 05:30:30 DEBUG : only6: md5 = 43c86f1862fb893d5dfc33e6fc31204e OK 2025/02/02 05:30:32 DEBUG : both7: md5 = f967e763ebd413fbb6d3dc9021fb4fc1 OK 2025/02/02 05:30:33 DEBUG : only7: md5 = 1234cdf851e7a80061a67d88e21bb24c OK 2025/02/02 05:30:35 DEBUG : both8: md5 = 635c564a9b201cc537a9fc16e3c023d3 OK 2025/02/02 05:30:36 DEBUG : only8: md5 = d93b546bf9ba9e93173bc601eb5a778b OK 2025/02/02 05:30:38 DEBUG : both9: md5 = ed4a685d6e1785da7a05f027122b64eb OK 2025/02/02 05:30:39 DEBUG : only9: md5 = 007791c0b06834b74aa98e2c73b31451 OK 2025/02/02 05:30:41 DEBUG : both10: md5 = b90eab3a2ef88bd8b506b70ffc8b7f08 OK 2025/02/02 05:30:43 DEBUG : only10: md5 = ee5d1985ce962de92e802c6567a14c5d OK 2025/02/02 05:30:44 DEBUG : both11: md5 = d9df173c38d9a94e4d8a87de3d16dd90 OK 2025/02/02 05:30:46 DEBUG : only11: md5 = a78932bd29b2d58db53429605fdf0d88 OK 2025/02/02 05:30:47 DEBUG : both12: md5 = 234a8ea5e9688b4a7478f76e89059af0 OK 2025/02/02 05:30:49 DEBUG : only12: md5 = b4a2b4a29d49a9add210d692cf6b1fee OK 2025/02/02 05:30:51 DEBUG : both13: md5 = 0497ea16c4776f32c5bee82cf40fa9ea OK 2025/02/02 05:30:52 DEBUG : only13: md5 = 433fdaf45515d8a980bbb6de7b96966c OK 2025/02/02 05:30:54 DEBUG : both14: md5 = c11d610e4cd63775d4d0e221f83c643e OK 2025/02/02 05:30:56 DEBUG : only14: md5 = 2c6acff688551f7a18fd6966cfe7a389 OK 2025/02/02 05:30:57 DEBUG : both15: md5 = cec2bed0a526df97c27e857f87f71546 OK 2025/02/02 05:30:59 DEBUG : only15: md5 = 051d938fa80ade0f2f5c7af1d736e907 OK 2025/02/02 05:31:01 DEBUG : both16: md5 = 1f711dfdcdaacf77fd7b543a3b061ada OK 2025/02/02 05:31:02 DEBUG : only16: md5 = acc3b0bbcc335c846071ed88c649e18c OK 2025/02/02 05:31:04 DEBUG : both17: md5 = de131b14809467c8e80bfb1e07cb6366 OK 2025/02/02 05:31:06 DEBUG : only17: md5 = a377e5ede712e41360c3c8c6635190a7 OK 2025/02/02 05:31:07 DEBUG : both18: md5 = 39976dd758982f66b8d19f2dc83c0791 OK 2025/02/02 05:31:09 DEBUG : only18: md5 = dc349173df8c5f987135412ab4baebc8 OK 2025/02/02 05:31:11 DEBUG : both19: md5 = 2cb60c1e6ab35bd11a63c8732fdb6fe8 OK 2025/02/02 05:31:12 DEBUG : only19: md5 = 24cfa45f4da66e58e608fa4320a091c4 OK 2025/02/02 05:31:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for checks to finish 2025/02/02 05:31:13 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both10: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both12: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both13: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both14: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both15: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both16: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both17: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both18: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both19: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both2: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both3: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both1: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both5: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both11: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both0: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both4: Unchanged skipping 2025/02/02 05:31:13 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/02 05:31:13 DEBUG : both9: Unchanged skipping 2025/02/02 05:31:13 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : both6: Unchanged skipping 2025/02/02 05:31:13 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : both7: Unchanged skipping 2025/02/02 05:31:13 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : both8: Unchanged skipping 2025/02/02 05:31:13 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/02/02 05:31:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Waiting for transfers to finish 2025/02/02 05:31:14 DEBUG : only10: md5 = e861e1e648da5cd5f3fcbf13ed113902 OK 2025/02/02 05:31:14 INFO : only10: Copied (replaced existing) 2025/02/02 05:31:14 DEBUG : only12: md5 = 9fb8dc4711aa647ec30eda02d74c397e OK 2025/02/02 05:31:14 INFO : only12: Copied (replaced existing) 2025/02/02 05:31:14 DEBUG : only0: md5 = a54d1953fdb176c3620c18f7e756cac2 OK 2025/02/02 05:31:14 INFO : only0: Copied (replaced existing) 2025/02/02 05:31:14 DEBUG : only14: md5 = cf19b2bc23aa78cae61e4a62c780b5f7 OK 2025/02/02 05:31:14 INFO : only14: Copied (replaced existing) 2025/02/02 05:31:15 DEBUG : only15: md5 = b9a9d331a6005f26d5957c6971759505 OK 2025/02/02 05:31:15 INFO : only15: Copied (replaced existing) 2025/02/02 05:31:16 DEBUG : only16: md5 = 0d7933e5cb2ae38f8e83f1fb4d17d6d5 OK 2025/02/02 05:31:16 INFO : only16: Copied (replaced existing) 2025/02/02 05:31:16 DEBUG : only17: md5 = 5c1c6bbfa32aaf22d4e525917df9b668 OK 2025/02/02 05:31:16 INFO : only17: Copied (replaced existing) 2025/02/02 05:31:16 DEBUG : only18: md5 = 8fd41f1ef517b7d6ff707caf39547a65 OK 2025/02/02 05:31:16 INFO : only18: Copied (replaced existing) 2025/02/02 05:31:17 DEBUG : only19: md5 = 2b43a29e27e1515a0ffe9e1ad47eb66d OK 2025/02/02 05:31:17 INFO : only19: Copied (replaced existing) 2025/02/02 05:31:17 DEBUG : only2: md5 = 9ef9e097ddf97dc9a0cc8e6549745aaa OK 2025/02/02 05:31:17 INFO : only2: Copied (replaced existing) 2025/02/02 05:31:17 DEBUG : only3: md5 = 483c666483e53dcf2ab69ec31750a4aa OK 2025/02/02 05:31:17 INFO : only3: Copied (replaced existing) 2025/02/02 05:31:17 DEBUG : only4: md5 = c557d55e398c2d32db2287f5c5ae0c6b OK 2025/02/02 05:31:17 INFO : only4: Copied (replaced existing) 2025/02/02 05:31:18 DEBUG : only11: md5 = a6cddccb0833ed7c2fa89452af7307c1 OK 2025/02/02 05:31:18 INFO : only11: Copied (replaced existing) 2025/02/02 05:31:18 DEBUG : only1: md5 = ca9f59c30e40952989be6ed9be1c2d10 OK 2025/02/02 05:31:18 INFO : only1: Copied (replaced existing) 2025/02/02 05:31:18 DEBUG : only13: md5 = 6aa955de4bf484e570548f2337b591d3 OK 2025/02/02 05:31:18 INFO : only13: Copied (replaced existing) 2025/02/02 05:31:18 DEBUG : only5: md5 = 984c66f4e79bba661663050c8cb7f9e7 OK 2025/02/02 05:31:18 INFO : only5: Copied (replaced existing) 2025/02/02 05:31:19 DEBUG : only7: md5 = e6ee718c5b39024452fd318b4359d816 OK 2025/02/02 05:31:19 INFO : only7: Copied (replaced existing) 2025/02/02 05:31:19 DEBUG : only9: md5 = cc7f26d25cd0bfa1cacf90e90d785c44 OK 2025/02/02 05:31:19 INFO : only9: Copied (replaced existing) 2025/02/02 05:31:20 DEBUG : only6: md5 = aad4776bd569baf46b4dff4a5efbf3f6 OK 2025/02/02 05:31:20 INFO : only6: Copied (replaced existing) 2025/02/02 05:31:20 DEBUG : only8: md5 = 69599917cee2a23741c05e15159d9c96 OK 2025/02/02 05:31:20 INFO : only8: Copied (replaced existing) 2025/02/02 05:31:20 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" --- PASS: TestSyncConcurrentTruncate (97.29s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:31:44 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/02/02 05:31:44 DEBUG : sub dir: Making directory with metadata 2025/02/02 05:31:45 INFO : sub dir: Made directory with metadata (mtime=2011-12-30T12:59:59Z) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:32:05 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/02/02 05:32:06 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/02/02 05:32:06 INFO : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/02 05:32:06 INFO : sub dirEmpty: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:32:21 DEBUG : gcsdkq814sbtppue42dpsbg8ug: Rmdir: contains trashed file: "sjuo65gk5q3r1199jselk9k9o0" 2025/02/02 05:32:22 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/37qp6d1gohacv723ij3brofbu8/i24n0ln37pa2hv8pctddeuh73o: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" 2025/02/02 05:32:23 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/37qp6d1gohacv723ij3brofbu8: Rmdir: contains trashed file: "i24n0ln37pa2hv8pctddeuh73o" 2025/02/02 05:32:23 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "37qp6d1gohacv723ij3brofbu8" 2025/02/02 05:32:24 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak" 2025/02/02 05:32:25 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak" 2025/02/02 05:32:26 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak" 2025/02/02 05:32:27 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak" 2025/02/02 05:32:28 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/4r13oncfa2rsrr82oqhsqv24ns: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" 2025/02/02 05:32:29 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc: Rmdir: contains trashed file: "4r13oncfa2rsrr82oqhsqv24ns" 2025/02/02 05:32:29 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak" 2025/02/02 05:32:29 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestNothingToTransferWithEmptyDirs (47.47s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:32:32 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/02/02 05:32:32 DEBUG : sub dir: Making directory with metadata 2025/02/02 05:32:33 INFO : sub dir: Made directory with metadata (mtime=2011-12-30T12:59:59Z) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:32:57 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/02/02 05:32:58 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/02/02 05:32:58 INFO : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/02 05:32:58 INFO : sub dirEmpty: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena'", Local "Local file system at /tmp/rclone2748306405", Modify Window "1ms" 2025/02/02 05:33:12 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/37qp6d1gohacv723ij3brofbu8/i24n0ln37pa2hv8pctddeuh73o: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" 2025/02/02 05:33:13 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/37qp6d1gohacv723ij3brofbu8: Rmdir: contains trashed file: "i24n0ln37pa2hv8pctddeuh73o" 2025/02/02 05:33:14 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "37qp6d1gohacv723ij3brofbu8" 2025/02/02 05:33:15 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak" 2025/02/02 05:33:16 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak" 2025/02/02 05:33:17 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak" 2025/02/02 05:33:17 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak" 2025/02/02 05:33:18 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/4r13oncfa2rsrr82oqhsqv24ns: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" 2025/02/02 05:33:19 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc: Rmdir: contains trashed file: "4r13oncfa2rsrr82oqhsqv24ns" 2025/02/02 05:33:19 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak" 2025/02/02 05:33:20 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestNothingToTransferWithoutEmptyDirs (50.51s) PASS 2025/02/02 05:33:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zijamiv1gena': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -fast-list" - Finished OK in 17m17.318101354s (try 1/5)