"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5) 2025/06/19 03:35:59 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi" 2025/06/19 03:35:59 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/19 03:36:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi.bin" 2025/06/19 03:36:00 DEBUG : Swift container crypt path rclone-test-becuvap8kusi.bin: Auto set use_segments_container to true 2025/06/19 03:36:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi" 2025/06/19 03:36:06 DEBUG : Swift container crypt path rclone-test-becuvap8kusi: Auto set use_segments_container to true 2025/06/19 03:36:11 DEBUG : Creating backend with remote "/tmp/rclone4272826195" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.05s) === 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 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:36:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:36:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:36:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:36:11 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) --- PASS: TestCopyWithDryRun (0.40s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:36:12 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/19 03:36:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:36:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:36:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:36:17 DEBUG : sub dir/hello world: md5 = c0276e986fbe6940d18d61672ac62cfd OK 2025/06/19 03:36:17 INFO : sub dir/hello world: Copied (new) 2025/06/19 03:36:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (16.04s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.10s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.45s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:36:28 DEBUG : Creating backend with remote "/non-existing" 2025/06/19 03:36:28 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/06/19 03:36:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:36:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.15s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:36:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:36:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:36:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:36:38 DEBUG : sub dir/hello world: md5 = 0353675c0d096e71a69a1d317812d638 OK 2025/06/19 03:36:38 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (25.69s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:36:54 INFO : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Running all checks before starting transfers 2025/06/19 03:36:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:36:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:36:54 INFO : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Checks finished, now starting transfers 2025/06/19 03:36:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:36:59 DEBUG : sub dir/hello world: md5 = 658aac79b1295b01b671b31c9c435d25 OK 2025/06/19 03:36:59 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (10.88s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:37:05 ERROR : Ignoring --no-traverse with sync 2025/06/19 03:37:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:37:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:37:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:37:10 DEBUG : sub dir/hello world: md5 = 9d1d88670e4ca766b81881a0a1c8c98e OK 2025/06/19 03:37:10 INFO : sub dir/hello world: Copied (new) 2025/06/19 03:37:10 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (15.82s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:37:21 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/06/19 03:37:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:37:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:37:21 DEBUG : hello world2: md5 = 2407b7539f6efb0a0a2ddd7b62edb110 OK 2025/06/19 03:37:21 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.27s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:37:22 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 03:37:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:37:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:37:27 DEBUG : potato2: md5 = 522ce312261f77b61bc13c542e167c39 OK 2025/06/19 03:37:27 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (11.05s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:37:38 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 03:37:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:37:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:37:43 DEBUG : potato2: md5 = e2aa50814257007e83a290aefd4bbb2b OK 2025/06/19 03:37:43 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (20.83s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:37:54 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/06/19 03:37:54 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/19 03:37:54 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/06/19 03:37:54 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/19 03:37:54 INFO : sub dir2: Making directory 2025/06/19 03:37:54 INFO : sub dir: Making directory 2025/06/19 03:37:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:37:54 INFO : sub dir2/sub sub dir2: Making directory 2025/06/19 03:37:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:37:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:37:59 DEBUG : sub dir/hello world: md5 = f5ab307d3a5ecfb6aeb0ddabf4f6d144 OK 2025/06/19 03:37:59 INFO : sub dir/hello world: Copied (new) fstest.go:247: Filtering empty directory "sub dir2" fstest.go:247: Filtering empty directory "sub dir2/sub sub dir2" 2025/06/19 03:37:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (10.87s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:38:05 INFO : sub dir2: Making directory 2025/06/19 03:38:05 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/06/19 03:38:05 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/19 03:38:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:38:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:38:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:38:10 DEBUG : sub dir/hello world: md5 = e072dc29e92435a505f1b040df2e7d09 OK 2025/06/19 03:38:10 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (15.89s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:38:21 DEBUG : sub dir2: Making directory with metadata 2025/06/19 03:38:21 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/19 03:38:21 INFO : sub dir2: Making directory 2025/06/19 03:38:21 INFO : sub dir: Making directory 2025/06/19 03:38:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:38:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:38:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:38:26 DEBUG : sub dir/hello world: md5 = bf25672b0ec14f374374cd334be369ef OK 2025/06/19 03:38:26 INFO : sub dir/hello world: Copied (new) 2025/06/19 03:38:26 INFO : sub dir/hello world: Deleted fstest.go:247: Filtering empty directory "sub dir2" 2025/06/19 03:38:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/06/19 03:38:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (15.92s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" sync_test.go:409: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.18s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:38:37 INFO : sub dir2: Making directory 2025/06/19 03:38:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:38:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:38:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:38:42 DEBUG : sub dir/hello world: md5 = 8f80377aeb9a7b9787ebe569766a1a2e OK 2025/06/19 03:38:42 INFO : sub dir/hello world: Copied (new) 2025/06/19 03:38:42 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (30.80s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:39:07 DEBUG : sub dir2: Making directory with metadata 2025/06/19 03:39:07 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/19 03:39:07 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/19 03:39:08 INFO : sub dir2: Making directory 2025/06/19 03:39:08 INFO : sub dir: Making directory 2025/06/19 03:39:08 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:39:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:39:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:39:28 DEBUG : sub dir/hello world: md5 = 1c73dd6941fc090b22451cb2805b701e OK 2025/06/19 03:39:28 INFO : sub dir/hello world: Copied (new) 2025/06/19 03:39:28 DEBUG : Waiting for deletions to finish fstest.go:247: Filtering empty directory "sub dir2" 2025/06/19 03:39:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (40.80s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" sync_test.go:508: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.20s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:39:48 INFO : sub dir2: Making directory 2025/06/19 03:39:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:39:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:39:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:40:09 DEBUG : sub dir/hello world: md5 = abd105685cb5ef4731864c57309e702a OK 2025/06/19 03:40:09 INFO : sub dir/hello world: Copied (new) 2025/06/19 03:40:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (30.72s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:40:24 DEBUG : sub dir/hello world: md5 = 3c7ffcb53733bfdcc53cc1e71135dbcb OK 2025/06/19 03:40:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xaqadam4rada" 2025/06/19 03:40:30 DEBUG : Config file has changed externally - reloading 2025/06/19 03:40:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xaqadam4rada.bin" 2025/06/19 03:40:30 DEBUG : Swift container crypt path rclone-test-xaqadam4rada.bin: Auto set use_segments_container to true 2025/06/19 03:40:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xaqadam4rada" 2025/06/19 03:40:36 DEBUG : Swift container crypt path rclone-test-xaqadam4rada: Auto set use_segments_container to true sync_test.go:591: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi' -> Encrypted drive 'TestCryptSwift:rclone-test-xaqadam4rada' 2025/06/19 03:40:41 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:40:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xaqadam4rada': Waiting for checks to finish 2025/06/19 03:40:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xaqadam4rada': Waiting for transfers to finish 2025/06/19 03:40:52 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/19 03:40:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xaqadam4rada': Purge remote 2025/06/19 03:40:52 DEBUG : Waiting for deletions to finish 2025/06/19 03:40:57 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (48.45s) === RUN TestCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:41:13 DEBUG : sub dir/hello world: md5 = 43725c9d03a16623ae4ca794a28460d1 OK 2025/06/19 03:41:13 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/19 03:41:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:41:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:41:18 DEBUG : sub dir/hello world: md5 = 9a452138cc38964b7517498c153dea44 OK 2025/06/19 03:41:18 INFO : sub dir/hello world: Copied (replaced existing) --- PASS: TestCopyOverSelf (16.33s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:41:29 DEBUG : sub dir/hello world: md5 = 5c3b06218655f5811abf0888e96b0527 OK 2025/06/19 03:41:29 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-jicocep7waba" 2025/06/19 03:41:29 DEBUG : Config file has changed externally - reloading 2025/06/19 03:41:29 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jicocep7waba.bin" 2025/06/19 03:41:30 DEBUG : Swift container crypt path rclone-test-jicocep7waba.bin: Auto set use_segments_container to true 2025/06/19 03:41:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jicocep7waba" 2025/06/19 03:41:35 DEBUG : Swift container crypt path rclone-test-jicocep7waba: Auto set use_segments_container to true sync_test.go:627: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi' -> Encrypted drive 'TestCryptSwift:rclone-test-jicocep7waba' 2025/06/19 03:41:46 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:41:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jicocep7waba': Waiting for checks to finish 2025/06/19 03:41:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jicocep7waba': Waiting for transfers to finish 2025/06/19 03:41:57 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/19 03:42:07 DEBUG : sub dir/hello world: md5 = 1dfb80b0eec7c31f33430956c262c0a9 OK 2025/06/19 03:42:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jicocep7waba': Waiting for checks to finish 2025/06/19 03:42:08 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/19 03:42:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jicocep7waba': Waiting for transfers to finish 2025/06/19 03:42:23 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/19 03:42:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jicocep7waba': Purge remote 2025/06/19 03:42:23 DEBUG : Waiting for deletions to finish 2025/06/19 03:42:33 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopyOverSelf (74.73s) === RUN TestMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:42:44 DEBUG : sub dir/hello world: md5 = 19e5655f3d62d0ca1735110ce249ab42 OK 2025/06/19 03:42:49 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/19 03:42:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:42:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:42:59 DEBUG : sub dir/hello world: md5 = a0c9731ef1e610b2bd20872926dea6ba OK 2025/06/19 03:42:59 INFO : sub dir/hello world: Copied (replaced existing) 2025/06/19 03:42:59 INFO : sub dir/hello world: Deleted --- PASS: TestMoveOverSelf (26.28s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:43:10 DEBUG : sub dir/hello world: md5 = 683e865d111f934c4a899f18f88d447c OK 2025/06/19 03:43:10 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yejinej2moya" 2025/06/19 03:43:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yejinej2moya.bin" 2025/06/19 03:43:11 DEBUG : Swift container crypt path rclone-test-yejinej2moya.bin: Auto set use_segments_container to true 2025/06/19 03:43:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yejinej2moya" 2025/06/19 03:43:16 DEBUG : Swift container crypt path rclone-test-yejinej2moya: Auto set use_segments_container to true sync_test.go:672: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi' -> Encrypted drive 'TestCryptSwift:rclone-test-yejinej2moya' 2025/06/19 03:43:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:43:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yejinej2moya': Waiting for checks to finish 2025/06/19 03:43:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yejinej2moya': Waiting for transfers to finish 2025/06/19 03:43:38 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/19 03:43:43 DEBUG : sub dir/hello world: md5 = 4075ac446a6ff5a3369041e6660dc3e9 OK 2025/06/19 03:43:44 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/19 03:43:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yejinej2moya': Waiting for checks to finish 2025/06/19 03:43:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yejinej2moya': Waiting for transfers to finish 2025/06/19 03:43:54 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/19 03:43:59 INFO : sub dir/hello world: Deleted 2025/06/19 03:44:05 DEBUG : sub dir/hello world: md5 = 277e197aac4427cc926a6f043cfa6eab OK 2025/06/19 03:44:05 DEBUG : testing file moves 2025/06/19 03:44:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yejinej2moya': Waiting for checks to finish 2025/06/19 03:44:05 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/06/19 03:44:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yejinej2moya': Waiting for transfers to finish 2025/06/19 03:44:21 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/19 03:44:26 INFO : sub dir/hello world: Deleted 2025/06/19 03:44:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yejinej2moya': Purge remote 2025/06/19 03:44:31 DEBUG : Waiting for deletions to finish 2025/06/19 03:44:41 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideMoveOverSelf (96.50s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:44:47 DEBUG : sub dir/hello world: md5 = c2fff45893b6d5bb699de27e85c07b0d OK 2025/06/19 03:44:47 ERROR : error listing: directory not found 2025/06/19 03:44:47 INFO : Local file system at /tmp/rclone4272826195: Making directory 2025/06/19 03:44:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:44:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (11.06s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:44:58 DEBUG : sub dir/hello world: md5 = d09e6bc6d18d874cf8399bcb0fae7650 OK 2025/06/19 03:45:03 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:45:03 DEBUG : Local file system at /tmp/rclone4272826195: Waiting for checks to finish 2025/06/19 03:45:03 DEBUG : Local file system at /tmp/rclone4272826195: Waiting for transfers to finish 2025/06/19 03:45:03 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world 2025/06/19 03:45:03 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (15.89s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:45:09 DEBUG : check sum: Need to transfer - File not found at Destination 2025/06/19 03:45:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:45:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:45:09 DEBUG : check sum: md5 = 6cdedab928841350542c0b8ac5c63312 OK 2025/06/19 03:45:09 INFO : check sum: Copied (new) 2025/06/19 03:45:09 DEBUG : Waiting for deletions to finish 2025/06/19 03:45:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:45:09 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/06/19 03:45:09 DEBUG : check sum: Size of src and dst objects identical 2025/06/19 03:45:09 DEBUG : check sum: Unchanged skipping 2025/06/19 03:45:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:45:09 DEBUG : Waiting for deletions to finish 2025/06/19 03:45:09 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.33s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:45:10 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/06/19 03:45:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:45:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:45:16 DEBUG : sizeonly: md5 = db73bedc5d27d24fb982e97030c88c37 OK 2025/06/19 03:45:16 INFO : sizeonly: Copied (new) 2025/06/19 03:45:16 DEBUG : Waiting for deletions to finish 2025/06/19 03:45:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:45:16 DEBUG : sizeonly: Sizes identical 2025/06/19 03:45:16 DEBUG : sizeonly: Unchanged skipping 2025/06/19 03:45:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:45:16 DEBUG : Waiting for deletions to finish 2025/06/19 03:45:16 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (11.99s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:45:22 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/06/19 03:45:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:45:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:45:23 DEBUG : ignore-size: md5 = 340dc55cb40518a36f0cd48001758c2b OK 2025/06/19 03:45:23 INFO : ignore-size: Copied (new) 2025/06/19 03:45:23 DEBUG : Waiting for deletions to finish 2025/06/19 03:45:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:45:23 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:45:23 DEBUG : ignore-size: Unchanged skipping 2025/06/19 03:45:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:45:23 DEBUG : Waiting for deletions to finish 2025/06/19 03:45:23 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (1.73s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:45:29 DEBUG : existing: md5 = 1e12d82517410a52b8a611a5aae337d4 OK 2025/06/19 03:45:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:45:29 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:45:29 DEBUG : existing: Unchanged skipping 2025/06/19 03:45:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:45:29 DEBUG : Waiting for deletions to finish 2025/06/19 03:45:29 INFO : There was nothing to transfer 2025/06/19 03:45:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:45:29 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/06/19 03:45:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:45:40 DEBUG : existing: md5 = 794f8f15e0d51ed30b02157d6ed1d0e6 OK 2025/06/19 03:45:40 INFO : existing: Copied (replaced existing) 2025/06/19 03:45:40 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (26.64s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:45:50 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/19 03:45:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:45:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:45:56 DEBUG : existing: md5 = a568e74aa9e414f332e13947f14e4ac1 OK 2025/06/19 03:45:56 INFO : existing: Copied (new) 2025/06/19 03:45:56 DEBUG : Waiting for deletions to finish 2025/06/19 03:45:56 DEBUG : existing: Destination exists, skipping 2025/06/19 03:45:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:45:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:45:56 DEBUG : Waiting for deletions to finish 2025/06/19 03:45:56 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (21.63s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:46:12 DEBUG : b/potato: md5 = 21ec66e5fe6f390308ab4da61583dfd4 OK 2025/06/19 03:46:13 DEBUG : c/non empty space: md5 = faea0cff6a826e9d57de98cffd1b42d1 OK 2025/06/19 03:46:13 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/06/19 03:46:13 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/19 03:46:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:46:14 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:46:14 DEBUG : c/non empty space: Unchanged skipping 2025/06/19 03:46:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:46:19 DEBUG : a/potato2: md5 = 4603a0dd943ccf09bedbd4998bb525da OK 2025/06/19 03:46:19 INFO : a/potato2: Copied (new) 2025/06/19 03:46:19 DEBUG : Waiting for deletions to finish 2025/06/19 03:46:19 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (23.42s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:46:40 DEBUG : empty space: md5 = bcb69a3de5bea2d2aea839d397743909 OK 2025/06/19 03:46:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:46:51 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/06/19 03:46:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:46:51 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2025/06/19 03:46:51 DEBUG : Waiting for deletions to finish 2025/06/19 03:46:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:46:56 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/06/19 03:46:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:47:01 DEBUG : empty space: md5 = b682d322f8f68f9d885fd26768e8bc1e OK 2025/06/19 03:47:01 INFO : empty space: Copied (replaced existing) 2025/06/19 03:47:01 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (36.64s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" sync_test.go:1017: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.10s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:47:17 DEBUG : foo: md5 = 0ebaed9c1e005fe7e60578d12cc6e13b OK 2025/06/19 03:47:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:47:28 DEBUG : foo: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/06/19 03:47:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:47:33 DEBUG : foo: md5 = 8ec67f9d58c36fe88a94bb7e020eace0 OK 2025/06/19 03:47:33 INFO : foo: Copied (replaced existing) 2025/06/19 03:47:33 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (31.55s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:47:49 DEBUG : empty space: md5 = 59790c0b0a68e6366640d6cbab080f3d OK 2025/06/19 03:47:54 DEBUG : potato: Need to transfer - File not found at Destination 2025/06/19 03:47:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:47:54 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:47:54 DEBUG : empty space: Unchanged skipping 2025/06/19 03:47:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:48:00 DEBUG : potato: md5 = c40f98793ed9902ac1cee6e2e26f4073 OK 2025/06/19 03:48:00 INFO : potato: Copied (new) 2025/06/19 03:48:00 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (31.93s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:48:21 DEBUG : potato: md5 = 40bc7ad77f8bc6884b35e83c5286cc28 OK 2025/06/19 03:48:26 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/06/19 03:48:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:48:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:48:37 DEBUG : potato: md5 = 40ac148540d81ec0e5672579f944bc1e OK 2025/06/19 03:48:37 INFO : potato: Copied (replaced existing) 2025/06/19 03:48:37 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (26.74s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:48:47 DEBUG : potato: md5 = 650393e6b1dd4306d6ef81a016230d92 OK 2025/06/19 03:48:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:48:48 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2025/06/19 03:48:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:48:53 DEBUG : potato: md5 = c0c0195122a51ea477aabb0cce13cd5c OK 2025/06/19 03:48:53 INFO : potato: Copied (replaced existing) 2025/06/19 03:48:53 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (16.66s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:49:04 DEBUG : potato: md5 = 388f11e55fe479418615dc6ab6e0607d OK 2025/06/19 03:49:09 DEBUG : empty space: md5 = 83d1993c44ba475a1f5ac96521b99e51 OK 2025/06/19 03:49:10 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 03:49:10 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/06/19 03:49:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:49:15 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:49:15 DEBUG : empty space: Unchanged skipping 2025/06/19 03:49:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:49:15 DEBUG : Waiting for deletions to finish 2025/06/19 03:49:15 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (31.92s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:49:36 DEBUG : potato: md5 = a28800068b642c25daf623e9449708fa OK 2025/06/19 03:49:41 DEBUG : empty space: md5 = 64d65b47e16c0c8bca5ccac2d09a1b0c OK 2025/06/19 03:49:42 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 03:49:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:49:42 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:49:42 DEBUG : empty space: Unchanged skipping 2025/06/19 03:49:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:49:47 DEBUG : potato2: md5 = f96d8afa13ed69be84ec04978d3a76b7 OK 2025/06/19 03:49:47 INFO : potato2: Copied (new) 2025/06/19 03:49:47 DEBUG : Waiting for deletions to finish 2025/06/19 03:49:57 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (41.85s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:50:13 DEBUG : b/potato: md5 = 0576ecf8b0945693585eff4ea454b020 OK 2025/06/19 03:50:13 DEBUG : c/non empty space: md5 = b2b41f570efa998135e660c570604ca3 OK 2025/06/19 03:50:13 INFO : d: Making directory 2025/06/19 03:50:13 INFO : d/e: Making directory fstest.go:247: Filtering empty directory "d" fstest.go:247: Filtering empty directory "d/e" 2025/06/19 03:50:13 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/19 03:50:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:50:14 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:50:14 DEBUG : c/non empty space: Unchanged skipping 2025/06/19 03:50:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:50:19 DEBUG : a/potato2: md5 = a11f9eb91fb099322b56d701008a7531 OK 2025/06/19 03:50:19 INFO : a/potato2: Copied (new) 2025/06/19 03:50:19 DEBUG : Waiting for deletions to finish 2025/06/19 03:50:19 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (11.93s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:50:25 DEBUG : b/potato: md5 = f0dd0ebebb84fbe088c7b354c46ef04c OK 2025/06/19 03:50:25 DEBUG : c/non empty space: md5 = c2f13d90c37ddec4638be6df9f24d90f OK 2025/06/19 03:50:25 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/06/19 03:50:25 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/19 03:50:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:50:25 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:50:25 DEBUG : c/non empty space: Unchanged skipping 2025/06/19 03:50:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:50:30 DEBUG : a/potato2: md5 = 6dac8df656ca3538bba527c1c0a67b34 OK 2025/06/19 03:50:30 INFO : a/potato2: Copied (new) 2025/06/19 03:50:30 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': not deleting files as there were IO errors 2025/06/19 03:50:30 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': not deleting directories as there were IO errors fstest.go:247: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (17.22s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:50:47 DEBUG : potato: md5 = 9b5d03d90928ceef93de7c9e743a26ec OK 2025/06/19 03:50:52 DEBUG : empty space: md5 = 3deaddfd894d321953808740e65b8eda OK 2025/06/19 03:50:52 DEBUG : Waiting for deletions to finish 2025/06/19 03:50:52 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 03:50:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:50:52 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:50:52 DEBUG : empty space: Unchanged skipping 2025/06/19 03:50:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:50:58 INFO : potato: Deleted 2025/06/19 03:50:58 DEBUG : potato2: md5 = 37508f6f00eed02e337e921824a3d159 OK 2025/06/19 03:50:58 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (31.65s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:51:19 DEBUG : potato: md5 = 911eec42412efecc9ccacf0826a833f5 OK 2025/06/19 03:51:24 DEBUG : empty space: md5 = 37e594fdb1bb5773a988ea589cb057cc OK 2025/06/19 03:51:24 DEBUG : Waiting for deletions to finish 2025/06/19 03:51:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:51:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:51:29 INFO : potato: Deleted 2025/06/19 03:51:30 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 03:51:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:51:30 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:51:30 DEBUG : empty space: Unchanged skipping 2025/06/19 03:51:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:51:35 DEBUG : potato2: md5 = a3ac32e3083862cab7aa7d91f8ceb373 OK 2025/06/19 03:51:35 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (41.96s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:52:01 DEBUG : potato: md5 = 659116b9588e76cb5545e19cd0737005 OK 2025/06/19 03:52:01 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 03:52:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:52:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:52:06 DEBUG : potato2: md5 = c69be2771c6e4a10e930804843938719 OK 2025/06/19 03:52:06 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (26.51s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:52:27 DEBUG : potato2: md5 = ff96855574809db91a1f28231d69dbe2 OK 2025/06/19 03:52:32 DEBUG : empty space: md5 = 4deac38259fbe9df19f353dbb9daf7b4 OK 2025/06/19 03:52:37 DEBUG : enormous: Excluded (Size Filter) 2025/06/19 03:52:37 DEBUG : enormous: Excluded 2025/06/19 03:52:37 DEBUG : potato2: Excluded (Size Filter) 2025/06/19 03:52:37 DEBUG : potato2: Excluded 2025/06/19 03:52:38 DEBUG : potato2: Excluded (Size Filter) 2025/06/19 03:52:38 DEBUG : potato2: Excluded 2025/06/19 03:52:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:52:38 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:52:38 DEBUG : empty space: Unchanged skipping 2025/06/19 03:52:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:52:38 DEBUG : Waiting for deletions to finish 2025/06/19 03:52:38 INFO : There was nothing to transfer 2025/06/19 03:52:43 DEBUG : enormous: Excluded (Size Filter) 2025/06/19 03:52:43 DEBUG : enormous: Excluded 2025/06/19 03:52:43 DEBUG : potato2: Excluded (Size Filter) 2025/06/19 03:52:43 DEBUG : potato2: Excluded 2025/06/19 03:52:43 DEBUG : potato2: Excluded (Size Filter) 2025/06/19 03:52:43 DEBUG : potato2: Excluded 2025/06/19 03:52:43 DEBUG : Local file system at /tmp/rclone4272826195: Waiting for checks to finish 2025/06/19 03:52:43 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:52:43 DEBUG : empty space: Unchanged skipping 2025/06/19 03:52:43 DEBUG : Local file system at /tmp/rclone4272826195: Waiting for transfers to finish 2025/06/19 03:52:43 DEBUG : Waiting for deletions to finish 2025/06/19 03:52:43 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (36.54s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:53:04 DEBUG : potato2: md5 = 22edb84c49b7330ae002109efcb1bf59 OK 2025/06/19 03:53:09 DEBUG : empty space: md5 = 9f37dbc4ccdd63b7eebea4d4b83de15e OK 2025/06/19 03:53:14 DEBUG : enormous: md5 = 1c8d7e21ecea2edcfb3cceb5b31c5b0b OK 2025/06/19 03:53:14 DEBUG : enormous: Excluded (Size Filter) 2025/06/19 03:53:14 DEBUG : enormous: Excluded 2025/06/19 03:53:14 DEBUG : potato2: Excluded (Size Filter) 2025/06/19 03:53:14 DEBUG : potato2: Excluded 2025/06/19 03:53:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:53:19 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:53:19 DEBUG : empty space: Unchanged skipping 2025/06/19 03:53:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:53:19 DEBUG : Waiting for deletions to finish 2025/06/19 03:53:25 INFO : enormous: Deleted 2025/06/19 03:53:25 INFO : potato2: Deleted 2025/06/19 03:53:25 INFO : There was nothing to transfer 2025/06/19 03:53:25 DEBUG : Local file system at /tmp/rclone4272826195: Waiting for checks to finish 2025/06/19 03:53:25 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:53:25 DEBUG : empty space: Unchanged skipping 2025/06/19 03:53:25 DEBUG : Local file system at /tmp/rclone4272826195: Waiting for transfers to finish 2025/06/19 03:53:25 DEBUG : Waiting for deletions to finish 2025/06/19 03:53:25 INFO : enormous: Deleted 2025/06/19 03:53:25 INFO : potato2: Deleted 2025/06/19 03:53:25 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (31.98s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:53:31 DEBUG : one: md5 = 9b66e47159ae50e046bf470aa1b33c8b OK 2025/06/19 03:53:36 DEBUG : two: md5 = 5bef7f5156aaaeb2c2b7ecd39727fea9 OK 2025/06/19 03:53:36 DEBUG : three: md5 = 9ebaa75efc3c7ea5546f8d834ee57eca OK 2025/06/19 03:53:37 DEBUG : four: md5 = ae6780c7fc62fe057b126f5a108dd6e0 OK 2025/06/19 03:53:37 DEBUG : five: Need to transfer - File not found at Destination 2025/06/19 03:53:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:53:37 DEBUG : one: Destination is newer than source, skipping 2025/06/19 03:53:37 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/06/19 03:53:37 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/06/19 03:53:37 DEBUG : three: Sizes identical 2025/06/19 03:53:37 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/06/19 03:53:38 DEBUG : four: md5 = 230b8c47e7244841bc896b7d70b7b643 OK 2025/06/19 03:53:38 INFO : four: Copied (replaced existing) 2025/06/19 03:53:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:53:43 DEBUG : five: md5 = 4b6e119ffc81121a391e13769b8a1ec2 OK 2025/06/19 03:53:43 INFO : five: Copied (new) 2025/06/19 03:53:48 DEBUG : two: md5 = 39047256675e5003addca8448dc04f7a OK 2025/06/19 03:53:48 INFO : two: Copied (replaced existing) 2025/06/19 03:53:48 DEBUG : Waiting for deletions to finish sync_test.go:1450: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (28.64s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1467: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1467: 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 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" sync_test.go:1539: Can track renames: false 2025/06/19 03:53:59 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Ignoring --track-renames as the source and destination do not have a common hash 2025/06/19 03:53:59 DEBUG : potato: Need to transfer - File not found at Destination 2025/06/19 03:53:59 DEBUG : yam: Need to transfer - File not found at Destination 2025/06/19 03:53:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:53:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:54:00 DEBUG : yam: md5 = ef4bbf4f9a195a57979d9563c11cebc6 OK 2025/06/19 03:54:00 INFO : yam: Copied (new) 2025/06/19 03:54:04 DEBUG : potato: md5 = bd15f8296a4b46e1dc2c2222def9fe59 OK 2025/06/19 03:54:04 INFO : potato: Copied (new) 2025/06/19 03:54:04 DEBUG : Waiting for deletions to finish 2025/06/19 03:54:10 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Ignoring --track-renames as the source and destination do not have a common hash 2025/06/19 03:54:10 DEBUG : yaml: Need to transfer - File not found at Destination 2025/06/19 03:54:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:54:10 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:54:10 DEBUG : potato: Unchanged skipping 2025/06/19 03:54:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:54:15 DEBUG : yaml: md5 = 54cc71e0dfee33cdffcb828f01b94388 OK 2025/06/19 03:54:15 INFO : yaml: Copied (new) 2025/06/19 03:54:15 DEBUG : Waiting for deletions to finish 2025/06/19 03:54:15 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (36.85s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" sync_test.go:1611: Can track renames: true 2025/06/19 03:54:36 INFO : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Making map for --track-renames 2025/06/19 03:54:36 INFO : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Finished making map for --track-renames 2025/06/19 03:54:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:54:36 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/19 03:54:36 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/06/19 03:54:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for renames to finish 2025/06/19 03:54:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:54:36 DEBUG : yam: md5 = 3b40e7b28182b5810812d2b4384e8ca7 OK 2025/06/19 03:54:36 INFO : yam: Copied (new) 2025/06/19 03:54:41 DEBUG : potato: md5 = e94816cc9d53e80773d33154fd10a8f5 OK 2025/06/19 03:54:41 INFO : potato: Copied (new) 2025/06/19 03:54:41 DEBUG : Waiting for deletions to finish 2025/06/19 03:54:42 INFO : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Making map for --track-renames 2025/06/19 03:54:42 INFO : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Finished making map for --track-renames 2025/06/19 03:54:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:54:42 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:54:42 DEBUG : potato: Unchanged skipping 2025/06/19 03:54:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for renames to finish 2025/06/19 03:54:57 INFO : yam: Copied (server-side copy) to: yaml 2025/06/19 03:54:57 INFO : yam: Deleted 2025/06/19 03:54:57 INFO : yaml: Renamed from "yam" 2025/06/19 03:54:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:54:57 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (42.19s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" sync_test.go:1650: Can track renames: true 2025/06/19 03:55:18 INFO : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Making map for --track-renames 2025/06/19 03:55:18 INFO : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Finished making map for --track-renames 2025/06/19 03:55:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:55:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for renames to finish 2025/06/19 03:55:18 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/19 03:55:18 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/06/19 03:55:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:55:23 DEBUG : potato: md5 = 9325714db28e3a1a328c6b2228d9149e OK 2025/06/19 03:55:23 INFO : potato: Copied (new) 2025/06/19 03:55:23 DEBUG : sub/yam: md5 = 69841a0018177d66c42ad3bfabccfe0b OK 2025/06/19 03:55:23 INFO : sub/yam: Copied (new) 2025/06/19 03:55:23 DEBUG : Waiting for deletions to finish 2025/06/19 03:55:29 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:55:29 DEBUG : potato: Unchanged skipping 2025/06/19 03:55:29 INFO : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Making map for --track-renames 2025/06/19 03:55:29 INFO : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Finished making map for --track-renames 2025/06/19 03:55:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:55:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for renames to finish 2025/06/19 03:55:39 INFO : sub/yam: Copied (server-side copy) to: yam 2025/06/19 03:55:44 INFO : sub/yam: Deleted 2025/06/19 03:55:44 INFO : yam: Renamed from "sub/yam" 2025/06/19 03:55:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:55:44 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (32.09s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:55:50 DEBUG : Creating backend with remote "/tmp/rclone4272826195/dir1" 2025/06/19 03:55:50 DEBUG : Config file has changed externally - reloading 2025/06/19 03:55:50 DEBUG : Creating backend with remote "/tmp/rclone4272826195/dir2" 2025/06/19 03:55:50 DEBUG : Local file system at /tmp/rclone4272826195/dir2: Using server-side directory move 2025/06/19 03:55:50 INFO : Local file system at /tmp/rclone4272826195/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/06/19 03:55:50 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/06/19 03:55:50 DEBUG : Local file system at /tmp/rclone4272826195/dir2: Waiting for checks to finish 2025/06/19 03:55:50 DEBUG : Local file system at /tmp/rclone4272826195/dir2: Waiting for transfers to finish 2025/06/19 03:55:50 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (0.61s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:55:51 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:55:51 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/06/19 03:55:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:55:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:55:51 DEBUG : nested/sub dir/file: md5 = ab30ce29785f0259545898f0351bd1bb OK 2025/06/19 03:55:51 INFO : nested/sub dir/file: Copied (new) 2025/06/19 03:55:51 INFO : nested/sub dir/file: Deleted 2025/06/19 03:55:56 DEBUG : sub dir/hello world: md5 = dbad312c11853739bb34850136c60f15 OK 2025/06/19 03:55:56 INFO : sub dir/hello world: Copied (new) 2025/06/19 03:55:56 INFO : sub dir/hello world: Deleted 2025/06/19 03:55:56 INFO : sub dir: Removing directory 2025/06/19 03:55:56 INFO : nested/sub dir: Removing directory 2025/06/19 03:55:56 INFO : nested: Removing directory 2025/06/19 03:55:56 DEBUG : Local file system at /tmp/rclone4272826195: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (11.08s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:56:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 03:56:02 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/06/19 03:56:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:56:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:56:02 DEBUG : nested/sub dir/file: md5 = fc84581cd1333ad2f1fc3d4cb77fd6d8 OK 2025/06/19 03:56:02 INFO : nested/sub dir/file: Copied (new) 2025/06/19 03:56:02 INFO : nested/sub dir/file: Deleted 2025/06/19 03:56:07 DEBUG : sub dir/hello world: md5 = d9cf05e8594a4acaed60460f93335207 OK 2025/06/19 03:56:07 INFO : sub dir/hello world: Copied (new) 2025/06/19 03:56:07 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (16.16s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:56:18 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/06/19 03:56:18 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/19 03:56:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:56:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:56:19 DEBUG : existing-b: md5 = 06270adc93a128559048a87dabee3310 OK 2025/06/19 03:56:19 INFO : existing-b: Copied (new) 2025/06/19 03:56:19 INFO : existing-b: Deleted 2025/06/19 03:56:23 DEBUG : existing: md5 = d1989d2dcc5dda1a77ead6691e46f8b4 OK 2025/06/19 03:56:23 INFO : existing: Copied (new) 2025/06/19 03:56:23 INFO : existing: Deleted 2025/06/19 03:56:29 DEBUG : existing: Destination exists, skipping 2025/06/19 03:56:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 03:56:34 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/06/19 03:56:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 03:56:34 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (31.35s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:56:49 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-zugoceb2nare" 2025/06/19 03:56:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zugoceb2nare.bin" 2025/06/19 03:56:50 DEBUG : Swift container crypt path rclone-test-zugoceb2nare.bin: Auto set use_segments_container to true 2025/06/19 03:56:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zugoceb2nare" 2025/06/19 03:56:55 DEBUG : Swift container crypt path rclone-test-zugoceb2nare: Auto set use_segments_container to true 2025/06/19 03:57:06 DEBUG : potato2: md5 = fa42147ef0e00348e65dd861efaba558 OK 2025/06/19 03:57:11 DEBUG : empty space: md5 = d45c87f03249b8e37bfb10eedc086850 OK 2025/06/19 03:57:12 DEBUG : potato3: md5 = 65eff928486902c72064e95073ab88e7 OK sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi' -> Encrypted drive 'TestCryptSwift:rclone-test-zugoceb2nare' 2025/06/19 03:57:13 DEBUG : empty space: md5 = 50d3e1d94ec9e02c85ebff9e50313ac4 OK 2025/06/19 03:57:13 DEBUG : potato3: md5 = 4c282ae4e52a4648da75129bd762b34d OK 2025/06/19 03:57:13 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 03:57:13 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/19 03:57:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zugoceb2nare': Waiting for checks to finish 2025/06/19 03:57:13 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 03:57:13 DEBUG : empty space: Unchanged skipping 2025/06/19 03:57:19 INFO : empty space: Deleted 2025/06/19 03:57:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zugoceb2nare': Waiting for transfers to finish 2025/06/19 03:57:19 INFO : potato3: Copied (server-side copy) 2025/06/19 03:57:19 INFO : potato3: Deleted 2025/06/19 03:57:19 INFO : potato2: Copied (server-side copy) 2025/06/19 03:57:24 INFO : potato2: Deleted 2025/06/19 03:57:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-jacolev6wetu" 2025/06/19 03:57:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jacolev6wetu.bin" 2025/06/19 03:57:25 DEBUG : Swift container crypt path rclone-test-jacolev6wetu.bin: Auto set use_segments_container to true 2025/06/19 03:57:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jacolev6wetu" 2025/06/19 03:57:31 DEBUG : Swift container crypt path rclone-test-jacolev6wetu: Auto set use_segments_container to true 2025/06/19 03:57:36 DEBUG : empty space: Need to transfer - File not found at Destination 2025/06/19 03:57:36 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 03:57:36 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/19 03:57:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jacolev6wetu': Waiting for checks to finish 2025/06/19 03:57:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jacolev6wetu': Waiting for transfers to finish 2025/06/19 03:57:47 INFO : potato2: Copied (server-side copy) 2025/06/19 03:57:47 INFO : potato2: Deleted 2025/06/19 03:57:52 INFO : potato3: Copied (server-side copy) 2025/06/19 03:57:52 INFO : empty space: Copied (server-side copy) 2025/06/19 03:57:52 INFO : potato3: Deleted 2025/06/19 03:57:52 INFO : empty space: Deleted 2025/06/19 03:58:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jacolev6wetu': Purge remote 2025/06/19 03:58:02 DEBUG : Waiting for deletions to finish 2025/06/19 03:58:07 INFO : empty space.bin: Deleted 2025/06/19 03:58:12 INFO : potato2.bin: Deleted 2025/06/19 03:58:12 INFO : potato3.bin: Deleted 2025/06/19 03:58:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zugoceb2nare': Purge remote 2025/06/19 03:58:12 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (83.65s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:58:13 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lowujih5pupe" 2025/06/19 03:58:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lowujih5pupe.bin" 2025/06/19 03:58:13 DEBUG : Swift container crypt path rclone-test-lowujih5pupe.bin: Auto set use_segments_container to true 2025/06/19 03:58:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lowujih5pupe" 2025/06/19 03:58:19 DEBUG : Swift container crypt path rclone-test-lowujih5pupe: Auto set use_segments_container to true 2025/06/19 03:58:30 DEBUG : potato2: md5 = ddea15ca1b7a727725de8e92a2abdfd9 OK 2025/06/19 03:58:35 DEBUG : empty space: md5 = c419b352b69db06b02d7ade1341861e2 OK 2025/06/19 03:58:35 DEBUG : potato3: md5 = eab67eddaf60fbca347bfc1a26d8a56d OK sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi' -> Encrypted drive 'TestCryptSwift:rclone-test-lowujih5pupe' 2025/06/19 03:58:36 DEBUG : empty space: md5 = d50e05196c60b2e96cc876233457be66 OK 2025/06/19 03:58:37 DEBUG : potato3: md5 = 20ca6baa1fd3e87643df45c58e68d683 OK 2025/06/19 03:58:37 DEBUG : empty space: Excluded (Size Filter) 2025/06/19 03:58:37 DEBUG : empty space: Excluded 2025/06/19 03:58:37 DEBUG : empty space: Excluded (Size Filter) 2025/06/19 03:58:37 DEBUG : empty space: Excluded 2025/06/19 03:58:37 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 03:58:37 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/19 03:58:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lowujih5pupe': Waiting for checks to finish 2025/06/19 03:58:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lowujih5pupe': Waiting for transfers to finish 2025/06/19 03:58:43 INFO : potato3: Copied (server-side copy) 2025/06/19 03:58:43 INFO : potato3: Deleted 2025/06/19 03:58:53 INFO : potato2: Copied (server-side copy) 2025/06/19 03:58:58 INFO : potato2: Deleted 2025/06/19 03:59:08 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-weriked9muko" 2025/06/19 03:59:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-weriked9muko.bin" 2025/06/19 03:59:09 DEBUG : Swift container crypt path rclone-test-weriked9muko.bin: Auto set use_segments_container to true 2025/06/19 03:59:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-weriked9muko" 2025/06/19 03:59:14 DEBUG : Swift container crypt path rclone-test-weriked9muko: Auto set use_segments_container to true 2025/06/19 03:59:20 DEBUG : empty space: Excluded (Size Filter) 2025/06/19 03:59:20 DEBUG : empty space: Excluded 2025/06/19 03:59:20 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 03:59:20 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/19 03:59:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-weriked9muko': Waiting for checks to finish 2025/06/19 03:59:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-weriked9muko': Waiting for transfers to finish 2025/06/19 03:59:25 INFO : potato2: Copied (server-side copy) 2025/06/19 03:59:26 INFO : potato3: Copied (server-side copy) 2025/06/19 03:59:26 INFO : potato3: Deleted 2025/06/19 03:59:30 INFO : potato2: Deleted 2025/06/19 03:59:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-weriked9muko': Purge remote 2025/06/19 03:59:31 DEBUG : Waiting for deletions to finish 2025/06/19 03:59:31 INFO : potato2.bin: Deleted 2025/06/19 03:59:31 INFO : potato3.bin: Deleted 2025/06/19 03:59:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lowujih5pupe': Purge remote 2025/06/19 03:59:31 DEBUG : Waiting for deletions to finish 2025/06/19 03:59:32 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (84.05s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 03:59:37 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-cogaxig5muba" 2025/06/19 03:59:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-cogaxig5muba.bin" 2025/06/19 03:59:37 DEBUG : Swift container crypt path rclone-test-cogaxig5muba.bin: Auto set use_segments_container to true 2025/06/19 03:59:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-cogaxig5muba" 2025/06/19 03:59:43 DEBUG : Swift container crypt path rclone-test-cogaxig5muba: Auto set use_segments_container to true 2025/06/19 03:59:54 DEBUG : potato2: md5 = 1a275b0ffd987b439dccccd2984aca78 OK 2025/06/19 03:59:59 DEBUG : empty space: md5 = a3ac9e34d2be7adc864ff9a3c5a2223b OK 2025/06/19 03:59:59 DEBUG : potato3: md5 = e1fc58b1cce48c4a5f4ffca7f9623418 OK 2025/06/19 03:59:59 INFO : tomatoDir: Making directory sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi' -> Encrypted drive 'TestCryptSwift:rclone-test-cogaxig5muba' 2025/06/19 04:00:10 DEBUG : empty space: md5 = 14a079e10b9c06c779de93481f25831a OK 2025/06/19 04:00:11 DEBUG : potato3: md5 = e007403a53529a3ce9d2c49640a8d068 OK 2025/06/19 04:00:11 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:00:11 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/19 04:00:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cogaxig5muba': Waiting for checks to finish 2025/06/19 04:00:16 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:00:16 DEBUG : empty space: Unchanged skipping 2025/06/19 04:00:17 INFO : potato3: Copied (server-side copy) 2025/06/19 04:00:17 INFO : potato3: Deleted 2025/06/19 04:00:21 INFO : empty space: Deleted 2025/06/19 04:00:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cogaxig5muba': Waiting for transfers to finish 2025/06/19 04:00:22 INFO : potato2: Copied (server-side copy) 2025/06/19 04:00:27 INFO : potato2: Deleted 2025/06/19 04:00:33 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-vequwud4yize" 2025/06/19 04:00:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vequwud4yize.bin" 2025/06/19 04:00:33 DEBUG : Swift container crypt path rclone-test-vequwud4yize.bin: Auto set use_segments_container to true 2025/06/19 04:00:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vequwud4yize" 2025/06/19 04:00:39 DEBUG : Swift container crypt path rclone-test-vequwud4yize: Auto set use_segments_container to true 2025/06/19 04:00:44 INFO : tomatoDir: Making directory 2025/06/19 04:00:44 DEBUG : empty space: Need to transfer - File not found at Destination 2025/06/19 04:00:44 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:00:44 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/19 04:00:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vequwud4yize': Waiting for checks to finish 2025/06/19 04:00:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vequwud4yize': Waiting for transfers to finish 2025/06/19 04:00:59 INFO : potato2: Copied (server-side copy) 2025/06/19 04:01:00 INFO : empty space: Copied (server-side copy) 2025/06/19 04:01:00 INFO : potato3: Copied (server-side copy) 2025/06/19 04:01:00 INFO : potato3: Deleted 2025/06/19 04:01:05 INFO : empty space: Deleted 2025/06/19 04:01:05 INFO : potato2: Deleted 2025/06/19 04:01:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vequwud4yize': Purge remote 2025/06/19 04:01:10 DEBUG : Waiting for deletions to finish 2025/06/19 04:01:15 INFO : empty space.bin: Deleted 2025/06/19 04:01:20 INFO : potato3.bin: Deleted 2025/06/19 04:01:20 INFO : potato2.bin: Deleted 2025/06/19 04:01:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cogaxig5muba': Purge remote 2025/06/19 04:01:20 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (103.57s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:01:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/rclone-move-test" 2025/06/19 04:01:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/rclone-move-test.bin" 2025/06/19 04:01:21 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/rclone-move-test.bin: Auto set use_segments_container to true 2025/06/19 04:01:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/rclone-move-test" 2025/06/19 04:01:27 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/rclone-move-test: Auto set use_segments_container to true 2025/06/19 04:01:38 DEBUG : potato2: md5 = b82b4d49cb31f252fcf8db37304b3714 OK --- PASS: TestServerSideMoveOverlap (27.54s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:01:48 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/rclone-sync-test" 2025/06/19 04:01:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/rclone-sync-test.bin" 2025/06/19 04:01:49 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/rclone-sync-test.bin: Auto set use_segments_container to true 2025/06/19 04:01:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/rclone-sync-test" 2025/06/19 04:01:54 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/rclone-sync-test: Auto set use_segments_container to true --- PASS: TestSyncOverlap (11.81s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:02:00 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/rclone-sync-test" 2025/06/19 04:02:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/rclone-sync-test.bin" 2025/06/19 04:02:00 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/rclone-sync-test.bin: Auto set use_segments_container to true 2025/06/19 04:02:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/rclone-sync-test" 2025/06/19 04:02:06 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/rclone-sync-test: Auto set use_segments_container to true 2025/06/19 04:02:11 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/rclone-sync-test-include/layer2" 2025/06/19 04:02:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/rclone-sync-test-include/layer2.bin" 2025/06/19 04:02:12 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/rclone-sync-test-include/layer2.bin: Auto set use_segments_container to true 2025/06/19 04:02:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/rclone-sync-test-include/layer2" 2025/06/19 04:02:17 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/rclone-sync-test-include/layer2: Auto set use_segments_container to true 2025/06/19 04:02:23 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/rclone-sync-test-ignore-file" 2025/06/19 04:02:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/rclone-sync-test-ignore-file.bin" 2025/06/19 04:02:23 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/rclone-sync-test-ignore-file.bin: Auto set use_segments_container to true 2025/06/19 04:02:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/rclone-sync-test-ignore-file" 2025/06/19 04:02:29 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/rclone-sync-test-ignore-file: Auto set use_segments_container to true 2025/06/19 04:02:35 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = d1267c6a8532f08ac3a324c5f2798ee8 OK 2025/06/19 04:02:40 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/19 04:02:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/rclone-sync-test': Waiting for checks to finish 2025/06/19 04:02:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/rclone-sync-test': Waiting for transfers to finish 2025/06/19 04:02:40 DEBUG : Waiting for deletions to finish 2025/06/19 04:02:40 INFO : There was nothing to transfer 2025/06/19 04:02:45 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/19 04:02:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 04:02:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 04:02:45 DEBUG : Waiting for deletions to finish 2025/06/19 04:02:45 INFO : There was nothing to transfer 2025/06/19 04:02:51 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/19 04:02:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/06/19 04:02:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/06/19 04:02:51 DEBUG : Waiting for deletions to finish 2025/06/19 04:02:51 INFO : There was nothing to transfer 2025/06/19 04:02:56 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/19 04:02:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 04:02:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 04:02:56 DEBUG : Waiting for deletions to finish 2025/06/19 04:02:56 INFO : There was nothing to transfer 2025/06/19 04:02:56 DEBUG : Excluded 2025/06/19 04:02:56 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/19 04:02:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/06/19 04:02:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/06/19 04:02:56 DEBUG : Waiting for deletions to finish 2025/06/19 04:02:56 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (56.69s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:02:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/dst" 2025/06/19 04:02:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst.bin" 2025/06/19 04:02:57 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst.bin: Auto set use_segments_container to true 2025/06/19 04:03:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst" 2025/06/19 04:03:03 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst: Auto set use_segments_container to true 2025/06/19 04:03:08 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/CompareDest" 2025/06/19 04:03:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/CompareDest.bin" 2025/06/19 04:03:08 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/CompareDest.bin: Auto set use_segments_container to true 2025/06/19 04:03:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/CompareDest" 2025/06/19 04:03:14 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/CompareDest: Auto set use_segments_container to true 2025/06/19 04:03:29 DEBUG : one: Need to transfer - File not found at Destination 2025/06/19 04:03:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:03:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:03:30 DEBUG : one: md5 = fb66526740e3afbb43b1deb2d915f031 OK 2025/06/19 04:03:30 INFO : one: Copied (new) 2025/06/19 04:03:30 DEBUG : Waiting for deletions to finish 2025/06/19 04:03:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:03:30 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 04:03:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:03:36 DEBUG : one: md5 = 8cb571251094a9738b196ae0007011fb OK 2025/06/19 04:03:36 INFO : one: Copied (replaced existing) 2025/06/19 04:03:36 DEBUG : Waiting for deletions to finish 2025/06/19 04:03:36 DEBUG : dst/one: md5 = ed8f6b53980b216b4b5413f2fa5fce97 OK 2025/06/19 04:03:37 DEBUG : CompareDest/one: md5 = d6776ce8f9cbfa60f140f7ad87762efa OK 2025/06/19 04:03:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:03:37 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 04:03:37 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:03:37 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/19 04:03:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:03:37 DEBUG : Waiting for deletions to finish 2025/06/19 04:03:37 INFO : There was nothing to transfer 2025/06/19 04:03:42 DEBUG : CompareDest/two: md5 = 0f4aa9d02009d8311b815c669e21a4c7 OK 2025/06/19 04:03:48 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 04:03:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:03:48 DEBUG : two: Destination found in --compare-dest, skipping 2025/06/19 04:03:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:03:48 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:03:48 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/19 04:03:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:03:48 DEBUG : Waiting for deletions to finish 2025/06/19 04:03:48 INFO : There was nothing to transfer 2025/06/19 04:03:48 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 04:03:48 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:03:48 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/19 04:03:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:03:48 DEBUG : two: Destination found in --compare-dest, skipping 2025/06/19 04:03:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:03:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:03:48 DEBUG : Waiting for deletions to finish 2025/06/19 04:03:48 INFO : There was nothing to transfer sync_test.go:2176: No hash on uploaded file so skipping compare timestamp test 2025/06/19 04:03:49 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 04:03:49 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:03:49 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/19 04:03:54 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/06/19 04:03:54 DEBUG : two: Need to transfer - File not found at Destination 2025/06/19 04:03:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:03:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:03:54 DEBUG : two: md5 = e69ba7092f5ea00b9d1f99fff9269c9a OK 2025/06/19 04:03:54 INFO : two: Copied (new) 2025/06/19 04:03:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (83.75s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:04:21 DEBUG : pre-dest1/1: md5 = 30a70533b3610e2a44bdd20cf3db18b5 OK 2025/06/19 04:04:41 DEBUG : pre-dest2/2: md5 = 730d0b53e6a5c2660e644bd40e5d17d7 OK 2025/06/19 04:05:01 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/dest" 2025/06/19 04:05:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dest.bin" 2025/06/19 04:05:02 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dest.bin: Auto set use_segments_container to true 2025/06/19 04:05:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dest" 2025/06/19 04:05:07 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dest: Auto set use_segments_container to true 2025/06/19 04:05:13 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/pre-dest1" 2025/06/19 04:05:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/pre-dest1.bin" 2025/06/19 04:05:13 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/pre-dest1.bin: Auto set use_segments_container to true 2025/06/19 04:05:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/pre-dest1" 2025/06/19 04:05:19 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/pre-dest1: Auto set use_segments_container to true 2025/06/19 04:05:39 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/pre-dest2" 2025/06/19 04:05:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/pre-dest2.bin" 2025/06/19 04:05:39 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/pre-dest2.bin: Auto set use_segments_container to true 2025/06/19 04:06:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/pre-dest2" 2025/06/19 04:06:00 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/pre-dest2: Auto set use_segments_container to true 2025/06/19 04:06:06 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:06:06 DEBUG : 1: Destination found in --compare-dest, skipping 2025/06/19 04:06:16 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:06:16 DEBUG : 2: Destination found in --compare-dest, skipping 2025/06/19 04:06:57 DEBUG : 3: Need to transfer - File not found at Destination 2025/06/19 04:06:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dest': Waiting for checks to finish 2025/06/19 04:06:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dest': Waiting for transfers to finish 2025/06/19 04:07:03 DEBUG : 3: md5 = f239f0643fceddecade986ce67a1266b OK 2025/06/19 04:07:03 INFO : 3: Copied (new) 2025/06/19 04:07:03 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (208.52s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:07:49 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/dst" 2025/06/19 04:07:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst.bin" 2025/06/19 04:07:49 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst.bin: Auto set use_segments_container to true 2025/06/19 04:08:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst" 2025/06/19 04:08:10 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst: Auto set use_segments_container to true 2025/06/19 04:08:15 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/CopyDest" 2025/06/19 04:08:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/CopyDest.bin" 2025/06/19 04:08:15 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/CopyDest.bin: Auto set use_segments_container to true 2025/06/19 04:08:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/CopyDest" 2025/06/19 04:08:21 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/CopyDest: Auto set use_segments_container to true 2025/06/19 04:08:32 DEBUG : one: Need to transfer - File not found at Destination 2025/06/19 04:08:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:08:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:08:32 DEBUG : one: md5 = 2b1f5301e29054ec9d50646a7fc0e41c OK 2025/06/19 04:08:32 INFO : one: Copied (new) 2025/06/19 04:08:32 DEBUG : Waiting for deletions to finish 2025/06/19 04:08:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 04:08:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:08:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:08:38 DEBUG : one: md5 = 1c0fa9dbea9a7cc725e7ce32acb56055 OK 2025/06/19 04:08:38 INFO : one: Copied (replaced existing) 2025/06/19 04:08:38 DEBUG : Waiting for deletions to finish 2025/06/19 04:08:39 DEBUG : dst/one: md5 = 49133af7e7f5622059fe4cb12f424914 OK 2025/06/19 04:08:39 DEBUG : CopyDest/one: md5 = a0b1c1bc4856cdeca0bac477acd5f46a OK 2025/06/19 04:08:39 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/BackupDir" 2025/06/19 04:08:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/BackupDir.bin" 2025/06/19 04:08:39 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/BackupDir.bin: Auto set use_segments_container to true 2025/06/19 04:08:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/BackupDir" 2025/06/19 04:08:45 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/BackupDir: Auto set use_segments_container to true 2025/06/19 04:08:51 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 04:08:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:08:51 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:08:51 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 04:09:02 INFO : one: Copied (server-side copy) 2025/06/19 04:09:02 INFO : one: Deleted 2025/06/19 04:09:07 INFO : one: Copied (server-side copy) 2025/06/19 04:09:07 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/06/19 04:09:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:09:07 DEBUG : Waiting for deletions to finish 2025/06/19 04:09:08 DEBUG : CopyDest/two: md5 = c6f068e855d8325b889870c303c0002c OK 2025/06/19 04:09:08 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:09:08 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:09:08 DEBUG : one: Unchanged skipping 2025/06/19 04:09:14 INFO : two: Copied (server-side copy) 2025/06/19 04:09:14 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/06/19 04:09:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:09:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:09:14 DEBUG : Waiting for deletions to finish 2025/06/19 04:09:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:09:14 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:09:14 DEBUG : one: Unchanged skipping 2025/06/19 04:09:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:09:14 DEBUG : two: Unchanged skipping 2025/06/19 04:09:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:09:14 DEBUG : Waiting for deletions to finish 2025/06/19 04:09:14 INFO : There was nothing to transfer 2025/06/19 04:09:15 DEBUG : CopyDest/three: md5 = 1dea1f3c264095b92c18f8c5214c4e9c OK 2025/06/19 04:09:16 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/06/19 04:09:16 DEBUG : three: Destination not found in --copy-dest 2025/06/19 04:09:16 DEBUG : three: Need to transfer - File not found at Destination 2025/06/19 04:09:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:09:16 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:09:16 DEBUG : one: Unchanged skipping 2025/06/19 04:09:16 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:09:16 DEBUG : two: Unchanged skipping 2025/06/19 04:09:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:09:21 DEBUG : three: md5 = c22108859ddf630cb112698d3982085c OK 2025/06/19 04:09:21 INFO : three: Copied (new) 2025/06/19 04:09:21 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (104.34s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:09:34 DEBUG : dst/one: md5 = f06225bb5ef54a717d37813e6f3fc2bb OK 2025/06/19 04:09:34 DEBUG : dst/two: md5 = ff4e54994ddf630f8ae48ef99b69ca06 OK 2025/06/19 04:09:39 DEBUG : dst/three.txt: md5 = 567e0223a45886c503b1aa971406763b OK 2025/06/19 04:09:39 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/dst" 2025/06/19 04:09:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst.bin" 2025/06/19 04:09:40 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst.bin: Auto set use_segments_container to true 2025/06/19 04:09:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst" 2025/06/19 04:09:45 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst: Auto set use_segments_container to true 2025/06/19 04:09:50 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/backup" 2025/06/19 04:09:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/backup.bin" 2025/06/19 04:09:51 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/backup.bin: Auto set use_segments_container to true 2025/06/19 04:09:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/backup" 2025/06/19 04:09:56 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/backup: Auto set use_segments_container to true 2025/06/19 04:10:07 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 04:10:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:10:07 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:10:07 DEBUG : two: Unchanged skipping 2025/06/19 04:10:23 INFO : one: Copied (server-side copy) 2025/06/19 04:10:23 INFO : one: Deleted 2025/06/19 04:10:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:10:23 DEBUG : one: md5 = 7a16e63eb61d7f952f9982cf794f0c39 OK 2025/06/19 04:10:23 INFO : one: Copied (new) 2025/06/19 04:10:23 DEBUG : Waiting for deletions to finish 2025/06/19 04:10:39 INFO : three.txt: Copied (server-side copy) 2025/06/19 04:10:44 INFO : three.txt: Deleted 2025/06/19 04:10:44 INFO : three.txt: Moved into backup dir 2025/06/19 04:10:50 DEBUG : dst/three.txt: md5 = 9017a0a1d26de3888791fc68253a6be2 OK 2025/06/19 04:10:50 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/19 04:10:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:10:50 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:10:50 DEBUG : two: Unchanged skipping 2025/06/19 04:11:01 INFO : one: Copied (server-side copy) 2025/06/19 04:11:01 INFO : one: Deleted 2025/06/19 04:11:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:11:01 DEBUG : one: md5 = d8fdc59d97e6c936119a4e0e4433e031 OK 2025/06/19 04:11:01 INFO : one: Copied (new) 2025/06/19 04:11:01 DEBUG : Waiting for deletions to finish 2025/06/19 04:11:27 INFO : three.txt: Copied (server-side copy) 2025/06/19 04:11:32 INFO : three.txt: Deleted 2025/06/19 04:11:32 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (134.88s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:11:48 DEBUG : dst/one: md5 = a1b98c09a15dc7a38b61df4a48eaa193 OK 2025/06/19 04:11:49 DEBUG : dst/two: md5 = bfd5d70cb0afffe460b21428cd6c916e OK 2025/06/19 04:11:54 DEBUG : dst/three.txt: md5 = 4192511c3e1cebbf310f2641ce2d2ddb OK 2025/06/19 04:11:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/dst" 2025/06/19 04:11:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst.bin" 2025/06/19 04:11:54 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst.bin: Auto set use_segments_container to true 2025/06/19 04:12:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst" 2025/06/19 04:12:00 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst: Auto set use_segments_container to true 2025/06/19 04:12:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/backup" 2025/06/19 04:12:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/backup.bin" 2025/06/19 04:12:06 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/backup.bin: Auto set use_segments_container to true 2025/06/19 04:12:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/backup" 2025/06/19 04:12:11 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/backup: Auto set use_segments_container to true 2025/06/19 04:12:21 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 04:12:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:12:22 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:12:22 DEBUG : two: Unchanged skipping 2025/06/19 04:12:32 INFO : one: Copied (server-side copy) to: one.bak 2025/06/19 04:12:33 INFO : one: Deleted 2025/06/19 04:12:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:12:33 DEBUG : one: md5 = 6684d93d503b990b00b880f617ccd5c0 OK 2025/06/19 04:12:33 INFO : one: Copied (new) 2025/06/19 04:12:33 DEBUG : Waiting for deletions to finish 2025/06/19 04:12:48 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/19 04:12:54 INFO : three.txt: Deleted 2025/06/19 04:12:54 INFO : three.txt: Moved into backup dir 2025/06/19 04:12:59 DEBUG : dst/three.txt: md5 = 2ae8d4011f6541d9b22a54a4f14d84a7 OK 2025/06/19 04:13:00 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/19 04:13:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:13:00 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:13:00 DEBUG : two: Unchanged skipping 2025/06/19 04:13:05 INFO : one: Copied (server-side copy) to: one.bak 2025/06/19 04:13:05 INFO : one: Deleted 2025/06/19 04:13:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:13:05 DEBUG : one: md5 = 38efef147c47f4398c919564f181ded2 OK 2025/06/19 04:13:05 INFO : one: Copied (new) 2025/06/19 04:13:05 DEBUG : Waiting for deletions to finish 2025/06/19 04:13:46 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/19 04:14:06 INFO : three.txt: Deleted 2025/06/19 04:14:06 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (139.00s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:14:07 DEBUG : dst/one: md5 = fe384dd80806fb0731ff0c2a6a9d78e6 OK 2025/06/19 04:14:08 DEBUG : dst/two: md5 = 89b3492e038a47a0f936c2d8c8993036 OK 2025/06/19 04:14:28 DEBUG : dst/three.txt: md5 = 5f6e846d424d59903d7eebb0c073ecb8 OK 2025/06/19 04:14:28 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/dst" 2025/06/19 04:14:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst.bin" 2025/06/19 04:14:28 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst.bin: Auto set use_segments_container to true 2025/06/19 04:14:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst" 2025/06/19 04:14:49 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst: Auto set use_segments_container to true 2025/06/19 04:14:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/backup" 2025/06/19 04:14:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/backup.bin" 2025/06/19 04:14:55 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/backup.bin: Auto set use_segments_container to true 2025/06/19 04:15:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/backup" 2025/06/19 04:15:00 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/backup: Auto set use_segments_container to true 2025/06/19 04:15:10 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 04:15:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:15:11 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:15:11 DEBUG : two: Unchanged skipping 2025/06/19 04:15:34 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/19 04:15:34 INFO : one: Deleted 2025/06/19 04:15:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:15:35 DEBUG : one: md5 = da1a3d5c2dca17fc69c88f89294b58ac OK 2025/06/19 04:15:35 INFO : one: Copied (new) 2025/06/19 04:15:35 DEBUG : Waiting for deletions to finish 2025/06/19 04:15:50 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/19 04:15:55 INFO : three.txt: Deleted 2025/06/19 04:15:55 INFO : three.txt: Moved into backup dir 2025/06/19 04:16:01 DEBUG : dst/three.txt: md5 = 5f314b15e44094244ab15acd4498ed91 OK 2025/06/19 04:16:12 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/19 04:16:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:16:12 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:16:12 DEBUG : two: Unchanged skipping 2025/06/19 04:16:30 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/19 04:16:30 INFO : one: Deleted 2025/06/19 04:16:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:16:30 DEBUG : one: md5 = d45a772cce33c2a86c1925c6fc68a43f OK 2025/06/19 04:16:30 INFO : one: Copied (new) 2025/06/19 04:16:30 DEBUG : Waiting for deletions to finish 2025/06/19 04:16:36 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/19 04:16:41 INFO : three.txt: Deleted 2025/06/19 04:16:41 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (170.01s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:16:57 DEBUG : dst/one: md5 = 19645818dafcb83ea56c2ead0eb881a8 OK 2025/06/19 04:16:57 DEBUG : dst/two: md5 = 3c61b886e38598c46ee717863dfb3346 OK 2025/06/19 04:17:03 DEBUG : dst/three.txt: md5 = 821cc09e5473cce3b495b0adb35967d8 OK 2025/06/19 04:17:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/dst" 2025/06/19 04:17:03 DEBUG : Config file has changed externally - reloading 2025/06/19 04:17:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst.bin" 2025/06/19 04:17:03 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst.bin: Auto set use_segments_container to true 2025/06/19 04:17:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst" 2025/06/19 04:17:09 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst: Auto set use_segments_container to true 2025/06/19 04:17:15 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 04:17:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:17:15 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:17:15 DEBUG : two: Unchanged skipping 2025/06/19 04:17:30 INFO : one: Copied (server-side copy) to: one.bak 2025/06/19 04:17:30 INFO : one: Deleted 2025/06/19 04:17:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:17:31 DEBUG : one: md5 = adfd047d7b29001a279fc2510b908997 OK 2025/06/19 04:17:31 INFO : one: Copied (new) 2025/06/19 04:17:31 DEBUG : Waiting for deletions to finish 2025/06/19 04:17:46 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/19 04:17:51 INFO : three.txt: Deleted 2025/06/19 04:17:51 INFO : three.txt: Moved into backup dir 2025/06/19 04:17:57 DEBUG : dst/three.txt: md5 = f74d97e83d25d18a5c317188d814afbb OK 2025/06/19 04:17:57 DEBUG : one.bak: Excluded (Path Filter) 2025/06/19 04:17:57 DEBUG : one.bak: Excluded 2025/06/19 04:17:57 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/06/19 04:17:57 DEBUG : three.txt.bak: Excluded 2025/06/19 04:17:57 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/19 04:17:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for checks to finish 2025/06/19 04:17:58 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:17:58 DEBUG : two: Unchanged skipping 2025/06/19 04:18:13 INFO : one: Copied (server-side copy) to: one.bak 2025/06/19 04:18:13 INFO : one: Deleted 2025/06/19 04:18:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi/dst': Waiting for transfers to finish 2025/06/19 04:18:13 DEBUG : one: md5 = 746b12abc552d577ff2041956b4effeb OK 2025/06/19 04:18:13 INFO : one: Copied (new) 2025/06/19 04:18:13 DEBUG : Waiting for deletions to finish 2025/06/19 04:18:18 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/19 04:18:24 INFO : three.txt: Deleted 2025/06/19 04:18:24 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (97.80s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:18:35 DEBUG : dst/one: md5 = e8196125505e76d016aad1f70e724515 OK 2025/06/19 04:18:35 DEBUG : dst/two: md5 = 0b6e4880dc83b413591f8d6c607d709c OK 2025/06/19 04:18:40 DEBUG : dst/three.txt: md5 = 1a3aec604c3d3fc8137f3d27400042cc OK 2025/06/19 04:18:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/dst" 2025/06/19 04:18:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst.bin" 2025/06/19 04:18:46 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst.bin: Auto set use_segments_container to true 2025/06/19 04:18:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst" 2025/06/19 04:18:52 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst: Auto set use_segments_container to true 2025/06/19 04:18:57 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 04:19:12 INFO : one: Copied (server-side copy) to: one.bak 2025/06/19 04:19:13 INFO : one: Deleted 2025/06/19 04:19:13 DEBUG : one: md5 = ccfd13727b74e39f72a8fb7a741f019b OK 2025/06/19 04:19:13 INFO : one: Copied (new) 2025/06/19 04:19:13 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:19:13 DEBUG : two: Unchanged skipping 2025/06/19 04:19:13 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/06/19 04:19:23 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/19 04:19:29 INFO : three.txt: Deleted 2025/06/19 04:19:34 DEBUG : three.txt: md5 = d213f48e31ae4e9745c277c805dffaff OK 2025/06/19 04:19:34 INFO : three.txt: Copied (new) 2025/06/19 04:19:45 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/19 04:19:55 INFO : one: Copied (server-side copy) to: one.bak 2025/06/19 04:19:55 INFO : one: Deleted 2025/06/19 04:19:55 DEBUG : one: md5 = e188b1fc6a78f55ad89ff212c76094ef OK 2025/06/19 04:19:55 INFO : one: Copied (new) 2025/06/19 04:19:55 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:19:55 DEBUG : two: Unchanged skipping 2025/06/19 04:20:00 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/06/19 04:20:06 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/19 04:20:12 INFO : three.txt: Deleted 2025/06/19 04:20:17 DEBUG : three.txt: md5 = e542850f8ab1683b533d1ad3d62ddeee OK 2025/06/19 04:20:17 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (123.81s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:20:39 DEBUG : dst/one: md5 = 76edbd978ffd0c5236838ac00ab1c2d5 OK 2025/06/19 04:20:39 DEBUG : dst/two: md5 = a19e1062444d4f21de81e72d452680c3 OK 2025/06/19 04:20:44 DEBUG : dst/three.txt: md5 = 324d13b861968012ce0705186f42b7b3 OK 2025/06/19 04:20:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-becuvap8kusi/dst" 2025/06/19 04:20:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst.bin" 2025/06/19 04:20:45 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst.bin: Auto set use_segments_container to true 2025/06/19 04:20:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-becuvap8kusi/dst" 2025/06/19 04:20:51 DEBUG : Swift container crypt path rclone-test-becuvap8kusi/dst: Auto set use_segments_container to true 2025/06/19 04:20:56 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 04:21:06 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/19 04:21:07 INFO : one: Deleted 2025/06/19 04:21:07 DEBUG : one: md5 = 55d838df01ca8d77f92a03eb6d004c7b OK 2025/06/19 04:21:07 INFO : one: Copied (new) 2025/06/19 04:21:07 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:21:07 DEBUG : two: Unchanged skipping 2025/06/19 04:21:07 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/06/19 04:21:23 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/19 04:21:28 INFO : three.txt: Deleted 2025/06/19 04:21:33 DEBUG : three.txt: md5 = d25d9ee2075bd2bcf83631410da5f630 OK 2025/06/19 04:21:33 INFO : three.txt: Copied (new) 2025/06/19 04:21:39 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/19 04:21:44 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/19 04:21:44 INFO : one: Deleted 2025/06/19 04:21:45 DEBUG : one: md5 = 6eb38f4db39ef68ef59f3849a4a5a96a OK 2025/06/19 04:21:45 INFO : one: Copied (new) 2025/06/19 04:21:45 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:21:45 DEBUG : two: Unchanged skipping 2025/06/19 04:21:45 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/06/19 04:21:55 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/19 04:22:00 INFO : three.txt: Deleted 2025/06/19 04:22:20 DEBUG : three.txt: md5 = 8560e4d560492dfb9c098a5dfbfce48f OK 2025/06/19 04:22:20 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (183.36s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:23:47 DEBUG : Testêé: md5 = 2079980d052d48014802369d58b25227 OK 2025/06/19 04:23:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 04:23:48 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/06/19 04:23:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 04:23:53 DEBUG : Testêé: md5 = 49ebee126a344caa141ded0134b273b1 OK 2025/06/19 04:23:53 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/06/19 04:23:53 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (21.23s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:24:04 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/19 04:24:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 04:24:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 04:24:09 DEBUG : existing: md5 = 93415eb86e0e1b45d0c5752770749fb5 OK 2025/06/19 04:24:09 INFO : existing: Copied (new) 2025/06/19 04:24:09 DEBUG : Waiting for deletions to finish 2025/06/19 04:24:14 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/06/19 04:24:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 04:24:19 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/06/19 04:24:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 04:24:19 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': not deleting files as there were IO errors 2025/06/19 04:24:19 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (26.60s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:24:30 DEBUG : EXISTING: md5 = 1c49035eb1e782cf6e87605635f90ab5 OK 2025/06/19 04:24:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 04:24:31 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:24:31 DEBUG : existing: Unchanged skipping 2025/06/19 04:24:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 04:24:31 DEBUG : Waiting for deletions to finish 2025/06/19 04:24:31 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.34s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" sync_test.go:2636: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.19s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" sync_test.go:2675: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" sync_test.go:2675: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" sync_test.go:2675: This test only runs on local --- PASS: TestMaxTransfer (0.37s) --- SKIP: TestMaxTransfer/Hard (0.10s) --- SKIP: TestMaxTransfer/Soft (0.18s) --- SKIP: TestMaxTransfer/Cautious (0.10s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:24:37 DEBUG : both0: md5 = 9f9d443eab6c28bf3dda4205b820ba1d OK 2025/06/19 04:24:43 DEBUG : only0: md5 = 35f61c56899e9d5215db80918fe06462 OK 2025/06/19 04:24:48 DEBUG : both1: md5 = bca84005115360d247b345d3be9a85d8 OK 2025/06/19 04:24:53 DEBUG : only1: md5 = e59eaec627d6e9a600ece04595d0d5fc OK 2025/06/19 04:24:59 DEBUG : both2: md5 = fdbd0e796de781f986e94f13cbbd6a62 OK 2025/06/19 04:25:04 DEBUG : only2: md5 = 8cc095c0f6f3455de3e5e464aa0febf6 OK 2025/06/19 04:25:04 DEBUG : both3: md5 = eb01e77fc6f91f33fde6dbf4c95de1ab OK 2025/06/19 04:25:05 DEBUG : only3: md5 = f5ba36f702342d41573ffd7ce3971f37 OK 2025/06/19 04:25:05 DEBUG : both4: md5 = ea895dc46c440c60ccd00348f1e9c4e3 OK 2025/06/19 04:25:10 DEBUG : only4: md5 = fe6caf5441d178e246629b1b6ec188c1 OK 2025/06/19 04:25:15 DEBUG : both5: md5 = eff31a647e828a7d38ea031c4587c59c OK 2025/06/19 04:25:21 DEBUG : only5: md5 = 4b515a17884a81c71ee99d27debace69 OK 2025/06/19 04:25:26 DEBUG : both6: md5 = 2e6d5350be2df7786f225a283064a9bd OK 2025/06/19 04:25:46 DEBUG : only6: md5 = f45760b1248991b05d6484d352ad74ad OK 2025/06/19 04:25:46 DEBUG : both7: md5 = 7872cd5444556737bc25fd802b8ee54f OK 2025/06/19 04:25:47 DEBUG : only7: md5 = e8dc5f2d13586843fc2a5e796591df37 OK 2025/06/19 04:25:48 DEBUG : both8: md5 = 9b45ab138f6a025e321d6bf059b04741 OK 2025/06/19 04:25:53 DEBUG : only8: md5 = a9ea172927c29c9dbc32f4b31ebd99ee OK 2025/06/19 04:25:58 DEBUG : both9: md5 = 187ff139c4d7a623f0b4bf84c74d510c OK 2025/06/19 04:25:58 DEBUG : only9: md5 = bb86322ba43ac815b61d7c42a179f8f1 OK 2025/06/19 04:26:04 DEBUG : both10: md5 = 1d6fb243347e50f7cc3a6e7f09f0e3cf OK 2025/06/19 04:26:09 DEBUG : only10: md5 = f78c5a20b64c9e5bde36b0d1bf5d8e3c OK 2025/06/19 04:26:14 DEBUG : both11: md5 = 11aae69e70444df63ab610d3c3340a90 OK 2025/06/19 04:26:20 DEBUG : only11: md5 = d8cf32ca9b4c11ad57c4b62d9ac77f11 OK 2025/06/19 04:26:20 DEBUG : both12: md5 = 6bf2849a93ec958fd8bf4b4497732547 OK 2025/06/19 04:26:25 DEBUG : only12: md5 = 65d3e742154c89fe077fb7f7cdbd5946 OK 2025/06/19 04:26:30 DEBUG : both13: md5 = 6861f4d59712eddfe720508d4cbd5278 OK 2025/06/19 04:26:31 DEBUG : only13: md5 = 55834aba7dbea2293af4fd2cccdfe05f OK 2025/06/19 04:26:37 DEBUG : both14: md5 = a618239fd7da7d9e60d820b6171031c2 OK 2025/06/19 04:26:43 DEBUG : only14: md5 = 1d36373aa4bf3e5729b7c0d77ead8495 OK 2025/06/19 04:26:48 DEBUG : both15: md5 = 835463fc4e2a24ea052d36a13aae1635 OK 2025/06/19 04:26:53 DEBUG : only15: md5 = 6587c7b33c4549296b3d3942dff9eed0 OK 2025/06/19 04:26:58 DEBUG : both16: md5 = c1fb8ff5435daabefd5413e264815f64 OK 2025/06/19 04:26:59 DEBUG : only16: md5 = 9859cf29cd67c0e0eebbaf831ee19313 OK 2025/06/19 04:27:04 DEBUG : both17: md5 = db44ab6082ed87300609ee2177f65811 OK 2025/06/19 04:27:10 DEBUG : only17: md5 = c18de288666ea35ed653fab97047e33c OK 2025/06/19 04:27:15 DEBUG : both18: md5 = d9950e82b11253d62c5d4e9c1bfa7f9d OK 2025/06/19 04:27:15 DEBUG : only18: md5 = ed799e6838b26884df1e47a80a9e1eb4 OK 2025/06/19 04:27:16 DEBUG : both19: md5 = cb3f9b55701ba08acdbc251b14075bbf OK 2025/06/19 04:27:16 DEBUG : only19: md5 = e46b00ac1569f4f70d285d573adfb3b4 OK 2025/06/19 04:28:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 04:28:08 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:08 DEBUG : both10: Unchanged skipping 2025/06/19 04:28:08 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:08 DEBUG : both12: Unchanged skipping 2025/06/19 04:28:08 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:08 DEBUG : both11: Unchanged skipping 2025/06/19 04:28:09 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:09 DEBUG : both14: Unchanged skipping 2025/06/19 04:28:09 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:09 DEBUG : both15: Unchanged skipping 2025/06/19 04:28:09 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:09 DEBUG : both16: Unchanged skipping 2025/06/19 04:28:09 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:09 DEBUG : both17: Unchanged skipping 2025/06/19 04:28:09 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:09 DEBUG : both19: Unchanged skipping 2025/06/19 04:28:13 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:13 DEBUG : both0: Unchanged skipping 2025/06/19 04:28:13 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:13 DEBUG : both2: Unchanged skipping 2025/06/19 04:28:13 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:13 DEBUG : both3: Unchanged skipping 2025/06/19 04:28:13 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:13 DEBUG : both4: Unchanged skipping 2025/06/19 04:28:14 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:14 DEBUG : both13: Unchanged skipping 2025/06/19 04:28:14 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:14 DEBUG : both18: Unchanged skipping 2025/06/19 04:28:14 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:14 DEBUG : both7: Unchanged skipping 2025/06/19 04:28:14 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:14 DEBUG : both1: Unchanged skipping 2025/06/19 04:28:14 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:14 DEBUG : both8: Unchanged skipping 2025/06/19 04:28:19 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:19 DEBUG : both5: Unchanged skipping 2025/06/19 04:28:19 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:19 DEBUG : both6: Unchanged skipping 2025/06/19 04:28:19 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:28:19 DEBUG : both9: Unchanged skipping 2025/06/19 04:28:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for transfers to finish 2025/06/19 04:28:19 DEBUG : Waiting for deletions to finish 2025/06/19 04:28:19 INFO : only16: Deleted 2025/06/19 04:28:19 INFO : only19: Deleted 2025/06/19 04:28:19 INFO : only13: Deleted 2025/06/19 04:28:19 INFO : only18: Deleted 2025/06/19 04:28:19 INFO : only3: Deleted 2025/06/19 04:28:24 INFO : only0: Deleted 2025/06/19 04:28:24 INFO : only11: Deleted 2025/06/19 04:28:24 INFO : only17: Deleted 2025/06/19 04:28:29 INFO : only5: Deleted 2025/06/19 04:28:29 INFO : only6: Deleted 2025/06/19 04:28:29 INFO : only12: Deleted 2025/06/19 04:28:30 INFO : only4: Deleted 2025/06/19 04:28:30 INFO : only7: Deleted 2025/06/19 04:28:34 INFO : only15: Deleted 2025/06/19 04:28:34 INFO : only9: Deleted 2025/06/19 04:28:39 INFO : only1: Deleted 2025/06/19 04:28:39 INFO : only2: Deleted 2025/06/19 04:28:40 INFO : only8: Deleted 2025/06/19 04:28:44 INFO : only14: Deleted 2025/06/19 04:28:45 INFO : only10: Deleted 2025/06/19 04:28:45 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (372.64s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi'", Local "Local file system at /tmp/rclone4272826195", Modify Window "1ns" 2025/06/19 04:30:50 DEBUG : both0: md5 = 87ce8f27fdfec3790ff32e9093184a4a OK 2025/06/19 04:31:10 DEBUG : only0: md5 = 9ac2e2caf994b1657672bbca09936bfe OK 2025/06/19 04:31:30 DEBUG : both1: md5 = f8caa417eb198aa833e5e68ba42658f9 OK 2025/06/19 04:31:51 DEBUG : only1: md5 = c574ac7e73af5bc60bab9ef944266997 OK 2025/06/19 04:32:11 DEBUG : both2: md5 = 64a8ef04d0d038e573c5a4e3651cba36 OK 2025/06/19 04:32:16 DEBUG : only2: md5 = 6be7df7974f33babe085bda321290bec OK 2025/06/19 04:32:16 DEBUG : both3: md5 = 96d44f83176db0f8204b2d82f42c8a8c OK 2025/06/19 04:32:17 DEBUG : only3: md5 = 432b1e71b6b4c1b0e3e72a3a256e0187 OK 2025/06/19 04:32:17 DEBUG : both4: md5 = 441199c3e949c722bb486e222e3d141f OK 2025/06/19 04:32:22 DEBUG : only4: md5 = e60ea693d8dfd01b86693c7f97a8c2b8 OK 2025/06/19 04:32:27 DEBUG : both5: md5 = ba0734424dd28c0ec62b12ee32be5793 OK 2025/06/19 04:32:33 DEBUG : only5: md5 = e344ce3c1dca5097edecda9a64d746bd OK 2025/06/19 04:32:54 DEBUG : both6: md5 = 298a480a9fc763b6d90451823bd11126 OK 2025/06/19 04:32:59 DEBUG : only6: md5 = a39a996680397390c38dfbc39e731ce6 OK 2025/06/19 04:32:59 DEBUG : both7: md5 = 6a5516cb29262aa6863c05e8e5f8563c OK 2025/06/19 04:32:59 DEBUG : only7: md5 = d1a168c9ee7161ba41410ca13b30420c OK 2025/06/19 04:33:00 DEBUG : both8: md5 = 0611560e2d340a0e9c4fddc1f8bb5e5b OK 2025/06/19 04:33:21 DEBUG : only8: md5 = 5d83e0f67f893f8c74dbf7c5049d788b OK 2025/06/19 04:33:26 DEBUG : both9: md5 = 9360fe3790417d220a0f39c2c295e7e0 OK 2025/06/19 04:33:26 DEBUG : only9: md5 = 5343ba77b50a807218f631740c45cbb7 OK 2025/06/19 04:33:31 DEBUG : both10: md5 = 9ef4ac9b0825f18a4eadaac16e35bf02 OK 2025/06/19 04:33:36 DEBUG : only10: md5 = 2dc0d187df41f7ab8b5396265e6259c9 OK 2025/06/19 04:33:41 DEBUG : both11: md5 = 8d35142f21a519d47bd6e005c10fb53b OK 2025/06/19 04:33:47 DEBUG : only11: md5 = cd78146e06bc65329862ecfa7073ed0b OK 2025/06/19 04:33:47 DEBUG : both12: md5 = ba03dc6808b939d54a20ec8df4095fd2 OK 2025/06/19 04:33:52 DEBUG : only12: md5 = 77d14a612910f607554532945c930f71 OK 2025/06/19 04:33:57 DEBUG : both13: md5 = b33e1fa1ae7435a18fa9c2d71f1d9780 OK 2025/06/19 04:33:57 DEBUG : only13: md5 = d08e67d7e606700b64d8e52161e93224 OK 2025/06/19 04:34:02 DEBUG : both14: md5 = 718e56d8c1586e731894a83d40cd9a12 OK 2025/06/19 04:34:08 DEBUG : only14: md5 = e53e23e4791ea8525abfddcdac573fa7 OK 2025/06/19 04:34:13 DEBUG : both15: md5 = fcf5518372dd49fc7d472ec319632079 OK 2025/06/19 04:34:18 DEBUG : only15: md5 = 0091bc2c588b2d6adf0931451b8cfccd OK 2025/06/19 04:34:23 DEBUG : both16: md5 = a062d02a37a5965684b7f1298ee27346 OK 2025/06/19 04:34:24 DEBUG : only16: md5 = 09a603e1a281d74ddf64ac01bfca5df7 OK 2025/06/19 04:34:29 DEBUG : both17: md5 = def910d1ba79f2f91a1fbb8d420f60fb OK 2025/06/19 04:34:49 DEBUG : only17: md5 = f52d7d811725b94eeb133a9adf9b5f34 OK 2025/06/19 04:35:09 DEBUG : both18: md5 = 5bf12c4ae50a320e0b15bf31a9fecd49 OK 2025/06/19 04:35:10 DEBUG : only18: md5 = 1c737e6beed5d30e0dc0cc3ebdddbecc OK 2025/06/19 04:35:10 DEBUG : both19: md5 = 429cd10a571d760a10869d875ecf41c3 OK 2025/06/19 04:35:10 DEBUG : only19: md5 = 325eee7b9dd9ef69f6a5341fb420a6e7 OK 2025/06/19 04:36:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-becuvap8kusi': Waiting for checks to finish 2025/06/19 04:36:05 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:05 DEBUG : both0: Unchanged skipping 2025/06/19 04:36:05 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:05 DEBUG : both11: Unchanged skipping 2025/06/19 04:36:05 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:05 DEBUG : both10: Unchanged skipping 2025/06/19 04:36:05 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:05 DEBUG : both12: Unchanged skipping 2025/06/19 04:36:05 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:05 DEBUG : both14: Unchanged skipping 2025/06/19 04:36:05 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:05 DEBUG : both15: Unchanged skipping 2025/06/19 04:36:06 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:06 DEBUG : both17: Unchanged skipping 2025/06/19 04:36:06 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:06 DEBUG : both18: Unchanged skipping 2025/06/19 04:36:06 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:06 DEBUG : both19: Unchanged skipping 2025/06/19 04:36:06 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:06 DEBUG : both1: Unchanged skipping 2025/06/19 04:36:06 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:06 DEBUG : both3: Unchanged skipping 2025/06/19 04:36:06 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:06 DEBUG : both4: Unchanged skipping 2025/06/19 04:36:10 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:10 DEBUG : both13: Unchanged skipping 2025/06/19 04:36:10 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:10 DEBUG : both16: Unchanged skipping 2025/06/19 04:36:11 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:11 DEBUG : both7: Unchanged skipping 2025/06/19 04:36:11 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:11 DEBUG : both8: Unchanged skipping 2025/06/19 04:36:11 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:11 DEBUG : both2: Unchanged skipping 2025/06/19 04:36:11 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/06/19 04:36:11 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:11 DEBUG : both9: Unchanged skipping 2025/06/19 04:36:11 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:36:11 DEBUG : both5: Unchanged skipping panic: test timed out after 1h0m0s running tests: TestSyncConcurrentTruncate (5m27s) goroutine 12507 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2484 +0x394 created by time.goFunc /usr/local/go/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 7 minutes]: testing.(*T).Run(0xc0000f3a40, {0x26be7d7?, 0xc00006bad8?}, 0x27c0888) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc0000f3a40) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc0000f3a40, 0xc00006bc18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc00044fc38, {0x3e7c5e0, 0x6b, 0x6b}, {0xedfe57cab?, 0x3e925a0?, 0x3e925a0?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc0004eb040) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc0004eb040) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:69 +0xa5 github.com/rclone/rclone/fs/sync.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:45 main.main() _testmain.go:259 +0xa9 goroutine 21 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 20 [syscall, 60 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:152 +0x1f goroutine 12505 [IO wait]: internal/poll.runtime_pollWait(0x7247b04434c0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001e80700?, 0xc0001d4600?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc001e80700, {0xc0001d4600, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001e80700, {0xc0001d4600?, 0x0?, 0xc001d21880?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc001c72750, {0xc0001d4600?, 0xc00064a2d0?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001f84c48, {0xc0001d4600?, 0x7247684668b8?, 0x7247b0506108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0019d5ce0, {0xc0001d4600?, 0xc0001844e0?, 0xc0006cf9e0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000627438, {0x2ace280, 0xc0019d5ce0}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000627188, {0x7247684f0d80, 0xc001f84c48}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000627188, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000627188, {0xc0007a2000, 0x1000, 0x7247b0506108?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000557e60, {0xc0007a2000?, 0x412565?, 0x68?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001fd6d80) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001fd6d80, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000557e60) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 12487 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 12174 [sync.WaitGroup.Wait]: sync.runtime_SemacquireWaitGroup(0x0?) /usr/local/go/src/runtime/sema.go:110 +0x25 sync.(*WaitGroup).Wait(0xc000695807?) /usr/local/go/src/sync/waitgroup.go:118 +0x48 github.com/rclone/rclone/fs/sync.(*syncCopyMove).stopCheckers(0xc0002a4a08) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:527 +0x6f github.com/rclone/rclone/fs/sync.(*syncCopyMove).run(0xc0002a4a08) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:972 +0x2bb github.com/rclone/rclone/fs/sync.runSyncCopyMove({0x2af3910, 0xc0003f4c00}, {0x2b07fe0, 0xc001b3cb60}, {0x2b09cc0, 0xc0004eb540}, 0x3, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:1366 +0x225 github.com/rclone/rclone/fs/sync.Sync({0x2af3910, 0xc0003f4c00}, {0x2b07fe0, 0xc001b3cb60}, {0x2b09cc0, 0xc0004eb540}, 0x0) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:1372 +0x77 github.com/rclone/rclone/fs/sync.testSyncConcurrent(0xc001a76000, {0x2687094, 0x8}) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2748 +0xd05 github.com/rclone/rclone/fs/sync.TestSyncConcurrentTruncate(0xc001a76000?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2763 +0x1f testing.tRunner(0xc001a76000, 0x27c0888) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 12486 [select]: net/http.(*persistConn).roundTrip(0xc0006bc240, 0xc0008e4280) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc0001844e0, 0xc0008e03c0) /usr/local/go/src/net/http/transport.go:696 +0xb9a net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0004381c0, 0xc0008e03c0) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 net/http.send(0xc0008e03c0, {0x2accd20, 0xc0004381c0}, {0xc000695b01?, 0xc00034f5a0?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc0019e5d70, 0xc0008e03c0, {0x0?, 0x0?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc0019e5d70, 0xc0008e03c0) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 github.com/ncw/swift/v2.(*Connection).doTimeoutRequest.func1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:421 +0x2b created by github.com/ncw/swift/v2.(*Connection).doTimeoutRequest in goroutine 12349 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:420 +0xb8 goroutine 12349 [select]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc00065a1e0, 0xc00046c850, 0xc0008e03c0) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:425 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc00065a1e0, {0x2af3948, 0xc000695950}, {0xc001a8c140, 0x4f}, {{0xc00068e000, 0x5}, {0xc00068e006, 0x23}, {0x2679d24, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:777 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc00065a1e0, {0x2af3948, 0xc000695950}, {{0xc00068e000, 0x5}, {0xc00068e006, 0x23}, {0x2679d24, 0x4}, 0x0, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:847 +0x151 github.com/ncw/swift/v2.(*Connection).objectBase(0x442c65?, {0x2af3948?, 0xc000695950?}, {0xc00068e000?, 0x77?}, {0xc00068e006, 0x23}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:2143 +0xf8 github.com/rclone/rclone/backend/swift.(*Object).readMetaData.func1.(*Connection).Object.1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:2132 +0x85 github.com/ncw/swift/v2.withLORetry(0x0, 0xc0007b9258) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/largeobjects.go:303 +0x9f github.com/ncw/swift/v2.(*Connection).Object(...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:2131 github.com/rclone/rclone/backend/swift.(*Object).readMetaData.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1334 +0x156 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0xa, 0xc0016b2808?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc001b00d80, 0xc001a0d680, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc001b00d80, 0xc001a0d680) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:216 +0x93 github.com/rclone/rclone/backend/swift.(*Object).readMetaData(0xc001e44a20, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1333 +0x165 github.com/rclone/rclone/backend/swift.(*Object).hasHeader(0xc001e44a20, {0x2af3948?, 0xc000695950?}, {0x26ae644, 0x15}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1246 +0x30 github.com/rclone/rclone/backend/swift.(*Object).isLargeObject(0xc001e44a20, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1277 +0x48 github.com/rclone/rclone/backend/swift.(*Object).Update(0xc001e44a20, {0x2af3948, 0xc000695950}, {0x2acbae0, 0xc001f84b28}, {0x2afeae0, 0xc000370b70}, {0xc0003b00e0, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1523 +0x1c8 github.com/rclone/rclone/backend/crypt.(*Object).Update.func1({0x2af3948?, 0xc000695950?}, {0x2acbae0?, 0xc001f84b28?}, {0x2afeae0?, 0xc000370b70?}, {0xc0003b00e0?, 0xc0008ca600?, 0x41acfb?}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:1091 +0x7c github.com/rclone/rclone/backend/crypt.(*Fs).put(0xc001b3cb60, {0x2af3948, 0xc000695950}, {0x2acbba0, 0xc00051ca00}, {0x724768667c68, 0xc0007c4f60}, {0xc0003b00e0, 0x1, 0x1}, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:536 +0x3c3 github.com/rclone/rclone/backend/crypt.(*Object).Update(0x2adeef0?, {0x2af3948?, 0xc000695950?}, {0x2acbba0?, 0xc00051ca00?}, {0x724768667c68?, 0xc0007c4f60?}, {0xc0003b00e0, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:1093 +0x85 github.com/rclone/rclone/fs/operations.(*copy).updateOrPut(0xc0008dc990, {0x2af3948, 0xc000695950}, {0x2adeef0?, 0xc00017a380?}, {0xc0003b00e0, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:212 +0x1a5 github.com/rclone/rclone/fs/operations.(*copy).manualCopy(0xc0008dc990, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:271 +0x6d8 github.com/rclone/rclone/fs/operations.(*copy).copy(0xc0008dc990, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:311 +0x170 github.com/rclone/rclone/fs/operations.Copy({0x2af3948, 0xc000695950}, {0x2b07fe0, 0xc001b3cb60}, {0x2b08050, 0xc000011590}, {0xc0021b3c68, 0x6}, {0x2b09d30, 0xc0007c4f60}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:413 +0x4db github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairCopyOrMove(0xc0002a4a08, {0x2af3948, 0xc000695950}, 0xc0006958b0, {0x2b07fe0, 0xc001b3cb60}, 0x32, 0xc0007dcf60?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:505 +0x1fe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startTransfers in goroutine 12174 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:535 +0x7d goroutine 12371 [chan receive]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:256 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 12174 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:254 +0x378 goroutine 12436 [select]: net/http.(*persistConn).writeLoop(0xc0005c6b40) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 12378 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 12483 [chan receive]: github.com/rclone/rclone/backend/local.(*fadvise).worker(0xc0008ca600) /home/rclone/go/src/github.com/rclone/rclone/backend/local/fadvise_unix.go:114 +0x3f created by github.com/rclone/rclone/backend/local.newFadvise in goroutine 12349 /home/rclone/go/src/github.com/rclone/rclone/backend/local/fadvise_unix.go:74 +0x11f goroutine 12503 [select]: net/http.(*persistConn).roundTrip(0xc0005c6b40, 0xc000796640) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc0001844e0, 0xc000292f00) /usr/local/go/src/net/http/transport.go:696 +0xb9a net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0004381c0, 0xc000292f00) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 net/http.send(0xc000292f00, {0x2accd20, 0xc0004381c0}, {0x1?, 0x378ce40?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc0019e5d70, 0xc000292f00, {0x1?, 0x1?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc0019e5d70, 0xc000292f00) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 github.com/ncw/swift/v2.(*Connection).doTimeoutRequest.func1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:421 +0x2b created by github.com/ncw/swift/v2.(*Connection).doTimeoutRequest in goroutine 12350 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:420 +0xb8 goroutine 12435 [IO wait]: internal/poll.runtime_pollWait(0x7247b0443060, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001a5c600?, 0xc000730600?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc001a5c600, {0xc000730600, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001a5c600, {0xc000730600?, 0xc0005570e0?, 0xc00009a8d8?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc001c723d8, {0xc000730600?, 0xc000822cf0?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001c67980, {0xc000730600?, 0x7247684668b8?, 0x7247b0506108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0019d56e0, {0xc000730600?, 0xc0001844e0?, 0xc000695d10?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0006270b8, {0x2ace280, 0xc0019d56e0}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000626e08, {0x7247684f0d80, 0xc001c67980}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000626e08, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000626e08, {0xc000891000, 0x1000, 0x7247b0506108?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0005c6b40, {0xc000891000?, 0x412565?, 0x68?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001a0c1e0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001a0c1e0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0005c6b40) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 12378 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 12348 [select]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc00065a1e0, 0xc0000f55e0, 0xc0002928c0) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:425 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc00065a1e0, {0x2af3948, 0xc000695950}, {0xc001a8c140, 0x4f}, {{0xc0019ed500, 0x5}, {0xc0019ed506, 0x23}, {0x2679d24, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:777 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc00065a1e0, {0x2af3948, 0xc000695950}, {{0xc0019ed500, 0x5}, {0xc0019ed506, 0x23}, {0x2679d24, 0x4}, 0x0, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:847 +0x151 github.com/ncw/swift/v2.(*Connection).objectBase(0x442c65?, {0x2af3948?, 0xc000695950?}, {0xc0019ed500?, 0x77?}, {0xc0019ed506, 0x23}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:2143 +0xf8 github.com/rclone/rclone/backend/swift.(*Object).readMetaData.func1.(*Connection).Object.1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:2132 +0x85 github.com/ncw/swift/v2.withLORetry(0x0, 0xc0007ad258) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/largeobjects.go:303 +0x9f github.com/ncw/swift/v2.(*Connection).Object(...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:2131 github.com/rclone/rclone/backend/swift.(*Object).readMetaData.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1334 +0x156 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0xa, 0xc000053808?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc001b00d80, 0xc001fd6ae0, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc001b00d80, 0xc001fd6ae0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:216 +0x93 github.com/rclone/rclone/backend/swift.(*Object).readMetaData(0xc001e449c0, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1333 +0x165 github.com/rclone/rclone/backend/swift.(*Object).hasHeader(0xc001e449c0, {0x2af3948?, 0xc000695950?}, {0x26ae644, 0x15}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1246 +0x30 github.com/rclone/rclone/backend/swift.(*Object).isLargeObject(0xc001e449c0, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1277 +0x48 github.com/rclone/rclone/backend/swift.(*Object).Update(0xc001e449c0, {0x2af3948, 0xc000695950}, {0x2acbae0, 0xc0019d5ae8}, {0x2afeae0, 0xc000374ed0}, {0xc0021367e0, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1523 +0x1c8 github.com/rclone/rclone/backend/crypt.(*Object).Update.func1({0x2af3948?, 0xc000695950?}, {0x2acbae0?, 0xc0019d5ae8?}, {0x2afeae0?, 0xc000374ed0?}, {0xc0021367e0?, 0xc000900ac0?, 0x41acfb?}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:1091 +0x7c github.com/rclone/rclone/backend/crypt.(*Fs).put(0xc001b3cb60, {0x2af3948, 0xc000695950}, {0x2acbba0, 0xc00038a700}, {0x724768667c68, 0xc0007c4ae0}, {0xc0021367e0, 0x1, 0x1}, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:536 +0x3c3 github.com/rclone/rclone/backend/crypt.(*Object).Update(0x2adeef0?, {0x2af3948?, 0xc000695950?}, {0x2acbba0?, 0xc00038a700?}, {0x724768667c68?, 0xc0007c4ae0?}, {0xc0021367e0, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:1093 +0x85 github.com/rclone/rclone/fs/operations.(*copy).updateOrPut(0xc0005ef9e0, {0x2af3948, 0xc000695950}, {0x2adeef0?, 0xc0019dc2a0?}, {0xc0021367e0, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:212 +0x1a5 github.com/rclone/rclone/fs/operations.(*copy).manualCopy(0xc0005ef9e0, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:271 +0x6d8 github.com/rclone/rclone/fs/operations.(*copy).copy(0xc0005ef9e0, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:311 +0x170 github.com/rclone/rclone/fs/operations.Copy({0x2af3948, 0xc000695950}, {0x2b07fe0, 0xc001b3cb60}, {0x2b08050, 0xc000011578}, {0xc0021b3aa8, 0x6}, {0x2b09d30, 0xc0007c4ae0}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:413 +0x4db github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairCopyOrMove(0xc0002a4a08, {0x2af3948, 0xc000695950}, 0xc0006958b0, {0x2b07fe0, 0xc001b3cb60}, 0x19, 0xc001fd17a8?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:505 +0x1fe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startTransfers in goroutine 12174 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:535 +0x7d goroutine 12419 [IO wait]: internal/poll.runtime_pollWait(0x7247b0443290, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001a5c500?, 0xc00072a000?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc001a5c500, {0xc00072a000, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001a5c500, {0xc00072a000?, 0x1973?, 0xc0019a56a0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc001c72330, {0xc00072a000?, 0xc000626c50?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001c672c0, {0xc00072a000?, 0x7247684668b8?, 0x7247b0506108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0019d5c80, {0xc00072a000?, 0x1973?, 0xc0008c39c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000626d38, {0x2ace280, 0xc0019d5c80}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000626a88, {0x7247684f0d80, 0xc001c672c0}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000626a88, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000626a88, {0xc000723000, 0x1000, 0xc00032f620?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0006bc240, {0xc000723000?, 0x7f4ac5?, 0x2acb1a0?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc0003956e0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc0003956e0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0006bc240) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 11905 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 12484 [select]: github.com/rclone/rclone/fs/accounting.(*Account).averageLoop(0xc00051ca00) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:221 +0xed created by github.com/rclone/rclone/fs/accounting.newAccountSizeName in goroutine 12349 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:119 +0x436 goroutine 12461 [select]: github.com/rclone/rclone/fs/accounting.(*Account).averageLoop(0xc00038a600) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:221 +0xed created by github.com/rclone/rclone/fs/accounting.newAccountSizeName in goroutine 12347 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:119 +0x436 goroutine 12400 [select]: net/http.(*persistConn).writeLoop(0xc0003e0900) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 12364 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 12434 [select]: net/http.(*persistConn).writeLoop.(*persistConn).waitForContinue.func1() /usr/local/go/src/net/http/transport.go:2534 +0xd9 net/http.(*Request).write(0xc0008e0640, {0x2acb240, 0xc000857380}, 0x0, 0xc000371650, 0xc000094f30) /usr/local/go/src/net/http/request.go:747 +0x9cb net/http.(*persistConn).writeLoop(0xc0005570e0) /usr/local/go/src/net/http/transport.go:2593 +0x174 created by net/http.(*Transport).dialConn in goroutine 12387 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 11467 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc001a9c3c0, {0x2af3948, 0xc000695ae0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 11519 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x117 goroutine 12506 [select]: net/http.(*persistConn).writeLoop(0xc000557e60) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 12487 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 11865 [IO wait]: internal/poll.runtime_pollWait(0x7247b04433a8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001a5c680?, 0xc0006b2000?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc001a5c680, {0xc0006b2000, 0x3500, 0x3500}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001a5c680, {0xc0006b2000?, 0x34f3?, 0xc0021aece0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00008a638, {0xc0006b2000?, 0xc000626550?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001f85e18, {0xc0006b2000?, 0x724768668698?, 0x7247b0506a78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc001f85278, {0xc0006b2000?, 0x34f3?, 0xc0006e19c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000626638, {0x2ace280, 0xc001f85278}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000626388, {0x7247684f0d80, 0xc001f85e18}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000626388, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000626388, {0xc0003f7000, 0x1000, 0xc00032f620?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000948d80, {0xc0003f7000?, 0x7f4ac5?, 0x2acb1a0?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001fd7440) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001fd7440, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000948d80) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 11841 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 12350 [select]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc00065a1e0, 0xc0000f5e30, 0xc000292f00) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:425 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc00065a1e0, {0x2af3948, 0xc000695950}, {0xc001a8c140, 0x4f}, {{0xc0019ed650, 0x5}, {0xc0019ed656, 0x23}, {0x2679d24, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:777 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc00065a1e0, {0x2af3948, 0xc000695950}, {{0xc0019ed650, 0x5}, {0xc0019ed656, 0x23}, {0x2679d24, 0x4}, 0x0, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:847 +0x151 github.com/ncw/swift/v2.(*Connection).objectBase(0x41a68c?, {0x2af3948?, 0xc000695950?}, {0xc0019ed650?, 0x7247b0511330?}, {0xc0019ed656, 0x23}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:2143 +0xf8 github.com/rclone/rclone/backend/swift.(*Object).readMetaData.func1.(*Connection).Object.1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:2132 +0x85 github.com/ncw/swift/v2.withLORetry(0x0, 0xc000861258) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/largeobjects.go:303 +0x9f github.com/ncw/swift/v2.(*Connection).Object(...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:2131 github.com/rclone/rclone/backend/swift.(*Object).readMetaData.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1334 +0x156 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0xa, 0xc000053808?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc001b00d80, 0xc001fd6ba0, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc001b00d80, 0xc001fd6ba0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:216 +0x93 github.com/rclone/rclone/backend/swift.(*Object).readMetaData(0xc001e44a80, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1333 +0x165 github.com/rclone/rclone/backend/swift.(*Object).hasHeader(0xc001e44a80, {0x2af3948?, 0xc000695950?}, {0x26ae644, 0x15}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1246 +0x30 github.com/rclone/rclone/backend/swift.(*Object).isLargeObject(0xc001e44a80, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1277 +0x48 github.com/rclone/rclone/backend/swift.(*Object).Update(0xc001e44a80, {0x2af3948, 0xc000695950}, {0x2acbae0, 0xc0019d5bf0}, {0x2afeae0, 0xc000375290}, {0xc002136990, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1523 +0x1c8 github.com/rclone/rclone/backend/crypt.(*Object).Update.func1({0x2af3948?, 0xc000695950?}, {0x2acbae0?, 0xc0019d5bf0?}, {0x2afeae0?, 0xc000375290?}, {0xc002136990?, 0xc000900b00?, 0x41acfb?}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:1091 +0x7c github.com/rclone/rclone/backend/crypt.(*Fs).put(0xc001b3cb60, {0x2af3948, 0xc000695950}, {0x2acbba0, 0xc00038a800}, {0x724768667c68, 0xc0007c51a0}, {0xc002136990, 0x1, 0x1}, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:536 +0x3c3 github.com/rclone/rclone/backend/crypt.(*Object).Update(0x2adeef0?, {0x2af3948?, 0xc000695950?}, {0x2acbba0?, 0xc00038a800?}, {0x724768667c68?, 0xc0007c51a0?}, {0xc002136990, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:1093 +0x85 github.com/rclone/rclone/fs/operations.(*copy).updateOrPut(0xc0005efc20, {0x2af3948, 0xc000695950}, {0x2adeef0?, 0xc0019dc380?}, {0xc002136990, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:212 +0x1a5 github.com/rclone/rclone/fs/operations.(*copy).manualCopy(0xc0005efc20, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:271 +0x6d8 github.com/rclone/rclone/fs/operations.(*copy).copy(0xc0005efc20, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:311 +0x170 github.com/rclone/rclone/fs/operations.Copy({0x2af3948, 0xc000695950}, {0x2b07fe0, 0xc001b3cb60}, {0x2b08050, 0xc000011650}, {0xc0021b3d28, 0x6}, {0x2b09d30, 0xc0007c51a0}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:413 +0x4db github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairCopyOrMove(0xc0002a4a08, {0x2af3948, 0xc000695950}, 0xc0006958b0, {0x2b07fe0, 0xc001b3cb60}, 0x4b, 0xc0006a45c0?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:505 +0x1fe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startTransfers in goroutine 12174 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:535 +0x7d goroutine 12401 [IO wait]: internal/poll.runtime_pollWait(0x7247b04436f0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000630200?, 0xc00072ec80?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000630200, {0xc00072ec80, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000630200, {0xc00072ec80?, 0x1973?, 0xc0019a5b40?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00008a4d8, {0xc00072ec80?, 0xc000000550?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001f1d680, {0xc00072ec80?, 0x724768668698?, 0x7247b0506a78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc001f84d08, {0xc00072ec80?, 0x1973?, 0x0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000000638, {0x2ace280, 0xc001f84d08}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000000388, {0x7247684f0d80, 0xc001f1d680}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000000388, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000000388, {0xc00088f000, 0x1000, 0xc00032f620?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0005570e0, {0xc00088f000?, 0x7f4ac5?, 0x2acb1a0?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc002081ec0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc002081ec0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0005570e0) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 12387 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 12460 [chan receive]: github.com/rclone/rclone/backend/local.(*fadvise).worker(0xc000900a80) /home/rclone/go/src/github.com/rclone/rclone/backend/local/fadvise_unix.go:114 +0x3f created by github.com/rclone/rclone/backend/local.newFadvise in goroutine 12347 /home/rclone/go/src/github.com/rclone/rclone/backend/local/fadvise_unix.go:74 +0x11f goroutine 12399 [IO wait]: internal/poll.runtime_pollWait(0x7247b04435d8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001c20580?, 0xc00072d300?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc001c20580, {0xc00072d300, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001c20580, {0xc00072d300?, 0x1973?, 0xc0019a5900?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0003a05c0, {0xc00072d300?, 0xc00069e1d0?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0019d5470, {0xc00072d300?, 0x7247684668b8?, 0x7247b0506108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0019d57d0, {0xc00072d300?, 0x1973?, 0x8155dc?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc00069e2b8, {0x2ace280, 0xc0019d57d0}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc00069e008, {0x7247684f0d80, 0xc0019d5470}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc00069e008, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc00069e008, {0xc0004e3000, 0x1000, 0xc00032f620?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0003e0900, {0xc0004e3000?, 0x7f4ac5?, 0x2acb1a0?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc002081d40) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc002081d40, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0003e0900) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 12364 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 12501 [select]: github.com/rclone/rclone/fs/accounting.(*Account).averageLoop(0xc00038a800) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:221 +0xed created by github.com/rclone/rclone/fs/accounting.newAccountSizeName in goroutine 12350 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:119 +0x436 goroutine 12499 [select]: net/http.(*persistConn).roundTrip(0xc000557440, 0xc000796550) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc0001844e0, 0xc0002928c0) /usr/local/go/src/net/http/transport.go:696 +0xb9a net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0004381c0, 0xc0002928c0) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 net/http.send(0xc0002928c0, {0x2accd20, 0xc0004381c0}, {0x1?, 0x2?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc0019e5d70, 0xc0002928c0, {0xc001b57c00?, 0xc0005b5be0?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc0019e5d70, 0xc0002928c0) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 github.com/ncw/swift/v2.(*Connection).doTimeoutRequest.func1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:421 +0x2b created by github.com/ncw/swift/v2.(*Connection).doTimeoutRequest in goroutine 12348 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:420 +0xb8 goroutine 12347 [select]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc00065a1e0, 0xc00046d5e0, 0xc0008e0500) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:425 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc00065a1e0, {0x2af3948, 0xc000695950}, {0xc001a8c140, 0x4f}, {{0xc0019ed2c0, 0x5}, {0xc0019ed2c6, 0x22}, {0x2678d24, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:777 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc00065a1e0, {0x2af3948, 0xc000695950}, {{0xc0019ed2c0, 0x5}, {0xc0019ed2c6, 0x22}, {0x2678d24, 0x3}, 0x0, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:847 +0x151 github.com/ncw/swift/v2.(*Connection).objectPut(0xc00065a1e0, {0x2af3948, 0xc000695950}, {0xc0019ed2c0, 0x5}, {0xc0019ed2c6, 0x22}, {0x2acbae0, 0xc0019d59c8}, 0x1, ...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:1569 +0x1f5 github.com/ncw/swift/v2.(*Connection).ObjectPut(...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:1615 github.com/rclone/rclone/backend/swift.(*Object).Update.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1559 +0xb0 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0x1, 0x0?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc001b00d80, 0xc001e80780, 0x1) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).CallNoRetry(...) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:225 github.com/rclone/rclone/backend/swift.(*Object).Update(0xc001e44900, {0x2af3948, 0xc000695950}, {0x2acbae0, 0xc0019d59c8}, {0x2afeae0, 0xc000374cf0}, {0xc002136640, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1558 +0x7b8 github.com/rclone/rclone/backend/crypt.(*Object).Update.func1({0x2af3948?, 0xc000695950?}, {0x2acbae0?, 0xc0019d59c8?}, {0x2afeae0?, 0xc000374cf0?}, {0xc002136640?, 0xc000900a80?, 0x41acfb?}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:1091 +0x7c github.com/rclone/rclone/backend/crypt.(*Fs).put(0xc001b3cb60, {0x2af3948, 0xc000695950}, {0x2acbba0, 0xc00038a600}, {0x724768667c68, 0xc0007c5140}, {0xc002136640, 0x1, 0x1}, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:536 +0x3c3 github.com/rclone/rclone/backend/crypt.(*Object).Update(0x2adeef0?, {0x2af3948?, 0xc000695950?}, {0x2acbba0?, 0xc00038a600?}, {0x724768667c68?, 0xc0007c5140?}, {0xc002136640, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:1093 +0x85 github.com/rclone/rclone/fs/operations.(*copy).updateOrPut(0xc0005ef7a0, {0x2af3948, 0xc000695950}, {0x2adeef0?, 0xc0019dc1c0?}, {0xc002136640, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:212 +0x1a5 github.com/rclone/rclone/fs/operations.(*copy).manualCopy(0xc0005ef7a0, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:271 +0x6d8 github.com/rclone/rclone/fs/operations.(*copy).copy(0xc0005ef7a0, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:311 +0x170 github.com/rclone/rclone/fs/operations.Copy({0x2af3948, 0xc000695950}, {0x2b07fe0, 0xc001b3cb60}, {0x2b08050, 0xc000011518}, {0xc0021b3d06, 0x5}, {0x2b09d30, 0xc0007c5140}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:413 +0x4db github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairCopyOrMove(0xc0002a4a08, {0x2af3948, 0xc000695950}, 0xc0006958b0, {0x2b07fe0, 0xc001b3cb60}, 0x0, 0xc0007d8f98?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:505 +0x1fe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startTransfers in goroutine 12174 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:535 +0x7d goroutine 1964 [chan receive, 53 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29 created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 2067 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 12464 [chan receive]: github.com/rclone/rclone/backend/local.(*fadvise).worker(0xc000900ac0) /home/rclone/go/src/github.com/rclone/rclone/backend/local/fadvise_unix.go:114 +0x3f created by github.com/rclone/rclone/backend/local.newFadvise in goroutine 12348 /home/rclone/go/src/github.com/rclone/rclone/backend/local/fadvise_unix.go:74 +0x11f goroutine 12500 [chan receive]: github.com/rclone/rclone/backend/local.(*fadvise).worker(0xc000900b00) /home/rclone/go/src/github.com/rclone/rclone/backend/local/fadvise_unix.go:114 +0x3f created by github.com/rclone/rclone/backend/local.newFadvise in goroutine 12350 /home/rclone/go/src/github.com/rclone/rclone/backend/local/fadvise_unix.go:74 +0x11f goroutine 12440 [IO wait]: internal/poll.runtime_pollWait(0x7247b0443178, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001e80600?, 0xc0001d2c80?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc001e80600, {0xc0001d2c80, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001e80600, {0xc0001d2c80?, 0x231ac40?, 0xc0008c68b0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00008a4e8, {0xc0001d2c80?, 0xc00064b0e0?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001f1d710, {0xc0001d2c80?, 0x724768668698?, 0x7247b0506a78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc001f84798, {0xc0001d2c80?, 0xc0001844e0?, 0xa05e0b?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000000d38, {0x2ace280, 0xc001f84798}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000000a88, {0x7247684f0d80, 0xc001f1d710}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000000a88, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000000a88, {0xc000895000, 0x1000, 0x7247b0506a78?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000557440, {0xc000895000?, 0x412565?, 0x68?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001a0d260) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001a0d260, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000557440) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 12394 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 12465 [select]: github.com/rclone/rclone/fs/accounting.(*Account).averageLoop(0xc00038a700) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:221 +0xed created by github.com/rclone/rclone/fs/accounting.newAccountSizeName in goroutine 12348 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:119 +0x436 goroutine 12456 [select]: net/http.(*persistConn).roundTrip(0xc0003e0900, 0xc000796230) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc0001844e0, 0xc000292500) /usr/local/go/src/net/http/transport.go:696 +0xb9a net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0004381c0, 0xc000292500) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 net/http.send(0xc000292500, {0x2accd20, 0xc0004381c0}, {0xc001d27501?, 0xc001d275a0?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc0019e5d70, 0xc000292500, {0x0?, 0x0?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc0019e5d70, 0xc000292500) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 github.com/ncw/swift/v2.(*Connection).doTimeoutRequest.func1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:421 +0x2b created by github.com/ncw/swift/v2.(*Connection).doTimeoutRequest in goroutine 12346 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:420 +0xb8 goroutine 11866 [select]: net/http.(*persistConn).writeLoop(0xc000948d80) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 11841 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 12495 [select]: net/http.(*persistConn).roundTrip(0xc0005570e0, 0xc0008e4500) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc0001844e0, 0xc0008e0500) /usr/local/go/src/net/http/transport.go:696 +0xb9a net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0004381c0, 0xc0008e0500) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 net/http.send(0xc0008e0500, {0x2accd20, 0xc0004381c0}, {0xc00229d501?, 0xc00229d5a0?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc0019e5d70, 0xc0008e0500, {0x0?, 0x0?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc0019e5d70, 0xc0008e0500) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 github.com/ncw/swift/v2.(*Connection).doTimeoutRequest.func1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:421 +0x2b created by github.com/ncw/swift/v2.(*Connection).doTimeoutRequest in goroutine 12347 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:420 +0xb8 goroutine 12346 [select]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc00065a1e0, 0xc000505030, 0xc000292500) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:425 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc00065a1e0, {0x2af3948, 0xc000695950}, {0xc001a8c140, 0x4f}, {{0xc0019ed020, 0x5}, {0xc0019ed026, 0x22}, {0x2679d24, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:777 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc00065a1e0, {0x2af3948, 0xc000695950}, {{0xc0019ed020, 0x5}, {0xc0019ed026, 0x22}, {0x2679d24, 0x4}, 0x0, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:847 +0x151 github.com/ncw/swift/v2.(*Connection).objectBase(0x0?, {0x2af3948?, 0xc000695950?}, {0xc0019ed020?, 0x0?}, {0xc0019ed026, 0x22}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:2143 +0xf8 github.com/rclone/rclone/backend/swift.(*Object).readMetaData.func1.(*Connection).Object.1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:2132 +0x85 github.com/ncw/swift/v2.withLORetry(0x0, 0xc0007ab850) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/largeobjects.go:303 +0x9f github.com/ncw/swift/v2.(*Connection).Object(...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:2131 github.com/rclone/rclone/backend/swift.(*Object).readMetaData.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1334 +0x156 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0xa, 0xc0002ec808?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc001b00d80, 0xc001fd68a0, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc001b00d80, 0xc001fd68a0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:216 +0x93 github.com/rclone/rclone/backend/swift.(*Object).readMetaData(0xc001e44780, {0x2af3948, 0xc000695950}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1333 +0x165 github.com/rclone/rclone/backend/swift.(*Object).ModTime(0xc001e44780, {0x2af3948?, 0xc000695950?}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1359 +0x3d github.com/rclone/rclone/fs/operations.equal({0x2af3948, 0xc000695950}, {0x724768667c68, 0xc0007c47e0}, {0x2b08050, 0xc000011140}, {0x0, 0x0, 0x1, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:289 +0x618 github.com/rclone/rclone/fs/operations.Equal({0x2af3948, 0xc000695950}, {0x724768667c68, 0xc0007c47e0}, {0x2b08050, 0xc000011140}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:143 +0x77 github.com/rclone/rclone/fs/operations.NeedTransfer({0x2af3948, 0xc000695950}, {0x2b08050, 0xc000011140}, {0x2b09d30, 0xc0007c47e0}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:1782 +0x595 github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairChecker(0xc0002a4a08, 0xc000695860, 0xc0006958b0, 0x4b, 0xc0007dbfa8?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:383 +0x1a9 created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startCheckers in goroutine 12174 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:519 +0x67 goroutine 12441 [select]: net/http.(*persistConn).writeLoop(0xc000557440) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 12394 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 12420 [select]: net/http.(*persistConn).writeLoop(0xc0006bc240) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 11905 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished ERROR in 1h0m11.716522897s (try 1/5): exit status 2: Failed []