"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list -test.run '^(TestCopyFileBackupDir|TestCopyFileCopyDest|TestDirMove|TestMoveFileBackupDir|TestMultithreadCopy|TestMultithreadCopyAbort|TestTouchDir)$'" - Starting (try 2/5)
2025/11/02 04:29:22 DEBUG : Creating backend with remote "TestB2:rclone-test-dikihoh7xube"
2025/11/02 04:29:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/02 04:29:23 DEBUG : Creating backend with remote "/tmp/rclone2306553806"
2025/11/02 04:29:23 DEBUG : Config file has changed externally - reloading
=== RUN   TestMultithreadCopy
    run.go:185: Remote "B2 bucket rclone-test-dikihoh7xube", Local "Local file system at /tmp/rclone2306553806", Modify Window "1ms"
    multithread_test.go:137: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:137
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:148
        	Error:      	Received unexpected error:
        	            	expected a FileSseMode but found: '' (400 bad_request)
        	Test:       	TestMultithreadCopy
--- FAIL: TestMultithreadCopy (1.39s)
=== RUN   TestMultithreadCopyAbort
    run.go:185: Remote "B2 bucket rclone-test-dikihoh7xube", Local "Local file system at /tmp/rclone2306553806", Modify Window "1ms"
    multithread_test.go:137: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:137
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:293
        	Error:      	Received unexpected error:
        	            	expected a FileSseMode but found: '' (400 bad_request)
        	Test:       	TestMultithreadCopyAbort
--- FAIL: TestMultithreadCopyAbort (0.54s)
=== RUN   TestCopyFileBackupDir
    run.go:185: Remote "B2 bucket rclone-test-dikihoh7xube", Local "Local file system at /tmp/rclone2306553806", Modify Window "1ms"
2025/11/02 04:29:26 DEBUG : Creating backend with remote "TestB2:rclone-test-dikihoh7xube/backup"
2025/11/02 04:29:28 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2306553806)
2025/11/02 04:29:28 DEBUG : dst/file1: size = 18 (B2 bucket rclone-test-dikihoh7xube)
2025/11/02 04:29:28 DEBUG : dst/file1: Sizes differ
2025/11/02 04:29:29 ERROR : dst/file1: Failed to copy: expected a FileSseMode but found: '' (400 bad_request)
2025/11/02 04:29:29 ERROR : dst/file1: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request)
    copy_test.go:186: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:186
        	Error:      	Received unexpected error:
        	            	moving to --backup-dir failed: expected a FileSseMode but found: '' (400 bad_request)
        	Test:       	TestCopyFileBackupDir
--- FAIL: TestCopyFileBackupDir (4.50s)
=== RUN   TestCopyFileCopyDest
    run.go:185: Remote "B2 bucket rclone-test-dikihoh7xube", Local "Local file system at /tmp/rclone2306553806", Modify Window "1ms"
2025/11/02 04:29:29 DEBUG : Creating backend with remote "TestB2:rclone-test-dikihoh7xube/dst"
2025/11/02 04:29:31 DEBUG : Creating backend with remote "TestB2:rclone-test-dikihoh7xube/CopyDest"
2025/11/02 04:29:32 DEBUG : one: Need to transfer - File not found at Destination
2025/11/02 04:29:34 DEBUG : one: size = 3 OK
2025/11/02 04:29:34 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2025/11/02 04:29:34 INFO  : one: Copied (new)
2025/11/02 04:29:34 DEBUG : one: size = 5 (Local file system at /tmp/rclone2306553806)
2025/11/02 04:29:34 DEBUG : one: size = 3 (B2 bucket rclone-test-dikihoh7xube path dst)
2025/11/02 04:29:34 DEBUG : one: Sizes differ
2025/11/02 04:29:35 DEBUG : one: size = 5 OK
2025/11/02 04:29:35 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK
2025/11/02 04:29:35 INFO  : one: Copied (replaced existing)
2025/11/02 04:29:37 DEBUG : Creating backend with remote "TestB2:rclone-test-dikihoh7xube/BackupDir"
2025/11/02 04:29:38 DEBUG : one: size = 5 (Local file system at /tmp/rclone2306553806)
2025/11/02 04:29:38 DEBUG : one: size = 3 (B2 bucket rclone-test-dikihoh7xube path dst)
2025/11/02 04:29:38 DEBUG : one: Sizes differ
2025/11/02 04:29:38 DEBUG : one: size = 5 OK
2025/11/02 04:29:38 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2025/11/02 04:29:38 DEBUG : one: size = 5 (Local file system at /tmp/rclone2306553806)
2025/11/02 04:29:38 DEBUG : one: size = 3 (B2 bucket rclone-test-dikihoh7xube path dst)
2025/11/02 04:29:38 DEBUG : one: Sizes differ
2025/11/02 04:29:39 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request)
2025/11/02 04:29:39 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request)
    copy_test.go:321: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:321
        	Error:      	Received unexpected error:
        	            	moving to --backup-dir failed: expected a FileSseMode but found: '' (400 bad_request)
        	Test:       	TestCopyFileCopyDest
