"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileCopyDest|TestDirMove|TestMaxDelete|TestPurge|TestRemoveExisting)$'" - Starting (try 2/5)
2025/04/22 01:22:30 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vahatev9kagi"
2025/04/22 01:22:30 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/04/22 01:22:31 DEBUG : Starting OpenDrive session with ID: 54eea55c793b82c68b92dbd563e73c2af2364a09bcca8e9bf2f90b16784683bf
2025/04/22 01:22:31 DEBUG : Creating backend with remote "/tmp/rclone191200977"
=== RUN   TestCheckSumDownload
    run.go:180: Remote "OpenDrive root 'rclone-test-vahatev9kagi'", Local "Local file system at /tmp/rclone191200977", Modify Window "1s"
2025/04/22 01:22:31 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vahatev9kagi/data"
2025/04/22 01:22:32 DEBUG : Starting OpenDrive session with ID: e3fc4ae0aba753c1f392fd4df11a98a1cca16aa99ad8f3806499b1b64b7e24db
2025/04/22 01:22:33 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0
2025/04/22 01:22:35 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0
=== RUN   TestCheckSumDownload/subtest1
2025/04/22 01:22:38 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0
2025/04/22 01:22:39 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0
=== RUN   TestCheckSumDownload/subtest2
2025/04/22 01:22:42 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSumDownload/subtest3
2025/04/22 01:22:44 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSumDownload/subtest4
2025/04/22 01:22:46 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0
=== RUN   TestCheckSumDownload/subtest5
2025/04/22 01:22:49 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0
=== RUN   TestCheckSumDownload/subtest6
2025/04/22 01:22:51 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0
2025/04/22 01:22:52 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0
2025/04/22 01:22:53 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (25.33s)
    --- PASS: TestCheckSumDownload/subtest1 (1.05s)
    --- PASS: TestCheckSumDownload/subtest2 (0.66s)
    --- PASS: TestCheckSumDownload/subtest3 (1.03s)
    --- PASS: TestCheckSumDownload/subtest4 (0.71s)
    --- PASS: TestCheckSumDownload/subtest5 (0.69s)
    --- PASS: TestCheckSumDownload/subtest6 (0.69s)
    --- PASS: TestCheckSumDownload/subtest7 (0.68s)
=== RUN   TestCopyFileCopyDest
    run.go:180: Remote "OpenDrive root 'rclone-test-vahatev9kagi'", Local "Local file system at /tmp/rclone191200977", Modify Window "1s"
