"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -verbose -fast-list -test.run '^(TestCheckDownload|TestHashSums|TestMoveFileBackupDir|TestRcat)$'" - Starting (try 2/5)
=== RUN   TestHashSums
--- PASS: TestHashSums (4.05s)
    run.go:176: Remote "B2 bucket rclone-test-howemat9puresat9kavovuc2", Local "Local file system at /tmp/rclone951944570", Modify Window "1ms"
=== RUN   TestCheckDownload
2020/05/15 05:57:35 DEBUG : B2 bucket rclone-test-howemat9puresat9kavovuc2: 1: Starting check test
2020/05/15 05:57:36 DEBUG : B2 bucket rclone-test-howemat9puresat9kavovuc2: 2: Starting check test
2020/05/15 05:57:36 DEBUG : B2 bucket rclone-test-howemat9puresat9kavovuc2: 3: Starting check test
2020/05/15 05:57:37 DEBUG : B2 bucket rclone-test-howemat9puresat9kavovuc2: 4: Starting check test
2020/05/15 05:57:38 DEBUG : B2 bucket rclone-test-howemat9puresat9kavovuc2: 5: Starting check test
2020/05/15 05:57:39 DEBUG : B2 bucket rclone-test-howemat9puresat9kavovuc2: 6: Starting check test
2020/05/15 05:57:39 DEBUG : B2 bucket rclone-test-howemat9puresat9kavovuc2: 7: Starting check test
--- PASS: TestCheckDownload (7.53s)
    run.go:176: Remote "B2 bucket rclone-test-howemat9puresat9kavovuc2", Local "Local file system at /tmp/rclone951944570", Modify Window "1ms"
