"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2024/09/15 04:14:48 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time" 2024/09/15 04:14:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/15 04:14:49 DEBUG : Creating backend with remote "/tmp/rclone3024799490" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:14:50 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:14:50 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2024/09/15 04:14:50 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:14:50 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestCopyWithDryRun (2.06s) === RUN TestCopy run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:14:51 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/15 04:14:51 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:14:51 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:14:51 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:14:52 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:14:52 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:14:52 DEBUG : B2 bucket rclone-test-yevubib6time: Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (2.34s) === RUN TestCopyMetadata run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.43s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.36s) === RUN TestCopyMissingDirectory run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:14:54 DEBUG : Creating backend with remote "/non-existing" 2024/09/15 04:14:54 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2024/09/15 04:14:54 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:14:54 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestCopyMissingDirectory (1.00s) === RUN TestCopyNoTraverse run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:14:56 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:14:56 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:14:56 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:14:56 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:14:56 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestCopyNoTraverse (1.93s) === RUN TestCopyCheckFirst run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:14:57 INFO : B2 bucket rclone-test-yevubib6time: Running all checks before starting transfers 2024/09/15 04:14:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:14:57 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:14:57 INFO : B2 bucket rclone-test-yevubib6time: Checks finished, now starting transfers 2024/09/15 04:14:57 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:14:58 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:14:58 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestCopyCheckFirst (1.81s) === RUN TestSyncNoTraverse run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:14:59 ERROR : Ignoring --no-traverse with sync 2024/09/15 04:14:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:14:59 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:14:59 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:14:59 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:14:59 INFO : sub dir/hello world: Copied (new) 2024/09/15 04:14:59 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncNoTraverse (1.75s) === RUN TestCopyWithDepth run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:01 DEBUG : hello world2: Need to transfer - File not found at Destination 2024/09/15 04:15:01 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:01 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:01 DEBUG : hello world2: sha1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK 2024/09/15 04:15:01 INFO : hello world2: Copied (new) run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestCopyWithDepth (1.66s) === RUN TestCopyWithFilesFrom run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:02 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/15 04:15:02 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:02 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:03 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:15:03 INFO : potato2: Copied (new) run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestCopyWithFilesFrom (1.61s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:04 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/15 04:15:04 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:04 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:05 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:15:05 INFO : potato2: Copied (new) run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestCopyWithFilesFromAndNoTraverse (2.07s) === RUN TestCopyEmptyDirectories run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:06 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2024/09/15 04:15:06 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/09/15 04:15:06 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2024/09/15 04:15:06 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/15 04:15:06 DEBUG : sub dir: Making directory 2024/09/15 04:15:06 DEBUG : sub dir2: Making directory 2024/09/15 04:15:06 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:15:06 DEBUG : sub dir2/sub sub dir2: Making directory 2024/09/15 04:15:06 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:06 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:07 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:15:07 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" fstest.go:244: Filtering empty directory "sub dir2" fstest.go:244: Filtering empty directory "sub dir2/sub sub dir2" 2024/09/15 04:15:07 DEBUG : B2 bucket rclone-test-yevubib6time: Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (1.84s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:08 DEBUG : sub dir2: Making directory 2024/09/15 04:15:08 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2024/09/15 04:15:08 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/09/15 04:15:08 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:15:08 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:08 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:08 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:15:08 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (1.23s) === RUN TestMoveEmptyDirectories run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:09 DEBUG : sub dir2: Making directory with metadata 2024/09/15 04:15:09 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/09/15 04:15:09 DEBUG : sub dir: Making directory 2024/09/15 04:15:09 DEBUG : sub dir2: Making directory 2024/09/15 04:15:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:15:09 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:09 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:10 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:15:10 INFO : sub dir/hello world: Copied (new) 2024/09/15 04:15:10 INFO : sub dir/hello world: Deleted run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" fstest.go:244: Filtering empty directory "sub dir2" 2024/09/15 04:15:10 DEBUG : B2 bucket rclone-test-yevubib6time: Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2024/09/15 04:15:10 DEBUG : B2 bucket rclone-test-yevubib6time: Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (1.87s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" sync_test.go:408: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.36s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:11 DEBUG : sub dir2: Making directory 2024/09/15 04:15:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:15:11 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:11 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:12 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:15:12 INFO : sub dir/hello world: Copied (new) 2024/09/15 04:15:12 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (1.42s) === RUN TestSyncEmptyDirectories run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:13 DEBUG : sub dir2: Making directory with metadata 2024/09/15 04:15:13 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/09/15 04:15:13 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/15 04:15:13 DEBUG : sub dir: Making directory 2024/09/15 04:15:13 DEBUG : sub dir2: Making directory 2024/09/15 04:15:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:15:13 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:13 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:13 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:15:13 INFO : sub dir/hello world: Copied (new) 2024/09/15 04:15:13 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" fstest.go:244: Filtering empty directory "sub dir2" 2024/09/15 04:15:13 DEBUG : B2 bucket rclone-test-yevubib6time: Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (1.63s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.36s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:15 DEBUG : sub dir2: Making directory 2024/09/15 04:15:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:15:15 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:15 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:16 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:15:16 INFO : sub dir/hello world: Copied (new) 2024/09/15 04:15:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (1.74s) === RUN TestServerSideCopy run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:17 DEBUG : Creating backend with remote "TestB2:rclone-test-lehepod9pexu" sync_test.go:590: Server side copy (if possible) B2 bucket rclone-test-yevubib6time -> B2 bucket rclone-test-lehepod9pexu 2024/09/15 04:15:18 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:15:18 DEBUG : B2 bucket rclone-test-lehepod9pexu: Waiting for checks to finish 2024/09/15 04:15:18 DEBUG : B2 bucket rclone-test-lehepod9pexu: Waiting for transfers to finish 2024/09/15 04:15:20 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:15:20 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:20 DEBUG : B2 bucket rclone-test-lehepod9pexu: Purge remote 2024/09/15 04:15:20 INFO : B2 bucket rclone-test-lehepod9pexu: cleaning bucket "rclone-test-lehepod9pexu" of all files 2024/09/15 04:15:21 DEBUG : sub dir/hello world: Deleting (id "4_z9fe26db3e04dfb3b9f180e1b_f11022c916ed60194_d20240915_m041520_c001_v0001098_t0006_u01726373720212") --- PASS: TestServerSideCopy (6.31s) === RUN TestCopyAfterDelete run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:23 ERROR : : error listing: directory not found 2024/09/15 04:15:24 DEBUG : Local file system at /tmp/rclone3024799490: Making directory 2024/09/15 04:15:24 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:24 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestCopyAfterDelete (2.37s) === RUN TestCopyRedownload run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:15:26 DEBUG : Local file system at /tmp/rclone3024799490: Waiting for checks to finish 2024/09/15 04:15:26 DEBUG : Local file system at /tmp/rclone3024799490: Waiting for transfers to finish 2024/09/15 04:15:26 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:15:26 DEBUG : sub dir/hello world.655e7eb.partial: renamed to: sub dir/hello world 2024/09/15 04:15:26 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestCopyRedownload (2.40s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:28 DEBUG : check sum: Need to transfer - File not found at Destination 2024/09/15 04:15:28 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:28 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:28 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2024/09/15 04:15:28 INFO : check sum: Copied (new) 2024/09/15 04:15:28 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:28 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:28 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2024/09/15 04:15:28 DEBUG : check sum: Size and sha1 of src and dst objects identical 2024/09/15 04:15:28 DEBUG : check sum: Unchanged skipping 2024/09/15 04:15:28 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:28 DEBUG : Waiting for deletions to finish 2024/09/15 04:15:28 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncBasedOnCheckSum (2.42s) === RUN TestSyncSizeOnly run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:30 DEBUG : sizeonly: Need to transfer - File not found at Destination 2024/09/15 04:15:30 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:30 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:30 DEBUG : sizeonly: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2024/09/15 04:15:30 INFO : sizeonly: Copied (new) 2024/09/15 04:15:30 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:31 DEBUG : sizeonly: Sizes identical 2024/09/15 04:15:31 DEBUG : sizeonly: Unchanged skipping 2024/09/15 04:15:31 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:31 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:31 DEBUG : Waiting for deletions to finish 2024/09/15 04:15:31 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncSizeOnly (2.78s) === RUN TestSyncIgnoreSize run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:33 DEBUG : ignore-size: Need to transfer - File not found at Destination 2024/09/15 04:15:33 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:33 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:33 DEBUG : ignore-size: sha1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK 2024/09/15 04:15:33 INFO : ignore-size: Copied (new) 2024/09/15 04:15:33 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:34 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:34 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:15:34 DEBUG : ignore-size: Unchanged skipping 2024/09/15 04:15:34 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:34 DEBUG : Waiting for deletions to finish 2024/09/15 04:15:34 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncIgnoreSize (2.88s) === RUN TestSyncIgnoreTimes run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:36 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:15:36 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:36 DEBUG : existing: Unchanged skipping 2024/09/15 04:15:36 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:36 DEBUG : Waiting for deletions to finish 2024/09/15 04:15:36 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:36 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2024/09/15 04:15:36 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:36 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:37 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2024/09/15 04:15:37 INFO : existing: Copied (replaced existing) 2024/09/15 04:15:37 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncIgnoreTimes (2.79s) === RUN TestSyncIgnoreExisting run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:38 DEBUG : existing: Need to transfer - File not found at Destination 2024/09/15 04:15:38 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:38 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:39 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2024/09/15 04:15:39 INFO : existing: Copied (new) 2024/09/15 04:15:39 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:39 DEBUG : existing: Destination exists, skipping 2024/09/15 04:15:39 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:39 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:39 DEBUG : Waiting for deletions to finish 2024/09/15 04:15:39 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncIgnoreExisting (2.50s) === RUN TestSyncIgnoreErrors run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:42 DEBUG : d: Making directory fstest.go:244: Filtering empty directory "d" 2024/09/15 04:15:42 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/09/15 04:15:42 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:15:42 DEBUG : c/non empty space: Unchanged skipping 2024/09/15 04:15:42 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:42 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:42 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/09/15 04:15:42 INFO : a/potato2: Copied (new) 2024/09/15 04:15:42 DEBUG : Waiting for deletions to finish 2024/09/15 04:15:43 INFO : b/potato: Deleted run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncIgnoreErrors (3.29s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:45 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:45 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2024/09/15 04:15:45 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2024/09/15 04:15:45 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2024/09/15 04:15:45 DEBUG : empty space: Unchanged skipping 2024/09/15 04:15:45 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:45 DEBUG : Waiting for deletions to finish 2024/09/15 04:15:45 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:45 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2024/09/15 04:15:45 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:45 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2024/09/15 04:15:47 INFO : empty space: Updated modification time in destination 2024/09/15 04:15:47 DEBUG : empty space: Unchanged skipping 2024/09/15 04:15:47 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:47 DEBUG : Waiting for deletions to finish 2024/09/15 04:15:47 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncAfterChangingModtimeOnly (4.13s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:49 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2024/09/15 04:15:49 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:49 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2024/09/15 04:15:49 DEBUG : empty space: Unchanged skipping 2024/09/15 04:15:49 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:49 DEBUG : Waiting for deletions to finish 2024/09/15 04:15:49 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (2.17s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:51 DEBUG : foo: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2024/09/15 04:15:51 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:51 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone3024799490) 2024/09/15 04:15:51 DEBUG : foo: sha1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-yevubib6time) 2024/09/15 04:15:51 DEBUG : foo: sha1 differ 2024/09/15 04:15:51 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:51 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK 2024/09/15 04:15:51 INFO : foo: Copied (replaced existing) 2024/09/15 04:15:51 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncDoesntUpdateModtime (1.84s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:53 DEBUG : potato: Need to transfer - File not found at Destination 2024/09/15 04:15:53 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:15:53 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:53 DEBUG : empty space: Unchanged skipping 2024/09/15 04:15:53 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:54 DEBUG : potato: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/09/15 04:15:54 INFO : potato: Copied (new) 2024/09/15 04:15:54 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncAfterAddingAFile (2.59s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:56 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2024/09/15 04:15:56 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:56 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:56 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK 2024/09/15 04:15:56 INFO : potato: Copied (replaced existing) 2024/09/15 04:15:56 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncAfterChangingFilesSizeOnly (2.33s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:15:58 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:15:58 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2024/09/15 04:15:58 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone3024799490) 2024/09/15 04:15:58 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-yevubib6time) 2024/09/15 04:15:58 DEBUG : potato: sha1 differ 2024/09/15 04:15:58 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:15:58 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK 2024/09/15 04:15:58 INFO : potato: Copied (replaced existing) 2024/09/15 04:15:58 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncAfterChangingContentsOnly (2.22s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:00 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/15 04:16:00 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2024/09/15 04:16:00 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:00 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:16:00 DEBUG : empty space: Unchanged skipping 2024/09/15 04:16:00 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:00 DEBUG : Waiting for deletions to finish 2024/09/15 04:16:00 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (2.17s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:02 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/15 04:16:02 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:16:02 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:02 DEBUG : empty space: Unchanged skipping 2024/09/15 04:16:02 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:03 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/09/15 04:16:03 INFO : potato2: Copied (new) 2024/09/15 04:16:03 DEBUG : Waiting for deletions to finish 2024/09/15 04:16:03 INFO : potato: Deleted run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (2.78s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:05 DEBUG : d: Making directory 2024/09/15 04:16:05 DEBUG : d/e: Making directory fstest.go:244: Filtering empty directory "d" fstest.go:244: Filtering empty directory "d/e" 2024/09/15 04:16:06 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/09/15 04:16:06 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:16:06 DEBUG : c/non empty space: Unchanged skipping 2024/09/15 04:16:06 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:06 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:06 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/09/15 04:16:06 INFO : a/potato2: Copied (new) 2024/09/15 04:16:06 DEBUG : Waiting for deletions to finish 2024/09/15 04:16:06 INFO : b/potato: Deleted run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (3.11s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:08 DEBUG : d: Making directory fstest.go:244: Filtering empty directory "d" 2024/09/15 04:16:09 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/09/15 04:16:09 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:16:09 DEBUG : c/non empty space: Unchanged skipping 2024/09/15 04:16:09 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:09 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:09 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/09/15 04:16:09 INFO : a/potato2: Copied (new) 2024/09/15 04:16:09 ERROR : B2 bucket rclone-test-yevubib6time: not deleting files as there were IO errors 2024/09/15 04:16:09 ERROR : B2 bucket rclone-test-yevubib6time: not deleting directories as there were IO errors run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" fstest.go:244: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (3.43s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:12 DEBUG : Waiting for deletions to finish 2024/09/15 04:16:12 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/15 04:16:12 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:16:12 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:12 DEBUG : empty space: Unchanged skipping 2024/09/15 04:16:12 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:12 INFO : potato: Deleted 2024/09/15 04:16:13 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/09/15 04:16:13 INFO : potato2: Copied (new) run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncDeleteDuring (3.13s) === RUN TestSyncDeleteBefore run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:15 DEBUG : Waiting for deletions to finish 2024/09/15 04:16:15 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:15 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:15 INFO : potato: Deleted 2024/09/15 04:16:16 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/15 04:16:16 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:16:16 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:16 DEBUG : empty space: Unchanged skipping 2024/09/15 04:16:16 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:16 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/09/15 04:16:16 INFO : potato2: Copied (new) run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncDeleteBefore (3.61s) === RUN TestCopyDeleteBefore run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:18 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/15 04:16:18 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:18 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:19 DEBUG : potato2: sha1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK 2024/09/15 04:16:19 INFO : potato2: Copied (new) run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestCopyDeleteBefore (2.51s) === RUN TestSyncWithExclude run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:21 DEBUG : enormous: Excluded (Size Filter) 2024/09/15 04:16:21 DEBUG : enormous: Excluded 2024/09/15 04:16:21 DEBUG : potato2: Excluded (Size Filter) 2024/09/15 04:16:21 DEBUG : potato2: Excluded 2024/09/15 04:16:22 DEBUG : potato2: Excluded (Size Filter) 2024/09/15 04:16:22 DEBUG : potato2: Excluded 2024/09/15 04:16:22 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:16:22 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:22 DEBUG : empty space: Unchanged skipping 2024/09/15 04:16:22 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:22 DEBUG : Waiting for deletions to finish 2024/09/15 04:16:22 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:22 DEBUG : enormous: Excluded (Size Filter) 2024/09/15 04:16:22 DEBUG : enormous: Excluded 2024/09/15 04:16:22 DEBUG : potato2: Excluded (Size Filter) 2024/09/15 04:16:22 DEBUG : potato2: Excluded 2024/09/15 04:16:22 DEBUG : potato2: Excluded (Size Filter) 2024/09/15 04:16:22 DEBUG : potato2: Excluded 2024/09/15 04:16:22 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2024/09/15 04:16:22 DEBUG : empty space: Unchanged skipping 2024/09/15 04:16:22 DEBUG : Local file system at /tmp/rclone3024799490: Waiting for checks to finish 2024/09/15 04:16:22 DEBUG : Local file system at /tmp/rclone3024799490: Waiting for transfers to finish 2024/09/15 04:16:22 DEBUG : Waiting for deletions to finish 2024/09/15 04:16:22 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncWithExclude (3.37s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:25 DEBUG : enormous: Excluded (Size Filter) 2024/09/15 04:16:25 DEBUG : enormous: Excluded 2024/09/15 04:16:25 DEBUG : potato2: Excluded (Size Filter) 2024/09/15 04:16:25 DEBUG : potato2: Excluded 2024/09/15 04:16:25 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:16:25 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:25 DEBUG : empty space: Unchanged skipping 2024/09/15 04:16:25 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:25 DEBUG : Waiting for deletions to finish 2024/09/15 04:16:25 INFO : potato2: Deleted 2024/09/15 04:16:26 INFO : enormous: Deleted 2024/09/15 04:16:26 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:26 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2024/09/15 04:16:26 DEBUG : empty space: Unchanged skipping 2024/09/15 04:16:26 DEBUG : Local file system at /tmp/rclone3024799490: Waiting for checks to finish 2024/09/15 04:16:26 DEBUG : Local file system at /tmp/rclone3024799490: Waiting for transfers to finish 2024/09/15 04:16:26 DEBUG : Waiting for deletions to finish 2024/09/15 04:16:26 INFO : enormous: Deleted 2024/09/15 04:16:26 INFO : potato2: Deleted 2024/09/15 04:16:26 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncWithExcludeAndDeleteExcluded (3.85s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:29 DEBUG : five: Need to transfer - File not found at Destination 2024/09/15 04:16:29 DEBUG : four: Sizes differ (src 4 vs dst 8) 2024/09/15 04:16:29 DEBUG : one: Destination is newer than source, skipping 2024/09/15 04:16:29 DEBUG : three: Sizes identical 2024/09/15 04:16:29 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:29 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2024/09/15 04:16:29 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone3024799490) 2024/09/15 04:16:29 DEBUG : two: sha1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-yevubib6time) 2024/09/15 04:16:29 DEBUG : two: sha1 differ 2024/09/15 04:16:29 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2024/09/15 04:16:29 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:29 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/09/15 04:16:29 INFO : five: Copied (new) 2024/09/15 04:16:29 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/09/15 04:16:29 INFO : four: Copied (replaced existing) 2024/09/15 04:16:30 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/09/15 04:16:30 INFO : two: Copied (replaced existing) 2024/09/15 04:16:30 DEBUG : Waiting for deletions to finish 2024/09/15 04:16:30 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:30 DEBUG : one: Destination is newer than source, skipping 2024/09/15 04:16:30 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/09/15 04:16:30 DEBUG : four: Size and sha1 of src and dst objects identical 2024/09/15 04:16:30 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2024/09/15 04:16:30 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone3024799490) 2024/09/15 04:16:30 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/09/15 04:16:30 DEBUG : three: sha1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-yevubib6time) 2024/09/15 04:16:30 DEBUG : two: Size and sha1 of src and dst objects identical 2024/09/15 04:16:30 DEBUG : three: sha1 differ 2024/09/15 04:16:30 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2024/09/15 04:16:30 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/09/15 04:16:30 DEBUG : five: Size and sha1 of src and dst objects identical 2024/09/15 04:16:30 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2024/09/15 04:16:30 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2024/09/15 04:16:30 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:30 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/09/15 04:16:30 INFO : three: Copied (replaced existing) 2024/09/15 04:16:30 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (4.66s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1364: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1364: 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 "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" sync_test.go:1436: Can track renames: true 2024/09/15 04:16:32 INFO : B2 bucket rclone-test-yevubib6time: Making map for --track-renames 2024/09/15 04:16:32 INFO : B2 bucket rclone-test-yevubib6time: Finished making map for --track-renames 2024/09/15 04:16:32 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:32 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for renames to finish 2024/09/15 04:16:32 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/09/15 04:16:32 DEBUG : yam: Need to transfer - No matching file found at Destination 2024/09/15 04:16:32 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:33 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2024/09/15 04:16:33 INFO : yam: Copied (new) 2024/09/15 04:16:34 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2024/09/15 04:16:34 INFO : potato: Copied (new) 2024/09/15 04:16:34 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:34 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:16:34 INFO : B2 bucket rclone-test-yevubib6time: Making map for --track-renames 2024/09/15 04:16:34 INFO : B2 bucket rclone-test-yevubib6time: Finished making map for --track-renames 2024/09/15 04:16:34 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:34 DEBUG : potato: Unchanged skipping 2024/09/15 04:16:34 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for renames to finish 2024/09/15 04:16:35 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2024/09/15 04:16:35 INFO : yam: Copied (server-side copy) to: yaml 2024/09/15 04:16:35 INFO : yam: Deleted 2024/09/15 04:16:35 INFO : yaml: Renamed from "yam" 2024/09/15 04:16:35 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:35 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncWithTrackRenames (4.66s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" sync_test.go:1508: Can track renames: true 2024/09/15 04:16:37 INFO : B2 bucket rclone-test-yevubib6time: Making map for --track-renames 2024/09/15 04:16:37 INFO : B2 bucket rclone-test-yevubib6time: Finished making map for --track-renames 2024/09/15 04:16:37 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:37 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/09/15 04:16:37 DEBUG : yam: Need to transfer - No matching file found at Destination 2024/09/15 04:16:37 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for renames to finish 2024/09/15 04:16:37 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:37 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2024/09/15 04:16:37 INFO : yam: Copied (new) 2024/09/15 04:16:38 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2024/09/15 04:16:38 INFO : potato: Copied (new) 2024/09/15 04:16:38 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:38 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:16:38 INFO : B2 bucket rclone-test-yevubib6time: Making map for --track-renames 2024/09/15 04:16:38 INFO : B2 bucket rclone-test-yevubib6time: Finished making map for --track-renames 2024/09/15 04:16:38 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:38 DEBUG : potato: Unchanged skipping 2024/09/15 04:16:38 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for renames to finish 2024/09/15 04:16:39 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2024/09/15 04:16:39 INFO : yam: Copied (server-side copy) to: yaml 2024/09/15 04:16:39 INFO : yam: Deleted 2024/09/15 04:16:39 INFO : yaml: Renamed from "yam" 2024/09/15 04:16:39 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:39 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncWithTrackRenamesStrategyModtime (4.31s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" sync_test.go:1547: Can track renames: true 2024/09/15 04:16:41 INFO : B2 bucket rclone-test-yevubib6time: Making map for --track-renames 2024/09/15 04:16:41 INFO : B2 bucket rclone-test-yevubib6time: Finished making map for --track-renames 2024/09/15 04:16:41 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:41 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/09/15 04:16:41 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2024/09/15 04:16:41 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for renames to finish 2024/09/15 04:16:41 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:42 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2024/09/15 04:16:42 INFO : sub/yam: Copied (new) 2024/09/15 04:16:42 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2024/09/15 04:16:42 INFO : potato: Copied (new) 2024/09/15 04:16:42 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:43 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:16:43 DEBUG : potato: Unchanged skipping 2024/09/15 04:16:43 INFO : B2 bucket rclone-test-yevubib6time: Making map for --track-renames 2024/09/15 04:16:43 INFO : B2 bucket rclone-test-yevubib6time: Finished making map for --track-renames 2024/09/15 04:16:43 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:43 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for renames to finish 2024/09/15 04:16:44 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2024/09/15 04:16:44 INFO : sub/yam: Copied (server-side copy) to: yam 2024/09/15 04:16:44 INFO : sub/yam: Deleted 2024/09/15 04:16:44 INFO : yam: Renamed from "sub/yam" 2024/09/15 04:16:44 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:44 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (5.14s) === RUN TestServerSideMoveLocal run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:46 DEBUG : Creating backend with remote "/tmp/rclone3024799490/dir1" 2024/09/15 04:16:46 DEBUG : Creating backend with remote "/tmp/rclone3024799490/dir2" 2024/09/15 04:16:46 DEBUG : Local file system at /tmp/rclone3024799490/dir2: Using server-side directory move 2024/09/15 04:16:46 INFO : Local file system at /tmp/rclone3024799490/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2024/09/15 04:16:46 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/09/15 04:16:46 INFO : file1.txt: Moved (server-side) 2024/09/15 04:16:46 DEBUG : Local file system at /tmp/rclone3024799490/dir2: Waiting for checks to finish 2024/09/15 04:16:46 DEBUG : Local file system at /tmp/rclone3024799490/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (0.37s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:47 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:16:47 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2024/09/15 04:16:47 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:47 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:47 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2024/09/15 04:16:47 INFO : nested/sub dir/file: Copied (new) 2024/09/15 04:16:47 INFO : nested/sub dir/file: Deleted 2024/09/15 04:16:47 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:16:47 INFO : sub dir/hello world: Copied (new) 2024/09/15 04:16:47 INFO : sub dir/hello world: Deleted 2024/09/15 04:16:47 INFO : sub dir: Removing directory 2024/09/15 04:16:47 INFO : nested/sub dir: Removing directory 2024/09/15 04:16:47 INFO : nested: Removing directory 2024/09/15 04:16:47 DEBUG : Local file system at /tmp/rclone3024799490: deleted 3 directories run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestMoveWithDeleteEmptySrcDirs (2.18s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/15 04:16:49 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2024/09/15 04:16:49 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:49 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:49 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2024/09/15 04:16:49 INFO : nested/sub dir/file: Copied (new) 2024/09/15 04:16:49 INFO : nested/sub dir/file: Deleted 2024/09/15 04:16:51 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2024/09/15 04:16:51 INFO : sub dir/hello world: Copied (new) 2024/09/15 04:16:51 INFO : sub dir/hello world: Deleted run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (4.03s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:53 DEBUG : existing: Need to transfer - File not found at Destination 2024/09/15 04:16:53 DEBUG : existing-b: Need to transfer - File not found at Destination 2024/09/15 04:16:53 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:53 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:53 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2024/09/15 04:16:53 INFO : existing: Copied (new) 2024/09/15 04:16:53 INFO : existing: Deleted 2024/09/15 04:16:54 DEBUG : existing-b: sha1 = 321f6b7e8bf7f29aab89c5e41a555b1b0baa41a9 OK 2024/09/15 04:16:54 INFO : existing-b: Copied (new) 2024/09/15 04:16:54 INFO : existing-b: Deleted 2024/09/15 04:16:54 DEBUG : existing: Destination exists, skipping 2024/09/15 04:16:54 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:16:54 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2024/09/15 04:16:54 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:16:54 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestMoveWithIgnoreExisting (2.71s) === RUN TestServerSideMove run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:16:55 DEBUG : Creating backend with remote "TestB2:rclone-test-fedefoc6lecu" sync_test.go:1603: Server side move (if possible) B2 bucket rclone-test-yevubib6time -> B2 bucket rclone-test-fedefoc6lecu 2024/09/15 04:17:00 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/15 04:17:00 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2024/09/15 04:17:00 DEBUG : empty space: Unchanged skipping 2024/09/15 04:17:00 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/09/15 04:17:00 DEBUG : B2 bucket rclone-test-fedefoc6lecu: Waiting for checks to finish 2024/09/15 04:17:00 INFO : empty space: Deleted 2024/09/15 04:17:00 DEBUG : B2 bucket rclone-test-fedefoc6lecu: Waiting for transfers to finish 2024/09/15 04:17:01 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/09/15 04:17:01 INFO : potato2: Copied (server-side copy) 2024/09/15 04:17:01 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2024/09/15 04:17:01 INFO : potato3: Copied (server-side copy) 2024/09/15 04:17:01 INFO : potato2: Deleted 2024/09/15 04:17:01 INFO : potato3: Deleted 2024/09/15 04:17:02 DEBUG : Creating backend with remote "TestB2:rclone-test-fihohiv1gute" 2024/09/15 04:17:03 DEBUG : empty space: Need to transfer - File not found at Destination 2024/09/15 04:17:03 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/15 04:17:03 DEBUG : potato3: Need to transfer - File not found at Destination 2024/09/15 04:17:03 DEBUG : B2 bucket rclone-test-fihohiv1gute: Waiting for checks to finish 2024/09/15 04:17:03 DEBUG : B2 bucket rclone-test-fihohiv1gute: Waiting for transfers to finish 2024/09/15 04:17:04 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2024/09/15 04:17:04 INFO : potato3: Copied (server-side copy) 2024/09/15 04:17:04 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/09/15 04:17:04 INFO : potato2: Copied (server-side copy) 2024/09/15 04:17:05 INFO : potato3: Deleted 2024/09/15 04:17:05 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2024/09/15 04:17:05 INFO : empty space: Copied (server-side copy) 2024/09/15 04:17:05 INFO : potato2: Deleted 2024/09/15 04:17:05 INFO : empty space: Deleted 2024/09/15 04:17:05 DEBUG : B2 bucket rclone-test-fihohiv1gute: Purge remote 2024/09/15 04:17:05 INFO : B2 bucket rclone-test-fihohiv1gute: cleaning bucket "rclone-test-fihohiv1gute" of all files 2024/09/15 04:17:05 DEBUG : empty space: Deleting (id "4_z5f520d43e06dfb3b9f180e1b_f11192ea8c2359969_d20240915_m041704_c001_v0001144_t0023_u01726373824282") 2024/09/15 04:17:05 DEBUG : potato2: Deleting (id "4_z5f520d43e06dfb3b9f180e1b_f1051720492a15e2c_d20240915_m041704_c001_v0001101_t0012_u01726373824648") 2024/09/15 04:17:05 DEBUG : potato3: Deleting (id "4_z5f520d43e06dfb3b9f180e1b_f118c4e07f45382a2_d20240915_m041704_c001_v0001172_t0009_u01726373824642") 2024/09/15 04:17:06 DEBUG : B2 bucket rclone-test-fedefoc6lecu: Purge remote 2024/09/15 04:17:06 INFO : B2 bucket rclone-test-fedefoc6lecu: cleaning bucket "rclone-test-fedefoc6lecu" of all files 2024/09/15 04:17:06 DEBUG : empty space: Deleting (id "4_z4f72fd23e05dfb3b9f180e1b_f4066c3b9fd2fb434_d20240915_m041705_c001_v7007000_t0000_u01726373825171") 2024/09/15 04:17:06 DEBUG : empty space: Deleting (id "4_z4f72fd23e05dfb3b9f180e1b_f102308170f11a7fe_d20240915_m041659_c001_v0001179_t0045_u01726373819984") 2024/09/15 04:17:06 DEBUG : potato2: Deleting (id "4_z4f72fd23e05dfb3b9f180e1b_f417de451f2e0d992_d20240915_m041705_c001_v7007000_t0000_u01726373825047") 2024/09/15 04:17:06 DEBUG : potato2: Deleting (id "4_z4f72fd23e05dfb3b9f180e1b_f10407ecaa2cced48_d20240915_m041700_c001_v0001099_t0010_u01726373820923") 2024/09/15 04:17:06 DEBUG : potato3: Deleting (id "4_z4f72fd23e05dfb3b9f180e1b_f4066c3b9fd2fb432_d20240915_m041704_c001_v7007000_t0000_u01726373824953") 2024/09/15 04:17:06 DEBUG : potato3: Deleting (id "4_z4f72fd23e05dfb3b9f180e1b_f118f99daad795871_d20240915_m041701_c001_v0001029_t0028_u01726373821239") 2024/09/15 04:17:06 DEBUG : potato3: Deleting (id "4_z4f72fd23e05dfb3b9f180e1b_f102308170f11a800_d20240915_m041700_c001_v0001179_t0021_u01726373820195") --- PASS: TestServerSideMove (12.87s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:17:08 DEBUG : Creating backend with remote "TestB2:rclone-test-voletey1maru" sync_test.go:1603: Server side move (if possible) B2 bucket rclone-test-yevubib6time -> B2 bucket rclone-test-voletey1maru 2024/09/15 04:17:15 DEBUG : empty space: Excluded (Size Filter) 2024/09/15 04:17:15 DEBUG : empty space: Excluded 2024/09/15 04:17:15 DEBUG : empty space: Excluded (Size Filter) 2024/09/15 04:17:15 DEBUG : empty space: Excluded 2024/09/15 04:17:15 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/15 04:17:15 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/09/15 04:17:15 DEBUG : B2 bucket rclone-test-voletey1maru: Waiting for checks to finish 2024/09/15 04:17:15 DEBUG : B2 bucket rclone-test-voletey1maru: Waiting for transfers to finish 2024/09/15 04:17:16 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/09/15 04:17:16 INFO : potato2: Copied (server-side copy) 2024/09/15 04:17:16 INFO : potato2: Deleted 2024/09/15 04:17:16 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2024/09/15 04:17:16 INFO : potato3: Copied (server-side copy) 2024/09/15 04:17:17 INFO : potato3: Deleted 2024/09/15 04:17:17 DEBUG : Creating backend with remote "TestB2:rclone-test-tunogag5foyi" 2024/09/15 04:17:18 DEBUG : empty space: Excluded (Size Filter) 2024/09/15 04:17:18 DEBUG : empty space: Excluded 2024/09/15 04:17:18 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/15 04:17:18 DEBUG : potato3: Need to transfer - File not found at Destination 2024/09/15 04:17:18 DEBUG : B2 bucket rclone-test-tunogag5foyi: Waiting for checks to finish 2024/09/15 04:17:18 DEBUG : B2 bucket rclone-test-tunogag5foyi: Waiting for transfers to finish 2024/09/15 04:17:19 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2024/09/15 04:17:19 INFO : potato3: Copied (server-side copy) 2024/09/15 04:17:19 INFO : potato3: Deleted 2024/09/15 04:17:20 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/09/15 04:17:20 INFO : potato2: Copied (server-side copy) 2024/09/15 04:17:20 INFO : potato2: Deleted 2024/09/15 04:17:20 DEBUG : B2 bucket rclone-test-tunogag5foyi: Purge remote 2024/09/15 04:17:20 INFO : B2 bucket rclone-test-tunogag5foyi: cleaning bucket "rclone-test-tunogag5foyi" of all files 2024/09/15 04:17:20 DEBUG : potato2: Deleting (id "4_z6fd23df3e06dfb3b9f180e1b_f104bdcf6bf1ee30f_d20240915_m041719_c001_v0001098_t0056_u01726373839623") 2024/09/15 04:17:20 DEBUG : potato3: Deleting (id "4_z6fd23df3e06dfb3b9f180e1b_f10447a172720da54_d20240915_m041719_c001_v0001136_t0047_u01726373839245") 2024/09/15 04:17:21 DEBUG : B2 bucket rclone-test-voletey1maru: Purge remote 2024/09/15 04:17:21 INFO : B2 bucket rclone-test-voletey1maru: cleaning bucket "rclone-test-voletey1maru" of all files 2024/09/15 04:17:21 DEBUG : empty space: Deleting (id "4_z6f922d73e06dfb3b9f180e1b_f105fb28331be628b_d20240915_m041713_c001_v0001144_t0010_u01726373833632") 2024/09/15 04:17:21 DEBUG : potato2: Deleting (id "4_z6f922d73e06dfb3b9f180e1b_f411edb8b3df37973_d20240915_m041720_c001_v7007000_t0000_u01726373840163") 2024/09/15 04:17:21 DEBUG : potato2: Deleting (id "4_z6f922d73e06dfb3b9f180e1b_f108ff453487e197f_d20240915_m041715_c001_v0001091_t0015_u01726373835991") 2024/09/15 04:17:21 DEBUG : potato3: Deleting (id "4_z6f922d73e06dfb3b9f180e1b_f411edb8b3df37972_d20240915_m041719_c001_v7007000_t0000_u01726373839657") 2024/09/15 04:17:21 DEBUG : potato3: Deleting (id "4_z6f922d73e06dfb3b9f180e1b_f113b92294050024e_d20240915_m041716_c001_v0001098_t0022_u01726373836337") 2024/09/15 04:17:21 DEBUG : potato3: Deleting (id "4_z6f922d73e06dfb3b9f180e1b_f105fb28331be6291_d20240915_m041714_c001_v0001144_t0015_u01726373834496") --- PASS: TestServerSideMoveWithFilter (15.14s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:17:23 DEBUG : Creating backend with remote "TestB2:rclone-test-lulumih8cusu" 2024/09/15 04:17:27 DEBUG : tomatoDir: Making directory sync_test.go:1603: Server side move (if possible) B2 bucket rclone-test-yevubib6time -> B2 bucket rclone-test-lulumih8cusu 2024/09/15 04:17:30 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/15 04:17:30 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2024/09/15 04:17:30 DEBUG : empty space: Unchanged skipping 2024/09/15 04:17:30 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/09/15 04:17:30 DEBUG : B2 bucket rclone-test-lulumih8cusu: Waiting for checks to finish 2024/09/15 04:17:30 INFO : empty space: Deleted 2024/09/15 04:17:30 DEBUG : B2 bucket rclone-test-lulumih8cusu: Waiting for transfers to finish 2024/09/15 04:17:31 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/09/15 04:17:31 INFO : potato2: Copied (server-side copy) 2024/09/15 04:17:31 INFO : potato2: Deleted 2024/09/15 04:17:31 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2024/09/15 04:17:31 INFO : potato3: Copied (server-side copy) 2024/09/15 04:17:32 INFO : potato3: Deleted 2024/09/15 04:17:32 DEBUG : Creating backend with remote "TestB2:rclone-test-cuqesum0qago" 2024/09/15 04:17:38 DEBUG : tomatoDir: Making directory 2024/09/15 04:17:38 DEBUG : empty space: Need to transfer - File not found at Destination 2024/09/15 04:17:38 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/15 04:17:38 DEBUG : potato3: Need to transfer - File not found at Destination 2024/09/15 04:17:38 DEBUG : B2 bucket rclone-test-cuqesum0qago: Waiting for checks to finish 2024/09/15 04:17:38 DEBUG : B2 bucket rclone-test-cuqesum0qago: Waiting for transfers to finish 2024/09/15 04:17:39 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2024/09/15 04:17:39 INFO : empty space: Copied (server-side copy) 2024/09/15 04:17:40 INFO : empty space: Deleted 2024/09/15 04:17:40 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2024/09/15 04:17:40 INFO : potato3: Copied (server-side copy) 2024/09/15 04:17:40 INFO : potato3: Deleted 2024/09/15 04:17:40 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/09/15 04:17:40 INFO : potato2: Copied (server-side copy) 2024/09/15 04:17:41 INFO : potato2: Deleted 2024/09/15 04:17:41 DEBUG : B2 bucket rclone-test-cuqesum0qago: Purge remote 2024/09/15 04:17:41 INFO : B2 bucket rclone-test-cuqesum0qago: cleaning bucket "rclone-test-cuqesum0qago" of all files 2024/09/15 04:17:41 DEBUG : empty space: Deleting (id "4_z4f928de3e06dfb3b9f180e1b_f109ed6b79c5b307a_d20240915_m041739_c001_v0001146_t0005_u01726373859500") 2024/09/15 04:17:41 DEBUG : potato2: Deleting (id "4_z4f928de3e06dfb3b9f180e1b_f101d101b6515671d_d20240915_m041740_c001_v0001116_t0043_u01726373860098") 2024/09/15 04:17:41 DEBUG : potato3: Deleting (id "4_z4f928de3e06dfb3b9f180e1b_f101464bf672000ea_d20240915_m041739_c001_v0001101_t0057_u01726373859871") 2024/09/15 04:17:42 DEBUG : B2 bucket rclone-test-lulumih8cusu: Purge remote 2024/09/15 04:17:42 INFO : B2 bucket rclone-test-lulumih8cusu: cleaning bucket "rclone-test-lulumih8cusu" of all files 2024/09/15 04:17:42 DEBUG : empty space: Deleting (id "4_zef326d33e06dfb3b9f180e1b_f4077696654e5fcc8_d20240915_m041739_c001_v7007000_t0000_u01726373859928") 2024/09/15 04:17:42 DEBUG : empty space: Deleting (id "4_zef326d33e06dfb3b9f180e1b_f1034349fd1648a5c_d20240915_m041729_c001_v0001039_t0036_u01726373849078") 2024/09/15 04:17:42 DEBUG : potato2: Deleting (id "4_zef326d33e06dfb3b9f180e1b_f4077696654e5fccb_d20240915_m041741_c001_v7007000_t0000_u01726373861049") 2024/09/15 04:17:42 DEBUG : potato2: Deleting (id "4_zef326d33e06dfb3b9f180e1b_f11000ada3b8d86cb_d20240915_m041730_c001_v0001101_t0025_u01726373850811") 2024/09/15 04:17:42 DEBUG : potato3: Deleting (id "4_zef326d33e06dfb3b9f180e1b_f4077696654e5fcc9_d20240915_m041740_c001_v7007000_t0000_u01726373860379") 2024/09/15 04:17:42 DEBUG : potato3: Deleting (id "4_zef326d33e06dfb3b9f180e1b_f1191d3adeaa9f7be_d20240915_m041731_c001_v0001116_t0057_u01726373851203") 2024/09/15 04:17:42 DEBUG : potato3: Deleting (id "4_zef326d33e06dfb3b9f180e1b_f1034349fd1648a5e_d20240915_m041729_c001_v0001039_t0033_u01726373849603") --- PASS: TestServerSideMoveDeleteEmptySourceDirs (20.97s) === RUN TestServerSideMoveOverlap run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:17:44 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/rclone-move-test" --- PASS: TestServerSideMoveOverlap (3.27s) === RUN TestSyncOverlap run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:17:48 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/rclone-sync-test" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncOverlap (3.02s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:17:51 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/rclone-sync-test" 2024/09/15 04:17:53 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/rclone-sync-test-include/layer2" 2024/09/15 04:17:55 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/rclone-sync-test-ignore-file" 2024/09/15 04:17:59 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/15 04:17:59 DEBUG : B2 bucket rclone-test-yevubib6time path rclone-sync-test: Waiting for checks to finish 2024/09/15 04:17:59 DEBUG : B2 bucket rclone-test-yevubib6time path rclone-sync-test: Waiting for transfers to finish 2024/09/15 04:17:59 DEBUG : Waiting for deletions to finish 2024/09/15 04:17:59 INFO : There was nothing to transfer 2024/09/15 04:18:00 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/15 04:18:00 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:18:00 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:18:00 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:00 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:18:00 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/15 04:18:00 DEBUG : B2 bucket rclone-test-yevubib6time path rclone-sync-test-include/layer2: Waiting for checks to finish 2024/09/15 04:18:00 DEBUG : B2 bucket rclone-test-yevubib6time path rclone-sync-test-include/layer2: Waiting for transfers to finish 2024/09/15 04:18:00 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:00 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:18:01 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/15 04:18:01 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:18:01 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:18:01 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:01 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:18:01 DEBUG : : Excluded 2024/09/15 04:18:01 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/15 04:18:01 DEBUG : B2 bucket rclone-test-yevubib6time path rclone-sync-test-ignore-file: Waiting for checks to finish 2024/09/15 04:18:01 DEBUG : B2 bucket rclone-test-yevubib6time path rclone-sync-test-ignore-file: Waiting for transfers to finish 2024/09/15 04:18:01 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:01 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncOverlapWithFilter (17.88s) === RUN TestSyncCompareDest run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:18:09 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/dst" 2024/09/15 04:18:10 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/CompareDest" 2024/09/15 04:18:13 DEBUG : one: Need to transfer - File not found at Destination 2024/09/15 04:18:13 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:18:13 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:18:14 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/15 04:18:14 INFO : one: Copied (new) 2024/09/15 04:18:14 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:15 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/15 04:18:15 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:18:15 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:18:16 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/09/15 04:18:16 INFO : one: Copied (replaced existing) 2024/09/15 04:18:16 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:19 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/15 04:18:19 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:18:20 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:18:20 DEBUG : one: Destination found in --compare-dest, skipping 2024/09/15 04:18:20 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:18:20 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:20 INFO : There was nothing to transfer 2024/09/15 04:18:21 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:18:21 DEBUG : two: Destination found in --compare-dest, skipping 2024/09/15 04:18:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/15 04:18:21 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:18:21 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:18:21 DEBUG : one: Destination found in --compare-dest, skipping 2024/09/15 04:18:21 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:18:21 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:21 INFO : There was nothing to transfer 2024/09/15 04:18:22 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:18:22 DEBUG : two: Destination found in --compare-dest, skipping 2024/09/15 04:18:22 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/15 04:18:22 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:18:22 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:18:22 DEBUG : one: Destination found in --compare-dest, skipping 2024/09/15 04:18:22 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:18:22 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:22 INFO : There was nothing to transfer 2024/09/15 04:18:23 DEBUG : two: Modification times differ by -119h59m59.877s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59.123 +0000 UTC 2024/09/15 04:18:23 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/09/15 04:18:23 DEBUG : two: Destination found in --compare-dest, skipping 2024/09/15 04:18:23 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/15 04:18:23 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:18:23 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:18:23 DEBUG : one: Destination found in --compare-dest, skipping 2024/09/15 04:18:23 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:18:23 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:23 INFO : There was nothing to transfer 2024/09/15 04:18:24 DEBUG : two: Sizes differ (src 5 vs dst 3) 2024/09/15 04:18:24 DEBUG : two: Need to transfer - File not found at Destination 2024/09/15 04:18:24 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/15 04:18:24 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:18:24 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:18:24 DEBUG : one: Destination found in --compare-dest, skipping 2024/09/15 04:18:24 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:18:26 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2024/09/15 04:18:26 INFO : two: Copied (new) 2024/09/15 04:18:26 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (18.84s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:18:30 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/dest" 2024/09/15 04:18:31 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/pre-dest1" 2024/09/15 04:18:32 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/pre-dest2" 2024/09/15 04:18:34 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:18:34 DEBUG : 1: Destination found in --compare-dest, skipping 2024/09/15 04:18:34 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:18:34 DEBUG : 2: Destination found in --compare-dest, skipping 2024/09/15 04:18:35 DEBUG : 3: Need to transfer - File not found at Destination 2024/09/15 04:18:35 DEBUG : B2 bucket rclone-test-yevubib6time path dest: Waiting for checks to finish 2024/09/15 04:18:35 DEBUG : B2 bucket rclone-test-yevubib6time path dest: Waiting for transfers to finish 2024/09/15 04:18:36 DEBUG : 3: sha1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK 2024/09/15 04:18:36 INFO : 3: Copied (new) 2024/09/15 04:18:36 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (9.86s) === RUN TestSyncCopyDest run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:18:37 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/dst" 2024/09/15 04:18:38 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/CopyDest" 2024/09/15 04:18:40 DEBUG : one: Need to transfer - File not found at Destination 2024/09/15 04:18:40 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:18:40 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:18:42 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/15 04:18:42 INFO : one: Copied (new) 2024/09/15 04:18:42 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:43 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/15 04:18:43 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:18:43 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:18:43 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/09/15 04:18:43 INFO : one: Copied (replaced existing) 2024/09/15 04:18:43 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:45 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/BackupDir" 2024/09/15 04:18:47 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:18:47 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/15 04:18:47 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:18:47 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/15 04:18:49 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/15 04:18:49 INFO : one: Copied (server-side copy) 2024/09/15 04:18:49 INFO : one: Deleted 2024/09/15 04:18:49 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/09/15 04:18:49 INFO : one: Copied (server-side copy) 2024/09/15 04:18:49 DEBUG : one: Destination found in --copy-dest, using server-side copy 2024/09/15 04:18:49 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:18:49 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:51 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:18:53 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/09/15 04:18:53 INFO : two: Copied (server-side copy) 2024/09/15 04:18:53 DEBUG : two: Destination found in --copy-dest, using server-side copy 2024/09/15 04:18:53 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:18:53 DEBUG : one: Unchanged skipping 2024/09/15 04:18:53 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:18:53 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:18:53 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:54 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:18:54 DEBUG : one: Unchanged skipping 2024/09/15 04:18:54 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:18:54 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:18:54 DEBUG : two: Unchanged skipping 2024/09/15 04:18:54 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:18:54 DEBUG : Waiting for deletions to finish 2024/09/15 04:18:54 INFO : There was nothing to transfer 2024/09/15 04:18:56 DEBUG : three: Sizes differ (src 7 vs dst 5) 2024/09/15 04:18:56 DEBUG : three: Destination not found in --copy-dest 2024/09/15 04:18:56 DEBUG : three: Need to transfer - File not found at Destination 2024/09/15 04:18:56 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:18:56 DEBUG : two: Unchanged skipping 2024/09/15 04:18:56 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:18:56 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/15 04:18:56 DEBUG : one: Unchanged skipping 2024/09/15 04:18:56 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:18:57 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2024/09/15 04:18:57 INFO : three: Copied (new) 2024/09/15 04:18:57 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (21.48s) === RUN TestSyncBackupDir run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:19:11 DEBUG : dst/two: Clearing upload URL because of error: incident id 5380ccdbde49-790bbd87687a0766 (500 internal_error) 2024/09/15 04:19:11 DEBUG : pacer: low level retry 1/1 (error incident id 5380ccdbde49-790bbd87687a0766 (500 internal_error)) 2024/09/15 04:19:11 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:281: Retry Put of "dst/two" to B2 bucket rclone-test-yevubib6time: 1/10 (incident id 5380ccdbde49-790bbd87687a0766 (500 internal_error)) 2024/09/15 04:19:13 DEBUG : pacer: Reducing sleep to 10ms 2024/09/15 04:19:15 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/dst" 2024/09/15 04:19:16 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/backup" 2024/09/15 04:19:18 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/15 04:19:18 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:19:18 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:19:18 DEBUG : two: Unchanged skipping 2024/09/15 04:19:19 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/15 04:19:19 INFO : one: Copied (server-side copy) 2024/09/15 04:19:19 INFO : one: Deleted 2024/09/15 04:19:19 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:19:20 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2024/09/15 04:19:20 INFO : one: Copied (new) 2024/09/15 04:19:20 DEBUG : Waiting for deletions to finish 2024/09/15 04:19:21 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/09/15 04:19:21 INFO : three.txt: Copied (server-side copy) 2024/09/15 04:19:21 INFO : three.txt: Deleted 2024/09/15 04:19:21 INFO : three.txt: Moved into backup dir 2024/09/15 04:19:24 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/15 04:19:24 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:19:24 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:19:24 DEBUG : two: Unchanged skipping 2024/09/15 04:19:25 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2024/09/15 04:19:25 INFO : one: Copied (server-side copy) 2024/09/15 04:19:25 INFO : one: Deleted 2024/09/15 04:19:25 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:19:26 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2024/09/15 04:19:26 INFO : one: Copied (new) 2024/09/15 04:19:26 DEBUG : Waiting for deletions to finish 2024/09/15 04:19:26 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2024/09/15 04:19:26 INFO : three.txt: Copied (server-side copy) 2024/09/15 04:19:27 INFO : three.txt: Deleted 2024/09/15 04:19:27 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (29.19s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:19:30 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/dst" 2024/09/15 04:19:31 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/backup" 2024/09/15 04:19:33 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/15 04:19:33 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:19:33 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:19:33 DEBUG : two: Unchanged skipping 2024/09/15 04:19:34 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/15 04:19:34 INFO : one: Copied (server-side copy) to: one.bak 2024/09/15 04:19:34 INFO : one: Deleted 2024/09/15 04:19:34 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:19:35 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2024/09/15 04:19:35 INFO : one: Copied (new) 2024/09/15 04:19:35 DEBUG : Waiting for deletions to finish 2024/09/15 04:19:36 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/09/15 04:19:36 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/15 04:19:36 INFO : three.txt: Deleted 2024/09/15 04:19:36 INFO : three.txt: Moved into backup dir 2024/09/15 04:19:38 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/15 04:19:38 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:19:38 DEBUG : two: Unchanged skipping 2024/09/15 04:19:38 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:19:39 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2024/09/15 04:19:39 INFO : one: Copied (server-side copy) to: one.bak 2024/09/15 04:19:39 INFO : one: Deleted 2024/09/15 04:19:39 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:19:39 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2024/09/15 04:19:39 INFO : one: Copied (new) 2024/09/15 04:19:39 DEBUG : Waiting for deletions to finish 2024/09/15 04:19:40 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2024/09/15 04:19:40 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/15 04:19:40 INFO : three.txt: Deleted 2024/09/15 04:19:40 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (13.77s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:19:44 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/dst" 2024/09/15 04:19:45 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/backup" 2024/09/15 04:19:47 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/15 04:19:47 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:19:47 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:19:47 DEBUG : two: Unchanged skipping 2024/09/15 04:19:48 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/15 04:19:48 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/09/15 04:19:48 INFO : one: Deleted 2024/09/15 04:19:48 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:19:49 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2024/09/15 04:19:49 INFO : one: Copied (new) 2024/09/15 04:19:49 DEBUG : Waiting for deletions to finish 2024/09/15 04:19:50 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/09/15 04:19:50 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/09/15 04:19:50 INFO : three.txt: Deleted 2024/09/15 04:19:50 INFO : three.txt: Moved into backup dir 2024/09/15 04:19:52 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/15 04:19:52 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:19:52 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:19:52 DEBUG : two: Unchanged skipping 2024/09/15 04:19:52 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2024/09/15 04:19:52 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/09/15 04:19:53 INFO : one: Deleted 2024/09/15 04:19:53 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:19:53 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2024/09/15 04:19:53 INFO : one: Copied (new) 2024/09/15 04:19:53 DEBUG : Waiting for deletions to finish 2024/09/15 04:19:57 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2024/09/15 04:19:57 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/09/15 04:19:58 INFO : three.txt: Deleted 2024/09/15 04:19:58 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (18.06s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:20:02 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/dst" 2024/09/15 04:20:04 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/15 04:20:04 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:20:04 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:20:04 DEBUG : two: Unchanged skipping 2024/09/15 04:20:05 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/15 04:20:05 INFO : one: Copied (server-side copy) to: one.bak 2024/09/15 04:20:05 INFO : one: Deleted 2024/09/15 04:20:05 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:20:06 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2024/09/15 04:20:06 INFO : one: Copied (new) 2024/09/15 04:20:06 DEBUG : Waiting for deletions to finish 2024/09/15 04:20:06 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/09/15 04:20:06 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/15 04:20:06 INFO : three.txt: Deleted 2024/09/15 04:20:06 INFO : three.txt: Moved into backup dir 2024/09/15 04:20:08 DEBUG : one.bak: Excluded (Path Filter) 2024/09/15 04:20:08 DEBUG : one.bak: Excluded 2024/09/15 04:20:08 DEBUG : three.txt.bak: Excluded (Path Filter) 2024/09/15 04:20:08 DEBUG : three.txt.bak: Excluded 2024/09/15 04:20:08 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/15 04:20:08 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:20:08 DEBUG : two: Unchanged skipping 2024/09/15 04:20:08 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for checks to finish 2024/09/15 04:20:08 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2024/09/15 04:20:08 INFO : one: Copied (server-side copy) to: one.bak 2024/09/15 04:20:08 INFO : one: Deleted 2024/09/15 04:20:08 DEBUG : B2 bucket rclone-test-yevubib6time path dst: Waiting for transfers to finish 2024/09/15 04:20:09 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2024/09/15 04:20:09 INFO : one: Copied (new) 2024/09/15 04:20:09 DEBUG : Waiting for deletions to finish 2024/09/15 04:20:11 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2024/09/15 04:20:11 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/15 04:20:11 INFO : three.txt: Deleted 2024/09/15 04:20:11 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (12.70s) === RUN TestSyncSuffix run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:20:15 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/dst" 2024/09/15 04:20:16 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/15 04:20:18 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/15 04:20:18 INFO : one: Copied (server-side copy) to: one.bak 2024/09/15 04:20:18 INFO : one: Deleted 2024/09/15 04:20:19 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2024/09/15 04:20:19 INFO : one: Copied (new) 2024/09/15 04:20:19 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:20:19 DEBUG : two: Unchanged skipping 2024/09/15 04:20:19 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/09/15 04:20:20 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/09/15 04:20:20 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/15 04:20:20 INFO : three.txt: Deleted 2024/09/15 04:20:21 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2024/09/15 04:20:21 INFO : three.txt: Copied (new) 2024/09/15 04:20:22 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/15 04:20:23 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2024/09/15 04:20:23 INFO : one: Copied (server-side copy) to: one.bak 2024/09/15 04:20:23 INFO : one: Deleted 2024/09/15 04:20:24 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2024/09/15 04:20:24 INFO : one: Copied (new) 2024/09/15 04:20:24 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:20:24 DEBUG : two: Unchanged skipping 2024/09/15 04:20:24 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/09/15 04:20:30 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2024/09/15 04:20:30 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/15 04:20:30 INFO : three.txt: Deleted 2024/09/15 04:20:31 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2024/09/15 04:20:31 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (20.33s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:20:35 DEBUG : Creating backend with remote "TestB2:rclone-test-yevubib6time/dst" 2024/09/15 04:20:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/15 04:20:39 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/15 04:20:39 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/09/15 04:20:39 INFO : one: Deleted 2024/09/15 04:20:40 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2024/09/15 04:20:40 INFO : one: Copied (new) 2024/09/15 04:20:40 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:20:40 DEBUG : two: Unchanged skipping 2024/09/15 04:20:41 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/09/15 04:20:41 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/09/15 04:20:41 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/09/15 04:20:41 INFO : three.txt: Deleted 2024/09/15 04:20:42 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2024/09/15 04:20:42 INFO : three.txt: Copied (new) 2024/09/15 04:20:43 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/15 04:20:45 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2024/09/15 04:20:45 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/09/15 04:20:45 INFO : one: Deleted 2024/09/15 04:20:46 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2024/09/15 04:20:46 INFO : one: Copied (new) 2024/09/15 04:20:46 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:20:46 DEBUG : two: Unchanged skipping 2024/09/15 04:20:46 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/09/15 04:20:47 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2024/09/15 04:20:47 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/09/15 04:20:47 INFO : three.txt: Deleted 2024/09/15 04:20:49 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2024/09/15 04:20:49 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (18.16s) === RUN TestSyncUTFNorm run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:20:53 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2024/09/15 04:20:53 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:20:53 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:20:54 DEBUG : Testêé: sha1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2024/09/15 04:20:54 INFO : Testêé: Copied (replaced existing) to: Testêé 2024/09/15 04:20:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (4.29s) === RUN TestSyncImmutable run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:20:56 DEBUG : existing: Need to transfer - File not found at Destination 2024/09/15 04:20:56 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:20:56 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:20:56 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2024/09/15 04:20:56 INFO : existing: Copied (new) 2024/09/15 04:20:56 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:20:57 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:20:57 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2024/09/15 04:20:57 ERROR : existing: Source and destination exist but do not match: immutable file modified 2024/09/15 04:20:57 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:20:57 ERROR : B2 bucket rclone-test-yevubib6time: not deleting files as there were IO errors 2024/09/15 04:20:57 ERROR : B2 bucket rclone-test-yevubib6time: not deleting directories as there were IO errors run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncImmutable (2.99s) === RUN TestSyncIgnoreCase run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:21:00 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:21:00 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:00 DEBUG : existing: Unchanged skipping 2024/09/15 04:21:00 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:21:00 DEBUG : Waiting for deletions to finish 2024/09/15 04:21:00 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (2.70s) === RUN TestFixCase run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" sync_test.go:2529: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.39s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" sync_test.go:2568: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" sync_test.go:2568: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" sync_test.go:2568: This test only runs on local --- PASS: TestMaxTransfer (1.14s) --- SKIP: TestMaxTransfer/Hard (0.38s) --- SKIP: TestMaxTransfer/Soft (0.38s) --- SKIP: TestMaxTransfer/Cautious (0.38s) === RUN TestSyncConcurrentDelete run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:21:26 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:21:26 DEBUG : both0: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both12: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both10: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both14: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both13: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both11: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both15: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both16: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both1: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both18: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both3: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both4: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both5: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both6: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both17: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both19: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:26 DEBUG : both2: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both7: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both8: Unchanged skipping 2024/09/15 04:21:26 DEBUG : both9: Unchanged skipping 2024/09/15 04:21:26 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:21:26 DEBUG : Waiting for deletions to finish 2024/09/15 04:21:26 INFO : only6: Deleted 2024/09/15 04:21:27 INFO : only15: Deleted 2024/09/15 04:21:27 INFO : only9: Deleted 2024/09/15 04:21:27 INFO : only10: Deleted 2024/09/15 04:21:27 INFO : only16: Deleted 2024/09/15 04:21:27 INFO : only17: Deleted 2024/09/15 04:21:27 INFO : only19: Deleted 2024/09/15 04:21:27 INFO : only2: Deleted 2024/09/15 04:21:27 INFO : only3: Deleted 2024/09/15 04:21:27 INFO : only1: Deleted 2024/09/15 04:21:27 INFO : only5: Deleted 2024/09/15 04:21:27 INFO : only7: Deleted 2024/09/15 04:21:27 INFO : only11: Deleted 2024/09/15 04:21:27 INFO : only12: Deleted 2024/09/15 04:21:27 INFO : only14: Deleted 2024/09/15 04:21:27 INFO : only4: Deleted 2024/09/15 04:21:27 INFO : only0: Deleted 2024/09/15 04:21:27 INFO : only13: Deleted 2024/09/15 04:21:28 INFO : only18: Deleted 2024/09/15 04:21:28 INFO : only8: Deleted 2024/09/15 04:21:28 INFO : There was nothing to transfer run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncConcurrentDelete (29.87s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:21:55 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for checks to finish 2024/09/15 04:21:55 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both1: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both12: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both11: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both10: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both0: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both13: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both17: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both18: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both14: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both15: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both19: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both16: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both4: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both6: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both7: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both8: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/15 04:21:55 DEBUG : both9: Unchanged skipping 2024/09/15 04:21:55 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : both2: Unchanged skipping 2024/09/15 04:21:55 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : both3: Unchanged skipping 2024/09/15 04:21:55 DEBUG : both5: Unchanged skipping 2024/09/15 04:21:55 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2024/09/15 04:21:55 DEBUG : B2 bucket rclone-test-yevubib6time: Waiting for transfers to finish 2024/09/15 04:21:55 DEBUG : only0: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:55 INFO : only0: Copied (replaced existing) 2024/09/15 04:21:55 DEBUG : only1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:55 INFO : only1: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only12: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only12: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only13: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only13: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only10: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only10: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only14: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only14: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only15: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only15: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only11: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only11: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only16: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only16: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only17: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only17: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only18: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only18: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only4: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only4: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only5: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only5: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only6: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only6: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only7: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only7: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only8: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only8: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only9: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only9: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only2: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only2: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only19: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only19: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : only3: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/09/15 04:21:56 INFO : only3: Copied (replaced existing) 2024/09/15 04:21:56 DEBUG : Waiting for deletions to finish run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestSyncConcurrentTruncate (32.72s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:22:05 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/15 04:22:05 DEBUG : sub dir: Making directory 2024/09/15 04:22:05 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestNothingToTransferWithEmptyDirs (4.71s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" 2024/09/15 04:22:10 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/15 04:22:10 DEBUG : sub dir: Making directory 2024/09/15 04:22:10 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" run.go:180: Remote "B2 bucket rclone-test-yevubib6time", Local "Local file system at /tmp/rclone3024799490", Modify Window "1ms" --- PASS: TestNothingToTransferWithoutEmptyDirs (4.52s) PASS 2024/09/15 04:22:14 DEBUG : B2 bucket rclone-test-yevubib6time: Purge remote 2024/09/15 04:22:14 INFO : B2 bucket rclone-test-yevubib6time: cleaning bucket "rclone-test-yevubib6time" of all files 2024/09/15 04:22:15 DEBUG : BackupDir/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a29d2_d20240915_m041857_c001_v7007000_t0000_u01726373937833") 2024/09/15 04:22:15 DEBUG : BackupDir/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1195a792a34071f7_d20240915_m041848_c001_v0001109_t0033_u01726373928396") 2024/09/15 04:22:15 DEBUG : CompareDest/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f409f8787e560e32f_d20240915_m041827_c001_v7007000_t0000_u01726373907031") 2024/09/15 04:22:15 DEBUG : CompareDest/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f106a56bdaf47b781_d20240915_m041818_c001_v0001117_t0043_u01726373898650") 2024/09/15 04:22:15 DEBUG : CompareDest/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f409f8787e560e330_d20240915_m041827_c001_v7007000_t0000_u01726373907219") 2024/09/15 04:22:15 DEBUG : CompareDest/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103bcf_d20240915_m041820_c001_v0001130_t0016_u01726373900757") 2024/09/15 04:22:15 DEBUG : CopyDest/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a29d3_d20240915_m041858_c001_v7007000_t0000_u01726373938017") 2024/09/15 04:22:15 DEBUG : CopyDest/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2e7c_d20240915_m041845_c001_v0001101_t0024_u01726373925264") 2024/09/15 04:22:15 DEBUG : CopyDest/three: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a29d4_d20240915_m041858_c001_v7007000_t0000_u01726373938201") 2024/09/15 04:22:15 DEBUG : CopyDest/three: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103d17_d20240915_m041855_c001_v0001130_t0054_u01726373935020") 2024/09/15 04:22:15 DEBUG : CopyDest/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a29d5_d20240915_m041858_c001_v7007000_t0000_u01726373938383") 2024/09/15 04:22:15 DEBUG : CopyDest/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f106a56bdaf47b8e1_d20240915_m041850_c001_v0001117_t0031_u01726373930593") 2024/09/15 04:22:15 DEBUG : EXISTING: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f402c22ca267bef7f_d20240915_m042101_c001_v7007000_t0000_u01726374061133") 2024/09/15 04:22:16 DEBUG : EXISTING: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1041e5_d20240915_m042058_c001_v0001130_t0016_u01726374058811") 2024/09/15 04:22:16 DEBUG : Testêé: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f402c22ca267bef7a_d20240915_m042055_c001_v7007000_t0000_u01726374055444") 2024/09/15 04:22:16 DEBUG : Testêé: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1041ad_d20240915_m042053_c001_v0001130_t0022_u01726374053550") 2024/09/15 04:22:16 DEBUG : Testêé: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3350_d20240915_m042051_c001_v0001101_t0003_u01726374051897") 2024/09/15 04:22:16 DEBUG : a/potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5faaa_d20240915_m041610_c001_v7007000_t0000_u01726373770319") 2024/09/15 04:22:16 DEBUG : a/potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a280e_d20240915_m041609_c001_v0001101_t0026_u01726373769442") 2024/09/15 04:22:16 DEBUG : a/potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65cdd0_d20240915_m041607_c001_v7007000_t0000_u01726373767066") 2024/09/15 04:22:16 DEBUG : a/potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a27e9_d20240915_m041606_c001_v0001101_t0037_u01726373766113") 2024/09/15 04:22:16 DEBUG : a/potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa71_d20240915_m041543_c001_v7007000_t0000_u01726373743722") 2024/09/15 04:22:16 DEBUG : a/potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a26e5_d20240915_m041542_c001_v0001101_t0035_u01726373742626") 2024/09/15 04:22:16 DEBUG : b/potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5faab_d20240915_m041610_c001_v7007000_t0000_u01726373770501") 2024/09/15 04:22:16 DEBUG : b/potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a27fc_d20240915_m041607_c001_v0001101_t0044_u01726373767981") 2024/09/15 04:22:16 DEBUG : b/potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65cdcd_d20240915_m041606_c001_v7007000_t0000_u01726373766499") 2024/09/15 04:22:16 DEBUG : b/potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a27d7_d20240915_m041604_c001_v0001101_t0055_u01726373764870") 2024/09/15 04:22:16 DEBUG : b/potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa70_d20240915_m041543_c001_v7007000_t0000_u01726373743091") 2024/09/15 04:22:16 DEBUG : b/potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a26db_d20240915_m041541_c001_v0001101_t0011_u01726373741347") 2024/09/15 04:22:16 DEBUG : backup/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f419f0e154ce1f414_d20240915_m041927_c001_v7007000_t0000_u01726373967576") 2024/09/15 04:22:16 DEBUG : backup/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1188beee15c663bf_d20240915_m041925_c001_v0001101_t0044_u01726373965182") 2024/09/15 04:22:16 DEBUG : backup/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10126a9e973fe330_d20240915_m041919_c001_v0001165_t0030_u01726373959333") 2024/09/15 04:22:16 DEBUG : backup/one-2019-01-01: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f8796e_d20240915_m041958_c001_v7007000_t0000_u01726373998912") 2024/09/15 04:22:17 DEBUG : backup/one-2019-01-01: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f11948ecc76ef2866_d20240915_m041952_c001_v0001131_t0042_u01726373992691") 2024/09/15 04:22:17 DEBUG : backup/one-2019-01-01: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f119a099b6c447612_d20240915_m041948_c001_v0001093_t0059_u01726373988446") 2024/09/15 04:22:17 DEBUG : backup/one.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f8792b_d20240915_m041941_c001_v7007000_t0000_u01726373981238") 2024/09/15 04:22:17 DEBUG : backup/one.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f105c5d1894f62e9e_d20240915_m041939_c001_v0001172_t0037_u01726373979179") 2024/09/15 04:22:17 DEBUG : backup/one.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f116fb82dffb4c1c3_d20240915_m041934_c001_v0001102_t0056_u01726373974315") 2024/09/15 04:22:17 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f87970_d20240915_m041959_c001_v7007000_t0000_u01726373999096") 2024/09/15 04:22:17 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1144d45b87c9d7ea_d20240915_m041955_c001_v0001117_t0019_u01726373995757") 2024/09/15 04:22:17 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1029080321a0fa5d_d20240915_m041950_c001_v0001130_t0044_u01726373990246") 2024/09/15 04:22:17 DEBUG : backup/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f419f0e154ce1f417_d20240915_m041927_c001_v7007000_t0000_u01726373967760") 2024/09/15 04:22:17 DEBUG : backup/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f109d02ba2a9c6212_d20240915_m041926_c001_v0001176_t0050_u01726373966756") 2024/09/15 04:22:17 DEBUG : backup/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f100292ade26099f3_d20240915_m041920_c001_v0001101_t0014_u01726373960815") 2024/09/15 04:22:17 DEBUG : backup/three.txt.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f8792e_d20240915_m041941_c001_v7007000_t0000_u01726373981426") 2024/09/15 04:22:17 DEBUG : backup/three.txt.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10471935ff6da14b_d20240915_m041940_c001_v0001101_t0019_u01726373980103") 2024/09/15 04:22:17 DEBUG : backup/three.txt.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f107abe7a29db6161_d20240915_m041936_c001_v0001039_t0058_u01726373976063") 2024/09/15 04:22:17 DEBUG : both0: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592231c_d20240915_m042157_c001_v7007000_t0000_u01726374117621") 2024/09/15 04:22:17 DEBUG : both0: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3514_d20240915_m042133_c001_v0001101_t0017_u01726374093276") 2024/09/15 04:22:17 DEBUG : both0: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61dd_d20240915_m042128_c001_v7007000_t0000_u01726374088629") 2024/09/15 04:22:17 DEBUG : both0: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a33c0_d20240915_m042103_c001_v0001101_t0055_u01726374063402") 2024/09/15 04:22:17 DEBUG : both1: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592231d_d20240915_m042157_c001_v7007000_t0000_u01726374117803") 2024/09/15 04:22:18 DEBUG : both1: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3530_d20240915_m042134_c001_v0001101_t0051_u01726374094797") 2024/09/15 04:22:18 DEBUG : both1: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61df_d20240915_m042128_c001_v7007000_t0000_u01726374088817") 2024/09/15 04:22:18 DEBUG : both1: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a33d2_d20240915_m042104_c001_v0001101_t0039_u01726374064112") 2024/09/15 04:22:18 DEBUG : both10: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592231e_d20240915_m042157_c001_v7007000_t0000_u01726374117987") 2024/09/15 04:22:18 DEBUG : both10: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a35aa_d20240915_m042145_c001_v0001101_t0002_u01726374105188") 2024/09/15 04:22:18 DEBUG : both10: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61e0_d20240915_m042129_c001_v7007000_t0000_u01726374089007") 2024/09/15 04:22:18 DEBUG : both10: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3434_d20240915_m042113_c001_v0001101_t0007_u01726374073425") 2024/09/15 04:22:18 DEBUG : both11: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592231f_d20240915_m042158_c001_v7007000_t0000_u01726374118176") 2024/09/15 04:22:18 DEBUG : both11: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a35bc_d20240915_m042146_c001_v0001101_t0024_u01726374106222") 2024/09/15 04:22:18 DEBUG : both11: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61e2_d20240915_m042129_c001_v7007000_t0000_u01726374089190") 2024/09/15 04:22:18 DEBUG : both11: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3444_d20240915_m042114_c001_v0001101_t0004_u01726374074278") 2024/09/15 04:22:18 DEBUG : both12: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922320_d20240915_m042158_c001_v7007000_t0000_u01726374118361") 2024/09/15 04:22:18 DEBUG : both12: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a35c6_d20240915_m042147_c001_v0001101_t0026_u01726374107076") 2024/09/15 04:22:18 DEBUG : both12: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61e4_d20240915_m042129_c001_v7007000_t0000_u01726374089373") 2024/09/15 04:22:18 DEBUG : both12: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3450_d20240915_m042114_c001_v0001101_t0047_u01726374074989") 2024/09/15 04:22:18 DEBUG : both13: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922321_d20240915_m042158_c001_v7007000_t0000_u01726374118545") 2024/09/15 04:22:18 DEBUG : both13: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a35da_d20240915_m042148_c001_v0001101_t0003_u01726374108181") 2024/09/15 04:22:18 DEBUG : both13: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61e5_d20240915_m042129_c001_v7007000_t0000_u01726374089555") 2024/09/15 04:22:18 DEBUG : both13: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3472_d20240915_m042117_c001_v0001101_t0034_u01726374077403") 2024/09/15 04:22:18 DEBUG : both14: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922322_d20240915_m042158_c001_v7007000_t0000_u01726374118731") 2024/09/15 04:22:18 DEBUG : both14: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a35ea_d20240915_m042149_c001_v0001101_t0037_u01726374109307") 2024/09/15 04:22:19 DEBUG : both14: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61e7_d20240915_m042129_c001_v7007000_t0000_u01726374089742") 2024/09/15 04:22:19 DEBUG : both14: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3476_d20240915_m042118_c001_v0001101_t0056_u01726374078297") 2024/09/15 04:22:19 DEBUG : both15: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922323_d20240915_m042158_c001_v7007000_t0000_u01726374118914") 2024/09/15 04:22:19 DEBUG : both15: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a35f6_d20240915_m042150_c001_v0001101_t0041_u01726374110393") 2024/09/15 04:22:19 DEBUG : both15: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61e8_d20240915_m042129_c001_v7007000_t0000_u01726374089929") 2024/09/15 04:22:19 DEBUG : both15: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3484_d20240915_m042119_c001_v0001101_t0045_u01726374079492") 2024/09/15 04:22:19 DEBUG : both16: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922324_d20240915_m042159_c001_v7007000_t0000_u01726374119098") 2024/09/15 04:22:19 DEBUG : both16: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3604_d20240915_m042151_c001_v0001101_t0032_u01726374111228") 2024/09/15 04:22:19 DEBUG : both16: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61eb_d20240915_m042130_c001_v7007000_t0000_u01726374090110") 2024/09/15 04:22:19 DEBUG : both16: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3498_d20240915_m042121_c001_v0001101_t0050_u01726374081200") 2024/09/15 04:22:19 DEBUG : both17: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922325_d20240915_m042159_c001_v7007000_t0000_u01726374119281") 2024/09/15 04:22:19 DEBUG : both17: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a360c_d20240915_m042152_c001_v0001101_t0022_u01726374112108") 2024/09/15 04:22:19 DEBUG : both17: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61ed_d20240915_m042130_c001_v7007000_t0000_u01726374090294") 2024/09/15 04:22:19 DEBUG : both17: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a34a4_d20240915_m042122_c001_v0001101_t0014_u01726374082068") 2024/09/15 04:22:19 DEBUG : both18: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922326_d20240915_m042159_c001_v7007000_t0000_u01726374119463") 2024/09/15 04:22:19 DEBUG : both18: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3626_d20240915_m042153_c001_v0001101_t0047_u01726374113345") 2024/09/15 04:22:19 DEBUG : both18: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61ef_d20240915_m042130_c001_v7007000_t0000_u01726374090477") 2024/09/15 04:22:19 DEBUG : both18: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a34b2_d20240915_m042123_c001_v0001101_t0021_u01726374083334") 2024/09/15 04:22:20 DEBUG : both19: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922327_d20240915_m042159_c001_v7007000_t0000_u01726374119649") 2024/09/15 04:22:20 DEBUG : both19: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a362c_d20240915_m042154_c001_v0001101_t0051_u01726374114012") 2024/09/15 04:22:20 DEBUG : both19: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61f1_d20240915_m042130_c001_v7007000_t0000_u01726374090664") 2024/09/15 04:22:20 DEBUG : both19: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a34bc_d20240915_m042124_c001_v0001101_t0040_u01726374084839") 2024/09/15 04:22:20 DEBUG : both2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922328_d20240915_m042159_c001_v7007000_t0000_u01726374119832") 2024/09/15 04:22:20 DEBUG : both2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a353c_d20240915_m042135_c001_v0001101_t0044_u01726374095878") 2024/09/15 04:22:20 DEBUG : both2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61f3_d20240915_m042130_c001_v7007000_t0000_u01726374090846") 2024/09/15 04:22:20 DEBUG : both2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a33e2_d20240915_m042105_c001_v0001101_t0029_u01726374065245") 2024/09/15 04:22:20 DEBUG : both3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922329_d20240915_m042200_c001_v7007000_t0000_u01726374120028") 2024/09/15 04:22:20 DEBUG : both3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3546_d20240915_m042136_c001_v0001101_t0049_u01726374096662") 2024/09/15 04:22:20 DEBUG : both3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61f5_d20240915_m042131_c001_v7007000_t0000_u01726374091027") 2024/09/15 04:22:20 DEBUG : both3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a33ea_d20240915_m042106_c001_v0001101_t0048_u01726374066605") 2024/09/15 04:22:20 DEBUG : both4: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592232a_d20240915_m042200_c001_v7007000_t0000_u01726374120212") 2024/09/15 04:22:20 DEBUG : both4: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3556_d20240915_m042137_c001_v0001101_t0053_u01726374097543") 2024/09/15 04:22:20 DEBUG : both4: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61f6_d20240915_m042131_c001_v7007000_t0000_u01726374091210") 2024/09/15 04:22:20 DEBUG : both4: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a33f8_d20240915_m042107_c001_v0001101_t0058_u01726374067372") 2024/09/15 04:22:20 DEBUG : both5: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592232c_d20240915_m042200_c001_v7007000_t0000_u01726374120406") 2024/09/15 04:22:21 DEBUG : both5: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a355e_d20240915_m042138_c001_v0001101_t0053_u01726374098259") 2024/09/15 04:22:21 DEBUG : both5: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61f9_d20240915_m042131_c001_v7007000_t0000_u01726374091405") 2024/09/15 04:22:21 DEBUG : both5: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3402_d20240915_m042108_c001_v0001101_t0059_u01726374068000") 2024/09/15 04:22:21 DEBUG : both6: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592232e_d20240915_m042200_c001_v7007000_t0000_u01726374120588") 2024/09/15 04:22:21 DEBUG : both6: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a356c_d20240915_m042140_c001_v0001101_t0016_u01726374100026") 2024/09/15 04:22:21 DEBUG : both6: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61fa_d20240915_m042131_c001_v7007000_t0000_u01726374091587") 2024/09/15 04:22:21 DEBUG : both6: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a340c_d20240915_m042108_c001_v0001101_t0010_u01726374068848") 2024/09/15 04:22:21 DEBUG : both7: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592232f_d20240915_m042200_c001_v7007000_t0000_u01726374120771") 2024/09/15 04:22:21 DEBUG : both7: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3576_d20240915_m042141_c001_v0001101_t0006_u01726374101239") 2024/09/15 04:22:21 DEBUG : both7: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61fc_d20240915_m042131_c001_v7007000_t0000_u01726374091775") 2024/09/15 04:22:21 DEBUG : both7: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a341a_d20240915_m042110_c001_v0001101_t0035_u01726374070168") 2024/09/15 04:22:21 DEBUG : both8: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922330_d20240915_m042200_c001_v7007000_t0000_u01726374120953") 2024/09/15 04:22:21 DEBUG : both8: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a358e_d20240915_m042143_c001_v0001101_t0018_u01726374103168") 2024/09/15 04:22:21 DEBUG : both8: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61fe_d20240915_m042131_c001_v7007000_t0000_u01726374091957") 2024/09/15 04:22:21 DEBUG : both8: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3422_d20240915_m042110_c001_v0001101_t0038_u01726374070911") 2024/09/15 04:22:21 DEBUG : both9: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922331_d20240915_m042201_c001_v7007000_t0000_u01726374121136") 2024/09/15 04:22:21 DEBUG : both9: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3598_d20240915_m042144_c001_v0001101_t0037_u01726374104276") 2024/09/15 04:22:21 DEBUG : both9: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61ff_d20240915_m042132_c001_v7007000_t0000_u01726374092139") 2024/09/15 04:22:21 DEBUG : both9: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a342a_d20240915_m042111_c001_v0001101_t0055_u01726374071821") 2024/09/15 04:22:21 DEBUG : c/non empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5faac_d20240915_m041610_c001_v7007000_t0000_u01726373770683") 2024/09/15 04:22:21 DEBUG : c/non empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2800_d20240915_m041608_c001_v0001101_t0032_u01726373768495") 2024/09/15 04:22:22 DEBUG : c/non empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65cdd1_d20240915_m041607_c001_v7007000_t0000_u01726373767255") 2024/09/15 04:22:22 DEBUG : c/non empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a27e1_d20240915_m041605_c001_v0001101_t0031_u01726373765320") 2024/09/15 04:22:22 DEBUG : c/non empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa72_d20240915_m041543_c001_v7007000_t0000_u01726373743905") 2024/09/15 04:22:22 DEBUG : c/non empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a26dd_d20240915_m041541_c001_v0001101_t0049_u01726373741759") 2024/09/15 04:22:22 DEBUG : check sum: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f862cf_d20240915_m041529_c001_v7007000_t0000_u01726373729280") 2024/09/15 04:22:22 DEBUG : check sum: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a263b_d20240915_m041528_c001_v0001101_t0029_u01726373728161") 2024/09/15 04:22:22 DEBUG : dest/3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a29c8_d20240915_m041837_c001_v7007000_t0000_u01726373917092") 2024/09/15 04:22:22 DEBUG : dest/3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416cfe50d3ce7867_d20240915_m041835_c001_v0001116_t0035_u01726373915971") 2024/09/15 04:22:22 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f402c22ca267bef75_d20240915_m042050_c001_v7007000_t0000_u01726374050361") 2024/09/15 04:22:22 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a10416f_d20240915_m042045_c001_v0001130_t0046_u01726374045849") 2024/09/15 04:22:22 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922305_d20240915_m042045_c001_v7007000_t0000_u01726374045666") 2024/09/15 04:22:22 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104145_d20240915_m042039_c001_v0001130_t0040_u01726374039847") 2024/09/15 04:22:22 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb0859222ff_d20240915_m042039_c001_v7007000_t0000_u01726374039108") 2024/09/15 04:22:22 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104113_d20240915_m042033_c001_v0001130_t0050_u01726374033736") 2024/09/15 04:22:22 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65d6c2_d20240915_m042032_c001_v7007000_t0000_u01726374032258") 2024/09/15 04:22:22 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f104c822b93999aa6_d20240915_m042023_c001_v0001113_t0031_u01726374023338") 2024/09/15 04:22:22 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40394297fe074414_d20240915_m042023_c001_v7007000_t0000_u01726374023155") 2024/09/15 04:22:22 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f104c822b93999a88_d20240915_m042018_c001_v0001113_t0036_u01726374018930") 2024/09/15 04:22:22 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40394297fe07440e_d20240915_m042018_c001_v7007000_t0000_u01726374018189") 2024/09/15 04:22:22 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a31f5_d20240915_m042013_c001_v0001101_t0049_u01726374013401") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f87987_d20240915_m042012_c001_v7007000_t0000_u01726374012109") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f103946ae3bbbe0b2_d20240915_m042009_c001_v0001178_t0042_u01726374009018") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40394297fe0743ff_d20240915_m042008_c001_v7007000_t0000_u01726374008835") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f103946ae3bbbe0a8_d20240915_m042005_c001_v0001178_t0055_u01726374005883") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40394297fe0743ee_d20240915_m042005_c001_v7007000_t0000_u01726374005154") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a317d_d20240915_m042000_c001_v0001101_t0026_u01726374000700") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f87972_d20240915_m041959_c001_v7007000_t0000_u01726373999311") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1181bdd59ce2af9d_d20240915_m041953_c001_v0001090_t0046_u01726373993161") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65d663_d20240915_m041952_c001_v7007000_t0000_u01726373992973") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1181bdd59ce2af99_d20240915_m041949_c001_v0001090_t0024_u01726373989466") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65d65d_d20240915_m041948_c001_v7007000_t0000_u01726373988727") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a30be_d20240915_m041942_c001_v0001101_t0040_u01726373982636") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f87930_d20240915_m041941_c001_v7007000_t0000_u01726373981708") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f115bd75257220b12_d20240915_m041939_c001_v0001179_t0052_u01726373979646") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922287_d20240915_m041939_c001_v7007000_t0000_u01726373979459") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f115bd75257220afc_d20240915_m041935_c001_v0001179_t0023_u01726373975512") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922285_d20240915_m041934_c001_v7007000_t0000_u01726373974780") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a302a_d20240915_m041928_c001_v0001101_t0030_u01726373968863") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f419f0e154ce1f41b_d20240915_m041927_c001_v7007000_t0000_u01726373967943") 2024/09/15 04:22:23 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f106449ba6abd4bca_d20240915_m041926_c001_v0001098_t0049_u01726373966099") 2024/09/15 04:22:24 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40034b1fc5956172_d20240915_m041925_c001_v7007000_t0000_u01726373965550") 2024/09/15 04:22:24 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f106449ba6abd4ba0_d20240915_m041920_c001_v0001098_t0051_u01726373960260") 2024/09/15 04:22:24 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40034b1fc5956148_d20240915_m041919_c001_v7007000_t0000_u01726373959533") 2024/09/15 04:22:24 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2f05_d20240915_m041859_c001_v0001101_t0039_u01726373939674") 2024/09/15 04:22:24 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a29d6_d20240915_m041858_c001_v7007000_t0000_u01726373938567") 2024/09/15 04:22:24 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f111f6c33a6d87c41_d20240915_m041849_c001_v0001172_t0054_u01726373929421") 2024/09/15 04:22:24 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf5efd_d20240915_m041849_c001_v7007000_t0000_u01726373929197") 2024/09/15 04:22:24 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103cab_d20240915_m041844_c001_v0001130_t0008_u01726373924606") 2024/09/15 04:22:24 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1188136af314c9b8_d20240915_m041843_c001_v0001117_t0027_u01726373923304") 2024/09/15 04:22:24 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1188136af314c9a4_d20240915_m041841_c001_v0001117_t0013_u01726373921549") 2024/09/15 04:22:24 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f409f8787e560e332_d20240915_m041827_c001_v7007000_t0000_u01726373907403") 2024/09/15 04:22:24 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2d53_d20240915_m041816_c001_v0001101_t0016_u01726373896943") 2024/09/15 04:22:24 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f109a52bfed4a819e_d20240915_m041815_c001_v0001044_t0016_u01726373895738") 2024/09/15 04:22:24 DEBUG : dst/one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f109a52bfed4a8198_d20240915_m041813_c001_v0001044_t0020_u01726373893947") 2024/09/15 04:22:24 DEBUG : dst/one-2019-01-01: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f402c22ca267bef76_d20240915_m042050_c001_v7007000_t0000_u01726374050548") 2024/09/15 04:22:24 DEBUG : dst/one-2019-01-01: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f11466b0e18a43782_d20240915_m042043_c001_v0001113_t0011_u01726374043996") 2024/09/15 04:22:24 DEBUG : dst/one-2019-01-01: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f108e0b6438b06afa_d20240915_m042038_c001_v0001115_t0034_u01726374038364") 2024/09/15 04:22:24 DEBUG : dst/one.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65d6c3_d20240915_m042032_c001_v7007000_t0000_u01726374032438") 2024/09/15 04:22:24 DEBUG : dst/one.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1051d91c76743fd7_d20240915_m042022_c001_v0001145_t0056_u01726374022408") 2024/09/15 04:22:25 DEBUG : dst/one.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1186d50d6dc8aa53_d20240915_m042017_c001_v0001098_t0035_u01726374017688") 2024/09/15 04:22:25 DEBUG : dst/one.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f87988_d20240915_m042012_c001_v7007000_t0000_u01726374012296") 2024/09/15 04:22:25 DEBUG : dst/one.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f100a46b66927e169_d20240915_m042008_c001_v0001154_t0025_u01726374008532") 2024/09/15 04:22:25 DEBUG : dst/one.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f11516014c0d04293_d20240915_m042004_c001_v0001120_t0036_u01726374004831") 2024/09/15 04:22:25 DEBUG : dst/three: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a29d7_d20240915_m041858_c001_v7007000_t0000_u01726373938748") 2024/09/15 04:22:25 DEBUG : dst/three: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1188136af314ca14_d20240915_m041856_c001_v0001117_t0019_u01726373936533") 2024/09/15 04:22:25 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f402c22ca267bef77_d20240915_m042050_c001_v7007000_t0000_u01726374050732") 2024/09/15 04:22:25 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f101464bf672006bd_d20240915_m042046_c001_v0001101_t0008_u01726374046959") 2024/09/15 04:22:25 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f109af77d5ae6434a_d20240915_m042041_c001_v0001128_t0036_u01726374041452") 2024/09/15 04:22:25 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f402c22ca267bef78_d20240915_m042050_c001_v7007000_t0000_u01726374050915") 2024/09/15 04:22:25 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104181_d20240915_m042048_c001_v0001130_t0021_u01726374048058") 2024/09/15 04:22:25 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922306_d20240915_m042047_c001_v7007000_t0000_u01726374047876") 2024/09/15 04:22:25 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104153_d20240915_m042042_c001_v0001130_t0008_u01726374042059") 2024/09/15 04:22:25 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922301_d20240915_m042041_c001_v7007000_t0000_u01726374041872") 2024/09/15 04:22:25 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a10411d_d20240915_m042035_c001_v0001130_t0010_u01726374035481") 2024/09/15 04:22:25 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65d6c4_d20240915_m042032_c001_v7007000_t0000_u01726374032619") 2024/09/15 04:22:25 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f104c822b93999ace_d20240915_m042030_c001_v0001113_t0000_u01726374030813") 2024/09/15 04:22:25 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40394297fe07441b_d20240915_m042030_c001_v7007000_t0000_u01726374030250") 2024/09/15 04:22:25 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f104c822b93999a94_d20240915_m042020_c001_v0001113_t0013_u01726374020575") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40394297fe074412_d20240915_m042020_c001_v7007000_t0000_u01726374020392") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3205_d20240915_m042014_c001_v0001101_t0036_u01726374014705") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40394297fe074408_d20240915_m042011_c001_v7007000_t0000_u01726374011556") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a10400b_d20240915_m042007_c001_v0001130_t0027_u01726374007353") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40394297fe0743f4_d20240915_m042006_c001_v7007000_t0000_u01726374006603") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3185_d20240915_m042001_c001_v0001101_t0020_u01726374001915") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65d674_d20240915_m041957_c001_v7007000_t0000_u01726373997934") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103f6f_d20240915_m041951_c001_v0001130_t0045_u01726373991720") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65d65f_d20240915_m041950_c001_v7007000_t0000_u01726373990615") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a30c4_d20240915_m041943_c001_v0001101_t0041_u01726373983461") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922288_d20240915_m041940_c001_v7007000_t0000_u01726373980615") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103ef9_d20240915_m041938_c001_v0001130_t0031_u01726373978127") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922286_d20240915_m041936_c001_v7007000_t0000_u01726373976450") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a3035_d20240915_m041929_c001_v0001101_t0047_u01726373969598") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40034b1fc5956178_d20240915_m041927_c001_v7007000_t0000_u01726373967015") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103e46_d20240915_m041922_c001_v0001130_t0007_u01726373962658") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40034b1fc5956157_d20240915_m041921_c001_v7007000_t0000_u01726373961544") 2024/09/15 04:22:26 DEBUG : dst/three.txt: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2f87_d20240915_m041914_c001_v0001101_t0014_u01726373954253") 2024/09/15 04:22:26 DEBUG : dst/three.txt.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65d6c5_d20240915_m042032_c001_v7007000_t0000_u01726374032800") 2024/09/15 04:22:26 DEBUG : dst/three.txt.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1086e1e808ddadc8_d20240915_m042024_c001_v0001105_t0009_u01726374024713") 2024/09/15 04:22:27 DEBUG : dst/three.txt.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1059be88cdced0f9_d20240915_m042020_c001_v0001180_t0007_u01726374020161") 2024/09/15 04:22:27 DEBUG : dst/three.txt.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f8798b_d20240915_m042012_c001_v7007000_t0000_u01726374012478") 2024/09/15 04:22:27 DEBUG : dst/three.txt.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f116d58358dd68b60_d20240915_m042009_c001_v0001109_t0035_u01726374009616") 2024/09/15 04:22:27 DEBUG : dst/three.txt.bak: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f102e795720b697bf_d20240915_m042006_c001_v0001157_t0008_u01726374006300") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f402c22ca267bef79_d20240915_m042051_c001_v7007000_t0000_u01726374051150") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a32b0_d20240915_m042034_c001_v0001101_t0002_u01726374034864") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65d6c6_d20240915_m042032_c001_v7007000_t0000_u01726374032987") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a10404d_d20240915_m042014_c001_v0001130_t0035_u01726374014429") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f8798c_d20240915_m042012_c001_v7007000_t0000_u01726374012661") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103fd3_d20240915_m042001_c001_v0001130_t0037_u01726374001626") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f87976_d20240915_m041959_c001_v7007000_t0000_u01726373999951") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103f21_d20240915_m041943_c001_v0001130_t0025_u01726373983016") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f87934_d20240915_m041941_c001_v7007000_t0000_u01726373981892") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103ea2_d20240915_m041929_c001_v0001130_t0039_u01726373969276") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f419f0e154ce1f41e_d20240915_m041928_c001_v7007000_t0000_u01726373968124") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103de2_d20240915_m041913_c001_v0001130_t0039_u01726373953487") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a29d8_d20240915_m041858_c001_v7007000_t0000_u01726373938931") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10489edccd671230_d20240915_m041851_c001_v0001037_t0017_u01726373931699") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f409f8787e560e333_d20240915_m041827_c001_v7007000_t0000_u01726373907587") 2024/09/15 04:22:27 DEBUG : dst/two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f109a52bfed4a81b5_d20240915_m041825_c001_v0001044_t0010_u01726373905149") 2024/09/15 04:22:27 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f405a8ff92a06f320_d20240915_m041730_c001_v7007000_t0000_u01726373850787") 2024/09/15 04:22:27 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a10399a_d20240915_m041725_c001_v0001130_t0004_u01726373845934") 2024/09/15 04:22:27 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f405a8ff92a06f303_d20240915_m041723_c001_v7007000_t0000_u01726373843601") 2024/09/15 04:22:27 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1038e3_d20240915_m041710_c001_v0001130_t0053_u01726373830888") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a2962_d20240915_m041700_c001_v7007000_t0000_u01726373820825") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103851_d20240915_m041657_c001_v0001130_t0024_u01726373817455") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce1e_d20240915_m041626_c001_v7007000_t0000_u01726373786784") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a28b7_d20240915_m041624_c001_v0001101_t0031_u01726373784424") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce19_d20240915_m041622_c001_v7007000_t0000_u01726373782748") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a288d_d20240915_m041621_c001_v0001101_t0049_u01726373781142") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce0f_d20240915_m041617_c001_v7007000_t0000_u01726373777238") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a284d_d20240915_m041614_c001_v0001101_t0054_u01726373774952") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fab3_d20240915_m041613_c001_v7007000_t0000_u01726373773633") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2823_d20240915_m041611_c001_v0001101_t0042_u01726373771770") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa95_d20240915_m041603_c001_v7007000_t0000_u01726373763955") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a27c3_d20240915_m041602_c001_v0001101_t0035_u01726373762363") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa8c_d20240915_m041601_c001_v7007000_t0000_u01726373761156") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a27b5_d20240915_m041600_c001_v0001101_t0042_u01726373760285") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa7f_d20240915_m041554_c001_v7007000_t0000_u01726373754458") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2752_d20240915_m041552_c001_v0001101_t0014_u01726373752782") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa7c_d20240915_m041550_c001_v7007000_t0000_u01726373750209") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2728_d20240915_m041548_c001_v0001101_t0045_u01726373748764") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa79_d20240915_m041547_c001_v7007000_t0000_u01726373747672") 2024/09/15 04:22:28 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f107c8b5a640534d1_d20240915_m041546_c001_v0001109_t0033_u01726373746421") 2024/09/15 04:22:29 DEBUG : empty space: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a26fd_d20240915_m041544_c001_v0001101_t0050_u01726373744637") 2024/09/15 04:22:29 DEBUG : enormous: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce1c_d20240915_m041625_c001_v7007000_t0000_u01726373785994") 2024/09/15 04:22:29 DEBUG : enormous: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a28bf_d20240915_m041624_c001_v0001101_t0009_u01726373784970") 2024/09/15 04:22:29 DEBUG : existing: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f402c22ca267bef7c_d20240915_m042057_c001_v7007000_t0000_u01726374057646") 2024/09/15 04:22:29 DEBUG : existing: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a337c_d20240915_m042056_c001_v0001101_t0019_u01726374056202") 2024/09/15 04:22:29 DEBUG : existing: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a2956_d20240915_m041655_c001_v7007000_t0000_u01726373815052") 2024/09/15 04:22:29 DEBUG : existing: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103815_d20240915_m041653_c001_v0001130_t0053_u01726373813453") 2024/09/15 04:22:29 DEBUG : existing: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f862e4_d20240915_m041540_c001_v7007000_t0000_u01726373740258") 2024/09/15 04:22:29 DEBUG : existing: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a26c1_d20240915_m041539_c001_v0001101_t0033_u01726373739031") 2024/09/15 04:22:29 DEBUG : existing: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f862e1_d20240915_m041537_c001_v7007000_t0000_u01726373737754") 2024/09/15 04:22:29 DEBUG : existing: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a26a3_d20240915_m041536_c001_v0001101_t0058_u01726373736923") 2024/09/15 04:22:29 DEBUG : existing: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2697_d20240915_m041536_c001_v0001101_t0041_u01726373736053") 2024/09/15 04:22:29 DEBUG : existing-b: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a2957_d20240915_m041655_c001_v7007000_t0000_u01726373815234") 2024/09/15 04:22:29 DEBUG : existing-b: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2a02_d20240915_m041653_c001_v0001101_t0040_u01726373813815") 2024/09/15 04:22:29 DEBUG : five: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce23_d20240915_m041631_c001_v7007000_t0000_u01726373791463") 2024/09/15 04:22:29 DEBUG : five: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4038c3f1fe0a28ed_d20240915_m041629_c001_v0001101_t0057_u01726373789298") 2024/09/15 04:22:29 DEBUG : foo: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa7d_d20240915_m041552_c001_v7007000_t0000_u01726373752047") 2024/09/15 04:22:29 DEBUG : foo: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4038c3f1fe0a2746_d20240915_m041551_c001_v0001101_t0057_u01726373751495") 2024/09/15 04:22:29 DEBUG : foo: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4038c3f1fe0a2740_d20240915_m041550_c001_v0001101_t0057_u01726373750939") 2024/09/15 04:22:29 DEBUG : four: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce26_d20240915_m041631_c001_v7007000_t0000_u01726373791643") 2024/09/15 04:22:30 DEBUG : four: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40779af57a103703_d20240915_m041629_c001_v0001130_t0019_u01726373789882") 2024/09/15 04:22:30 DEBUG : four: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a28e9_d20240915_m041628_c001_v0001101_t0049_u01726373788586") 2024/09/15 04:22:30 DEBUG : hello world2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4066c3b9fd2fb2af_d20240915_m041502_c001_v7007000_t0000_u01726373702032") 2024/09/15 04:22:30 DEBUG : hello world2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2525_d20240915_m041501_c001_v0001101_t0044_u01726373701278") 2024/09/15 04:22:30 DEBUG : ignore-size: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f862de_d20240915_m041534_c001_v7007000_t0000_u01726373734952") 2024/09/15 04:22:30 DEBUG : ignore-size: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2673_d20240915_m041533_c001_v0001101_t0036_u01726373733355") 2024/09/15 04:22:30 DEBUG : nested/sub dir/file: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a2953_d20240915_m041652_c001_v7007000_t0000_u01726373812340") 2024/09/15 04:22:30 DEBUG : nested/sub dir/file: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1037e5_d20240915_m041649_c001_v0001130_t0056_u01726373809424") 2024/09/15 04:22:30 DEBUG : nested/sub dir/file: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a2950_d20240915_m041648_c001_v7007000_t0000_u01726373808310") 2024/09/15 04:22:30 DEBUG : nested/sub dir/file: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1037cb_d20240915_m041647_c001_v0001130_t0035_u01726373807241") 2024/09/15 04:22:30 DEBUG : one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce27_d20240915_m041631_c001_v7007000_t0000_u01726373791825") 2024/09/15 04:22:30 DEBUG : one: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4038c3f1fe0a28db_d20240915_m041627_c001_v0001101_t0057_u01726373787911") 2024/09/15 04:22:30 DEBUG : only0: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922332_d20240915_m042201_c001_v7007000_t0000_u01726374121319") 2024/09/15 04:22:30 DEBUG : only0: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4038c3f1fe0a3648_d20240915_m042155_c001_v0001101_t0057_u01726374115583") 2024/09/15 04:22:30 DEBUG : only0: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a10433f_d20240915_m042134_c001_v0001130_t0023_u01726374094052") 2024/09/15 04:22:30 DEBUG : only0: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f411edb8b3df37b5f_d20240915_m042127_c001_v7007000_t0000_u01726374087882") 2024/09/15 04:22:30 DEBUG : only0: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a10421a_d20240915_m042103_c001_v0001130_t0053_u01726374063784") 2024/09/15 04:22:30 DEBUG : only1: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922333_d20240915_m042201_c001_v7007000_t0000_u01726374121502") 2024/09/15 04:22:30 DEBUG : only1: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40779af57a104421_d20240915_m042155_c001_v0001130_t0019_u01726374115593") 2024/09/15 04:22:30 DEBUG : only1: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104349_d20240915_m042135_c001_v0001130_t0036_u01726374095202") 2024/09/15 04:22:30 DEBUG : only1: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f87a0e_d20240915_m042127_c001_v7007000_t0000_u01726374087440") 2024/09/15 04:22:30 DEBUG : only1: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104220_d20240915_m042104_c001_v0001130_t0026_u01726374064476") 2024/09/15 04:22:31 DEBUG : only10: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922335_d20240915_m042201_c001_v7007000_t0000_u01726374121708") 2024/09/15 04:22:31 DEBUG : only10: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f415ac0f03240c4da_d20240915_m042156_c001_v0001130_t0029_u01726374116160") 2024/09/15 04:22:31 DEBUG : only10: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1043af_d20240915_m042145_c001_v0001130_t0056_u01726374105871") 2024/09/15 04:22:31 DEBUG : only10: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61d0_d20240915_m042127_c001_v7007000_t0000_u01726374087246") 2024/09/15 04:22:31 DEBUG : only10: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104289_d20240915_m042113_c001_v0001130_t0038_u01726374073854") 2024/09/15 04:22:31 DEBUG : only11: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922336_d20240915_m042201_c001_v7007000_t0000_u01726374121906") 2024/09/15 04:22:31 DEBUG : only11: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4078d84a930b2477_d20240915_m042156_c001_v0001137_t0042_u01726374116339") 2024/09/15 04:22:31 DEBUG : only11: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1043b3_d20240915_m042146_c001_v0001130_t0009_u01726374106634") 2024/09/15 04:22:31 DEBUG : only11: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f411edb8b3df37b5e_d20240915_m042127_c001_v7007000_t0000_u01726374087642") 2024/09/15 04:22:31 DEBUG : only11: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a10428d_d20240915_m042114_c001_v0001130_t0056_u01726374074573") 2024/09/15 04:22:31 DEBUG : only12: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922337_d20240915_m042202_c001_v7007000_t0000_u01726374122088") 2024/09/15 04:22:31 DEBUG : only12: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4038c3f1fe0a364c_d20240915_m042155_c001_v0001101_t0057_u01726374115982") 2024/09/15 04:22:31 DEBUG : only12: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1043bd_d20240915_m042147_c001_v0001130_t0025_u01726374107670") 2024/09/15 04:22:31 DEBUG : only12: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f87a0f_d20240915_m042127_c001_v7007000_t0000_u01726374087655") 2024/09/15 04:22:31 DEBUG : only12: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a10429b_d20240915_m042116_c001_v0001130_t0047_u01726374076207") 2024/09/15 04:22:31 DEBUG : only13: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922338_d20240915_m042202_c001_v7007000_t0000_u01726374122271") 2024/09/15 04:22:31 DEBUG : only13: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40779af57a10442a_d20240915_m042155_c001_v0001130_t0019_u01726374115995") 2024/09/15 04:22:31 DEBUG : only13: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1043c9_d20240915_m042148_c001_v0001130_t0046_u01726374108585") 2024/09/15 04:22:31 DEBUG : only13: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f87a10_d20240915_m042127_c001_v7007000_t0000_u01726374087878") 2024/09/15 04:22:31 DEBUG : only13: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1042a9_d20240915_m042117_c001_v0001130_t0045_u01726374077918") 2024/09/15 04:22:32 DEBUG : only14: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922339_d20240915_m042202_c001_v7007000_t0000_u01726374122454") 2024/09/15 04:22:32 DEBUG : only14: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4038c3f1fe0a364e_d20240915_m042156_c001_v0001101_t0057_u01726374116165") 2024/09/15 04:22:32 DEBUG : only14: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1043d5_d20240915_m042149_c001_v0001130_t0021_u01726374109842") 2024/09/15 04:22:32 DEBUG : only14: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f403c4170a6dfdc18_d20240915_m042127_c001_v7007000_t0000_u01726374087785") 2024/09/15 04:22:32 DEBUG : only14: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1042af_d20240915_m042118_c001_v0001130_t0037_u01726374078853") 2024/09/15 04:22:32 DEBUG : only15: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592233a_d20240915_m042202_c001_v7007000_t0000_u01726374122637") 2024/09/15 04:22:32 DEBUG : only15: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40779af57a10442d_d20240915_m042156_c001_v0001130_t0019_u01726374116180") 2024/09/15 04:22:32 DEBUG : only15: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1043e5_d20240915_m042150_c001_v0001130_t0024_u01726374110740") 2024/09/15 04:22:32 DEBUG : only15: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f403c4170a6dfdc14_d20240915_m042127_c001_v7007000_t0000_u01726374087057") 2024/09/15 04:22:32 DEBUG : only15: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1042c5_d20240915_m042120_c001_v0001130_t0056_u01726374080735") 2024/09/15 04:22:32 DEBUG : only16: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592233b_d20240915_m042202_c001_v7007000_t0000_u01726374122821") 2024/09/15 04:22:32 DEBUG : only16: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f415ac0f03240c4de_d20240915_m042156_c001_v0001130_t0029_u01726374116345") 2024/09/15 04:22:32 DEBUG : only16: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1043ed_d20240915_m042151_c001_v0001130_t0044_u01726374111771") 2024/09/15 04:22:32 DEBUG : only16: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f87a0d_d20240915_m042127_c001_v7007000_t0000_u01726374087253") 2024/09/15 04:22:32 DEBUG : only16: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1042cf_d20240915_m042121_c001_v0001130_t0006_u01726374081685") 2024/09/15 04:22:32 DEBUG : only17: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592233c_d20240915_m042203_c001_v7007000_t0000_u01726374123003") 2024/09/15 04:22:32 DEBUG : only17: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4038c3f1fe0a3658_d20240915_m042156_c001_v0001101_t0057_u01726374116354") 2024/09/15 04:22:32 DEBUG : only17: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1043f5_d20240915_m042152_c001_v0001130_t0000_u01726374112785") 2024/09/15 04:22:33 DEBUG : only17: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f411edb8b3df37b5c_d20240915_m042127_c001_v7007000_t0000_u01726374087279") 2024/09/15 04:22:33 DEBUG : only17: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1042d7_d20240915_m042122_c001_v0001130_t0000_u01726374082481") 2024/09/15 04:22:33 DEBUG : only18: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592233d_d20240915_m042203_c001_v7007000_t0000_u01726374123185") 2024/09/15 04:22:33 DEBUG : only18: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40779af57a10442f_d20240915_m042156_c001_v0001130_t0019_u01726374116367") 2024/09/15 04:22:33 DEBUG : only18: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104401_d20240915_m042153_c001_v0001130_t0049_u01726374113672") 2024/09/15 04:22:33 DEBUG : only18: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f403c4170a6dfdc19_d20240915_m042127_c001_v7007000_t0000_u01726374087969") 2024/09/15 04:22:33 DEBUG : only18: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1042e9_d20240915_m042124_c001_v0001130_t0019_u01726374084174") 2024/09/15 04:22:33 DEBUG : only19: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592233e_d20240915_m042203_c001_v7007000_t0000_u01726374123367") 2024/09/15 04:22:33 DEBUG : only19: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40779af57a104435_d20240915_m042156_c001_v0001130_t0019_u01726374116739") 2024/09/15 04:22:33 DEBUG : only19: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104415_d20240915_m042154_c001_v0001130_t0011_u01726374114518") 2024/09/15 04:22:33 DEBUG : only19: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f403c4170a6dfdc16_d20240915_m042127_c001_v7007000_t0000_u01726374087421") 2024/09/15 04:22:33 DEBUG : only19: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1042f7_d20240915_m042125_c001_v0001130_t0028_u01726374085369") 2024/09/15 04:22:33 DEBUG : only2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592233f_d20240915_m042203_c001_v7007000_t0000_u01726374123551") 2024/09/15 04:22:33 DEBUG : only2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4038c3f1fe0a365c_d20240915_m042156_c001_v0001101_t0057_u01726374116730") 2024/09/15 04:22:33 DEBUG : only2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104359_d20240915_m042136_c001_v0001130_t0010_u01726374096279") 2024/09/15 04:22:33 DEBUG : only2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61d2_d20240915_m042127_c001_v7007000_t0000_u01726374087432") 2024/09/15 04:22:33 DEBUG : only2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104233_d20240915_m042106_c001_v0001130_t0034_u01726374066116") 2024/09/15 04:22:33 DEBUG : only3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922340_d20240915_m042203_c001_v7007000_t0000_u01726374123734") 2024/09/15 04:22:33 DEBUG : only3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4078d84a930b247d_d20240915_m042156_c001_v0001137_t0042_u01726374116889") 2024/09/15 04:22:33 DEBUG : only3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a10435f_d20240915_m042137_c001_v0001130_t0030_u01726374097040") 2024/09/15 04:22:34 DEBUG : only3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f411edb8b3df37b5d_d20240915_m042127_c001_v7007000_t0000_u01726374087461") 2024/09/15 04:22:34 DEBUG : only3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a10423b_d20240915_m042107_c001_v0001130_t0053_u01726374067035") 2024/09/15 04:22:34 DEBUG : only4: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922341_d20240915_m042203_c001_v7007000_t0000_u01726374123929") 2024/09/15 04:22:34 DEBUG : only4: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4078d84a930b2479_d20240915_m042156_c001_v0001137_t0042_u01726374116522") 2024/09/15 04:22:34 DEBUG : only4: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104365_d20240915_m042137_c001_v0001130_t0016_u01726374097901") 2024/09/15 04:22:34 DEBUG : only4: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61d7_d20240915_m042127_c001_v7007000_t0000_u01726374087796") 2024/09/15 04:22:34 DEBUG : only4: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a10423f_d20240915_m042107_c001_v0001130_t0043_u01726374067668") 2024/09/15 04:22:34 DEBUG : only5: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922342_d20240915_m042204_c001_v7007000_t0000_u01726374124123") 2024/09/15 04:22:34 DEBUG : only5: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f415ac0f03240c4e0_d20240915_m042156_c001_v0001130_t0029_u01726374116533") 2024/09/15 04:22:34 DEBUG : only5: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104369_d20240915_m042138_c001_v0001130_t0043_u01726374098566") 2024/09/15 04:22:34 DEBUG : only5: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f403c4170a6dfdc17_d20240915_m042127_c001_v7007000_t0000_u01726374087604") 2024/09/15 04:22:34 DEBUG : only5: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104249_d20240915_m042108_c001_v0001130_t0026_u01726374068419") 2024/09/15 04:22:34 DEBUG : only6: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922343_d20240915_m042204_c001_v7007000_t0000_u01726374124308") 2024/09/15 04:22:34 DEBUG : only6: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4038c3f1fe0a365a_d20240915_m042156_c001_v0001101_t0057_u01726374116541") 2024/09/15 04:22:34 DEBUG : only6: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104377_d20240915_m042140_c001_v0001130_t0038_u01726374100500") 2024/09/15 04:22:34 DEBUG : only6: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f403c4170a6dfdc13_d20240915_m042126_c001_v7007000_t0000_u01726374086872") 2024/09/15 04:22:34 DEBUG : only6: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104255_d20240915_m042109_c001_v0001130_t0014_u01726374069312") 2024/09/15 04:22:34 DEBUG : only7: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922344_d20240915_m042204_c001_v7007000_t0000_u01726374124490") 2024/09/15 04:22:34 DEBUG : only7: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40779af57a104433_d20240915_m042156_c001_v0001130_t0019_u01726374116554") 2024/09/15 04:22:34 DEBUG : only7: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104383_d20240915_m042141_c001_v0001130_t0018_u01726374101611") 2024/09/15 04:22:35 DEBUG : only7: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61d5_d20240915_m042127_c001_v7007000_t0000_u01726374087613") 2024/09/15 04:22:35 DEBUG : only7: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a10426b_d20240915_m042110_c001_v0001130_t0056_u01726374070540") 2024/09/15 04:22:35 DEBUG : only8: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922345_d20240915_m042204_c001_v7007000_t0000_u01726374124686") 2024/09/15 04:22:35 DEBUG : only8: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4078d84a930b247b_d20240915_m042156_c001_v0001137_t0042_u01726374116707") 2024/09/15 04:22:35 DEBUG : only8: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104397_d20240915_m042143_c001_v0001130_t0021_u01726374103628") 2024/09/15 04:22:35 DEBUG : only8: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf61d9_d20240915_m042127_c001_v7007000_t0000_u01726374087997") 2024/09/15 04:22:35 DEBUG : only8: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104275_d20240915_m042111_c001_v0001130_t0027_u01726374071268") 2024/09/15 04:22:35 DEBUG : only9: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922346_d20240915_m042204_c001_v7007000_t0000_u01726374124870") 2024/09/15 04:22:35 DEBUG : only9: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f415ac0f03240c4e4_d20240915_m042156_c001_v0001130_t0029_u01726374116716") 2024/09/15 04:22:35 DEBUG : only9: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a10439f_d20240915_m042144_c001_v0001130_t0003_u01726374104568") 2024/09/15 04:22:35 DEBUG : only9: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f403c4170a6dfdc15_d20240915_m042127_c001_v7007000_t0000_u01726374087239") 2024/09/15 04:22:35 DEBUG : only9: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a104279_d20240915_m042112_c001_v0001130_t0018_u01726374072213") 2024/09/15 04:22:35 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a294e_d20240915_m041645_c001_v7007000_t0000_u01726373805390") 2024/09/15 04:22:35 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f106a56bdaf47b367_d20240915_m041642_c001_v0001117_t0049_u01726373802079") 2024/09/15 04:22:35 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce35_d20240915_m041640_c001_v7007000_t0000_u01726373800244") 2024/09/15 04:22:35 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2947_d20240915_m041637_c001_v0001101_t0008_u01726373797773") 2024/09/15 04:22:35 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce30_d20240915_m041635_c001_v7007000_t0000_u01726373795919") 2024/09/15 04:22:35 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f106a56bdaf47b2f7_d20240915_m041632_c001_v0001117_t0046_u01726373792764") 2024/09/15 04:22:35 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce14_d20240915_m041619_c001_v7007000_t0000_u01726373779754") 2024/09/15 04:22:35 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2869_d20240915_m041618_c001_v0001101_t0028_u01726373778153") 2024/09/15 04:22:35 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce07_d20240915_m041615_c001_v7007000_t0000_u01726373775860") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2849_d20240915_m041614_c001_v0001101_t0046_u01726373774548") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fab1_d20240915_m041612_c001_v7007000_t0000_u01726373772542") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a281d_d20240915_m041611_c001_v0001101_t0049_u01726373771417") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa92_d20240915_m041603_c001_v7007000_t0000_u01726373763401") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a27bf_d20240915_m041602_c001_v0001101_t0040_u01726373762087") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa8f_d20240915_m041601_c001_v7007000_t0000_u01726373761351") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a27b1_d20240915_m041559_c001_v0001101_t0015_u01726373759916") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa88_d20240915_m041559_c001_v7007000_t0000_u01726373759179") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a278b_d20240915_m041558_c001_v0001101_t0025_u01726373758438") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2783_d20240915_m041557_c001_v0001101_t0004_u01726373757700") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa85_d20240915_m041556_c001_v7007000_t0000_u01726373756969") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2770_d20240915_m041556_c001_v0001101_t0048_u01726373756171") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a276a_d20240915_m041555_c001_v0001101_t0010_u01726373755370") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa80_d20240915_m041554_c001_v7007000_t0000_u01726373754642") 2024/09/15 04:22:36 DEBUG : potato: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a275e_d20240915_m041553_c001_v0001101_t0042_u01726373753590") 2024/09/15 04:22:36 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fcd4_d20240915_m041747_c001_v7007000_t0000_u01726373867842") 2024/09/15 04:22:36 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f106a56bdaf47b61b_d20240915_m041746_c001_v0001117_t0040_u01726373866457") 2024/09/15 04:22:36 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f405a8ff92a06f321_d20240915_m041731_c001_v7007000_t0000_u01726373851188") 2024/09/15 04:22:36 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f106a56bdaf47b52c_d20240915_m041724_c001_v0001117_t0029_u01726373844955") 2024/09/15 04:22:36 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40394297fe07400b_d20240915_m041716_c001_v7007000_t0000_u01726373836415") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f106a56bdaf47b48a_d20240915_m041709_c001_v0001117_t0040_u01726373829759") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a2965_d20240915_m041701_c001_v7007000_t0000_u01726373821508") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f106a56bdaf47b410_d20240915_m041656_c001_v0001117_t0021_u01726373816533") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce1b_d20240915_m041625_c001_v7007000_t0000_u01726373785800") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a28b3_d20240915_m041624_c001_v0001101_t0009_u01726373784042") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce1a_d20240915_m041622_c001_v7007000_t0000_u01726373782932") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2887_d20240915_m041620_c001_v0001101_t0026_u01726373780669") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce15_d20240915_m041619_c001_v7007000_t0000_u01726373779938") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2873_d20240915_m041618_c001_v0001101_t0057_u01726373778962") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce10_d20240915_m041617_c001_v7007000_t0000_u01726373777422") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2857_d20240915_m041616_c001_v0001101_t0036_u01726373776235") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fab7_d20240915_m041613_c001_v7007000_t0000_u01726373773816") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2831_d20240915_m041612_c001_v0001101_t0031_u01726373772554") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4077696654e5fa97_d20240915_m041604_c001_v7007000_t0000_u01726373764138") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a27c7_d20240915_m041603_c001_v0001101_t0044_u01726373763085") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4066c3b9fd2fb2b3_d20240915_m041505_c001_v7007000_t0000_u01726373705718") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2553_d20240915_m041504_c001_v0001101_t0031_u01726373704916") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4066c3b9fd2fb2b1_d20240915_m041503_c001_v7007000_t0000_u01726373703639") 2024/09/15 04:22:37 DEBUG : potato2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2533_d20240915_m041502_c001_v0001101_t0000_u01726373702936") 2024/09/15 04:22:37 DEBUG : potato3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f405a8ff92a06f325_d20240915_m041732_c001_v7007000_t0000_u01726373852075") 2024/09/15 04:22:37 DEBUG : potato3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2b56_d20240915_m041727_c001_v0001101_t0008_u01726373847050") 2024/09/15 04:22:38 DEBUG : potato3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40394297fe07400d_d20240915_m041716_c001_v7007000_t0000_u01726373836911") 2024/09/15 04:22:38 DEBUG : potato3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2aab_d20240915_m041711_c001_v0001101_t0058_u01726373831610") 2024/09/15 04:22:38 DEBUG : potato3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a2966_d20240915_m041701_c001_v7007000_t0000_u01726373821693") 2024/09/15 04:22:38 DEBUG : potato3: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2a21_d20240915_m041657_c001_v0001101_t0006_u01726373817808") 2024/09/15 04:22:38 DEBUG : pre-dest1/1: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a29c9_d20240915_m041837_c001_v7007000_t0000_u01726373917274") 2024/09/15 04:22:38 DEBUG : pre-dest1/1: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2dc7_d20240915_m041828_c001_v0001101_t0006_u01726373908348") 2024/09/15 04:22:38 DEBUG : pre-dest2/2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a29ca_d20240915_m041837_c001_v7007000_t0000_u01726373917456") 2024/09/15 04:22:38 DEBUG : pre-dest2/2: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f106a56bdaf47b7eb_d20240915_m041829_c001_v0001117_t0041_u01726373909090") 2024/09/15 04:22:38 DEBUG : rclone-sync-test-ignore-file/.ignore: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4044add742cf5e9c_d20240915_m041802_c001_v7007000_t0000_u01726373882204") 2024/09/15 04:22:38 DEBUG : rclone-sync-test-ignore-file/.ignore: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103adb_d20240915_m041757_c001_v0001130_t0056_u01726373877495") 2024/09/15 04:22:38 DEBUG : sizeonly: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f862d8_d20240915_m041532_c001_v7007000_t0000_u01726373732083") 2024/09/15 04:22:38 DEBUG : sizeonly: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2657_d20240915_m041530_c001_v0001101_t0057_u01726373730570") 2024/09/15 04:22:38 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592234a_d20240915_m042213_c001_v7007000_t0000_u01726374133516") 2024/09/15 04:22:38 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a1044a9_d20240915_m042210_c001_v0001130_t0051_u01726374130921") 2024/09/15 04:22:38 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922348_d20240915_m042208_c001_v7007000_t0000_u01726374128990") 2024/09/15 04:22:38 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f115ac0f03240c50e_d20240915_m042206_c001_v0001130_t0059_u01726374126205") 2024/09/15 04:22:38 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a2954_d20240915_m041652_c001_v7007000_t0000_u01726373812522") 2024/09/15 04:22:38 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f106a56bdaf47b3c1_d20240915_m041649_c001_v0001117_t0052_u01726373809775") 2024/09/15 04:22:38 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a2951_d20240915_m041648_c001_v7007000_t0000_u01726373808493") 2024/09/15 04:22:38 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a29ba_d20240915_m041647_c001_v0001101_t0051_u01726373807606") 2024/09/15 04:22:38 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f862c7_d20240915_m041527_c001_v7007000_t0000_u01726373727231") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a261f_d20240915_m041525_c001_v0001101_t0033_u01726373725569") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f862b4_d20240915_m041524_c001_v7007000_t0000_u01726373724825") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a260f_d20240915_m041523_c001_v0001101_t0038_u01726373723577") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f862a0_d20240915_m041522_c001_v7007000_t0000_u01726373722472") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a25c5_d20240915_m041516_c001_v0001101_t0005_u01726373716884") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f416c1d5a07f8627a_d20240915_m041516_c001_v7007000_t0000_u01726373716519") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a25bd_d20240915_m041515_c001_v0001101_t0055_u01726373715706") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4066c3b9fd2fb2c2_d20240915_m041514_c001_v7007000_t0000_u01726373714067") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a25a7_d20240915_m041513_c001_v0001101_t0020_u01726373713332") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4066c3b9fd2fb2c1_d20240915_m041512_c001_v7007000_t0000_u01726373712795") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2599_d20240915_m041511_c001_v0001101_t0031_u01726373711915") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4066c3b9fd2fb2bd_d20240915_m041510_c001_v7007000_t0000_u01726373710661") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2583_d20240915_m041509_c001_v0001101_t0008_u01726373709716") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4066c3b9fd2fb2ba_d20240915_m041509_c001_v7007000_t0000_u01726373709145") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2579_d20240915_m041508_c001_v0001101_t0055_u01726373708451") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4066c3b9fd2fb2b7_d20240915_m041507_c001_v7007000_t0000_u01726373707551") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2563_d20240915_m041506_c001_v0001101_t0043_u01726373706613") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4066c3b9fd2fb2ac_d20240915_m041500_c001_v7007000_t0000_u01726373700383") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a2509_d20240915_m041459_c001_v0001101_t0034_u01726373699527") 2024/09/15 04:22:39 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4066c3b9fd2fb2aa_d20240915_m041458_c001_v7007000_t0000_u01726373698633") 2024/09/15 04:22:40 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a24f1_d20240915_m041457_c001_v0001101_t0035_u01726373697711") 2024/09/15 04:22:40 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4066c3b9fd2fb2a3_d20240915_m041456_c001_v7007000_t0000_u01726373696819") 2024/09/15 04:22:40 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a24d7_d20240915_m041456_c001_v0001101_t0046_u01726373696159") 2024/09/15 04:22:40 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4066c3b9fd2fb29d_d20240915_m041453_c001_v7007000_t0000_u01726373693101") 2024/09/15 04:22:40 DEBUG : sub dir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a24a3_d20240915_m041452_c001_v0001101_t0018_u01726373692228") 2024/09/15 04:22:40 DEBUG : sub dir2/very/very/very/very/very/nested/subdir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb08592234b_d20240915_m042213_c001_v7007000_t0000_u01726374133699") 2024/09/15 04:22:40 DEBUG : sub dir2/very/very/very/very/very/nested/subdir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1078d84a930b248d_d20240915_m042210_c001_v0001137_t0033_u01726374130919") 2024/09/15 04:22:40 DEBUG : sub dir2/very/very/very/very/very/nested/subdir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4130bdb085922349_d20240915_m042209_c001_v7007000_t0000_u01726374129177") 2024/09/15 04:22:40 DEBUG : sub dir2/very/very/very/very/very/nested/subdir/hello world: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a36ba_d20240915_m042206_c001_v0001101_t0057_u01726374126209") 2024/09/15 04:22:40 DEBUG : sub/yam: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a294d_d20240915_m041644_c001_v7007000_t0000_u01726373804840") 2024/09/15 04:22:40 DEBUG : sub/yam: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103785_d20240915_m041641_c001_v0001130_t0021_u01726373801729") 2024/09/15 04:22:40 DEBUG : three: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce28_d20240915_m041632_c001_v7007000_t0000_u01726373792009") 2024/09/15 04:22:40 DEBUG : three: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a28f7_d20240915_m041630_c001_v0001101_t0029_u01726373790619") 2024/09/15 04:22:40 DEBUG : three: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1038c3f1fe0a28e3_d20240915_m041628_c001_v0001101_t0050_u01726373788289") 2024/09/15 04:22:40 DEBUG : two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce29_d20240915_m041632_c001_v7007000_t0000_u01726373792193") 2024/09/15 04:22:40 DEBUG : two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f406a56bdaf47b2d7_d20240915_m041630_c001_v0001117_t0052_u01726373790057") 2024/09/15 04:22:40 DEBUG : two: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f4038c3f1fe0a28e1_d20240915_m041628_c001_v0001101_t0057_u01726373788097") 2024/09/15 04:22:40 DEBUG : yam: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f40419d34e21a294f_d20240915_m041645_c001_v7007000_t0000_u01726373805572") 2024/09/15 04:22:40 DEBUG : yam: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10712da9bc3a6d9c_d20240915_m041643_c001_v0001116_t0036_u01726373803645") 2024/09/15 04:22:40 DEBUG : yam: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce34_d20240915_m041639_c001_v7007000_t0000_u01726373799691") 2024/09/15 04:22:40 DEBUG : yam: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103749_d20240915_m041637_c001_v0001130_t0054_u01726373797417") 2024/09/15 04:22:41 DEBUG : yam: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce2e_d20240915_m041635_c001_v7007000_t0000_u01726373795369") 2024/09/15 04:22:41 DEBUG : yam: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f10779af57a103711_d20240915_m041632_c001_v0001130_t0024_u01726373792752") 2024/09/15 04:22:41 DEBUG : yaml: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce36_d20240915_m041640_c001_v7007000_t0000_u01726373800432") 2024/09/15 04:22:41 DEBUG : yaml: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f1181fa1231c132aa_d20240915_m041638_c001_v0001109_t0015_u01726373798738") 2024/09/15 04:22:41 DEBUG : yaml: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f41877901ff65ce32_d20240915_m041636_c001_v7007000_t0000_u01726373796104") 2024/09/15 04:22:41 DEBUG : yaml: Deleting (id "4_z0f32fda3e03dfb3b9f180e1b_f104d1cd70d08ba79_d20240915_m041635_c001_v0001145_t0059_u01726373795104") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 7m53.562417294s (try 1/5)