"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyDeleteBefore|TestSyncAfterChangingFilesSizeOnly|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncOverlapWithFilter)$'" - Starting (try 2/5)
2025/03/19 03:56:36 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-piyiroc5lefo"
2025/03/19 03:56:36 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/19 03:56:37 DEBUG : Starting OpenDrive session with ID: 7a28ef5d660d6f487623630e2c05338bb59dd5d7994da250a9b31eef630ca3aa
2025/03/19 03:56:37 DEBUG : Creating backend with remote "/tmp/rclone1973488852"
=== RUN   TestSyncAfterChangingFilesSizeOnly
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
2025/03/19 03:56:38 DEBUG : potato: Uploading chunk 0, size=60, remain=0
2025/03/19 03:56:40 DEBUG : potato: Sizes differ (src 21 vs dst 60)
2025/03/19 03:56:40 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo': Waiting for checks to finish
2025/03/19 03:56:40 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo': Waiting for transfers to finish
2025/03/19 03:56:40 DEBUG : potato: Uploading chunk 0, size=21, remain=0
2025/03/19 03:56:41 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 OK
2025/03/19 03:56:41 INFO  : potato: Copied (replaced existing)
2025/03/19 03:56:41 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
--- PASS: TestSyncAfterChangingFilesSizeOnly (4.25s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
2025/03/19 03:56:43 DEBUG : b/potato: Uploading chunk 0, size=21, remain=0
2025/03/19 03:56:45 DEBUG : c/non empty space: Uploading chunk 0, size=5, remain=0
2025/03/19 03:56:46 INFO  : d: Making directory
2025/03/19 03:56:46 INFO  : d/e: Making directory
2025/03/19 03:56:47 DEBUG : a/potato2: Need to transfer - File not found at Destination
2025/03/19 03:56:47 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/19 03:56:47 DEBUG : c/non empty space: Unchanged skipping
2025/03/19 03:56:48 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo': Waiting for checks to finish
2025/03/19 03:56:48 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo': Waiting for transfers to finish
2025/03/19 03:56:48 DEBUG : a/potato2: Uploading chunk 0, size=60, remain=0
2025/03/19 03:56:49 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/03/19 03:56:49 INFO  : a/potato2: Copied (new)
2025/03/19 03:56:49 DEBUG : Waiting for deletions to finish
2025/03/19 03:56:49 INFO  : b/potato: Deleted
2025/03/19 03:56:49 INFO  : d/e: Removing directory
2025/03/19 03:56:50 INFO  : d: Removing directory
2025/03/19 03:56:50 INFO  : b: Removing directory
2025/03/19 03:56:50 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo': deleted 3 directories
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", 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: TestSyncAfterRemovingAFileAndAddingAFileSubDir (18.61s)
=== RUN   TestCopyDeleteBefore
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
2025/03/19 03:57:01 DEBUG : potato: Uploading chunk 0, size=21, remain=0
2025/03/19 03:57:02 DEBUG : potato2: Need to transfer - File not found at Destination
2025/03/19 03:57:02 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo': Waiting for checks to finish
2025/03/19 03:57:02 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo': Waiting for transfers to finish
2025/03/19 03:57:03 DEBUG : potato2: Uploading chunk 0, size=19, remain=0
2025/03/19 03:57:04 DEBUG : potato2: md5 = 9519bc2e940e1696ea7365b4b81adedc OK
2025/03/19 03:57:04 INFO  : potato2: Copied (new)
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
--- PASS: TestCopyDeleteBefore (4.86s)
=== RUN   TestSyncOverlapWithFilter
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
2025/03/19 03:57:05 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-piyiroc5lefo/rclone-sync-test"
2025/03/19 03:57:05 DEBUG : Starting OpenDrive session with ID: 099dc0414edeca3db0cc05393f6b9178ced04953d5a2b94c5e5b62a24c84e920
2025/03/19 03:57:06 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-piyiroc5lefo/rclone-sync-test-include/layer2"
2025/03/19 03:57:07 DEBUG : Starting OpenDrive session with ID: 2a19994e06d863d0d24e70498361a0bde48166259e1751061c54194abd8bb9c3
2025/03/19 03:57:08 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-piyiroc5lefo/rclone-sync-test-ignore-file"
2025/03/19 03:57:09 DEBUG : Starting OpenDrive session with ID: c5496324d2744a6dceeddd649c76c3234984e5942a4a74f46733ac47fad2d10e
2025/03/19 03:57:11 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 0, size=1, remain=0
2025/03/19 03:57:12 DEBUG : rclone-sync-test: Excluded
2025/03/19 03:57:12 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/19 03:57:13 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/03/19 03:57:13 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo/rclone-sync-test': Waiting for checks to finish
2025/03/19 03:57:13 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo/rclone-sync-test': Waiting for transfers to finish
2025/03/19 03:57:13 DEBUG : Waiting for deletions to finish
2025/03/19 03:57:13 INFO  : There was nothing to transfer
2025/03/19 03:57:13 DEBUG : rclone-sync-test: Excluded
2025/03/19 03:57:13 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/19 03:57:14 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/03/19 03:57:14 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo': Waiting for checks to finish
2025/03/19 03:57:14 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo': Waiting for transfers to finish
2025/03/19 03:57:14 DEBUG : Waiting for deletions to finish
2025/03/19 03:57:14 INFO  : rclone-sync-test-include: Removing directory
2025/03/19 03:57:14 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo': deleted 1 directories
2025/03/19 03:57:14 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
2025/03/19 03:57:15 DEBUG : rclone-sync-test: Excluded
2025/03/19 03:57:15 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/19 03:57:15 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo/rclone-sync-test-include/layer2': Waiting for checks to finish
2025/03/19 03:57:15 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo/rclone-sync-test-include/layer2': Waiting for transfers to finish
2025/03/19 03:57:15 DEBUG : Waiting for deletions to finish
2025/03/19 03:57:15 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
2025/03/19 03:57:15 DEBUG : rclone-sync-test: Excluded
2025/03/19 03:57:16 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/19 03:57:16 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo': Waiting for checks to finish
2025/03/19 03:57:16 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo': Waiting for transfers to finish
2025/03/19 03:57:16 DEBUG : Waiting for deletions to finish
2025/03/19 03:57:16 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
2025/03/19 03:57:16 DEBUG : : Excluded
2025/03/19 03:57:16 DEBUG : rclone-sync-test: Excluded
2025/03/19 03:57:16 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/19 03:57:16 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo/rclone-sync-test-ignore-file': Waiting for checks to finish
2025/03/19 03:57:16 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo/rclone-sync-test-ignore-file': Waiting for transfers to finish
2025/03/19 03:57:16 DEBUG : Waiting for deletions to finish
2025/03/19 03:57:16 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-piyiroc5lefo'", Local "Local file system at /tmp/rclone1973488852", Modify Window "1s"
--- PASS: TestSyncOverlapWithFilter (18.50s)
PASS
2025/03/19 03:57:23 DEBUG : OpenDrive root 'rclone-test-piyiroc5lefo': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyDeleteBefore|TestSyncAfterChangingFilesSizeOnly|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncOverlapWithFilter)$'" - Finished OK in 47.484170813s (try 2/5)