"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCopyAfterDelete|TestCopyOverSelf|TestCopyRedownload|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestMoveWithoutDeleteEmptySrcDirs|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories)$'" - Starting (try 2/5)
2024/11/05 01:21:32 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-focaxoz6geda"
2024/11/05 01:21:32 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/05 01:21:32 DEBUG : Creating backend with remote "/tmp/rclone2279305582"
=== RUN   TestSyncEmptyDirectories
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:21:32 DEBUG : sub dir2: Making directory with metadata
2024/11/05 01:21:32 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/11/05 01:21:32 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/11/05 01:21:34 DEBUG : sub dir: Making directory
2024/11/05 01:21:35 DEBUG : sub dir2: Making directory
2024/11/05 01:21:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/05 01:21:37 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Waiting for checks to finish
2024/11/05 01:21:37 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Waiting for transfers to finish
2024/11/05 01:21:39 INFO  : sub dir/hello world: Copied (new)
2024/11/05 01:21:39 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:21:39 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestSyncEmptyDirectories (12.95s)
=== RUN   TestSyncNoEmptyDirectories
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:21:45 DEBUG : sub dir2: Making directory
2024/11/05 01:21:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/05 01:21:45 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Waiting for checks to finish
2024/11/05 01:21:45 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Waiting for transfers to finish
2024/11/05 01:21:49 INFO  : sub dir/hello world: Copied (new)
2024/11/05 01:21:49 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoEmptyDirectories (7.53s)
=== RUN   TestServerSideCopy
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:21:56 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-kojuwod6yiqe"
2024/11/05 01:21:56 DEBUG : Config file has changed externally - reloading
    sync_test.go:590: Server side copy (if possible) Linkbox root 'rclone-test-focaxoz6geda' -> Linkbox root 'rclone-test-kojuwod6yiqe'
2024/11/05 01:21:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/05 01:21:57 DEBUG : Linkbox root 'rclone-test-kojuwod6yiqe': Waiting for checks to finish
2024/11/05 01:21:57 DEBUG : Linkbox root 'rclone-test-kojuwod6yiqe': Waiting for transfers to finish
2024/11/05 01:22:02 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:22:03 DEBUG : Linkbox root 'rclone-test-kojuwod6yiqe': Purge remote
--- PASS: TestServerSideCopy (15.46s)
=== RUN   TestCopyOverSelf
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:22:12 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Waiting for checks to finish
2024/11/05 01:22:12 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/11/05 01:22:12 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Waiting for transfers to finish
2024/11/05 01:22:12 DEBUG : sub dir/hello world: Update: removing old file
2024/11/05 01:22:15 INFO  : sub dir/hello world: Copied (replaced existing)
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
--- PASS: TestCopyOverSelf (11.69s)
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:22:24 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-figiset6jogu"
    sync_test.go:626: Server side copy (if possible) Linkbox root 'rclone-test-focaxoz6geda' -> Linkbox root 'rclone-test-figiset6jogu'
2024/11/05 01:22:24 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/05 01:22:24 DEBUG : Linkbox root 'rclone-test-figiset6jogu': Waiting for checks to finish
2024/11/05 01:22:24 DEBUG : Linkbox root 'rclone-test-figiset6jogu': Waiting for transfers to finish
2024/11/05 01:22:29 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:22:30 DEBUG : sub dir/hello world: Update: removing old file
2024/11/05 01:22:34 DEBUG : Linkbox root 'rclone-test-figiset6jogu': Waiting for checks to finish
2024/11/05 01:22:34 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/11/05 01:22:34 DEBUG : Linkbox root 'rclone-test-figiset6jogu': Waiting for transfers to finish
2024/11/05 01:22:34 DEBUG : sub dir/hello world: Update: removing old file
2024/11/05 01:22:37 INFO  : sub dir/hello world: Copied (replaced existing)
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:22:37 DEBUG : Linkbox root 'rclone-test-figiset6jogu': Purge remote
--- PASS: TestServerSideCopyOverSelf (23.30s)
=== RUN   TestMoveOverSelf
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:22:47 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/11/05 01:22:47 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Waiting for checks to finish
2024/11/05 01:22:47 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Waiting for transfers to finish
2024/11/05 01:22:47 DEBUG : sub dir/hello world: Update: removing old file
2024/11/05 01:22:50 INFO  : sub dir/hello world: Copied (replaced existing)
2024/11/05 01:22:50 INFO  : sub dir/hello world: Deleted
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
--- PASS: TestMoveOverSelf (11.63s)
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:22:59 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-wividil3hoko"
    sync_test.go:671: Server side copy (if possible) Linkbox root 'rclone-test-focaxoz6geda' -> Linkbox root 'rclone-test-wividil3hoko'
