"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCopyAfterDelete|TestCopyDeleteBefore|TestCopyOverSelf|TestCopyRedownload|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestMoveWithIgnoreExisting|TestMoveWithoutDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveOverlap|TestServerSideMoveWithFilter|TestSyncAfterAddingAFile|TestSyncAfterChangingContentsOnly|TestSyncAfterChangingFilesSizeOnly|TestSyncAfterChangingModtimeOnly|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncAfterRemovingAFileAndAddingAFileDryRun|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors|TestSyncBasedOnCheckSum|TestSyncDeleteBefore|TestSyncDeleteDuring|TestSyncIgnoreErrors|TestSyncIgnoreExisting|TestSyncIgnoreSize|TestSyncIgnoreTimes|TestSyncNoEmptyDirectories|TestSyncSizeOnly|TestSyncWithExclude|TestSyncWithExcludeAndDeleteExcluded|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 2/5)
2024/11/04 01:14:05 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-josehog4nupu"
2024/11/04 01:14:05 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/04 01:14:05 DEBUG : Creating backend with remote "/tmp/rclone4225971449"
=== RUN   TestSyncNoEmptyDirectories
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:14:05 DEBUG : sub dir2: Making directory
2024/11/04 01:14:07 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/04 01:14:07 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:14:07 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:14:11 INFO  : sub dir/hello world: Copied (new)
2024/11/04 01:14:11 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoEmptyDirectories (9.07s)
=== RUN   TestServerSideCopy
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:14:18 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-febiwoh3muje"
    sync_test.go:590: Server side copy (if possible) Linkbox root 'rclone-test-josehog4nupu' -> Linkbox root 'rclone-test-febiwoh3muje'
2024/11/04 01:14:19 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/04 01:14:19 DEBUG : Linkbox root 'rclone-test-febiwoh3muje': Waiting for checks to finish
2024/11/04 01:14:19 DEBUG : Linkbox root 'rclone-test-febiwoh3muje': Waiting for transfers to finish
2024/11/04 01:14:24 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:14:25 DEBUG : Linkbox root 'rclone-test-febiwoh3muje': Purge remote
--- PASS: TestServerSideCopy (15.40s)
=== RUN   TestCopyOverSelf
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:14:34 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:14:34 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/11/04 01:14:34 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:14:34 DEBUG : sub dir/hello world: Update: removing old file
2024/11/04 01:14:37 INFO  : sub dir/hello world: Copied (replaced existing)
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestCopyOverSelf (11.73s)
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:14:45 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-tirepij8moqu"
    sync_test.go:626: Server side copy (if possible) Linkbox root 'rclone-test-josehog4nupu' -> Linkbox root 'rclone-test-tirepij8moqu'
2024/11/04 01:14:46 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/04 01:14:46 DEBUG : Linkbox root 'rclone-test-tirepij8moqu': Waiting for checks to finish
2024/11/04 01:14:46 DEBUG : Linkbox root 'rclone-test-tirepij8moqu': Waiting for transfers to finish
2024/11/04 01:14:51 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:14:52 DEBUG : sub dir/hello world: Update: removing old file
2024/11/04 01:14:55 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10)
2024/11/04 01:14:55 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10)
2024/11/04 01:14:56 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10)
2024/11/04 01:14:56 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 800ms (4/10)
2024/11/04 01:14:57 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 1.6s (5/10)
2024/11/04 01:14:59 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 3.2s (6/10)
2024/11/04 01:15:02 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 6.4s (7/10)
2024/11/04 01:15:09 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 12.8s (8/10)
2024/11/04 01:15:22 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 25.6s (9/10)
2024/11/04 01:15:48 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 51.2s (10/10)
    run.go:285: Failed to put "sub dir/hello world" to "Linkbox root 'rclone-test-josehog4nupu'": object not found
2024/11/04 01:16:39 DEBUG : Linkbox root 'rclone-test-tirepij8moqu': Purge remote
--- FAIL: TestServerSideCopyOverSelf (122.52s)
=== RUN   TestMoveOverSelf
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:16:48 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/11/04 01:16:48 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:16:48 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:16:48 DEBUG : sub dir/hello world: Update: removing old file
2024/11/04 01:16:51 INFO  : sub dir/hello world: Copied (replaced existing)
2024/11/04 01:16:51 INFO  : sub dir/hello world: Deleted
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestMoveOverSelf (11.77s)
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:17:00 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-putexeh9xotu"
    sync_test.go:671: Server side copy (if possible) Linkbox root 'rclone-test-josehog4nupu' -> Linkbox root 'rclone-test-putexeh9xotu'
