"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCheckSizeOnly|TestCheckSum|TestCheckSumDownload|TestCopyFile|TestCopyFileCompareDest|TestDirMove|TestLsWithFilesFrom|TestMoveFileBackupDir|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot)$'" - Starting (try 2/5) 2025/07/11 01:47:05 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-cagilad4cuze" 2025/07/11 01:47:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/11 01:47:06 DEBUG : Starting OpenDrive session with ID: edec0ad7e141a810fbdde9cfa53633e4c3c3e61e27b60712cad1f33cbf224abd 2025/07/11 01:47:06 DEBUG : Creating backend with remote "/tmp/rclone1563434333" === RUN TestCheckSizeOnly run.go:180: Remote "OpenDrive root 'rclone-test-cagilad4cuze'", Local "Local file system at /tmp/rclone1563434333", Modify Window "1s" 2025/07/11 01:47:07 DEBUG : rutabaga: Uploading chunk 0, size=8, remain=0 === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2025/07/11 01:47:10 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 === NAME TestCheckSizeOnly 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/check_test.go:122 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 Error: Should be true Test: TestCheckSizeOnly Messages: empty space: Modification time difference too big |-118716h47m10.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-07-11 01:47:10 +0000 UTC) (precision 1s) === RUN TestCheckSizeOnly/3 2025/07/11 01:47:12 DEBUG : potato2: Uploading chunk 0, size=60, remain=0 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2025/07/11 01:47:14 DEBUG : remotepotato: Uploading chunk 0, size=60, remain=0 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- FAIL: TestCheckSizeOnly (11.18s) --- PASS: TestCheckSizeOnly/1 (0.17s) --- PASS: TestCheckSizeOnly/2 (0.17s) --- PASS: TestCheckSizeOnly/3 (0.17s) --- PASS: TestCheckSizeOnly/4 (0.18s) --- PASS: TestCheckSizeOnly/5 (0.17s) --- PASS: TestCheckSizeOnly/6 (0.17s) --- PASS: TestCheckSizeOnly/7 (0.17s) === RUN TestCheckSum run.go:180: Remote "OpenDrive root 'rclone-test-cagilad4cuze'", Local "Local file system at /tmp/rclone1563434333", Modify Window "1s" 2025/07/11 01:47:17 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-cagilad4cuze/data" 2025/07/11 01:47:18 DEBUG : Starting OpenDrive session with ID: c48d97b3cbe80c4f720bf8f96bf34d9343507e3010f4c49a54f924ee24c8517c 2025/07/11 01:47:19 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0 2025/07/11 01:47:21 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0 === RUN TestCheckSum/subtest1 2025/07/11 01:47:24 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0 2025/07/11 01:47:25 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0 === RUN TestCheckSum/subtest2 2025/07/11 01:47:27 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSum/subtest3 2025/07/11 01:47:30 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSum/subtest4 2025/07/11 01:47:32 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0 === RUN TestCheckSum/subtest5 2025/07/11 01:47:34 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0 === RUN TestCheckSum/subtest6 2025/07/11 01:47:36 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0 2025/07/11 01:47:38 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0 2025/07/11 01:47:39 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSum/subtest7 --- PASS: TestCheckSum (25.52s) --- PASS: TestCheckSum/subtest1 (0.69s) --- PASS: TestCheckSum/subtest2 (0.51s) --- PASS: TestCheckSum/subtest3 (0.52s) --- PASS: TestCheckSum/subtest4 (0.51s) --- PASS: TestCheckSum/subtest5 (0.51s) --- PASS: TestCheckSum/subtest6 (0.56s) --- PASS: TestCheckSum/subtest7 (0.55s) === RUN TestCheckSumDownload run.go:180: Remote "OpenDrive root 'rclone-test-cagilad4cuze'", Local "Local file system at /tmp/rclone1563434333", Modify Window "1s" 2025/07/11 01:47:43 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-cagilad4cuze/data" 2025/07/11 01:47:43 DEBUG : Starting OpenDrive session with ID: 478ec91895fe2202c5b09583e9db52b45917e5fca668ce802e8177c27d75bbcd 2025/07/11 01:47:45 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0 2025/07/11 01:47:47 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0 === RUN TestCheckSumDownload/subtest1 2025/07/11 01:47:49 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0 2025/07/11 01:47:51 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0 === RUN TestCheckSumDownload/subtest2 2025/07/11 01:47:53 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSumDownload/subtest3 2025/07/11 01:47:56 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSumDownload/subtest4 2025/07/11 01:47:58 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0 === RUN TestCheckSumDownload/subtest5 2025/07/11 01:48:01 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0 === RUN TestCheckSumDownload/subtest6 2025/07/11 01:48:03 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0 2025/07/11 01:48:04 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0 2025/07/11 01:48:06 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (26.38s) --- PASS: TestCheckSumDownload/subtest1 (0.86s) --- PASS: TestCheckSumDownload/subtest2 (0.68s) --- PASS: TestCheckSumDownload/subtest3 (1.04s) --- PASS: TestCheckSumDownload/subtest4 (0.70s) --- PASS: TestCheckSumDownload/subtest5 (0.69s) --- PASS: TestCheckSumDownload/subtest6 (0.68s) --- PASS: TestCheckSumDownload/subtest7 (0.72s) === RUN TestCopyFile run.go:180: Remote "OpenDrive root 'rclone-test-cagilad4cuze'", Local "Local file system at /tmp/rclone1563434333", Modify Window "1s" 2025/07/11 01:48:09 DEBUG : file1: Need to transfer - File not found at Destination 2025/07/11 01:48:10 DEBUG : sub/file2: Uploading chunk 0, size=14, remain=0 2025/07/11 01:48:11 DEBUG : file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2025/07/11 01:48:11 INFO : file1: Copied (new) to: sub/file2 2025/07/11 01:48:12 DEBUG : file1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/11 01:48:12 DEBUG : file1: Unchanged skipping 2025/07/11 01:48:12 DEBUG : OpenDrive root 'rclone-test-cagilad4cuze': don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (4.57s) === RUN TestCopyFileCompareDest run.go:180: Remote "OpenDrive root 'rclone-test-cagilad4cuze'", Local "Local file system at /tmp/rclone1563434333", Modify Window "1s" 2025/07/11 01:48:14 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-cagilad4cuze/dst" 2025/07/11 01:48:14 DEBUG : Starting OpenDrive session with ID: bd53f5d3d926ae39d84eae5ba4c5cfb88516bce5703bb813dee0acbb6bb15401 2025/07/11 01:48:15 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-cagilad4cuze/CompareDest" 2025/07/11 01:48:15 DEBUG : Starting OpenDrive session with ID: b7d70cd22e545a3269fdce6747bd51d698cdc86f2390b3dbef105a8d812eb6ba 2025/07/11 01:48:16 DEBUG : one: Need to transfer - File not found at Destination 2025/07/11 01:48:17 DEBUG : one: Uploading chunk 0, size=3, remain=0 2025/07/11 01:48:19 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2025/07/11 01:48:19 INFO : one: Copied (new) 2025/07/11 01:48:19 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/11 01:48:20 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/07/11 01:48:21 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/07/11 01:48:21 INFO : one: Copied (replaced existing) 2025/07/11 01:48:22 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/07/11 01:48:24 DEBUG : CompareDest/one: Uploading chunk 0, size=5, remain=0 2025/07/11 01:48:26 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/11 01:48:26 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/07/11 01:48:26 DEBUG : one: Destination found in --compare-dest, skipping 2025/07/11 01:48:27 DEBUG : CompareDest/two: Uploading chunk 0, size=3, remain=0 2025/07/11 01:48:29 DEBUG : two: Need to transfer - File not found at Destination 2025/07/11 01:48:29 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/07/11 01:48:29 DEBUG : two: Destination found in --compare-dest, skipping 2025/07/11 01:48:30 DEBUG : two: Need to transfer - File not found at Destination 2025/07/11 01:48:30 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/07/11 01:48:30 DEBUG : two: Destination found in --compare-dest, skipping 2025/07/11 01:48:31 DEBUG : two: Need to transfer - File not found at Destination 2025/07/11 01:48:31 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/07/11 01:48:32 DEBUG : two: Uploading chunk 0, size=5, remain=0 2025/07/11 01:48:33 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK 2025/07/11 01:48:33 INFO : two: Copied (new) run.go:125: removing dir "dst" failed - try 1/3: folder not empty fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Flushing the directory cache fstest.go:289: Sleeping for 6s just to make sure --- PASS: TestCopyFileCompareDest (30.79s) === RUN TestLsWithFilesFrom run.go:180: Remote "OpenDrive root 'rclone-test-cagilad4cuze'", Local "Local file system at /tmp/rclone1563434333", Modify Window "1s" 2025/07/11 01:48:45 DEBUG : potato2: Uploading chunk 0, size=60, remain=0 2025/07/11 01:48:47 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 2025/07/11 01:48:48 DEBUG : empty space: Excluded (FilesFrom Filter) 2025/07/11 01:48:48 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (4.72s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "OpenDrive root 'rclone-test-cagilad4cuze'", Local "Local file system at /tmp/rclone1563434333", Modify Window "1s" 2025/07/11 01:48:51 DEBUG : A1/B1/C1/one: Uploading chunk 0, size=3, remain=0 2025/07/11 01:48:52 INFO : A2: Making directory 2025/07/11 01:48:52 INFO : A1/B2: Making directory 2025/07/11 01:48:52 INFO : A1/B2/C2: Making directory 2025/07/11 01:48:53 INFO : A1/B1/C3: Making directory 2025/07/11 01:48:53 INFO : A3: Making directory 2025/07/11 01:48:53 INFO : A3/B3: Making directory 2025/07/11 01:48:54 INFO : A3/B3/C4: Making directory operations_test.go:658: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:658 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestRmdirsNoLeaveRoot --- FAIL: TestRmdirsNoLeaveRoot (8.88s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "OpenDrive root 'rclone-test-cagilad4cuze'", Local "Local file system at /tmp/rclone1563434333", Modify Window "1s" 2025/07/11 01:48:58 INFO : A1: Making directory 2025/07/11 01:48:58 INFO : A1/B1: Making directory 2025/07/11 01:48:59 INFO : A1/B1/C1: Making directory 2025/07/11 01:49:00 DEBUG : removing 1 level 3 directories 2025/07/11 01:49:00 INFO : A1/B1/C1: Removing directory 2025/07/11 01:49:01 DEBUG : removing 1 level 2 directories 2025/07/11 01:49:01 INFO : A1/B1: Removing directory --- PASS: TestRmdirsLeaveRoot (4.12s) === RUN TestMoveFileBackupDir run.go:180: Remote "OpenDrive root 'rclone-test-cagilad4cuze'", Local "Local file system at /tmp/rclone1563434333", Modify Window "1s" 2025/07/11 01:49:03 DEBUG : dst/file1: Uploading chunk 0, size=18, remain=0 2025/07/11 01:49:05 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-cagilad4cuze/backup" 2025/07/11 01:49:05 DEBUG : Starting OpenDrive session with ID: d09c566edd05e117abb2f307a30001abfae8a1551c62a8bc093a76ddb24182bd 2025/07/11 01:49:06 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2025/07/11 01:49:07 INFO : dst/file1: Moved (server-side) 2025/07/11 01:49:08 DEBUG : dst/file1: Uploading chunk 0, size=14, remain=0 2025/07/11 01:49:09 DEBUG : dst/file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2025/07/11 01:49:09 INFO : dst/file1: Copied (new) 2025/07/11 01:49:09 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (9.16s) === RUN TestDirMove run.go:180: Remote "OpenDrive root 'rclone-test-cagilad4cuze'", Local "Local file system at /tmp/rclone1563434333", Modify Window "1s" 2025/07/11 01:49:13 DEBUG : A1/one: Uploading chunk 0, size=3, remain=0 2025/07/11 01:49:14 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0 2025/07/11 01:49:16 DEBUG : A1/B1/three: Uploading chunk 0, size=5, remain=0 2025/07/11 01:49:18 DEBUG : A1/B1/C1/four: Uploading chunk 0, size=4, remain=0 2025/07/11 01:49:20 DEBUG : A1/B1/C2/five: Uploading chunk 0, size=4, remain=0 2025/07/11 01:49:21 INFO : A1/B2: Making directory 2025/07/11 01:49:22 INFO : A1/B1/C3: Making directory 2025/07/11 01:49:23 DEBUG : A2: same parent dir (ODFfOTE2MTI1MV8xd29mSQ) - using folder/rename instead of move_copy fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Flushing the directory cache fstest.go:289: Sleeping for 6s just to make sure operations_test.go:1405: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1405 Error: Received unexpected error: RenameDir mkdir: failed to get folder list: Directory doesn't exist (Error 404) Test: TestDirMove fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Flushing the directory cache fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Flushing the directory cache fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:298: Flushing the directory cache fstest.go:324: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:324 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1041 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1405 Error: Not equal: expected: []string{} actual : []string{"A3"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=2) "A3" } Test: TestDirMove Messages: directories --- FAIL: TestDirMove (33.86s) FAIL 2025/07/11 01:49:45 DEBUG : OpenDrive root 'rclone-test-cagilad4cuze': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCheckSizeOnly|TestCheckSum|TestCheckSumDownload|TestCopyFile|TestCopyFileCompareDest|TestDirMove|TestLsWithFilesFrom|TestMoveFileBackupDir|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot)$'" - Finished ERROR in 2m41.023126811s (try 2/5): exit status 1: Failed [TestCheckSizeOnly TestRmdirsNoLeaveRoot TestDirMove]