"./operations.test -test.v -test.timeout 2h0m0s -remote TestPutio: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileCopyDest)$'" - Starting (try 2/5) 2021/11/13 05:56:06 DEBUG : Creating backend with remote "TestPutio:rclone-test-rotayaz7nenesur7vudorem1" 2021/11/13 05:56:06 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/11/13 05:56:06 DEBUG : Creating backend with remote "/tmp/rclone2801236161" === RUN TestCheckSumDownload run.go:181: Remote "Putio root 'rclone-test-rotayaz7nenesur7vudorem1'", Local "Local file system at /tmp/rclone2801236161", Modify Window "1s" 2021/11/13 05:56:06 DEBUG : Creating backend with remote "TestPutio:rclone-test-rotayaz7nenesur7vudorem1/data" 2021/11/13 05:56:43 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': chunkStart: 0, reqSize: 13 2021/11/13 05:56:43 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': Sending chunk. transferOffset: 0 length: 13 2021/11/13 05:57:29 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': chunkStart: 0, reqSize: 41 2021/11/13 05:57:29 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': Sending chunk. transferOffset: 0 length: 41 === RUN TestCheckSumDownload/subtest1 2021/11/13 05:58:04 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': chunkStart: 0, reqSize: 15 2021/11/13 05:58:04 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': Sending chunk. transferOffset: 0 length: 15 2021/11/13 05:58:39 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': chunkStart: 0, reqSize: 41 2021/11/13 05:58:39 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': Sending chunk. transferOffset: 0 length: 41 === RUN TestCheckSumDownload/subtest2 2021/11/13 05:59:12 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': chunkStart: 0, reqSize: 82 2021/11/13 05:59:12 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': Sending chunk. transferOffset: 0 length: 82 === RUN TestCheckSumDownload/subtest3 2021/11/13 05:59:46 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': chunkStart: 0, reqSize: 82 2021/11/13 05:59:46 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': Sending chunk. transferOffset: 0 length: 82 === RUN TestCheckSumDownload/subtest4 2021/11/13 06:00:22 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': chunkStart: 0, reqSize: 123 2021/11/13 06:00:22 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': Sending chunk. transferOffset: 0 length: 123 === RUN TestCheckSumDownload/subtest5 2021/11/13 06:00:59 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': chunkStart: 0, reqSize: 123 2021/11/13 06:00:59 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': Sending chunk. transferOffset: 0 length: 123 === RUN TestCheckSumDownload/subtest6 2021/11/13 06:01:37 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': chunkStart: 0, reqSize: 13 2021/11/13 06:01:37 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': Sending chunk. transferOffset: 0 length: 13 2021/11/13 06:02:10 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': chunkStart: 0, reqSize: 15 2021/11/13 06:02:10 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': Sending chunk. transferOffset: 0 length: 15 2021/11/13 06:02:42 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': chunkStart: 0, reqSize: 82 2021/11/13 06:02:42 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': Sending chunk. transferOffset: 0 length: 82 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (400.72s) --- PASS: TestCheckSumDownload/subtest1 (1.58s) --- PASS: TestCheckSumDownload/subtest2 (1.31s) --- PASS: TestCheckSumDownload/subtest3 (1.11s) --- PASS: TestCheckSumDownload/subtest4 (1.16s) --- PASS: TestCheckSumDownload/subtest5 (1.06s) --- PASS: TestCheckSumDownload/subtest6 (1.32s) --- PASS: TestCheckSumDownload/subtest7 (2.66s) === RUN TestCopyFileCopyDest run.go:181: Remote "Putio root 'rclone-test-rotayaz7nenesur7vudorem1'", Local "Local file system at /tmp/rclone2801236161", Modify Window "1s" 2021/11/13 06:02:47 DEBUG : Creating backend with remote "TestPutio:rclone-test-rotayaz7nenesur7vudorem1/dst" 2021/11/13 06:02:47 DEBUG : Config file has changed externaly - reloading 2021/11/13 06:02:47 DEBUG : Creating backend with remote "TestPutio:rclone-test-rotayaz7nenesur7vudorem1/CopyDest" 2021/11/13 06:02:48 DEBUG : one: Need to transfer - File not found at Destination 2021/11/13 06:03:18 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1/dst': chunkStart: 0, reqSize: 3 2021/11/13 06:03:18 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1/dst': Sending chunk. transferOffset: 0 length: 3 2021/11/13 06:03:19 DEBUG : one: crc32 = 7a6c86f1 OK 2021/11/13 06:03:19 INFO : one: Copied (new) 2021/11/13 06:03:19 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/11/13 06:03:50 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1/dst': chunkStart: 0, reqSize: 5 2021/11/13 06:03:50 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1/dst': Sending chunk. transferOffset: 0 length: 5 2021/11/13 06:03:50 DEBUG : one: crc32 = cb217308 OK 2021/11/13 06:03:50 INFO : one: Copied (replaced existing) 2021/11/13 06:04:21 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': chunkStart: 0, reqSize: 3 2021/11/13 06:04:21 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': Sending chunk. transferOffset: 0 length: 3 2021/11/13 06:04:59 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': chunkStart: 0, reqSize: 5 2021/11/13 06:04:59 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': Sending chunk. transferOffset: 0 length: 5 2021/11/13 06:05:02 DEBUG : Creating backend with remote "TestPutio:rclone-test-rotayaz7nenesur7vudorem1/BackupDir" 2021/11/13 06:05:02 DEBUG : Config file has changed externaly - reloading 2021/11/13 06:05:02 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/11/13 06:05:02 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/11/13 06:05:03 INFO : one: Moved (server-side) 2021/11/13 06:05:03 ERROR : one: Failed to copy: unexpected status code (400) response while doing POST to https://api.put.io/v2/files/copy 2021/11/13 06:05:03 ERROR : one: Destination found in --copy-dest, error copying 2021/11/13 06:05:03 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/11/13 06:05:04 INFO : one: Deleted 2021/11/13 06:05:04 ERROR : one: Couldn't move: object not found operations_test.go:1114: Error Trace: operations_test.go:1114 Error: Received unexpected error: moving to --backup-dir failed: object not found Test: TestCopyFileCopyDest --- FAIL: TestCopyFileCopyDest (138.32s) FAIL 2021/11/13 06:05:05 DEBUG : Putio root 'rclone-test-rotayaz7nenesur7vudorem1': Purge remote "./operations.test -test.v -test.timeout 2h0m0s -remote TestPutio: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileCopyDest)$'" - Finished ERROR in 8m59.583509702s (try 2/5): exit status 1: Failed [TestCopyFileCopyDest]