"./sync.test -test.v -test.timeout 1h0m0s -remote TestSugarSync:Test -verbose -test.run '^(TestSyncCompareDest|TestSyncOverlapWithFilter)$'" - Starting (try 2/5)
2026/02/15 03:30:50 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-miteyib9gavi"
2026/02/15 03:30:50 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/15 03:30:59 DEBUG : Creating backend with remote "/tmp/rclone1287044595"
=== RUN   TestSyncOverlapWithFilter
    run.go:185: Remote "sugarsync root 'Test/rclone-test-miteyib9gavi'", Local "Local file system at /tmp/rclone1287044595", Modify Window "876000h0m0s"
2026/02/15 03:30:59 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-miteyib9gavi/rclone-sync-test"
2026/02/15 03:31:08 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-miteyib9gavi/rclone-sync-test-include/layer2"
2026/02/15 03:31:29 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-miteyib9gavi/rclone-sync-test-ignore-file"
2026/02/15 03:33:07 DEBUG : pacer: low level retry 1/1 (error HTTP error 503 (503 Service Unavailable): The server is currently unable to handle the request due to a temporary overloading or maintenance of the server)
2026/02/15 03:33:07 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/15 03:33:07 DEBUG : pacer: Reducing sleep to 15ms
    run.go:286: Retry Put of "rclone-sync-test-ignore-file/.ignore" to sugarsync root 'Test/rclone-test-miteyib9gavi': 1/10 (failed to upload file: HTTP error 503 (503 Service Unavailable): The server is currently unable to handle the request due to a temporary overloading or maintenance of the server)
2026/02/15 03:33:10 DEBUG : pacer: Reducing sleep to 11.25ms
2026/02/15 03:33:11 DEBUG : pacer: Reducing sleep to 10ms
2026/02/15 03:33:23 DEBUG : rclone-sync-test: Excluded
2026/02/15 03:33:40 DEBUG : rclone-sync-test-ignore-file: Excluded
2026/02/15 03:33:43 DEBUG : rclone-sync-test-include/layer2: Excluded
2026/02/15 03:33:43 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/rclone-sync-test': Waiting for checks to finish
2026/02/15 03:33:43 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/rclone-sync-test': Waiting for transfers to finish
2026/02/15 03:33:43 DEBUG : Waiting for deletions to finish
2026/02/15 03:33:43 INFO  : There was nothing to transfer
2026/02/15 03:33:56 DEBUG : rclone-sync-test: Excluded
2026/02/15 03:33:57 DEBUG : rclone-sync-test-ignore-file: Excluded
2026/02/15 03:34:18 DEBUG : rclone-sync-test-include/layer2: Excluded
2026/02/15 03:34:18 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi': Waiting for checks to finish
2026/02/15 03:34:18 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi': Waiting for transfers to finish
2026/02/15 03:34:18 DEBUG : Waiting for deletions to finish
2026/02/15 03:34:18 INFO  : rclone-sync-test-include: Removing directory
2026/02/15 03:34:19 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty
2026/02/15 03:34:19 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi': failed to delete 1 directories
2026/02/15 03:34:19 INFO  : There was nothing to transfer
2026/02/15 03:34:36 DEBUG : rclone-sync-test: Excluded
2026/02/15 03:34:39 DEBUG : rclone-sync-test-ignore-file: Excluded
2026/02/15 03:34:53 DEBUG : rclone-sync-test-include/layer2: Excluded
2026/02/15 03:34:53 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/rclone-sync-test-include/layer2': Waiting for checks to finish
2026/02/15 03:34:53 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/rclone-sync-test-include/layer2': Waiting for transfers to finish
2026/02/15 03:34:53 DEBUG : Waiting for deletions to finish
2026/02/15 03:34:53 INFO  : There was nothing to transfer
2026/02/15 03:34:56 DEBUG : rclone-sync-test: Excluded
2026/02/15 03:34:57 DEBUG : rclone-sync-test-ignore-file: Excluded
2026/02/15 03:34:59 DEBUG : rclone-sync-test-include/layer2: Excluded
2026/02/15 03:34:59 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi': Waiting for checks to finish
2026/02/15 03:34:59 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi': Waiting for transfers to finish
2026/02/15 03:34:59 DEBUG : Waiting for deletions to finish
2026/02/15 03:34:59 INFO  : rclone-sync-test-include: Removing directory
2026/02/15 03:34:59 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty
2026/02/15 03:34:59 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi': failed to delete 1 directories
2026/02/15 03:34:59 INFO  : There was nothing to transfer
2026/02/15 03:35:01 DEBUG : Excluded
2026/02/15 03:35:04 DEBUG : rclone-sync-test: Excluded
2026/02/15 03:35:23 DEBUG : rclone-sync-test-ignore-file: Excluded
2026/02/15 03:35:26 DEBUG : rclone-sync-test-include/layer2: Excluded
2026/02/15 03:35:26 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/rclone-sync-test-ignore-file': Waiting for checks to finish
2026/02/15 03:35:26 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/rclone-sync-test-ignore-file': Waiting for transfers to finish
2026/02/15 03:35:26 DEBUG : Waiting for deletions to finish
2026/02/15 03:35:26 INFO  : There was nothing to transfer
--- PASS: TestSyncOverlapWithFilter (302.04s)
=== RUN   TestSyncCompareDest
    run.go:185: Remote "sugarsync root 'Test/rclone-test-miteyib9gavi'", Local "Local file system at /tmp/rclone1287044595", Modify Window "876000h0m0s"
