"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5)
2024/12/17 01:48:18 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko"
2024/12/17 01:48:18 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/17 01:48:18 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg"
2024/12/17 01:48:19 DEBUG : Creating backend with remote "/tmp/rclone2827950342"
=== 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-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:48:20 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:48:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:48:20 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11)
2024/12/17 01:48:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:48:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:48:20 NOTICE: sub dir: Skipped set directory modification time as --dry-run is set
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestCopyWithDryRun (2.40s)
=== RUN   TestCopy
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:48:21 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/17 01:48:21 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:48:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:48:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:48:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:48:24 DEBUG : sub dir/hello world: md5 = 479b0ea8e2e40d77fa2dceed3b1932ed OK
2024/12/17 01:48:24 INFO  : sub dir/hello world: Copied (new)
2024/12/17 01:48:25 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:48:27 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestCopy (7.04s)
=== RUN   TestCopyMetadata
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:48:28 DEBUG : metadata sub dir: Making directory with metadata
2024/12/17 01:48:28 INFO  : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/17 01:48:28 DEBUG : empty metadata sub dir: Making directory with metadata
2024/12/17 01:48:28 INFO  : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/17 01:48:28 DEBUG : Local file system at /tmp/rclone2827950342: File to upload is small (21 bytes), uploading instead of streaming
2024/12/17 01:48:28 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK
2024/12/17 01:48:28 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical
2024/12/17 01:48:28 INFO  : metadata sub dir: Set directory modification time (using DirSetModTime)
2024/12/17 01:48:29 DEBUG : Google drive root 'crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg': Skipping btime metadata as can't update it on an existing file: 2024-12-17T01:48:28.612871828Z
2024/12/17 01:48:29 INFO  : empty metadata sub dir: Updated directory metadata
2024/12/17 01:48:30 DEBUG : Google drive root 'crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg': Skipping btime metadata as can't update it on an existing file: 2024-12-17T01:48:28.612871828Z
2024/12/17 01:48:30 INFO  : metadata sub dir: Updated directory metadata
2024/12/17 01:48:30 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination
2024/12/17 01:48:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:48:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:48:32 DEBUG : metadata sub dir/hello metadata world: md5 = 4624aac3b0e3efbb27c1bed307529ca6 OK
2024/12/17 01:48:32 INFO  : metadata sub dir/hello metadata world: Copied (new)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:48:35 DEBUG : fg45q1rm3kh00nmmkk4m6ihmrcnel1p7551kqhjr9bk2gtd2gbf0: Rmdir: contains trashed file: "dmuvulfij81f693ur0f1vvbvq9v6iscp56daas3m51n9li5e3470"
--- PASS: TestCopyMetadata (9.32s)
=== RUN   TestCopyMetadataNoEmptyDirs
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:48:37 DEBUG : metadata sub dir: Making directory with metadata
2024/12/17 01:48:37 INFO  : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/17 01:48:37 DEBUG : empty metadata sub dir: Making directory with metadata
2024/12/17 01:48:37 INFO  : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/17 01:48:37 DEBUG : Local file system at /tmp/rclone2827950342: File to upload is small (21 bytes), uploading instead of streaming
2024/12/17 01:48:37 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK
2024/12/17 01:48:37 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical
2024/12/17 01:48:37 INFO  : metadata sub dir: Set directory modification time (using DirSetModTime)
2024/12/17 01:48:38 DEBUG : Added delayed dir = "empty metadata sub dir", newDst=<nil>
2024/12/17 01:48:38 DEBUG : Added delayed dir = "metadata sub dir", newDst=<nil>
2024/12/17 01:48:38 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination
2024/12/17 01:48:38 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:48:38 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:48:40 DEBUG : metadata sub dir/hello metadata world: md5 = 0cee6ad7e58caecd07d3031d71e14f94 OK
2024/12/17 01:48:40 INFO  : metadata sub dir/hello metadata world: Copied (new)
2024/12/17 01:48:40 DEBUG : Google drive root 'crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg': Skipping btime metadata as can't update it on an existing file: 2024-12-17T01:48:37.936823145Z
2024/12/17 01:48:41 INFO  : metadata sub dir: Updated directory metadata
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:48:44 DEBUG : fg45q1rm3kh00nmmkk4m6ihmrcnel1p7551kqhjr9bk2gtd2gbf0: Rmdir: contains trashed file: "dmuvulfij81f693ur0f1vvbvq9v6iscp56daas3m51n9li5e3470"
--- PASS: TestCopyMetadataNoEmptyDirs (8.06s)
=== RUN   TestCopyMissingDirectory
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:48:45 DEBUG : Creating backend with remote "/non-existing"
2024/12/17 01:48:46 ERROR : Local file system at /non-existing: error reading source root directory: directory not found
2024/12/17 01:48:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:48:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestCopyMissingDirectory (1.17s)
=== RUN   TestCopyNoTraverse
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:48:47 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:48:47 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:48:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:48:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:48:50 DEBUG : sub dir/hello world: md5 = d6930dc0f6980acd7d8a8238629b49e0 OK
2024/12/17 01:48:50 INFO  : sub dir/hello world: Copied (new)
2024/12/17 01:48:50 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:48:52 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestCopyNoTraverse (6.77s)
=== RUN   TestCopyCheckFirst
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:48:53 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Running all checks before starting transfers
2024/12/17 01:48:54 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:48:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:48:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:48:54 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Checks finished, now starting transfers
2024/12/17 01:48:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:48:56 DEBUG : sub dir/hello world: md5 = 40f966d651b6c36d0a329b441161c01d OK
2024/12/17 01:48:56 INFO  : sub dir/hello world: Copied (new)
2024/12/17 01:48:57 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:48:59 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestCopyCheckFirst (6.94s)
=== RUN   TestSyncNoTraverse
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:49:00 ERROR : Ignoring --no-traverse with sync
2024/12/17 01:49:01 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:49:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:49:01 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:49:01 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:49:03 DEBUG : sub dir/hello world: md5 = 2968d1a8f1d56654a72ab2debc2c02cb OK
2024/12/17 01:49:03 INFO  : sub dir/hello world: Copied (new)
2024/12/17 01:49:03 DEBUG : Waiting for deletions to finish
2024/12/17 01:49:04 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:49:06 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestSyncNoTraverse (6.63s)
=== RUN   TestCopyWithDepth
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:49:07 DEBUG : hello world2: Need to transfer - File not found at Destination
2024/12/17 01:49:07 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:49:07 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:49:07 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:49:09 DEBUG : hello world2: md5 = ceb89193e0e5ca27cc0fe13a88a834fe OK
2024/12/17 01:49:09 INFO  : hello world2: Copied (new)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestCopyWithDepth (3.63s)
=== RUN   TestCopyWithFilesFrom
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:49:11 DEBUG : potato2: Need to transfer - File not found at Destination
2024/12/17 01:49:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:49:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:49:13 DEBUG : potato2: md5 = 8a59ef0bf24277745c8bbac053208ebf OK
2024/12/17 01:49:13 INFO  : potato2: Copied (new)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestCopyWithFilesFrom (3.92s)
=== RUN   TestCopyWithFilesFromAndNoTraverse
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:49:15 DEBUG : potato2: Need to transfer - File not found at Destination
2024/12/17 01:49:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:49:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:49:16 DEBUG : potato2: md5 = 9fda9ccc3dec9f9f4872bd1662271351 OK
2024/12/17 01:49:16 INFO  : potato2: Copied (new)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestCopyWithFilesFromAndNoTraverse (3.74s)
=== RUN   TestCopyEmptyDirectories
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:49:18 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2024/12/17 01:49:18 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/17 01:49:18 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2024/12/17 01:49:18 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/17 01:49:19 DEBUG : sub dir: Making directory with metadata
2024/12/17 01:49:20 INFO  : sub dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2024/12/17 01:49:20 DEBUG : sub dir2: Making directory with metadata
2024/12/17 01:49:21 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/17 01:49:21 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2024/12/17 01:49:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:49:22 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/17 01:49:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:49:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:49:23 DEBUG : sub dir/hello world: md5 = 11ae590f62f064def35f0fc0b05faefd OK
2024/12/17 01:49:23 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:49:27 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc: Rmdir: contains trashed file: "cv6a9uf28ngrajena8kt5ijjn0"
2024/12/17 01:49:28 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestCopyEmptyDirectories (10.59s)
=== RUN   TestCopyNoEmptyDirectories
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:49:29 DEBUG : sub dir2: Making directory
2024/12/17 01:49:29 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2024/12/17 01:49:29 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/17 01:49:29 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:49:29 DEBUG : Added delayed dir = "sub dir2", newDst=<nil>
2024/12/17 01:49:29 DEBUG : Added delayed dir = "sub dir2/sub sub dir2", newDst=<nil>
2024/12/17 01:49:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:49:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:49:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:49:32 DEBUG : sub dir/hello world: md5 = 399586776fead722d903c09178e57a04 OK
2024/12/17 01:49:32 INFO  : sub dir/hello world: Copied (new)
2024/12/17 01:49:33 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/17 01:49:35 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestCopyNoEmptyDirectories (6.48s)
=== RUN   TestMoveEmptyDirectories
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:49:35 DEBUG : sub dir2: Making directory with metadata
2024/12/17 01:49:35 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/17 01:49:36 DEBUG : sub dir: Making directory with metadata
2024/12/17 01:49:37 INFO  : sub dir: Made directory with metadata (mtime=2024-12-17T01:49:35.856517615Z)
2024/12/17 01:49:37 DEBUG : sub dir2: Making directory with metadata
2024/12/17 01:49:38 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/17 01:49:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:49:38 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:49:38 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:49:40 DEBUG : sub dir/hello world: md5 = 52275eedb08dba41898e4c37a9cc51c7 OK
2024/12/17 01:49:40 INFO  : sub dir/hello world: Copied (new)
2024/12/17 01:49:40 INFO  : sub dir/hello world: Deleted
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:49:43 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestMoveEmptyDirectories (8.83s)
=== RUN   TestSyncNoUpdateDirModtime
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:49:44 DEBUG : sub dir no update dir modtime: Making directory with metadata
2024/12/17 01:49:44 INFO  : sub dir no update dir modtime: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2024/12/17 01:49:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:49:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:49:46 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncNoUpdateDirModtime (4.71s)
=== RUN   TestMoveNoEmptyDirectories
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:49:49 DEBUG : sub dir2: Making directory
2024/12/17 01:49:49 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:49:49 DEBUG : Added delayed dir = "sub dir2", newDst=<nil>
2024/12/17 01:49:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:49:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:49:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:49:52 DEBUG : sub dir/hello world: md5 = 8b5efad804f1b3d27a1224570a5e43c5 OK
2024/12/17 01:49:52 INFO  : sub dir/hello world: Copied (new)
2024/12/17 01:49:52 INFO  : sub dir/hello world: Deleted
2024/12/17 01:49:52 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/17 01:49:54 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestMoveNoEmptyDirectories (6.06s)
=== RUN   TestSyncEmptyDirectories
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:49:55 DEBUG : sub dir2: Making directory with metadata
2024/12/17 01:49:55 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/17 01:49:55 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/17 01:49:55 DEBUG : sub dir: Making directory with metadata
2024/12/17 01:49:56 INFO  : sub dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2024/12/17 01:49:56 DEBUG : sub dir2: Making directory with metadata
2024/12/17 01:49:57 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/17 01:49:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:49:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:49:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:49:59 DEBUG : sub dir/hello world: md5 = 96e0946490a027c3d7a5e4b112b9094a OK
2024/12/17 01:49:59 INFO  : sub dir/hello world: Copied (new)
2024/12/17 01:49:59 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:50:02 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestSyncEmptyDirectories (8.19s)
=== RUN   TestSyncSetDelayedModTimes
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set
--- SKIP: TestSyncSetDelayedModTimes (0.47s)
=== RUN   TestSyncNoEmptyDirectories
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:50:04 DEBUG : sub dir2: Making directory
2024/12/17 01:50:04 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:50:04 DEBUG : Added delayed dir = "sub dir2", newDst=<nil>
2024/12/17 01:50:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:50:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:50:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:50:07 DEBUG : sub dir/hello world: md5 = 63ec6910a950fc65aaa04ffcf53cfc1a OK
2024/12/17 01:50:07 INFO  : sub dir/hello world: Copied (new)
2024/12/17 01:50:07 DEBUG : Waiting for deletions to finish
2024/12/17 01:50:07 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/17 01:50:09 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestSyncNoEmptyDirectories (6.53s)
=== RUN   TestServerSideCopy
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:50:13 DEBUG : sub dir/hello world: md5 = 6877545bdb896e6172ccfaa669b9d88f OK
2024/12/17 01:50:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kumibal5vine"
2024/12/17 01:50:13 DEBUG : Creating backend with remote "TestDrive:crypt/ecepdj3s8paj0t2141b3nagr8at1camkv6buli6c1159jhjvv5p0"
    sync_test.go:590: Server side copy (if possible) Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko' -> Encrypted drive 'TestCryptDrive:rclone-test-kumibal5vine'