2024/11/05 01:22:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/05 01:22:59 DEBUG : Linkbox root 'rclone-test-wividil3hoko': Waiting for checks to finish
2024/11/05 01:22:59 DEBUG : Linkbox root 'rclone-test-wividil3hoko': Waiting for transfers to finish
2024/11/05 01:23:04 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:23:05 DEBUG : sub dir/hello world: Update: removing old file
2024/11/05 01:23:09 DEBUG : Linkbox root 'rclone-test-wividil3hoko': Waiting for checks to finish
2024/11/05 01:23:09 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/11/05 01:23:09 DEBUG : Linkbox root 'rclone-test-wividil3hoko': Waiting for transfers to finish
2024/11/05 01:23:09 DEBUG : sub dir/hello world: Update: removing old file
2024/11/05 01:23:12 INFO  : sub dir/hello world: Copied (replaced existing)
2024/11/05 01:23:13 INFO  : sub dir/hello world: Deleted
2024/11/05 01:23:17 DEBUG : testing file moves
2024/11/05 01:23:17 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2024/11/05 01:23:17 DEBUG : Linkbox root 'rclone-test-wividil3hoko': Waiting for checks to finish
2024/11/05 01:23:17 DEBUG : Linkbox root 'rclone-test-wividil3hoko': Waiting for transfers to finish
2024/11/05 01:23:17 DEBUG : sub dir/hello world: Update: removing old file
2024/11/05 01:23:20 INFO  : sub dir/hello world: Copied (replaced existing)
2024/11/05 01:23:21 INFO  : sub dir/hello world: Deleted
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:23:22 DEBUG : Linkbox root 'rclone-test-wividil3hoko': Purge remote
--- PASS: TestServerSideMoveOverSelf (31.81s)
=== RUN   TestCopyAfterDelete
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:23:30 ERROR : : error listing: directory not found
2024/11/05 01:23:30 DEBUG : Local file system at /tmp/rclone2279305582: Making directory
2024/11/05 01:23:31 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Waiting for checks to finish
2024/11/05 01:23:31 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Waiting for transfers to finish
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
--- PASS: TestCopyAfterDelete (8.58s)
=== RUN   TestCopyRedownload
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:23:39 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/11/05 01:23:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/05 01:23:39 DEBUG : Local file system at /tmp/rclone2279305582: Waiting for checks to finish
2024/11/05 01:23:39 DEBUG : Local file system at /tmp/rclone2279305582: Waiting for transfers to finish
2024/11/05 01:23:39 DEBUG : sub dir/hello world.d65a1577.partial: renamed to: sub dir/hello world
2024/11/05 01:23:39 INFO  : sub dir/hello world: Copied (new)
2024/11/05 01:23:39 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
--- PASS: TestCopyRedownload (8.34s)
=== RUN   TestMoveWithDeleteEmptySrcDirs
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:23:44 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/05 01:23:44 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2024/11/05 01:23:44 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Waiting for checks to finish
2024/11/05 01:23:44 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Waiting for transfers to finish
2024/11/05 01:23:50 INFO  : sub dir/hello world: Copied (new)
2024/11/05 01:23:50 INFO  : sub dir/hello world: Deleted
2024/11/05 01:23:51 INFO  : nested/sub dir/file: Copied (new)
2024/11/05 01:23:51 INFO  : nested/sub dir/file: Deleted
2024/11/05 01:23:51 INFO  : sub dir: Removing directory
2024/11/05 01:23:51 INFO  : nested/sub dir: Removing directory
2024/11/05 01:23:51 INFO  : nested: Removing directory
2024/11/05 01:23:51 DEBUG : Local file system at /tmp/rclone2279305582: deleted 3 directories
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
--- PASS: TestMoveWithDeleteEmptySrcDirs (16.66s)
=== RUN   TestMoveWithoutDeleteEmptySrcDirs
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:24:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/05 01:24:00 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2024/11/05 01:24:00 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Waiting for checks to finish
2024/11/05 01:24:00 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Waiting for transfers to finish
2024/11/05 01:24:07 INFO  : sub dir/hello world: Copied (new)
2024/11/05 01:24:07 INFO  : sub dir/hello world: Deleted
2024/11/05 01:24:07 INFO  : nested/sub dir/file: Copied (new)
2024/11/05 01:24:07 INFO  : nested/sub dir/file: Deleted
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
--- PASS: TestMoveWithoutDeleteEmptySrcDirs (16.70s)
=== RUN   TestNothingToTransferWithEmptyDirs
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:24:17 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/11/05 01:24:17 DEBUG : sub dir: Making directory
2024/11/05 01:24:18 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
--- PASS: TestNothingToTransferWithEmptyDirs (41.71s)
=== RUN   TestNothingToTransferWithoutEmptyDirs
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
2024/11/05 01:24:58 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/11/05 01:24:58 DEBUG : sub dir: Making directory
2024/11/05 01:25:00 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
    run.go:180: Remote "Linkbox root 'rclone-test-focaxoz6geda'", Local "Local file system at /tmp/rclone2279305582", Modify Window "876000h0m0s"
--- PASS: TestNothingToTransferWithoutEmptyDirs (41.81s)
PASS
2024/11/05 01:25:40 DEBUG : Linkbox root 'rclone-test-focaxoz6geda': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCopyAfterDelete|TestCopyOverSelf|TestCopyRedownload|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestMoveWithoutDeleteEmptySrcDirs|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories)$'" - Finished OK in 4m9.920981628s (try 2/5)