--- FAIL: TestCopyFileCopyDest (10.61s)
=== RUN   TestMoveFileBackupDir
    run.go:185: Remote "B2 bucket rclone-test-dikihoh7xube", Local "Local file system at /tmp/rclone2306553806", Modify Window "1ms"
2025/11/02 04:29:41 DEBUG : Creating backend with remote "TestB2:rclone-test-dikihoh7xube/backup"
2025/11/02 04:29:42 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2306553806)
2025/11/02 04:29:42 DEBUG : dst/file1: size = 18 (B2 bucket rclone-test-dikihoh7xube)
2025/11/02 04:29:42 DEBUG : dst/file1: Sizes differ
2025/11/02 04:29:43 ERROR : dst/file1: Failed to copy: expected a FileSseMode but found: '' (400 bad_request)
2025/11/02 04:29:43 ERROR : dst/file1: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request)
    operations_test.go:1052: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1052
        	Error:      	Received unexpected error:
        	            	moving to --backup-dir failed: expected a FileSseMode but found: '' (400 bad_request)
        	Test:       	TestMoveFileBackupDir
--- FAIL: TestMoveFileBackupDir (3.96s)
=== RUN   TestDirMove
    run.go:185: Remote "B2 bucket rclone-test-dikihoh7xube", Local "Local file system at /tmp/rclone2306553806", Modify Window "1ms"
2025/11/02 04:29:47 INFO  : A1/B2: Making directory
2025/11/02 04:29:47 INFO  : A1/B1/C3: Making directory
    fstest.go:250: Filtering empty directory "A1/B2"
    fstest.go:250: Filtering empty directory "A1/B1/C3"
2025/11/02 04:29:48 ERROR : A1/B1/C1/four: Failed to copy: expected a FileSseMode but found: '' (400 bad_request)
2025/11/02 04:29:48 ERROR : A1/B1/C1/four: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request)
2025/11/02 04:29:48 ERROR : A1/B1/three: Failed to copy: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request)
2025/11/02 04:29:48 ERROR : A1/B1/three: Not deleting source as copy failed: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request)
2025/11/02 04:29:48 ERROR : A1/one: Failed to copy: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request)
2025/11/02 04:29:48 ERROR : A1/one: Not deleting source as copy failed: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request)
2025/11/02 04:29:48 ERROR : A1/B1/C2/five: Failed to copy: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request)
2025/11/02 04:29:48 ERROR : A1/B1/C2/five: Not deleting source as copy failed: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request)
2025/11/02 04:29:48 ERROR : A1/two: Failed to copy: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request)
2025/11/02 04:29:48 ERROR : A1/two: Not deleting source as copy failed: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request)
    operations_test.go:1388: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1388
        	Error:      	Received unexpected error:
        	            	RenameDir renames: expected a FileSseMode but found: '' (400 bad_request)
        	Test:       	TestDirMove
--- FAIL: TestDirMove (5.91s)
=== RUN   TestTouchDir
    run.go:185: Remote "B2 bucket rclone-test-dikihoh7xube", Local "Local file system at /tmp/rclone2306553806", Modify Window "1ms"
2025/11/02 04:29:51 DEBUG : B2 bucket rclone-test-dikihoh7xube: Touching "sub dir/potato3"
2025/11/02 04:29:51 DEBUG : B2 bucket rclone-test-dikihoh7xube: Touching "empty space"
2025/11/02 04:29:51 DEBUG : B2 bucket rclone-test-dikihoh7xube: Touching "potato2"
2025/11/02 04:29:52 ERROR : sub dir/potato3: failed to touch: expected a FileSseMode but found: '' (400 bad_request)
2025/11/02 04:29:52 ERROR : empty space: failed to touch: expected a FileSseMode but found: '' (400 bad_request)
2025/11/02 04:29:52 ERROR : potato2: failed to touch: expected a FileSseMode but found: '' (400 bad_request)
    operations_test.go:1687: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1687
        	Error:      	Should be true
        	Test:       	TestTouchDir
