"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileCompareDest|TestPurge|TestRemoveExisting|TestRmdirsLeaveRoot)$'" - Starting (try 2/5)
2025/07/14 01:44:58 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-romefox2pari"
2025/07/14 01:44:58 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/14 01:44:58 DEBUG : Starting OpenDrive session with ID: 059f76aa1455038cd95d089522b4a4b30f0b7fb817f06003a62a4fa3f72be1f6
2025/07/14 01:44:58 DEBUG : Creating backend with remote "/tmp/rclone2987415610"
=== RUN   TestCheckSumDownload
    run.go:180: Remote "OpenDrive root 'rclone-test-romefox2pari'", Local "Local file system at /tmp/rclone2987415610", Modify Window "1s"
2025/07/14 01:44:58 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-romefox2pari/data"
2025/07/14 01:44:59 DEBUG : Starting OpenDrive session with ID: 3ac7eb445e66bfc961a1019d0f6864cb23c40b5f7a92c30807955cb65bab3477
2025/07/14 01:45:01 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0
2025/07/14 01:45:02 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0
=== RUN   TestCheckSumDownload/subtest1
2025/07/14 01:45:05 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0
2025/07/14 01:45:07 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0
=== RUN   TestCheckSumDownload/subtest2
2025/07/14 01:45:09 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSumDownload/subtest3
2025/07/14 01:45:12 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSumDownload/subtest4
2025/07/14 01:45:14 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0
=== RUN   TestCheckSumDownload/subtest5
2025/07/14 01:45:16 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0
=== RUN   TestCheckSumDownload/subtest6
2025/07/14 01:45:19 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0
2025/07/14 01:45:20 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0
2025/07/14 01:45:21 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (26.15s)
    --- PASS: TestCheckSumDownload/subtest1 (1.03s)
    --- PASS: TestCheckSumDownload/subtest2 (0.68s)
    --- PASS: TestCheckSumDownload/subtest3 (1.14s)
    --- PASS: TestCheckSumDownload/subtest4 (0.69s)
    --- PASS: TestCheckSumDownload/subtest5 (0.68s)
    --- PASS: TestCheckSumDownload/subtest6 (0.70s)
    --- PASS: TestCheckSumDownload/subtest7 (0.69s)
=== RUN   TestCopyFileCompareDest
    run.go:180: Remote "OpenDrive root 'rclone-test-romefox2pari'", Local "Local file system at /tmp/rclone2987415610", Modify Window "1s"
2025/07/14 01:45:25 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-romefox2pari/dst"
2025/07/14 01:45:25 DEBUG : Starting OpenDrive session with ID: 7a5a9a329f3d5676e808b91a84cf48f39829fa084fce4a91f6ae53c542d6b86a
2025/07/14 01:45:26 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-romefox2pari/CompareDest"
2025/07/14 01:45:26 DEBUG : Starting OpenDrive session with ID: b240e645354f09c0b6bcc471ce53762abcde958a6311a408aad346b251f5a677
2025/07/14 01:45:27 DEBUG : one: Need to transfer - File not found at Destination
2025/07/14 01:45:28 DEBUG : one: Uploading chunk 0, size=3, remain=0
2025/07/14 01:45:29 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2025/07/14 01:45:29 INFO  : one: Copied (new)
2025/07/14 01:45:30 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/07/14 01:45:31 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/07/14 01:45:32 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2025/07/14 01:45:32 INFO  : one: Copied (replaced existing)
2025/07/14 01:45:32 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/07/14 01:45:35 DEBUG : CompareDest/one: Uploading chunk 0, size=5, remain=0
2025/07/14 01:45:36 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/07/14 01:45:37 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/07/14 01:45:37 DEBUG : one: Destination found in --compare-dest, skipping
2025/07/14 01:45:38 DEBUG : CompareDest/two: Uploading chunk 0, size=3, remain=0
2025/07/14 01:45:39 DEBUG : two: Need to transfer - File not found at Destination
2025/07/14 01:45:39 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/07/14 01:45:39 DEBUG : two: Destination found in --compare-dest, skipping
2025/07/14 01:45:40 DEBUG : two: Need to transfer - File not found at Destination
2025/07/14 01:45:40 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/07/14 01:45:40 DEBUG : two: Destination found in --compare-dest, skipping
2025/07/14 01:45:41 DEBUG : two: Need to transfer - File not found at Destination
2025/07/14 01:45:41 DEBUG : two: Sizes differ (src 5 vs dst 3)
2025/07/14 01:45:42 DEBUG : two: Uploading chunk 0, size=5, remain=0
2025/07/14 01:45:43 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK
2025/07/14 01:45:43 INFO  : two: Copied (new)
--- PASS: TestCopyFileCompareDest (20.68s)
=== RUN   TestPurge
2025/07/14 01:45:45 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jomigog3maku"
2025/07/14 01:45:45 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/14 01:45:46 DEBUG : Starting OpenDrive session with ID: 7405bbd2840e113da020523cc540ea9d1863487981cad8b8677b66229b756b22
2025/07/14 01:45:46 DEBUG : Creating backend with remote "/tmp/rclone835188887"
    run.go:180: Remote "OpenDrive root 'rclone-test-jomigog3maku'", Local "Local file system at /tmp/rclone835188887", Modify Window "1s"