2025/04/22 01:22:56 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vahatev9kagi/dst"
2025/04/22 01:22:57 DEBUG : Starting OpenDrive session with ID: ff248dc0a8566b04ffb4bd3add2afe20fab0fa6c0a72a86c42b5ecd90d1027c8
2025/04/22 01:22:58 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vahatev9kagi/CopyDest"
2025/04/22 01:22:58 DEBUG : Starting OpenDrive session with ID: 7481054655a0baf615d99b643ab005c8a8175632c51c614b3a910f73baf8438b
2025/04/22 01:22:59 DEBUG : one: Need to transfer - File not found at Destination
2025/04/22 01:23:00 DEBUG : one: Uploading chunk 0, size=3, remain=0
2025/04/22 01:23:01 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2025/04/22 01:23:01 INFO  : one: Copied (new)
2025/04/22 01:23:02 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/04/22 01:23:02 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/04/22 01:23:03 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2025/04/22 01:23:03 INFO  : one: Copied (replaced existing)
2025/04/22 01:23:04 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/04/22 01:23:06 DEBUG : CopyDest/one: Uploading chunk 0, size=5, remain=0
2025/04/22 01:23:08 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vahatev9kagi/BackupDir"
2025/04/22 01:23:08 DEBUG : Starting OpenDrive session with ID: 4f10ab5184b3878c676cd08ec9dd2233066a572bd96db8c75072267187731ad7
2025/04/22 01:23:09 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/04/22 01:23:09 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/04/22 01:23:09 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/04/22 01:23:10 INFO  : one: Moved (server-side)
2025/04/22 01:23:10 DEBUG : one: Dst hash empty - aborting Src hash check
2025/04/22 01:23:10 INFO  : one: Copied (server-side copy)
2025/04/22 01:23:10 DEBUG : one: Destination found in --copy-dest, using server-side copy
2025/04/22 01:23:12 DEBUG : CopyDest/two: Uploading chunk 0, size=3, remain=0
2025/04/22 01:23:13 DEBUG : two: Need to transfer - File not found at Destination
2025/04/22 01:23:13 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/04/22 01:23:14 DEBUG : two: Dst hash empty - aborting Src hash check
2025/04/22 01:23:14 INFO  : two: Copied (server-side copy)
2025/04/22 01:23:14 DEBUG : two: Destination found in --copy-dest, using server-side copy
2025/04/22 01:23:14 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/04/22 01:23:14 DEBUG : two: Unchanged skipping
2025/04/22 01:23:15 DEBUG : CopyDest/three: Uploading chunk 0, size=5, remain=0
2025/04/22 01:23:17 DEBUG : three: Need to transfer - File not found at Destination
2025/04/22 01:23:17 DEBUG : three: Sizes differ (src 7 vs dst 5)
2025/04/22 01:23:17 DEBUG : three: Destination not found in --copy-dest
2025/04/22 01:23:18 DEBUG : three: Uploading chunk 0, size=7, remain=0
2025/04/22 01:23:19 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK
2025/04/22 01:23:19 INFO  : three: Copied (new)
--- PASS: TestCopyFileCopyDest (25.26s)
=== RUN   TestMaxDelete
    run.go:180: Remote "OpenDrive root 'rclone-test-vahatev9kagi'", Local "Local file system at /tmp/rclone191200977", Modify Window "1s"
2025/04/22 01:23:22 DEBUG : small: Uploading chunk 0, size=10, remain=0
2025/04/22 01:23:24 DEBUG : medium: Uploading chunk 0, size=60, remain=0
2025/04/22 01:23:26 DEBUG : large: Uploading chunk 0, size=100, remain=0
2025/04/22 01:23:27 DEBUG : Waiting for deletions to finish
2025/04/22 01:23:27 ERROR : small: Got fatal error on delete: --max-delete threshold reached
2025/04/22 01:23:27 INFO  : large: Deleted
2025/04/22 01:23:27 INFO  : medium: Deleted
    operations_test.go:453: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:453
        	Error:      	Not equal: 
        	            	expected: 1
        	            	actual  : 3
        	Test:       	TestMaxDelete
--- FAIL: TestMaxDelete (6.53s)
=== RUN   TestPurge
2025/04/22 01:23:28 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-haxoqoy7zosu"
2025/04/22 01:23:28 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/04/22 01:23:29 DEBUG : Starting OpenDrive session with ID: 320f41a898b91df839736d92632e7b23cf384428f21ecfa57c4d7d13f10c0b97
2025/04/22 01:23:29 DEBUG : Creating backend with remote "/tmp/rclone3287231616"
    run.go:180: Remote "OpenDrive root 'rclone-test-haxoqoy7zosu'", Local "Local file system at /tmp/rclone3287231616", Modify Window "1s"
2025/04/22 01:23:31 DEBUG : A1/B1/C1/one: Uploading chunk 0, size=3, remain=0
2025/04/22 01:23:32 INFO  : A2: Making directory
2025/04/22 01:23:32 INFO  : A1/B2: Making directory
2025/04/22 01:23:33 INFO  : A1/B2/C2: Making directory
2025/04/22 01:23:33 INFO  : A1/B1/C3: Making directory
2025/04/22 01:23:33 INFO  : A3: Making directory
2025/04/22 01:23:34 INFO  : A3/B3: Making directory
2025/04/22 01:23:34 INFO  : A3/B3/C4: Making directory
2025/04/22 01:23:35 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0
2025/04/22 01:23:39 ERROR : : error listing: directory not found
2025/04/22 01:23:39 DEBUG : OpenDrive root 'rclone-test-haxoqoy7zosu': Purge remote
2025/04/22 01:23:39 NOTICE: purge failed: directory not found
--- PASS: TestPurge (10.72s)
=== RUN   TestDirMove
    run.go:180: Remote "OpenDrive root 'rclone-test-vahatev9kagi'", Local "Local file system at /tmp/rclone191200977", Modify Window "1s"
