"./sync.test -test.v -test.timeout 2h0m0s -remote TestPutio: -verbose" - Starting (try 1/5)
2021/12/01 06:38:03 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2"
2021/12/01 06:38:03 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2021/12/01 06:38:03 DEBUG : Creating backend with remote "/tmp/rclone1890035687"
=== 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:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:38:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:38:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:38:03 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11)
--- PASS: TestCopyWithDryRun (0.57s)
=== RUN   TestCopy
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:38:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:38:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:38:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:38:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:38:05 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2021/12/01 06:38:05 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopy (2.84s)
=== RUN   TestCopyMissingDirectory
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:38:06 DEBUG : Creating backend with remote "/non-existing"
2021/12/01 06:38:06 ERROR : Local file system at /non-existing: error reading source root directory: directory not found
2021/12/01 06:38:06 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:38:06 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
--- PASS: TestCopyMissingDirectory (0.23s)
=== RUN   TestCopyNoTraverse
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:38:07 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:38:07 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:38:07 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:38:07 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:38:08 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2021/12/01 06:38:08 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyNoTraverse (2.35s)
=== RUN   TestCopyCheckFirst
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:38:09 INFO  : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Running all checks before starting transfers
2021/12/01 06:38:09 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:38:09 INFO  : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Checks finished, now starting transfers
2021/12/01 06:38:09 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:38:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:38:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:38:15 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2021/12/01 06:38:15 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyCheckFirst (10.54s)
=== RUN   TestSyncNoTraverse
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:38:19 ERROR : Ignoring --no-traverse with sync
2021/12/01 06:38:19 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:38:19 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:38:20 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:38:20 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:38:23 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2021/12/01 06:38:23 INFO  : sub dir/hello world: Copied (new)
2021/12/01 06:38:23 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoTraverse (4.11s)
=== RUN   TestCopyWithDepth
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:38:24 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:38:24 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:38:24 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 12
2021/12/01 06:38:24 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 12
2021/12/01 06:38:27 DEBUG : hello world2: crc32 = 870a83b3 OK
2021/12/01 06:38:27 INFO  : hello world2: Copied (new)
--- PASS: TestCopyWithDepth (3.55s)
=== RUN   TestCopyWithFilesFrom
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:38:27 DEBUG : hello world2: Excluded
2021/12/01 06:38:27 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:38:27 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:38:28 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:38:28 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:38:29 DEBUG : potato2: crc32 = 0d4a1185 OK
2021/12/01 06:38:29 INFO  : potato2: Copied (new)
--- PASS: TestCopyWithFilesFrom (1.89s)
=== RUN   TestCopyWithFilesFromAndNoTraverse
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:38:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:38:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:38:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:38:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:38:40 DEBUG : potato2: crc32 = 0d4a1185 OK
2021/12/01 06:38:40 INFO  : potato2: Copied (new)
--- PASS: TestCopyWithFilesFromAndNoTraverse (11.66s)
=== RUN   TestCopyEmptyDirectories
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:38:41 DEBUG : sub dir2: Making directory
2021/12/01 06:38:41 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:38:41 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:38:42 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:38:42 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:38:44 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2021/12/01 06:38:44 INFO  : sub dir/hello world: Copied (new)
2021/12/01 06:38:44 DEBUG : sub dir2: Making directory
2021/12/01 06:38:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': copied 1 directories
--- PASS: TestCopyEmptyDirectories (5.19s)
=== RUN   TestMoveEmptyDirectories
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:38:46 DEBUG : sub dir2: Making directory
2021/12/01 06:38:46 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:38:46 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:38:47 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:38:47 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:38:49 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2021/12/01 06:38:49 INFO  : sub dir/hello world: Copied (new)
2021/12/01 06:38:49 INFO  : sub dir/hello world: Deleted
2021/12/01 06:38:49 DEBUG : sub dir2: Making directory
2021/12/01 06:38:49 DEBUG : sub dir: Making directory
2021/12/01 06:38:49 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': copied 2 directories
--- PASS: TestMoveEmptyDirectories (4.28s)
=== RUN   TestSyncEmptyDirectories
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:38:50 DEBUG : sub dir2: Making directory
2021/12/01 06:38:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:38:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:38:51 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:38:51 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:38:52 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2021/12/01 06:38:52 INFO  : sub dir/hello world: Copied (new)
2021/12/01 06:38:52 DEBUG : sub dir2: Making directory
2021/12/01 06:38:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': copied 1 directories
2021/12/01 06:38:52 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncEmptyDirectories (2.74s)
=== RUN   TestServerSideCopy
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:38:54 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:38:54 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:39:03 DEBUG : Creating backend with remote "TestPutio:rclone-test-ciyafon0zesukal3zadotom1"
    sync_test.go:275: Server side copy (if possible) Putio root 'rclone-test-tametin3xuciqil2matoyez2' -> Putio root 'rclone-test-ciyafon0zesukal3zadotom1'
2021/12/01 06:39:04 DEBUG : Putio root 'rclone-test-ciyafon0zesukal3zadotom1': Waiting for checks to finish
2021/12/01 06:39:04 DEBUG : Putio root 'rclone-test-ciyafon0zesukal3zadotom1': Waiting for transfers to finish
2021/12/01 06:39:04 ERROR : sub dir/hello world: Failed to copy: unexpected status code (400) response while doing POST to https://api.put.io/v2/files/copy
    sync_test.go:278: 
        	Error Trace:	sync_test.go:278
        	Error:      	Received unexpected error:
        	            	unexpected status code (400) response while doing POST to https://api.put.io/v2/files/copy
        	Test:       	TestServerSideCopy
