"./sync.test -test.v -test.timeout 2h0m0s -remote TestPutio: -verbose" - Starting (try 1/5)
2025/03/17 09:11:58 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale"
2025/03/17 09:11:58 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/17 09:11:58 DEBUG : Creating backend with remote "/tmp/rclone2273536011"
=== RUN   TestPipe
--- PASS: TestPipe (0.00s)
=== RUN   TestPipeConcurrent
--- PASS: TestPipeConcurrent (0.02s)
=== RUN   TestPipeOrderBy
=== RUN   TestPipeOrderBy/#00
=== RUN   TestPipeOrderBy/size
=== RUN   TestPipeOrderBy/name
=== RUN   TestPipeOrderBy/modtime
=== RUN   TestPipeOrderBy/size,ascending
=== RUN   TestPipeOrderBy/name,asc
=== RUN   TestPipeOrderBy/modtime,ascending
=== RUN   TestPipeOrderBy/size,descending
=== RUN   TestPipeOrderBy/name,desc
=== RUN   TestPipeOrderBy/modtime,descending
=== RUN   TestPipeOrderBy/size,mixed,50
=== RUN   TestPipeOrderBy/size,mixed,51
--- PASS: TestPipeOrderBy (0.00s)
    --- PASS: TestPipeOrderBy/#00 (0.00s)
    --- PASS: TestPipeOrderBy/size (0.00s)
    --- PASS: TestPipeOrderBy/name (0.00s)
    --- PASS: TestPipeOrderBy/modtime (0.00s)
    --- PASS: TestPipeOrderBy/size,ascending (0.00s)
    --- PASS: TestPipeOrderBy/name,asc (0.00s)
    --- PASS: TestPipeOrderBy/modtime,ascending (0.00s)
    --- PASS: TestPipeOrderBy/size,descending (0.00s)
    --- PASS: TestPipeOrderBy/name,desc (0.00s)
    --- PASS: TestPipeOrderBy/modtime,descending (0.00s)
    --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s)
    --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s)
=== RUN   TestNewLess
=== RUN   TestNewLess/blankOK
=== RUN   TestNewLess/tooManyParts
=== RUN   TestNewLess/tooManyParts2
=== RUN   TestNewLess/badMixed
=== RUN   TestNewLess/unknownComparison
=== RUN   TestNewLess/unknownSortDirection
=== RUN   TestNewLess/size
=== RUN   TestNewLess/name
=== RUN   TestNewLess/modtime
=== RUN   TestNewLess/size,ascending
=== RUN   TestNewLess/name,asc
=== RUN   TestNewLess/modtime,ascending
=== RUN   TestNewLess/size,descending
=== RUN   TestNewLess/name,desc
=== RUN   TestNewLess/modtime,descending
=== RUN   TestNewLess/modtime,mixed
=== RUN   TestNewLess/modtime,mixed,30
--- PASS: TestNewLess (0.00s)
    --- PASS: TestNewLess/blankOK (0.00s)
    --- PASS: TestNewLess/tooManyParts (0.00s)
    --- PASS: TestNewLess/tooManyParts2 (0.00s)
    --- PASS: TestNewLess/badMixed (0.00s)
    --- PASS: TestNewLess/unknownComparison (0.00s)
    --- PASS: TestNewLess/unknownSortDirection (0.00s)
    --- PASS: TestNewLess/size (0.00s)
    --- PASS: TestNewLess/name (0.00s)
    --- PASS: TestNewLess/modtime (0.00s)
    --- PASS: TestNewLess/size,ascending (0.00s)
    --- PASS: TestNewLess/name,asc (0.00s)
    --- PASS: TestNewLess/modtime,ascending (0.00s)
    --- PASS: TestNewLess/size,descending (0.00s)
    --- PASS: TestNewLess/name,desc (0.00s)
    --- PASS: TestNewLess/modtime,descending (0.00s)
    --- PASS: TestNewLess/modtime,mixed (0.00s)
    --- PASS: TestNewLess/modtime,mixed,30 (0.00s)
=== RUN   TestRcCopy
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcCopy (0.00s)
=== RUN   TestRcMove
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcMove (0.00s)
=== RUN   TestRcSync
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcSync (0.00s)
=== RUN   TestCopyWithDryRun
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:11:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:11:59 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:11:59 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11)
2025/03/17 09:11:59 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestCopyWithDryRun (0.64s)
=== RUN   TestCopy
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:11:59 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/03/17 09:11:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:11:59 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:11:59 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:00 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:00 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:00 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:12:00 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:01 DEBUG : Putio root 'rclone-test-rogadic8yale': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestCopy (2.40s)
=== RUN   TestCopyMetadata
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    sync_test.go:97: Skipping as metadata not supported
--- SKIP: TestCopyMetadata (0.79s)
=== RUN   TestCopyMetadataNoEmptyDirs
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    sync_test.go:97: Skipping as metadata not supported
--- SKIP: TestCopyMetadataNoEmptyDirs (0.12s)
=== RUN   TestCopyMissingDirectory
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:02 DEBUG : Creating backend with remote "/non-existing"
2025/03/17 09:12:02 ERROR : Local file system at /non-existing: error reading source root directory: directory not found
2025/03/17 09:12:02 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:02 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestCopyMissingDirectory (0.31s)
=== RUN   TestCopyNoTraverse
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:03 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:12:03 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:03 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:04 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:04 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:04 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:12:04 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestCopyNoTraverse (4.09s)
=== RUN   TestCopyCheckFirst
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:07 INFO  : Putio root 'rclone-test-rogadic8yale': Running all checks before starting transfers
2025/03/17 09:12:07 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:12:07 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:07 INFO  : Putio root 'rclone-test-rogadic8yale': Checks finished, now starting transfers
2025/03/17 09:12:07 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:07 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:07 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:08 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:12:08 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestCopyCheckFirst (2.20s)
=== RUN   TestSyncNoTraverse
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:09 ERROR : Ignoring --no-traverse with sync
2025/03/17 09:12:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:12:09 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:09 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:09 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:09 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:10 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:12:10 INFO  : sub dir/hello world: Copied (new)
2025/03/17 09:12:10 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncNoTraverse (1.74s)
=== RUN   TestCopyWithDepth
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:11 DEBUG : hello world2: Need to transfer - File not found at Destination
2025/03/17 09:12:11 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:11 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:11 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 12
2025/03/17 09:12:11 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 12
2025/03/17 09:12:12 DEBUG : hello world2: crc32 = 870a83b3 OK
2025/03/17 09:12:12 INFO  : hello world2: Copied (new)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestCopyWithDepth (2.56s)
=== RUN   TestCopyWithFilesFrom
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:13 DEBUG : potato2: Need to transfer - File not found at Destination
2025/03/17 09:12:13 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:13 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:14 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:14 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:14 DEBUG : potato2: crc32 = 0d4a1185 OK
2025/03/17 09:12:14 INFO  : potato2: Copied (new)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestCopyWithFilesFrom (1.36s)
=== RUN   TestCopyWithFilesFromAndNoTraverse
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:15 DEBUG : potato2: Need to transfer - File not found at Destination
2025/03/17 09:12:15 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:15 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:15 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:15 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:17 DEBUG : potato2: crc32 = 0d4a1185 OK
2025/03/17 09:12:17 INFO  : potato2: Copied (new)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestCopyWithFilesFromAndNoTraverse (2.74s)
=== RUN   TestCopyEmptyDirectories
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:17 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2025/03/17 09:12:17 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/03/17 09:12:17 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2025/03/17 09:12:17 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/03/17 09:12:17 INFO  : sub dir: Making directory
2025/03/17 09:12:18 INFO  : sub dir2: Making directory
2025/03/17 09:12:18 INFO  : sub dir2/sub sub dir2: Making directory
2025/03/17 09:12:18 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:12:18 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:18 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:18 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:18 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:19 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:12:19 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:19 DEBUG : Putio root 'rclone-test-rogadic8yale': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestCopyEmptyDirectories (3.64s)
=== RUN   TestCopyNoEmptyDirectories
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:21 INFO  : sub dir2: Making directory
2025/03/17 09:12:21 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2025/03/17 09:12:21 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/03/17 09:12:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:12:21 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:21 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:21 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:21 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:22 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:12:22 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyNoEmptyDirectories (1.56s)
=== RUN   TestMoveEmptyDirectories
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:23 DEBUG : sub dir2: Making directory with metadata
2025/03/17 09:12:23 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/03/17 09:12:23 INFO  : sub dir: Making directory
2025/03/17 09:12:23 INFO  : sub dir2: Making directory
2025/03/17 09:12:23 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:12:23 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:23 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:23 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:23 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:24 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:12:24 INFO  : sub dir/hello world: Copied (new)
2025/03/17 09:12:24 INFO  : sub dir/hello world: Deleted
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:24 DEBUG : Putio root 'rclone-test-rogadic8yale': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
2025/03/17 09:12:25 DEBUG : Putio root 'rclone-test-rogadic8yale': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestMoveEmptyDirectories (3.41s)
=== RUN   TestSyncNoUpdateDirModtime
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    sync_test.go:408: Skipping test as backend does not support DirSetModTime
--- SKIP: TestSyncNoUpdateDirModtime (0.12s)
=== RUN   TestMoveNoEmptyDirectories
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:26 INFO  : sub dir2: Making directory
2025/03/17 09:12:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:12:26 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:26 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:27 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:27 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:29 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:12:29 INFO  : sub dir/hello world: Copied (new)
2025/03/17 09:12:29 INFO  : sub dir/hello world: Deleted
--- PASS: TestMoveNoEmptyDirectories (4.19s)
=== RUN   TestSyncEmptyDirectories
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:30 DEBUG : sub dir2: Making directory with metadata
2025/03/17 09:12:30 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/03/17 09:12:30 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/03/17 09:12:30 INFO  : sub dir: Making directory
2025/03/17 09:12:30 INFO  : sub dir2: Making directory
2025/03/17 09:12:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:12:31 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:31 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:31 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:31 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:31 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:12:31 INFO  : sub dir/hello world: Copied (new)
2025/03/17 09:12:31 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:31 DEBUG : Putio root 'rclone-test-rogadic8yale': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestSyncEmptyDirectories (2.64s)
=== RUN   TestSyncSetDelayedModTimes
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set
--- SKIP: TestSyncSetDelayedModTimes (0.12s)
=== RUN   TestSyncNoEmptyDirectories
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:33 INFO  : sub dir2: Making directory
2025/03/17 09:12:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:12:33 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:33 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:33 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:33 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:34 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:12:34 INFO  : sub dir/hello world: Copied (new)
2025/03/17 09:12:34 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoEmptyDirectories (1.47s)
=== RUN   TestServerSideCopy
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:35 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:35 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:35 DEBUG : Creating backend with remote "TestPutio:rclone-test-pimigax7boya"
    sync_test.go:590: Server side copy (if possible) Putio root 'rclone-test-rogadic8yale' -> Putio root 'rclone-test-pimigax7boya'
2025/03/17 09:12:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:12:36 DEBUG : Putio root 'rclone-test-pimigax7boya': Waiting for checks to finish
2025/03/17 09:12:36 DEBUG : Putio root 'rclone-test-pimigax7boya': Waiting for transfers to finish
2025/03/17 09:12:36 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:12:36 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:37 DEBUG : Putio root 'rclone-test-pimigax7boya': Purge remote
--- PASS: TestServerSideCopy (5.82s)
=== RUN   TestCopyOverSelf
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:41 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:41 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:42 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:42 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/03/17 09:12:42 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:42 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 17
2025/03/17 09:12:42 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 17
2025/03/17 09:12:42 DEBUG : sub dir/hello world: crc32 = 0c611827 OK
2025/03/17 09:12:42 INFO  : sub dir/hello world: Copied (replaced existing)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestCopyOverSelf (3.17s)
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:44 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:44 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:44 DEBUG : Creating backend with remote "TestPutio:rclone-test-bapoxek0mebe"
2025/03/17 09:12:44 DEBUG : Config file has changed externally - reloading
    sync_test.go:626: Server side copy (if possible) Putio root 'rclone-test-rogadic8yale' -> Putio root 'rclone-test-bapoxek0mebe'
