"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestFixCase|TestMoveOverSelf|TestSyncCopyDest)$'" - Starting (try 2/5)
2025/11/27 04:35:35 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-gateyas1paxo"
2025/11/27 04:35:35 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/27 04:35:35 DEBUG : Creating backend with remote "/tmp/rclone296622399"
=== RUN   TestMoveOverSelf
    run.go:185: Remote "files root 'rclone-test-gateyas1paxo'", Local "Local file system at /tmp/rclone296622399", Modify Window "1s"
2025/11/27 04:35:38 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/11/27 04:35:38 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2025/11/27 04:35:38 DEBUG : files root 'rclone-test-gateyas1paxo': Waiting for checks to finish
2025/11/27 04:35:38 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone296622399)
2025/11/27 04:35:38 DEBUG : sub dir/hello world: size = 11 (files root 'rclone-test-gateyas1paxo')
2025/11/27 04:35:38 DEBUG : sub dir/hello world: Sizes differ
2025/11/27 04:35:38 DEBUG : files root 'rclone-test-gateyas1paxo': Waiting for transfers to finish
2025/11/27 04:35:39 DEBUG : sub dir/hello world: size = 17 OK
2025/11/27 04:35:39 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2025/11/27 04:35:39 INFO  : sub dir/hello world: Copied (replaced existing)
2025/11/27 04:35:39 INFO  : sub dir/hello world: Deleted
2025/11/27 04:35:40 INFO  : sub dir: Set directory modification time (using DirSetModTime)
--- PASS: TestMoveOverSelf (5.33s)
=== RUN   TestSyncCopyDest
    run.go:185: Remote "files root 'rclone-test-gateyas1paxo'", Local "Local file system at /tmp/rclone296622399", Modify Window "1s"