2026/02/15 03:36:01 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-miteyib9gavi/dst"
2026/02/15 03:36:14 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-miteyib9gavi/CompareDest"
2026/02/15 03:36:49 DEBUG : one: Need to transfer - File not found at Destination
2026/02/15 03:36:49 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/dst': Waiting for checks to finish
2026/02/15 03:36:49 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/dst': Waiting for transfers to finish
2026/02/15 03:37:33 DEBUG : one: size = 3 OK
2026/02/15 03:37:33 INFO  : one: Copied (new)
2026/02/15 03:37:33 DEBUG : Waiting for deletions to finish
2026/02/15 03:37:37 DEBUG : one: size = 5 (Local file system at /tmp/rclone1287044595)
2026/02/15 03:37:37 DEBUG : one: size = 3 (sugarsync root 'Test/rclone-test-miteyib9gavi/dst')
2026/02/15 03:37:37 DEBUG : one: Sizes differ
2026/02/15 03:37:37 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/dst': Waiting for checks to finish
2026/02/15 03:37:38 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/dst': Waiting for transfers to finish
2026/02/15 03:37:44 DEBUG : one: size = 5 OK
2026/02/15 03:37:44 INFO  : one: Copied (replaced existing)
2026/02/15 03:37:44 DEBUG : Waiting for deletions to finish
2026/02/15 03:37:59 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/dst': Waiting for checks to finish
2026/02/15 03:37:59 DEBUG : one: size = 5 (Local file system at /tmp/rclone1287044595)
2026/02/15 03:37:59 DEBUG : one: size = 3 (sugarsync root 'Test/rclone-test-miteyib9gavi/dst')
2026/02/15 03:37:59 DEBUG : one: Sizes differ
2026/02/15 03:38:01 DEBUG : one: size = 5 OK
2026/02/15 03:38:01 DEBUG : one: Sizes identical
2026/02/15 03:38:01 DEBUG : one: Destination found in --compare-dest, skipping
2026/02/15 03:38:01 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/dst': Waiting for transfers to finish
2026/02/15 03:38:01 DEBUG : Waiting for deletions to finish
2026/02/15 03:38:01 INFO  : There was nothing to transfer
2026/02/15 03:38:18 DEBUG : one: size = 5 (Local file system at /tmp/rclone1287044595)
2026/02/15 03:38:18 DEBUG : one: size = 3 (sugarsync root 'Test/rclone-test-miteyib9gavi/dst')
2026/02/15 03:38:18 DEBUG : one: Sizes differ
2026/02/15 03:38:18 DEBUG : one: size = 5 OK
2026/02/15 03:38:18 DEBUG : one: Sizes identical
2026/02/15 03:38:18 DEBUG : one: Destination found in --compare-dest, skipping
2026/02/15 03:38:18 DEBUG : two: size = 3 OK
2026/02/15 03:38:18 DEBUG : two: Sizes identical
2026/02/15 03:38:18 DEBUG : two: Destination found in --compare-dest, skipping
2026/02/15 03:38:18 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/dst': Waiting for checks to finish
2026/02/15 03:38:18 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/dst': Waiting for transfers to finish
2026/02/15 03:38:18 DEBUG : Waiting for deletions to finish
2026/02/15 03:38:18 INFO  : There was nothing to transfer
2026/02/15 03:38:26 DEBUG : one: size = 5 (Local file system at /tmp/rclone1287044595)
2026/02/15 03:38:26 DEBUG : one: size = 3 (sugarsync root 'Test/rclone-test-miteyib9gavi/dst')
2026/02/15 03:38:26 DEBUG : one: Sizes differ
2026/02/15 03:38:27 DEBUG : two: size = 3 OK
2026/02/15 03:38:27 DEBUG : two: Sizes identical
2026/02/15 03:38:27 DEBUG : two: Destination found in --compare-dest, skipping
2026/02/15 03:38:27 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/dst': Waiting for checks to finish
2026/02/15 03:38:27 DEBUG : one: size = 5 OK
2026/02/15 03:38:27 DEBUG : one: Sizes identical
2026/02/15 03:38:27 DEBUG : one: Destination found in --compare-dest, skipping
2026/02/15 03:38:27 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/dst': Waiting for transfers to finish
2026/02/15 03:38:27 DEBUG : Waiting for deletions to finish
2026/02/15 03:38:27 INFO  : There was nothing to transfer
    sync_test.go:2206: No hash on uploaded file so skipping compare timestamp test