2024/11/04 01:17:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/04 01:17:01 DEBUG : Linkbox root 'rclone-test-putexeh9xotu': Waiting for checks to finish
2024/11/04 01:17:01 DEBUG : Linkbox root 'rclone-test-putexeh9xotu': Waiting for transfers to finish
2024/11/04 01:17:06 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:17:06 DEBUG : sub dir/hello world: Update: removing old file
2024/11/04 01:17:10 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/11/04 01:17:10 DEBUG : Linkbox root 'rclone-test-putexeh9xotu': Waiting for checks to finish
2024/11/04 01:17:10 DEBUG : Linkbox root 'rclone-test-putexeh9xotu': Waiting for transfers to finish
2024/11/04 01:17:10 DEBUG : sub dir/hello world: Update: removing old file
2024/11/04 01:17:13 INFO  : sub dir/hello world: Copied (replaced existing)
2024/11/04 01:17:15 INFO  : sub dir/hello world: Deleted
2024/11/04 01:17:18 DEBUG : testing file moves
2024/11/04 01:17:18 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2024/11/04 01:17:18 DEBUG : Linkbox root 'rclone-test-putexeh9xotu': Waiting for checks to finish
2024/11/04 01:17:18 DEBUG : Linkbox root 'rclone-test-putexeh9xotu': Waiting for transfers to finish
2024/11/04 01:17:18 DEBUG : sub dir/hello world: Update: removing old file
2024/11/04 01:17:21 INFO  : sub dir/hello world: Copied (replaced existing)
2024/11/04 01:17:23 INFO  : sub dir/hello world: Deleted
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:17:24 DEBUG : Linkbox root 'rclone-test-putexeh9xotu': Purge remote
--- PASS: TestServerSideMoveOverSelf (32.02s)
=== RUN   TestCopyAfterDelete
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:17:31 ERROR : : error listing: directory not found
2024/11/04 01:17:32 DEBUG : Local file system at /tmp/rclone4225971449: Making directory
2024/11/04 01:17:32 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:17:32 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestCopyAfterDelete (8.51s)
=== RUN   TestCopyRedownload
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:17:40 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/11/04 01:17:41 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/04 01:17:41 DEBUG : Local file system at /tmp/rclone4225971449: Waiting for checks to finish
2024/11/04 01:17:41 DEBUG : Local file system at /tmp/rclone4225971449: Waiting for transfers to finish
2024/11/04 01:17:41 DEBUG : sub dir/hello world.d65a1577.partial: renamed to: sub dir/hello world
2024/11/04 01:17:41 INFO  : sub dir/hello world: Copied (new)
2024/11/04 01:17:41 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestCopyRedownload (8.27s)
=== RUN   TestSyncBasedOnCheckSum
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:17:45 DEBUG : check sum: Need to transfer - File not found at Destination
2024/11/04 01:17:45 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:17:45 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:17:47 INFO  : check sum: Copied (new)
2024/11/04 01:17:47 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:17:47 NOTICE: Linkbox root 'rclone-test-josehog4nupu': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2024/11/04 01:17:47 DEBUG : check sum: Size of src and dst objects identical
2024/11/04 01:17:47 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:17:47 DEBUG : check sum: Unchanged skipping
2024/11/04 01:17:47 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:17:47 DEBUG : Waiting for deletions to finish
2024/11/04 01:17:47 INFO  : There was nothing to transfer
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncBasedOnCheckSum (5.29s)
=== RUN   TestSyncSizeOnly
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:17:50 DEBUG : sizeonly: Need to transfer - File not found at Destination
2024/11/04 01:17:50 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:17:50 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:17:52 INFO  : sizeonly: Copied (new)
2024/11/04 01:17:52 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:17:53 DEBUG : sizeonly: Sizes identical
2024/11/04 01:17:53 DEBUG : sizeonly: Unchanged skipping
2024/11/04 01:17:53 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:17:53 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:17:53 DEBUG : Waiting for deletions to finish
2024/11/04 01:17:53 INFO  : There was nothing to transfer
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncSizeOnly (5.30s)
=== RUN   TestSyncIgnoreSize
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:17:55 DEBUG : ignore-size: Need to transfer - File not found at Destination
2024/11/04 01:17:55 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:17:55 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:17:57 INFO  : ignore-size: Copied (new)
2024/11/04 01:17:57 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:17:58 DEBUG : ignore-size: Sizes identical
2024/11/04 01:17:58 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:17:58 DEBUG : ignore-size: Unchanged skipping
2024/11/04 01:17:58 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:17:58 DEBUG : Waiting for deletions to finish
2024/11/04 01:17:58 INFO  : There was nothing to transfer
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncIgnoreSize (5.30s)
=== RUN   TestSyncIgnoreTimes
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:18:03 DEBUG : existing: Sizes identical
2024/11/04 01:18:03 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:18:03 DEBUG : existing: Unchanged skipping
2024/11/04 01:18:03 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:18:03 DEBUG : Waiting for deletions to finish
2024/11/04 01:18:03 INFO  : There was nothing to transfer
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:18:03 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:18:03 DEBUG : existing: Transferring unconditionally as --ignore-times is in use
2024/11/04 01:18:03 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:18:03 DEBUG : existing: Update: removing old file
2024/11/04 01:18:06 INFO  : existing: Copied (replaced existing)
2024/11/04 01:18:06 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncIgnoreTimes (8.40s)
=== RUN   TestSyncIgnoreExisting
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:18:09 DEBUG : existing: Need to transfer - File not found at Destination
2024/11/04 01:18:09 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:18:09 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:18:11 INFO  : existing: Copied (new)
2024/11/04 01:18:11 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:18:11 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:18:11 DEBUG : existing: Destination exists, skipping
2024/11/04 01:18:11 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:18:11 DEBUG : Waiting for deletions to finish
2024/11/04 01:18:11 INFO  : There was nothing to transfer
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncIgnoreExisting (5.27s)
=== RUN   TestSyncIgnoreErrors
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:18:21 DEBUG : d: Making directory
2024/11/04 01:18:24 DEBUG : a/potato2: Need to transfer - File not found at Destination
2024/11/04 01:18:24 DEBUG : c/non empty space: Sizes identical
2024/11/04 01:18:24 DEBUG : c/non empty space: Unchanged skipping
2024/11/04 01:18:24 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:18:24 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:18:28 INFO  : a/potato2: Copied (new)
2024/11/04 01:18:28 DEBUG : Waiting for deletions to finish
2024/11/04 01:18:29 INFO  : b/potato: Deleted
2024/11/04 01:18:29 INFO  : d: Removing directory
2024/11/04 01:18:31 INFO  : b: Removing directory
2024/11/04 01:18:32 DEBUG : Linkbox root 'rclone-test-josehog4nupu': deleted 2 directories
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncIgnoreErrors (25.30s)
=== RUN   TestSyncAfterChangingModtimeOnly
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:18:42 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:18:42 DEBUG : empty space: Sizes identical
2024/11/04 01:18:42 DEBUG : empty space: Unchanged skipping
2024/11/04 01:18:42 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:18:42 DEBUG : Waiting for deletions to finish
2024/11/04 01:18:42 INFO  : There was nothing to transfer
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:18:42 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:18:42 DEBUG : empty space: Sizes identical
2024/11/04 01:18:42 DEBUG : empty space: Unchanged skipping
2024/11/04 01:18:42 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:18:42 DEBUG : Waiting for deletions to finish
2024/11/04 01:18:42 INFO  : There was nothing to transfer
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncAfterChangingModtimeOnly (5.49s)
=== RUN   TestSyncAfterAddingAFile
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:18:47 DEBUG : potato: Need to transfer - File not found at Destination
2024/11/04 01:18:47 DEBUG : empty space: Sizes identical
2024/11/04 01:18:47 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:18:47 DEBUG : empty space: Unchanged skipping
2024/11/04 01:18:47 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:18:49 INFO  : potato: Copied (new)
2024/11/04 01:18:49 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncAfterAddingAFile (8.05s)
=== RUN   TestSyncAfterChangingFilesSizeOnly
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:18:55 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:18:55 DEBUG : potato: Sizes differ (src 21 vs dst 60)
2024/11/04 01:18:55 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:18:55 DEBUG : potato: Update: removing old file
2024/11/04 01:18:59 INFO  : potato: Copied (replaced existing)
2024/11/04 01:18:59 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncAfterChangingFilesSizeOnly (7.79s)
=== RUN   TestSyncAfterChangingContentsOnly
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
    sync_test.go:1106: ModTimeNotSupported so forcing file to be a different size