2025/07/14 01:45:48 DEBUG : A1/B1/C1/one: Uploading chunk 0, size=3, remain=0
2025/07/14 01:45:49 INFO  : A2: Making directory
2025/07/14 01:45:50 INFO  : A1/B2: Making directory
2025/07/14 01:45:50 INFO  : A1/B2/C2: Making directory
2025/07/14 01:45:50 INFO  : A1/B1/C3: Making directory
2025/07/14 01:45:51 INFO  : A3: Making directory
2025/07/14 01:45:51 INFO  : A3/B3: Making directory
2025/07/14 01:45:51 INFO  : A3/B3/C4: Making directory
2025/07/14 01:45:52 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0
2025/07/14 01:45:56 ERROR : error listing: directory not found
2025/07/14 01:45:56 DEBUG : OpenDrive root 'rclone-test-jomigog3maku': Purge remote
2025/07/14 01:45:57 NOTICE: purge failed: directory not found
--- PASS: TestPurge (11.39s)
=== RUN   TestRmdirsLeaveRoot
    run.go:180: Remote "OpenDrive root 'rclone-test-romefox2pari'", Local "Local file system at /tmp/rclone2987415610", Modify Window "1s"
2025/07/14 01:45:57 INFO  : A1: Making directory
2025/07/14 01:45:57 INFO  : A1/B1: Making directory
2025/07/14 01:45:57 INFO  : A1/B1/C1: Making directory
2025/07/14 01:45:59 DEBUG : removing 1 level 3 directories
2025/07/14 01:45:59 INFO  : A1/B1/C1: Removing directory
2025/07/14 01:45:59 DEBUG : removing 1 level 2 directories
2025/07/14 01:45:59 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsLeaveRoot (4.16s)
=== RUN   TestRemoveExisting
    run.go:180: Remote "OpenDrive root 'rclone-test-romefox2pari'", Local "Local file system at /tmp/rclone2987415610", Modify Window "1s"
2025/07/14 01:46:02 DEBUG : sub dir/test remove existing: Uploading chunk 0, size=11, remain=0
2025/07/14 01:46:04 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: Uploading chunk 0, size=21, remain=0
    fstest.go:124: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:124
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:129
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:148
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1905
        	Error:      	Should be true
        	Test:       	TestRemoveExisting
        	Messages:   	sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: Modification time difference too big |-214245h40m57.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-07-14 01:46:04 +0000 UTC) (precision 1s)
2025/07/14 01:46:06 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.joyojid9" before starting
2025/07/14 01:46:06 DEBUG : sub dir/test remove existing: same parent dir (ODFfOTE3Nzk1OF9GMHN2Mw) - using file/rename instead of move_copy for sub dir/test remove existing.joyojid9
2025/07/14 01:46:07 DEBUG : sub dir/test remove existing.joyojid9: TEST: removing renamed existing file after operation
2025/07/14 01:46:07 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.hujimag4" before starting
2025/07/14 01:46:07 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: same parent dir (ODFfOTE3Nzk1OF9GMHN2Mw) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.hujimag4
2025/07/14 01:46:08 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.hujimag4: TEST: renaming existing back after failed operation
2025/07/14 01:46:08 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.hujimag4: same parent dir (ODFfOTE3Nzk1OF9GMHN2Mw) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789
2025/07/14 01:46:09 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.cixamiq0" before starting
2025/07/14 01:46:09 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: same parent dir (ODFfOTE3Nzk1OF9GMHN2Mw) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.cixamiq0
2025/07/14 01:46:10 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.cixamiq0: TEST: removing renamed existing file after operation
--- FAIL: TestRemoveExisting (10.13s)
FAIL
2025/07/14 01:46:11 DEBUG : OpenDrive root 'rclone-test-romefox2pari': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileCompareDest|TestPurge|TestRemoveExisting|TestRmdirsLeaveRoot)$'" - Finished ERROR in 1m13.8651103s (try 2/5): exit status 1: Failed [TestRemoveExisting]