"./sync.test -test.v -test.timeout 2h0m0s -remote TestPutio: -verbose -test.run '^(TestServerSideCopy|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCompareDest|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncCopyDest|TestSyncIgnoreCase|TestSyncImmutable|TestSyncMultipleCompareDest|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncUTFNorm)$'" - Starting (try 2/5) 2022/03/15 05:33:15 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3" 2022/03/15 05:33:15 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/03/15 05:33:15 DEBUG : Creating backend with remote "/tmp/rclone1356309219" === RUN TestServerSideCopy run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" 2022/03/15 05:33:16 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 11 2022/03/15 05:33:16 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 11 2022/03/15 05:33:17 DEBUG : Creating backend with remote "TestPutio:rclone-test-yowinuy0qawijec5kisitaj8" sync_test.go:275: Server side copy (if possible) Putio root 'rclone-test-xowewug8xodafak8bojamuq3' -> Putio root 'rclone-test-yowinuy0qawijec5kisitaj8' 2022/03/15 05:33:18 DEBUG : Putio root 'rclone-test-yowinuy0qawijec5kisitaj8': Waiting for checks to finish 2022/03/15 05:33:18 DEBUG : Putio root 'rclone-test-yowinuy0qawijec5kisitaj8': Waiting for transfers to finish 2022/03/15 05:33:18 ERROR : sub dir/hello world: Failed to copy: unexpected status code (400) response while doing POST to https://api.put.io/v2/files/copy sync_test.go:278: Error Trace: sync_test.go:278 Error: Received unexpected error: unexpected status code (400) response while doing POST to https://api.put.io/v2/files/copy Test: TestServerSideCopy 2022/03/15 05:33:18 DEBUG : Putio root 'rclone-test-yowinuy0qawijec5kisitaj8': Purge remote --- FAIL: TestServerSideCopy (3.54s) === RUN TestServerSideMove run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" 2022/03/15 05:33:19 DEBUG : Creating backend with remote "TestPutio:rclone-test-doxihaq9luleseg9bugitab4" 2022/03/15 05:33:19 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 60 2022/03/15 05:33:19 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 60 2022/03/15 05:33:21 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 1 2022/03/15 05:33:21 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 1 2022/03/15 05:33:23 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 68 2022/03/15 05:33:23 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 68 sync_test.go:1215: Server side move (if possible) Putio root 'rclone-test-xowewug8xodafak8bojamuq3' -> Putio root 'rclone-test-doxihaq9luleseg9bugitab4' 2022/03/15 05:33:25 DEBUG : Putio root 'rclone-test-doxihaq9luleseg9bugitab4': chunkStart: 0, reqSize: 1 2022/03/15 05:33:25 DEBUG : Putio root 'rclone-test-doxihaq9luleseg9bugitab4': Sending chunk. transferOffset: 0 length: 1 2022/03/15 05:33:27 DEBUG : Putio root 'rclone-test-doxihaq9luleseg9bugitab4': chunkStart: 0, reqSize: 60 2022/03/15 05:33:27 DEBUG : Putio root 'rclone-test-doxihaq9luleseg9bugitab4': Sending chunk. transferOffset: 0 length: 60 2022/03/15 05:33:27 DEBUG : Putio root 'rclone-test-doxihaq9luleseg9bugitab4': Using server-side directory move 2022/03/15 05:33:27 INFO : Putio root 'rclone-test-doxihaq9luleseg9bugitab4': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2022/03/15 05:33:28 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2022/03/15 05:33:28 DEBUG : empty space: Unchanged skipping 2022/03/15 05:33:28 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/03/15 05:33:28 DEBUG : Putio root 'rclone-test-doxihaq9luleseg9bugitab4': Waiting for checks to finish 2022/03/15 05:33:28 INFO : empty space: Deleted 2022/03/15 05:33:28 DEBUG : Putio root 'rclone-test-doxihaq9luleseg9bugitab4': Waiting for transfers to finish 2022/03/15 05:33:28 INFO : potato2: Moved (server-side) 2022/03/15 05:33:29 INFO : potato3: Deleted 2022/03/15 05:33:29 INFO : potato3: Moved (server-side) 2022/03/15 05:33:29 INFO : There was nothing to transfer 2022/03/15 05:33:29 DEBUG : Creating backend with remote "TestPutio:rclone-test-yucelug7wohibit8huhamuj3" 2022/03/15 05:33:29 DEBUG : Putio root 'rclone-test-yucelug7wohibit8huhamuj3': Using server-side directory move 2022/03/15 05:33:30 INFO : Putio root 'rclone-test-yucelug7wohibit8huhamuj3': Server side directory move succeeded 2022/03/15 05:33:30 ERROR : : error listing: directory not found 2022/03/15 05:33:30 DEBUG : Putio root 'rclone-test-yucelug7wohibit8huhamuj3': Purge remote 2022/03/15 05:33:30 DEBUG : Putio root 'rclone-test-doxihaq9luleseg9bugitab4': Purge remote 2022/03/15 05:33:30 purge failed: Rmdir: directory not found --- PASS: TestServerSideMove (11.37s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" 2022/03/15 05:33:30 DEBUG : Creating backend with remote "TestPutio:rclone-test-haxapor4lorataw9kiyenig0" 2022/03/15 05:33:31 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 60 2022/03/15 05:33:31 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 60 2022/03/15 05:33:32 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 1 2022/03/15 05:33:32 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 1 2022/03/15 05:33:33 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 68 2022/03/15 05:33:33 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 68 sync_test.go:1215: Server side move (if possible) Putio root 'rclone-test-xowewug8xodafak8bojamuq3' -> Putio root 'rclone-test-haxapor4lorataw9kiyenig0' 2022/03/15 05:33:35 DEBUG : Putio root 'rclone-test-haxapor4lorataw9kiyenig0': chunkStart: 0, reqSize: 1 2022/03/15 05:33:35 DEBUG : Putio root 'rclone-test-haxapor4lorataw9kiyenig0': Sending chunk. transferOffset: 0 length: 1 2022/03/15 05:33:36 DEBUG : Putio root 'rclone-test-haxapor4lorataw9kiyenig0': chunkStart: 0, reqSize: 60 2022/03/15 05:33:36 DEBUG : Putio root 'rclone-test-haxapor4lorataw9kiyenig0': Sending chunk. transferOffset: 0 length: 60 2022/03/15 05:33:37 DEBUG : empty space: Excluded 2022/03/15 05:33:37 DEBUG : empty space: Excluded 2022/03/15 05:33:37 DEBUG : Putio root 'rclone-test-haxapor4lorataw9kiyenig0': Waiting for checks to finish 2022/03/15 05:33:37 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/03/15 05:33:37 DEBUG : Putio root 'rclone-test-haxapor4lorataw9kiyenig0': Waiting for transfers to finish 2022/03/15 05:33:37 INFO : potato3: Deleted 2022/03/15 05:33:37 INFO : potato2: Moved (server-side) 2022/03/15 05:33:37 INFO : potato3: Moved (server-side) 2022/03/15 05:33:37 INFO : There was nothing to transfer 2022/03/15 05:33:37 DEBUG : Creating backend with remote "TestPutio:rclone-test-soqisit5zapamor3jufejab0" 2022/03/15 05:33:37 DEBUG : empty space: Excluded 2022/03/15 05:33:37 DEBUG : Putio root 'rclone-test-soqisit5zapamor3jufejab0': Waiting for checks to finish 2022/03/15 05:33:37 DEBUG : Putio root 'rclone-test-soqisit5zapamor3jufejab0': Waiting for transfers to finish 2022/03/15 05:33:38 INFO : potato2: Moved (server-side) 2022/03/15 05:33:38 INFO : potato3: Moved (server-side) 2022/03/15 05:33:38 INFO : There was nothing to transfer 2022/03/15 05:33:38 DEBUG : Putio root 'rclone-test-soqisit5zapamor3jufejab0': Purge remote 2022/03/15 05:33:38 DEBUG : Putio root 'rclone-test-haxapor4lorataw9kiyenig0': Purge remote --- PASS: TestServerSideMoveWithFilter (8.23s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" 2022/03/15 05:33:38 DEBUG : Creating backend with remote "TestPutio:rclone-test-dogumex1camikud4racixom1" 2022/03/15 05:33:39 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 60 2022/03/15 05:33:39 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 60 2022/03/15 05:33:40 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 1 2022/03/15 05:33:40 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 1 2022/03/15 05:33:43 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 68 2022/03/15 05:33:43 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 68 2022/03/15 05:33:44 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) Putio root 'rclone-test-xowewug8xodafak8bojamuq3' -> Putio root 'rclone-test-dogumex1camikud4racixom1' 2022/03/15 05:33:45 DEBUG : Putio root 'rclone-test-dogumex1camikud4racixom1': chunkStart: 0, reqSize: 1 2022/03/15 05:33:45 DEBUG : Putio root 'rclone-test-dogumex1camikud4racixom1': Sending chunk. transferOffset: 0 length: 1 2022/03/15 05:33:48 DEBUG : Putio root 'rclone-test-dogumex1camikud4racixom1': chunkStart: 0, reqSize: 60 2022/03/15 05:33:48 DEBUG : Putio root 'rclone-test-dogumex1camikud4racixom1': Sending chunk. transferOffset: 0 length: 60 2022/03/15 05:33:49 DEBUG : Putio root 'rclone-test-dogumex1camikud4racixom1': Using server-side directory move 2022/03/15 05:33:49 INFO : Putio root 'rclone-test-dogumex1camikud4racixom1': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2022/03/15 05:33:49 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2022/03/15 05:33:49 DEBUG : empty space: Unchanged skipping 2022/03/15 05:33:49 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/03/15 05:33:49 DEBUG : Putio root 'rclone-test-dogumex1camikud4racixom1': Waiting for checks to finish 2022/03/15 05:33:49 INFO : empty space: Deleted 2022/03/15 05:33:49 DEBUG : Putio root 'rclone-test-dogumex1camikud4racixom1': Waiting for transfers to finish 2022/03/15 05:33:49 INFO : potato2: Moved (server-side) 2022/03/15 05:33:50 INFO : potato3: Deleted 2022/03/15 05:33:50 INFO : potato3: Moved (server-side) 2022/03/15 05:33:50 INFO : tomatoDir: Removing directory 2022/03/15 05:33:51 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': deleted 1 directories 2022/03/15 05:33:51 INFO : There was nothing to transfer 2022/03/15 05:33:51 DEBUG : Creating backend with remote "TestPutio:rclone-test-namawel9daliqop8wazagal2" 2022/03/15 05:33:51 DEBUG : tomatoDir: Making directory 2022/03/15 05:33:51 DEBUG : Putio root 'rclone-test-namawel9daliqop8wazagal2': Using server-side directory move 2022/03/15 05:33:51 INFO : Putio root 'rclone-test-namawel9daliqop8wazagal2': Server side directory move succeeded 2022/03/15 05:33:51 ERROR : : error listing: directory not found 2022/03/15 05:33:52 ERROR : : error listing: directory not found 2022/03/15 05:33:52 DEBUG : Putio root 'rclone-test-namawel9daliqop8wazagal2': Purge remote 2022/03/15 05:33:52 DEBUG : Putio root 'rclone-test-dogumex1camikud4racixom1': Purge remote 2022/03/15 05:33:52 purge failed: Rmdir: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (13.57s) === RUN TestSyncCompareDest run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" 2022/03/15 05:33:52 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/dst" 2022/03/15 05:33:52 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/CompareDest" 2022/03/15 05:33:53 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:33:53 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:33:53 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 3 2022/03/15 05:33:53 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:33:55 DEBUG : one: crc32 = 7a6c86f1 OK 2022/03/15 05:33:55 INFO : one: Copied (new) 2022/03/15 05:33:55 DEBUG : Waiting for deletions to finish 2022/03/15 05:33:56 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:33:56 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/15 05:33:56 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:33:56 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 5 2022/03/15 05:33:56 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 5 2022/03/15 05:34:01 DEBUG : one: crc32 = cb217308 OK 2022/03/15 05:34:01 INFO : one: Copied (replaced existing) 2022/03/15 05:34:01 DEBUG : Waiting for deletions to finish 2022/03/15 05:34:01 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 3 2022/03/15 05:34:01 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:34:03 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 5 2022/03/15 05:34:03 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 5 2022/03/15 05:34:05 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:34:05 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/03/15 05:34:05 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/15 05:34:05 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:34:05 DEBUG : Waiting for deletions to finish 2022/03/15 05:34:05 INFO : There was nothing to transfer 2022/03/15 05:34:05 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 3 2022/03/15 05:34:05 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:34:06 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/03/15 05:34:06 DEBUG : two: Destination found in --compare-dest, skipping 2022/03/15 05:34:06 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:34:06 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/03/15 05:34:06 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/15 05:34:06 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:34:06 DEBUG : Waiting for deletions to finish 2022/03/15 05:34:06 INFO : There was nothing to transfer 2022/03/15 05:34:07 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/03/15 05:34:07 DEBUG : two: Destination found in --compare-dest, skipping 2022/03/15 05:34:07 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:34:07 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/03/15 05:34:07 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/15 05:34:07 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:34:07 DEBUG : Waiting for deletions to finish 2022/03/15 05:34:07 INFO : There was nothing to transfer 2022/03/15 05:34:07 DEBUG : two: Modification times differ by -120h0m0s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59 +0000 UTC 2022/03/15 05:34:07 DEBUG : two: crc32 = 11ca8a66 OK 2022/03/15 05:34:07 DEBUG : two: Destination found in --compare-dest, skipping 2022/03/15 05:34:07 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:34:07 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/03/15 05:34:07 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/15 05:34:07 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:34:07 DEBUG : Waiting for deletions to finish 2022/03/15 05:34:07 INFO : There was nothing to transfer 2022/03/15 05:34:08 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/03/15 05:34:08 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:34:08 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/03/15 05:34:08 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/15 05:34:08 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:34:08 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 5 2022/03/15 05:34:08 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 5 2022/03/15 05:34:09 DEBUG : two: crc32 = 8b9078ce OK 2022/03/15 05:34:09 INFO : two: Copied (new) 2022/03/15 05:34:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (17.92s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" 2022/03/15 05:34:10 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 1 2022/03/15 05:34:10 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 1 2022/03/15 05:34:12 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 1 2022/03/15 05:34:12 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 1 2022/03/15 05:34:12 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/dest" 2022/03/15 05:34:13 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/pre-dest1" 2022/03/15 05:34:13 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/pre-dest2" 2022/03/15 05:34:13 DEBUG : 1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/03/15 05:34:13 DEBUG : 1: Destination found in --compare-dest, skipping 2022/03/15 05:34:13 DEBUG : 2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/03/15 05:34:13 DEBUG : 2: Destination found in --compare-dest, skipping 2022/03/15 05:34:14 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dest': Waiting for checks to finish 2022/03/15 05:34:14 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dest': Waiting for transfers to finish 2022/03/15 05:34:14 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dest': chunkStart: 0, reqSize: 1 2022/03/15 05:34:14 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dest': Sending chunk. transferOffset: 0 length: 1 2022/03/15 05:34:15 DEBUG : 3: crc32 = 6dd28e9b OK 2022/03/15 05:34:15 INFO : 3: Copied (new) 2022/03/15 05:34:15 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (6.42s) === RUN TestSyncCopyDest run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" 2022/03/15 05:34:16 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/dst" 2022/03/15 05:34:17 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/CopyDest" 2022/03/15 05:34:17 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:34:17 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:34:17 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 3 2022/03/15 05:34:17 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:34:18 DEBUG : one: crc32 = 7a6c86f1 OK 2022/03/15 05:34:18 INFO : one: Copied (new) 2022/03/15 05:34:18 DEBUG : Waiting for deletions to finish 2022/03/15 05:34:18 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:34:18 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/15 05:34:18 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:34:19 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 5 2022/03/15 05:34:19 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 5 2022/03/15 05:34:20 DEBUG : one: crc32 = cb217308 OK 2022/03/15 05:34:20 INFO : one: Copied (replaced existing) 2022/03/15 05:34:20 DEBUG : Waiting for deletions to finish 2022/03/15 05:34:20 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 3 2022/03/15 05:34:20 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:34:22 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 5 2022/03/15 05:34:22 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 5 2022/03/15 05:34:22 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/BackupDir" 2022/03/15 05:34:23 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:34:23 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/03/15 05:34:23 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/15 05:34:23 INFO : one: Moved (server-side) 2022/03/15 05:34:23 ERROR : one: Failed to copy: unexpected status code (400) response while doing POST to https://api.put.io/v2/files/copy 2022/03/15 05:34:23 ERROR : one: Destination found in --copy-dest, error copying 2022/03/15 05:34:23 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/15 05:34:24 INFO : one: Deleted 2022/03/15 05:34:24 ERROR : one: Couldn't move: object not found 2022/03/15 05:34:24 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:34:24 ERROR : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': not deleting files as there were IO errors 2022/03/15 05:34:24 ERROR : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': not deleting directories as there were IO errors sync_test.go:1648: Error Trace: sync_test.go:1648 Error: Received unexpected error: object not found Test: TestSyncCopyDest --- FAIL: TestSyncCopyDest (8.40s) === RUN TestSyncBackupDir run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" 2022/03/15 05:34:25 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 3 2022/03/15 05:34:25 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:34:29 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 3 2022/03/15 05:34:29 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:34:31 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 5 2022/03/15 05:34:31 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 5 2022/03/15 05:34:31 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/dst" 2022/03/15 05:34:32 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/backup" 2022/03/15 05:34:32 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:34:32 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/15 05:34:32 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/03/15 05:34:32 DEBUG : two: Unchanged skipping 2022/03/15 05:34:32 INFO : one: Moved (server-side) 2022/03/15 05:34:32 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:34:33 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 4 2022/03/15 05:34:33 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 4 2022/03/15 05:34:33 DEBUG : one: crc32 = 19193087 OK 2022/03/15 05:34:33 INFO : one: Copied (new) 2022/03/15 05:34:33 DEBUG : Waiting for deletions to finish 2022/03/15 05:34:33 INFO : three.txt: Moved (server-side) 2022/03/15 05:34:33 INFO : three.txt: Moved into backup dir 2022/03/15 05:34:34 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 6 2022/03/15 05:34:34 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 6 2022/03/15 05:34:35 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/15 05:34:35 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:34:35 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/03/15 05:34:35 DEBUG : two: Unchanged skipping 2022/03/15 05:34:35 INFO : one: Deleted 2022/03/15 05:34:35 INFO : one: Moved (server-side) 2022/03/15 05:34:35 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:34:35 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 5 2022/03/15 05:34:35 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 5 2022/03/15 05:34:41 DEBUG : one: crc32 = 12389341 OK 2022/03/15 05:34:41 INFO : one: Copied (new) 2022/03/15 05:34:41 DEBUG : Waiting for deletions to finish 2022/03/15 05:34:41 INFO : three.txt: Deleted 2022/03/15 05:34:42 INFO : three.txt: Moved (server-side) 2022/03/15 05:34:42 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (18.10s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" 2022/03/15 05:34:43 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 3 2022/03/15 05:34:43 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:34:44 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 3 2022/03/15 05:34:44 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:34:45 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 5 2022/03/15 05:34:45 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 5 2022/03/15 05:34:48 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/dst" 2022/03/15 05:34:48 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/backup" 2022/03/15 05:34:48 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:34:48 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/03/15 05:34:48 DEBUG : two: Unchanged skipping 2022/03/15 05:34:48 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/15 05:34:49 INFO : one: Moved (server-side) to: one.bak 2022/03/15 05:34:49 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:34:49 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 4 2022/03/15 05:34:49 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 4 2022/03/15 05:34:51 DEBUG : one: crc32 = 19193087 OK 2022/03/15 05:34:51 INFO : one: Copied (new) 2022/03/15 05:34:51 DEBUG : Waiting for deletions to finish 2022/03/15 05:34:51 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/03/15 05:34:51 INFO : three.txt: Moved into backup dir 2022/03/15 05:34:52 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 6 2022/03/15 05:34:52 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 6 2022/03/15 05:34:53 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/15 05:34:53 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/03/15 05:34:53 DEBUG : two: Unchanged skipping 2022/03/15 05:34:53 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:34:54 INFO : one.bak: Deleted 2022/03/15 05:34:54 INFO : one: Moved (server-side) to: one.bak 2022/03/15 05:34:54 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:34:54 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 5 2022/03/15 05:34:54 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 5 2022/03/15 05:34:55 DEBUG : one: crc32 = 12389341 OK 2022/03/15 05:34:55 INFO : one: Copied (new) 2022/03/15 05:34:55 DEBUG : Waiting for deletions to finish 2022/03/15 05:34:55 INFO : three.txt.bak: Deleted 2022/03/15 05:34:56 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/03/15 05:34:56 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (14.02s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" 2022/03/15 05:34:57 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 3 2022/03/15 05:34:57 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:34:58 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 3 2022/03/15 05:34:58 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:34:59 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 5 2022/03/15 05:34:59 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 5 2022/03/15 05:35:00 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/dst" 2022/03/15 05:35:00 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/backup" 2022/03/15 05:35:01 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:35:01 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/03/15 05:35:01 DEBUG : two: Unchanged skipping 2022/03/15 05:35:01 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/15 05:35:01 INFO : one: Moved (server-side) to: one-2019-01-01 2022/03/15 05:35:01 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:35:01 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 4 2022/03/15 05:35:01 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 4 2022/03/15 05:35:04 DEBUG : one: crc32 = 19193087 OK 2022/03/15 05:35:04 INFO : one: Copied (new) 2022/03/15 05:35:04 DEBUG : Waiting for deletions to finish 2022/03/15 05:35:04 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/03/15 05:35:04 INFO : three.txt: Moved into backup dir 2022/03/15 05:35:05 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 6 2022/03/15 05:35:05 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 6 2022/03/15 05:35:06 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/15 05:35:06 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:35:06 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/03/15 05:35:06 DEBUG : two: Unchanged skipping 2022/03/15 05:35:06 INFO : one-2019-01-01: Deleted 2022/03/15 05:35:07 INFO : one: Moved (server-side) to: one-2019-01-01 2022/03/15 05:35:07 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:35:07 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 5 2022/03/15 05:35:07 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 5 2022/03/15 05:35:07 DEBUG : one: crc32 = 12389341 OK 2022/03/15 05:35:07 INFO : one: Copied (new) 2022/03/15 05:35:07 DEBUG : Waiting for deletions to finish 2022/03/15 05:35:08 INFO : three-2019-01-01.txt: Deleted 2022/03/15 05:35:08 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/03/15 05:35:08 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (12.32s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" 2022/03/15 05:35:10 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 3 2022/03/15 05:35:10 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:35:11 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 3 2022/03/15 05:35:11 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:35:12 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 5 2022/03/15 05:35:12 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 5 2022/03/15 05:35:13 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/dst" 2022/03/15 05:35:13 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:35:13 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/03/15 05:35:13 DEBUG : two: Unchanged skipping 2022/03/15 05:35:13 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/15 05:35:13 INFO : one: Moved (server-side) to: one.bak 2022/03/15 05:35:13 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:35:14 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 4 2022/03/15 05:35:14 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 4 2022/03/15 05:35:14 DEBUG : one: crc32 = 19193087 OK 2022/03/15 05:35:14 INFO : one: Copied (new) 2022/03/15 05:35:14 DEBUG : Waiting for deletions to finish 2022/03/15 05:35:15 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/03/15 05:35:15 INFO : three.txt: Moved into backup dir 2022/03/15 05:35:15 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 6 2022/03/15 05:35:15 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 6 2022/03/15 05:35:16 DEBUG : one.bak: Excluded 2022/03/15 05:35:16 DEBUG : three.txt.bak: Excluded 2022/03/15 05:35:16 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/15 05:35:16 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for checks to finish 2022/03/15 05:35:16 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/03/15 05:35:16 DEBUG : two: Unchanged skipping 2022/03/15 05:35:16 INFO : one.bak: Deleted 2022/03/15 05:35:16 INFO : one: Moved (server-side) to: one.bak 2022/03/15 05:35:16 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Waiting for transfers to finish 2022/03/15 05:35:17 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 5 2022/03/15 05:35:17 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 5 2022/03/15 05:35:17 DEBUG : one: crc32 = 12389341 OK 2022/03/15 05:35:17 INFO : one: Copied (new) 2022/03/15 05:35:17 DEBUG : Waiting for deletions to finish 2022/03/15 05:35:17 INFO : three.txt.bak: Deleted 2022/03/15 05:35:18 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/03/15 05:35:18 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (9.60s) === RUN TestSyncSuffix run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" 2022/03/15 05:35:19 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 3 2022/03/15 05:35:19 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:35:20 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 3 2022/03/15 05:35:20 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 3 2022/03/15 05:35:21 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': chunkStart: 0, reqSize: 5 2022/03/15 05:35:21 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Sending chunk. transferOffset: 0 length: 5 2022/03/15 05:35:22 DEBUG : Creating backend with remote "TestPutio:rclone-test-xowewug8xodafak8bojamuq3/dst" 2022/03/15 05:35:22 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/15 05:35:22 INFO : one: Moved (server-side) to: one.bak 2022/03/15 05:35:23 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 4 2022/03/15 05:35:23 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 4 2022/03/15 05:35:24 DEBUG : one: crc32 = 19193087 OK 2022/03/15 05:35:24 INFO : one: Copied (new) 2022/03/15 05:35:24 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/03/15 05:35:24 DEBUG : two: Unchanged skipping 2022/03/15 05:35:24 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/03/15 05:35:24 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/03/15 05:35:24 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': chunkStart: 0, reqSize: 6 2022/03/15 05:35:24 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3/dst': Sending chunk. transferOffset: 0 length: 6 2022/03/15 05:35:25 DEBUG : three.txt: crc32 = 1e485dc0 OK 2022/03/15 05:35:25 INFO : three.txt: Copied (new) 2022/03/15 05:35:25 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/15 05:35:26 INFO : one.bak: Deleted 2022/03/15 05:35:26 INFO : one: Moved (server-side) to: one.bak 2022/03/15 05:35:26 ERROR : one: Failed to copy: unexpected status code from upload create: 429 sync_test.go:1855: Error Trace: sync_test.go:1855 sync_test.go:1875 Error: Received unexpected error: unexpected status code from upload create: 429 Test: TestSyncSuffix --- FAIL: TestSyncSuffix (8.05s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" run.go:287: Failed to put "dst/one" to "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'": unexpected status code from upload create: 429 --- FAIL: TestSyncSuffixKeepExtension (0.91s) === RUN TestSyncUTFNorm run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" run.go:287: Failed to put "Testêé" to "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'": unexpected status code from upload create: 429 --- FAIL: TestSyncUTFNorm (0.37s) === RUN TestSyncImmutable run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" 2022/03/15 05:35:28 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Waiting for checks to finish 2022/03/15 05:35:28 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Waiting for transfers to finish 2022/03/15 05:35:28 ERROR : existing: Failed to copy: unexpected status code from upload create: 429 2022/03/15 05:35:28 ERROR : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': not deleting files as there were IO errors 2022/03/15 05:35:28 ERROR : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': not deleting directories as there were IO errors sync_test.go:1929: Error Trace: sync_test.go:1929 Error: Received unexpected error: unexpected status code from upload create: 429 Test: TestSyncImmutable --- FAIL: TestSyncImmutable (0.52s) === RUN TestSyncIgnoreCase run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" run.go:287: Failed to put "EXISTING" to "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'": unexpected status code from upload create: 429 --- FAIL: TestSyncIgnoreCase (0.37s) === RUN TestSyncConcurrentDelete run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" run.go:287: Failed to put "both0" to "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'": unexpected status code from upload create: 429 --- FAIL: TestSyncConcurrentDelete (0.66s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'", Local "Local file system at /tmp/rclone1356309219", Modify Window "1s" run.go:287: Failed to put "both0" to "Putio root 'rclone-test-xowewug8xodafak8bojamuq3'": unexpected status code from upload create: 429 --- FAIL: TestSyncConcurrentTruncate (0.57s) FAIL 2022/03/15 05:35:30 DEBUG : Putio root 'rclone-test-xowewug8xodafak8bojamuq3': Purge remote "./sync.test -test.v -test.timeout 2h0m0s -remote TestPutio: -verbose -test.run '^(TestServerSideCopy|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCompareDest|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncCopyDest|TestSyncIgnoreCase|TestSyncImmutable|TestSyncMultipleCompareDest|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncUTFNorm)$'" - Finished ERROR in 2m15.471132834s (try 2/5): exit status 1: Failed [TestServerSideCopy TestSyncCopyDest TestSyncSuffix TestSyncSuffixKeepExtension TestSyncUTFNorm TestSyncImmutable TestSyncIgnoreCase TestSyncConcurrentDelete TestSyncConcurrentTruncate]