"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestServerSideMoveWithFilter|TestSyncOverlapWithFilter)$'" - Starting (try 2/5)
2025/04/21 01:13:42 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-mokozuc5fulu"
2025/04/21 01:13:42 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/04/21 01:13:42 DEBUG : Starting OpenDrive session with ID: 1a5743661cb57bd32bee3de9fe7de0dbf6387f932f6e8fca5db970e61c41b99d
2025/04/21 01:13:42 DEBUG : Creating backend with remote "/tmp/rclone703497960"
=== RUN   TestServerSideMoveWithFilter
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
2025/04/21 01:13:42 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-ponukux8mixe"
2025/04/21 01:13:43 DEBUG : Starting OpenDrive session with ID: 54976934b8909ca28903eab9b169d47deaa04acbab9582eb75c1bb9bb9888581
2025/04/21 01:13:45 DEBUG : potato2: Uploading chunk 0, size=60, remain=0
2025/04/21 01:13:46 DEBUG : empty space: Uploading chunk 0, size=1, remain=0
2025/04/21 01:13:48 DEBUG : potato3: Uploading chunk 0, size=68, remain=0
    sync_test.go:1705: Server side move (if possible) OpenDrive root 'rclone-test-mokozuc5fulu' -> OpenDrive root 'rclone-test-ponukux8mixe'
