"./operations.test -test.v -test.timeout 2h0m0s -remote TestPutio: -verbose -test.run '^(TestCopyFile|TestCopyFileBackupDir|TestCopyFileCompareDest|TestCopyFileCopyDest|TestCopyFileMaxTransfer|TestCopyURL|TestDirMove|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestPurge|TestRcat|TestRcatSize|TestRmdirsNoLeaveRoot|TestTouchDir)$/^withChecksum=false,ignoreChecksum=false$'" - Starting (try 2/5) 2022/03/03 05:11:00 DEBUG : Creating backend with remote "TestPutio:rclone-test-dotezos1codohov9bopaseq5" 2022/03/03 05:11:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/03/03 05:11:00 DEBUG : Creating backend with remote "/tmp/rclone161752194" === RUN TestPurge 2022/03/03 05:11:00 DEBUG : Creating backend with remote "TestPutio:rclone-test-xofuteq4gokovip0qaziguh4" 2022/03/03 05:11:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/03/03 05:11:00 DEBUG : Creating backend with remote "/tmp/rclone292274028" run.go:181: Remote "Putio root 'rclone-test-xofuteq4gokovip0qaziguh4'", Local "Local file system at /tmp/rclone292274028", Modify Window "1s" 2022/03/03 05:11:02 DEBUG : Putio root 'rclone-test-xofuteq4gokovip0qaziguh4': chunkStart: 0, reqSize: 3 2022/03/03 05:11:02 DEBUG : Putio root 'rclone-test-xofuteq4gokovip0qaziguh4': Sending chunk. transferOffset: 0 length: 3 2022/03/03 05:11:03 DEBUG : A2: Making directory 2022/03/03 05:11:03 DEBUG : A1/B2: Making directory 2022/03/03 05:11:03 DEBUG : A1/B2/C2: Making directory 2022/03/03 05:11:03 DEBUG : A1/B1/C3: Making directory 2022/03/03 05:11:03 DEBUG : A3: Making directory 2022/03/03 05:11:03 DEBUG : A3/B3: Making directory 2022/03/03 05:11:03 DEBUG : A3/B3/C4: Making directory 2022/03/03 05:11:04 DEBUG : Putio root 'rclone-test-xofuteq4gokovip0qaziguh4': chunkStart: 0, reqSize: 3 2022/03/03 05:11:04 DEBUG : Putio root 'rclone-test-xofuteq4gokovip0qaziguh4': Sending chunk. transferOffset: 0 length: 3 2022/03/03 05:11:06 ERROR : : error listing: directory not found 2022/03/03 05:11:06 DEBUG : Putio root 'rclone-test-xofuteq4gokovip0qaziguh4': Purge remote 2022/03/03 05:11:06 purge failed: Rmdir: directory not found --- PASS: TestPurge (5.35s) === RUN TestRmdirsNoLeaveRoot run.go:181: Remote "Putio root 'rclone-test-dotezos1codohov9bopaseq5'", Local "Local file system at /tmp/rclone161752194", Modify Window "1s" 2022/03/03 05:11:07 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': chunkStart: 0, reqSize: 3 2022/03/03 05:11:07 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': Sending chunk. transferOffset: 0 length: 3 2022/03/03 05:11:07 DEBUG : A2: Making directory 2022/03/03 05:11:08 DEBUG : A1/B2: Making directory 2022/03/03 05:11:08 DEBUG : A1/B2/C2: Making directory 2022/03/03 05:11:08 DEBUG : A1/B1/C3: Making directory 2022/03/03 05:11:08 DEBUG : A3: Making directory 2022/03/03 05:11:08 DEBUG : A3/B3: Making directory 2022/03/03 05:11:08 DEBUG : A3/B3/C4: Making directory 2022/03/03 05:11:09 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': chunkStart: 0, reqSize: 3 2022/03/03 05:11:09 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': Sending chunk. transferOffset: 0 length: 3 2022/03/03 05:11:10 INFO : A3/B3/C4: Removing directory 2022/03/03 05:11:11 INFO : A3/B3: Removing directory 2022/03/03 05:11:11 INFO : A3: Removing directory 2022/03/03 05:11:12 INFO : A2: Removing directory 2022/03/03 05:11:13 INFO : A1/B2/C2: Removing directory 2022/03/03 05:11:13 INFO : A1/B2: Removing directory 2022/03/03 05:11:13 INFO : A1/B1/C3: Removing directory --- PASS: TestRmdirsNoLeaveRoot (10.01s) === RUN TestCopyURL run.go:181: Remote "Putio root 'rclone-test-dotezos1codohov9bopaseq5'", Local "Local file system at /tmp/rclone161752194", Modify Window "1s" 2022/03/03 05:11:16 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': chunkStart: 0, reqSize: 14 2022/03/03 05:11:16 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': Sending chunk. transferOffset: 0 length: 14 2022/03/03 05:11:17 DEBUG : filename.txt: File name found in url 2022/03/03 05:11:17 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': chunkStart: 0, reqSize: 14 2022/03/03 05:11:17 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': Sending chunk. transferOffset: 0 length: 14 2022/03/03 05:11:19 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': chunkStart: 0, reqSize: 14 2022/03/03 05:11:19 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': Sending chunk. transferOffset: 0 length: 14 --- PASS: TestCopyURL (5.61s) === RUN TestMoveFile run.go:181: Remote "Putio root 'rclone-test-dotezos1codohov9bopaseq5'", Local "Local file system at /tmp/rclone161752194", Modify Window "1s" 2022/03/03 05:11:21 DEBUG : file1: Need to transfer - File not found at Destination 2022/03/03 05:11:22 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': chunkStart: 0, reqSize: 14 2022/03/03 05:11:22 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': Sending chunk. transferOffset: 0 length: 14 2022/03/03 05:11:23 DEBUG : file1: crc32 = f401d3a6 OK 2022/03/03 05:11:23 INFO : file1: Copied (new) to: sub/file2 2022/03/03 05:11:23 INFO : file1: Deleted 2022/03/03 05:11:23 DEBUG : file1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/03/03 05:11:23 DEBUG : file1: Unchanged skipping 2022/03/03 05:11:23 INFO : file1: Deleted 2022/03/03 05:11:23 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (2.55s) === RUN TestMoveFileWithIgnoreExisting run.go:181: Remote "Putio root 'rclone-test-dotezos1codohov9bopaseq5'", Local "Local file system at /tmp/rclone161752194", Modify Window "1s" 2022/03/03 05:11:24 DEBUG : file1: Need to transfer - File not found at Destination 2022/03/03 05:11:24 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': chunkStart: 0, reqSize: 14 2022/03/03 05:11:24 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': Sending chunk. transferOffset: 0 length: 14 2022/03/03 05:11:25 DEBUG : file1: crc32 = f401d3a6 OK 2022/03/03 05:11:25 INFO : file1: Copied (new) 2022/03/03 05:11:25 INFO : file1: Deleted 2022/03/03 05:11:25 DEBUG : file1: Destination exists, skipping 2022/03/03 05:11:25 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.32s) === RUN TestMoveFileBackupDir run.go:181: Remote "Putio root 'rclone-test-dotezos1codohov9bopaseq5'", Local "Local file system at /tmp/rclone161752194", Modify Window "1s" 2022/03/03 05:11:26 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': chunkStart: 0, reqSize: 18 2022/03/03 05:11:26 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': Sending chunk. transferOffset: 0 length: 18 2022/03/03 05:11:28 DEBUG : Creating backend with remote "TestPutio:rclone-test-dotezos1codohov9bopaseq5/backup" 2022/03/03 05:11:29 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2022/03/03 05:11:29 INFO : dst/file1: Moved (server-side) 2022/03/03 05:11:30 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': chunkStart: 0, reqSize: 14 2022/03/03 05:11:30 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': Sending chunk. transferOffset: 0 length: 14 2022/03/03 05:11:31 DEBUG : dst/file1: crc32 = f401d3a6 OK 2022/03/03 05:11:31 INFO : dst/file1: Copied (new) 2022/03/03 05:11:31 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (6.61s) === RUN TestCopyFile run.go:181: Remote "Putio root 'rclone-test-dotezos1codohov9bopaseq5'", Local "Local file system at /tmp/rclone161752194", Modify Window "1s" 2022/03/03 05:11:32 DEBUG : file1: Need to transfer - File not found at Destination 2022/03/03 05:11:33 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': chunkStart: 0, reqSize: 14 2022/03/03 05:11:33 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': Sending chunk. transferOffset: 0 length: 14 2022/03/03 05:11:33 DEBUG : file1: crc32 = f401d3a6 OK 2022/03/03 05:11:33 INFO : file1: Copied (new) to: sub/file2 2022/03/03 05:11:33 DEBUG : file1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/03/03 05:11:33 DEBUG : file1: Unchanged skipping 2022/03/03 05:11:33 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.37s) === RUN TestCopyFileBackupDir run.go:181: Remote "Putio root 'rclone-test-dotezos1codohov9bopaseq5'", Local "Local file system at /tmp/rclone161752194", Modify Window "1s" 2022/03/03 05:11:35 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': chunkStart: 0, reqSize: 18 2022/03/03 05:11:35 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': Sending chunk. transferOffset: 0 length: 18 2022/03/03 05:11:35 DEBUG : Creating backend with remote "TestPutio:rclone-test-dotezos1codohov9bopaseq5/backup" 2022/03/03 05:11:36 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2022/03/03 05:11:36 INFO : dst/file1: Moved (server-side) 2022/03/03 05:11:37 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': chunkStart: 0, reqSize: 14 2022/03/03 05:11:37 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': Sending chunk. transferOffset: 0 length: 14 2022/03/03 05:11:44 DEBUG : dst/file1: crc32 = f401d3a6 OK 2022/03/03 05:11:44 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (11.15s) === RUN TestCopyFileCompareDest run.go:181: Remote "Putio root 'rclone-test-dotezos1codohov9bopaseq5'", Local "Local file system at /tmp/rclone161752194", Modify Window "1s" 2022/03/03 05:11:45 DEBUG : Creating backend with remote "TestPutio:rclone-test-dotezos1codohov9bopaseq5/dst" 2022/03/03 05:11:46 DEBUG : Creating backend with remote "TestPutio:rclone-test-dotezos1codohov9bopaseq5/CompareDest" 2022/03/03 05:11:46 DEBUG : one: Need to transfer - File not found at Destination 2022/03/03 05:11:47 ERROR : one: Failed to copy: unexpected status code from upload create: 429 operations_test.go:999: Error Trace: operations_test.go:999 Error: Received unexpected error: unexpected status code from upload create: 429 Test: TestCopyFileCompareDest --- FAIL: TestCopyFileCompareDest (1.60s) === RUN TestCopyFileCopyDest run.go:181: Remote "Putio root 'rclone-test-dotezos1codohov9bopaseq5'", Local "Local file system at /tmp/rclone161752194", Modify Window "1s" 2022/03/03 05:11:47 DEBUG : Creating backend with remote "TestPutio:rclone-test-dotezos1codohov9bopaseq5/dst" 2022/03/03 05:11:47 DEBUG : Creating backend with remote "TestPutio:rclone-test-dotezos1codohov9bopaseq5/CopyDest" 2022/03/03 05:11:48 DEBUG : one: Need to transfer - File not found at Destination 2022/03/03 05:11:48 ERROR : one: Failed to copy: unexpected status code from upload create: 429 operations_test.go:1083: Error Trace: operations_test.go:1083 Error: Received unexpected error: unexpected status code from upload create: 429 Test: TestCopyFileCopyDest --- FAIL: TestCopyFileCopyDest (1.47s) === RUN TestDirMove run.go:181: Remote "Putio root 'rclone-test-dotezos1codohov9bopaseq5'", Local "Local file system at /tmp/rclone161752194", Modify Window "1s" run.go:287: Failed to put "A1/one" to "Putio root 'rclone-test-dotezos1codohov9bopaseq5'": unexpected status code from upload create: 429 --- FAIL: TestDirMove (0.66s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false === CONT TestRcat run.go:181: Remote "Putio root 'rclone-test-dotezos1codohov9bopaseq5'", Local "Local file system at /tmp/rclone161752194", Modify Window "1s" 2022/03/03 05:11:49 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': File to upload is small (34 bytes), uploading instead of streaming 2022/03/03 05:11:49 ERROR : no_checksum_small_file_from_pipe: Failed to copy: unexpected status code from upload create: 429 operations_test.go:1509: Error Trace: operations_test.go:1509 operations_test.go:1524 Error: Received unexpected error: unexpected status code from upload create: 429 Test: TestRcat === CONT TestRcat/withChecksum=false,ignoreChecksum=false testing.go:1169: test executed panic(nil) or runtime.Goexit: subtest may have called FailNow on a parent test --- FAIL: TestRcat (0.44s) --- FAIL: TestRcat/withChecksum=false,ignoreChecksum=false (0.44s) === RUN TestRcatSize run.go:181: Remote "Putio root 'rclone-test-dotezos1codohov9bopaseq5'", Local "Local file system at /tmp/rclone161752194", Modify Window "1s" 2022/03/03 05:11:50 ERROR : potato1: Post request put error: unexpected status code from upload create: 429 operations_test.go:1540: Error Trace: operations_test.go:1540 Error: Received unexpected error: unexpected status code from upload create: 429 Test: TestRcatSize --- FAIL: TestRcatSize (0.34s) === RUN TestCopyFileMaxTransfer run.go:181: Remote "Putio root 'rclone-test-dotezos1codohov9bopaseq5'", Local "Local file system at /tmp/rclone161752194", Modify Window "1s" 2022/03/03 05:11:50 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2022/03/03 05:11:50 ERROR : TestCopyFileMaxTransfer/file1: Failed to copy: unexpected status code from upload create: 429 operations_test.go:1583: Error Trace: operations_test.go:1583 Error: Received unexpected error: unexpected status code from upload create: 429 Test: TestCopyFileMaxTransfer --- FAIL: TestCopyFileMaxTransfer (0.83s) === RUN TestTouchDir run.go:181: Remote "Putio root 'rclone-test-dotezos1codohov9bopaseq5'", Local "Local file system at /tmp/rclone161752194", Modify Window "1s" run.go:287: Failed to put "potato2" to "Putio root 'rclone-test-dotezos1codohov9bopaseq5'": unexpected status code from upload create: 429 --- FAIL: TestTouchDir (0.36s) FAIL 2022/03/03 05:11:51 DEBUG : Putio root 'rclone-test-dotezos1codohov9bopaseq5': Purge remote "./operations.test -test.v -test.timeout 2h0m0s -remote TestPutio: -verbose -test.run '^(TestCopyFile|TestCopyFileBackupDir|TestCopyFileCompareDest|TestCopyFileCopyDest|TestCopyFileMaxTransfer|TestCopyURL|TestDirMove|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestPurge|TestRcat|TestRcatSize|TestRmdirsNoLeaveRoot|TestTouchDir)$/^withChecksum=false,ignoreChecksum=false$'" - Finished ERROR in 51.136145582s (try 2/5): exit status 1: Failed [TestCopyFileCompareDest TestCopyFileCopyDest TestDirMove TestRcat/withChecksum=false,ignoreChecksum=false TestRcatSize TestCopyFileMaxTransfer TestTouchDir]