2021/12/01 06:39:04 DEBUG : Putio root 'rclone-test-ciyafon0zesukal3zadotom1': Purge remote
--- FAIL: TestServerSideCopy (12.31s)
=== RUN   TestCopyAfterDelete
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:39:06 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:39:06 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:39:07 ERROR : : error listing: directory not found
2021/12/01 06:39:07 DEBUG : Local file system at /tmp/rclone1890035687: Making directory
2021/12/01 06:39:07 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:07 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
--- PASS: TestCopyAfterDelete (2.56s)
=== RUN   TestCopyRedownload
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:39:08 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:39:08 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:39:10 DEBUG : Local file system at /tmp/rclone1890035687: Waiting for checks to finish
2021/12/01 06:39:10 DEBUG : Local file system at /tmp/rclone1890035687: Waiting for transfers to finish
2021/12/01 06:39:10 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2021/12/01 06:39:10 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyRedownload (2.92s)
=== RUN   TestSyncBasedOnCheckSum
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:39:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:39:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:39:16 DEBUG : check sum: crc32 = 97ddb3f8 OK
2021/12/01 06:39:16 INFO  : check sum: Copied (new)
2021/12/01 06:39:16 DEBUG : Waiting for deletions to finish
2021/12/01 06:39:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:17 DEBUG : check sum: crc32 = 97ddb3f8 OK
2021/12/01 06:39:17 DEBUG : check sum: Size and crc32 of src and dst objects identical
2021/12/01 06:39:17 DEBUG : check sum: Unchanged skipping
2021/12/01 06:39:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:17 DEBUG : Waiting for deletions to finish
2021/12/01 06:39:17 INFO  : There was nothing to transfer
--- PASS: TestSyncBasedOnCheckSum (6.44s)
=== RUN   TestSyncSizeOnly
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:39:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:39:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:39:18 DEBUG : sizeonly: crc32 = 9a941a19 OK
2021/12/01 06:39:18 INFO  : sizeonly: Copied (new)
2021/12/01 06:39:18 DEBUG : Waiting for deletions to finish
2021/12/01 06:39:18 DEBUG : sizeonly: Sizes identical
2021/12/01 06:39:18 DEBUG : sizeonly: Unchanged skipping
2021/12/01 06:39:18 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:18 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:18 DEBUG : Waiting for deletions to finish
2021/12/01 06:39:18 INFO  : There was nothing to transfer
--- PASS: TestSyncSizeOnly (1.81s)
=== RUN   TestSyncIgnoreSize
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:39:19 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:19 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:19 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 8
2021/12/01 06:39:19 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 8
2021/12/01 06:39:21 DEBUG : ignore-size: crc32 = b4fa1177 OK
2021/12/01 06:39:21 INFO  : ignore-size: Copied (new)
2021/12/01 06:39:21 DEBUG : Waiting for deletions to finish
2021/12/01 06:39:21 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:21 DEBUG : ignore-size: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:39:21 DEBUG : ignore-size: Unchanged skipping
2021/12/01 06:39:21 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:21 DEBUG : Waiting for deletions to finish
2021/12/01 06:39:21 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreSize (3.02s)
=== RUN   TestSyncIgnoreTimes
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:39:22 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:39:22 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:39:24 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:24 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:39:24 DEBUG : existing: Unchanged skipping
2021/12/01 06:39:24 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:24 DEBUG : Waiting for deletions to finish
2021/12/01 06:39:24 INFO  : There was nothing to transfer
2021/12/01 06:39:24 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:24 DEBUG : existing: Transferring unconditionally as --ignore-times is in use
2021/12/01 06:39:24 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:25 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:39:25 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:39:27 DEBUG : existing: crc32 = 9a941a19 OK
2021/12/01 06:39:27 INFO  : existing: Copied (replaced existing)
2021/12/01 06:39:27 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncIgnoreTimes (5.22s)
=== RUN   TestSyncIgnoreExisting
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:39:27 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:27 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:28 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:39:28 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:39:28 DEBUG : existing: crc32 = 9a941a19 OK
2021/12/01 06:39:28 INFO  : existing: Copied (new)
2021/12/01 06:39:28 DEBUG : Waiting for deletions to finish
2021/12/01 06:39:29 DEBUG : existing: Destination exists, skipping
2021/12/01 06:39:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:29 DEBUG : Waiting for deletions to finish
2021/12/01 06:39:29 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreExisting (1.87s)
=== RUN   TestSyncIgnoreErrors
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:39:30 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 21
2021/12/01 06:39:30 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 21
2021/12/01 06:39:32 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 5
2021/12/01 06:39:32 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:39:36 DEBUG : d: Making directory
2021/12/01 06:39:36 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:39:36 DEBUG : c/non empty space: Unchanged skipping
2021/12/01 06:39:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 60
2021/12/01 06:39:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:39:38 DEBUG : a/potato2: crc32 = d423bfba OK
2021/12/01 06:39:38 INFO  : a/potato2: Copied (new)
2021/12/01 06:39:38 DEBUG : Waiting for deletions to finish
2021/12/01 06:39:38 INFO  : b/potato: Deleted
2021/12/01 06:39:38 INFO  : d: Removing directory
2021/12/01 06:39:38 INFO  : b: Removing directory
2021/12/01 06:39:38 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': deleted 2 directories
--- PASS: TestSyncIgnoreErrors (10.76s)
=== RUN   TestSyncAfterChangingModtimeOnly
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:39:40 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:39:40 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:39:43 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:43 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC
2021/12/01 06:39:43 DEBUG : empty space: crc32 = 97ddb3f8 OK
2021/12/01 06:39:43 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1)
2021/12/01 06:39:43 DEBUG : empty space: Unchanged skipping
2021/12/01 06:39:43 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:43 DEBUG : Waiting for deletions to finish
2021/12/01 06:39:43 INFO  : There was nothing to transfer
2021/12/01 06:39:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:44 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC
2021/12/01 06:39:44 DEBUG : empty space: crc32 = 97ddb3f8 OK
2021/12/01 06:39:44 INFO  : empty space: Updated modification time in destination
2021/12/01 06:39:44 DEBUG : empty space: Unchanged skipping
2021/12/01 06:39:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:44 DEBUG : Waiting for deletions to finish
2021/12/01 06:39:44 INFO  : There was nothing to transfer
--- PASS: TestSyncAfterChangingModtimeOnly (4.63s)
=== RUN   TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:39:45 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:39:45 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:39:45 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:45 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC
2021/12/01 06:39:45 DEBUG : empty space: crc32 = 97ddb3f8 OK
2021/12/01 06:39:45 DEBUG : empty space: Unchanged skipping
2021/12/01 06:39:45 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:45 DEBUG : Waiting for deletions to finish
2021/12/01 06:39:45 INFO  : There was nothing to transfer
--- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (1.54s)
=== RUN   TestSyncDoesntUpdateModtime
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:39:46 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:39:46 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:39:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:50 DEBUG : foo: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC
2021/12/01 06:39:50 DEBUG : foo: crc32 = 8c736521 (Local file system at /tmp/rclone1890035687)
2021/12/01 06:39:50 DEBUG : foo: crc32 = 76ff8caa (Putio root 'rclone-test-tametin3xuciqil2matoyez2')
2021/12/01 06:39:50 DEBUG : foo: crc32 differ
2021/12/01 06:39:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:39:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:39:51 DEBUG : foo: crc32 = 8c736521 OK
2021/12/01 06:39:51 INFO  : foo: Copied (replaced existing)
2021/12/01 06:39:51 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncDoesntUpdateModtime (5.73s)
=== RUN   TestSyncAfterAddingAFile
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:39:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:39:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:39:56 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:39:56 DEBUG : empty space: Unchanged skipping
2021/12/01 06:39:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:39:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:39:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 60
2021/12/01 06:39:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:39:59 DEBUG : potato: crc32 = d423bfba OK
2021/12/01 06:39:59 INFO  : potato: Copied (new)
2021/12/01 06:39:59 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterAddingAFile (8.39s)
=== RUN   TestSyncAfterChangingFilesSizeOnly
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:40:00 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 60
2021/12/01 06:40:00 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:40:01 DEBUG : potato: Sizes differ (src 21 vs dst 60)
2021/12/01 06:40:01 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:40:01 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:40:02 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 21
2021/12/01 06:40:02 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 21
2021/12/01 06:40:03 DEBUG : potato: crc32 = f4498cc3 OK
2021/12/01 06:40:03 INFO  : potato: Copied (replaced existing)
2021/12/01 06:40:03 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterChangingFilesSizeOnly (2.96s)
=== RUN   TestSyncAfterChangingContentsOnly
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:40:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 21
2021/12/01 06:40:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 21
2021/12/01 06:40:05 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
2021/12/01 06:40:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:40:05 DEBUG : potato: crc32 = 1b366e1c (Local file system at /tmp/rclone1890035687)
2021/12/01 06:40:05 DEBUG : potato: crc32 = f4498cc3 (Putio root 'rclone-test-tametin3xuciqil2matoyez2')
2021/12/01 06:40:05 DEBUG : potato: crc32 differ
2021/12/01 06:40:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:40:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 21
2021/12/01 06:40:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 21
2021/12/01 06:40:06 DEBUG : potato: crc32 = 1b366e1c OK
2021/12/01 06:40:06 INFO  : potato: Copied (replaced existing)
2021/12/01 06:40:06 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterChangingContentsOnly (3.56s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileDryRun
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:40:07 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 21
2021/12/01 06:40:07 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 21
2021/12/01 06:40:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:40:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:40:15 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:40:15 DEBUG : empty space: Unchanged skipping
2021/12/01 06:40:15 NOTICE: potato2: Skipped copy as --dry-run is set (size 60)
2021/12/01 06:40:15 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:40:15 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:40:15 DEBUG : Waiting for deletions to finish
2021/12/01 06:40:15 NOTICE: potato: Skipped delete as --dry-run is set (size 21)
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (8.86s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFile
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:40:16 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 21
2021/12/01 06:40:16 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 21
2021/12/01 06:40:18 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:40:18 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:40:19 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:40:19 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:40:19 DEBUG : empty space: Unchanged skipping
2021/12/01 06:40:19 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:40:20 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 60
2021/12/01 06:40:20 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:40:20 DEBUG : potato2: crc32 = d423bfba OK
2021/12/01 06:40:20 INFO  : potato2: Copied (new)
2021/12/01 06:40:20 DEBUG : Waiting for deletions to finish
2021/12/01 06:40:20 INFO  : potato: Deleted
--- PASS: TestSyncAfterRemovingAFileAndAddingAFile (5.52s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:40:22 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 21
2021/12/01 06:40:22 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 21
2021/12/01 06:40:23 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 5
2021/12/01 06:40:23 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:40:24 DEBUG : d: Making directory
2021/12/01 06:40:24 DEBUG : d/e: Making directory
2021/12/01 06:40:24 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:40:24 DEBUG : c/non empty space: Unchanged skipping
2021/12/01 06:40:25 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:40:25 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:40:25 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 60
2021/12/01 06:40:25 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:40:28 DEBUG : a/potato2: crc32 = d423bfba OK
2021/12/01 06:40:28 INFO  : a/potato2: Copied (new)
2021/12/01 06:40:28 DEBUG : Waiting for deletions to finish
2021/12/01 06:40:28 INFO  : b/potato: Deleted
2021/12/01 06:40:28 INFO  : d/e: Removing directory
2021/12/01 06:40:28 INFO  : d: Removing directory
2021/12/01 06:40:28 INFO  : b: Removing directory
2021/12/01 06:40:28 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': deleted 3 directories
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (8.72s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:40:30 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 21
2021/12/01 06:40:30 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 21
2021/12/01 06:40:32 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 5
2021/12/01 06:40:32 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:40:37 DEBUG : d: Making directory
2021/12/01 06:40:38 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:40:38 DEBUG : c/non empty space: Unchanged skipping
2021/12/01 06:40:38 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:40:38 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:40:39 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 60
2021/12/01 06:40:39 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:40:39 DEBUG : a/potato2: crc32 = d423bfba OK
2021/12/01 06:40:39 INFO  : a/potato2: Copied (new)
2021/12/01 06:40:39 ERROR : Putio root 'rclone-test-tametin3xuciqil2matoyez2': not deleting files as there were IO errors
2021/12/01 06:40:39 ERROR : Putio root 'rclone-test-tametin3xuciqil2matoyez2': not deleting directories as there were IO errors
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (11.36s)
=== RUN   TestSyncDeleteAfter
--- PASS: TestSyncDeleteAfter (0.00s)
=== RUN   TestSyncDeleteDuring
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:40:42 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 21
2021/12/01 06:40:42 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 21
2021/12/01 06:40:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:40:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:41:00 DEBUG : Waiting for deletions to finish
2021/12/01 06:41:00 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:41:00 DEBUG : empty space: Unchanged skipping
2021/12/01 06:41:00 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:41:00 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:41:00 INFO  : potato: Deleted
2021/12/01 06:41:00 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 60
2021/12/01 06:41:00 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:41:02 DEBUG : potato2: crc32 = d423bfba OK
2021/12/01 06:41:02 INFO  : potato2: Copied (new)
--- PASS: TestSyncDeleteDuring (21.85s)
=== RUN   TestSyncDeleteBefore
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:41:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 21
2021/12/01 06:41:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 21
2021/12/01 06:41:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:41:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:41:11 DEBUG : Waiting for deletions to finish
2021/12/01 06:41:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:41:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:41:12 INFO  : potato: Deleted
2021/12/01 06:41:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:41:12 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:41:12 DEBUG : empty space: Unchanged skipping
2021/12/01 06:41:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:41:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 60
2021/12/01 06:41:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:41:16 DEBUG : potato2: crc32 = d423bfba OK
2021/12/01 06:41:16 INFO  : potato2: Copied (new)
--- PASS: TestSyncDeleteBefore (13.84s)
=== RUN   TestCopyDeleteBefore
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:41:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 21
2021/12/01 06:41:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 21
2021/12/01 06:41:22 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:41:22 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:41:22 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 19
2021/12/01 06:41:22 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 19
2021/12/01 06:41:28 DEBUG : potato2: crc32 = 9f4e1e48 OK
2021/12/01 06:41:28 INFO  : potato2: Copied (new)
--- PASS: TestCopyDeleteBefore (12.15s)
=== RUN   TestSyncWithExclude
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:41:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 60
2021/12/01 06:41:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:41:30 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:41:30 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:41:31 DEBUG : enormous: Excluded
2021/12/01 06:41:31 DEBUG : potato2: Excluded
2021/12/01 06:41:31 DEBUG : potato2: Excluded
2021/12/01 06:41:31 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:41:31 DEBUG : empty space: Unchanged skipping
2021/12/01 06:41:31 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:41:31 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:41:31 DEBUG : Waiting for deletions to finish
2021/12/01 06:41:31 INFO  : There was nothing to transfer
2021/12/01 06:41:31 DEBUG : enormous: Excluded
2021/12/01 06:41:31 DEBUG : potato2: Excluded
2021/12/01 06:41:31 DEBUG : potato2: Excluded
2021/12/01 06:41:31 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s)
2021/12/01 06:41:31 DEBUG : empty space: Unchanged skipping
2021/12/01 06:41:31 DEBUG : Local file system at /tmp/rclone1890035687: Waiting for checks to finish
2021/12/01 06:41:31 DEBUG : Local file system at /tmp/rclone1890035687: Waiting for transfers to finish
2021/12/01 06:41:31 DEBUG : Waiting for deletions to finish
2021/12/01 06:41:31 INFO  : There was nothing to transfer
--- PASS: TestSyncWithExclude (2.87s)
=== RUN   TestSyncWithExcludeAndDeleteExcluded
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:41:32 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 60
2021/12/01 06:41:32 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:41:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:41:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:41:38 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 100
2021/12/01 06:41:38 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 100
2021/12/01 06:41:40 DEBUG : enormous: Excluded
2021/12/01 06:41:40 DEBUG : potato2: Excluded
2021/12/01 06:41:40 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:41:40 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:41:40 DEBUG : empty space: Unchanged skipping
2021/12/01 06:41:40 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:41:40 DEBUG : Waiting for deletions to finish
2021/12/01 06:41:41 INFO  : enormous: Deleted
2021/12/01 06:41:42 INFO  : potato2: Deleted
2021/12/01 06:41:42 INFO  : There was nothing to transfer
2021/12/01 06:41:42 DEBUG : Local file system at /tmp/rclone1890035687: Waiting for checks to finish
2021/12/01 06:41:42 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s)
2021/12/01 06:41:42 DEBUG : empty space: Unchanged skipping
2021/12/01 06:41:42 DEBUG : Local file system at /tmp/rclone1890035687: Waiting for transfers to finish
2021/12/01 06:41:42 DEBUG : Waiting for deletions to finish
2021/12/01 06:41:42 INFO  : enormous: Deleted
2021/12/01 06:41:42 INFO  : potato2: Deleted
2021/12/01 06:41:42 INFO  : There was nothing to transfer
--- PASS: TestSyncWithExcludeAndDeleteExcluded (10.55s)
=== RUN   TestSyncWithUpdateOlder
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:41:43 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:41:43 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:41:46 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:41:46 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:41:48 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 5
2021/12/01 06:41:48 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:41:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 8
2021/12/01 06:41:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 8
2021/12/01 06:41:51 DEBUG : four: Sizes differ (src 4 vs dst 8)
2021/12/01 06:41:51 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring
2021/12/01 06:41:51 DEBUG : one: Destination is newer than source, skipping
2021/12/01 06:41:51 DEBUG : three: Sizes identical
2021/12/01 06:41:51 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping
2021/12/01 06:41:51 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:41:51 DEBUG : two: crc32 = 11ca8a66 (Local file system at /tmp/rclone1890035687)
2021/12/01 06:41:51 DEBUG : two: crc32 = 876dc8ec (Putio root 'rclone-test-tametin3xuciqil2matoyez2')
2021/12/01 06:41:51 DEBUG : two: crc32 differ
2021/12/01 06:41:51 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:41:51 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 4
2021/12/01 06:41:51 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 4
2021/12/01 06:41:51 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 4
2021/12/01 06:41:51 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 4
2021/12/01 06:41:52 DEBUG : five: crc32 = 3cb2cccb OK
2021/12/01 06:41:52 INFO  : five: Copied (new)
2021/12/01 06:41:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:41:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:41:53 DEBUG : two: crc32 = 11ca8a66 OK
2021/12/01 06:41:53 INFO  : two: Copied (replaced existing)
2021/12/01 06:41:54 DEBUG : four: crc32 = 90c1667d OK
2021/12/01 06:41:54 INFO  : four: Copied (replaced existing)
2021/12/01 06:41:54 DEBUG : Waiting for deletions to finish
2021/12/01 06:41:54 DEBUG : one: Destination is newer than source, skipping
2021/12/01 06:41:54 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:41:54 DEBUG : five: crc32 = 3cb2cccb OK
2021/12/01 06:41:54 DEBUG : five: Size and crc32 of src and dst objects identical
2021/12/01 06:41:54 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping
2021/12/01 06:41:54 DEBUG : three: crc32 = 46c5d8f5 (Local file system at /tmp/rclone1890035687)
2021/12/01 06:41:54 DEBUG : three: crc32 = b1911a45 (Putio root 'rclone-test-tametin3xuciqil2matoyez2')
2021/12/01 06:41:54 DEBUG : three: crc32 differ
2021/12/01 06:41:54 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring
2021/12/01 06:41:54 DEBUG : four: crc32 = 90c1667d OK
2021/12/01 06:41:54 DEBUG : four: Size and crc32 of src and dst objects identical
2021/12/01 06:41:54 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping
2021/12/01 06:41:54 DEBUG : two: crc32 = 11ca8a66 OK
2021/12/01 06:41:54 DEBUG : two: Size and crc32 of src and dst objects identical
2021/12/01 06:41:54 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping
2021/12/01 06:41:54 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:41:54 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 5
2021/12/01 06:41:54 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:41:55 DEBUG : three: crc32 = 46c5d8f5 OK
2021/12/01 06:41:55 INFO  : three: Copied (replaced existing)
2021/12/01 06:41:55 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithUpdateOlder (13.96s)
=== RUN   TestSyncWithMaxDuration
    sync_test.go:1009: Skipping test on non local remote
--- SKIP: TestSyncWithMaxDuration (0.00s)
=== RUN   TestSyncWithTrackRenames
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
    sync_test.go:1058: Can track renames: true
2021/12/01 06:41:56 INFO  : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Making map for --track-renames
2021/12/01 06:41:56 INFO  : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Finished making map for --track-renames
2021/12/01 06:41:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:41:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for renames to finish
2021/12/01 06:41:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:41:57 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 14
2021/12/01 06:41:57 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 14
2021/12/01 06:41:57 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:41:57 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:41:59 DEBUG : potato: crc32 = 403f91c8 OK
2021/12/01 06:41:59 INFO  : potato: Copied (new)
2021/12/01 06:42:02 DEBUG : yam: crc32 = ae1fefed OK
2021/12/01 06:42:02 INFO  : yam: Copied (new)
2021/12/01 06:42:02 DEBUG : Waiting for deletions to finish
2021/12/01 06:42:03 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:42:03 INFO  : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Making map for --track-renames
2021/12/01 06:42:03 DEBUG : potato: Unchanged skipping
2021/12/01 06:42:03 INFO  : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Finished making map for --track-renames
2021/12/01 06:42:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:42:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for renames to finish
2021/12/01 06:42:03 INFO  : yam: Moved (server-side) to: yaml
2021/12/01 06:42:03 INFO  : yaml: Renamed from "yam"
2021/12/01 06:42:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:42:03 DEBUG : Waiting for deletions to finish
2021/12/01 06:42:03 INFO  : There was nothing to transfer
--- PASS: TestSyncWithTrackRenames (7.26s)
=== RUN   TestParseRenamesStrategyModtime
--- PASS: TestParseRenamesStrategyModtime (0.00s)
=== RUN   TestRenamesStrategyModtime
--- PASS: TestRenamesStrategyModtime (0.00s)
=== RUN   TestSyncWithTrackRenamesStrategyModtime
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
    sync_test.go:1127: Can track renames: true
2021/12/01 06:42:04 INFO  : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Making map for --track-renames
2021/12/01 06:42:04 INFO  : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Finished making map for --track-renames
2021/12/01 06:42:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:42:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for renames to finish
2021/12/01 06:42:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:42:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:42:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:42:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 14
2021/12/01 06:42:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 14
2021/12/01 06:42:05 DEBUG : yam: crc32 = ae1fefed OK
2021/12/01 06:42:05 INFO  : yam: Copied (new)
2021/12/01 06:42:05 DEBUG : potato: crc32 = 403f91c8 OK
2021/12/01 06:42:05 INFO  : potato: Copied (new)
2021/12/01 06:42:05 DEBUG : Waiting for deletions to finish
2021/12/01 06:42:05 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:42:05 DEBUG : potato: Unchanged skipping
2021/12/01 06:42:05 INFO  : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Making map for --track-renames
2021/12/01 06:42:05 INFO  : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Finished making map for --track-renames
2021/12/01 06:42:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:42:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for renames to finish
2021/12/01 06:42:06 INFO  : yam: Moved (server-side) to: yaml
2021/12/01 06:42:06 INFO  : yaml: Renamed from "yam"
2021/12/01 06:42:06 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:42:06 DEBUG : Waiting for deletions to finish
2021/12/01 06:42:06 INFO  : There was nothing to transfer
--- PASS: TestSyncWithTrackRenamesStrategyModtime (2.75s)
=== RUN   TestSyncWithTrackRenamesStrategyLeaf
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
    sync_test.go:1163: Can track renames: true
2021/12/01 06:42:06 INFO  : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Making map for --track-renames
2021/12/01 06:42:06 INFO  : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Finished making map for --track-renames
2021/12/01 06:42:06 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:42:06 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for renames to finish
2021/12/01 06:42:06 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:42:07 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:42:07 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:42:07 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 14
2021/12/01 06:42:07 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 14
2021/12/01 06:42:07 DEBUG : sub/yam: crc32 = ae1fefed OK
2021/12/01 06:42:07 INFO  : sub/yam: Copied (new)
2021/12/01 06:42:08 DEBUG : potato: crc32 = 403f91c8 OK
2021/12/01 06:42:08 INFO  : potato: Copied (new)
2021/12/01 06:42:08 DEBUG : Waiting for deletions to finish
2021/12/01 06:42:08 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:42:08 DEBUG : potato: Unchanged skipping
2021/12/01 06:42:08 INFO  : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Making map for --track-renames
2021/12/01 06:42:08 INFO  : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Finished making map for --track-renames
2021/12/01 06:42:08 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:42:08 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for renames to finish
2021/12/01 06:42:09 INFO  : sub/yam: Moved (server-side) to: yam
2021/12/01 06:42:09 INFO  : yam: Renamed from "sub/yam"
2021/12/01 06:42:09 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:42:09 DEBUG : Waiting for deletions to finish
2021/12/01 06:42:09 INFO  : There was nothing to transfer
--- PASS: TestSyncWithTrackRenamesStrategyLeaf (3.27s)
=== RUN   TestMoveWithDeleteEmptySrcDirs
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:42:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:42:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:42:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:42:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:42:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:42:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:42:16 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2021/12/01 06:42:16 INFO  : sub dir/hello world: Copied (new)
2021/12/01 06:42:16 INFO  : sub dir/hello world: Deleted
2021/12/01 06:42:31 DEBUG : nested/sub dir/file: crc32 = aac9c2e9 OK
2021/12/01 06:42:31 INFO  : nested/sub dir/file: Copied (new)
2021/12/01 06:42:31 INFO  : nested/sub dir/file: Deleted
2021/12/01 06:42:31 INFO  : sub dir: Removing directory
2021/12/01 06:42:31 INFO  : nested/sub dir: Removing directory
2021/12/01 06:42:31 INFO  : nested: Removing directory
2021/12/01 06:42:31 DEBUG : Local file system at /tmp/rclone1890035687: deleted 3 directories
--- PASS: TestMoveWithDeleteEmptySrcDirs (23.24s)
=== RUN   TestMoveWithoutDeleteEmptySrcDirs
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:42:33 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:42:33 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:42:34 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 11
2021/12/01 06:42:34 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 11
2021/12/01 06:42:34 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:42:34 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:42:34 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2021/12/01 06:42:34 INFO  : sub dir/hello world: Copied (new)
2021/12/01 06:42:34 INFO  : sub dir/hello world: Deleted
2021/12/01 06:42:35 DEBUG : nested/sub dir/file: crc32 = aac9c2e9 OK
2021/12/01 06:42:35 INFO  : nested/sub dir/file: Copied (new)
2021/12/01 06:42:35 INFO  : nested/sub dir/file: Deleted
--- PASS: TestMoveWithoutDeleteEmptySrcDirs (3.60s)
=== RUN   TestMoveWithIgnoreExisting
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:42:36 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:42:36 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:42:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:42:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:42:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:42:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:42:38 DEBUG : existing-b: crc32 = 2c14401a OK
2021/12/01 06:42:38 INFO  : existing-b: Copied (new)
2021/12/01 06:42:38 INFO  : existing-b: Deleted
2021/12/01 06:42:38 DEBUG : existing: crc32 = 9a941a19 OK
2021/12/01 06:42:38 INFO  : existing: Copied (new)
2021/12/01 06:42:38 INFO  : existing: Deleted
2021/12/01 06:42:38 DEBUG : existing: Destination exists, skipping
2021/12/01 06:42:38 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set
2021/12/01 06:42:38 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:42:38 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:42:38 INFO  : There was nothing to transfer
--- PASS: TestMoveWithIgnoreExisting (2.59s)
=== RUN   TestServerSideMove
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:42:39 DEBUG : Creating backend with remote "TestPutio:rclone-test-lupuweg3hutujum1wipoxah2"
2021/12/01 06:42:39 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 60
2021/12/01 06:42:39 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:42:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:42:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:42:48 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 68
2021/12/01 06:42:48 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 68
    sync_test.go:1215: Server side move (if possible) Putio root 'rclone-test-tametin3xuciqil2matoyez2' -> Putio root 'rclone-test-lupuweg3hutujum1wipoxah2'
2021/12/01 06:42:51 DEBUG : Putio root 'rclone-test-lupuweg3hutujum1wipoxah2': chunkStart: 0, reqSize: 1
2021/12/01 06:42:51 DEBUG : Putio root 'rclone-test-lupuweg3hutujum1wipoxah2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:42:55 DEBUG : Putio root 'rclone-test-lupuweg3hutujum1wipoxah2': chunkStart: 0, reqSize: 60
2021/12/01 06:42:55 DEBUG : Putio root 'rclone-test-lupuweg3hutujum1wipoxah2': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:42:59 DEBUG : Putio root 'rclone-test-lupuweg3hutujum1wipoxah2': Using server-side directory move
2021/12/01 06:42:59 INFO  : Putio root 'rclone-test-lupuweg3hutujum1wipoxah2': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2021/12/01 06:43:00 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s)
2021/12/01 06:43:00 DEBUG : empty space: Unchanged skipping
2021/12/01 06:43:00 DEBUG : Putio root 'rclone-test-lupuweg3hutujum1wipoxah2': Waiting for checks to finish
2021/12/01 06:43:00 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2021/12/01 06:43:00 INFO  : empty space: Deleted
2021/12/01 06:43:00 DEBUG : Putio root 'rclone-test-lupuweg3hutujum1wipoxah2': Waiting for transfers to finish
2021/12/01 06:43:00 INFO  : potato2: Moved (server-side)
2021/12/01 06:43:01 INFO  : potato3: Deleted
2021/12/01 06:43:01 INFO  : potato3: Moved (server-side)
2021/12/01 06:43:01 INFO  : There was nothing to transfer
2021/12/01 06:43:01 DEBUG : Creating backend with remote "TestPutio:rclone-test-zupoquv7migeqom7qazakut1"
2021/12/01 06:43:01 DEBUG : Putio root 'rclone-test-zupoquv7migeqom7qazakut1': Using server-side directory move
2021/12/01 06:43:01 INFO  : Putio root 'rclone-test-zupoquv7migeqom7qazakut1': Server side directory move succeeded
2021/12/01 06:43:02 ERROR : : error listing: directory not found
2021/12/01 06:43:02 DEBUG : Putio root 'rclone-test-zupoquv7migeqom7qazakut1': Purge remote
2021/12/01 06:43:02 DEBUG : Putio root 'rclone-test-lupuweg3hutujum1wipoxah2': Purge remote
2021/12/01 06:43:02 purge failed: Rmdir: directory not found
--- PASS: TestServerSideMove (23.16s)
=== RUN   TestServerSideMoveWithFilter
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:43:02 DEBUG : Creating backend with remote "TestPutio:rclone-test-rolakos6loqaqik5foduhof5"
2021/12/01 06:43:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 60
2021/12/01 06:43:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:43:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:43:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:43:08 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 68
2021/12/01 06:43:08 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 68
    sync_test.go:1215: Server side move (if possible) Putio root 'rclone-test-tametin3xuciqil2matoyez2' -> Putio root 'rclone-test-rolakos6loqaqik5foduhof5'
2021/12/01 06:43:10 DEBUG : Putio root 'rclone-test-rolakos6loqaqik5foduhof5': chunkStart: 0, reqSize: 1
2021/12/01 06:43:10 DEBUG : Putio root 'rclone-test-rolakos6loqaqik5foduhof5': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:43:11 DEBUG : Putio root 'rclone-test-rolakos6loqaqik5foduhof5': chunkStart: 0, reqSize: 60
2021/12/01 06:43:11 DEBUG : Putio root 'rclone-test-rolakos6loqaqik5foduhof5': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:43:22 DEBUG : empty space: Excluded
2021/12/01 06:43:22 DEBUG : empty space: Excluded
2021/12/01 06:43:22 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2021/12/01 06:43:22 DEBUG : Putio root 'rclone-test-rolakos6loqaqik5foduhof5': Waiting for checks to finish
2021/12/01 06:43:22 DEBUG : Putio root 'rclone-test-rolakos6loqaqik5foduhof5': Waiting for transfers to finish
2021/12/01 06:43:22 INFO  : potato3: Deleted
2021/12/01 06:43:22 INFO  : potato2: Moved (server-side)
2021/12/01 06:43:22 INFO  : potato3: Moved (server-side)
2021/12/01 06:43:22 INFO  : There was nothing to transfer
2021/12/01 06:43:23 DEBUG : Creating backend with remote "TestPutio:rclone-test-qedeqiq3dalutug1giladok9"
2021/12/01 06:43:23 DEBUG : empty space: Excluded
2021/12/01 06:43:23 DEBUG : Putio root 'rclone-test-qedeqiq3dalutug1giladok9': Waiting for checks to finish
2021/12/01 06:43:23 DEBUG : Putio root 'rclone-test-qedeqiq3dalutug1giladok9': Waiting for transfers to finish
2021/12/01 06:43:23 INFO  : potato2: Moved (server-side)
2021/12/01 06:43:23 INFO  : potato3: Moved (server-side)
2021/12/01 06:43:23 INFO  : There was nothing to transfer
2021/12/01 06:43:24 DEBUG : Putio root 'rclone-test-qedeqiq3dalutug1giladok9': Purge remote
2021/12/01 06:43:24 DEBUG : Putio root 'rclone-test-rolakos6loqaqik5foduhof5': Purge remote
--- PASS: TestServerSideMoveWithFilter (22.05s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:43:24 DEBUG : Creating backend with remote "TestPutio:rclone-test-bijiciq6denigug9nikucur0"
2021/12/01 06:43:25 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 60
2021/12/01 06:43:25 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:43:26 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:43:26 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:43:32 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 68
2021/12/01 06:43:32 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 68
2021/12/01 06:43:32 DEBUG : tomatoDir: Making directory
    sync_test.go:1215: Server side move (if possible) Putio root 'rclone-test-tametin3xuciqil2matoyez2' -> Putio root 'rclone-test-bijiciq6denigug9nikucur0'
2021/12/01 06:43:33 DEBUG : Putio root 'rclone-test-bijiciq6denigug9nikucur0': chunkStart: 0, reqSize: 1
2021/12/01 06:43:33 DEBUG : Putio root 'rclone-test-bijiciq6denigug9nikucur0': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:43:36 DEBUG : Putio root 'rclone-test-bijiciq6denigug9nikucur0': chunkStart: 0, reqSize: 60
2021/12/01 06:43:36 DEBUG : Putio root 'rclone-test-bijiciq6denigug9nikucur0': Sending chunk. transferOffset: 0 length: 60
2021/12/01 06:43:37 DEBUG : Putio root 'rclone-test-bijiciq6denigug9nikucur0': Using server-side directory move
2021/12/01 06:43:37 INFO  : Putio root 'rclone-test-bijiciq6denigug9nikucur0': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2021/12/01 06:43:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s)
2021/12/01 06:43:37 DEBUG : empty space: Unchanged skipping
2021/12/01 06:43:37 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2021/12/01 06:43:37 DEBUG : Putio root 'rclone-test-bijiciq6denigug9nikucur0': Waiting for checks to finish
2021/12/01 06:43:37 INFO  : potato3: Deleted
2021/12/01 06:43:37 INFO  : potato2: Moved (server-side)
2021/12/01 06:43:37 INFO  : potato3: Moved (server-side)
2021/12/01 06:43:38 INFO  : empty space: Deleted
2021/12/01 06:43:38 DEBUG : Putio root 'rclone-test-bijiciq6denigug9nikucur0': Waiting for transfers to finish
2021/12/01 06:43:38 INFO  : tomatoDir: Removing directory
2021/12/01 06:43:38 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': deleted 1 directories
2021/12/01 06:43:38 INFO  : There was nothing to transfer
2021/12/01 06:43:39 DEBUG : Creating backend with remote "TestPutio:rclone-test-fixeroc2xadipep7demeruq1"
2021/12/01 06:43:39 DEBUG : tomatoDir: Making directory
2021/12/01 06:43:39 DEBUG : Putio root 'rclone-test-fixeroc2xadipep7demeruq1': Using server-side directory move
2021/12/01 06:43:39 INFO  : Putio root 'rclone-test-fixeroc2xadipep7demeruq1': Server side directory move succeeded
2021/12/01 06:43:40 ERROR : : error listing: directory not found
2021/12/01 06:43:40 ERROR : : error listing: directory not found
2021/12/01 06:43:40 DEBUG : Putio root 'rclone-test-fixeroc2xadipep7demeruq1': Purge remote
2021/12/01 06:43:40 DEBUG : Putio root 'rclone-test-bijiciq6denigug9nikucur0': Purge remote
2021/12/01 06:43:40 purge failed: Rmdir: directory not found
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (15.82s)
=== RUN   TestServerSideMoveOverlap
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
    sync_test.go:1399: Skipping test as remote supports DirMove
--- SKIP: TestServerSideMoveOverlap (0.15s)
=== RUN   TestSyncOverlap
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:43:40 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/rclone-sync-test"
--- PASS: TestSyncOverlap (0.49s)
=== RUN   TestSyncCompareDest
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:43:41 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/dst"
2021/12/01 06:43:41 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/CompareDest"
2021/12/01 06:43:41 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:43:41 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:43:42 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 3
2021/12/01 06:43:42 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:43:43 DEBUG : one: crc32 = 7a6c86f1 OK
2021/12/01 06:43:43 INFO  : one: Copied (new)
2021/12/01 06:43:43 DEBUG : Waiting for deletions to finish
2021/12/01 06:43:43 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:43:43 DEBUG : one: Sizes differ (src 5 vs dst 3)
2021/12/01 06:43:43 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:43:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 5
2021/12/01 06:43:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:43:44 DEBUG : one: crc32 = cb217308 OK
2021/12/01 06:43:44 INFO  : one: Copied (replaced existing)
2021/12/01 06:43:44 DEBUG : Waiting for deletions to finish
2021/12/01 06:43:45 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:43:45 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:43:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 5
2021/12/01 06:43:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:44:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:44:04 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:44:04 DEBUG : one: Destination found in --compare-dest, skipping
2021/12/01 06:44:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:44:04 DEBUG : Waiting for deletions to finish
2021/12/01 06:44:04 INFO  : There was nothing to transfer
2021/12/01 06:44:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:44:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:44:16 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:44:16 DEBUG : two: Destination found in --compare-dest, skipping
2021/12/01 06:44:16 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:44:16 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:44:16 DEBUG : one: Destination found in --compare-dest, skipping
2021/12/01 06:44:16 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:44:16 DEBUG : Waiting for deletions to finish
2021/12/01 06:44:16 INFO  : There was nothing to transfer
2021/12/01 06:44:16 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:44:16 DEBUG : two: Destination found in --compare-dest, skipping
2021/12/01 06:44:16 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:44:16 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:44:16 DEBUG : one: Destination found in --compare-dest, skipping
2021/12/01 06:44:16 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:44:16 DEBUG : Waiting for deletions to finish
2021/12/01 06:44:16 INFO  : There was nothing to transfer
2021/12/01 06:44:17 DEBUG : two: Modification times differ by -120h0m0s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59 +0000 UTC
2021/12/01 06:44:17 DEBUG : two: crc32 = 11ca8a66 OK
2021/12/01 06:44:17 DEBUG : two: Destination found in --compare-dest, skipping
2021/12/01 06:44:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:44:17 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:44:17 DEBUG : one: Destination found in --compare-dest, skipping
2021/12/01 06:44:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:44:17 DEBUG : Waiting for deletions to finish
2021/12/01 06:44:17 INFO  : There was nothing to transfer
2021/12/01 06:44:18 DEBUG : two: Sizes differ (src 5 vs dst 3)
2021/12/01 06:44:18 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:44:18 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:44:18 DEBUG : one: Destination found in --compare-dest, skipping
2021/12/01 06:44:18 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:44:18 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 5
2021/12/01 06:44:18 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:44:19 DEBUG : two: crc32 = 8b9078ce OK
2021/12/01 06:44:19 INFO  : two: Copied (new)
2021/12/01 06:44:19 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCompareDest (40.35s)
=== RUN   TestSyncMultipleCompareDest
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:44:22 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:44:22 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:44:28 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 1
2021/12/01 06:44:28 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:44:30 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/dest"
2021/12/01 06:44:30 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/pre-dest1"
2021/12/01 06:44:30 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/pre-dest2"
2021/12/01 06:44:30 DEBUG : 1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:44:30 DEBUG : 1: Destination found in --compare-dest, skipping
2021/12/01 06:44:31 DEBUG : 2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:44:31 DEBUG : 2: Destination found in --compare-dest, skipping
2021/12/01 06:44:31 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dest': Waiting for checks to finish
2021/12/01 06:44:31 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dest': Waiting for transfers to finish
2021/12/01 06:44:32 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dest': chunkStart: 0, reqSize: 1
2021/12/01 06:44:32 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dest': Sending chunk. transferOffset: 0 length: 1
2021/12/01 06:44:33 DEBUG : 3: crc32 = 6dd28e9b OK
2021/12/01 06:44:33 INFO  : 3: Copied (new)
2021/12/01 06:44:33 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncMultipleCompareDest (13.77s)
=== RUN   TestSyncCopyDest
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:44:35 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/dst"
2021/12/01 06:44:35 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/CopyDest"
2021/12/01 06:44:36 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:44:36 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:44:36 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 3
2021/12/01 06:44:36 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:44:37 DEBUG : one: crc32 = 7a6c86f1 OK
2021/12/01 06:44:37 INFO  : one: Copied (new)
2021/12/01 06:44:37 DEBUG : Waiting for deletions to finish
2021/12/01 06:44:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:44:37 DEBUG : one: Sizes differ (src 5 vs dst 3)
2021/12/01 06:44:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:44:38 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 5
2021/12/01 06:44:38 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:44:39 DEBUG : one: crc32 = cb217308 OK
2021/12/01 06:44:39 INFO  : one: Copied (replaced existing)
2021/12/01 06:44:39 DEBUG : Waiting for deletions to finish
2021/12/01 06:44:40 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:44:40 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:44:42 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 5
2021/12/01 06:44:42 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:44:42 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/BackupDir"
2021/12/01 06:44:43 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:44:43 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2021/12/01 06:44:43 DEBUG : one: Sizes differ (src 5 vs dst 3)
2021/12/01 06:44:44 INFO  : one: Moved (server-side)
2021/12/01 06:44:44 ERROR : one: Failed to copy: unexpected status code (400) response while doing POST to https://api.put.io/v2/files/copy
2021/12/01 06:44:44 ERROR : one: Destination found in --copy-dest, error copying
2021/12/01 06:44:44 DEBUG : one: Sizes differ (src 5 vs dst 3)
2021/12/01 06:44:44 INFO  : one: Deleted
2021/12/01 06:44:44 ERROR : one: Couldn't move: object not found
2021/12/01 06:44:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:44:44 ERROR : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': not deleting files as there were IO errors
2021/12/01 06:44:44 ERROR : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': not deleting directories as there were IO errors
    sync_test.go:1648: 
        	Error Trace:	sync_test.go:1648
        	Error:      	Received unexpected error:
        	            	object not found
        	Test:       	TestSyncCopyDest
--- FAIL: TestSyncCopyDest (10.34s)
=== RUN   TestSyncBackupDir
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:44:47 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:44:47 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:44:48 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:44:48 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:44:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 5
2021/12/01 06:44:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:44:52 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/dst"
2021/12/01 06:44:52 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/backup"
2021/12/01 06:44:53 DEBUG : one: Sizes differ (src 4 vs dst 3)
2021/12/01 06:44:53 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:44:53 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:44:53 DEBUG : two: Unchanged skipping
2021/12/01 06:44:53 INFO  : one: Moved (server-side)
2021/12/01 06:44:53 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:44:54 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 4
2021/12/01 06:44:54 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 4
2021/12/01 06:44:56 DEBUG : one: crc32 = 19193087 OK
2021/12/01 06:44:56 INFO  : one: Copied (new)
2021/12/01 06:44:56 DEBUG : Waiting for deletions to finish
2021/12/01 06:44:56 INFO  : three.txt: Moved (server-side)
2021/12/01 06:44:56 INFO  : three.txt: Moved into backup dir
2021/12/01 06:44:57 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:44:57 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:44:59 DEBUG : one: Sizes differ (src 5 vs dst 4)
2021/12/01 06:44:59 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:44:59 DEBUG : two: Unchanged skipping
2021/12/01 06:44:59 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:45:00 INFO  : one: Deleted
2021/12/01 06:45:00 INFO  : one: Moved (server-side)
2021/12/01 06:45:00 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:45:00 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 5
2021/12/01 06:45:00 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:45:01 DEBUG : one: crc32 = 12389341 OK
2021/12/01 06:45:01 INFO  : one: Copied (new)
2021/12/01 06:45:01 DEBUG : Waiting for deletions to finish
2021/12/01 06:45:01 INFO  : three.txt: Deleted
2021/12/01 06:45:01 INFO  : three.txt: Moved (server-side)
2021/12/01 06:45:01 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDir (18.94s)
=== RUN   TestSyncBackupDirWithSuffix
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:45:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:45:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:45:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:45:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:45:19 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 5
2021/12/01 06:45:19 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:45:20 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/dst"
2021/12/01 06:45:20 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/backup"
2021/12/01 06:45:20 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:45:20 DEBUG : one: Sizes differ (src 4 vs dst 3)
2021/12/01 06:45:20 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:45:20 DEBUG : two: Unchanged skipping
2021/12/01 06:45:21 INFO  : one: Moved (server-side) to: one.bak
2021/12/01 06:45:21 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:45:21 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 4
2021/12/01 06:45:21 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 4
2021/12/01 06:45:22 DEBUG : one: crc32 = 19193087 OK
2021/12/01 06:45:22 INFO  : one: Copied (new)
2021/12/01 06:45:22 DEBUG : Waiting for deletions to finish
2021/12/01 06:45:22 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2021/12/01 06:45:22 INFO  : three.txt: Moved into backup dir
2021/12/01 06:45:23 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:45:23 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:45:24 DEBUG : one: Sizes differ (src 5 vs dst 4)
2021/12/01 06:45:24 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:45:24 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:45:24 DEBUG : two: Unchanged skipping
2021/12/01 06:45:24 INFO  : one.bak: Deleted
2021/12/01 06:45:24 INFO  : one: Moved (server-side) to: one.bak
2021/12/01 06:45:24 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:45:25 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 5
2021/12/01 06:45:25 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:45:26 DEBUG : one: crc32 = 12389341 OK
2021/12/01 06:45:26 INFO  : one: Copied (new)
2021/12/01 06:45:26 DEBUG : Waiting for deletions to finish
2021/12/01 06:45:26 INFO  : three.txt.bak: Deleted
2021/12/01 06:45:26 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2021/12/01 06:45:26 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffix (23.83s)
=== RUN   TestSyncBackupDirWithSuffixKeepExtension
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:45:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:45:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:45:30 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:45:30 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:45:40 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 5
2021/12/01 06:45:40 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:45:51 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/dst"
2021/12/01 06:45:51 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/backup"
2021/12/01 06:45:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:45:52 DEBUG : one: Sizes differ (src 4 vs dst 3)
2021/12/01 06:45:52 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:45:52 DEBUG : two: Unchanged skipping
2021/12/01 06:45:52 INFO  : one: Moved (server-side) to: one-2019-01-01
2021/12/01 06:45:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:45:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 4
2021/12/01 06:45:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 4
2021/12/01 06:45:53 DEBUG : one: crc32 = 19193087 OK
2021/12/01 06:45:53 INFO  : one: Copied (new)
2021/12/01 06:45:53 DEBUG : Waiting for deletions to finish
2021/12/01 06:45:53 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2021/12/01 06:45:53 INFO  : three.txt: Moved into backup dir
2021/12/01 06:45:54 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:45:54 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:45:55 DEBUG : one: Sizes differ (src 5 vs dst 4)
2021/12/01 06:45:55 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:45:55 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:45:55 DEBUG : two: Unchanged skipping
2021/12/01 06:45:56 INFO  : one-2019-01-01: Deleted
2021/12/01 06:45:56 INFO  : one: Moved (server-side) to: one-2019-01-01
2021/12/01 06:45:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:45:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 5
2021/12/01 06:45:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:45:57 DEBUG : one: crc32 = 12389341 OK
2021/12/01 06:45:57 INFO  : one: Copied (new)
2021/12/01 06:45:57 DEBUG : Waiting for deletions to finish
2021/12/01 06:45:57 INFO  : three-2019-01-01.txt: Deleted
2021/12/01 06:45:58 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2021/12/01 06:45:58 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffixKeepExtension (31.35s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:46:00 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:46:00 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:46:01 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:46:01 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:46:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 5
2021/12/01 06:46:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:46:09 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/dst"
2021/12/01 06:46:09 DEBUG : one: Sizes differ (src 4 vs dst 3)
2021/12/01 06:46:09 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:46:09 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:46:09 DEBUG : two: Unchanged skipping
2021/12/01 06:46:10 INFO  : one: Moved (server-side) to: one.bak
2021/12/01 06:46:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:46:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 4
2021/12/01 06:46:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 4
2021/12/01 06:46:11 DEBUG : one: crc32 = 19193087 OK
2021/12/01 06:46:11 INFO  : one: Copied (new)
2021/12/01 06:46:11 DEBUG : Waiting for deletions to finish
2021/12/01 06:46:11 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2021/12/01 06:46:11 INFO  : three.txt: Moved into backup dir
2021/12/01 06:46:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:46:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:46:15 DEBUG : one.bak: Excluded
2021/12/01 06:46:15 DEBUG : three.txt.bak: Excluded
2021/12/01 06:46:15 DEBUG : one: Sizes differ (src 5 vs dst 4)
2021/12/01 06:46:15 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:46:15 DEBUG : two: Unchanged skipping
2021/12/01 06:46:15 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for checks to finish
2021/12/01 06:46:15 INFO  : one.bak: Deleted
2021/12/01 06:46:15 INFO  : one: Moved (server-side) to: one.bak
2021/12/01 06:46:15 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Waiting for transfers to finish
2021/12/01 06:46:16 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 5
2021/12/01 06:46:16 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:46:16 DEBUG : one: crc32 = 12389341 OK
2021/12/01 06:46:16 INFO  : one: Copied (new)
2021/12/01 06:46:16 DEBUG : Waiting for deletions to finish
2021/12/01 06:46:17 INFO  : three.txt.bak: Deleted
2021/12/01 06:46:17 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2021/12/01 06:46:17 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (19.16s)
=== RUN   TestSyncSuffix
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:46:19 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:46:19 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:46:20 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:46:20 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:46:26 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 5
2021/12/01 06:46:26 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:46:26 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/dst"
2021/12/01 06:46:27 DEBUG : one: Sizes differ (src 4 vs dst 3)
2021/12/01 06:46:27 INFO  : one: Moved (server-side) to: one.bak
2021/12/01 06:46:27 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 4
2021/12/01 06:46:27 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 4
2021/12/01 06:46:34 DEBUG : one: crc32 = 19193087 OK
2021/12/01 06:46:34 INFO  : one: Copied (new)
2021/12/01 06:46:34 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:46:34 DEBUG : two: Unchanged skipping
2021/12/01 06:46:34 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2021/12/01 06:46:35 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2021/12/01 06:46:35 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 6
2021/12/01 06:46:35 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:46:35 DEBUG : three.txt: crc32 = 1e485dc0 OK
2021/12/01 06:46:35 INFO  : three.txt: Copied (new)
2021/12/01 06:46:36 DEBUG : one: Sizes differ (src 5 vs dst 4)
2021/12/01 06:46:36 INFO  : one.bak: Deleted
2021/12/01 06:46:36 INFO  : one: Moved (server-side) to: one.bak
2021/12/01 06:46:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 5
2021/12/01 06:46:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:46:39 DEBUG : one: crc32 = 12389341 OK
2021/12/01 06:46:39 INFO  : one: Copied (new)
2021/12/01 06:46:39 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:46:39 DEBUG : two: Unchanged skipping
2021/12/01 06:46:39 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2021/12/01 06:46:39 INFO  : three.txt.bak: Deleted
2021/12/01 06:46:39 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2021/12/01 06:46:40 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 19
2021/12/01 06:46:40 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 19
2021/12/01 06:46:41 DEBUG : three.txt: crc32 = 5c880d37 OK
2021/12/01 06:46:41 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffix (23.68s)
=== RUN   TestSyncSuffixKeepExtension
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:46:43 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:46:43 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:46:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 3
2021/12/01 06:46:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 3
2021/12/01 06:46:48 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 5
2021/12/01 06:46:48 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:46:49 DEBUG : Creating backend with remote "TestPutio:rclone-test-tametin3xuciqil2matoyez2/dst"
2021/12/01 06:46:49 DEBUG : one: Sizes differ (src 4 vs dst 3)
2021/12/01 06:46:50 INFO  : one: Moved (server-side) to: one-2019-01-01
2021/12/01 06:46:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 4
2021/12/01 06:46:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 4
2021/12/01 06:46:52 DEBUG : one: crc32 = 19193087 OK
2021/12/01 06:46:52 INFO  : one: Copied (new)
2021/12/01 06:46:52 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:46:52 DEBUG : two: Unchanged skipping
2021/12/01 06:46:52 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2021/12/01 06:46:52 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2021/12/01 06:46:53 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 6
2021/12/01 06:46:53 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:46:54 DEBUG : three.txt: crc32 = 1e485dc0 OK
2021/12/01 06:46:54 INFO  : three.txt: Copied (new)
2021/12/01 06:46:54 DEBUG : one: Sizes differ (src 5 vs dst 4)
2021/12/01 06:46:54 INFO  : one-2019-01-01: Deleted
2021/12/01 06:46:54 INFO  : one: Moved (server-side) to: one-2019-01-01
2021/12/01 06:46:55 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 5
2021/12/01 06:46:55 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 5
2021/12/01 06:46:55 DEBUG : one: crc32 = 12389341 OK
2021/12/01 06:46:55 INFO  : one: Copied (new)
2021/12/01 06:46:55 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:46:55 DEBUG : two: Unchanged skipping
2021/12/01 06:46:55 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2021/12/01 06:46:56 INFO  : three-2019-01-01.txt: Deleted
2021/12/01 06:46:56 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2021/12/01 06:46:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': chunkStart: 0, reqSize: 19
2021/12/01 06:46:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2/dst': Sending chunk. transferOffset: 0 length: 19
2021/12/01 06:46:57 DEBUG : three.txt: crc32 = 5c880d37 OK
2021/12/01 06:46:57 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffixKeepExtension (16.05s)
=== RUN   TestSyncUTFNorm
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:46:59 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 18
2021/12/01 06:46:59 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 18
2021/12/01 06:46:59 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:46:59 DEBUG : Testêé: Sizes differ (src 14 vs dst 18)
2021/12/01 06:46:59 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:47:00 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 14
2021/12/01 06:47:00 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 14
2021/12/01 06:47:02 DEBUG : Testêé: crc32 = c07a9f32 OK
2021/12/01 06:47:02 INFO  : Testêé: Copied (replaced existing)
2021/12/01 06:47:02 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncUTFNorm (4.35s)
=== RUN   TestSyncImmutable
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:47:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:47:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:47:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:05 DEBUG : existing: crc32 = 9a941a19 OK
2021/12/01 06:47:05 INFO  : existing: Copied (new)
2021/12/01 06:47:05 DEBUG : Waiting for deletions to finish
2021/12/01 06:47:05 DEBUG : existing: Sizes differ (src 8 vs dst 6)
2021/12/01 06:47:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:47:05 ERROR : existing: Source and destination exist but do not match: immutable file modified
2021/12/01 06:47:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:47:05 ERROR : Putio root 'rclone-test-tametin3xuciqil2matoyez2': not deleting files as there were IO errors
2021/12/01 06:47:05 ERROR : Putio root 'rclone-test-tametin3xuciqil2matoyez2': not deleting directories as there were IO errors
--- PASS: TestSyncImmutable (2.73s)
=== RUN   TestSyncIgnoreCase
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:47:06 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:06 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:09 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:47:09 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:47:09 DEBUG : existing: Unchanged skipping
2021/12/01 06:47:09 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:47:09 DEBUG : Waiting for deletions to finish
2021/12/01 06:47:09 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreCase (4.13s)
=== RUN   TestMaxTransfer
=== RUN   TestMaxTransfer/Hard
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
    sync_test.go:1989: This test only runs on local
=== RUN   TestMaxTransfer/Soft
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
    sync_test.go:1989: This test only runs on local
=== RUN   TestMaxTransfer/Cautious
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
    sync_test.go:1989: This test only runs on local
--- PASS: TestMaxTransfer (0.84s)
    --- SKIP: TestMaxTransfer/Hard (0.32s)
    --- SKIP: TestMaxTransfer/Soft (0.32s)
    --- SKIP: TestMaxTransfer/Cautious (0.20s)
=== RUN   TestSyncConcurrentDelete
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:47:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:13 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:13 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:14 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:14 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:15 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:15 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:16 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:16 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:20 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:20 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:27 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:27 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:33 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:33 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:35 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:35 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:37 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:43 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:43 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:46 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:46 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:47 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:47 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:47:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:47:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:07 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:07 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:09 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:09 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:13 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:13 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:22 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:22 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:25 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:25 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:26 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:26 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:27 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:27 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:32 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:32 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:33 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:33 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:34 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:34 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:38 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:38 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:39 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:39 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:40 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:40 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:42 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:42 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:48:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:48:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:01 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:01 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:01 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both0: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both11: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both12: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:49:01 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both13: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both14: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both15: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both16: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both17: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both18: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both19: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both2: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both3: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both4: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both5: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both10: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both6: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both8: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both7: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both1: Unchanged skipping
2021/12/01 06:49:01 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:49:01 DEBUG : both9: Unchanged skipping
2021/12/01 06:49:01 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:49:01 DEBUG : Waiting for deletions to finish
2021/12/01 06:49:02 INFO  : only14: Deleted
2021/12/01 06:49:02 INFO  : only5: Deleted
2021/12/01 06:49:02 INFO  : only8: Deleted
2021/12/01 06:49:02 INFO  : only1: Deleted
2021/12/01 06:49:02 INFO  : only2: Deleted
2021/12/01 06:49:02 INFO  : only6: Deleted
2021/12/01 06:49:03 INFO  : only17: Deleted
2021/12/01 06:49:03 INFO  : only11: Deleted
2021/12/01 06:49:03 INFO  : only13: Deleted
2021/12/01 06:49:03 INFO  : only7: Deleted
2021/12/01 06:49:03 INFO  : only0: Deleted
2021/12/01 06:49:03 INFO  : only12: Deleted
2021/12/01 06:49:03 INFO  : only15: Deleted
2021/12/01 06:49:04 INFO  : only9: Deleted
2021/12/01 06:49:04 INFO  : only19: Deleted
2021/12/01 06:49:04 INFO  : only4: Deleted
2021/12/01 06:49:04 INFO  : only10: Deleted
2021/12/01 06:49:05 INFO  : only18: Deleted
2021/12/01 06:49:06 INFO  : only3: Deleted
2021/12/01 06:49:07 INFO  : only16: Deleted
2021/12/01 06:49:07 INFO  : There was nothing to transfer
--- PASS: TestSyncConcurrentDelete (120.19s)
=== RUN   TestSyncConcurrentTruncate
    run.go:181: Remote "Putio root 'rclone-test-tametin3xuciqil2matoyez2'", Local "Local file system at /tmp/rclone1890035687", Modify Window "1s"
2021/12/01 06:49:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:17 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:18 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:18 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:19 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:19 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:20 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:20 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:23 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:23 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:24 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:24 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:33 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:33 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:35 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:35 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:40 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:40 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:41 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:41 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:42 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:42 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:43 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:43 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:44 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:46 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:46 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:47 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:47 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:48 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:48 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:49 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:49 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:50 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:51 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:51 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:58 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:58 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:49:59 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:49:59 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:01 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:01 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:06 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:06 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:12 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:13 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:13 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:26 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:26 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:28 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:28 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:29 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:30 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:30 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:34 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:34 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:35 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:35 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:51 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:51 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:52 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:53 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:53 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:54 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': chunkStart: 0, reqSize: 6
2021/12/01 06:50:54 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending chunk. transferOffset: 0 length: 6
2021/12/01 06:50:55 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for checks to finish
2021/12/01 06:50:55 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both10: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both12: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both13: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both14: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both11: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both16: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both17: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both18: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both19: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both2: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both15: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both3: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both4: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both5: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both1: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both7: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : both6: Unchanged skipping
2021/12/01 06:50:55 DEBUG : both0: Unchanged skipping
2021/12/01 06:50:55 DEBUG : only0: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : both8: Unchanged skipping
2021/12/01 06:50:55 DEBUG : only11: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only10: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only12: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only14: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only15: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only16: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only13: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only1: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2021/12/01 06:50:55 DEBUG : only18: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only2: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only3: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only4: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only5: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only6: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only7: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only8: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only9: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only19: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : only17: Sizes differ (src 0 vs dst 6)
2021/12/01 06:50:55 DEBUG : both9: Unchanged skipping
2021/12/01 06:50:55 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Waiting for transfers to finish
2021/12/01 06:50:56 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:50:57 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:50:57 DEBUG : only0: crc32 = 00000000 OK
2021/12/01 06:50:57 INFO  : only0: Copied (replaced existing)
2021/12/01 06:50:57 DEBUG : only11: crc32 = 00000000 OK
2021/12/01 06:50:57 INFO  : only11: Copied (replaced existing)
2021/12/01 06:50:58 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:50:58 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:50:58 DEBUG : only14: crc32 = 00000000 OK
2021/12/01 06:50:58 INFO  : only14: Copied (replaced existing)
2021/12/01 06:50:59 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:00 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:00 DEBUG : only16: crc32 = 00000000 OK
2021/12/01 06:51:00 INFO  : only16: Copied (replaced existing)
2021/12/01 06:51:01 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:01 DEBUG : only10: crc32 = 00000000 OK
2021/12/01 06:51:01 INFO  : only10: Copied (replaced existing)
2021/12/01 06:51:01 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:01 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:02 DEBUG : only15: crc32 = 00000000 OK
2021/12/01 06:51:02 INFO  : only15: Copied (replaced existing)
2021/12/01 06:51:02 DEBUG : only12: crc32 = 00000000 OK
2021/12/01 06:51:02 INFO  : only12: Copied (replaced existing)
2021/12/01 06:51:02 DEBUG : only1: crc32 = 00000000 OK
2021/12/01 06:51:02 INFO  : only1: Copied (replaced existing)
2021/12/01 06:51:02 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:03 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:04 DEBUG : only3: crc32 = 00000000 OK
2021/12/01 06:51:04 INFO  : only3: Copied (replaced existing)
2021/12/01 06:51:04 DEBUG : only2: crc32 = 00000000 OK
2021/12/01 06:51:04 INFO  : only2: Copied (replaced existing)
2021/12/01 06:51:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:04 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:05 DEBUG : only4: crc32 = 00000000 OK
2021/12/01 06:51:05 INFO  : only4: Copied (replaced existing)
2021/12/01 06:51:05 DEBUG : only5: crc32 = 00000000 OK
2021/12/01 06:51:05 INFO  : only5: Copied (replaced existing)
2021/12/01 06:51:05 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:06 DEBUG : only13: crc32 = 00000000 OK
2021/12/01 06:51:06 INFO  : only13: Copied (replaced existing)
2021/12/01 06:51:06 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:06 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:07 DEBUG : only8: crc32 = 00000000 OK
2021/12/01 06:51:07 INFO  : only8: Copied (replaced existing)
2021/12/01 06:51:08 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:08 DEBUG : pacer: low level retry 1/10 (error unexpected status code (500) response while doing PATCH to https://s66.put.io/files/79baf3c73996aa449a074f55d93641aac988d6c5)
2021/12/01 06:51:08 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/12/01 06:51:08 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:08 DEBUG : pacer: Reducing sleep to 15ms
2021/12/01 06:51:08 DEBUG : pacer: Reducing sleep to 11.25ms
2021/12/01 06:51:08 DEBUG : only7: crc32 = 00000000 OK
2021/12/01 06:51:08 INFO  : only7: Copied (replaced existing)
2021/12/01 06:51:09 DEBUG : pacer: Reducing sleep to 10ms
2021/12/01 06:51:09 DEBUG : only9: crc32 = 00000000 OK
2021/12/01 06:51:09 INFO  : only9: Copied (replaced existing)
2021/12/01 06:51:09 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:09 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:10 DEBUG : pacer: low level retry 1/10 (error unexpected status code (500) response while doing PATCH to https://s77.put.io/files/213335392f4730d667a8c3e2583e47abfd335fc7)
2021/12/01 06:51:10 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/12/01 06:51:10 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:10 DEBUG : pacer: Reducing sleep to 15ms
2021/12/01 06:51:10 DEBUG : pacer: Reducing sleep to 11.25ms
2021/12/01 06:51:10 DEBUG : only6: crc32 = 00000000 OK
2021/12/01 06:51:10 INFO  : only6: Copied (replaced existing)
2021/12/01 06:51:10 DEBUG : pacer: Reducing sleep to 10ms
2021/12/01 06:51:11 DEBUG : only17: crc32 = 00000000 OK
2021/12/01 06:51:11 INFO  : only17: Copied (replaced existing)
2021/12/01 06:51:11 DEBUG : pacer: low level retry 1/10 (error unexpected status code (500) response while doing PATCH to https://s76.put.io/files/7b69f906531347b9b1822eb92beecd844aef5fa4)
2021/12/01 06:51:11 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/12/01 06:51:11 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Sending zero length chunk
2021/12/01 06:51:11 DEBUG : pacer: Reducing sleep to 15ms
2021/12/01 06:51:11 ERROR : only18: Failed to copy: unexpected status code (404) response while doing PATCH to https://s76.put.io/files/7b69f906531347b9b1822eb92beecd844aef5fa4
2021/12/01 06:51:11 DEBUG : pacer: Reducing sleep to 11.25ms
2021/12/01 06:51:11 DEBUG : pacer: Reducing sleep to 10ms
2021/12/01 06:51:11 DEBUG : only19: crc32 = 00000000 OK
2021/12/01 06:51:11 INFO  : only19: Copied (replaced existing)
2021/12/01 06:51:11 ERROR : Putio root 'rclone-test-tametin3xuciqil2matoyez2': not deleting files as there were IO errors
2021/12/01 06:51:11 ERROR : Putio root 'rclone-test-tametin3xuciqil2matoyez2': not deleting directories as there were IO errors
    sync_test.go:2056: 
        	Error Trace:	sync_test.go:2056
        	            				sync_test.go:2066
        	Error:      	Received unexpected error:
        	            	unexpected status code (404) response while doing PATCH to https://s76.put.io/files/7b69f906531347b9b1822eb92beecd844aef5fa4
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	Sync must not return a error
    sync_test.go:2057: 
        	Error Trace:	sync_test.go:2057
        	            				sync_test.go:2066
        	Error:      	Should be false
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	Low level errors must not have happened
    fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:302: Flushing the directory cache
    fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:302: Flushing the directory cache
    fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:302: Flushing the directory cache
    fstest.go:306: 
        	Error Trace:	fstest.go:306
        	            				fstest.go:339
        	            				fstest.go:351
        	            				run.go:342
        	            				sync_test.go:2058
        	            				sync_test.go:2066
        	Error:      	Should be true
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	listing wrong, want
        	            	  both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (0), only1 (0), only10 (0), only11 (0), only12 (0), only13 (0), only14 (0), only15 (0), only16 (0), only17 (0), only18 (0), only19 (0), only2 (0), only3 (0), only4 (0), only5 (0), only6 (0), only7 (0), only8 (0), only9 (0) got
        	            	  both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (0), only1 (0), only10 (0), only11 (0), only12 (0), only13 (0), only14 (0), only15 (0), only16 (0), only17 (0), only19 (0), only2 (0), only3 (0), only4 (0), only5 (0), only6 (0), only7 (0), only8 (0), only9 (0)
    fstest.go:204: Not found "only18"
    fstest.go:207: 
        	Error Trace:	fstest.go:207
        	            				fstest.go:311
        	            				fstest.go:339
        	            				fstest.go:351
        	            				run.go:342
        	            				sync_test.go:2058
        	            				sync_test.go:2066
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 1
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	1 objects not found
--- FAIL: TestSyncConcurrentTruncate (133.58s)
FAIL
2021/12/01 06:51:24 DEBUG : Putio root 'rclone-test-tametin3xuciqil2matoyez2': Purge remote
"./sync.test -test.v -test.timeout 2h0m0s -remote TestPutio: -verbose" - Finished ERROR in 13m21.534009982s (try 1/5): exit status 1: Failed [TestServerSideCopy TestSyncCopyDest TestSyncConcurrentTruncate]