2026/02/15 03:38:31 DEBUG : one: size = 5 (Local file system at /tmp/rclone1287044595)
2026/02/15 03:38:31 DEBUG : one: size = 3 (sugarsync root 'Test/rclone-test-miteyib9gavi/dst')
2026/02/15 03:38:31 DEBUG : one: Sizes differ
2026/02/15 03:38:31 DEBUG : two: size = 5 (Local file system at /tmp/rclone1287044595)
2026/02/15 03:38:31 DEBUG : two: size = 3 (sugarsync root 'Test/rclone-test-miteyib9gavi/CompareDest')
2026/02/15 03:38:31 DEBUG : two: Sizes differ
2026/02/15 03:38:31 DEBUG : two: Need to transfer - File not found at Destination
2026/02/15 03:38:31 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/dst': Waiting for checks to finish
2026/02/15 03:38:31 DEBUG : one: size = 5 OK
2026/02/15 03:38:31 DEBUG : one: Sizes identical
2026/02/15 03:38:31 DEBUG : one: Destination found in --compare-dest, skipping
2026/02/15 03:38:31 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi/dst': Waiting for transfers to finish
2026/02/15 03:38:36 DEBUG : two: size = 5 OK
2026/02/15 03:38:36 INFO  : two: Copied (new)
2026/02/15 03:38:36 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCompareDest (169.47s)
PASS
2026/02/15 03:38:50 DEBUG : sugarsync root 'Test/rclone-test-miteyib9gavi': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestSugarSync:Test -verbose -test.run '^(TestSyncCompareDest|TestSyncOverlapWithFilter)$'" - Finished OK in 8m1.089083341s (try 2/5)