"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyFileCopyDest|TestListDirSortedFn|TestMaxDeleteSize)$'" - Starting (try 3/5)
2025/09/21 01:34:59 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zekikuj3wose"
2025/09/21 01:34:59 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/09/21 01:35:00 DEBUG : Starting OpenDrive session with ID: 5dc55c9572946858a751065013aa42fe1290d076cc32854c5dfed705592a8d35
2025/09/21 01:35:00 DEBUG : Creating backend with remote "/tmp/rclone1981279513"
=== RUN   TestCopyFileCopyDest
    run.go:180: Remote "OpenDrive root 'rclone-test-zekikuj3wose'", Local "Local file system at /tmp/rclone1981279513", Modify Window "1s"
2025/09/21 01:35:00 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zekikuj3wose/dst"
2025/09/21 01:35:00 DEBUG : Starting OpenDrive session with ID: da143ac6cd982fa96e92c41260693a26dade574c28e96788b13b9e8ce7cb37c4
2025/09/21 01:35:01 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zekikuj3wose/CopyDest"
2025/09/21 01:35:01 DEBUG : Starting OpenDrive session with ID: 4b49256ab2705646c12313c4474a94c94b0ef754ca9debf452ec6935a0fd3b2f
2025/09/21 01:35:02 DEBUG : one: Need to transfer - File not found at Destination
2025/09/21 01:35:04 DEBUG : one: Uploading chunk 0, size=3, remain=0
2025/09/21 01:35:05 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2025/09/21 01:35:05 INFO  : one: Copied (new)
2025/09/21 01:35:06 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/09/21 01:35:06 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/09/21 01:35:07 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2025/09/21 01:35:07 INFO  : one: Copied (replaced existing)
2025/09/21 01:35:08 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/09/21 01:35:10 DEBUG : CopyDest/one: Uploading chunk 0, size=5, remain=0
2025/09/21 01:35:12 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zekikuj3wose/BackupDir"
2025/09/21 01:35:12 DEBUG : Starting OpenDrive session with ID: d016e2a86d78621fc38b41026fb9c0dddc867d3dc66bea4fdbe7501f59132217
2025/09/21 01:35:13 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/09/21 01:35:13 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/09/21 01:35:13 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/09/21 01:35:14 INFO  : one: Moved (server-side)
2025/09/21 01:35:15 DEBUG : one: Dst hash empty - aborting Src hash check
2025/09/21 01:35:15 INFO  : one: Copied (server-side copy)
2025/09/21 01:35:15 DEBUG : one: Destination found in --copy-dest, using server-side copy
2025/09/21 01:35:16 DEBUG : CopyDest/two: Uploading chunk 0, size=3, remain=0
2025/09/21 01:35:18 DEBUG : two: Need to transfer - File not found at Destination
2025/09/21 01:35:18 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/09/21 01:35:18 DEBUG : two: Dst hash empty - aborting Src hash check
2025/09/21 01:35:18 INFO  : two: Copied (server-side copy)
2025/09/21 01:35:18 DEBUG : two: Destination found in --copy-dest, using server-side copy
2025/09/21 01:35:19 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/09/21 01:35:19 DEBUG : two: Unchanged skipping
2025/09/21 01:35:20 DEBUG : CopyDest/three: Uploading chunk 0, size=5, remain=0
2025/09/21 01:35:21 DEBUG : three: Need to transfer - File not found at Destination
2025/09/21 01:35:22 DEBUG : three: Sizes differ (src 7 vs dst 5)
2025/09/21 01:35:22 DEBUG : three: Destination not found in --copy-dest
2025/09/21 01:35:22 DEBUG : three: Uploading chunk 0, size=7, remain=0
2025/09/21 01:35:23 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK
2025/09/21 01:35:23 INFO  : three: Copied (new)
--- PASS: TestCopyFileCopyDest (26.76s)
=== RUN   TestListDirSortedFn
    run.go:180: Remote "OpenDrive root 'rclone-test-zekikuj3wose'", Local "Local file system at /tmp/rclone1981279513", Modify Window "1s"
2025/09/21 01:35:27 DEBUG : a.txt: Uploading chunk 0, size=11, remain=0
2025/09/21 01:35:29 DEBUG : zend.txt: Uploading chunk 0, size=5, remain=0
2025/09/21 01:35:31 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0
2025/09/21 01:35:33 DEBUG : sub dir/hello world2: Uploading chunk 0, size=11, remain=0
2025/09/21 01:35:35 DEBUG : sub dir/ignore dir/.ignore: Uploading chunk 0, size=1, remain=0
2025/09/21 01:35:37 DEBUG : sub dir/ignore dir/should be ignored: Uploading chunk 0, size=9, remain=0
2025/09/21 01:35:39 DEBUG : sub dir/sub sub dir/hello world3: Uploading chunk 0, size=11, remain=0
2025/09/21 01:35:41 DEBUG : a.txt: Excluded (Size Filter)
2025/09/21 01:35:41 DEBUG : a.txt: Excluded
2025/09/21 01:35:42 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/09/21 01:35:42 DEBUG : sub dir/hello world: Excluded
2025/09/21 01:35:42 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/09/21 01:35:42 DEBUG : sub dir/hello world2: Excluded
2025/09/21 01:35:42 DEBUG : sub dir/ignore dir: Excluded
2025/09/21 01:35:42 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/09/21 01:35:42 DEBUG : sub dir/hello world: Excluded
2025/09/21 01:35:42 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/09/21 01:35:42 DEBUG : sub dir/hello world2: Excluded
2025/09/21 01:35:42 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (19.12s)
=== RUN   TestMaxDeleteSize
    run.go:180: Remote "OpenDrive root 'rclone-test-zekikuj3wose'", Local "Local file system at /tmp/rclone1981279513", Modify Window "1s"
2025/09/21 01:35:47 DEBUG : small: Uploading chunk 0, size=10, remain=0
2025/09/21 01:35:48 DEBUG : medium: Uploading chunk 0, size=60, remain=0
2025/09/21 01:35:50 DEBUG : large: Uploading chunk 0, size=100, remain=0
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
2025/09/21 01:35:59 DEBUG : Waiting for deletions to finish
2025/09/21 01:35:59 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached
2025/09/21 01:35:59 INFO  : large: Deleted
2025/09/21 01:35:59 INFO  : medium: Deleted
--- PASS: TestMaxDeleteSize (14.31s)
PASS
2025/09/21 01:36:00 DEBUG : OpenDrive root 'rclone-test-zekikuj3wose': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyFileCopyDest|TestListDirSortedFn|TestMaxDeleteSize)$'" - Finished OK in 1m1.481697181s (try 3/5)