2025/11/27 04:35:41 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-gateyas1paxo/dst"
2025/11/27 04:35:41 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-gateyas1paxo/CopyDest"
2025/11/27 04:35:42 DEBUG : one: Need to transfer - File not found at Destination
2025/11/27 04:35:42 DEBUG : files root 'rclone-test-gateyas1paxo/dst': Waiting for checks to finish
2025/11/27 04:35:42 DEBUG : files root 'rclone-test-gateyas1paxo/dst': Waiting for transfers to finish
2025/11/27 04:35:44 DEBUG : one: size = 3 OK
2025/11/27 04:35:44 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2025/11/27 04:35:44 INFO  : one: Copied (new)
2025/11/27 04:35:44 DEBUG : Waiting for deletions to finish
2025/11/27 04:35:45 DEBUG : one: size = 5 (Local file system at /tmp/rclone296622399)
2025/11/27 04:35:45 DEBUG : files root 'rclone-test-gateyas1paxo/dst': Waiting for checks to finish
2025/11/27 04:35:45 DEBUG : one: size = 3 (files root 'rclone-test-gateyas1paxo/dst')
2025/11/27 04:35:45 DEBUG : one: Sizes differ
2025/11/27 04:35:46 DEBUG : files root 'rclone-test-gateyas1paxo/dst': Waiting for transfers to finish
2025/11/27 04:35:47 DEBUG : one: size = 5 OK
2025/11/27 04:35:47 DEBUG : one: Dst hash empty - aborting Src hash check
2025/11/27 04:35:47 INFO  : one: Copied (replaced existing)
2025/11/27 04:35:47 DEBUG : Waiting for deletions to finish
2025/11/27 04:35:51 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-gateyas1paxo/BackupDir"
2025/11/27 04:35:52 DEBUG : one: size = 5 (Local file system at /tmp/rclone296622399)
2025/11/27 04:35:52 DEBUG : one: size = 3 (files root 'rclone-test-gateyas1paxo/dst')
2025/11/27 04:35:52 DEBUG : one: Sizes differ
2025/11/27 04:35:52 DEBUG : files root 'rclone-test-gateyas1paxo/dst': Waiting for checks to finish
2025/11/27 04:35:52 DEBUG : one: size = 5 OK
2025/11/27 04:35:52 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/11/27 04:35:52 DEBUG : one: size = 5 (Local file system at /tmp/rclone296622399)
2025/11/27 04:35:52 DEBUG : one: size = 3 (files root 'rclone-test-gateyas1paxo/dst')
2025/11/27 04:35:52 DEBUG : one: Sizes differ
2025/11/27 04:35:53 INFO  : one: Moved (server-side)
2025/11/27 04:35:56 DEBUG : one: size = 5 OK
2025/11/27 04:35:56 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2025/11/27 04:35:56 INFO  : one: Copied (server-side copy)
2025/11/27 04:35:56 DEBUG : one: Destination found in --copy-dest, using server-side copy
2025/11/27 04:35:56 DEBUG : files root 'rclone-test-gateyas1paxo/dst': Waiting for transfers to finish
2025/11/27 04:35:56 DEBUG : Waiting for deletions to finish
2025/11/27 04:35:59 DEBUG : one: size = 5 OK
2025/11/27 04:35:59 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/11/27 04:35:59 DEBUG : one: Unchanged skipping
2025/11/27 04:35:59 DEBUG : two: size = 3 OK
2025/11/27 04:35:59 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/11/27 04:36:02 DEBUG : two: size = 3 OK
2025/11/27 04:36:02 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK
2025/11/27 04:36:02 INFO  : two: Copied (server-side copy)
2025/11/27 04:36:02 DEBUG : two: Destination found in --copy-dest, using server-side copy
2025/11/27 04:36:02 DEBUG : files root 'rclone-test-gateyas1paxo/dst': Waiting for checks to finish
2025/11/27 04:36:02 DEBUG : files root 'rclone-test-gateyas1paxo/dst': Waiting for transfers to finish
2025/11/27 04:36:02 DEBUG : Waiting for deletions to finish
2025/11/27 04:36:03 DEBUG : one: size = 5 OK
2025/11/27 04:36:03 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/11/27 04:36:03 DEBUG : one: Unchanged skipping
2025/11/27 04:36:03 DEBUG : two: size = 3 OK
2025/11/27 04:36:03 DEBUG : files root 'rclone-test-gateyas1paxo/dst': Waiting for checks to finish
2025/11/27 04:36:03 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/11/27 04:36:03 DEBUG : two: Unchanged skipping
2025/11/27 04:36:03 DEBUG : files root 'rclone-test-gateyas1paxo/dst': Waiting for transfers to finish
2025/11/27 04:36:03 DEBUG : Waiting for deletions to finish
2025/11/27 04:36:03 INFO  : There was nothing to transfer
2025/11/27 04:36:06 DEBUG : one: size = 5 OK
2025/11/27 04:36:06 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/11/27 04:36:06 DEBUG : one: Unchanged skipping
2025/11/27 04:36:06 DEBUG : three: size = 7 (Local file system at /tmp/rclone296622399)
2025/11/27 04:36:06 DEBUG : three: size = 5 (files root 'rclone-test-gateyas1paxo/CopyDest')
2025/11/27 04:36:06 DEBUG : three: Sizes differ
2025/11/27 04:36:06 DEBUG : three: Destination not found in --copy-dest
2025/11/27 04:36:06 DEBUG : three: Need to transfer - File not found at Destination
2025/11/27 04:36:06 DEBUG : files root 'rclone-test-gateyas1paxo/dst': Waiting for checks to finish
2025/11/27 04:36:06 DEBUG : two: size = 3 OK
2025/11/27 04:36:06 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/11/27 04:36:06 DEBUG : two: Unchanged skipping
2025/11/27 04:36:06 DEBUG : files root 'rclone-test-gateyas1paxo/dst': Waiting for transfers to finish
2025/11/27 04:36:07 DEBUG : three: size = 7 OK
2025/11/27 04:36:07 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK
2025/11/27 04:36:07 INFO  : three: Copied (new)
2025/11/27 04:36:07 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCopyDest (29.03s)
=== RUN   TestFixCase
    run.go:185: Remote "files root 'rclone-test-gateyas1paxo'", Local "Local file system at /tmp/rclone296622399", Modify Window "1s"