2025/03/17 09:12:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:12:45 DEBUG : Putio root 'rclone-test-bapoxek0mebe': Waiting for checks to finish
2025/03/17 09:12:45 DEBUG : Putio root 'rclone-test-bapoxek0mebe': Waiting for transfers to finish
2025/03/17 09:12:46 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:12:46 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:46 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 17
2025/03/17 09:12:46 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 17
2025/03/17 09:12:47 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/03/17 09:12:47 DEBUG : Putio root 'rclone-test-bapoxek0mebe': Waiting for checks to finish
2025/03/17 09:12:47 DEBUG : Putio root 'rclone-test-bapoxek0mebe': Waiting for transfers to finish
2025/03/17 09:12:48 DEBUG : sub dir/hello world: crc32 = 0c611827 OK
2025/03/17 09:12:48 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:48 DEBUG : Putio root 'rclone-test-bapoxek0mebe': Purge remote
--- PASS: TestServerSideCopyOverSelf (5.91s)
=== RUN   TestMoveOverSelf
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:50 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:50 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:51 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/03/17 09:12:51 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:12:51 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:12:51 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 17
2025/03/17 09:12:51 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 17
2025/03/17 09:12:52 DEBUG : sub dir/hello world: crc32 = 0c611827 OK
2025/03/17 09:12:52 INFO  : sub dir/hello world: Copied (replaced existing)
2025/03/17 09:12:52 INFO  : sub dir/hello world: Deleted
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestMoveOverSelf (3.90s)
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:54 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:12:54 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:12:54 DEBUG : Creating backend with remote "TestPutio:rclone-test-fokokon9depi"
    sync_test.go:671: Server side copy (if possible) Putio root 'rclone-test-rogadic8yale' -> Putio root 'rclone-test-fokokon9depi'