2025/04/22 01:23:40 DEBUG : A1/one: Uploading chunk 0, size=3, remain=0
2025/04/22 01:23:42 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0
2025/04/22 01:23:44 DEBUG : A1/B1/three: Uploading chunk 0, size=5, remain=0
2025/04/22 01:23:45 DEBUG : A1/B1/C1/four: Uploading chunk 0, size=4, remain=0
2025/04/22 01:23:47 DEBUG : A1/B1/C2/five: Uploading chunk 0, size=4, remain=0
2025/04/22 01:23:48 INFO  : A1/B2: Making directory
2025/04/22 01:23:49 INFO  : A1/B1/C3: Making directory
2025/04/22 01:23:50 DEBUG : A2: same parent dir (ODFfODQ0MDA0NV9ESEpraw) - using folder/rename instead of move_copy
2025/04/22 01:23:54 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2025/04/22 01:23:54 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2025/04/22 01:23:54 INFO  : A2/one: Moved (server-side) to: A3/one
2025/04/22 01:23:54 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2025/04/22 01:23:55 INFO  : A2/two: Moved (server-side) to: A3/two
2025/04/22 01:23:58 INFO  : OpenDrive root 'rclone-test-vahatev9kagi': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2025/04/22 01:24:01 INFO  : A3/one: Moved (server-side) to: A4/one
2025/04/22 01:24:01 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2025/04/22 01:24:01 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2025/04/22 01:24:01 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2025/04/22 01:24:01 INFO  : A3/two: Moved (server-side) to: A4/two
--- PASS: TestDirMove (29.09s)
=== RUN   TestRemoveExisting
    run.go:180: Remote "OpenDrive root 'rclone-test-vahatev9kagi'", Local "Local file system at /tmp/rclone191200977", Modify Window "1s"
2025/04/22 01:24:09 DEBUG : sub dir/test remove existing: Uploading chunk 0, size=11, remain=0
2025/04/22 01:24:11 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: Uploading chunk 0, size=21, remain=0
2025/04/22 01:24:13 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.nokadoh8" before starting
2025/04/22 01:24:13 DEBUG : sub dir/test remove existing: same parent dir (ODFfODQ0MDEzOV9nTGY5eQ) - using file/rename instead of move_copy for sub dir/test remove existing.nokadoh8
2025/04/22 01:24:14 DEBUG : sub dir/test remove existing.nokadoh8: TEST: removing renamed existing file after operation
2025/04/22 01:24:15 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.nanedof4" before starting
2025/04/22 01:24:15 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: same parent dir (ODFfODQ0MDEzOV9nTGY5eQ) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.nanedof4
2025/04/22 01:24:15 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.nanedof4: TEST: renaming existing back after failed operation
2025/04/22 01:24:15 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.nanedof4: same parent dir (ODFfODQ0MDEzOV9nTGY5eQ) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789
2025/04/22 01:24:16 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.yuqogiv4" before starting
2025/04/22 01:24:16 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: same parent dir (ODFfODQ0MDEzOV9nTGY5eQ) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.yuqogiv4
2025/04/22 01:24:17 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.yuqogiv4: TEST: removing renamed existing file after operation
--- PASS: TestRemoveExisting (9.95s)
FAIL
2025/04/22 01:24:18 DEBUG : OpenDrive root 'rclone-test-vahatev9kagi': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileCopyDest|TestDirMove|TestMaxDelete|TestPurge|TestRemoveExisting)$'" - Finished ERROR in 1m48.245251072s (try 2/5): exit status 1: Failed [TestMaxDelete]