2025/11/27 04:36:14 DEBUG : EXISTING: size = 6 OK
2025/11/27 04:36:14 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/11/27 04:36:14 DEBUG : existing: Unchanged skipping
2025/11/27 04:36:14 DEBUG : existingbutdifferent: size = 5 (Local file system at /tmp/rclone296622399)
2025/11/27 04:36:14 DEBUG : EXISTINGBUTDIFFERENT: size = 8 (files root 'rclone-test-gateyas1paxo')
2025/11/27 04:36:14 DEBUG : existingbutdifferent: Sizes differ
2025/11/27 04:36:14 INFO  : SUBDIRA: Set directory modification time (using DirSetModTime)
2025/11/27 04:36:14 DEBUG : Added delayed dir = "SUBDIRA", newDst=SUBDIRA
2025/11/27 04:36:14 DEBUG : EXISTING: moving to existing-rclone-move-wesapuv1
2025/11/27 04:36:14 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-jixaxob9
2025/11/27 04:36:14 INFO  : EXISTING: Moved (server-side) to: existing-rclone-move-wesapuv1
2025/11/27 04:36:14 DEBUG : EXISTING: moving to existing
2025/11/27 04:36:14 INFO  : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-jixaxob9
2025/11/27 04:36:14 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent
2025/11/27 04:36:15 INFO  : existing-rclone-move-wesapuv1: Moved (server-side) to: existing
2025/11/27 04:36:15 INFO  : EXISTING: Moved (server-side) to: existing
2025/11/27 04:36:15 INFO  : EXISTING: Fixed case by renaming to: existing
2025/11/27 04:36:15 INFO  : existingbutdifferent-rclone-move-jixaxob9: Moved (server-side) to: existingbutdifferent
2025/11/27 04:36:15 INFO  : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent
2025/11/27 04:36:15 INFO  : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent
2025/11/27 04:36:15 INFO  : SUBDIRA: Fixed case by renaming to: subdira
2025/11/27 04:36:15 DEBUG : existingbutdifferent: size = 5 OK
2025/11/27 04:36:15 DEBUG : existingbutdifferent: Dst hash empty - aborting Src hash check
2025/11/27 04:36:15 INFO  : existingbutdifferent: Copied (replaced existing)
2025/11/27 04:36:16 INFO  : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb
2025/11/27 04:36:17 INFO  : SUBDIRA/subdirb/SUBDIRC: Set directory modification time (using DirSetModTime)
2025/11/27 04:36:17 DEBUG : Added delayed dir = "SUBDIRA/subdirb/SUBDIRC", newDst=SUBDIRA/subdirb/SUBDIRC
2025/11/27 04:36:18 INFO  : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc
2025/11/27 04:36:18 DEBUG : subdira/subdirb/subdirc/hello: size = 5 (Local file system at /tmp/rclone296622399)
2025/11/27 04:36:18 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: size = 8 (files root 'rclone-test-gateyas1paxo')
2025/11/27 04:36:18 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ
2025/11/27 04:36:18 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird: Set directory modification time (using DirSetModTime)
2025/11/27 04:36:18 DEBUG : Added delayed dir = "SUBDIRA/subdirb/SUBDIRC/subdird", newDst=SUBDIRA/subdirb/SUBDIRC/subdird
2025/11/27 04:36:18 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-lahoxih2
2025/11/27 04:36:18 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-lahoxih2
2025/11/27 04:36:18 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello
2025/11/27 04:36:19 INFO  : subdira/subdirb/subdirc/hello-rclone-move-lahoxih2: Moved (server-side) to: subdira/subdirb/subdirc/hello
2025/11/27 04:36:19 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello
2025/11/27 04:36:19 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello
2025/11/27 04:36:19 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird: Fixed case by renaming to: subdira/subdirb/subdirc/subdird
2025/11/27 04:36:20 DEBUG : files root 'rclone-test-gateyas1paxo': Waiting for checks to finish
2025/11/27 04:36:20 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: size = 5 (Local file system at /tmp/rclone296622399)
2025/11/27 04:36:20 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: size = 8 (files root 'rclone-test-gateyas1paxo')
2025/11/27 04:36:20 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Sizes differ
2025/11/27 04:36:20 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-modalaf0
2025/11/27 04:36:20 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-modalaf0
2025/11/27 04:36:20 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/11/27 04:36:20 DEBUG : subdira/subdirb/subdirc/hello: size = 5 OK
2025/11/27 04:36:20 DEBUG : subdira/subdirb/subdirc/hello: Dst hash empty - aborting Src hash check
2025/11/27 04:36:20 INFO  : subdira/subdirb/subdirc/hello: Copied (replaced existing)
2025/11/27 04:36:21 INFO  : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-modalaf0: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/11/27 04:36:21 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/11/27 04:36:21 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Fixed case by renaming to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/11/27 04:36:21 DEBUG : files root 'rclone-test-gateyas1paxo': Waiting for transfers to finish
2025/11/27 04:36:21 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: size = 5 OK
2025/11/27 04:36:21 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Dst hash empty - aborting Src hash check
2025/11/27 04:36:21 INFO  : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (replaced existing)
2025/11/27 04:36:21 ERROR : files root 'rclone-test-gateyas1paxo': not deleting files as there were IO errors
2025/11/27 04:36:21 ERROR : files root 'rclone-test-gateyas1paxo': not deleting directories as there were IO errors
    sync_test.go:2685: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2685
        	Error:      	Received unexpected error:
        	            	Not Found - `Not Found.  This may be related to your permissions.`
        	Test:       	TestFixCase
2025/11/27 04:36:23 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-gateyas1paxo/subdira not empty`)
2025/11/27 04:36:23 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/11/27 04:36:23 DEBUG : pacer: Reducing sleep to 15ms
2025/11/27 04:36:23 DEBUG : pacer: Reducing sleep to 11.25ms
--- FAIL: TestFixCase (14.02s)
FAIL
2025/11/27 04:36:24 DEBUG : files root 'rclone-test-gateyas1paxo': Purge remote
2025/11/27 04:36:24 DEBUG : pacer: Reducing sleep to 10ms
"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestFixCase|TestMoveOverSelf|TestSyncCopyDest)$'" - Finished ERROR in 49.249228427s (try 2/5): exit status 1: Failed [TestFixCase]