=== RUN   TestMoveFileBackupDir
2020/05/15 05:57:44 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18)
2020/05/15 05:57:44 DEBUG : dst/file1: SHA-1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK
2020/05/15 05:57:44 INFO  : dst/file1: Copied (server side copy)
2020/05/15 05:57:45 INFO  : dst/file1: Deleted
2020/05/15 05:57:45 DEBUG : dst/file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK
2020/05/15 05:57:45 INFO  : dst/file1: Copied (new)
2020/05/15 05:57:45 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (4.39s)
    run.go:176: Remote "B2 bucket rclone-test-howemat9puresat9kavovuc2", Local "Local file system at /tmp/rclone951944570", Modify Window "1ms"
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
2020/05/15 05:57:47 DEBUG : B2 bucket rclone-test-howemat9puresat9kavovuc2: File to upload is small (34 bytes), uploading instead of streaming
2020/05/15 05:57:47 DEBUG : no_checksum_small_file_from_pipe: SHA-1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK
2020/05/15 05:57:47 INFO  : no_checksum_small_file_from_pipe: Copied (new)
2020/05/15 05:57:47 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload.
2020/05/15 05:57:48 DEBUG : no_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=false
2020/05/15 05:57:49 DEBUG : B2 bucket rclone-test-howemat9puresat9kavovuc2: File to upload is small (34 bytes), uploading instead of streaming
2020/05/15 05:57:50 DEBUG : with_checksum_small_file_from_pipe: SHA-1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK
2020/05/15 05:57:50 INFO  : with_checksum_small_file_from_pipe: Copied (new)
2020/05/15 05:57:50 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload.
2020/05/15 05:57:50 DEBUG : with_checksum_big_file_from_pipe: SHA-1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK
2020/05/15 05:57:50 DEBUG : with_checksum_big_file_from_pipe: Size and SHA-1 of src and dst objects identical
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=true
2020/05/15 05:57:52 DEBUG : B2 bucket rclone-test-howemat9puresat9kavovuc2: File to upload is small (34 bytes), uploading instead of streaming
2020/05/15 05:57:53 INFO  : ignore_checksum_small_file_from_pipe: Copied (new)
2020/05/15 05:57:53 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload.
2020/05/15 05:57:54 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=true
2020/05/15 05:57:55 DEBUG : B2 bucket rclone-test-howemat9puresat9kavovuc2: File to upload is small (34 bytes), uploading instead of streaming
2020/05/15 05:57:56 INFO  : ignore_checksum_small_file_from_pipe: Copied (new)
2020/05/15 05:57:56 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload.
2020/05/15 05:57:56 NOTICE: B2 bucket rclone-test-howemat9puresat9kavovuc2: --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2020/05/15 05:57:56 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (12.11s)
    run.go:176: Remote "B2 bucket rclone-test-howemat9puresat9kavovuc2", Local "Local file system at /tmp/rclone951944570", Modify Window "1ms"
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (2.72s)
    run.go:176: Remote "B2 bucket rclone-test-howemat9puresat9kavovuc2", Local "Local file system at /tmp/rclone951944570", Modify Window "1ms"
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (3.02s)
    run.go:176: Remote "B2 bucket rclone-test-howemat9puresat9kavovuc2", Local "Local file system at /tmp/rclone951944570", Modify Window "1ms"
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.86s)
    run.go:176: Remote "B2 bucket rclone-test-howemat9puresat9kavovuc2", Local "Local file system at /tmp/rclone951944570", Modify Window "1ms"
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (3.51s)
PASS
2020/05/15 05:57:59 DEBUG : B2 bucket rclone-test-howemat9puresat9kavovuc2: Purge remote
2020/05/15 05:57:59 DEBUG : backup/dst/file1: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f114cff2ee798017a_d20200515_m055745_c001_v0001136_t0013")
2020/05/15 05:57:59 DEBUG : backup/dst/file1: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f112c319c17eba6d5_d20200515_m055744_c001_v0001136_t0036")
2020/05/15 05:57:59 DEBUG : dst/file1: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f115235bdbb2db091_d20200515_m055746_c001_v0001134_t0005")
2020/05/15 05:57:59 DEBUG : dst/file1: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc50_d20200515_m055745_c001_v0001131_t0020")
2020/05/15 05:57:59 DEBUG : dst/file1: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f105163960383d9e9_d20200515_m055744_c001_v0001134_t0007")
2020/05/15 05:57:59 DEBUG : dst/file1: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc44_d20200515_m055742_c001_v0001131_t0020")
2020/05/15 05:57:59 DEBUG : empty space: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f110d5f6bbfa1c156_d20200515_m055740_c001_v0001134_t0042")
2020/05/15 05:57:59 DEBUG : empty space: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc2d_d20200515_m055736_c001_v0001131_t0020")
2020/05/15 05:57:59 DEBUG : empty space: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f118581612d3b57e5_d20200515_m055734_c001_v0001130_t0028")
2020/05/15 05:57:59 DEBUG : empty space: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc15_d20200515_m055733_c001_v0001131_t0020")
2020/05/15 05:57:59 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f11937c7da8624519_d20200515_m055757_c001_v0001134_t0054")
2020/05/15 05:57:59 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc82_d20200515_m055756_c001_v0001131_t0020")
2020/05/15 05:57:59 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f1002796a82608a57_d20200515_m055754_c001_v0001134_t0022")
2020/05/15 05:57:59 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc72_d20200515_m055753_c001_v0001131_t0020")
2020/05/15 05:57:59 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f103d0314a0cfb4d9_d20200515_m055758_c001_v0001137_t0041")
2020/05/15 05:57:59 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc80_d20200515_m055755_c001_v0001131_t0020")
2020/05/15 05:57:59 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f114768723e080a7b_d20200515_m055754_c001_v0001137_t0042")
2020/05/15 05:58:00 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc70_d20200515_m055752_c001_v0001131_t0020")
2020/05/15 05:58:00 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f108a74353681b990_d20200515_m055748_c001_v0001131_t0015")
2020/05/15 05:58:00 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc5c_d20200515_m055747_c001_v0001131_t0020")
2020/05/15 05:58:00 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10290d61e94a6597_d20200515_m055749_c001_v0001136_t0001")
2020/05/15 05:58:00 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc58_d20200515_m055747_c001_v0001131_t0020")
2020/05/15 05:58:00 DEBUG : potato2: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f1088ce6c1fafd7a6_d20200515_m055740_c001_v0001134_t0005")
2020/05/15 05:58:00 DEBUG : potato2: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc31_d20200515_m055737_c001_v0001131_t0020")
2020/05/15 05:58:00 DEBUG : potato2: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f113435d251bfce24_d20200515_m055734_c001_v0001134_t0043")
2020/05/15 05:58:00 DEBUG : potato2: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc10_d20200515_m055732_c001_v0001131_t0020")
2020/05/15 05:58:00 DEBUG : remotepotato: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f104f9a9ebc41c7b3_d20200515_m055741_c001_v0001134_t0039")
2020/05/15 05:58:00 DEBUG : remotepotato: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc3b_d20200515_m055738_c001_v0001131_t0020")
2020/05/15 05:58:00 DEBUG : rutabaga: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f106e7974c5980e5f_d20200515_m055741_c001_v0001137_t0013")
2020/05/15 05:58:00 DEBUG : rutabaga: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc21_d20200515_m055735_c001_v0001131_t0020")
2020/05/15 05:58:00 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f1017f79fa2638839_d20200515_m055751_c001_v0001134_t0026")
2020/05/15 05:58:00 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc66_d20200515_m055750_c001_v0001131_t0020")
2020/05/15 05:58:00 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f114516d2153c72b6_d20200515_m055751_c001_v0001128_t0040")
2020/05/15 05:58:00 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zef02bde3e08d1b6b7f280e1b_f10132c8234cebc60_d20200515_m055749_c001_v0001131_t0020")
"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -verbose -fast-list -test.run '^(TestCheckDownload|TestHashSums|TestMoveFileBackupDir|TestRcat)$'" - Finished OK in 31.139049812s (try 2/5)