2024/12/17 01:50:15 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:50:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:50:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kumibal5vine': Waiting for checks to finish
2024/12/17 01:50:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kumibal5vine': Waiting for transfers to finish
2024/12/17 01:50:18 INFO  : sub dir/hello world: Copied (server-side copy)
2024/12/17 01:50:19 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:50:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kumibal5vine': Purge remote
2024/12/17 01:50:22 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestServerSideCopy (12.70s)
=== RUN   TestCopyOverSelf
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:50:26 DEBUG : sub dir/hello world: md5 = d0c9afd3b476cf1e44cc7efc8312ee96 OK
2024/12/17 01:50:27 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2024/12/17 01:50:27 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/12/17 01:50:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:50:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:50:28 DEBUG : sub dir/hello world: md5 = d87382db07941717f1f2699d7349b276 OK
2024/12/17 01:50:28 INFO  : sub dir/hello world: Copied (replaced existing)
2024/12/17 01:50:29 INFO  : sub dir: Set directory modification time (using SetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:50:31 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestCopyOverSelf (9.59s)
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:50:35 DEBUG : sub dir/hello world: md5 = 60c2206c7316128e1b8f833344502486 OK
2024/12/17 01:50:36 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gijisiz6hafu"
2024/12/17 01:50:36 DEBUG : Creating backend with remote "TestDrive:crypt/og6gh04tvnce9ctn25pkj9llpqejr8fr2ajiqunv9ptikqqg47mg"
    sync_test.go:626: Server side copy (if possible) Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko' -> Encrypted drive 'TestCryptDrive:rclone-test-gijisiz6hafu'
2024/12/17 01:50:37 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:50:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:50:37 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gijisiz6hafu': Waiting for checks to finish
2024/12/17 01:50:37 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gijisiz6hafu': Waiting for transfers to finish
2024/12/17 01:50:41 INFO  : sub dir/hello world: Copied (server-side copy)
2024/12/17 01:50:41 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:50:44 DEBUG : sub dir/hello world: md5 = 99c34f50217aeeac815f8c267104775c OK
2024/12/17 01:50:44 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1ms)
2024/12/17 01:50:44 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2024/12/17 01:50:45 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/12/17 01:50:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gijisiz6hafu': Waiting for checks to finish
2024/12/17 01:50:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gijisiz6hafu': Waiting for transfers to finish
2024/12/17 01:50:46 INFO  : sub dir/hello world: Copied (server-side copy)
2024/12/17 01:50:47 INFO  : sub dir: Set directory modification time (using SetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:50:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gijisiz6hafu': Purge remote
2024/12/17 01:50:50 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestServerSideCopyOverSelf (18.95s)
=== RUN   TestMoveOverSelf
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:50:54 DEBUG : sub dir/hello world: md5 = 15774b7b34fc8c406ada502126c90075 OK
2024/12/17 01:50:55 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2024/12/17 01:50:55 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/12/17 01:50:55 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:50:55 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:50:57 DEBUG : sub dir/hello world: md5 = b564f8172f539082f2d84f34222a534f OK
2024/12/17 01:50:57 INFO  : sub dir/hello world: Copied (replaced existing)
2024/12/17 01:50:57 INFO  : sub dir/hello world: Deleted
2024/12/17 01:50:57 INFO  : sub dir: Set directory modification time (using SetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:50:59 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestMoveOverSelf (8.96s)
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:03 DEBUG : sub dir/hello world: md5 = 85a3d004b38560206babd777342c2de7 OK
2024/12/17 01:51:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-qixabey1voza"
2024/12/17 01:51:04 DEBUG : Creating backend with remote "TestDrive:crypt/35d9uk59e8aqjn2npgq59kum3tqgf4jnl657i6qsgb0sto9v9210"
    sync_test.go:671: Server side copy (if possible) Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko' -> Encrypted drive 'TestCryptDrive:rclone-test-qixabey1voza'
2024/12/17 01:51:05 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:51:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:51:05 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qixabey1voza': Waiting for checks to finish
2024/12/17 01:51:05 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qixabey1voza': Waiting for transfers to finish
2024/12/17 01:51:09 INFO  : sub dir/hello world: Copied (server-side copy)
2024/12/17 01:51:11 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:13 DEBUG : sub dir/hello world: md5 = 485cb66deda6ec2dabf018579f1a630e OK
2024/12/17 01:51:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qixabey1voza': Using server-side directory move
2024/12/17 01:51:13 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-qixabey1voza': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2024/12/17 01:51:14 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1ms)
2024/12/17 01:51:14 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2024/12/17 01:51:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qixabey1voza': Waiting for checks to finish
2024/12/17 01:51:14 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/12/17 01:51:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qixabey1voza': Waiting for transfers to finish
2024/12/17 01:51:14 INFO  : sub dir/hello world: Deleted
2024/12/17 01:51:15 INFO  : sub dir/hello world: Moved (server-side)
2024/12/17 01:51:16 INFO  : sub dir: Set directory modification time (using SetModTime)
2024/12/17 01:51:19 DEBUG : sub dir/hello world: md5 = 8cf709d64bd91bcc4ccd0214c9487ccb OK
2024/12/17 01:51:19 DEBUG : testing file moves
2024/12/17 01:51:19 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1ms)
2024/12/17 01:51:19 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2024/12/17 01:51:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qixabey1voza': Waiting for checks to finish
2024/12/17 01:51:20 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2024/12/17 01:51:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qixabey1voza': Waiting for transfers to finish
2024/12/17 01:51:20 INFO  : sub dir/hello world: Deleted
2024/12/17 01:51:21 INFO  : sub dir/hello world: Moved (server-side)
2024/12/17 01:51:22 INFO  : sub dir: Set directory modification time (using SetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qixabey1voza': Purge remote
--- PASS: TestServerSideMoveOverSelf (25.25s)
=== RUN   TestCopyAfterDelete
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:28 DEBUG : sub dir/hello world: md5 = 8dc7dd12dff6a935ebc01d230bf329ee OK
2024/12/17 01:51:28 ERROR : : error listing: directory not found
2024/12/17 01:51:29 DEBUG : Local file system at /tmp/rclone2827950342: Making directory
2024/12/17 01:51:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:51:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:31 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestCopyAfterDelete (7.14s)
=== RUN   TestCopyRedownload
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:35 DEBUG : sub dir/hello world: md5 = 72b737dd4db523c8b0d9963a1d47fbdc OK
2024/12/17 01:51:36 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:51:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:51:36 DEBUG : Local file system at /tmp/rclone2827950342: Waiting for checks to finish
2024/12/17 01:51:36 DEBUG : Local file system at /tmp/rclone2827950342: Waiting for transfers to finish
2024/12/17 01:51:38 DEBUG : sub dir/hello world.285f0a30.partial: renamed to: sub dir/hello world
2024/12/17 01:51:38 INFO  : sub dir/hello world: Copied (new)
2024/12/17 01:51:38 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:39 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestCopyRedownload (7.71s)
=== RUN   TestSyncBasedOnCheckSum
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:41 DEBUG : check sum: Need to transfer - File not found at Destination
2024/12/17 01:51:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:51:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:51:42 DEBUG : check sum: md5 = 9930d590c913c11859540c9391985d64 OK
2024/12/17 01:51:42 INFO  : check sum: Copied (new)
2024/12/17 01:51:42 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2024/12/17 01:51:43 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:51:43 DEBUG : check sum: Size of src and dst objects identical
2024/12/17 01:51:43 DEBUG : check sum: Unchanged skipping
2024/12/17 01:51:43 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:51:43 DEBUG : Waiting for deletions to finish
2024/12/17 01:51:43 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncBasedOnCheckSum (4.84s)
=== RUN   TestSyncSizeOnly
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:46 DEBUG : sizeonly: Need to transfer - File not found at Destination
2024/12/17 01:51:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:51:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:51:47 DEBUG : sizeonly: md5 = b57106c0fe092558cc02e0c9f43a8ba4 OK
2024/12/17 01:51:47 INFO  : sizeonly: Copied (new)
2024/12/17 01:51:47 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:51:48 DEBUG : sizeonly: Sizes identical
2024/12/17 01:51:48 DEBUG : sizeonly: Unchanged skipping
2024/12/17 01:51:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:51:48 DEBUG : Waiting for deletions to finish
2024/12/17 01:51:48 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncSizeOnly (4.83s)
=== RUN   TestSyncIgnoreSize
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:50 DEBUG : ignore-size: Need to transfer - File not found at Destination
2024/12/17 01:51:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:51:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:51:52 DEBUG : ignore-size: md5 = bdd41e096ef4a4f7f71ff914959d417e OK
2024/12/17 01:51:52 INFO  : ignore-size: Copied (new)
2024/12/17 01:51:52 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:51:53 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 01:51:53 DEBUG : ignore-size: Unchanged skipping
2024/12/17 01:51:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:51:53 DEBUG : Waiting for deletions to finish
2024/12/17 01:51:53 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncIgnoreSize (4.95s)
=== RUN   TestSyncIgnoreTimes
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:57 DEBUG : existing: md5 = 8da24d9247e5a5f248d078b55eb04bb9 OK
2024/12/17 01:51:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:51:57 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 01:51:57 DEBUG : existing: Unchanged skipping
2024/12/17 01:51:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:51:57 DEBUG : Waiting for deletions to finish
2024/12/17 01:51:57 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:51:58 DEBUG : existing: Transferring unconditionally as --ignore-times is in use
2024/12/17 01:51:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:51:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:51:59 DEBUG : existing: md5 = 96f0fd6d8c6f4e9f7403a32a784f486d OK
2024/12/17 01:51:59 INFO  : existing: Copied (replaced existing)
2024/12/17 01:51:59 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncIgnoreTimes (5.91s)
=== RUN   TestSyncIgnoreExisting
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:52:01 DEBUG : existing: Need to transfer - File not found at Destination
2024/12/17 01:52:01 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:52:01 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:52:03 DEBUG : existing: md5 = fddd4b482c0adff935a092c1c26deccc OK
2024/12/17 01:52:03 INFO  : existing: Copied (new)
2024/12/17 01:52:03 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:52:03 DEBUG : existing: Destination exists, skipping
2024/12/17 01:52:03 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:52:03 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:52:03 DEBUG : Waiting for deletions to finish
2024/12/17 01:52:03 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncIgnoreExisting (4.77s)
=== RUN   TestSyncIgnoreErrors
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:52:08 DEBUG : b/potato: md5 = dcf7db2ddf94e13846a578256787fb16 OK
2024/12/17 01:52:11 DEBUG : c/non empty space: md5 = 1061fcb4803af958e6ac6a38eb5ea133 OK
2024/12/17 01:52:11 DEBUG : d: Making directory
2024/12/17 01:52:13 DEBUG : Added delayed dir = "a", newDst=<nil>
2024/12/17 01:52:13 DEBUG : Added delayed dir = "c", newDst=c
2024/12/17 01:52:13 DEBUG : a/potato2: Need to transfer - File not found at Destination
2024/12/17 01:52:13 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:52:13 DEBUG : c/non empty space: Unchanged skipping
2024/12/17 01:52:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:52:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:52:16 DEBUG : a/potato2: md5 = 634d138c50dd77b0ff5fb31c95d16def OK
2024/12/17 01:52:16 INFO  : a/potato2: Copied (new)
2024/12/17 01:52:16 DEBUG : Waiting for deletions to finish
2024/12/17 01:52:16 INFO  : b/potato: Deleted
2024/12/17 01:52:17 INFO  : a: Set directory modification time (using DirSetModTime)
2024/12/17 01:52:17 INFO  : d: Removing directory
2024/12/17 01:52:18 INFO  : b: Removing directory
2024/12/17 01:52:18 DEBUG : 32d8ua6196mc1r7qkfog53rkg0: Rmdir: contains trashed file: "2m507n64kafsi59v9q71r281a4"
2024/12/17 01:52:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': deleted 2 directories
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:52:21 DEBUG : 3i9du405pfogq3ncai6pg7ouio: Rmdir: contains trashed file: "982d3pss7t5pn0il16r2foj31s"
2024/12/17 01:52:22 DEBUG : 2bsvlrk2ne1lfitm6tttcu0qt8: Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg"
--- PASS: TestSyncIgnoreErrors (17.75s)
=== RUN   TestSyncAfterChangingModtimeOnly
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:52:25 DEBUG : empty space: md5 = e9c21eaf0e1a74ae227c0730a23a9df5 OK
2024/12/17 01:52:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:52:26 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
2024/12/17 01:52:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:52:26 NOTICE: empty space: Skipped copy as --dry-run is set (size 1)
2024/12/17 01:52:26 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:52:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:52:26 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
2024/12/17 01:52:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:52:28 DEBUG : empty space: md5 = 179e821380056b8cd41c118987f6e35f OK
2024/12/17 01:52:28 INFO  : empty space: Copied (replaced existing)
2024/12/17 01:52:28 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncAfterChangingModtimeOnly (6.48s)
=== RUN   TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    sync_test.go:1016: Can't check this if no hashes supported
--- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.45s)
=== RUN   TestSyncDoesntUpdateModtime
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:52:32 DEBUG : foo: md5 = 3f0104daa9772b7e1a31c34304cc2e35 OK
2024/12/17 01:52:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:52:33 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
2024/12/17 01:52:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:52:34 DEBUG : foo: md5 = 503c694c0fa47b6f64ccd695b94c8601 OK
2024/12/17 01:52:34 INFO  : foo: Copied (replaced existing)
2024/12/17 01:52:34 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncDoesntUpdateModtime (5.50s)
=== RUN   TestSyncAfterAddingAFile
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:52:38 DEBUG : empty space: md5 = 08d2116652edb0ad4c979498db1e3691 OK
2024/12/17 01:52:38 DEBUG : potato: Need to transfer - File not found at Destination
2024/12/17 01:52:38 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:52:38 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:52:38 DEBUG : empty space: Unchanged skipping
2024/12/17 01:52:38 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:52:40 DEBUG : potato: md5 = e8ae2bc491d8ffb41179d3cd38749170 OK
2024/12/17 01:52:40 INFO  : potato: Copied (new)
2024/12/17 01:52:40 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncAfterAddingAFile (6.70s)
=== RUN   TestSyncAfterChangingFilesSizeOnly
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:52:45 DEBUG : potato: md5 = ddccd91cb8cd15cf522faa9ac2f5902b OK
2024/12/17 01:52:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:52:45 DEBUG : potato: Sizes differ (src 21 vs dst 60)
2024/12/17 01:52:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:52:46 DEBUG : potato: md5 = 9bc1ab5f62effbfce527de1ca740a7f0 OK
2024/12/17 01:52:46 INFO  : potato: Copied (replaced existing)
2024/12/17 01:52:46 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncAfterChangingFilesSizeOnly (5.49s)
=== RUN   TestSyncAfterChangingContentsOnly
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:52:50 DEBUG : potato: md5 = 46537cc3e847015c43a5f8ed6b580f16 OK
2024/12/17 01:52:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:52:50 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
2024/12/17 01:52:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:52:51 DEBUG : potato: md5 = 8a104a6a25911ae939143491ccd48f17 OK
2024/12/17 01:52:51 INFO  : potato: Copied (replaced existing)
2024/12/17 01:52:51 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncAfterChangingContentsOnly (5.21s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileDryRun
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:52:55 DEBUG : potato: md5 = 574987f8d91984e4fe5f36dd08d14cde OK
2024/12/17 01:52:57 DEBUG : empty space: md5 = a196fd6c3657a17c31c1612886495eef OK
2024/12/17 01:52:57 DEBUG : potato2: Need to transfer - File not found at Destination
2024/12/17 01:52:57 NOTICE: potato2: Skipped copy as --dry-run is set (size 60)
2024/12/17 01:52:57 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:52:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:52:57 DEBUG : empty space: Unchanged skipping
2024/12/17 01:52:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:52:57 DEBUG : Waiting for deletions to finish
2024/12/17 01:52:57 NOTICE: potato: Skipped delete as --dry-run is set (size 21)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (6.25s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFile
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:53:01 DEBUG : potato: md5 = 8da96eb15226b0d00d66f1daab0969bc OK
2024/12/17 01:53:03 DEBUG : empty space: md5 = a60115725730aaa99af185669de24374 OK
2024/12/17 01:53:03 DEBUG : potato2: Need to transfer - File not found at Destination
2024/12/17 01:53:03 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:53:03 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:53:03 DEBUG : empty space: Unchanged skipping
2024/12/17 01:53:03 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:53:05 DEBUG : potato2: md5 = 75abe57e8b31478a32556af75f365514 OK
2024/12/17 01:53:05 INFO  : potato2: Copied (new)
2024/12/17 01:53:05 DEBUG : Waiting for deletions to finish
2024/12/17 01:53:06 INFO  : potato: Deleted
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFile (8.85s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:53:11 DEBUG : b/potato: md5 = a729974b84c0466c4a16f476be07a991 OK
2024/12/17 01:53:14 DEBUG : c/non empty space: md5 = 471f7da2d4259c0c72cf34a0f74d4551 OK
2024/12/17 01:53:14 DEBUG : d: Making directory
2024/12/17 01:53:15 DEBUG : d/e: Making directory
2024/12/17 01:53:17 DEBUG : Added delayed dir = "a", newDst=<nil>
2024/12/17 01:53:17 DEBUG : Added delayed dir = "c", newDst=c
2024/12/17 01:53:17 DEBUG : a/potato2: Need to transfer - File not found at Destination
2024/12/17 01:53:17 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:53:17 DEBUG : c/non empty space: Unchanged skipping
2024/12/17 01:53:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:53:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:53:20 DEBUG : a/potato2: md5 = 6a78f337b7f1d1663901f11a25404d23 OK
2024/12/17 01:53:20 INFO  : a/potato2: Copied (new)
2024/12/17 01:53:20 DEBUG : Waiting for deletions to finish
2024/12/17 01:53:20 INFO  : b/potato: Deleted
2024/12/17 01:53:21 INFO  : a: Set directory modification time (using DirSetModTime)
2024/12/17 01:53:21 INFO  : d/e: Removing directory
2024/12/17 01:53:22 INFO  : d: Removing directory
2024/12/17 01:53:22 DEBUG : ka6id8d20d3s5ktapedlphgta8: Rmdir: contains trashed file: "r8sppajuabeak3sbgj2ak8vuf8"
2024/12/17 01:53:23 INFO  : b: Removing directory
2024/12/17 01:53:23 DEBUG : 32d8ua6196mc1r7qkfog53rkg0: Rmdir: contains trashed file: "2m507n64kafsi59v9q71r281a4"
2024/12/17 01:53:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': deleted 3 directories
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:53:26 DEBUG : 3i9du405pfogq3ncai6pg7ouio: Rmdir: contains trashed file: "982d3pss7t5pn0il16r2foj31s"
2024/12/17 01:53:27 DEBUG : 2bsvlrk2ne1lfitm6tttcu0qt8: Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (20.05s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:53:31 DEBUG : b/potato: md5 = 44b79de08e0da6b25cc68e019b4628ba OK
2024/12/17 01:53:34 DEBUG : c/non empty space: md5 = 47ce9a5ea25db4416d9baeb5890689c9 OK
2024/12/17 01:53:34 DEBUG : d: Making directory
2024/12/17 01:53:36 DEBUG : Added delayed dir = "a", newDst=<nil>
2024/12/17 01:53:36 DEBUG : Added delayed dir = "c", newDst=c
2024/12/17 01:53:36 DEBUG : a/potato2: Need to transfer - File not found at Destination
2024/12/17 01:53:36 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:53:36 DEBUG : c/non empty space: Unchanged skipping
2024/12/17 01:53:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:53:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:53:38 DEBUG : a/potato2: md5 = ed1a01cab3c7e60f4522b8cce3a77d7d OK
2024/12/17 01:53:38 INFO  : a/potato2: Copied (new)
2024/12/17 01:53:38 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': not deleting files as there were IO errors
2024/12/17 01:53:39 INFO  : a: Set directory modification time (using DirSetModTime)
2024/12/17 01:53:39 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': not deleting directories as there were IO errors
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:53:43 DEBUG : 3i9du405pfogq3ncai6pg7ouio: Rmdir: contains trashed file: "982d3pss7t5pn0il16r2foj31s"
2024/12/17 01:53:44 DEBUG : 32d8ua6196mc1r7qkfog53rkg0: Rmdir: contains trashed file: "2m507n64kafsi59v9q71r281a4"
2024/12/17 01:53:45 DEBUG : 2bsvlrk2ne1lfitm6tttcu0qt8: Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (17.92s)
=== RUN   TestSyncDeleteAfter
--- PASS: TestSyncDeleteAfter (0.00s)
=== RUN   TestSyncDeleteDuring
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:53:48 DEBUG : potato: md5 = 18ab51ebacb44c1f3ac60b5e4d624a6f OK
2024/12/17 01:53:50 DEBUG : empty space: md5 = 1e411e8c3abb34eedfdd24e040f0fcca OK
2024/12/17 01:53:50 DEBUG : Waiting for deletions to finish
2024/12/17 01:53:51 DEBUG : potato2: Need to transfer - File not found at Destination
2024/12/17 01:53:51 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:53:51 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:53:51 DEBUG : empty space: Unchanged skipping
2024/12/17 01:53:51 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:53:51 INFO  : potato: Deleted
2024/12/17 01:53:52 DEBUG : potato2: md5 = e02a70b82fdbc12aacf662a3be9a73dd OK
2024/12/17 01:53:52 INFO  : potato2: Copied (new)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncDeleteDuring (8.34s)
=== RUN   TestSyncDeleteBefore
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:53:57 DEBUG : potato: md5 = 496d75d0c1341c68c67e00faa387c570 OK
2024/12/17 01:53:58 DEBUG : empty space: md5 = f7d5d91dc2e6292ae687b484e1d124fc OK
2024/12/17 01:53:59 DEBUG : Waiting for deletions to finish
2024/12/17 01:53:59 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:53:59 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:53:59 INFO  : potato: Deleted
2024/12/17 01:54:00 DEBUG : potato2: Need to transfer - File not found at Destination
2024/12/17 01:54:00 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:54:00 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:54:00 DEBUG : empty space: Unchanged skipping
2024/12/17 01:54:00 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:54:01 DEBUG : potato2: md5 = 37da78be83768d3a03aca0f4243095cb OK
2024/12/17 01:54:01 INFO  : potato2: Copied (new)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncDeleteBefore (8.95s)
=== RUN   TestCopyDeleteBefore
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:54:05 DEBUG : potato: md5 = 75023d16c7a6334f4ca8bbe888ee0c7c OK
2024/12/17 01:54:06 DEBUG : potato2: Need to transfer - File not found at Destination
2024/12/17 01:54:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:54:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:54:07 DEBUG : potato2: md5 = 5fe40e20327cbcca96b9079b68ca48da OK
2024/12/17 01:54:07 INFO  : potato2: Copied (new)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestCopyDeleteBefore (6.08s)
=== RUN   TestSyncWithExclude
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:54:12 DEBUG : potato2: md5 = 78907e7e71b86d78054087cc79b57fb5 OK
2024/12/17 01:54:13 DEBUG : empty space: md5 = 933abf640531288a8c858a3983cb9e18 OK
2024/12/17 01:54:13 DEBUG : enormous: Excluded (Size Filter)
2024/12/17 01:54:13 DEBUG : enormous: Excluded
2024/12/17 01:54:13 DEBUG : potato2: Excluded (Size Filter)
2024/12/17 01:54:13 DEBUG : potato2: Excluded
2024/12/17 01:54:14 DEBUG : potato2: Excluded (Size Filter)
2024/12/17 01:54:14 DEBUG : potato2: Excluded
2024/12/17 01:54:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:54:14 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:54:14 DEBUG : empty space: Unchanged skipping
2024/12/17 01:54:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:54:14 DEBUG : Waiting for deletions to finish
2024/12/17 01:54:14 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:54:14 DEBUG : enormous: Excluded (Size Filter)
2024/12/17 01:54:14 DEBUG : enormous: Excluded
2024/12/17 01:54:14 DEBUG : potato2: Excluded (Size Filter)
2024/12/17 01:54:14 DEBUG : potato2: Excluded
2024/12/17 01:54:14 DEBUG : potato2: Excluded (Size Filter)
2024/12/17 01:54:14 DEBUG : potato2: Excluded
2024/12/17 01:54:14 DEBUG : Local file system at /tmp/rclone2827950342: Waiting for checks to finish
2024/12/17 01:54:14 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms)
2024/12/17 01:54:14 DEBUG : empty space: Unchanged skipping
2024/12/17 01:54:14 DEBUG : Local file system at /tmp/rclone2827950342: Waiting for transfers to finish
2024/12/17 01:54:14 DEBUG : Waiting for deletions to finish
2024/12/17 01:54:14 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncWithExclude (7.23s)
=== RUN   TestSyncWithExcludeAndDeleteExcluded
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:54:19 DEBUG : potato2: md5 = 83c2b8a7269ac207a92fd4faa4cc9dfe OK
2024/12/17 01:54:21 DEBUG : empty space: md5 = a6bf9475455fcbd0b74c26ec61b3884f OK
2024/12/17 01:54:22 DEBUG : enormous: md5 = fa328bc3ff7c613242c241e2100371c0 OK
2024/12/17 01:54:23 DEBUG : enormous: Excluded (Size Filter)
2024/12/17 01:54:23 DEBUG : enormous: Excluded
2024/12/17 01:54:23 DEBUG : potato2: Excluded (Size Filter)
2024/12/17 01:54:23 DEBUG : potato2: Excluded
2024/12/17 01:54:23 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:54:23 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:54:23 DEBUG : empty space: Unchanged skipping
2024/12/17 01:54:23 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:54:23 DEBUG : Waiting for deletions to finish
2024/12/17 01:54:23 INFO  : enormous: Deleted
2024/12/17 01:54:23 INFO  : potato2: Deleted
2024/12/17 01:54:23 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:54:24 DEBUG : Local file system at /tmp/rclone2827950342: Waiting for checks to finish
2024/12/17 01:54:24 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms)
2024/12/17 01:54:24 DEBUG : empty space: Unchanged skipping
2024/12/17 01:54:24 DEBUG : Local file system at /tmp/rclone2827950342: Waiting for transfers to finish
2024/12/17 01:54:24 DEBUG : Waiting for deletions to finish
2024/12/17 01:54:24 INFO  : enormous: Deleted
2024/12/17 01:54:24 INFO  : potato2: Deleted
2024/12/17 01:54:24 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncWithExcludeAndDeleteExcluded (9.21s)
=== RUN   TestSyncWithUpdateOlder
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:54:28 DEBUG : one: md5 = eb015393b7ebe9adf6fbce0cbcdb0de1 OK
2024/12/17 01:54:29 DEBUG : two: md5 = 4adde42c64e365685888d557732bae8f OK
2024/12/17 01:54:31 DEBUG : three: md5 = 4c29f1df07177833fe7e6f1a68f79245 OK
2024/12/17 01:54:33 DEBUG : four: md5 = dc1ec42937ea9417691d4ba209d078c6 OK
2024/12/17 01:54:33 DEBUG : five: Need to transfer - File not found at Destination
2024/12/17 01:54:33 DEBUG : four: Sizes differ (src 4 vs dst 8)
2024/12/17 01:54:33 DEBUG : three: Sizes identical
2024/12/17 01:54:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:54:33 DEBUG : one: Destination is newer than source, skipping
2024/12/17 01:54:33 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring
2024/12/17 01:54:33 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping
2024/12/17 01:54:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:54:35 DEBUG : four: md5 = 2442ceb01b6312dda177e51b16a0fdf3 OK
2024/12/17 01:54:35 INFO  : four: Copied (replaced existing)
2024/12/17 01:54:35 DEBUG : two: md5 = 769faa4f0cf39e5f7dba957860c720ac OK
2024/12/17 01:54:35 INFO  : two: Copied (replaced existing)
2024/12/17 01:54:35 DEBUG : five: md5 = 99da6f1c737d37ad16561de3c80082d4 OK
2024/12/17 01:54:35 INFO  : five: Copied (new)
2024/12/17 01:54:35 DEBUG : Waiting for deletions to finish
    sync_test.go:1449: Skip test with --checksum as no hashes supported
--- PASS: TestSyncWithUpdateOlder (12.54s)
=== 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-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    sync_test.go:1538: Can track renames: false
2024/12/17 01:54:39 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Ignoring --track-renames as the source and destination do not have a common hash
2024/12/17 01:54:39 DEBUG : potato: Need to transfer - File not found at Destination
2024/12/17 01:54:39 DEBUG : yam: Need to transfer - File not found at Destination
2024/12/17 01:54:39 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:54:39 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:54:41 DEBUG : potato: md5 = 79effcef399218ef5857303df8636c18 OK
2024/12/17 01:54:41 INFO  : potato: Copied (new)
2024/12/17 01:54:41 DEBUG : yam: md5 = 25bdf548b9f93c5d645e6114eb59b766 OK
2024/12/17 01:54:41 INFO  : yam: Copied (new)
2024/12/17 01:54:41 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:54:41 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Ignoring --track-renames as the source and destination do not have a common hash
2024/12/17 01:54:41 DEBUG : yaml: Need to transfer - File not found at Destination
2024/12/17 01:54:41 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 01:54:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:54:41 DEBUG : potato: Unchanged skipping
2024/12/17 01:54:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:54:44 DEBUG : yaml: md5 = 6e20aa23bdd7a4507d10f86cea727124 OK
2024/12/17 01:54:44 INFO  : yaml: Copied (new)
2024/12/17 01:54:44 DEBUG : Waiting for deletions to finish
2024/12/17 01:54:44 INFO  : yam: Deleted
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncWithTrackRenames (8.21s)
=== RUN   TestParseRenamesStrategyModtime
--- PASS: TestParseRenamesStrategyModtime (0.00s)
=== RUN   TestRenamesStrategyModtime
--- PASS: TestRenamesStrategyModtime (0.00s)
=== RUN   TestSyncWithTrackRenamesStrategyModtime
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    sync_test.go:1610: Can track renames: true
2024/12/17 01:54:47 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Making map for --track-renames
2024/12/17 01:54:47 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Finished making map for --track-renames
2024/12/17 01:54:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:54:47 DEBUG : potato: Need to transfer - No matching file found at Destination
2024/12/17 01:54:47 DEBUG : yam: Need to transfer - No matching file found at Destination
2024/12/17 01:54:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for renames to finish
2024/12/17 01:54:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:54:49 DEBUG : yam: md5 = 7adaa18b845c4e234355a0f7c97eb317 OK
2024/12/17 01:54:49 INFO  : yam: Copied (new)
2024/12/17 01:54:49 DEBUG : potato: md5 = d1acdac458bfa80410e357912e694627 OK
2024/12/17 01:54:49 INFO  : potato: Copied (new)
2024/12/17 01:54:49 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:54:49 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 01:54:49 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Making map for --track-renames
2024/12/17 01:54:49 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Finished making map for --track-renames
2024/12/17 01:54:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:54:49 DEBUG : potato: Unchanged skipping
2024/12/17 01:54:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for renames to finish
2024/12/17 01:54:50 INFO  : yam: Moved (server-side) to: yaml
2024/12/17 01:54:50 INFO  : yaml: Renamed from "yam"
2024/12/17 01:54:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:54:50 DEBUG : Waiting for deletions to finish
2024/12/17 01:54:50 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncWithTrackRenamesStrategyModtime (6.24s)
=== RUN   TestSyncWithTrackRenamesStrategyLeaf
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    sync_test.go:1649: Can track renames: true
2024/12/17 01:54:53 DEBUG : Added delayed dir = "sub", newDst=<nil>
2024/12/17 01:54:53 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Making map for --track-renames
2024/12/17 01:54:53 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Finished making map for --track-renames
2024/12/17 01:54:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:54:53 DEBUG : potato: Need to transfer - No matching file found at Destination
2024/12/17 01:54:53 DEBUG : sub/yam: Need to transfer - No matching file found at Destination
2024/12/17 01:54:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for renames to finish
2024/12/17 01:54:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:54:56 DEBUG : sub/yam: md5 = 2c722ca762a0ca51f1d482d513a681c2 OK
2024/12/17 01:54:56 INFO  : sub/yam: Copied (new)
2024/12/17 01:54:56 DEBUG : potato: md5 = 68d9c4f4ab95ffac775678eac4ece58a OK
2024/12/17 01:54:56 INFO  : potato: Copied (new)
2024/12/17 01:54:56 DEBUG : Waiting for deletions to finish
2024/12/17 01:54:57 INFO  : sub: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:54:58 DEBUG : Added delayed dir = "sub", newDst=sub
2024/12/17 01:54:58 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 01:54:58 DEBUG : potato: Unchanged skipping
2024/12/17 01:54:58 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Making map for --track-renames
2024/12/17 01:54:58 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Finished making map for --track-renames
2024/12/17 01:54:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:54:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for renames to finish
2024/12/17 01:54:59 INFO  : sub/yam: Moved (server-side) to: yam
2024/12/17 01:54:59 INFO  : yam: Renamed from "sub/yam"
2024/12/17 01:54:59 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:54:59 DEBUG : Waiting for deletions to finish
2024/12/17 01:54:59 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncWithTrackRenamesStrategyLeaf (10.52s)
=== RUN   TestServerSideMoveLocal
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:55:04 DEBUG : Creating backend with remote "/tmp/rclone2827950342/dir1"
2024/12/17 01:55:04 DEBUG : Config file has changed externally - reloading
2024/12/17 01:55:04 DEBUG : Creating backend with remote "/tmp/rclone2827950342/dir2"
2024/12/17 01:55:04 DEBUG : Local file system at /tmp/rclone2827950342/dir2: Using server-side directory move
2024/12/17 01:55:04 INFO  : Local file system at /tmp/rclone2827950342/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2024/12/17 01:55:04 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/12/17 01:55:04 DEBUG : Local file system at /tmp/rclone2827950342/dir2: Waiting for checks to finish
2024/12/17 01:55:04 DEBUG : Local file system at /tmp/rclone2827950342/dir2: Waiting for transfers to finish
2024/12/17 01:55:04 INFO  : file1.txt: Moved (server-side)
--- PASS: TestServerSideMoveLocal (0.48s)
=== RUN   TestMoveWithDeleteEmptySrcDirs
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:55:05 DEBUG : Added delayed dir = "nested", newDst=<nil>
2024/12/17 01:55:05 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:55:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:55:05 DEBUG : Added delayed dir = "nested/sub dir", newDst=<nil>
2024/12/17 01:55:05 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2024/12/17 01:55:05 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:55:05 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:55:07 DEBUG : sub dir/hello world: md5 = 0aebc1cff39299817ecc619a85856ed6 OK
2024/12/17 01:55:07 INFO  : sub dir/hello world: Copied (new)
2024/12/17 01:55:07 INFO  : sub dir/hello world: Deleted
2024/12/17 01:55:10 DEBUG : nested/sub dir/file: md5 = 48eff61a87b8487197f60bdafc1a0100 OK
2024/12/17 01:55:10 INFO  : nested/sub dir/file: Copied (new)
2024/12/17 01:55:10 INFO  : nested/sub dir/file: Deleted
2024/12/17 01:55:11 INFO  : nested/sub dir: Set directory modification time (using DirSetModTime)
2024/12/17 01:55:11 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/17 01:55:11 INFO  : nested: Set directory modification time (using DirSetModTime)
2024/12/17 01:55:11 INFO  : sub dir: Removing directory
2024/12/17 01:55:11 INFO  : nested/sub dir: Removing directory
2024/12/17 01:55:11 INFO  : nested: Removing directory
2024/12/17 01:55:11 DEBUG : Local file system at /tmp/rclone2827950342: deleted 3 directories
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:55:14 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
2024/12/17 01:55:15 DEBUG : 37qp6d1gohacv723ij3brofbu8/14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "ftp4kuv8se7tqduu6pmmcvjsmk"
2024/12/17 01:55:16 DEBUG : 37qp6d1gohacv723ij3brofbu8: Rmdir: contains trashed file: "14begaa36ih249fgrnfk6nulg0"
--- PASS: TestMoveWithDeleteEmptySrcDirs (13.32s)
=== RUN   TestMoveWithoutDeleteEmptySrcDirs
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:55:18 DEBUG : Added delayed dir = "nested", newDst=<nil>
2024/12/17 01:55:18 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/17 01:55:18 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/17 01:55:18 DEBUG : Added delayed dir = "nested/sub dir", newDst=<nil>
2024/12/17 01:55:18 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2024/12/17 01:55:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:55:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:55:21 DEBUG : sub dir/hello world: md5 = 4064ed3254bbd67fba928ba98ee5ab65 OK
2024/12/17 01:55:21 INFO  : sub dir/hello world: Copied (new)
2024/12/17 01:55:21 INFO  : sub dir/hello world: Deleted
2024/12/17 01:55:23 DEBUG : nested/sub dir/file: md5 = 68e16d2110a1425f6bb467ebd0ae574e OK
2024/12/17 01:55:23 INFO  : nested/sub dir/file: Copied (new)
2024/12/17 01:55:23 INFO  : nested/sub dir/file: Deleted
2024/12/17 01:55:24 INFO  : nested/sub dir: Set directory modification time (using DirSetModTime)
2024/12/17 01:55:24 INFO  : nested: Set directory modification time (using DirSetModTime)
2024/12/17 01:55:25 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:55:27 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
2024/12/17 01:55:28 DEBUG : 37qp6d1gohacv723ij3brofbu8/14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "ftp4kuv8se7tqduu6pmmcvjsmk"
2024/12/17 01:55:29 DEBUG : 37qp6d1gohacv723ij3brofbu8: Rmdir: contains trashed file: "14begaa36ih249fgrnfk6nulg0"
--- PASS: TestMoveWithoutDeleteEmptySrcDirs (13.18s)
=== RUN   TestMoveWithIgnoreExisting
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:55:31 DEBUG : existing: Need to transfer - File not found at Destination
2024/12/17 01:55:31 DEBUG : existing-b: Need to transfer - File not found at Destination
2024/12/17 01:55:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:55:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:55:32 DEBUG : existing-b: md5 = 4a24e031adc8a9f2dfa34d81fff9e899 OK
2024/12/17 01:55:32 INFO  : existing-b: Copied (new)
2024/12/17 01:55:32 INFO  : existing-b: Deleted
2024/12/17 01:55:33 DEBUG : existing: md5 = f9025ffac5259c52d8aa5f64ec4c721a OK
2024/12/17 01:55:33 INFO  : existing: Copied (new)
2024/12/17 01:55:33 INFO  : existing: Deleted
2024/12/17 01:55:33 DEBUG : existing: Destination exists, skipping
2024/12/17 01:55:33 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set
2024/12/17 01:55:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:55:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:55:33 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestMoveWithIgnoreExisting (4.87s)
=== RUN   TestServerSideMove
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:55:36 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-rivosin5gixi"
2024/12/17 01:55:36 DEBUG : Creating backend with remote "TestDrive:crypt/9hl0j9rbmvoicskjae4k585cc0a4usupv07md6fmhg5m0obiu25g"
2024/12/17 01:55:38 DEBUG : potato2: md5 = 390597f428881724ec7c7cb016d91924 OK
2024/12/17 01:55:40 DEBUG : empty space: md5 = 49f5ac30517ee80e060cd658b5f18baa OK
2024/12/17 01:55:42 DEBUG : potato3: md5 = 11f239bc3a86e455f4b266d43e4593f8 OK
    sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko' -> Encrypted drive 'TestCryptDrive:rclone-test-rivosin5gixi'
2024/12/17 01:55:45 DEBUG : empty space: md5 = a04771fe231a9d1cdb4f221fa9fa828e OK
2024/12/17 01:55:47 DEBUG : potato3: md5 = 69b1a0e53dcccf048546a174f76a8b85 OK
2024/12/17 01:55:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-rivosin5gixi': Using server-side directory move
2024/12/17 01:55:47 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-rivosin5gixi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2024/12/17 01:55:47 DEBUG : potato2: Need to transfer - File not found at Destination
2024/12/17 01:55:47 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms)
2024/12/17 01:55:47 DEBUG : empty space: Unchanged skipping
2024/12/17 01:55:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-rivosin5gixi': Waiting for checks to finish
2024/12/17 01:55:47 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2024/12/17 01:55:48 INFO  : empty space: Deleted
2024/12/17 01:55:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-rivosin5gixi': Waiting for transfers to finish
2024/12/17 01:55:48 INFO  : potato3: Deleted
2024/12/17 01:55:48 INFO  : potato2: Moved (server-side)
2024/12/17 01:55:49 INFO  : potato3: Moved (server-side)
2024/12/17 01:55:49 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zaqizij9toxo"
2024/12/17 01:55:49 DEBUG : Creating backend with remote "TestDrive:crypt/864qtbrvjcl02f403koer9td2age3bla8reb5i7h3l97pf4b08dg"
2024/12/17 01:55:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zaqizij9toxo': Using server-side directory move
2024/12/17 01:55:51 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-zaqizij9toxo': Server side directory move succeeded
2024/12/17 01:55:52 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zaqizij9toxo': Purge remote
2024/12/17 01:55:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-rivosin5gixi': Purge remote
2024/12/17 01:55:53 NOTICE: purge failed: directory not found
--- PASS: TestServerSideMove (17.75s)
=== RUN   TestServerSideMoveWithFilter
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:55:53 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cuzepul2noya"
2024/12/17 01:55:53 DEBUG : Creating backend with remote "TestDrive:crypt/su9lhfmmnem550fukb9rmijd6ml9krj7cpb010dmq6rqppg33hdg"
2024/12/17 01:55:56 DEBUG : potato2: md5 = 019d394cc2ab9cba653bc93db8d71d5c OK
2024/12/17 01:55:58 DEBUG : empty space: md5 = 7172f984c985cfc3d60bd84b70c2fa93 OK
2024/12/17 01:55:59 DEBUG : potato3: md5 = fdabd30f5ad46348443e393ff78ac1da OK
    sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko' -> Encrypted drive 'TestCryptDrive:rclone-test-cuzepul2noya'
2024/12/17 01:56:02 DEBUG : empty space: md5 = 0e9bbc603ed49599f1295a6422356d27 OK
2024/12/17 01:56:04 DEBUG : potato3: md5 = 1bc090d089986172b6d3df3da3c9d05e OK
2024/12/17 01:56:05 DEBUG : empty space: Excluded (Size Filter)
2024/12/17 01:56:05 DEBUG : empty space: Excluded
2024/12/17 01:56:05 DEBUG : empty space: Excluded (Size Filter)
2024/12/17 01:56:05 DEBUG : empty space: Excluded
2024/12/17 01:56:05 DEBUG : potato2: Need to transfer - File not found at Destination
2024/12/17 01:56:05 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2024/12/17 01:56:05 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cuzepul2noya': Waiting for checks to finish
2024/12/17 01:56:05 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cuzepul2noya': Waiting for transfers to finish
2024/12/17 01:56:05 INFO  : potato3: Deleted
2024/12/17 01:56:05 INFO  : potato2: Moved (server-side)
2024/12/17 01:56:06 INFO  : potato3: Moved (server-side)
2024/12/17 01:56:07 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-qozugew1yewo"
2024/12/17 01:56:07 DEBUG : Creating backend with remote "TestDrive:crypt/lmbddlibmnpes0d3mesvl4chlrsuu6ihaa8mjjv4h4lfe2qje750"
2024/12/17 01:56:08 DEBUG : empty space: Excluded (Size Filter)
2024/12/17 01:56:08 DEBUG : empty space: Excluded
2024/12/17 01:56:08 DEBUG : potato2: Need to transfer - File not found at Destination
2024/12/17 01:56:08 DEBUG : potato3: Need to transfer - File not found at Destination
2024/12/17 01:56:08 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qozugew1yewo': Waiting for checks to finish
2024/12/17 01:56:08 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qozugew1yewo': Waiting for transfers to finish
2024/12/17 01:56:10 INFO  : potato3: Moved (server-side)
2024/12/17 01:56:11 INFO  : potato2: Moved (server-side)
2024/12/17 01:56:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qozugew1yewo': Purge remote
2024/12/17 01:56:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cuzepul2noya': Purge remote
--- PASS: TestServerSideMoveWithFilter (20.31s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:56:14 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-noredaj3bica"
2024/12/17 01:56:14 DEBUG : Creating backend with remote "TestDrive:crypt/mtjq36c11g5874r1hvkesmnr7jg3n94d4l4as6a1qlsko2sje4qg"
2024/12/17 01:56:16 DEBUG : potato2: md5 = 0cebd563e1981f42384d19a0b3ec3fbf OK
2024/12/17 01:56:18 DEBUG : empty space: md5 = d7e805e9c19382edab09f83b0ed7f8c8 OK
2024/12/17 01:56:20 DEBUG : potato3: md5 = 77cbab23d1056f86e817d59d5fd88b02 OK
2024/12/17 01:56:20 DEBUG : tomatoDir: Making directory
    sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko' -> Encrypted drive 'TestCryptDrive:rclone-test-noredaj3bica'
2024/12/17 01:56:24 DEBUG : empty space: md5 = 9b67d305660500108cf9e5957f2c3bb9 OK
2024/12/17 01:56:26 DEBUG : potato3: md5 = 8250aa2267a4225b2b5bcf1bba139f43 OK
2024/12/17 01:56:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-noredaj3bica': Using server-side directory move
2024/12/17 01:56:26 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-noredaj3bica': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2024/12/17 01:56:26 DEBUG : potato2: Need to transfer - File not found at Destination
2024/12/17 01:56:26 DEBUG : Added delayed dir = "tomatoDir", newDst=<nil>
2024/12/17 01:56:26 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms)
2024/12/17 01:56:26 DEBUG : empty space: Unchanged skipping
2024/12/17 01:56:26 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2024/12/17 01:56:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-noredaj3bica': Waiting for checks to finish
2024/12/17 01:56:27 INFO  : empty space: Deleted
2024/12/17 01:56:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-noredaj3bica': Waiting for transfers to finish
2024/12/17 01:56:27 INFO  : potato3: Deleted
2024/12/17 01:56:27 INFO  : potato2: Moved (server-side)
2024/12/17 01:56:28 INFO  : potato3: Moved (server-side)
2024/12/17 01:56:28 INFO  : tomatoDir: Removing directory
2024/12/17 01:56:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': deleted 1 directories
2024/12/17 01:56:30 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kijimer0coke"
2024/12/17 01:56:30 DEBUG : Config file has changed externally - reloading
2024/12/17 01:56:30 DEBUG : Creating backend with remote "TestDrive:crypt/hrta65cojb3si44p9dvgpgpvc93asvn2k3cpc6ro632mqqcifq50"
2024/12/17 01:56:31 DEBUG : tomatoDir: Making directory
2024/12/17 01:56:32 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kijimer0coke': Using server-side directory move
2024/12/17 01:56:33 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kijimer0coke': Server side directory move succeeded
2024/12/17 01:56:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kijimer0coke': Purge remote
2024/12/17 01:56:35 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-noredaj3bica': Purge remote
2024/12/17 01:56:35 NOTICE: purge failed: directory not found
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (21.83s)
=== RUN   TestServerSideMoveOverlap
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    sync_test.go:1910: Skipping test as remote supports DirMove
--- SKIP: TestServerSideMoveOverlap (0.46s)
=== RUN   TestSyncOverlap
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:56:36 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/rclone-sync-test"
2024/12/17 01:56:36 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/i7355aa0sb89uagn75vfjqr6s3e3e1h2et8in57abn1fe8qfego0"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncOverlap (4.01s)
=== RUN   TestSyncOverlapWithFilter
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:56:40 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/rclone-sync-test"
2024/12/17 01:56:40 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/i7355aa0sb89uagn75vfjqr6s3e3e1h2et8in57abn1fe8qfego0"
2024/12/17 01:56:42 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/rclone-sync-test-include/layer2"
2024/12/17 01:56:43 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/khf2vlblvnbf9ftevqm0htmss73hj1j5a7q328e4ohrgs7e2loe0/n01jpl9202nn9ec1rb75h0f1c4"
2024/12/17 01:56:46 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/rclone-sync-test-ignore-file"
2024/12/17 01:56:46 DEBUG : Config file has changed externally - reloading
2024/12/17 01:56:46 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/kmf11jujl9uha3tjganvgpkn45bu778hb5vb5riug1c26b38k7sg"
2024/12/17 01:56:51 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = 5da6b20ac8841a710c9b2867b2ea2b25 OK
2024/12/17 01:56:52 DEBUG : rclone-sync-test-ignore-file: Excluded
2024/12/17 01:56:52 DEBUG : rclone-sync-test: Excluded
2024/12/17 01:56:52 DEBUG : Added delayed dir = "rclone-sync-test-include", newDst=<nil>
2024/12/17 01:56:53 DEBUG : rclone-sync-test-include/layer2: Excluded
2024/12/17 01:56:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/rclone-sync-test': Waiting for checks to finish
2024/12/17 01:56:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/rclone-sync-test': Waiting for transfers to finish
2024/12/17 01:56:53 DEBUG : Waiting for deletions to finish
2024/12/17 01:56:53 INFO  : There was nothing to transfer
2024/12/17 01:56:54 DEBUG : rclone-sync-test-ignore-file: Excluded
2024/12/17 01:56:54 DEBUG : rclone-sync-test: Excluded
2024/12/17 01:56:55 DEBUG : rclone-sync-test-include/layer2: Excluded
2024/12/17 01:56:55 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:56:55 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:56:55 DEBUG : Waiting for deletions to finish
2024/12/17 01:56:55 INFO  : rclone-sync-test-include: Removing directory
2024/12/17 01:56:55 DEBUG : khf2vlblvnbf9ftevqm0htmss73hj1j5a7q328e4ohrgs7e2loe0: Rmdir: contains file: "n01jpl9202nn9ec1rb75h0f1c4"
2024/12/17 01:56:55 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty
2024/12/17 01:56:55 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': failed to delete 1 directories
2024/12/17 01:56:55 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:56:56 DEBUG : rclone-sync-test-ignore-file: Excluded
2024/12/17 01:56:56 DEBUG : rclone-sync-test: Excluded
2024/12/17 01:56:56 DEBUG : Added delayed dir = "rclone-sync-test-include", newDst=<nil>
2024/12/17 01:56:57 DEBUG : rclone-sync-test-include/layer2: Excluded
2024/12/17 01:56:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/rclone-sync-test-include/layer2': Waiting for checks to finish
2024/12/17 01:56:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/rclone-sync-test-include/layer2': Waiting for transfers to finish
2024/12/17 01:56:57 DEBUG : Waiting for deletions to finish
2024/12/17 01:56:57 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:56:57 DEBUG : rclone-sync-test-ignore-file: Excluded
2024/12/17 01:56:58 DEBUG : rclone-sync-test: Excluded
2024/12/17 01:56:58 DEBUG : rclone-sync-test-include/layer2: Excluded
2024/12/17 01:56:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 01:56:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 01:56:58 DEBUG : Waiting for deletions to finish
2024/12/17 01:56:58 INFO  : rclone-sync-test-include: Removing directory
2024/12/17 01:56:59 DEBUG : khf2vlblvnbf9ftevqm0htmss73hj1j5a7q328e4ohrgs7e2loe0: Rmdir: contains file: "n01jpl9202nn9ec1rb75h0f1c4"
2024/12/17 01:56:59 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty
2024/12/17 01:56:59 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': failed to delete 1 directories
2024/12/17 01:56:59 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:56:59 DEBUG : : Excluded
2024/12/17 01:56:59 DEBUG : rclone-sync-test-ignore-file: Excluded
2024/12/17 01:57:00 DEBUG : rclone-sync-test: Excluded
2024/12/17 01:57:00 DEBUG : Added delayed dir = "rclone-sync-test-include", newDst=<nil>
2024/12/17 01:57:00 DEBUG : rclone-sync-test-include/layer2: Excluded
2024/12/17 01:57:00 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/rclone-sync-test-ignore-file': Waiting for checks to finish
2024/12/17 01:57:00 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/rclone-sync-test-ignore-file': Waiting for transfers to finish
2024/12/17 01:57:00 DEBUG : Waiting for deletions to finish
2024/12/17 01:57:00 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:57:03 DEBUG : khf2vlblvnbf9ftevqm0htmss73hj1j5a7q328e4ohrgs7e2loe0: Rmdir: contains trashed file: "n01jpl9202nn9ec1rb75h0f1c4"
2024/12/17 01:57:04 DEBUG : kmf11jujl9uha3tjganvgpkn45bu778hb5vb5riug1c26b38k7sg: Rmdir: contains trashed file: "i0t74httmdlljg425j6tg570d8"
--- PASS: TestSyncOverlapWithFilter (33.57s)
=== RUN   TestSyncCompareDest
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:57:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/dst"
2024/12/17 01:57:14 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/31u3jie661vd5p8j7rtc3hgbh0"
2024/12/17 01:57:15 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/CompareDest"
2024/12/17 01:57:15 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/gveqi14airsml4bgu7krj116o8"
2024/12/17 01:57:17 DEBUG : one: Need to transfer - File not found at Destination
2024/12/17 01:57:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:57:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:57:20 DEBUG : one: md5 = dc3a20aa43b6e9ee6571e97934112598 OK
2024/12/17 01:57:20 INFO  : one: Copied (new)
2024/12/17 01:57:20 DEBUG : Waiting for deletions to finish
2024/12/17 01:57:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:57:21 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/12/17 01:57:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:57:23 DEBUG : one: md5 = 855a26dfbd8a35f7de714722de09a892 OK
2024/12/17 01:57:23 INFO  : one: Copied (replaced existing)
2024/12/17 01:57:23 DEBUG : Waiting for deletions to finish
2024/12/17 01:57:25 DEBUG : dst/one: md5 = 3bc362e9995904268259da98969e8863 OK
2024/12/17 01:57:28 DEBUG : CompareDest/one: md5 = ba84a9a268351e372e885e4c91bc6ff7 OK
2024/12/17 01:57:29 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/12/17 01:57:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:57:29 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:57:29 DEBUG : one: Destination found in --compare-dest, skipping
2024/12/17 01:57:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:57:29 DEBUG : Waiting for deletions to finish
2024/12/17 01:57:29 INFO  : There was nothing to transfer
2024/12/17 01:57:32 DEBUG : CompareDest/two: md5 = 9f322e7085bb748b32627c79880f2e01 OK
2024/12/17 01:57:33 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:57:33 DEBUG : two: Destination found in --compare-dest, skipping
2024/12/17 01:57:33 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/12/17 01:57:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:57:33 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:57:33 DEBUG : one: Destination found in --compare-dest, skipping
2024/12/17 01:57:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:57:33 DEBUG : Waiting for deletions to finish
2024/12/17 01:57:33 INFO  : There was nothing to transfer
2024/12/17 01:57:34 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:57:34 DEBUG : two: Destination found in --compare-dest, skipping
2024/12/17 01:57:34 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/12/17 01:57:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:57:34 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:57:34 DEBUG : one: Destination found in --compare-dest, skipping
2024/12/17 01:57:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:57:34 DEBUG : Waiting for deletions to finish
2024/12/17 01:57:34 INFO  : There was nothing to transfer
    sync_test.go:2175: No hash on uploaded file so skipping compare timestamp test
2024/12/17 01:57:36 DEBUG : two: Sizes differ (src 5 vs dst 3)
2024/12/17 01:57:36 DEBUG : two: Need to transfer - File not found at Destination
2024/12/17 01:57:36 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/12/17 01:57:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:57:36 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:57:36 DEBUG : one: Destination found in --compare-dest, skipping
2024/12/17 01:57:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:57:38 DEBUG : two: md5 = 3684f6bba9919660697a2471a0a266bc OK
2024/12/17 01:57:38 INFO  : two: Copied (new)
2024/12/17 01:57:38 DEBUG : Waiting for deletions to finish
2024/12/17 01:57:41 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k"
2024/12/17 01:57:41 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc"
2024/12/17 01:57:42 DEBUG : gveqi14airsml4bgu7krj116o8: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc"
2024/12/17 01:57:42 DEBUG : gveqi14airsml4bgu7krj116o8: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k"
--- PASS: TestSyncCompareDest (29.14s)
=== RUN   TestSyncMultipleCompareDest
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:57:46 DEBUG : pre-dest1/1: md5 = 1e44507a05373451a3a91a45429b645f OK
2024/12/17 01:57:48 DEBUG : pre-dest2/2: md5 = bdd7ed1980214e5d939c33b51c996b6d OK
2024/12/17 01:57:49 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/dest"
2024/12/17 01:57:49 DEBUG : Config file has changed externally - reloading
2024/12/17 01:57:49 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/rg03c1jvnehrrc617i0lnqjddc"
2024/12/17 01:57:50 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/pre-dest1"
2024/12/17 01:57:50 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/bbnblvh6k061ssopqrp18kd7gc"
2024/12/17 01:57:51 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/pre-dest2"
2024/12/17 01:57:51 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/dgicm1h6b5ejvlltm8eeif0bnk"
2024/12/17 01:57:53 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 01:57:53 DEBUG : 1: Destination found in --compare-dest, skipping
2024/12/17 01:57:53 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 01:57:53 DEBUG : 2: Destination found in --compare-dest, skipping
2024/12/17 01:57:54 DEBUG : 3: Need to transfer - File not found at Destination
2024/12/17 01:57:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dest': Waiting for checks to finish
2024/12/17 01:57:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dest': Waiting for transfers to finish
2024/12/17 01:57:56 DEBUG : 3: md5 = 2d84ee42e6d538e2fe2cdc1fad1d0b13 OK
2024/12/17 01:57:56 INFO  : 3: Copied (new)
2024/12/17 01:57:56 DEBUG : Waiting for deletions to finish
2024/12/17 01:58:00 DEBUG : dgicm1h6b5ejvlltm8eeif0bnk: Rmdir: contains trashed file: "1ghu8geql9vrl726n32nrf65bc"
2024/12/17 01:58:01 DEBUG : bbnblvh6k061ssopqrp18kd7gc: Rmdir: contains trashed file: "skbfko3oil7es0lj68ob574sro"
2024/12/17 01:58:01 DEBUG : rg03c1jvnehrrc617i0lnqjddc: Rmdir: contains trashed file: "g072p7hmjchlnj0gibe64c1epg"
--- PASS: TestSyncMultipleCompareDest (19.70s)
=== RUN   TestSyncCopyDest
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:58:02 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/dst"
2024/12/17 01:58:02 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/31u3jie661vd5p8j7rtc3hgbh0"
2024/12/17 01:58:04 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/CopyDest"
2024/12/17 01:58:04 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/d09o6po3f7bm6ce32vdgs8h9ls"
2024/12/17 01:58:06 DEBUG : one: Need to transfer - File not found at Destination
2024/12/17 01:58:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:58:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:58:08 DEBUG : one: md5 = 5181ea40a72403e3a627aa25107e3835 OK
2024/12/17 01:58:08 INFO  : one: Copied (new)
2024/12/17 01:58:08 DEBUG : Waiting for deletions to finish
2024/12/17 01:58:10 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/12/17 01:58:10 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:58:10 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:58:11 DEBUG : one: md5 = 98056e037ecadf2be6d1e09918cab1eb OK
2024/12/17 01:58:11 INFO  : one: Copied (replaced existing)
2024/12/17 01:58:11 DEBUG : Waiting for deletions to finish
2024/12/17 01:58:14 DEBUG : dst/one: md5 = 2c2fc6c0a04dac9153c816790274367e OK
2024/12/17 01:58:17 DEBUG : CopyDest/one: md5 = 09a62ffa1a627280c94f6542e2ead62c OK
2024/12/17 01:58:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/BackupDir"
2024/12/17 01:58:17 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/s6dbk3lfi7c9kfvo6j7bla9m0g"
2024/12/17 01:58:19 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/12/17 01:58:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:58:19 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:58:19 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/12/17 01:58:22 INFO  : one: Moved (server-side)
2024/12/17 01:58:23 INFO  : one: Copied (server-side copy)
2024/12/17 01:58:23 DEBUG : one: Destination found in --copy-dest, using server-side copy
2024/12/17 01:58:23 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:58:23 DEBUG : Waiting for deletions to finish
2024/12/17 01:58:25 DEBUG : CopyDest/two: md5 = 9c16ef6f6bf12ea4960225e47f31872c OK
2024/12/17 01:58:26 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:58:27 INFO  : two: Copied (server-side copy)
2024/12/17 01:58:27 DEBUG : two: Destination found in --copy-dest, using server-side copy
2024/12/17 01:58:27 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:58:27 DEBUG : one: Unchanged skipping
2024/12/17 01:58:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:58:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:58:27 DEBUG : Waiting for deletions to finish
2024/12/17 01:58:28 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:58:28 DEBUG : one: Unchanged skipping
2024/12/17 01:58:28 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:58:28 DEBUG : two: Unchanged skipping
2024/12/17 01:58:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:58:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:58:28 DEBUG : Waiting for deletions to finish
2024/12/17 01:58:28 INFO  : There was nothing to transfer
2024/12/17 01:58:30 DEBUG : CopyDest/three: md5 = 8f1b701d0ab6b93aca87bc979a9ad182 OK
2024/12/17 01:58:31 DEBUG : three: Sizes differ (src 7 vs dst 5)
2024/12/17 01:58:31 DEBUG : three: Destination not found in --copy-dest
2024/12/17 01:58:31 DEBUG : three: Need to transfer - File not found at Destination
2024/12/17 01:58:31 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:58:31 DEBUG : one: Unchanged skipping
2024/12/17 01:58:31 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2024/12/17 01:58:31 DEBUG : two: Unchanged skipping
2024/12/17 01:58:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:58:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:58:33 DEBUG : three: md5 = 3df9f5c80f040dc36eb544524eeb4947 OK
2024/12/17 01:58:33 INFO  : three: Copied (new)
2024/12/17 01:58:33 DEBUG : Waiting for deletions to finish
2024/12/17 01:58:38 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "b6jdmn2b1adrhc8vikh7edr9os"
2024/12/17 01:58:38 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc"
2024/12/17 01:58:38 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k"
2024/12/17 01:58:39 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc"
2024/12/17 01:58:39 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "b6jdmn2b1adrhc8vikh7edr9os"
2024/12/17 01:58:39 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k"
2024/12/17 01:58:40 DEBUG : s6dbk3lfi7c9kfvo6j7bla9m0g: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc"
--- PASS: TestSyncCopyDest (38.60s)
=== RUN   TestSyncBackupDir
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:58:44 DEBUG : dst/one: md5 = 1ebe022767ffc65f92e8d75d6eda1675 OK
2024/12/17 01:58:45 DEBUG : dst/two: md5 = 8913b01555a0d0e8e71c59507a6cef2b OK
2024/12/17 01:58:47 DEBUG : dst/three.txt: md5 = 1a3d1628e8aeb9d3aa9fbf7ab003c582 OK
2024/12/17 01:58:48 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/dst"
2024/12/17 01:58:48 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/31u3jie661vd5p8j7rtc3hgbh0"
2024/12/17 01:58:48 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/backup"
2024/12/17 01:58:48 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/1nrff024r7pq65ecp72fc28jb0"
2024/12/17 01:58:50 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/12/17 01:58:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:58:50 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 01:58:50 DEBUG : two: Unchanged skipping
2024/12/17 01:58:53 INFO  : one: Moved (server-side)
2024/12/17 01:58:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:58:54 DEBUG : one: md5 = 142cb5ff388d80c21290722f34f56678 OK
2024/12/17 01:58:54 INFO  : one: Copied (new)
2024/12/17 01:58:54 DEBUG : Waiting for deletions to finish
2024/12/17 01:58:56 INFO  : three.txt: Moved (server-side)
2024/12/17 01:58:56 INFO  : three.txt: Moved into backup dir
2024/12/17 01:58:58 DEBUG : dst/three.txt: md5 = 24331d41d6bcc318e2e0a88c917b62de OK
2024/12/17 01:58:58 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/12/17 01:58:58 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 01:58:58 DEBUG : two: Unchanged skipping
2024/12/17 01:58:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:58:59 INFO  : one: Deleted
2024/12/17 01:59:00 INFO  : one: Moved (server-side)
2024/12/17 01:59:00 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:59:02 DEBUG : one: md5 = 5b3093c313533553f8215b4c6532265f OK
2024/12/17 01:59:02 INFO  : one: Copied (new)
2024/12/17 01:59:02 DEBUG : Waiting for deletions to finish
2024/12/17 01:59:03 INFO  : three.txt: Deleted
2024/12/17 01:59:04 INFO  : three.txt: Moved (server-side)
2024/12/17 01:59:04 INFO  : three.txt: Moved into backup dir
2024/12/17 01:59:07 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc"
2024/12/17 01:59:07 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k"
2024/12/17 01:59:08 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc"
2024/12/17 01:59:08 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "tr2hj63d80ftlmvm6a952snjcc"
2024/12/17 01:59:08 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc"
2024/12/17 01:59:08 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "tr2hj63d80ftlmvm6a952snjcc"
--- PASS: TestSyncBackupDir (28.25s)
=== RUN   TestSyncBackupDirWithSuffix
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:59:12 DEBUG : dst/one: md5 = ebe649767042565ec40ad0ded57c201c OK
2024/12/17 01:59:13 DEBUG : dst/two: md5 = 6cc8ac2242f9d457d96757dcd751216b OK
2024/12/17 01:59:15 DEBUG : dst/three.txt: md5 = e476375c9952a43278dec43e8b9fa4e7 OK
2024/12/17 01:59:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/dst"
2024/12/17 01:59:16 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/31u3jie661vd5p8j7rtc3hgbh0"
2024/12/17 01:59:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/backup"
2024/12/17 01:59:17 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/1nrff024r7pq65ecp72fc28jb0"
2024/12/17 01:59:18 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/12/17 01:59:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:59:18 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 01:59:18 DEBUG : two: Unchanged skipping
2024/12/17 01:59:21 INFO  : one: Moved (server-side) to: one.bak
2024/12/17 01:59:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:59:22 DEBUG : one: md5 = b9ee904263436c0b082b104cec02d5cb OK
2024/12/17 01:59:22 INFO  : one: Copied (new)
2024/12/17 01:59:22 DEBUG : Waiting for deletions to finish
2024/12/17 01:59:23 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/12/17 01:59:23 INFO  : three.txt: Moved into backup dir
2024/12/17 01:59:26 DEBUG : dst/three.txt: md5 = 528769abe6974608f9a564ea50699a62 OK
2024/12/17 01:59:26 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 01:59:26 DEBUG : two: Unchanged skipping
2024/12/17 01:59:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:59:26 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/12/17 01:59:27 INFO  : one.bak: Deleted
2024/12/17 01:59:28 INFO  : one: Moved (server-side) to: one.bak
2024/12/17 01:59:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:59:30 DEBUG : one: md5 = da0bf46e0699f627d047dbd3b4f8bd7d OK
2024/12/17 01:59:30 INFO  : one: Copied (new)
2024/12/17 01:59:30 DEBUG : Waiting for deletions to finish
2024/12/17 01:59:31 INFO  : three.txt.bak: Deleted
2024/12/17 01:59:32 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/12/17 01:59:32 INFO  : three.txt: Moved into backup dir
2024/12/17 01:59:36 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc"
2024/12/17 01:59:36 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k"
2024/12/17 01:59:37 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "sk91qgsqfn0r271r2ehsaoc20c"
2024/12/17 01:59:37 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "qltcak2uaegtmcoa8vijdppbp8"
2024/12/17 01:59:37 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "qltcak2uaegtmcoa8vijdppbp8"
2024/12/17 01:59:37 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "sk91qgsqfn0r271r2ehsaoc20c"
--- PASS: TestSyncBackupDirWithSuffix (28.67s)
=== RUN   TestSyncBackupDirWithSuffixKeepExtension
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 01:59:41 DEBUG : dst/one: md5 = a042587849e25b442bf0411203bf0dfd OK
2024/12/17 01:59:43 DEBUG : dst/two: md5 = eb5b3897304823b77e191b7cc6de1637 OK
2024/12/17 01:59:44 DEBUG : dst/three.txt: md5 = ea042b92d3d3eeee3e43fe0c7f9ee88e OK
2024/12/17 01:59:45 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/dst"
2024/12/17 01:59:45 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/31u3jie661vd5p8j7rtc3hgbh0"
2024/12/17 01:59:45 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/backup"
2024/12/17 01:59:46 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/1nrff024r7pq65ecp72fc28jb0"
2024/12/17 01:59:47 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/12/17 01:59:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:59:47 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 01:59:47 DEBUG : two: Unchanged skipping
2024/12/17 01:59:49 INFO  : one: Moved (server-side) to: one-2019-01-01
2024/12/17 01:59:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:59:51 DEBUG : one: md5 = a7a8d467385e20f072a5ed0be4204271 OK
2024/12/17 01:59:51 INFO  : one: Copied (new)
2024/12/17 01:59:51 DEBUG : Waiting for deletions to finish
2024/12/17 01:59:52 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2024/12/17 01:59:52 INFO  : three.txt: Moved into backup dir
2024/12/17 01:59:55 DEBUG : dst/three.txt: md5 = b1e822dcf85809dad0f0501074d23558 OK
2024/12/17 01:59:55 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/12/17 01:59:55 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 01:59:55 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 01:59:55 DEBUG : two: Unchanged skipping
2024/12/17 01:59:56 INFO  : one-2019-01-01: Deleted
2024/12/17 01:59:57 INFO  : one: Moved (server-side) to: one-2019-01-01
2024/12/17 01:59:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 01:59:58 DEBUG : one: md5 = db28130037ada0933438b58b2fd49d26 OK
2024/12/17 01:59:58 INFO  : one: Copied (new)
2024/12/17 01:59:58 DEBUG : Waiting for deletions to finish
2024/12/17 01:59:59 INFO  : three-2019-01-01.txt: Deleted
2024/12/17 02:00:01 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2024/12/17 02:00:01 INFO  : three.txt: Moved into backup dir
2024/12/17 02:00:04 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc"
2024/12/17 02:00:04 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k"
2024/12/17 02:00:06 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "qtq2f6qq4lgpsmj88vb7147qje3prudmtfdtr2342fth58vo9a50"
2024/12/17 02:00:06 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "jcjl1bv3ep0v8kt35lqql1co90"
2024/12/17 02:00:06 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "jcjl1bv3ep0v8kt35lqql1co90"
2024/12/17 02:00:06 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "qtq2f6qq4lgpsmj88vb7147qje3prudmtfdtr2342fth58vo9a50"
--- PASS: TestSyncBackupDirWithSuffixKeepExtension (28.74s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:00:09 DEBUG : dst/one: md5 = 2f01a90af868d45ac2f23928e2b8e9bf OK
2024/12/17 02:00:11 DEBUG : dst/two: md5 = 7798149411f58f34583af129445e6d07 OK
2024/12/17 02:00:13 DEBUG : dst/three.txt: md5 = e2778fd66181255e4ab2c97b639f0748 OK
2024/12/17 02:00:14 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/dst"
2024/12/17 02:00:14 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/31u3jie661vd5p8j7rtc3hgbh0"
2024/12/17 02:00:15 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/12/17 02:00:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 02:00:15 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:00:15 DEBUG : two: Unchanged skipping
2024/12/17 02:00:16 INFO  : one: Moved (server-side) to: one.bak
2024/12/17 02:00:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 02:00:17 DEBUG : one: md5 = e678a87ab742ebc3af0191150a0d684c OK
2024/12/17 02:00:17 INFO  : one: Copied (new)
2024/12/17 02:00:17 DEBUG : Waiting for deletions to finish
2024/12/17 02:00:18 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/12/17 02:00:18 INFO  : three.txt: Moved into backup dir
2024/12/17 02:00:20 DEBUG : dst/three.txt: md5 = d9ab79bfec901501b550df4cbfe6979d OK
2024/12/17 02:00:21 DEBUG : three.txt.bak: Excluded (Path Filter)
2024/12/17 02:00:21 DEBUG : three.txt.bak: Excluded
2024/12/17 02:00:21 DEBUG : one.bak: Excluded (Path Filter)
2024/12/17 02:00:21 DEBUG : one.bak: Excluded
2024/12/17 02:00:21 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/12/17 02:00:21 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:00:21 DEBUG : two: Unchanged skipping
2024/12/17 02:00:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for checks to finish
2024/12/17 02:00:22 INFO  : one.bak: Deleted
2024/12/17 02:00:22 INFO  : one: Moved (server-side) to: one.bak
2024/12/17 02:00:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko/dst': Waiting for transfers to finish
2024/12/17 02:00:24 DEBUG : one: md5 = f5678755e6eb86137af784413a699704 OK
2024/12/17 02:00:24 INFO  : one: Copied (new)
2024/12/17 02:00:24 DEBUG : Waiting for deletions to finish
2024/12/17 02:00:25 INFO  : three.txt.bak: Deleted
2024/12/17 02:00:26 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/12/17 02:00:26 INFO  : three.txt: Moved into backup dir
2024/12/17 02:00:30 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc"
2024/12/17 02:00:30 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "sk91qgsqfn0r271r2ehsaoc20c"
2024/12/17 02:00:30 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "qltcak2uaegtmcoa8vijdppbp8"
2024/12/17 02:00:30 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "sk91qgsqfn0r271r2ehsaoc20c"
2024/12/17 02:00:30 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k"
2024/12/17 02:00:30 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "qltcak2uaegtmcoa8vijdppbp8"
--- PASS: TestSyncBackupDirSuffixOnly (24.02s)
=== RUN   TestSyncSuffix
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:00:33 DEBUG : dst/one: md5 = d7a139aa1177b4f8e37c32a659319da2 OK
2024/12/17 02:00:35 DEBUG : dst/two: md5 = d0c91fd458d713d422360846e800d45b OK
2024/12/17 02:00:37 DEBUG : dst/three.txt: md5 = e28166ff7be2f6972ea2079615f6274d OK
2024/12/17 02:00:37 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/dst"
2024/12/17 02:00:37 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/31u3jie661vd5p8j7rtc3hgbh0"
2024/12/17 02:00:38 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/12/17 02:00:39 INFO  : one: Moved (server-side) to: one.bak
2024/12/17 02:00:41 DEBUG : one: md5 = c5bf99a9a9e469555bd827c50b5d1f2d OK
2024/12/17 02:00:41 INFO  : one: Copied (new)
2024/12/17 02:00:41 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:00:41 DEBUG : two: Unchanged skipping
2024/12/17 02:00:41 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2024/12/17 02:00:42 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/12/17 02:00:44 DEBUG : three.txt: md5 = 178b7a2c070b52a9bb9f2375e51ebc66 OK
2024/12/17 02:00:44 INFO  : three.txt: Copied (new)
2024/12/17 02:00:45 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/12/17 02:00:46 INFO  : one.bak: Deleted
2024/12/17 02:00:47 INFO  : one: Moved (server-side) to: one.bak
2024/12/17 02:00:48 DEBUG : one: md5 = d8f53633c4379a34db6172500901d7dd OK
2024/12/17 02:00:48 INFO  : one: Copied (new)
2024/12/17 02:00:49 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:00:49 DEBUG : two: Unchanged skipping
2024/12/17 02:00:49 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2024/12/17 02:00:50 INFO  : three.txt.bak: Deleted
2024/12/17 02:00:50 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/12/17 02:00:52 DEBUG : three.txt: md5 = b0388a84f417820a2db89402df6f3b83 OK
2024/12/17 02:00:52 INFO  : three.txt: Copied (new)
2024/12/17 02:00:56 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc"
2024/12/17 02:00:56 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "tr2hj63d80ftlmvm6a952snjcc"
2024/12/17 02:00:56 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "qltcak2uaegtmcoa8vijdppbp8"
2024/12/17 02:00:56 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k"
2024/12/17 02:00:56 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "sk91qgsqfn0r271r2ehsaoc20c"
2024/12/17 02:00:56 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "sk91qgsqfn0r271r2ehsaoc20c"
2024/12/17 02:00:56 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "qltcak2uaegtmcoa8vijdppbp8"
--- PASS: TestSyncSuffix (26.67s)
=== RUN   TestSyncSuffixKeepExtension
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:01:00 DEBUG : dst/one: md5 = 6fe2b9d68a16fe8f30bbc020b11f75ba OK
2024/12/17 02:01:02 DEBUG : dst/two: md5 = f0a47cf3a33618bb68f23244ab7487de OK
2024/12/17 02:01:04 DEBUG : dst/three.txt: md5 = c9382ee8b8b7517f5145034675238139 OK
2024/12/17 02:01:05 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cejugaf3doko/dst"
2024/12/17 02:01:05 DEBUG : Creating backend with remote "TestDrive:crypt/lo228cht4920s7vdbr8u48a6egojsg9r25hq0tqd6ubt6jd132sg/31u3jie661vd5p8j7rtc3hgbh0"
2024/12/17 02:01:06 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/12/17 02:01:06 INFO  : one: Moved (server-side) to: one-2019-01-01
2024/12/17 02:01:08 DEBUG : one: md5 = 5bae8f9cd362c21f4a84e78b7bd7b401 OK
2024/12/17 02:01:08 INFO  : one: Copied (new)
2024/12/17 02:01:08 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:01:08 DEBUG : two: Unchanged skipping
2024/12/17 02:01:09 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2024/12/17 02:01:09 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2024/12/17 02:01:11 DEBUG : three.txt: md5 = 53cdd4a8909238da4905289a424a9cb3 OK
2024/12/17 02:01:11 INFO  : three.txt: Copied (new)
2024/12/17 02:01:12 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/12/17 02:01:13 INFO  : one-2019-01-01: Deleted
2024/12/17 02:01:14 INFO  : one: Moved (server-side) to: one-2019-01-01
2024/12/17 02:01:15 DEBUG : one: md5 = 0a5d9b8f82628caf86d29a32e27711f5 OK
2024/12/17 02:01:15 INFO  : one: Copied (new)
2024/12/17 02:01:16 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:01:16 DEBUG : two: Unchanged skipping
2024/12/17 02:01:16 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2024/12/17 02:01:17 INFO  : three-2019-01-01.txt: Deleted
2024/12/17 02:01:17 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2024/12/17 02:01:19 DEBUG : three.txt: md5 = b1ff886d889bdb8948e04a5420df03a4 OK
2024/12/17 02:01:19 INFO  : three.txt: Copied (new)
2024/12/17 02:01:24 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "tr2hj63d80ftlmvm6a952snjcc"
2024/12/17 02:01:24 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc"
2024/12/17 02:01:24 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "jcjl1bv3ep0v8kt35lqql1co90"
2024/12/17 02:01:24 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "jcjl1bv3ep0v8kt35lqql1co90"
2024/12/17 02:01:24 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "qtq2f6qq4lgpsmj88vb7147qje3prudmtfdtr2342fth58vo9a50"
2024/12/17 02:01:24 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k"
2024/12/17 02:01:24 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "qtq2f6qq4lgpsmj88vb7147qje3prudmtfdtr2342fth58vo9a50"
--- PASS: TestSyncSuffixKeepExtension (27.15s)
=== RUN   TestSyncUTFNorm
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:01:26 DEBUG : Testêé: md5 = 639b3c110741e1957a32c91bfc2d0538 OK
2024/12/17 02:01:27 DEBUG : Testêé: Sizes differ (src 14 vs dst 18)
2024/12/17 02:01:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 02:01:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 02:01:28 DEBUG : Testêé: md5 = f4524336e283565375e4fb65e323707c OK
2024/12/17 02:01:28 INFO  : Testêé: Copied (replaced existing) to: Testêé
2024/12/17 02:01:28 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncUTFNorm (4.73s)
=== RUN   TestSyncImmutable
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:01:30 DEBUG : existing: Need to transfer - File not found at Destination
2024/12/17 02:01:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 02:01:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 02:01:31 DEBUG : existing: md5 = 0c370bbb54b84948e4cec46a22e9acaa OK
2024/12/17 02:01:31 INFO  : existing: Copied (new)
2024/12/17 02:01:31 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:01:32 DEBUG : existing: Sizes differ (src 8 vs dst 6)
2024/12/17 02:01:32 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 02:01:32 ERROR : existing: Source and destination exist but do not match: immutable file modified
2024/12/17 02:01:32 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 02:01:32 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': not deleting files as there were IO errors
2024/12/17 02:01:32 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': not deleting directories as there were IO errors
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncImmutable (5.24s)
=== RUN   TestSyncIgnoreCase
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:01:36 DEBUG : EXISTING: md5 = 2ffb9ef6780b207abab983172dc81ded OK
2024/12/17 02:01:37 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:01:37 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 02:01:37 DEBUG : existing: Unchanged skipping
2024/12/17 02:01:37 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 02:01:37 DEBUG : Waiting for deletions to finish
2024/12/17 02:01:37 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreCase (4.76s)
=== RUN   TestFixCase
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    sync_test.go:2635: Skipping test as local or remote are case-sensitive
--- SKIP: TestFixCase (0.49s)
=== RUN   TestMaxTransfer
=== RUN   TestMaxTransfer/Hard
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", 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-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", 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-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    sync_test.go:2674: This test only runs on local
--- PASS: TestMaxTransfer (1.39s)
    --- SKIP: TestMaxTransfer/Hard (0.45s)
    --- SKIP: TestMaxTransfer/Soft (0.46s)
    --- SKIP: TestMaxTransfer/Cautious (0.48s)
=== RUN   TestSyncConcurrentDelete
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:01:43 DEBUG : both0: md5 = 304cb770cb002c723f75cacb4fca7e87 OK
2024/12/17 02:01:44 DEBUG : only0: md5 = 2228ea7b21044a11673f4a7d5bfa3a8e OK
2024/12/17 02:01:46 DEBUG : both1: md5 = a7fbee241afff93f618fb0f33c5cc30b OK
2024/12/17 02:01:48 DEBUG : only1: md5 = 520ca544a86ca95afe12c38d0cf0c662 OK
2024/12/17 02:01:50 DEBUG : both2: md5 = cd6d4550a43c5655d6c57ca9eeb18abb OK
2024/12/17 02:01:51 DEBUG : only2: md5 = 4dfdf77d7e18109faefc38781a4d5ea1 OK
2024/12/17 02:01:53 DEBUG : both3: md5 = 261cc78d0ffc76a53967a806e5cd1d70 OK
2024/12/17 02:01:55 DEBUG : only3: md5 = 438233df2b4748547e398d37543cf76e OK
2024/12/17 02:01:57 DEBUG : both4: md5 = 1bf39820607bbb606a9ea496300866f8 OK
2024/12/17 02:01:59 DEBUG : only4: md5 = 182c5e414e8a59d8ad46eafb4e74e36e OK
2024/12/17 02:02:00 DEBUG : both5: md5 = 9e9adb2c727de3d18c1a367c3387cb2f OK
2024/12/17 02:02:02 DEBUG : only5: md5 = e1a5e6150d36f2ebd4a8997ac1bebdd7 OK
2024/12/17 02:02:04 DEBUG : both6: md5 = 3bd1054aa75e03a6c011c9cf67fb4b1c OK
2024/12/17 02:02:06 DEBUG : only6: md5 = bce1ecb56455fc3267dab9c0df959c3f OK
2024/12/17 02:02:07 DEBUG : both7: md5 = 4d23f58a56471c3ed44b30354e2a695e OK
2024/12/17 02:02:09 DEBUG : only7: md5 = ff29282255b88d09963dd2ef2dcbc627 OK
2024/12/17 02:02:11 DEBUG : both8: md5 = ea2a73437e9c17c603a9bcb5e2f983ab OK
2024/12/17 02:02:12 DEBUG : only8: md5 = 42ec64dca586f56a9f4e5ba05b0ac344 OK
2024/12/17 02:02:14 DEBUG : both9: md5 = 27b73320b1ebda49b2fda1044442852e OK
2024/12/17 02:02:15 DEBUG : only9: md5 = 093d1bb1c17c7fb4b17db86630ad8afa OK
2024/12/17 02:02:17 DEBUG : both10: md5 = 4f509d3fd71cdbb0149ba319a246f9af OK
2024/12/17 02:02:19 DEBUG : only10: md5 = 581a01c5a9c29075445c56b0e11e2a25 OK
2024/12/17 02:02:21 DEBUG : both11: md5 = 57d942a95f4fe9d0e284f7cd1657af88 OK
2024/12/17 02:02:22 DEBUG : only11: md5 = be66a00b7fc1bd23c1e9bcbec96abb09 OK
2024/12/17 02:02:24 DEBUG : both12: md5 = 431ddfdc7f9b395adb195e7a4575258b OK
2024/12/17 02:02:26 DEBUG : only12: md5 = 7018f84fa3881d029146f951bb173ad1 OK
2024/12/17 02:02:27 DEBUG : both13: md5 = 8387737c6ca1b7128464e2a2d2100661 OK
2024/12/17 02:02:29 DEBUG : only13: md5 = c69d88f7b6e543eb0a8120990ef577de OK
2024/12/17 02:02:31 DEBUG : both14: md5 = b3ef914ab34ed7daeb46374c2ad7e4fa OK
2024/12/17 02:02:33 DEBUG : only14: md5 = 3e366c523c270f969096532a4b765ab6 OK
2024/12/17 02:02:34 DEBUG : both15: md5 = d5e8df3e4ce88983a7200f625700420b OK
2024/12/17 02:02:36 DEBUG : only15: md5 = cfd609876ae6ace0797fbe52cc1fc268 OK
2024/12/17 02:02:38 DEBUG : both16: md5 = c81dae91b9a7dd8b495e96f41be58748 OK
2024/12/17 02:02:39 DEBUG : only16: md5 = e208ba1178974efdf617a9aea55286c7 OK
2024/12/17 02:02:41 DEBUG : both17: md5 = 8753b606ad171a0f14245b0341423ff9 OK
2024/12/17 02:02:43 DEBUG : only17: md5 = 7cd97d4d5e3dfe4dfeb9e9db05b8713a OK
2024/12/17 02:02:44 DEBUG : both18: md5 = db5079bac773ad18f3c49a762f037c01 OK
2024/12/17 02:02:46 DEBUG : only18: md5 = d94c929963cfb55394692c3b2b585c03 OK
2024/12/17 02:02:48 DEBUG : both19: md5 = a4f50bb4cebeb2e62c4058099ae3bbd7 OK
2024/12/17 02:02:49 DEBUG : only19: md5 = 8483c76cfb0a0bce85ae869320384a13 OK
2024/12/17 02:02:50 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 02:02:50 DEBUG : both0: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both12: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both13: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both14: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both15: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both16: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both17: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both18: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both19: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both2: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both3: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both4: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both5: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both6: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both7: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both8: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:02:50 DEBUG : both9: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both10: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both1: Unchanged skipping
2024/12/17 02:02:50 DEBUG : both11: Unchanged skipping
2024/12/17 02:02:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 02:02:50 DEBUG : Waiting for deletions to finish
2024/12/17 02:02:50 INFO  : only7: Deleted
2024/12/17 02:02:50 INFO  : only8: Deleted
2024/12/17 02:02:51 INFO  : only14: Deleted
2024/12/17 02:02:51 INFO  : only9: Deleted
2024/12/17 02:02:51 INFO  : only5: Deleted
2024/12/17 02:02:51 INFO  : only16: Deleted
2024/12/17 02:02:51 INFO  : only2: Deleted
2024/12/17 02:02:51 INFO  : only12: Deleted
2024/12/17 02:02:52 INFO  : only17: Deleted
2024/12/17 02:02:52 INFO  : only18: Deleted
2024/12/17 02:02:52 INFO  : only19: Deleted
2024/12/17 02:02:52 INFO  : only6: Deleted
2024/12/17 02:02:52 INFO  : only0: Deleted
2024/12/17 02:02:52 INFO  : only10: Deleted
2024/12/17 02:02:52 INFO  : only13: Deleted
2024/12/17 02:02:53 INFO  : only15: Deleted
2024/12/17 02:02:53 INFO  : only3: Deleted
2024/12/17 02:02:53 INFO  : only4: Deleted
2024/12/17 02:02:53 INFO  : only1: Deleted
2024/12/17 02:02:53 INFO  : only11: Deleted
2024/12/17 02:02:53 INFO  : There was nothing to transfer
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncConcurrentDelete (86.58s)
=== RUN   TestSyncConcurrentTruncate
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:03:09 DEBUG : both0: md5 = 46c19e84c32383d00913be1b6a52aed4 OK
2024/12/17 02:03:11 DEBUG : only0: md5 = fd1d4407467a9cdefbec6b41403495d5 OK
2024/12/17 02:03:13 DEBUG : both1: md5 = 0b521e0485e8730c0c520be288c3890c OK
2024/12/17 02:03:14 DEBUG : only1: md5 = 72da80317c61a6a73b1389ce00ffe1f3 OK
2024/12/17 02:03:16 DEBUG : both2: md5 = c3c269892059dbe8caa03cc07df34e19 OK
2024/12/17 02:03:18 DEBUG : only2: md5 = 08568116bc49a30916501e0aee16e236 OK
2024/12/17 02:03:19 DEBUG : both3: md5 = 8748cc0d379ef0526ff5ecd60106b796 OK
2024/12/17 02:03:21 DEBUG : only3: md5 = 3a8a6a3567e070b021dfe1fa33061f0f OK
2024/12/17 02:03:23 DEBUG : both4: md5 = 8fa2df4ecbda86cc312cf07d278f18ba OK
2024/12/17 02:03:25 DEBUG : only4: md5 = ee84c84315772dcd95b4fb6a387bf330 OK
2024/12/17 02:03:26 DEBUG : both5: md5 = df0ca84382e89012a0cb5d8b00d41695 OK
2024/12/17 02:03:28 DEBUG : only5: md5 = 2f21be51231b0f7f0ffc28c6a37c5b85 OK
2024/12/17 02:03:30 DEBUG : both6: md5 = fb72511f8f59adc7eaf52c64868926d8 OK
2024/12/17 02:03:31 DEBUG : only6: md5 = 7f063274edf1387949ede79374eb2432 OK
2024/12/17 02:03:33 DEBUG : both7: md5 = d5b7121aefa6ebda80c06eeb47fa0c5d OK
2024/12/17 02:03:35 DEBUG : only7: md5 = 387fa1c5e0930b2e564293d98e5d7db8 OK
2024/12/17 02:03:37 DEBUG : both8: md5 = af86e6739a7a3cdc104e922de7e9ec01 OK
2024/12/17 02:03:38 DEBUG : only8: md5 = 58c01ca2eec75e2cfbb2cc10b2ccb5a7 OK
2024/12/17 02:03:40 DEBUG : both9: md5 = 9ac09e237de3f2595bbf32f15ca29598 OK
2024/12/17 02:03:42 DEBUG : only9: md5 = 48970535f170a1ad5e2a3cfaba82dd80 OK
2024/12/17 02:03:43 DEBUG : both10: md5 = 40a060704a0436c3c01204a571b9fb78 OK
2024/12/17 02:03:45 DEBUG : only10: md5 = 564753b393274ddb9b7f085a0e0c56b9 OK
2024/12/17 02:03:47 DEBUG : both11: md5 = 8fc7d20294a8946f39276c480713c335 OK
2024/12/17 02:03:48 DEBUG : only11: md5 = 8f94c9e49fda494bcb46e70dea641375 OK
2024/12/17 02:03:50 DEBUG : both12: md5 = 25ab53f9b7f9cd915033eb67ca5a0177 OK
2024/12/17 02:03:52 DEBUG : only12: md5 = 5cdc5ac056e0a30df560a5013802322d OK
2024/12/17 02:03:54 DEBUG : both13: md5 = 1b738efa2f3ede7032e455c2d18f7aec OK
2024/12/17 02:03:55 DEBUG : only13: md5 = 4be8aba3092641d4316ba93b1f1a4888 OK
2024/12/17 02:03:57 DEBUG : both14: md5 = 776195658810df3d4acf556cce0b0342 OK
2024/12/17 02:03:59 DEBUG : only14: md5 = 76beb05a432cee59d3477da62e567c4f OK
2024/12/17 02:04:01 DEBUG : both15: md5 = b8eaf5bebb6aedb4a23a0dbb9d41c075 OK
2024/12/17 02:04:02 DEBUG : only15: md5 = 71f382e7f9fc921ec5c04d947804954a OK
2024/12/17 02:04:04 DEBUG : both16: md5 = bad3e91be1080b6594e9292040cb5d32 OK
2024/12/17 02:04:05 DEBUG : only16: md5 = c18d1e3201e0ae73ccb2ffcda4064a42 OK
2024/12/17 02:04:07 DEBUG : both17: md5 = 0cf00b34d987eaccbbcd30f7daca4b59 OK
2024/12/17 02:04:09 DEBUG : only17: md5 = 4756546f8d248f3c2d30d0dde71edd94 OK
2024/12/17 02:04:11 DEBUG : both18: md5 = a44a20c8f96d798f98e31f71b9f8dbcc OK
2024/12/17 02:04:12 DEBUG : only18: md5 = a1c666e0b29791de807308f6244649c5 OK
2024/12/17 02:04:14 DEBUG : both19: md5 = e4c3cb2cc5b253b63ed0e434501e987d OK
2024/12/17 02:04:16 DEBUG : only19: md5 = 4ca2c98bd19b9daf452ce1dc3d315798 OK
2024/12/17 02:04:16 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for checks to finish
2024/12/17 02:04:16 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both0: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both12: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both10: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both14: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both1: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both11: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both16: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both18: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both19: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both15: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both13: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both2: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both5: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both6: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both7: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both3: Unchanged skipping
2024/12/17 02:04:16 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2024/12/17 02:04:16 DEBUG : both4: Unchanged skipping
2024/12/17 02:04:16 DEBUG : only0: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : both17: Unchanged skipping
2024/12/17 02:04:16 DEBUG : only1: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : both8: Unchanged skipping
2024/12/17 02:04:16 DEBUG : only10: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only11: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only12: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only13: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only14: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only15: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only16: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only17: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only18: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only19: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : both9: Unchanged skipping
2024/12/17 02:04:16 DEBUG : only2: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only3: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only4: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only5: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only6: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only7: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only8: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : only9: Sizes differ (src 0 vs dst 6)
2024/12/17 02:04:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Waiting for transfers to finish
2024/12/17 02:04:17 DEBUG : only13: md5 = 0eeec1ac60d3fe038244485d24f7f667 OK
2024/12/17 02:04:17 INFO  : only13: Copied (replaced existing)
2024/12/17 02:04:17 DEBUG : only10: md5 = 00640d5f9fa81c072164eaa293866885 OK
2024/12/17 02:04:17 INFO  : only10: Copied (replaced existing)
2024/12/17 02:04:17 DEBUG : only12: md5 = 3b5de8aa0afdb09a99d321db80c2f1a8 OK
2024/12/17 02:04:17 INFO  : only12: Copied (replaced existing)
2024/12/17 02:04:17 DEBUG : only11: md5 = a25847edb8b2b913d227eb386d5bf392 OK
2024/12/17 02:04:17 INFO  : only11: Copied (replaced existing)
2024/12/17 02:04:19 DEBUG : only15: md5 = e1c5c56efd6f1cc051ae1b120455d2f8 OK
2024/12/17 02:04:19 INFO  : only15: Copied (replaced existing)
2024/12/17 02:04:19 DEBUG : only16: md5 = 31b04c092938d0bd592567da8c2e6fab OK
2024/12/17 02:04:19 INFO  : only16: Copied (replaced existing)
2024/12/17 02:04:19 DEBUG : only14: md5 = 40b8b86281d5f33a8876af15937efd29 OK
2024/12/17 02:04:19 INFO  : only14: Copied (replaced existing)
2024/12/17 02:04:19 DEBUG : only17: md5 = 78fe27dba2bfad8e9469b93b85263ae9 OK
2024/12/17 02:04:19 INFO  : only17: Copied (replaced existing)
2024/12/17 02:04:20 DEBUG : only18: md5 = 824c414aba900f97ccd28e3af6fa75f6 OK
2024/12/17 02:04:20 INFO  : only18: Copied (replaced existing)
2024/12/17 02:04:20 DEBUG : only2: md5 = fa5daa5ec1d155650f8f46e54127c760 OK
2024/12/17 02:04:20 INFO  : only2: Copied (replaced existing)
2024/12/17 02:04:20 DEBUG : only0: md5 = 3c569d2ea622777db8e9dfe5bffeeacd OK
2024/12/17 02:04:20 INFO  : only0: Copied (replaced existing)
2024/12/17 02:04:20 DEBUG : only1: md5 = 4afa562c047859f5ecfef7933e9d74c9 OK
2024/12/17 02:04:20 INFO  : only1: Copied (replaced existing)
2024/12/17 02:04:21 DEBUG : only19: md5 = 954428d3281647a4f5ef6d0efa5417da OK
2024/12/17 02:04:21 INFO  : only19: Copied (replaced existing)
2024/12/17 02:04:21 DEBUG : only4: md5 = f9e18db32ccb7999392bff4e34bbc9c7 OK
2024/12/17 02:04:21 INFO  : only4: Copied (replaced existing)
2024/12/17 02:04:21 DEBUG : only3: md5 = f28a318877206d32eba4a1873a76a63f OK
2024/12/17 02:04:21 INFO  : only3: Copied (replaced existing)
2024/12/17 02:04:22 DEBUG : only7: md5 = b66de60613f7d144bef1cd26a107926e OK
2024/12/17 02:04:22 INFO  : only7: Copied (replaced existing)
2024/12/17 02:04:22 DEBUG : only9: md5 = bf3a9a25016d66dd3b57440c66d40a45 OK
2024/12/17 02:04:22 INFO  : only9: Copied (replaced existing)
2024/12/17 02:04:22 DEBUG : only8: md5 = f05fe22dc4c8d7e660a4fb0bb5282176 OK
2024/12/17 02:04:22 INFO  : only8: Copied (replaced existing)
2024/12/17 02:04:22 DEBUG : only6: md5 = da5d7e58f77e58e3961a6d2ef0825ce4 OK
2024/12/17 02:04:22 INFO  : only6: Copied (replaced existing)
2024/12/17 02:04:23 DEBUG : only5: md5 = 70464c7552d15925e514ad68f7b24793 OK
2024/12/17 02:04:23 INFO  : only5: Copied (replaced existing)
2024/12/17 02:04:23 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
--- PASS: TestSyncConcurrentTruncate (100.17s)
=== RUN   TestNothingToTransferWithEmptyDirs
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:04:48 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/17 02:04:48 DEBUG : sub dir: Making directory with metadata
2024/12/17 02:04:49 INFO  : sub dir: Made directory with metadata (mtime=2011-12-30T12:59:59Z)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:05:16 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2024/12/17 02:05:16 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2024/12/17 02:05:16 INFO  : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/17 02:05:16 INFO  : sub dirEmpty: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:05:30 DEBUG : gcsdkq814sbtppue42dpsbg8ug: Rmdir: contains trashed file: "sjuo65gk5q3r1199jselk9k9o0"
2024/12/17 02:05:31 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/37qp6d1gohacv723ij3brofbu8/i24n0ln37pa2hv8pctddeuh73o: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
2024/12/17 02:05:32 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/37qp6d1gohacv723ij3brofbu8: Rmdir: contains trashed file: "i24n0ln37pa2hv8pctddeuh73o"
2024/12/17 02:05:32 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "37qp6d1gohacv723ij3brofbu8"
2024/12/17 02:05:33 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak"
2024/12/17 02:05:34 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak"
2024/12/17 02:05:35 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak"
2024/12/17 02:05:36 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak"
2024/12/17 02:05:37 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/4r13oncfa2rsrr82oqhsqv24ns: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
2024/12/17 02:05:38 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc: Rmdir: contains trashed file: "4r13oncfa2rsrr82oqhsqv24ns"
2024/12/17 02:05:38 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak"
2024/12/17 02:05:39 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestNothingToTransferWithEmptyDirs (53.14s)
=== RUN   TestNothingToTransferWithoutEmptyDirs
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:05:41 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/17 02:05:41 DEBUG : sub dir: Making directory with metadata
2024/12/17 02:05:43 INFO  : sub dir: Made directory with metadata (mtime=2011-12-30T12:59:59Z)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:06:13 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2024/12/17 02:06:14 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2024/12/17 02:06:14 INFO  : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/17 02:06:14 INFO  : sub dirEmpty: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko'", Local "Local file system at /tmp/rclone2827950342", Modify Window "1ms"
2024/12/17 02:06:26 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/37qp6d1gohacv723ij3brofbu8/i24n0ln37pa2hv8pctddeuh73o: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
2024/12/17 02:06:27 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/37qp6d1gohacv723ij3brofbu8: Rmdir: contains trashed file: "i24n0ln37pa2hv8pctddeuh73o"
2024/12/17 02:06:28 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "37qp6d1gohacv723ij3brofbu8"
2024/12/17 02:06:29 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak"
2024/12/17 02:06:30 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak"
2024/12/17 02:06:31 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak"
2024/12/17 02:06:31 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/jefmjbgpu25gb7gcub4i80nlak: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak"
2024/12/17 02:06:32 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc/4r13oncfa2rsrr82oqhsqv24ns: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
2024/12/17 02:06:33 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc: Rmdir: contains trashed file: "4r13oncfa2rsrr82oqhsqv24ns"
2024/12/17 02:06:33 DEBUG : s0tvtpcbgsp8ufdmedqtvlq3hc: Rmdir: contains trashed file: "jefmjbgpu25gb7gcub4i80nlak"
2024/12/17 02:06:34 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestNothingToTransferWithoutEmptyDirs (55.38s)
PASS
2024/12/17 02:06:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cejugaf3doko': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 18m19.344065058s (try 1/5)