2024/11/04 01:19:03 DEBUG : potato: Sizes differ (src 21 vs dst 36)
2024/11/04 01:19:03 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:19:03 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:19:03 DEBUG : potato: Update: removing old file
2024/11/04 01:19:06 INFO  : potato: Copied (replaced existing)
2024/11/04 01:19:06 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncAfterChangingContentsOnly (7.85s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileDryRun
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:19:13 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/04 01:19:13 DEBUG : empty space: Sizes identical
2024/11/04 01:19:13 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:19:13 DEBUG : empty space: Unchanged skipping
2024/11/04 01:19:13 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:19:13 NOTICE: potato2: Skipped copy as --dry-run is set (size 60)
2024/11/04 01:19:13 DEBUG : Waiting for deletions to finish
2024/11/04 01:19:13 NOTICE: potato: Skipped delete as --dry-run is set (size 21)
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (7.81s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFile
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:19:21 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/04 01:19:21 DEBUG : empty space: Sizes identical
2024/11/04 01:19:21 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:19:21 DEBUG : empty space: Unchanged skipping
2024/11/04 01:19:21 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:19:23 INFO  : potato2: Copied (new)
2024/11/04 01:19:23 DEBUG : Waiting for deletions to finish
2024/11/04 01:19:24 INFO  : potato: Deleted
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFile (11.28s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:19:35 DEBUG : d: Making directory
2024/11/04 01:19:36 DEBUG : d/e: Making directory
2024/11/04 01:19:39 DEBUG : a/potato2: Need to transfer - File not found at Destination
2024/11/04 01:19:39 DEBUG : c/non empty space: Sizes identical
2024/11/04 01:19:39 DEBUG : c/non empty space: Unchanged skipping
2024/11/04 01:19:41 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:19:41 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:19:43 INFO  : a/potato2: Copied (new)
2024/11/04 01:19:43 DEBUG : Waiting for deletions to finish
2024/11/04 01:19:45 INFO  : b/potato: Deleted
2024/11/04 01:19:45 INFO  : d/e: Removing directory
2024/11/04 01:19:46 INFO  : d: Removing directory
2024/11/04 01:19:48 INFO  : b: Removing directory
2024/11/04 01:19:49 DEBUG : Linkbox root 'rclone-test-josehog4nupu': deleted 3 directories
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (28.68s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:20:03 DEBUG : d: Making directory
2024/11/04 01:20:06 DEBUG : a/potato2: Need to transfer - File not found at Destination
2024/11/04 01:20:06 DEBUG : c/non empty space: Sizes identical
2024/11/04 01:20:06 DEBUG : c/non empty space: Unchanged skipping
2024/11/04 01:20:07 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:20:07 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:20:10 INFO  : a/potato2: Copied (new)
2024/11/04 01:20:10 ERROR : Linkbox root 'rclone-test-josehog4nupu': not deleting files as there were IO errors
2024/11/04 01:20:10 ERROR : Linkbox root 'rclone-test-josehog4nupu': not deleting directories as there were IO errors
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (26.07s)
=== RUN   TestSyncDeleteDuring
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:20:27 DEBUG : Waiting for deletions to finish
2024/11/04 01:20:27 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/04 01:20:27 DEBUG : empty space: Sizes identical
2024/11/04 01:20:27 DEBUG : empty space: Unchanged skipping
2024/11/04 01:20:27 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:20:27 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:20:28 INFO  : potato: Deleted
2024/11/04 01:20:29 INFO  : potato2: Copied (new)
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncDeleteDuring (10.22s)
=== RUN   TestSyncDeleteBefore
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:20:37 DEBUG : Waiting for deletions to finish
2024/11/04 01:20:37 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:20:37 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:20:38 INFO  : potato: Deleted
2024/11/04 01:20:39 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/04 01:20:39 DEBUG : empty space: Sizes identical
2024/11/04 01:20:39 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:20:39 DEBUG : empty space: Unchanged skipping
2024/11/04 01:20:39 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:20:41 INFO  : potato2: Copied (new)
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncDeleteBefore (11.46s)
=== RUN   TestCopyDeleteBefore
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:20:47 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/04 01:20:47 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:20:47 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:20:49 INFO  : potato2: Copied (new)
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestCopyDeleteBefore (7.97s)
=== RUN   TestSyncWithExclude
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:20:56 DEBUG : enormous: Excluded (Size Filter)
2024/11/04 01:20:56 DEBUG : enormous: Excluded
2024/11/04 01:20:56 DEBUG : potato2: Excluded (Size Filter)
2024/11/04 01:20:56 DEBUG : potato2: Excluded
2024/11/04 01:20:57 DEBUG : potato2: Excluded (Size Filter)
2024/11/04 01:20:57 DEBUG : potato2: Excluded
2024/11/04 01:20:57 DEBUG : empty space: Sizes identical
2024/11/04 01:20:57 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:20:57 DEBUG : empty space: Unchanged skipping
2024/11/04 01:20:57 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:20:57 DEBUG : Waiting for deletions to finish
2024/11/04 01:20:57 INFO  : There was nothing to transfer
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:20:57 DEBUG : enormous: Excluded (Size Filter)
2024/11/04 01:20:57 DEBUG : enormous: Excluded
2024/11/04 01:20:57 DEBUG : potato2: Excluded (Size Filter)
2024/11/04 01:20:57 DEBUG : potato2: Excluded
2024/11/04 01:20:57 DEBUG : potato2: Excluded (Size Filter)
2024/11/04 01:20:57 DEBUG : potato2: Excluded
2024/11/04 01:20:57 DEBUG : empty space: Sizes identical
2024/11/04 01:20:57 DEBUG : empty space: Unchanged skipping
2024/11/04 01:20:57 DEBUG : Local file system at /tmp/rclone4225971449: Waiting for checks to finish
2024/11/04 01:20:57 DEBUG : Local file system at /tmp/rclone4225971449: Waiting for transfers to finish
2024/11/04 01:20:57 DEBUG : Waiting for deletions to finish
2024/11/04 01:20:57 INFO  : There was nothing to transfer
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncWithExclude (8.63s)
=== RUN   TestSyncWithExcludeAndDeleteExcluded
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:21:07 DEBUG : enormous: Excluded (Size Filter)
2024/11/04 01:21:07 DEBUG : enormous: Excluded
2024/11/04 01:21:07 DEBUG : potato2: Excluded (Size Filter)
2024/11/04 01:21:07 DEBUG : potato2: Excluded
2024/11/04 01:21:07 DEBUG : empty space: Sizes identical
2024/11/04 01:21:07 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:21:07 DEBUG : empty space: Unchanged skipping
2024/11/04 01:21:07 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:21:07 DEBUG : Waiting for deletions to finish
2024/11/04 01:21:09 INFO  : enormous: Deleted
2024/11/04 01:21:09 INFO  : potato2: Deleted
2024/11/04 01:21:09 INFO  : There was nothing to transfer
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:21:09 DEBUG : empty space: Sizes identical
2024/11/04 01:21:09 DEBUG : Local file system at /tmp/rclone4225971449: Waiting for checks to finish
2024/11/04 01:21:09 DEBUG : empty space: Unchanged skipping
2024/11/04 01:21:09 DEBUG : Local file system at /tmp/rclone4225971449: Waiting for transfers to finish
2024/11/04 01:21:09 DEBUG : Waiting for deletions to finish
2024/11/04 01:21:09 INFO  : potato2: Deleted
2024/11/04 01:21:09 INFO  : enormous: Deleted
2024/11/04 01:21:09 INFO  : There was nothing to transfer
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncWithExcludeAndDeleteExcluded (10.95s)
=== RUN   TestSyncWithTrackRenames
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
    sync_test.go:1538: Can track renames: false
2024/11/04 01:21:12 ERROR : Linkbox root 'rclone-test-josehog4nupu': Ignoring --track-renames as the destination does not support server-side move or copy
2024/11/04 01:21:12 ERROR : Linkbox root 'rclone-test-josehog4nupu': Ignoring --track-renames as the source and destination do not have a common hash
2024/11/04 01:21:12 DEBUG : potato: Need to transfer - File not found at Destination
2024/11/04 01:21:12 DEBUG : yam: Need to transfer - File not found at Destination
2024/11/04 01:21:12 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:21:12 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:21:14 INFO  : potato: Copied (new)
2024/11/04 01:21:15 INFO  : yam: Copied (new)
2024/11/04 01:21:15 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:21:15 ERROR : Linkbox root 'rclone-test-josehog4nupu': Ignoring --track-renames as the destination does not support server-side move or copy
2024/11/04 01:21:15 ERROR : Linkbox root 'rclone-test-josehog4nupu': Ignoring --track-renames as the source and destination do not have a common hash
2024/11/04 01:21:15 DEBUG : yaml: Need to transfer - File not found at Destination
2024/11/04 01:21:15 DEBUG : potato: Sizes identical
2024/11/04 01:21:15 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:21:15 DEBUG : potato: Unchanged skipping
2024/11/04 01:21:15 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:21:17 INFO  : yaml: Copied (new)
2024/11/04 01:21:17 DEBUG : Waiting for deletions to finish
2024/11/04 01:21:18 INFO  : yam: Deleted
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncWithTrackRenames (10.47s)
=== RUN   TestSyncWithTrackRenamesStrategyModtime
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
    sync_test.go:1610: Can track renames: false
2024/11/04 01:21:22 ERROR : Linkbox root 'rclone-test-josehog4nupu': Ignoring --track-renames as the destination does not support server-side move or copy
2024/11/04 01:21:22 ERROR : Linkbox root 'rclone-test-josehog4nupu': Ignoring --track-renames as either the source or destination do not support modtime
2024/11/04 01:21:22 DEBUG : potato: Need to transfer - File not found at Destination
2024/11/04 01:21:22 DEBUG : yam: Need to transfer - File not found at Destination
2024/11/04 01:21:22 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:21:22 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:21:25 INFO  : potato: Copied (new)
2024/11/04 01:21:25 INFO  : yam: Copied (new)
2024/11/04 01:21:25 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:21:25 ERROR : Linkbox root 'rclone-test-josehog4nupu': Ignoring --track-renames as the destination does not support server-side move or copy
2024/11/04 01:21:25 ERROR : Linkbox root 'rclone-test-josehog4nupu': Ignoring --track-renames as either the source or destination do not support modtime
2024/11/04 01:21:25 DEBUG : yaml: Need to transfer - File not found at Destination
2024/11/04 01:21:25 DEBUG : potato: Sizes identical
2024/11/04 01:21:25 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:21:25 DEBUG : potato: Unchanged skipping
2024/11/04 01:21:25 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:21:27 INFO  : yaml: Copied (new)
2024/11/04 01:21:27 DEBUG : Waiting for deletions to finish
2024/11/04 01:21:29 INFO  : yam: Deleted
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncWithTrackRenamesStrategyModtime (10.43s)
=== RUN   TestSyncWithTrackRenamesStrategyLeaf
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
    sync_test.go:1649: Can track renames: false
2024/11/04 01:21:33 ERROR : Linkbox root 'rclone-test-josehog4nupu': Ignoring --track-renames as the destination does not support server-side move or copy
2024/11/04 01:21:33 DEBUG : potato: Need to transfer - File not found at Destination
2024/11/04 01:21:33 DEBUG : sub/yam: Need to transfer - File not found at Destination
2024/11/04 01:21:33 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:21:33 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:21:36 INFO  : potato: Copied (new)
2024/11/04 01:21:37 INFO  : sub/yam: Copied (new)
2024/11/04 01:21:37 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:21:37 ERROR : Linkbox root 'rclone-test-josehog4nupu': Ignoring --track-renames as the destination does not support server-side move or copy
2024/11/04 01:21:38 DEBUG : yam: Need to transfer - File not found at Destination
2024/11/04 01:21:38 DEBUG : potato: Sizes identical
2024/11/04 01:21:38 DEBUG : potato: Unchanged skipping
2024/11/04 01:21:38 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:21:38 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:21:40 INFO  : yam: Copied (new)
2024/11/04 01:21:40 DEBUG : Waiting for deletions to finish
2024/11/04 01:21:41 INFO  : sub/yam: Deleted
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestSyncWithTrackRenamesStrategyLeaf (14.31s)
=== RUN   TestMoveWithDeleteEmptySrcDirs
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:21:47 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/04 01:21:47 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2024/11/04 01:21:47 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:21:47 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:21:54 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10)
2024/11/04 01:21:54 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10)
2024/11/04 01:21:54 DEBUG : nested/sub dir/file: Trying to read object after upload: try again in 100ms (1/10)
2024/11/04 01:21:55 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10)
2024/11/04 01:21:55 DEBUG : nested/sub dir/file: Trying to read object after upload: try again in 200ms (2/10)
2024/11/04 01:21:55 INFO  : sub dir/hello world: Copied (new)
2024/11/04 01:21:55 INFO  : sub dir/hello world: Deleted
2024/11/04 01:21:55 DEBUG : nested/sub dir/file: Trying to read object after upload: try again in 400ms (3/10)
2024/11/04 01:21:56 DEBUG : nested/sub dir/file: Trying to read object after upload: try again in 800ms (4/10)
2024/11/04 01:21:57 DEBUG : nested/sub dir/file: Trying to read object after upload: try again in 1.6s (5/10)
2024/11/04 01:21:59 INFO  : nested/sub dir/file: Copied (new)
2024/11/04 01:21:59 INFO  : nested/sub dir/file: Deleted
2024/11/04 01:21:59 INFO  : sub dir: Removing directory
2024/11/04 01:21:59 INFO  : nested/sub dir: Removing directory
2024/11/04 01:21:59 INFO  : nested: Removing directory
2024/11/04 01:21:59 DEBUG : Local file system at /tmp/rclone4225971449: deleted 3 directories
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestMoveWithDeleteEmptySrcDirs (21.05s)
=== RUN   TestMoveWithoutDeleteEmptySrcDirs
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:22:08 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/04 01:22:08 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2024/11/04 01:22:08 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:22:08 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:22:15 INFO  : sub dir/hello world: Copied (new)
2024/11/04 01:22:15 INFO  : sub dir/hello world: Deleted
2024/11/04 01:22:15 INFO  : nested/sub dir/file: Copied (new)
2024/11/04 01:22:15 INFO  : nested/sub dir/file: Deleted
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestMoveWithoutDeleteEmptySrcDirs (16.59s)
=== RUN   TestMoveWithIgnoreExisting
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:22:25 DEBUG : existing: Need to transfer - File not found at Destination
2024/11/04 01:22:25 DEBUG : existing-b: Need to transfer - File not found at Destination
2024/11/04 01:22:25 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:22:25 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:22:27 INFO  : existing: Copied (new)
2024/11/04 01:22:27 INFO  : existing: Deleted
2024/11/04 01:22:27 INFO  : existing-b: Copied (new)
2024/11/04 01:22:27 INFO  : existing-b: Deleted
2024/11/04 01:22:28 DEBUG : existing: Destination exists, skipping
2024/11/04 01:22:28 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set
2024/11/04 01:22:28 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for checks to finish
2024/11/04 01:22:28 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Waiting for transfers to finish
2024/11/04 01:22:28 INFO  : There was nothing to transfer
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
--- PASS: TestMoveWithIgnoreExisting (6.77s)
=== RUN   TestServerSideMove
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:22:31 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-vebuluc0maca"
2024/11/04 01:22:31 DEBUG : Config file has changed externally - reloading
    sync_test.go:1705: Server side move (if possible) Linkbox root 'rclone-test-josehog4nupu' -> Linkbox root 'rclone-test-vebuluc0maca'
2024/11/04 01:22:44 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/04 01:22:44 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2024/11/04 01:22:44 DEBUG : Linkbox root 'rclone-test-vebuluc0maca': Waiting for checks to finish
2024/11/04 01:22:44 DEBUG : empty space: Sizes identical
2024/11/04 01:22:44 DEBUG : empty space: Unchanged skipping
2024/11/04 01:22:44 DEBUG : potato3: Update: removing old file
2024/11/04 01:22:46 INFO  : empty space: Deleted
2024/11/04 01:22:46 DEBUG : Linkbox root 'rclone-test-vebuluc0maca': Waiting for transfers to finish
2024/11/04 01:22:46 INFO  : potato2: Copied (new)
2024/11/04 01:22:47 INFO  : potato3: Copied (replaced existing)
2024/11/04 01:22:48 INFO  : potato2: Deleted
2024/11/04 01:22:49 INFO  : potato3: Deleted
2024/11/04 01:22:49 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-madaroz9sita"
2024/11/04 01:22:49 DEBUG : Config file has changed externally - reloading
2024/11/04 01:22:50 DEBUG : empty space: Need to transfer - File not found at Destination
2024/11/04 01:22:50 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/04 01:22:50 DEBUG : potato3: Need to transfer - File not found at Destination
2024/11/04 01:22:50 DEBUG : Linkbox root 'rclone-test-madaroz9sita': Waiting for checks to finish
2024/11/04 01:22:50 DEBUG : Linkbox root 'rclone-test-madaroz9sita': Waiting for transfers to finish
2024/11/04 01:22:54 INFO  : empty space: Copied (new)
2024/11/04 01:22:54 INFO  : potato2: Copied (new)
2024/11/04 01:22:54 INFO  : potato3: Copied (new)
2024/11/04 01:22:55 INFO  : empty space: Deleted
2024/11/04 01:22:55 INFO  : potato2: Deleted
2024/11/04 01:22:56 INFO  : potato3: Deleted
2024/11/04 01:22:56 DEBUG : Linkbox root 'rclone-test-madaroz9sita': Purge remote
2024/11/04 01:22:57 DEBUG : Linkbox root 'rclone-test-vebuluc0maca': Purge remote
--- PASS: TestServerSideMove (27.69s)
=== RUN   TestServerSideMoveWithFilter
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:22:59 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-koqemut6bada"
    sync_test.go:1705: Server side move (if possible) Linkbox root 'rclone-test-josehog4nupu' -> Linkbox root 'rclone-test-koqemut6bada'
2024/11/04 01:23:12 DEBUG : empty space: Excluded (Size Filter)
2024/11/04 01:23:12 DEBUG : empty space: Excluded
2024/11/04 01:23:12 DEBUG : empty space: Excluded (Size Filter)
2024/11/04 01:23:12 DEBUG : empty space: Excluded
2024/11/04 01:23:12 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/04 01:23:12 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2024/11/04 01:23:12 DEBUG : Linkbox root 'rclone-test-koqemut6bada': Waiting for checks to finish
2024/11/04 01:23:12 DEBUG : Linkbox root 'rclone-test-koqemut6bada': Waiting for transfers to finish
2024/11/04 01:23:12 DEBUG : potato3: Update: removing old file
2024/11/04 01:23:14 INFO  : potato2: Copied (new)
2024/11/04 01:23:15 INFO  : potato2: Deleted
2024/11/04 01:23:15 INFO  : potato3: Copied (replaced existing)
2024/11/04 01:23:17 INFO  : potato3: Deleted
2024/11/04 01:23:17 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-zejucuc2rafe"
2024/11/04 01:23:18 DEBUG : empty space: Excluded (Size Filter)
2024/11/04 01:23:18 DEBUG : empty space: Excluded
2024/11/04 01:23:18 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/04 01:23:18 DEBUG : potato3: Need to transfer - File not found at Destination
2024/11/04 01:23:18 DEBUG : Linkbox root 'rclone-test-zejucuc2rafe': Waiting for checks to finish
2024/11/04 01:23:18 DEBUG : Linkbox root 'rclone-test-zejucuc2rafe': Waiting for transfers to finish
2024/11/04 01:23:22 INFO  : potato3: Copied (new)
2024/11/04 01:23:22 INFO  : potato2: Copied (new)
2024/11/04 01:23:23 INFO  : potato3: Deleted
2024/11/04 01:23:23 INFO  : potato2: Deleted
2024/11/04 01:23:23 DEBUG : Linkbox root 'rclone-test-zejucuc2rafe': Purge remote
2024/11/04 01:23:25 DEBUG : Linkbox root 'rclone-test-koqemut6bada': Purge remote
--- PASS: TestServerSideMoveWithFilter (28.69s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:23:28 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-zecabix8suqa"
2024/11/04 01:23:34 DEBUG : tomatoDir: Making directory
    sync_test.go:1705: Server side move (if possible) Linkbox root 'rclone-test-josehog4nupu' -> Linkbox root 'rclone-test-zecabix8suqa'
2024/11/04 01:23:42 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/04 01:23:42 DEBUG : empty space: Sizes identical
2024/11/04 01:23:42 DEBUG : empty space: Unchanged skipping
2024/11/04 01:23:42 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2024/11/04 01:23:42 DEBUG : Linkbox root 'rclone-test-zecabix8suqa': Waiting for checks to finish
2024/11/04 01:23:42 DEBUG : potato3: Update: removing old file
2024/11/04 01:23:44 INFO  : empty space: Deleted
2024/11/04 01:23:44 DEBUG : Linkbox root 'rclone-test-zecabix8suqa': Waiting for transfers to finish
2024/11/04 01:23:44 INFO  : potato2: Copied (new)
2024/11/04 01:23:46 INFO  : potato3: Copied (replaced existing)
2024/11/04 01:23:46 INFO  : potato2: Deleted
2024/11/04 01:23:47 INFO  : potato3: Deleted
2024/11/04 01:23:47 INFO  : tomatoDir: Removing directory
2024/11/04 01:23:48 DEBUG : Linkbox root 'rclone-test-josehog4nupu': deleted 1 directories
2024/11/04 01:23:49 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-zukurup3daca"
2024/11/04 01:23:49 DEBUG : tomatoDir: Making directory
2024/11/04 01:23:51 DEBUG : empty space: Need to transfer - File not found at Destination
2024/11/04 01:23:51 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/04 01:23:51 DEBUG : potato3: Need to transfer - File not found at Destination
2024/11/04 01:23:51 DEBUG : Linkbox root 'rclone-test-zukurup3daca': Waiting for checks to finish
2024/11/04 01:23:51 DEBUG : Linkbox root 'rclone-test-zukurup3daca': Waiting for transfers to finish
2024/11/04 01:23:56 INFO  : empty space: Copied (new)
2024/11/04 01:23:56 INFO  : potato2: Copied (new)
2024/11/04 01:23:56 INFO  : potato3: Copied (new)
2024/11/04 01:23:57 INFO  : empty space: Deleted
2024/11/04 01:23:57 INFO  : potato2: Deleted
2024/11/04 01:23:57 INFO  : potato3: Deleted
2024/11/04 01:23:57 INFO  : tomatoDir: Removing directory
2024/11/04 01:23:59 DEBUG : Linkbox root 'rclone-test-zecabix8suqa': deleted 1 directories
2024/11/04 01:23:59 DEBUG : Linkbox root 'rclone-test-zukurup3daca': Purge remote
2024/11/04 01:24:01 DEBUG : Linkbox root 'rclone-test-zecabix8suqa': Purge remote
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (34.58s)
=== RUN   TestServerSideMoveOverlap
    run.go:180: Remote "Linkbox root 'rclone-test-josehog4nupu'", Local "Local file system at /tmp/rclone4225971449", Modify Window "876000h0m0s"
2024/11/04 01:24:02 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-josehog4nupu/rclone-move-test"
--- PASS: TestServerSideMoveOverlap (4.68s)
FAIL
2024/11/04 01:24:07 DEBUG : Linkbox root 'rclone-test-josehog4nupu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCopyAfterDelete|TestCopyDeleteBefore|TestCopyOverSelf|TestCopyRedownload|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestMoveWithIgnoreExisting|TestMoveWithoutDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveOverlap|TestServerSideMoveWithFilter|TestSyncAfterAddingAFile|TestSyncAfterChangingContentsOnly|TestSyncAfterChangingFilesSizeOnly|TestSyncAfterChangingModtimeOnly|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncAfterRemovingAFileAndAddingAFileDryRun|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors|TestSyncBasedOnCheckSum|TestSyncDeleteBefore|TestSyncDeleteDuring|TestSyncIgnoreErrors|TestSyncIgnoreExisting|TestSyncIgnoreSize|TestSyncIgnoreTimes|TestSyncNoEmptyDirectories|TestSyncSizeOnly|TestSyncWithExclude|TestSyncWithExcludeAndDeleteExcluded|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 10m3.435452114s (try 2/5): exit status 1: Failed [TestServerSideCopyOverSelf]