--- FAIL: TestTouchDir (2.78s)
FAIL
2025/11/02 04:29:53 DEBUG : B2 bucket rclone-test-dikihoh7xube: Purge remote
2025/11/02 04:29:53 INFO  : B2 bucket rclone-test-dikihoh7xube: cleaning bucket "rclone-test-dikihoh7xube" of all files
2025/11/02 04:29:53 DEBUG : A1/B1/C1/four: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f423e001ce827fe55_d20251102_m042949_c001_v7007000_t0000_u01762057789253")
2025/11/02 04:29:53 DEBUG : A1/B1/C1/four: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f10341e7e644de3fd_d20251102_m042946_c001_v0001105_t0041_u01762057786759")
2025/11/02 04:29:53 DEBUG : A1/B1/C2/five: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f423e001ce827fe57_d20251102_m042949_c001_v7007000_t0000_u01762057789436")
2025/11/02 04:29:53 DEBUG : A1/B1/C2/five: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f10341e7e644de3ff_d20251102_m042947_c001_v0001105_t0015_u01762057787187")
2025/11/02 04:29:53 DEBUG : A1/B1/three: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f423e001ce827fe59_d20251102_m042949_c001_v7007000_t0000_u01762057789621")
2025/11/02 04:29:53 DEBUG : A1/B1/three: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f10341e7e644de3f9_d20251102_m042945_c001_v0001105_t0029_u01762057785293")
2025/11/02 04:29:53 DEBUG : A1/one: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f423e001ce827fe5b_d20251102_m042949_c001_v7007000_t0000_u01762057789804")
2025/11/02 04:29:53 DEBUG : A1/one: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f10341e7e644de3ef_d20251102_m042944_c001_v0001105_t0000_u01762057784442")
2025/11/02 04:29:53 DEBUG : A1/two: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f423e001ce827fe5d_d20251102_m042949_c001_v7007000_t0000_u01762057789988")
2025/11/02 04:29:53 DEBUG : A1/two: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f10341e7e644de3f5_d20251102_m042944_c001_v0001105_t0038_u01762057784852")
2025/11/02 04:29:53 DEBUG : CopyDest/one: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f40157fc1b2eb51d3_d20251102_m042939_c001_v7007000_t0000_u01762057779938")
2025/11/02 04:29:53 DEBUG : CopyDest/one: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f10341e7e644de3c6_d20251102_m042936_c001_v0001105_t0027_u01762057776426")
2025/11/02 04:29:53 DEBUG : dst/file1: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f40157fc1b2eb51ec_d20251102_m042944_c001_v7007000_t0000_u01762057784076")
2025/11/02 04:29:53 DEBUG : dst/file1: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f10341e7e644de3d3_d20251102_m042940_c001_v0001105_t0020_u01762057780493")
2025/11/02 04:29:53 DEBUG : dst/file1: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f40157fc1b2eb518f_d20251102_m042929_c001_v7007000_t0000_u01762057769506")
2025/11/02 04:29:53 DEBUG : dst/file1: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f10341e7e644de3a1_d20251102_m042925_c001_v0001105_t0019_u01762057765923")
2025/11/02 04:29:53 DEBUG : dst/one: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f40157fc1b2eb51d6_d20251102_m042940_c001_v7007000_t0000_u01762057780121")
2025/11/02 04:29:53 DEBUG : dst/one: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f10341e7e644de3c2_d20251102_m042935_c001_v0001105_t0048_u01762057775931")
2025/11/02 04:29:53 DEBUG : dst/one: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f105ff7ac03e61bce_d20251102_m042934_c001_v0001172_t0005_u01762057774871")
2025/11/02 04:29:53 DEBUG : dst/one: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f105ff7ac03e61bcc_d20251102_m042933_c001_v0001172_t0057_u01762057773775")
2025/11/02 04:29:53 DEBUG : empty space: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f409b81d62475919a_d20251102_m042952_c001_v7007000_t0000_u01762057792391")
2025/11/02 04:29:54 DEBUG : empty space: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f10341e7e644de40f_d20251102_m042950_c001_v0001105_t0021_u01762057790751")
2025/11/02 04:29:54 DEBUG : potato2: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f409b81d62475919b_d20251102_m042952_c001_v7007000_t0000_u01762057792573")
2025/11/02 04:29:54 DEBUG : potato2: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f10341e7e644de40b_d20251102_m042950_c001_v0001105_t0045_u01762057790354")
2025/11/02 04:29:54 DEBUG : sub dir/potato3: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f409b81d62475919c_d20251102_m042952_c001_v7007000_t0000_u01762057792768")
2025/11/02 04:29:54 DEBUG : sub dir/potato3: Deleting (id "4_z0f02bdc3d03d4b2b9fa80e1b_f10341e7e644de411_d20251102_m042951_c001_v0001105_t0054_u01762057791156")
"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list -test.run '^(TestCopyFileBackupDir|TestCopyFileCopyDest|TestDirMove|TestMoveFileBackupDir|TestMultithreadCopy|TestMultithreadCopyAbort|TestTouchDir)$'" - Finished ERROR in 32.845708556s (try 2/5): exit status 1: Failed [TestMultithreadCopy TestMultithreadCopyAbort TestCopyFileBackupDir TestCopyFileCopyDest TestMoveFileBackupDir TestDirMove TestTouchDir]