2025/03/17 09:12:55 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:12:55 DEBUG : Putio root 'rclone-test-fokokon9depi': Waiting for checks to finish
2025/03/17 09:12:55 DEBUG : Putio root 'rclone-test-fokokon9depi': Waiting for transfers to finish
2025/03/17 09:12:56 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:12:56 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:56 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 17
2025/03/17 09:12:56 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 17
2025/03/17 09:12:57 DEBUG : Putio root 'rclone-test-fokokon9depi': Using server-side directory move
2025/03/17 09:12:57 INFO  : Putio root 'rclone-test-fokokon9depi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/03/17 09:12:57 DEBUG : Putio root 'rclone-test-fokokon9depi': Waiting for checks to finish
2025/03/17 09:12:57 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/03/17 09:12:57 DEBUG : Putio root 'rclone-test-fokokon9depi': Waiting for transfers to finish
2025/03/17 09:12:57 INFO  : sub dir/hello world: Deleted
2025/03/17 09:12:57 INFO  : sub dir/hello world: Moved (server-side)
2025/03/17 09:12:58 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 24
2025/03/17 09:12:58 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 24
2025/03/17 09:12:58 DEBUG : testing file moves
2025/03/17 09:12:59 DEBUG : Putio root 'rclone-test-fokokon9depi': Waiting for checks to finish
2025/03/17 09:12:59 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2025/03/17 09:12:59 DEBUG : Putio root 'rclone-test-fokokon9depi': Waiting for transfers to finish
2025/03/17 09:12:59 INFO  : sub dir/hello world: Deleted
2025/03/17 09:12:59 INFO  : sub dir/hello world: Moved (server-side)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:12:59 DEBUG : Putio root 'rclone-test-fokokon9depi': Purge remote
--- PASS: TestServerSideMoveOverSelf (8.67s)
=== RUN   TestCopyAfterDelete
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:02 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:13:02 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:13:03 ERROR : : error listing: directory not found
2025/03/17 09:13:03 INFO  : Local file system at /tmp/rclone2273536011: Making directory
2025/03/17 09:13:03 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:03 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestCopyAfterDelete (1.84s)
=== RUN   TestCopyRedownload
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:04 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:13:04 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:13:05 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2025/03/17 09:13:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:13:05 DEBUG : Local file system at /tmp/rclone2273536011: Waiting for checks to finish
2025/03/17 09:13:05 DEBUG : Local file system at /tmp/rclone2273536011: Waiting for transfers to finish
2025/03/17 09:13:05 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:13:05 DEBUG : sub dir/hello world.1e3901b6.partial: renamed to: sub dir/hello world
2025/03/17 09:13:05 INFO  : sub dir/hello world: Copied (new)
2025/03/17 09:13:05 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestCopyRedownload (2.45s)
=== RUN   TestSyncBasedOnCheckSum
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:06 DEBUG : check sum: Need to transfer - File not found at Destination
2025/03/17 09:13:06 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:06 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:07 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:13:07 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:13:07 DEBUG : check sum: crc32 = 97ddb3f8 OK
2025/03/17 09:13:07 INFO  : check sum: Copied (new)
2025/03/17 09:13:07 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:07 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:07 DEBUG : check sum: crc32 = 97ddb3f8 OK
2025/03/17 09:13:07 DEBUG : check sum: Size and crc32 of src and dst objects identical
2025/03/17 09:13:07 DEBUG : check sum: Unchanged skipping
2025/03/17 09:13:07 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:07 DEBUG : Waiting for deletions to finish
2025/03/17 09:13:07 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncBasedOnCheckSum (2.11s)
=== RUN   TestSyncSizeOnly
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:08 DEBUG : sizeonly: Need to transfer - File not found at Destination
2025/03/17 09:13:08 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:08 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:09 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:13:09 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:13:09 DEBUG : sizeonly: crc32 = 9a941a19 OK
2025/03/17 09:13:09 INFO  : sizeonly: Copied (new)
2025/03/17 09:13:09 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:09 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:09 DEBUG : sizeonly: Sizes identical
2025/03/17 09:13:09 DEBUG : sizeonly: Unchanged skipping
2025/03/17 09:13:09 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:09 DEBUG : Waiting for deletions to finish
2025/03/17 09:13:09 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncSizeOnly (2.20s)
=== RUN   TestSyncIgnoreSize
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:11 DEBUG : ignore-size: Need to transfer - File not found at Destination
2025/03/17 09:13:11 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:11 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:11 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 8
2025/03/17 09:13:11 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 8
2025/03/17 09:13:11 DEBUG : ignore-size: crc32 = b4fa1177 OK
2025/03/17 09:13:11 INFO  : ignore-size: Copied (new)
2025/03/17 09:13:11 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:12 DEBUG : ignore-size: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:13:12 DEBUG : ignore-size: Unchanged skipping
2025/03/17 09:13:12 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:12 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:12 DEBUG : Waiting for deletions to finish
2025/03/17 09:13:12 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncIgnoreSize (1.48s)
=== RUN   TestSyncIgnoreTimes
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:12 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:13:12 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:13:13 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:13:13 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:13 DEBUG : existing: Unchanged skipping
2025/03/17 09:13:13 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:13 DEBUG : Waiting for deletions to finish
2025/03/17 09:13:13 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:13 DEBUG : existing: Transferring unconditionally as --ignore-times is in use
2025/03/17 09:13:13 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:13 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:14 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:13:14 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:13:14 DEBUG : existing: crc32 = 9a941a19 OK
2025/03/17 09:13:14 INFO  : existing: Copied (replaced existing)
2025/03/17 09:13:14 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncIgnoreTimes (2.74s)
=== RUN   TestSyncIgnoreExisting
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:15 DEBUG : existing: Need to transfer - File not found at Destination
2025/03/17 09:13:15 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:15 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:15 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:13:15 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:13:16 DEBUG : existing: crc32 = 9a941a19 OK
2025/03/17 09:13:16 INFO  : existing: Copied (new)
2025/03/17 09:13:16 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:16 DEBUG : existing: Destination exists, skipping
2025/03/17 09:13:16 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:16 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:16 DEBUG : Waiting for deletions to finish
2025/03/17 09:13:16 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncIgnoreExisting (1.40s)
=== RUN   TestSyncIgnoreErrors
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:17 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 21
2025/03/17 09:13:17 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 21
2025/03/17 09:13:18 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 5
2025/03/17 09:13:18 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:13:19 INFO  : d: Making directory
2025/03/17 09:13:20 DEBUG : a/potato2: Need to transfer - File not found at Destination
2025/03/17 09:13:20 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 60
2025/03/17 09:13:20 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:13:20 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:13:20 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:20 DEBUG : c/non empty space: Unchanged skipping
2025/03/17 09:13:20 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:22 DEBUG : a/potato2: crc32 = d423bfba OK
2025/03/17 09:13:22 INFO  : a/potato2: Copied (new)
2025/03/17 09:13:22 DEBUG : Waiting for deletions to finish
2025/03/17 09:13:23 INFO  : b/potato: Deleted
2025/03/17 09:13:23 INFO  : d: Removing directory
2025/03/17 09:13:23 INFO  : b: Removing directory
2025/03/17 09:13:23 DEBUG : Putio root 'rclone-test-rogadic8yale': deleted 2 directories
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncIgnoreErrors (8.61s)
=== RUN   TestSyncAfterChangingModtimeOnly
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:25 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:13:25 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:13:26 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:26 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
2025/03/17 09:13:26 DEBUG : empty space: crc32 = 97ddb3f8 OK
2025/03/17 09:13:26 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1)
2025/03/17 09:13:26 DEBUG : empty space: Unchanged skipping
2025/03/17 09:13:26 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:26 DEBUG : Waiting for deletions to finish
2025/03/17 09:13:26 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:26 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:26 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
2025/03/17 09:13:26 DEBUG : empty space: crc32 = 97ddb3f8 OK
2025/03/17 09:13:26 INFO  : empty space: Updated modification time in destination
2025/03/17 09:13:26 DEBUG : empty space: Unchanged skipping
2025/03/17 09:13:26 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:26 DEBUG : Waiting for deletions to finish
2025/03/17 09:13:26 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncAfterChangingModtimeOnly (1.56s)
=== RUN   TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:27 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:13:27 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:13:28 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:28 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
2025/03/17 09:13:28 DEBUG : empty space: crc32 = 97ddb3f8 OK
2025/03/17 09:13:28 DEBUG : empty space: Unchanged skipping
2025/03/17 09:13:28 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:28 DEBUG : Waiting for deletions to finish
2025/03/17 09:13:28 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (1.60s)
=== RUN   TestSyncDoesntUpdateModtime
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:28 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:13:28 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:13:29 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:29 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
2025/03/17 09:13:29 DEBUG : foo: crc32 = 8c736521 (Local file system at /tmp/rclone2273536011)
2025/03/17 09:13:29 DEBUG : foo: crc32 = 76ff8caa (Putio root 'rclone-test-rogadic8yale')
2025/03/17 09:13:29 DEBUG : foo: crc32 differ
2025/03/17 09:13:29 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:29 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:13:29 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:13:30 DEBUG : foo: crc32 = 8c736521 OK
2025/03/17 09:13:30 INFO  : foo: Copied (replaced existing)
2025/03/17 09:13:30 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncDoesntUpdateModtime (2.31s)
=== RUN   TestSyncAfterAddingAFile
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:31 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:13:31 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:13:32 DEBUG : potato: Need to transfer - File not found at Destination
2025/03/17 09:13:32 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:13:32 DEBUG : empty space: Unchanged skipping
2025/03/17 09:13:32 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:32 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:32 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 60
2025/03/17 09:13:32 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:13:32 DEBUG : potato: crc32 = d423bfba OK
2025/03/17 09:13:32 INFO  : potato: Copied (new)
2025/03/17 09:13:32 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncAfterAddingAFile (2.68s)
=== RUN   TestSyncAfterChangingFilesSizeOnly
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:33 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 60
2025/03/17 09:13:33 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:13:34 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:34 DEBUG : potato: Sizes differ (src 21 vs dst 60)
2025/03/17 09:13:34 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:35 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 21
2025/03/17 09:13:35 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 21
2025/03/17 09:13:36 DEBUG : potato: crc32 = f4498cc3 OK
2025/03/17 09:13:36 INFO  : potato: Copied (replaced existing)
2025/03/17 09:13:36 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncAfterChangingFilesSizeOnly (3.37s)
=== RUN   TestSyncAfterChangingContentsOnly
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:37 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 21
2025/03/17 09:13:37 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 21
2025/03/17 09:13:37 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:37 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC
2025/03/17 09:13:37 DEBUG : potato: crc32 = 1b366e1c (Local file system at /tmp/rclone2273536011)
2025/03/17 09:13:37 DEBUG : potato: crc32 = f4498cc3 (Putio root 'rclone-test-rogadic8yale')
2025/03/17 09:13:37 DEBUG : potato: crc32 differ
2025/03/17 09:13:37 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:38 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 21
2025/03/17 09:13:38 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 21
2025/03/17 09:13:38 DEBUG : potato: crc32 = 1b366e1c OK
2025/03/17 09:13:38 INFO  : potato: Copied (replaced existing)
2025/03/17 09:13:38 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncAfterChangingContentsOnly (2.75s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileDryRun
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:39 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 21
2025/03/17 09:13:39 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 21
2025/03/17 09:13:40 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:13:40 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:13:40 DEBUG : potato2: Need to transfer - File not found at Destination
2025/03/17 09:13:40 NOTICE: potato2: Skipped copy as --dry-run is set (size 60)
2025/03/17 09:13:40 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:13:40 DEBUG : empty space: Unchanged skipping
2025/03/17 09:13:40 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:40 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:40 DEBUG : Waiting for deletions to finish
2025/03/17 09:13:40 NOTICE: potato: Skipped delete as --dry-run is set (size 21)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (2.02s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFile
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:42 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 21
2025/03/17 09:13:42 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 21
2025/03/17 09:13:42 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:13:42 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:13:43 DEBUG : potato2: Need to transfer - File not found at Destination
2025/03/17 09:13:43 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:13:43 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:43 DEBUG : empty space: Unchanged skipping
2025/03/17 09:13:43 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:43 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 60
2025/03/17 09:13:43 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:13:44 DEBUG : potato2: crc32 = d423bfba OK
2025/03/17 09:13:44 INFO  : potato2: Copied (new)
2025/03/17 09:13:44 DEBUG : Waiting for deletions to finish
2025/03/17 09:13:44 INFO  : potato: Deleted
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFile (3.59s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:45 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 21
2025/03/17 09:13:45 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 21
2025/03/17 09:13:46 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 5
2025/03/17 09:13:46 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:13:47 INFO  : d: Making directory
2025/03/17 09:13:47 INFO  : d/e: Making directory
2025/03/17 09:13:48 DEBUG : a/potato2: Need to transfer - File not found at Destination
2025/03/17 09:13:48 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:13:48 DEBUG : c/non empty space: Unchanged skipping
2025/03/17 09:13:48 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 60
2025/03/17 09:13:48 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:13:49 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:49 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:49 DEBUG : a/potato2: crc32 = d423bfba OK
2025/03/17 09:13:49 INFO  : a/potato2: Copied (new)
2025/03/17 09:13:49 DEBUG : Waiting for deletions to finish
2025/03/17 09:13:49 INFO  : b/potato: Deleted
2025/03/17 09:13:49 INFO  : d/e: Removing directory
2025/03/17 09:13:49 INFO  : d: Removing directory
2025/03/17 09:13:49 INFO  : b: Removing directory
2025/03/17 09:13:49 DEBUG : Putio root 'rclone-test-rogadic8yale': deleted 3 directories
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (6.90s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:13:54 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 21
2025/03/17 09:13:54 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 21
2025/03/17 09:13:54 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 5
2025/03/17 09:13:54 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:13:56 INFO  : d: Making directory
2025/03/17 09:13:56 DEBUG : a/potato2: Need to transfer - File not found at Destination
2025/03/17 09:13:56 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:13:56 DEBUG : c/non empty space: Unchanged skipping
2025/03/17 09:13:56 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:13:56 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:13:57 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 60
2025/03/17 09:13:57 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:13:57 DEBUG : a/potato2: crc32 = d423bfba OK
2025/03/17 09:13:57 INFO  : a/potato2: Copied (new)
2025/03/17 09:13:57 ERROR : Putio root 'rclone-test-rogadic8yale': not deleting files as there were IO errors
2025/03/17 09:13:57 ERROR : Putio root 'rclone-test-rogadic8yale': not deleting directories as there were IO errors
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (7.78s)
=== RUN   TestSyncDeleteAfter
--- PASS: TestSyncDeleteAfter (0.00s)
=== RUN   TestSyncDeleteDuring
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:14:00 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 21
2025/03/17 09:14:00 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 21
2025/03/17 09:14:00 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:14:00 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:14:01 DEBUG : Waiting for deletions to finish
2025/03/17 09:14:01 DEBUG : potato2: Need to transfer - File not found at Destination
2025/03/17 09:14:01 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:14:01 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:14:01 DEBUG : empty space: Unchanged skipping
2025/03/17 09:14:01 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:14:01 INFO  : potato: Deleted
2025/03/17 09:14:01 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 60
2025/03/17 09:14:01 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:14:02 DEBUG : potato2: crc32 = d423bfba OK
2025/03/17 09:14:02 INFO  : potato2: Copied (new)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncDeleteDuring (3.07s)
=== RUN   TestSyncDeleteBefore
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:14:03 DEBUG : pacer: low level retry 1/10 (error unexpected status code (429) response while doing POST to https://upload.put.io/files/)
2025/03/17 09:14:03 DEBUG : pacer: Rate limited, increasing sleep to 57.89771814s
2025/03/17 09:14:03 DEBUG : pacer: low level retry 2/10 (error unexpected status code (429) response while doing POST to https://upload.put.io/files/)
2025/03/17 09:14:03 DEBUG : pacer: Rate limited, increasing sleep to 57.774847027s
2025/03/17 09:15:01 DEBUG : pacer: Reducing sleep to 28.887423513s
2025/03/17 09:15:01 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 21
2025/03/17 09:15:58 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 21
2025/03/17 09:15:59 DEBUG : pacer: Reducing sleep to 14.443711756s
2025/03/17 09:16:27 DEBUG : pacer: Reducing sleep to 7.221855878s
2025/03/17 09:16:42 DEBUG : pacer: Reducing sleep to 3.610927939s
2025/03/17 09:16:49 DEBUG : pacer: Reducing sleep to 1.805463969s
2025/03/17 09:16:49 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:16:52 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:16:53 DEBUG : pacer: Reducing sleep to 902.731984ms
2025/03/17 09:16:54 DEBUG : pacer: Reducing sleep to 451.365992ms
2025/03/17 09:16:55 DEBUG : pacer: Reducing sleep to 225.682996ms
2025/03/17 09:16:55 DEBUG : Waiting for deletions to finish
2025/03/17 09:16:56 DEBUG : pacer: Reducing sleep to 112.841498ms
2025/03/17 09:16:56 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:16:56 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:16:56 DEBUG : pacer: Reducing sleep to 56.420749ms
2025/03/17 09:16:56 INFO  : potato: Deleted
2025/03/17 09:16:56 DEBUG : pacer: Reducing sleep to 28.210374ms
2025/03/17 09:16:56 DEBUG : potato2: Need to transfer - File not found at Destination
2025/03/17 09:16:56 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:16:56 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:16:56 DEBUG : empty space: Unchanged skipping
2025/03/17 09:16:56 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:16:57 DEBUG : pacer: Reducing sleep to 14.105187ms
2025/03/17 09:16:57 DEBUG : pacer: Reducing sleep to 10ms
2025/03/17 09:16:57 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 60
2025/03/17 09:16:57 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:16:57 DEBUG : potato2: crc32 = d423bfba OK
2025/03/17 09:16:57 INFO  : potato2: Copied (new)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncDeleteBefore (175.76s)
=== RUN   TestCopyDeleteBefore
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:16:58 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 21
2025/03/17 09:16:58 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 21
2025/03/17 09:16:59 DEBUG : potato2: Need to transfer - File not found at Destination
2025/03/17 09:16:59 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:16:59 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:00 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 19
2025/03/17 09:17:00 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 19
2025/03/17 09:17:00 DEBUG : potato2: crc32 = 9f4e1e48 OK
2025/03/17 09:17:00 INFO  : potato2: Copied (new)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestCopyDeleteBefore (2.63s)
=== RUN   TestSyncWithExclude
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:01 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 60
2025/03/17 09:17:01 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:17:03 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:17:03 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:17:04 DEBUG : enormous: Excluded (Size Filter)
2025/03/17 09:17:04 DEBUG : enormous: Excluded
2025/03/17 09:17:04 DEBUG : potato2: Excluded (Size Filter)
2025/03/17 09:17:04 DEBUG : potato2: Excluded
2025/03/17 09:17:04 DEBUG : potato2: Excluded (Size Filter)
2025/03/17 09:17:04 DEBUG : potato2: Excluded
2025/03/17 09:17:04 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:17:04 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:17:04 DEBUG : empty space: Unchanged skipping
2025/03/17 09:17:04 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:04 DEBUG : Waiting for deletions to finish
2025/03/17 09:17:04 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:04 DEBUG : enormous: Excluded (Size Filter)
2025/03/17 09:17:04 DEBUG : enormous: Excluded
2025/03/17 09:17:04 DEBUG : potato2: Excluded (Size Filter)
2025/03/17 09:17:04 DEBUG : potato2: Excluded
2025/03/17 09:17:04 DEBUG : potato2: Excluded (Size Filter)
2025/03/17 09:17:04 DEBUG : potato2: Excluded
2025/03/17 09:17:04 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s)
2025/03/17 09:17:04 DEBUG : empty space: Unchanged skipping
2025/03/17 09:17:04 DEBUG : Local file system at /tmp/rclone2273536011: Waiting for checks to finish
2025/03/17 09:17:04 DEBUG : Local file system at /tmp/rclone2273536011: Waiting for transfers to finish
2025/03/17 09:17:04 DEBUG : Waiting for deletions to finish
2025/03/17 09:17:04 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncWithExclude (5.01s)
=== RUN   TestSyncWithExcludeAndDeleteExcluded
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:06 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 60
2025/03/17 09:17:06 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:17:07 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:17:07 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:17:08 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 100
2025/03/17 09:17:08 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 100
2025/03/17 09:17:09 DEBUG : enormous: Excluded (Size Filter)
2025/03/17 09:17:09 DEBUG : enormous: Excluded
2025/03/17 09:17:09 DEBUG : potato2: Excluded (Size Filter)
2025/03/17 09:17:09 DEBUG : potato2: Excluded
2025/03/17 09:17:09 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:17:09 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:17:09 DEBUG : empty space: Unchanged skipping
2025/03/17 09:17:09 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:09 DEBUG : Waiting for deletions to finish
2025/03/17 09:17:09 INFO  : enormous: Deleted
2025/03/17 09:17:10 INFO  : potato2: Deleted
2025/03/17 09:17:10 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:11 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s)
2025/03/17 09:17:11 DEBUG : Local file system at /tmp/rclone2273536011: Waiting for checks to finish
2025/03/17 09:17:11 DEBUG : empty space: Unchanged skipping
2025/03/17 09:17:11 DEBUG : Local file system at /tmp/rclone2273536011: Waiting for transfers to finish
2025/03/17 09:17:11 DEBUG : Waiting for deletions to finish
2025/03/17 09:17:11 INFO  : enormous: Deleted
2025/03/17 09:17:11 INFO  : potato2: Deleted
2025/03/17 09:17:11 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncWithExcludeAndDeleteExcluded (5.54s)
=== RUN   TestSyncWithUpdateOlder
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:12 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:17:12 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:17:12 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:17:12 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:17:13 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 5
2025/03/17 09:17:13 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:17:15 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 8
2025/03/17 09:17:15 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 8
2025/03/17 09:17:15 DEBUG : five: Need to transfer - File not found at Destination
2025/03/17 09:17:15 DEBUG : four: Sizes differ (src 4 vs dst 8)
2025/03/17 09:17:15 DEBUG : one: Destination is newer than source, skipping
2025/03/17 09:17:15 DEBUG : three: Sizes identical
2025/03/17 09:17:15 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:17:15 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring
2025/03/17 09:17:15 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping
2025/03/17 09:17:15 DEBUG : two: crc32 = 11ca8a66 (Local file system at /tmp/rclone2273536011)
2025/03/17 09:17:15 DEBUG : two: crc32 = 876dc8ec (Putio root 'rclone-test-rogadic8yale')
2025/03/17 09:17:15 DEBUG : two: crc32 differ
2025/03/17 09:17:15 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:16 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 4
2025/03/17 09:17:16 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 4
2025/03/17 09:17:16 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 4
2025/03/17 09:17:16 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 4
2025/03/17 09:17:16 DEBUG : five: crc32 = 3cb2cccb OK
2025/03/17 09:17:16 INFO  : five: Copied (new)
2025/03/17 09:17:16 DEBUG : four: crc32 = 90c1667d OK
2025/03/17 09:17:16 INFO  : four: Copied (replaced existing)
2025/03/17 09:17:17 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:17:17 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:17:17 DEBUG : two: crc32 = 11ca8a66 OK
2025/03/17 09:17:17 INFO  : two: Copied (replaced existing)
2025/03/17 09:17:17 DEBUG : Waiting for deletions to finish
2025/03/17 09:17:17 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:17:17 DEBUG : one: Destination is newer than source, skipping
2025/03/17 09:17:17 DEBUG : two: crc32 = 11ca8a66 OK
2025/03/17 09:17:17 DEBUG : two: Size and crc32 of src and dst objects identical
2025/03/17 09:17:17 DEBUG : four: crc32 = 90c1667d OK
2025/03/17 09:17:17 DEBUG : four: Size and crc32 of src and dst objects identical
2025/03/17 09:17:17 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping
2025/03/17 09:17:17 DEBUG : five: crc32 = 3cb2cccb OK
2025/03/17 09:17:17 DEBUG : five: Size and crc32 of src and dst objects identical
2025/03/17 09:17:17 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping
2025/03/17 09:17:17 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping
2025/03/17 09:17:17 DEBUG : three: crc32 = 46c5d8f5 (Local file system at /tmp/rclone2273536011)
2025/03/17 09:17:17 DEBUG : three: crc32 = b1911a45 (Putio root 'rclone-test-rogadic8yale')
2025/03/17 09:17:17 DEBUG : three: crc32 differ
2025/03/17 09:17:17 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring
2025/03/17 09:17:17 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:18 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 5
2025/03/17 09:17:18 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:17:19 DEBUG : three: crc32 = 46c5d8f5 OK
2025/03/17 09:17:19 INFO  : three: Copied (replaced existing)
2025/03/17 09:17:19 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithUpdateOlder (8.04s)
=== RUN   TestSyncWithMaxDuration
=== RUN   TestSyncWithMaxDuration/Hard
    sync_test.go:1466: Skipping test on non local remote
=== RUN   TestSyncWithMaxDuration/Soft
    sync_test.go:1466: Skipping test on non local remote
--- PASS: TestSyncWithMaxDuration (0.00s)
    --- SKIP: TestSyncWithMaxDuration/Hard (0.00s)
    --- SKIP: TestSyncWithMaxDuration/Soft (0.00s)
=== RUN   TestSyncWithTrackRenames
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    sync_test.go:1538: Can track renames: true
2025/03/17 09:17:20 INFO  : Putio root 'rclone-test-rogadic8yale': Making map for --track-renames
2025/03/17 09:17:20 INFO  : Putio root 'rclone-test-rogadic8yale': Finished making map for --track-renames
2025/03/17 09:17:20 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:17:20 DEBUG : potato: Need to transfer - No matching file found at Destination
2025/03/17 09:17:20 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for renames to finish
2025/03/17 09:17:20 DEBUG : yam: Need to transfer - No matching file found at Destination
2025/03/17 09:17:20 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:20 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 14
2025/03/17 09:17:20 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 14
2025/03/17 09:17:20 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:17:20 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:17:20 DEBUG : potato: crc32 = 403f91c8 OK
2025/03/17 09:17:20 INFO  : potato: Copied (new)
2025/03/17 09:17:21 DEBUG : yam: crc32 = ae1fefed OK
2025/03/17 09:17:21 INFO  : yam: Copied (new)
2025/03/17 09:17:21 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:21 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:17:21 INFO  : Putio root 'rclone-test-rogadic8yale': Making map for --track-renames
2025/03/17 09:17:21 INFO  : Putio root 'rclone-test-rogadic8yale': Finished making map for --track-renames
2025/03/17 09:17:21 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:17:21 DEBUG : potato: Unchanged skipping
2025/03/17 09:17:21 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for renames to finish
2025/03/17 09:17:21 INFO  : yam: Moved (server-side) to: yaml
2025/03/17 09:17:21 INFO  : yaml: Renamed from "yam"
2025/03/17 09:17:21 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:21 DEBUG : Waiting for deletions to finish
2025/03/17 09:17:21 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncWithTrackRenames (2.36s)
=== RUN   TestParseRenamesStrategyModtime
--- PASS: TestParseRenamesStrategyModtime (0.00s)
=== RUN   TestRenamesStrategyModtime
--- PASS: TestRenamesStrategyModtime (0.00s)
=== RUN   TestSyncWithTrackRenamesStrategyModtime
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    sync_test.go:1610: Can track renames: true
2025/03/17 09:17:22 INFO  : Putio root 'rclone-test-rogadic8yale': Making map for --track-renames
2025/03/17 09:17:22 INFO  : Putio root 'rclone-test-rogadic8yale': Finished making map for --track-renames
2025/03/17 09:17:22 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:17:22 DEBUG : potato: Need to transfer - No matching file found at Destination
2025/03/17 09:17:22 DEBUG : yam: Need to transfer - No matching file found at Destination
2025/03/17 09:17:22 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for renames to finish
2025/03/17 09:17:22 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:22 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:17:22 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:17:22 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 14
2025/03/17 09:17:22 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 14
2025/03/17 09:17:23 DEBUG : yam: crc32 = ae1fefed OK
2025/03/17 09:17:23 INFO  : yam: Copied (new)
2025/03/17 09:17:23 DEBUG : potato: crc32 = 403f91c8 OK
2025/03/17 09:17:23 INFO  : potato: Copied (new)
2025/03/17 09:17:23 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:23 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:17:23 INFO  : Putio root 'rclone-test-rogadic8yale': Making map for --track-renames
2025/03/17 09:17:23 INFO  : Putio root 'rclone-test-rogadic8yale': Finished making map for --track-renames
2025/03/17 09:17:23 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:17:23 DEBUG : potato: Unchanged skipping
2025/03/17 09:17:23 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for renames to finish
2025/03/17 09:17:23 INFO  : yam: Moved (server-side) to: yaml
2025/03/17 09:17:23 INFO  : yaml: Renamed from "yam"
2025/03/17 09:17:23 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:23 DEBUG : Waiting for deletions to finish
2025/03/17 09:17:23 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncWithTrackRenamesStrategyModtime (2.59s)
=== RUN   TestSyncWithTrackRenamesStrategyLeaf
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    sync_test.go:1649: Can track renames: true
2025/03/17 09:17:24 INFO  : Putio root 'rclone-test-rogadic8yale': Making map for --track-renames
2025/03/17 09:17:24 INFO  : Putio root 'rclone-test-rogadic8yale': Finished making map for --track-renames
2025/03/17 09:17:24 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:17:24 DEBUG : potato: Need to transfer - No matching file found at Destination
2025/03/17 09:17:24 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for renames to finish
2025/03/17 09:17:24 DEBUG : sub/yam: Need to transfer - No matching file found at Destination
2025/03/17 09:17:24 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:25 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:17:25 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:17:25 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 14
2025/03/17 09:17:25 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 14
2025/03/17 09:17:25 DEBUG : sub/yam: crc32 = ae1fefed OK
2025/03/17 09:17:25 INFO  : sub/yam: Copied (new)
2025/03/17 09:17:25 DEBUG : potato: crc32 = 403f91c8 OK
2025/03/17 09:17:25 INFO  : potato: Copied (new)
2025/03/17 09:17:25 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:26 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:17:26 DEBUG : potato: Unchanged skipping
2025/03/17 09:17:26 INFO  : Putio root 'rclone-test-rogadic8yale': Making map for --track-renames
2025/03/17 09:17:26 INFO  : Putio root 'rclone-test-rogadic8yale': Finished making map for --track-renames
2025/03/17 09:17:26 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:17:26 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for renames to finish
2025/03/17 09:17:26 INFO  : sub/yam: Moved (server-side) to: yam
2025/03/17 09:17:26 INFO  : yam: Renamed from "sub/yam"
2025/03/17 09:17:26 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:26 DEBUG : Waiting for deletions to finish
2025/03/17 09:17:26 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncWithTrackRenamesStrategyLeaf (2.85s)
=== RUN   TestServerSideMoveLocal
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:27 DEBUG : Creating backend with remote "/tmp/rclone2273536011/dir1"
2025/03/17 09:17:27 DEBUG : Config file has changed externally - reloading
2025/03/17 09:17:27 DEBUG : Creating backend with remote "/tmp/rclone2273536011/dir2"
2025/03/17 09:17:27 DEBUG : Local file system at /tmp/rclone2273536011/dir2: Using server-side directory move
2025/03/17 09:17:27 INFO  : Local file system at /tmp/rclone2273536011/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/03/17 09:17:27 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/17 09:17:27 INFO  : file1.txt: Moved (server-side)
2025/03/17 09:17:27 DEBUG : Local file system at /tmp/rclone2273536011/dir2: Waiting for checks to finish
2025/03/17 09:17:27 DEBUG : Local file system at /tmp/rclone2273536011/dir2: Waiting for transfers to finish
--- PASS: TestServerSideMoveLocal (0.13s)
=== RUN   TestMoveWithDeleteEmptySrcDirs
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:17:27 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2025/03/17 09:17:27 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:17:27 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:28 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:17:28 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:17:28 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:17:28 INFO  : sub dir/hello world: Copied (new)
2025/03/17 09:17:28 INFO  : sub dir/hello world: Deleted
2025/03/17 09:17:28 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:17:28 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:17:29 DEBUG : nested/sub dir/file: crc32 = aac9c2e9 OK
2025/03/17 09:17:29 INFO  : nested/sub dir/file: Copied (new)
2025/03/17 09:17:29 INFO  : nested/sub dir/file: Deleted
2025/03/17 09:17:29 INFO  : sub dir: Removing directory
2025/03/17 09:17:29 INFO  : nested/sub dir: Removing directory
2025/03/17 09:17:29 INFO  : nested: Removing directory
2025/03/17 09:17:29 DEBUG : Local file system at /tmp/rclone2273536011: deleted 3 directories
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestMoveWithDeleteEmptySrcDirs (3.13s)
=== RUN   TestMoveWithoutDeleteEmptySrcDirs
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/17 09:17:31 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2025/03/17 09:17:31 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:17:31 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:31 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 11
2025/03/17 09:17:31 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 11
2025/03/17 09:17:31 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:17:31 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:17:31 DEBUG : sub dir/hello world: crc32 = 0d4a1185 OK
2025/03/17 09:17:31 INFO  : sub dir/hello world: Copied (new)
2025/03/17 09:17:31 INFO  : sub dir/hello world: Deleted
2025/03/17 09:17:32 DEBUG : nested/sub dir/file: crc32 = aac9c2e9 OK
2025/03/17 09:17:32 INFO  : nested/sub dir/file: Copied (new)
2025/03/17 09:17:32 INFO  : nested/sub dir/file: Deleted
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestMoveWithoutDeleteEmptySrcDirs (5.62s)
=== RUN   TestMoveWithIgnoreExisting
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:36 DEBUG : existing: Need to transfer - File not found at Destination
2025/03/17 09:17:36 DEBUG : existing-b: Need to transfer - File not found at Destination
2025/03/17 09:17:36 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:17:36 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:36 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:17:36 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:17:37 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:17:37 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:17:37 DEBUG : existing: crc32 = 9a941a19 OK
2025/03/17 09:17:37 INFO  : existing: Copied (new)
2025/03/17 09:17:37 INFO  : existing: Deleted
2025/03/17 09:17:37 DEBUG : existing-b: crc32 = 2c14401a OK
2025/03/17 09:17:37 INFO  : existing-b: Copied (new)
2025/03/17 09:17:37 INFO  : existing-b: Deleted
2025/03/17 09:17:37 DEBUG : existing: Destination exists, skipping
2025/03/17 09:17:37 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set
2025/03/17 09:17:37 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:17:37 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:17:37 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestMoveWithIgnoreExisting (1.54s)
=== RUN   TestServerSideMove
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:38 DEBUG : Creating backend with remote "TestPutio:rclone-test-geqezuw2vopu"
2025/03/17 09:17:38 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 60
2025/03/17 09:17:38 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:17:39 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:17:39 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:17:39 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 68
2025/03/17 09:17:39 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 68
    sync_test.go:1705: Server side move (if possible) Putio root 'rclone-test-rogadic8yale' -> Putio root 'rclone-test-geqezuw2vopu'
2025/03/17 09:17:40 DEBUG : Putio root 'rclone-test-geqezuw2vopu': chunkStart: 0, reqSize: 1
2025/03/17 09:17:40 DEBUG : Putio root 'rclone-test-geqezuw2vopu': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:17:41 DEBUG : Putio root 'rclone-test-geqezuw2vopu': chunkStart: 0, reqSize: 60
2025/03/17 09:17:41 DEBUG : Putio root 'rclone-test-geqezuw2vopu': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:17:41 DEBUG : Putio root 'rclone-test-geqezuw2vopu': Using server-side directory move
2025/03/17 09:17:41 INFO  : Putio root 'rclone-test-geqezuw2vopu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/03/17 09:17:42 DEBUG : potato2: Need to transfer - File not found at Destination
2025/03/17 09:17:42 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s)
2025/03/17 09:17:42 DEBUG : empty space: Unchanged skipping
2025/03/17 09:17:42 DEBUG : Putio root 'rclone-test-geqezuw2vopu': Waiting for checks to finish
2025/03/17 09:17:42 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2025/03/17 09:17:42 INFO  : empty space: Deleted
2025/03/17 09:17:42 DEBUG : Putio root 'rclone-test-geqezuw2vopu': Waiting for transfers to finish
2025/03/17 09:17:42 INFO  : potato2: Moved (server-side)
2025/03/17 09:17:43 INFO  : potato3: Deleted
2025/03/17 09:17:43 INFO  : potato3: Moved (server-side)
2025/03/17 09:17:43 DEBUG : Creating backend with remote "TestPutio:rclone-test-waqedeq0sisa"
2025/03/17 09:17:43 DEBUG : Putio root 'rclone-test-waqedeq0sisa': Using server-side directory move
2025/03/17 09:17:43 INFO  : Putio root 'rclone-test-waqedeq0sisa': Server side directory move succeeded
2025/03/17 09:17:44 ERROR : : error listing: directory not found
2025/03/17 09:17:44 DEBUG : Putio root 'rclone-test-waqedeq0sisa': Purge remote
2025/03/17 09:17:44 DEBUG : Putio root 'rclone-test-geqezuw2vopu': Purge remote
2025/03/17 09:17:44 NOTICE: purge failed: Rmdir: directory not found
--- PASS: TestServerSideMove (6.37s)
=== RUN   TestServerSideMoveWithFilter
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:44 DEBUG : Creating backend with remote "TestPutio:rclone-test-zobovaj4ropa"
2025/03/17 09:17:44 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 60
2025/03/17 09:17:44 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:17:45 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:17:45 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:17:46 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 68
2025/03/17 09:17:46 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 68
    sync_test.go:1705: Server side move (if possible) Putio root 'rclone-test-rogadic8yale' -> Putio root 'rclone-test-zobovaj4ropa'
2025/03/17 09:17:47 DEBUG : Putio root 'rclone-test-zobovaj4ropa': chunkStart: 0, reqSize: 1
2025/03/17 09:17:47 DEBUG : Putio root 'rclone-test-zobovaj4ropa': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:17:48 DEBUG : Putio root 'rclone-test-zobovaj4ropa': chunkStart: 0, reqSize: 60
2025/03/17 09:17:48 DEBUG : Putio root 'rclone-test-zobovaj4ropa': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:17:51 DEBUG : empty space: Excluded (Size Filter)
2025/03/17 09:17:51 DEBUG : empty space: Excluded
2025/03/17 09:17:51 DEBUG : empty space: Excluded (Size Filter)
2025/03/17 09:17:51 DEBUG : empty space: Excluded
2025/03/17 09:17:51 DEBUG : potato2: Need to transfer - File not found at Destination
2025/03/17 09:17:51 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2025/03/17 09:17:51 DEBUG : Putio root 'rclone-test-zobovaj4ropa': Waiting for checks to finish
2025/03/17 09:17:51 DEBUG : Putio root 'rclone-test-zobovaj4ropa': Waiting for transfers to finish
2025/03/17 09:17:51 INFO  : potato3: Deleted
2025/03/17 09:17:52 INFO  : potato3: Moved (server-side)
2025/03/17 09:17:52 INFO  : potato2: Moved (server-side)
2025/03/17 09:17:52 DEBUG : Creating backend with remote "TestPutio:rclone-test-poxiqep5soyi"
2025/03/17 09:17:52 DEBUG : empty space: Excluded (Size Filter)
2025/03/17 09:17:52 DEBUG : empty space: Excluded
2025/03/17 09:17:52 DEBUG : potato2: Need to transfer - File not found at Destination
2025/03/17 09:17:52 DEBUG : potato3: Need to transfer - File not found at Destination
2025/03/17 09:17:52 DEBUG : Putio root 'rclone-test-poxiqep5soyi': Waiting for checks to finish
2025/03/17 09:17:52 DEBUG : Putio root 'rclone-test-poxiqep5soyi': Waiting for transfers to finish
2025/03/17 09:17:53 INFO  : potato2: Moved (server-side)
2025/03/17 09:17:53 INFO  : potato3: Moved (server-side)
2025/03/17 09:17:53 DEBUG : Putio root 'rclone-test-poxiqep5soyi': Purge remote
2025/03/17 09:17:54 DEBUG : Putio root 'rclone-test-zobovaj4ropa': Purge remote
--- PASS: TestServerSideMoveWithFilter (10.08s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:17:54 DEBUG : Creating backend with remote "TestPutio:rclone-test-zomesow6diqu"
2025/03/17 09:17:55 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 60
2025/03/17 09:17:55 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:17:55 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:17:55 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:17:57 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 68
2025/03/17 09:17:57 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 68
2025/03/17 09:17:57 INFO  : tomatoDir: Making directory
    sync_test.go:1705: Server side move (if possible) Putio root 'rclone-test-rogadic8yale' -> Putio root 'rclone-test-zomesow6diqu'
2025/03/17 09:17:58 DEBUG : Putio root 'rclone-test-zomesow6diqu': chunkStart: 0, reqSize: 1
2025/03/17 09:17:58 DEBUG : Putio root 'rclone-test-zomesow6diqu': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:17:59 DEBUG : Putio root 'rclone-test-zomesow6diqu': chunkStart: 0, reqSize: 60
2025/03/17 09:17:59 DEBUG : Putio root 'rclone-test-zomesow6diqu': Sending chunk. transferOffset: 0 length: 60
2025/03/17 09:17:59 DEBUG : Putio root 'rclone-test-zomesow6diqu': Using server-side directory move
2025/03/17 09:17:59 INFO  : Putio root 'rclone-test-zomesow6diqu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/03/17 09:17:59 DEBUG : potato2: Need to transfer - File not found at Destination
2025/03/17 09:17:59 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2025/03/17 09:17:59 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s)
2025/03/17 09:17:59 DEBUG : empty space: Unchanged skipping
2025/03/17 09:17:59 DEBUG : Putio root 'rclone-test-zomesow6diqu': Waiting for checks to finish
2025/03/17 09:17:59 INFO  : potato3: Deleted
2025/03/17 09:17:59 INFO  : potato2: Moved (server-side)
2025/03/17 09:18:00 INFO  : potato3: Moved (server-side)
2025/03/17 09:18:00 INFO  : empty space: Deleted
2025/03/17 09:18:00 DEBUG : Putio root 'rclone-test-zomesow6diqu': Waiting for transfers to finish
2025/03/17 09:18:00 INFO  : tomatoDir: Removing directory
2025/03/17 09:18:01 DEBUG : Putio root 'rclone-test-rogadic8yale': deleted 1 directories
2025/03/17 09:18:01 DEBUG : Creating backend with remote "TestPutio:rclone-test-sujavis6mida"
2025/03/17 09:18:01 DEBUG : Config file has changed externally - reloading
2025/03/17 09:18:01 INFO  : tomatoDir: Making directory
2025/03/17 09:18:01 DEBUG : Putio root 'rclone-test-sujavis6mida': Using server-side directory move
2025/03/17 09:18:02 INFO  : Putio root 'rclone-test-sujavis6mida': Server side directory move succeeded
2025/03/17 09:18:02 ERROR : : error listing: directory not found
2025/03/17 09:18:02 ERROR : : error listing: directory not found
2025/03/17 09:18:02 DEBUG : Putio root 'rclone-test-sujavis6mida': Purge remote
2025/03/17 09:18:04 DEBUG : Putio root 'rclone-test-zomesow6diqu': Purge remote
2025/03/17 09:18:04 NOTICE: purge failed: Rmdir: directory not found
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (9.66s)
=== RUN   TestServerSideMoveOverlap
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    sync_test.go:1910: Skipping test as remote supports DirMove
--- SKIP: TestServerSideMoveOverlap (0.12s)
=== RUN   TestSyncOverlap
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:18:04 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/rclone-sync-test"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncOverlap (0.92s)
=== RUN   TestSyncOverlapWithFilter
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:18:05 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/rclone-sync-test"
2025/03/17 09:18:05 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/rclone-sync-test-include/layer2"
2025/03/17 09:18:06 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/rclone-sync-test-ignore-file"
2025/03/17 09:18:08 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:18:08 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:18:09 DEBUG : rclone-sync-test: Excluded
2025/03/17 09:18:09 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/17 09:18:09 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/03/17 09:18:09 DEBUG : Putio root 'rclone-test-rogadic8yale/rclone-sync-test': Waiting for checks to finish
2025/03/17 09:18:09 DEBUG : Putio root 'rclone-test-rogadic8yale/rclone-sync-test': Waiting for transfers to finish
2025/03/17 09:18:09 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:09 INFO  : There was nothing to transfer
2025/03/17 09:18:09 DEBUG : rclone-sync-test: Excluded
2025/03/17 09:18:09 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/17 09:18:09 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/03/17 09:18:09 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:18:09 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:18:09 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:09 INFO  : rclone-sync-test-include: Removing directory
2025/03/17 09:18:09 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty
2025/03/17 09:18:09 DEBUG : Putio root 'rclone-test-rogadic8yale': failed to delete 1 directories
2025/03/17 09:18:09 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:18:10 DEBUG : rclone-sync-test: Excluded
2025/03/17 09:18:10 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/17 09:18:10 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/03/17 09:18:10 DEBUG : Putio root 'rclone-test-rogadic8yale/rclone-sync-test-include/layer2': Waiting for checks to finish
2025/03/17 09:18:10 DEBUG : Putio root 'rclone-test-rogadic8yale/rclone-sync-test-include/layer2': Waiting for transfers to finish
2025/03/17 09:18:10 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:10 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:18:10 DEBUG : rclone-sync-test: Excluded
2025/03/17 09:18:10 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/17 09:18:10 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/03/17 09:18:10 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:18:10 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:18:10 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:10 INFO  : rclone-sync-test-include: Removing directory
2025/03/17 09:18:10 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty
2025/03/17 09:18:10 DEBUG : Putio root 'rclone-test-rogadic8yale': failed to delete 1 directories
2025/03/17 09:18:10 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:18:10 DEBUG : : Excluded
2025/03/17 09:18:10 DEBUG : rclone-sync-test: Excluded
2025/03/17 09:18:10 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/17 09:18:11 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/03/17 09:18:11 DEBUG : Putio root 'rclone-test-rogadic8yale/rclone-sync-test-ignore-file': Waiting for checks to finish
2025/03/17 09:18:11 DEBUG : Putio root 'rclone-test-rogadic8yale/rclone-sync-test-ignore-file': Waiting for transfers to finish
2025/03/17 09:18:11 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:11 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncOverlapWithFilter (9.61s)
=== RUN   TestSyncCompareDest
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:18:14 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/dst"
2025/03/17 09:18:14 DEBUG : Config file has changed externally - reloading
2025/03/17 09:18:15 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/CompareDest"
2025/03/17 09:18:15 DEBUG : one: Need to transfer - File not found at Destination
2025/03/17 09:18:15 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:15 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:16 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 3
2025/03/17 09:18:16 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:18:16 DEBUG : one: crc32 = 7a6c86f1 OK
2025/03/17 09:18:16 INFO  : one: Copied (new)
2025/03/17 09:18:16 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:17 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/03/17 09:18:17 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:17 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:17 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 5
2025/03/17 09:18:17 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:18:17 DEBUG : one: crc32 = cb217308 OK
2025/03/17 09:18:17 INFO  : one: Copied (replaced existing)
2025/03/17 09:18:17 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:18 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:18:18 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:18:19 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 5
2025/03/17 09:18:19 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:18:21 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/03/17 09:18:21 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:21 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:18:21 DEBUG : one: Destination found in --compare-dest, skipping
2025/03/17 09:18:21 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:21 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:21 INFO  : There was nothing to transfer
2025/03/17 09:18:22 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:18:22 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:18:23 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:18:23 DEBUG : two: Destination found in --compare-dest, skipping
2025/03/17 09:18:23 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/03/17 09:18:23 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:23 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:18:23 DEBUG : one: Destination found in --compare-dest, skipping
2025/03/17 09:18:23 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:23 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:23 INFO  : There was nothing to transfer
2025/03/17 09:18:23 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:18:23 DEBUG : two: Destination found in --compare-dest, skipping
2025/03/17 09:18:23 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/03/17 09:18:23 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:23 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:18:23 DEBUG : one: Destination found in --compare-dest, skipping
2025/03/17 09:18:23 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:23 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:23 INFO  : There was nothing to transfer
2025/03/17 09:18:23 DEBUG : two: Modification times differ by -120h0m0s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59 +0000 UTC
2025/03/17 09:18:23 DEBUG : two: crc32 = 11ca8a66 OK
2025/03/17 09:18:23 DEBUG : two: Destination found in --compare-dest, skipping
2025/03/17 09:18:23 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/03/17 09:18:23 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:23 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:18:23 DEBUG : one: Destination found in --compare-dest, skipping
2025/03/17 09:18:23 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:23 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:23 INFO  : There was nothing to transfer
2025/03/17 09:18:24 DEBUG : two: Sizes differ (src 5 vs dst 3)
2025/03/17 09:18:24 DEBUG : two: Need to transfer - File not found at Destination
2025/03/17 09:18:24 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/03/17 09:18:24 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:24 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:18:24 DEBUG : one: Destination found in --compare-dest, skipping
2025/03/17 09:18:24 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:24 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 5
2025/03/17 09:18:24 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:18:25 DEBUG : two: crc32 = 8b9078ce OK
2025/03/17 09:18:25 INFO  : two: Copied (new)
2025/03/17 09:18:25 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCompareDest (11.75s)
=== RUN   TestSyncMultipleCompareDest
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:18:27 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:18:27 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:18:27 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 1
2025/03/17 09:18:27 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:18:28 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/dest"
2025/03/17 09:18:28 DEBUG : Config file has changed externally - reloading
2025/03/17 09:18:28 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/pre-dest1"
2025/03/17 09:18:28 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/pre-dest2"
2025/03/17 09:18:29 DEBUG : 1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:18:29 DEBUG : 1: Destination found in --compare-dest, skipping
2025/03/17 09:18:29 DEBUG : 2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:18:29 DEBUG : 2: Destination found in --compare-dest, skipping
2025/03/17 09:18:29 DEBUG : 3: Need to transfer - File not found at Destination
2025/03/17 09:18:29 DEBUG : Putio root 'rclone-test-rogadic8yale/dest': Waiting for checks to finish
2025/03/17 09:18:29 DEBUG : Putio root 'rclone-test-rogadic8yale/dest': Waiting for transfers to finish
2025/03/17 09:18:29 DEBUG : Putio root 'rclone-test-rogadic8yale/dest': chunkStart: 0, reqSize: 1
2025/03/17 09:18:29 DEBUG : Putio root 'rclone-test-rogadic8yale/dest': Sending chunk. transferOffset: 0 length: 1
2025/03/17 09:18:30 DEBUG : 3: crc32 = 6dd28e9b OK
2025/03/17 09:18:30 INFO  : 3: Copied (new)
2025/03/17 09:18:30 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncMultipleCompareDest (5.37s)
=== RUN   TestSyncCopyDest
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:18:32 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/dst"
2025/03/17 09:18:32 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/CopyDest"
2025/03/17 09:18:32 DEBUG : one: Need to transfer - File not found at Destination
2025/03/17 09:18:32 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:32 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:33 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 3
2025/03/17 09:18:33 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:18:33 DEBUG : one: crc32 = 7a6c86f1 OK
2025/03/17 09:18:33 INFO  : one: Copied (new)
2025/03/17 09:18:33 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:34 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:34 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/03/17 09:18:34 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:34 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 5
2025/03/17 09:18:34 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:18:35 DEBUG : one: crc32 = cb217308 OK
2025/03/17 09:18:35 INFO  : one: Copied (replaced existing)
2025/03/17 09:18:35 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:35 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:18:35 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:18:37 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 5
2025/03/17 09:18:37 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:18:38 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/BackupDir"
2025/03/17 09:18:38 DEBUG : Config file has changed externally - reloading
2025/03/17 09:18:38 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/03/17 09:18:38 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:39 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:18:39 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/03/17 09:18:39 INFO  : one: Moved (server-side)
2025/03/17 09:18:39 DEBUG : one: crc32 = cb217308 OK
2025/03/17 09:18:39 INFO  : one: Copied (server-side copy)
2025/03/17 09:18:39 DEBUG : one: Destination found in --copy-dest, using server-side copy
2025/03/17 09:18:39 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:39 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:40 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:18:40 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:18:41 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:18:41 DEBUG : two: crc32 = 11ca8a66 OK
2025/03/17 09:18:41 INFO  : two: Copied (server-side copy)
2025/03/17 09:18:41 DEBUG : two: Destination found in --copy-dest, using server-side copy
2025/03/17 09:18:41 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:18:41 DEBUG : one: Unchanged skipping
2025/03/17 09:18:41 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:41 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:41 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:42 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:18:42 DEBUG : one: Unchanged skipping
2025/03/17 09:18:42 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:18:42 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:42 DEBUG : two: Unchanged skipping
2025/03/17 09:18:42 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:42 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:42 INFO  : There was nothing to transfer
2025/03/17 09:18:42 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 5
2025/03/17 09:18:42 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:18:43 DEBUG : three: Sizes differ (src 7 vs dst 5)
2025/03/17 09:18:43 DEBUG : three: Destination not found in --copy-dest
2025/03/17 09:18:43 DEBUG : three: Need to transfer - File not found at Destination
2025/03/17 09:18:43 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:18:43 DEBUG : one: Unchanged skipping
2025/03/17 09:18:43 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/17 09:18:43 DEBUG : two: Unchanged skipping
2025/03/17 09:18:43 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:43 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:43 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 7
2025/03/17 09:18:43 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 7
2025/03/17 09:18:44 DEBUG : three: crc32 = 5499c6c0 OK
2025/03/17 09:18:44 INFO  : three: Copied (new)
2025/03/17 09:18:44 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCopyDest (16.23s)
=== RUN   TestSyncBackupDir
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:18:48 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:18:48 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:18:49 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:18:49 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:18:49 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 5
2025/03/17 09:18:49 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:18:50 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/dst"
2025/03/17 09:18:50 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/backup"
2025/03/17 09:18:51 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/03/17 09:18:51 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:18:51 DEBUG : two: Unchanged skipping
2025/03/17 09:18:51 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:51 INFO  : one: Moved (server-side)
2025/03/17 09:18:51 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:51 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 4
2025/03/17 09:18:51 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 4
2025/03/17 09:18:52 DEBUG : one: crc32 = 19193087 OK
2025/03/17 09:18:52 INFO  : one: Copied (new)
2025/03/17 09:18:52 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:52 INFO  : three.txt: Moved (server-side)
2025/03/17 09:18:52 INFO  : three.txt: Moved into backup dir
2025/03/17 09:18:52 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:18:52 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:18:53 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/03/17 09:18:53 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:18:53 DEBUG : two: Unchanged skipping
2025/03/17 09:18:53 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:18:54 INFO  : one: Deleted
2025/03/17 09:18:54 INFO  : one: Moved (server-side)
2025/03/17 09:18:54 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:18:55 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 5
2025/03/17 09:18:55 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:18:55 DEBUG : one: crc32 = 12389341 OK
2025/03/17 09:18:55 INFO  : one: Copied (new)
2025/03/17 09:18:55 DEBUG : Waiting for deletions to finish
2025/03/17 09:18:55 INFO  : three.txt: Deleted
2025/03/17 09:18:56 INFO  : three.txt: Moved (server-side)
2025/03/17 09:18:56 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDir (9.31s)
=== RUN   TestSyncBackupDirWithSuffix
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:18:58 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:18:58 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:18:59 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:18:59 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:18:59 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 5
2025/03/17 09:18:59 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:19:00 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/dst"
2025/03/17 09:19:00 DEBUG : Config file has changed externally - reloading
2025/03/17 09:19:00 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/backup"
2025/03/17 09:19:01 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/03/17 09:19:01 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:19:01 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:19:01 DEBUG : two: Unchanged skipping
2025/03/17 09:19:01 INFO  : one: Moved (server-side) to: one.bak
2025/03/17 09:19:01 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:19:01 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 4
2025/03/17 09:19:01 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 4
2025/03/17 09:19:02 DEBUG : one: crc32 = 19193087 OK
2025/03/17 09:19:02 INFO  : one: Copied (new)
2025/03/17 09:19:02 DEBUG : Waiting for deletions to finish
2025/03/17 09:19:02 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/03/17 09:19:02 INFO  : three.txt: Moved into backup dir
2025/03/17 09:19:02 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:19:02 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:19:03 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/03/17 09:19:03 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:19:03 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:19:03 DEBUG : two: Unchanged skipping
2025/03/17 09:19:03 INFO  : one.bak: Deleted
2025/03/17 09:19:04 INFO  : one: Moved (server-side) to: one.bak
2025/03/17 09:19:04 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:19:04 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 5
2025/03/17 09:19:04 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:19:04 DEBUG : one: crc32 = 12389341 OK
2025/03/17 09:19:04 INFO  : one: Copied (new)
2025/03/17 09:19:04 DEBUG : Waiting for deletions to finish
2025/03/17 09:19:05 INFO  : three.txt.bak: Deleted
2025/03/17 09:19:05 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/03/17 09:19:05 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffix (9.60s)
=== RUN   TestSyncBackupDirWithSuffixKeepExtension
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:19:08 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:19:08 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:19:08 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:19:08 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:19:09 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 5
2025/03/17 09:19:09 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:19:09 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/dst"
2025/03/17 09:19:10 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/backup"
2025/03/17 09:19:10 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/03/17 09:19:10 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:19:10 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:19:10 DEBUG : two: Unchanged skipping
2025/03/17 09:19:11 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/03/17 09:19:11 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:19:11 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 4
2025/03/17 09:19:11 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 4
2025/03/17 09:19:12 DEBUG : one: crc32 = 19193087 OK
2025/03/17 09:19:12 INFO  : one: Copied (new)
2025/03/17 09:19:12 DEBUG : Waiting for deletions to finish
2025/03/17 09:19:12 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/03/17 09:19:12 INFO  : three.txt: Moved into backup dir
2025/03/17 09:19:13 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:19:13 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:19:13 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/03/17 09:19:13 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:19:13 DEBUG : two: Unchanged skipping
2025/03/17 09:19:13 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:19:14 INFO  : one-2019-01-01: Deleted
2025/03/17 09:19:14 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/03/17 09:19:14 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:19:14 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 5
2025/03/17 09:19:14 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:19:14 DEBUG : one: crc32 = 12389341 OK
2025/03/17 09:19:14 INFO  : one: Copied (new)
2025/03/17 09:19:14 DEBUG : Waiting for deletions to finish
2025/03/17 09:19:15 INFO  : three-2019-01-01.txt: Deleted
2025/03/17 09:19:15 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/03/17 09:19:15 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffixKeepExtension (10.49s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:19:18 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:19:18 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:19:19 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:19:19 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:19:19 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 5
2025/03/17 09:19:19 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:19:20 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/dst"
2025/03/17 09:19:20 DEBUG : Config file has changed externally - reloading
2025/03/17 09:19:21 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/03/17 09:19:21 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:19:21 DEBUG : two: Unchanged skipping
2025/03/17 09:19:21 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:19:21 INFO  : one: Moved (server-side) to: one.bak
2025/03/17 09:19:21 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:19:22 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 4
2025/03/17 09:19:22 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 4
2025/03/17 09:19:22 DEBUG : one: crc32 = 19193087 OK
2025/03/17 09:19:22 INFO  : one: Copied (new)
2025/03/17 09:19:22 DEBUG : Waiting for deletions to finish
2025/03/17 09:19:22 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/03/17 09:19:22 INFO  : three.txt: Moved into backup dir
2025/03/17 09:19:23 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:19:23 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:19:23 DEBUG : one.bak: Excluded (Path Filter)
2025/03/17 09:19:23 DEBUG : one.bak: Excluded
2025/03/17 09:19:23 DEBUG : three.txt.bak: Excluded (Path Filter)
2025/03/17 09:19:23 DEBUG : three.txt.bak: Excluded
2025/03/17 09:19:23 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/03/17 09:19:23 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:19:23 DEBUG : two: Unchanged skipping
2025/03/17 09:19:23 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for checks to finish
2025/03/17 09:19:24 INFO  : one.bak: Deleted
2025/03/17 09:19:24 INFO  : one: Moved (server-side) to: one.bak
2025/03/17 09:19:24 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Waiting for transfers to finish
2025/03/17 09:19:24 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 5
2025/03/17 09:19:24 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:19:24 DEBUG : one: crc32 = 12389341 OK
2025/03/17 09:19:24 INFO  : one: Copied (new)
2025/03/17 09:19:24 DEBUG : Waiting for deletions to finish
2025/03/17 09:19:25 INFO  : three.txt.bak: Deleted
2025/03/17 09:19:25 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/03/17 09:19:25 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (9.93s)
=== RUN   TestSyncSuffix
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:19:27 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:19:27 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:19:29 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:19:29 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:19:30 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 5
2025/03/17 09:19:30 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:19:31 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/dst"
2025/03/17 09:19:31 DEBUG : Config file has changed externally - reloading
2025/03/17 09:19:31 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/03/17 09:19:32 INFO  : one: Moved (server-side) to: one.bak
2025/03/17 09:19:32 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 4
2025/03/17 09:19:32 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 4
2025/03/17 09:19:33 DEBUG : one: crc32 = 19193087 OK
2025/03/17 09:19:33 INFO  : one: Copied (new)
2025/03/17 09:19:33 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:19:33 DEBUG : two: Unchanged skipping
2025/03/17 09:19:33 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2025/03/17 09:19:33 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/03/17 09:19:33 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 6
2025/03/17 09:19:33 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:19:33 DEBUG : three.txt: crc32 = 1e485dc0 OK
2025/03/17 09:19:33 INFO  : three.txt: Copied (new)
2025/03/17 09:19:34 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/03/17 09:19:34 INFO  : one.bak: Deleted
2025/03/17 09:19:34 INFO  : one: Moved (server-side) to: one.bak
2025/03/17 09:19:34 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 5
2025/03/17 09:19:34 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:19:35 DEBUG : one: crc32 = 12389341 OK
2025/03/17 09:19:35 INFO  : one: Copied (new)
2025/03/17 09:19:35 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:19:35 DEBUG : two: Unchanged skipping
2025/03/17 09:19:35 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2025/03/17 09:19:35 INFO  : three.txt.bak: Deleted
2025/03/17 09:19:36 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/03/17 09:19:36 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 19
2025/03/17 09:19:36 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 19
2025/03/17 09:19:37 DEBUG : three.txt: crc32 = 5c880d37 OK
2025/03/17 09:19:37 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffix (10.74s)
=== RUN   TestSyncSuffixKeepExtension
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:19:38 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:19:38 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:19:39 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 3
2025/03/17 09:19:39 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 3
2025/03/17 09:19:40 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 5
2025/03/17 09:19:40 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:19:43 DEBUG : Creating backend with remote "TestPutio:rclone-test-rogadic8yale/dst"
2025/03/17 09:19:44 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/03/17 09:19:44 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/03/17 09:19:44 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 4
2025/03/17 09:19:44 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 4
2025/03/17 09:19:45 DEBUG : one: crc32 = 19193087 OK
2025/03/17 09:19:45 INFO  : one: Copied (new)
2025/03/17 09:19:45 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:19:45 DEBUG : two: Unchanged skipping
2025/03/17 09:19:45 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2025/03/17 09:19:45 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/03/17 09:19:45 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 6
2025/03/17 09:19:45 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:19:46 DEBUG : three.txt: crc32 = 1e485dc0 OK
2025/03/17 09:19:46 INFO  : three.txt: Copied (new)
2025/03/17 09:19:46 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/03/17 09:19:46 INFO  : one-2019-01-01: Deleted
2025/03/17 09:19:46 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/03/17 09:19:46 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 5
2025/03/17 09:19:46 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 5
2025/03/17 09:19:47 DEBUG : one: crc32 = 12389341 OK
2025/03/17 09:19:47 INFO  : one: Copied (new)
2025/03/17 09:19:47 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:19:47 DEBUG : two: Unchanged skipping
2025/03/17 09:19:47 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2025/03/17 09:19:47 INFO  : three-2019-01-01.txt: Deleted
2025/03/17 09:19:47 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/03/17 09:19:48 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': chunkStart: 0, reqSize: 19
2025/03/17 09:19:48 DEBUG : Putio root 'rclone-test-rogadic8yale/dst': Sending chunk. transferOffset: 0 length: 19
2025/03/17 09:19:48 DEBUG : three.txt: crc32 = 5c880d37 OK
2025/03/17 09:19:48 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffixKeepExtension (11.37s)
=== RUN   TestSyncUTFNorm
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:19:49 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 18
2025/03/17 09:19:49 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 18
2025/03/17 09:19:50 DEBUG : Testêé: Sizes differ (src 14 vs dst 18)
2025/03/17 09:19:50 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:19:50 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:19:51 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 14
2025/03/17 09:19:51 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 14
2025/03/17 09:19:52 DEBUG : Testêé: crc32 = c07a9f32 OK
2025/03/17 09:19:52 INFO  : Testêé: Copied (replaced existing) to: Testêé
2025/03/17 09:19:52 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncUTFNorm (4.00s)
=== RUN   TestSyncImmutable
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:19:53 DEBUG : existing: Need to transfer - File not found at Destination
2025/03/17 09:19:53 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:19:53 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:19:54 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:19:54 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:19:54 DEBUG : existing: crc32 = 9a941a19 OK
2025/03/17 09:19:54 INFO  : existing: Copied (new)
2025/03/17 09:19:54 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:19:54 DEBUG : existing: Sizes differ (src 8 vs dst 6)
2025/03/17 09:19:54 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:19:54 ERROR : existing: Source and destination exist but do not match: immutable file modified
2025/03/17 09:19:54 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:19:54 ERROR : Putio root 'rclone-test-rogadic8yale': not deleting files as there were IO errors
2025/03/17 09:19:54 ERROR : Putio root 'rclone-test-rogadic8yale': not deleting directories as there were IO errors
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncImmutable (1.42s)
=== RUN   TestSyncIgnoreCase
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:19:55 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:19:55 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:19:55 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:19:55 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:19:55 DEBUG : existing: Unchanged skipping
2025/03/17 09:19:55 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:19:55 DEBUG : Waiting for deletions to finish
2025/03/17 09:19:55 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreCase (1.11s)
=== RUN   TestFixCase
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    sync_test.go:2635: Skipping test as local or remote are case-sensitive
--- SKIP: TestFixCase (0.43s)
=== RUN   TestMaxTransfer
=== RUN   TestMaxTransfer/Hard
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    sync_test.go:2674: This test only runs on local
=== RUN   TestMaxTransfer/Soft
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    sync_test.go:2674: This test only runs on local
=== RUN   TestMaxTransfer/Cautious
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    sync_test.go:2674: This test only runs on local
--- PASS: TestMaxTransfer (0.34s)
    --- SKIP: TestMaxTransfer/Hard (0.11s)
    --- SKIP: TestMaxTransfer/Soft (0.11s)
    --- SKIP: TestMaxTransfer/Cautious (0.12s)
=== RUN   TestSyncConcurrentDelete
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:19:57 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:19:57 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:19:57 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:19:57 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:19:58 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:19:58 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:19:59 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:19:59 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:00 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:00 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:00 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:00 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:02 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:02 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:03 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:03 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:04 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:04 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:05 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:05 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:06 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:06 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:07 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:07 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:08 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:08 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:08 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:08 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:09 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:09 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:10 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:10 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:11 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:11 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:12 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:12 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:13 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:13 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:14 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:14 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:15 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:15 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:16 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:16 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:17 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:17 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:18 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:18 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:18 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:18 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:19 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:19 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:20 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:20 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:20 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:20 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:21 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:21 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:21 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:21 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:22 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:22 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:23 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:23 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:24 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:24 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:24 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:24 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:26 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:26 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:27 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:27 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:28 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:28 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:29 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:29 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:29 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:29 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:30 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:30 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:31 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:20:31 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both0: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both1: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both13: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both14: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both11: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both16: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both10: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both12: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both15: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both17: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both18: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both4: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both5: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both6: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both19: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both2: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:20:31 DEBUG : both9: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both3: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both7: Unchanged skipping
2025/03/17 09:20:31 DEBUG : both8: Unchanged skipping
2025/03/17 09:20:31 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:20:31 DEBUG : Waiting for deletions to finish
2025/03/17 09:20:31 INFO  : only11: Deleted
2025/03/17 09:20:31 INFO  : only3: Deleted
2025/03/17 09:20:31 INFO  : only5: Deleted
2025/03/17 09:20:31 INFO  : only1: Deleted
2025/03/17 09:20:31 INFO  : only10: Deleted
2025/03/17 09:20:31 INFO  : only2: Deleted
2025/03/17 09:20:31 INFO  : only8: Deleted
2025/03/17 09:20:32 INFO  : only18: Deleted
2025/03/17 09:20:32 INFO  : only7: Deleted
2025/03/17 09:20:32 INFO  : only17: Deleted
2025/03/17 09:20:32 INFO  : only4: Deleted
2025/03/17 09:20:32 INFO  : only13: Deleted
2025/03/17 09:20:32 INFO  : only14: Deleted
2025/03/17 09:20:33 INFO  : only6: Deleted
2025/03/17 09:20:33 INFO  : only9: Deleted
2025/03/17 09:20:33 INFO  : only0: Deleted
2025/03/17 09:20:33 INFO  : only12: Deleted
2025/03/17 09:20:34 INFO  : only19: Deleted
2025/03/17 09:20:34 INFO  : only15: Deleted
2025/03/17 09:20:35 INFO  : only16: Deleted
2025/03/17 09:20:35 INFO  : There was nothing to transfer
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncConcurrentDelete (43.23s)
=== RUN   TestSyncConcurrentTruncate
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:20:40 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:40 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:41 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:41 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:41 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:41 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:42 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:42 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:43 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:43 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:43 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:43 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:44 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:44 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:45 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:45 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:45 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:45 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:46 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:46 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:47 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:47 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:47 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:47 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:48 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:48 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:49 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:49 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:50 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:50 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:50 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:50 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:51 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:51 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:52 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:52 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:52 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:52 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:53 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:53 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:53 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:53 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:54 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:54 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:55 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:55 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:56 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:56 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:56 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:56 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:57 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:57 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:58 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:58 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:58 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:58 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:20:59 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:20:59 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:21:00 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:21:00 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:21:01 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:21:01 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:21:02 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:21:02 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:21:02 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:21:02 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:21:03 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:21:03 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:21:05 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:21:05 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:21:05 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:21:05 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:21:06 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:21:06 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:21:07 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:21:07 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:21:08 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:21:08 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:21:09 DEBUG : Putio root 'rclone-test-rogadic8yale': chunkStart: 0, reqSize: 6
2025/03/17 09:21:09 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending chunk. transferOffset: 0 length: 6
2025/03/17 09:21:10 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for checks to finish
2025/03/17 09:21:10 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both1: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both12: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both10: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both14: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both11: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both16: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both17: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both0: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both13: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both2: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both15: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both18: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both5: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both19: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both3: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both4: Unchanged skipping
2025/03/17 09:21:10 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/17 09:21:10 DEBUG : both9: Unchanged skipping
2025/03/17 09:21:10 DEBUG : only0: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only1: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only10: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : both6: Unchanged skipping
2025/03/17 09:21:10 DEBUG : only11: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only12: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : both7: Unchanged skipping
2025/03/17 09:21:10 DEBUG : only13: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only14: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only15: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only16: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : both8: Unchanged skipping
2025/03/17 09:21:10 DEBUG : only17: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only18: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only19: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only2: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only3: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only4: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only5: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only6: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only7: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only8: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : only9: Sizes differ (src 0 vs dst 6)
2025/03/17 09:21:10 DEBUG : Putio root 'rclone-test-rogadic8yale': Waiting for transfers to finish
2025/03/17 09:21:10 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:11 DEBUG : only0: crc32 = 00000000 OK
2025/03/17 09:21:11 INFO  : only0: Copied (replaced existing)
2025/03/17 09:21:11 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:11 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:11 DEBUG : only11: crc32 = 00000000 OK
2025/03/17 09:21:11 INFO  : only11: Copied (replaced existing)
2025/03/17 09:21:11 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:11 DEBUG : only14: crc32 = 00000000 OK
2025/03/17 09:21:11 INFO  : only14: Copied (replaced existing)
2025/03/17 09:21:12 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:12 DEBUG : only15: crc32 = 00000000 OK
2025/03/17 09:21:12 INFO  : only15: Copied (replaced existing)
2025/03/17 09:21:12 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:12 DEBUG : only13: crc32 = 00000000 OK
2025/03/17 09:21:12 INFO  : only13: Copied (replaced existing)
2025/03/17 09:21:13 DEBUG : only17: crc32 = 00000000 OK
2025/03/17 09:21:13 INFO  : only17: Copied (replaced existing)
2025/03/17 09:21:13 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:13 DEBUG : only18: crc32 = 00000000 OK
2025/03/17 09:21:13 INFO  : only18: Copied (replaced existing)
2025/03/17 09:21:14 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:14 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:14 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:14 DEBUG : only3: crc32 = 00000000 OK
2025/03/17 09:21:14 INFO  : only3: Copied (replaced existing)
2025/03/17 09:21:14 DEBUG : only19: crc32 = 00000000 OK
2025/03/17 09:21:14 INFO  : only19: Copied (replaced existing)
2025/03/17 09:21:14 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:14 DEBUG : only2: crc32 = 00000000 OK
2025/03/17 09:21:14 INFO  : only2: Copied (replaced existing)
2025/03/17 09:21:14 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:15 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:15 DEBUG : only4: crc32 = 00000000 OK
2025/03/17 09:21:15 INFO  : only4: Copied (replaced existing)
2025/03/17 09:21:15 DEBUG : only10: crc32 = 00000000 OK
2025/03/17 09:21:15 INFO  : only10: Copied (replaced existing)
2025/03/17 09:21:15 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:15 DEBUG : only6: crc32 = 00000000 OK
2025/03/17 09:21:15 INFO  : only6: Copied (replaced existing)
2025/03/17 09:21:15 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:15 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:15 DEBUG : only8: crc32 = 00000000 OK
2025/03/17 09:21:15 INFO  : only8: Copied (replaced existing)
2025/03/17 09:21:16 DEBUG : only16: crc32 = 00000000 OK
2025/03/17 09:21:16 INFO  : only16: Copied (replaced existing)
2025/03/17 09:21:16 DEBUG : only12: crc32 = 00000000 OK
2025/03/17 09:21:16 INFO  : only12: Copied (replaced existing)
2025/03/17 09:21:16 ERROR : only1: Failed to copy: unexpected status code (400) response while doing POST to https://api.put.io/v2/files/delete
2025/03/17 09:21:16 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:17 DEBUG : only5: crc32 = 00000000 OK
2025/03/17 09:21:17 INFO  : only5: Copied (replaced existing)
2025/03/17 09:21:17 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:17 DEBUG : only7: crc32 = 00000000 OK
2025/03/17 09:21:17 INFO  : only7: Copied (replaced existing)
2025/03/17 09:21:18 DEBUG : Putio root 'rclone-test-rogadic8yale': Sending zero length chunk
2025/03/17 09:21:18 DEBUG : only9: crc32 = 00000000 OK
2025/03/17 09:21:18 INFO  : only9: Copied (replaced existing)
2025/03/17 09:21:18 ERROR : Putio root 'rclone-test-rogadic8yale': not deleting files as there were IO errors
2025/03/17 09:21:18 ERROR : Putio root 'rclone-test-rogadic8yale': not deleting directories as there were IO errors
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    sync_test.go:2752: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2752
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762
        	Error:      	Received unexpected error:
        	            	unexpected status code (400) response while doing POST to https://api.put.io/v2/files/delete
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	Sync must not return a error
    sync_test.go:2753: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2753
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762
        	Error:      	Should be false
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	Low level errors must not have happened
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:299: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2754
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762
        	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 (6), 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)
    fstest.go:137: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2754
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762
        	Error:      	Should be true
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	Putio root 'rclone-test-rogadic8yale'/only1: crc32 hash incorrect - expecting "00000000" got "9a941a19"
    fstest.go:144: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2754
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 6
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	only1: size incorrect file=0 vs obj=6
--- FAIL: TestSyncConcurrentTruncate (54.17s)
=== RUN   TestSyncReplaceDirModTime
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:21:34 DEBUG : empty_dir: Making directory with metadata
2025/03/17 09:21:34 INFO  : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/03/17 09:21:34 DEBUG : empty_on_remote: Making directory with metadata
2025/03/17 09:21:34 INFO  : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/03/17 09:21:34 INFO  : empty_on_remote: Making directory
2025/03/17 09:21:34 INFO  : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:21:36 INFO  : test_dir1: Set directory modification time (using DirSetModTime)
2025/03/17 09:21:36 INFO  : test_dir2: Set directory modification time (using DirSetModTime)
2025/03/17 09:21:36 INFO  : test_dir2/sub_dir: Set directory modification time (using DirSetModTime)
2025/03/17 09:21:36 INFO  : empty_on_remote: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncReplaceDirModTime (8.02s)
=== RUN   TestSyncReplaceDirModTimeWithEmptyDirs
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:21:42 DEBUG : empty_dir: Making directory with metadata
2025/03/17 09:21:42 INFO  : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/03/17 09:21:42 DEBUG : empty_on_remote: Making directory with metadata
2025/03/17 09:21:42 INFO  : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/03/17 09:21:42 INFO  : empty_on_remote: Making directory
2025/03/17 09:21:42 INFO  : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:21:44 INFO  : test_dir1: Set directory modification time (using DirSetModTime)
2025/03/17 09:21:44 INFO  : test_dir2: Set directory modification time (using DirSetModTime)
2025/03/17 09:21:44 INFO  : test_dir2/sub_dir: Set directory modification time (using DirSetModTime)
2025/03/17 09:21:44 INFO  : empty_on_remote: Set directory modification time (using DirSetModTime)
2025/03/17 09:21:44 INFO  : empty_dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (4.87s)
=== RUN   TestNothingToTransferWithEmptyDirs
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:21:47 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/03/17 09:21:47 INFO  : sub dir: Making directory
2025/03/17 09:21:47 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestNothingToTransferWithEmptyDirs (12.09s)
=== RUN   TestNothingToTransferWithoutEmptyDirs
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
2025/03/17 09:21:59 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/03/17 09:21:59 INFO  : sub dir: Making directory
2025/03/17 09:21:59 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
    run.go:180: Remote "Putio root 'rclone-test-rogadic8yale'", Local "Local file system at /tmp/rclone2273536011", Modify Window "1s"
--- PASS: TestNothingToTransferWithoutEmptyDirs (10.63s)
FAIL
2025/03/17 09:22:10 DEBUG : Putio root 'rclone-test-rogadic8yale': Purge remote
"./sync.test -test.v -test.timeout 2h0m0s -remote TestPutio: -verbose" - Finished ERROR in 10m13.896891766s (try 1/5): exit status 1: Failed [TestSyncConcurrentTruncate]