"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyFileCopyDest|TestMaxDeleteSize|TestPurge|TestRmdirsWithFilter)$|^TestRcat$/^withChecksum=false,ignoreChecksum=false$'" - Starting (try 2/5) 2025/12/30 01:10:02 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jehofoy0gizo" 2025/12/30 01:10:02 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/30 01:10:02 DEBUG : Starting OpenDrive session with ID: f42964381cea93e06671feac5f07fc1fce7832d71e2047b77c3604de23d079ac 2025/12/30 01:10:03 DEBUG : Creating backend with remote "/tmp/rclone1379861344" === RUN TestCopyFileCopyDest run.go:185: Remote "OpenDrive root 'rclone-test-jehofoy0gizo'", Local "Local file system at /tmp/rclone1379861344", Modify Window "1s" 2025/12/30 01:10:03 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jehofoy0gizo/dst" 2025/12/30 01:10:03 DEBUG : Starting OpenDrive session with ID: 674b2fb867ea54f079be3c7cfc826c371b7af1e7b85743e3bc5b2ba5ea386eb7 2025/12/30 01:10:04 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jehofoy0gizo/CopyDest" 2025/12/30 01:10:04 DEBUG : Starting OpenDrive session with ID: aa35e7279c8887f09c898ee3912287bd568c98694b234fb7c0bf27e0c149888f 2025/12/30 01:10:04 DEBUG : one: Need to transfer - File not found at Destination 2025/12/30 01:10:06 DEBUG : one: Uploading chunk 0, size=3, remain=0 2025/12/30 01:10:07 DEBUG : one: size = 3 OK 2025/12/30 01:10:07 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2025/12/30 01:10:07 INFO : one: Copied (new) 2025/12/30 01:10:08 DEBUG : one: size = 5 (Local file system at /tmp/rclone1379861344) 2025/12/30 01:10:08 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-jehofoy0gizo/dst') 2025/12/30 01:10:08 DEBUG : one: Sizes differ 2025/12/30 01:10:09 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/12/30 01:10:10 DEBUG : one: size = 5 OK 2025/12/30 01:10:10 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/12/30 01:10:10 INFO : one: Copied (replaced existing) 2025/12/30 01:10:11 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/12/30 01:10:13 DEBUG : CopyDest/one: Uploading chunk 0, size=5, remain=0 2025/12/30 01:10:15 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jehofoy0gizo/BackupDir" 2025/12/30 01:10:15 DEBUG : Starting OpenDrive session with ID: 13f985cda92502cdb2936d9f55a83dd063664f3c3258088c5206cf433b5be889 2025/12/30 01:10:16 DEBUG : one: size = 5 (Local file system at /tmp/rclone1379861344) 2025/12/30 01:10:16 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-jehofoy0gizo/dst') 2025/12/30 01:10:16 DEBUG : one: Sizes differ 2025/12/30 01:10:16 DEBUG : one: size = 5 OK 2025/12/30 01:10:16 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/12/30 01:10:16 DEBUG : one: size = 5 (Local file system at /tmp/rclone1379861344) 2025/12/30 01:10:16 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-jehofoy0gizo/dst') 2025/12/30 01:10:16 DEBUG : one: Sizes differ 2025/12/30 01:10:17 INFO : one: Moved (server-side) 2025/12/30 01:10:18 DEBUG : one: size = 5 OK 2025/12/30 01:10:18 DEBUG : one: Dst hash empty - aborting Src hash check 2025/12/30 01:10:18 INFO : one: Copied (server-side copy) 2025/12/30 01:10:18 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/12/30 01:10:19 DEBUG : CopyDest/two: Uploading chunk 0, size=3, remain=0 fstest.go:127: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:127 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:132 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:151 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:333 Error: Should be true Test: TestCopyFileCopyDest Messages: CopyDest/two: Modification time difference too big |-122844h10m19.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-12-30 01:10:19 +0000 UTC) (precision 1s) 2025/12/30 01:10:21 DEBUG : two: Need to transfer - File not found at Destination 2025/12/30 01:10:21 DEBUG : two: size = 3 OK 2025/12/30 01:10:21 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/12/30 01:10:21 DEBUG : two: size = 3 OK 2025/12/30 01:10:21 DEBUG : two: Dst hash empty - aborting Src hash check 2025/12/30 01:10:21 INFO : two: Copied (server-side copy) 2025/12/30 01:10:21 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/12/30 01:10:22 DEBUG : two: size = 3 OK 2025/12/30 01:10:22 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/12/30 01:10:22 DEBUG : two: Unchanged skipping 2025/12/30 01:10:23 DEBUG : CopyDest/three: Uploading chunk 0, size=5, remain=0 2025/12/30 01:10:25 DEBUG : three: Need to transfer - File not found at Destination 2025/12/30 01:10:25 DEBUG : three: size = 7 (Local file system at /tmp/rclone1379861344) 2025/12/30 01:10:25 DEBUG : three: size = 5 (OpenDrive root 'rclone-test-jehofoy0gizo/CopyDest') 2025/12/30 01:10:25 DEBUG : three: Sizes differ 2025/12/30 01:10:25 DEBUG : three: Destination not found in --copy-dest 2025/12/30 01:10:26 DEBUG : three: Uploading chunk 0, size=7, remain=0 2025/12/30 01:10:27 DEBUG : three: size = 7 OK 2025/12/30 01:10:27 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2025/12/30 01:10:27 INFO : three: Copied (new) --- FAIL: TestCopyFileCopyDest (27.33s) === RUN TestMaxDeleteSize run.go:185: Remote "OpenDrive root 'rclone-test-jehofoy0gizo'", Local "Local file system at /tmp/rclone1379861344", Modify Window "1s" 2025/12/30 01:10:31 DEBUG : small: Uploading chunk 0, size=10, remain=0 2025/12/30 01:10:32 DEBUG : medium: Uploading chunk 0, size=60, remain=0 2025/12/30 01:10:34 DEBUG : large: Uploading chunk 0, size=100, remain=0 2025/12/30 01:10:35 DEBUG : Waiting for deletions to finish 2025/12/30 01:10:35 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2025/12/30 01:10:36 INFO : large: Deleted 2025/12/30 01:10:36 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (6.85s) === RUN TestPurge 2025/12/30 01:10:37 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wudenev0sohu" 2025/12/30 01:10:37 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/30 01:10:37 DEBUG : Starting OpenDrive session with ID: 6d4e0b04f70786eeeec86ff6b67f02557c3d1968d48aa3750b9dfcc235dff6cd 2025/12/30 01:10:38 DEBUG : Creating backend with remote "/tmp/rclone3138468487" run.go:185: Remote "OpenDrive root 'rclone-test-wudenev0sohu'", Local "Local file system at /tmp/rclone3138468487", Modify Window "1s" 2025/12/30 01:10:40 DEBUG : A1/B1/C1/one: Uploading chunk 0, size=3, remain=0 2025/12/30 01:10:41 INFO : A2: Making directory 2025/12/30 01:10:42 INFO : A1/B2: Making directory 2025/12/30 01:10:42 INFO : A1/B2/C2: Making directory 2025/12/30 01:10:42 INFO : A1/B1/C3: Making directory 2025/12/30 01:10:43 INFO : A3: Making directory 2025/12/30 01:10:43 INFO : A3/B3: Making directory 2025/12/30 01:10:44 INFO : A3/B3/C4: Making directory 2025/12/30 01:10:45 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0 2025/12/30 01:10:49 ERROR : error listing: directory not found 2025/12/30 01:10:49 DEBUG : OpenDrive root 'rclone-test-wudenev0sohu': Purge remote 2025/12/30 01:10:49 NOTICE: purge failed: directory not found --- PASS: TestPurge (12.02s) === RUN TestRmdirsWithFilter run.go:185: Remote "OpenDrive root 'rclone-test-jehofoy0gizo'", Local "Local file system at /tmp/rclone1379861344", Modify Window "1s" 2025/12/30 01:10:49 INFO : A1: Making directory 2025/12/30 01:10:49 INFO : A1/B1: Making directory 2025/12/30 01:10:49 INFO : A1/B1/C1: Making directory 2025/12/30 01:10:51 DEBUG : removing 1 level 3 directories 2025/12/30 01:10:51 INFO : A1/B1/C1: Removing directory 2025/12/30 01:10:52 DEBUG : removing 1 level 2 directories 2025/12/30 01:10:52 INFO : A1/B1: Removing directory --- PASS: TestRmdirsWithFilter (4.85s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:185: Remote "OpenDrive root 'rclone-test-jehofoy0gizo'", Local "Local file system at /tmp/rclone1379861344", Modify Window "1s" 2025/12/30 01:10:54 DEBUG : OpenDrive root 'rclone-test-jehofoy0gizo': File to upload is small (34 bytes), uploading instead of streaming 2025/12/30 01:10:55 DEBUG : no_checksum_small_file_from_pipe: Uploading chunk 0, size=34, remain=0 2025/12/30 01:10:56 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2025/12/30 01:10:56 DEBUG : no_checksum_small_file_from_pipe: md5 = 4c762bc6ec18f21b23fcae426c7863b5 OK 2025/12/30 01:10:56 DEBUG : no_checksum_small_file_from_pipe: Size and md5 of src and dst objects identical 2025/12/30 01:10:56 DEBUG : OpenDrive root 'rclone-test-jehofoy0gizo': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2025/12/30 01:10:57 DEBUG : no_checksum_big_file_from_pipe: Uploading chunk 0, size=102401, remain=0 2025/12/30 01:10:58 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2025/12/30 01:10:58 DEBUG : no_checksum_big_file_from_pipe: md5 = fffc7956ba9a7b58a63c01b6ce1ddc45 OK 2025/12/30 01:10:58 DEBUG : no_checksum_big_file_from_pipe: Size and md5 of src and dst objects identical --- PASS: TestRcat (5.63s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.63s) FAIL 2025/12/30 01:10:59 DEBUG : OpenDrive root 'rclone-test-jehofoy0gizo': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyFileCopyDest|TestMaxDeleteSize|TestPurge|TestRmdirsWithFilter)$|^TestRcat$/^withChecksum=false,ignoreChecksum=false$'" - Finished ERROR in 58.076962888s (try 2/5): exit status 1: Failed [TestCopyFileCopyDest]