2025/04/21 01:13:51 DEBUG : empty space: Uploading chunk 0, size=1, remain=0
2025/04/21 01:13:52 DEBUG : potato3: Uploading chunk 0, size=60, remain=0
2025/04/21 01:13:54 DEBUG : empty space: Excluded (Size Filter)
2025/04/21 01:13:54 DEBUG : empty space: Excluded
2025/04/21 01:13:54 DEBUG : empty space: Excluded (Size Filter)
2025/04/21 01:13:54 DEBUG : empty space: Excluded
2025/04/21 01:13:54 DEBUG : potato2: Need to transfer - File not found at Destination
2025/04/21 01:13:54 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2025/04/21 01:13:54 DEBUG : OpenDrive root 'rclone-test-ponukux8mixe': Waiting for checks to finish
2025/04/21 01:13:54 DEBUG : OpenDrive root 'rclone-test-ponukux8mixe': Waiting for transfers to finish
2025/04/21 01:13:55 INFO  : potato3: Deleted
2025/04/21 01:13:55 INFO  : potato2: Moved (server-side)
2025/04/21 01:13:55 INFO  : potato3: Moved (server-side)
2025/04/21 01:13:55 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rupacud4rehu"
2025/04/21 01:13:56 DEBUG : Starting OpenDrive session with ID: 3c39f495a77e6e5d468e8fc7e688e7edf44e761747df030253b4d46b0838bc83
2025/04/21 01:13:56 DEBUG : empty space: Excluded (Size Filter)
2025/04/21 01:13:56 DEBUG : empty space: Excluded
2025/04/21 01:13:56 DEBUG : potato2: Need to transfer - File not found at Destination
2025/04/21 01:13:56 DEBUG : potato3: Need to transfer - File not found at Destination
2025/04/21 01:13:56 DEBUG : OpenDrive root 'rclone-test-rupacud4rehu': Waiting for checks to finish
2025/04/21 01:13:56 DEBUG : OpenDrive root 'rclone-test-rupacud4rehu': Waiting for transfers to finish
2025/04/21 01:13:57 INFO  : potato2: Moved (server-side)
2025/04/21 01:13:57 INFO  : potato3: Moved (server-side)
2025/04/21 01:13:57 DEBUG : OpenDrive root 'rclone-test-rupacud4rehu': Purge remote
2025/04/21 01:13:58 DEBUG : OpenDrive root 'rclone-test-ponukux8mixe': Purge remote
--- PASS: TestServerSideMoveWithFilter (16.24s)
=== RUN   TestSyncOverlapWithFilter
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
2025/04/21 01:13:59 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-mokozuc5fulu/rclone-sync-test"
2025/04/21 01:13:59 DEBUG : Starting OpenDrive session with ID: 6141c84f2e7261900bb16b4a1a89487df4e74990b330f06258c97ac7f492ce39
2025/04/21 01:14:00 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-mokozuc5fulu/rclone-sync-test-include/layer2"
2025/04/21 01:14:01 DEBUG : Starting OpenDrive session with ID: 33f95e11b7945c1b3bfe715547bfab8da1ded3259b4b52e95ea2ffda09fdefbe
2025/04/21 01:14:02 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-mokozuc5fulu/rclone-sync-test-ignore-file"
2025/04/21 01:14:03 DEBUG : Starting OpenDrive session with ID: cc1584cb26fdbbef2d226302ef2f8cc90b1e2e4644e2b4608ced4252eb1e7e3f
2025/04/21 01:14:05 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 0, size=1, remain=0
2025/04/21 01:14:07 DEBUG : rclone-sync-test: Excluded
2025/04/21 01:14:08 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/04/21 01:14:08 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/04/21 01:14:08 DEBUG : OpenDrive root 'rclone-test-mokozuc5fulu/rclone-sync-test': Waiting for checks to finish
2025/04/21 01:14:08 DEBUG : OpenDrive root 'rclone-test-mokozuc5fulu/rclone-sync-test': Waiting for transfers to finish
2025/04/21 01:14:08 DEBUG : Waiting for deletions to finish
2025/04/21 01:14:08 INFO  : There was nothing to transfer
2025/04/21 01:14:09 DEBUG : rclone-sync-test: Excluded
2025/04/21 01:14:09 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/04/21 01:14:09 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/04/21 01:14:09 DEBUG : OpenDrive root 'rclone-test-mokozuc5fulu': Waiting for checks to finish
2025/04/21 01:14:09 DEBUG : OpenDrive root 'rclone-test-mokozuc5fulu': Waiting for transfers to finish
2025/04/21 01:14:09 DEBUG : Waiting for deletions to finish
2025/04/21 01:14:09 INFO  : rclone-sync-test-include: Removing directory
2025/04/21 01:14:10 DEBUG : OpenDrive root 'rclone-test-mokozuc5fulu': deleted 1 directories
2025/04/21 01:14:10 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
2025/04/21 01:14:10 DEBUG : rclone-sync-test: Excluded
2025/04/21 01:14:11 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/04/21 01:14:11 DEBUG : OpenDrive root 'rclone-test-mokozuc5fulu/rclone-sync-test-include/layer2': Waiting for checks to finish
2025/04/21 01:14:11 DEBUG : OpenDrive root 'rclone-test-mokozuc5fulu/rclone-sync-test-include/layer2': Waiting for transfers to finish
2025/04/21 01:14:11 DEBUG : Waiting for deletions to finish
2025/04/21 01:14:11 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
2025/04/21 01:14:11 DEBUG : rclone-sync-test: Excluded
2025/04/21 01:14:11 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/04/21 01:14:11 DEBUG : OpenDrive root 'rclone-test-mokozuc5fulu': Waiting for checks to finish
2025/04/21 01:14:11 DEBUG : OpenDrive root 'rclone-test-mokozuc5fulu': Waiting for transfers to finish
2025/04/21 01:14:11 DEBUG : Waiting for deletions to finish
2025/04/21 01:14:11 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
2025/04/21 01:14:12 DEBUG : : Excluded
2025/04/21 01:14:12 DEBUG : rclone-sync-test: Excluded
2025/04/21 01:14:12 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/04/21 01:14:12 DEBUG : OpenDrive root 'rclone-test-mokozuc5fulu/rclone-sync-test-ignore-file': Waiting for checks to finish
2025/04/21 01:14:12 DEBUG : OpenDrive root 'rclone-test-mokozuc5fulu/rclone-sync-test-ignore-file': Waiting for transfers to finish
2025/04/21 01:14:12 DEBUG : Waiting for deletions to finish
2025/04/21 01:14:12 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-mokozuc5fulu'", Local "Local file system at /tmp/rclone703497960", Modify Window "1s"
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
    fstest.go:286: Sleeping for 6s just to make sure
--- PASS: TestSyncOverlapWithFilter (28.44s)
PASS
2025/04/21 01:14:27 DEBUG : OpenDrive root 'rclone-test-mokozuc5fulu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestServerSideMoveWithFilter|TestSyncOverlapWithFilter)$'" - Finished OK in 46.07581095s (try 2/5)