"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^(TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveWithFilter|TestSyncCompareDest)$'" - Starting (try 2/5)
2026/05/14 04:46:53 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-zocanoq9rage"
2026/05/14 04:46:53 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/14 04:46:53 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2026/05/14 04:46:54 DEBUG : Creating backend with remote "/tmp/rclone767920815"
=== RUN   TestServerSideMoveWithFilter
    run.go:198: Remote "OneDrive root 'rclone-test-zocanoq9rage'", Local "Local file system at /tmp/rclone767920815", Modify Window "1s"
2026/05/14 04:46:54 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-celezub8cipu"
2026/05/14 04:46:54 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2026/05/14 04:46:56 DEBUG : potato2: Starting multipart upload
2026/05/14 04:46:57 DEBUG : potato2: Uploading segment 0/60 size 60
2026/05/14 04:46:58 DEBUG : empty space: Starting multipart upload
2026/05/14 04:46:59 DEBUG : empty space: Uploading segment 0/1 size 1
2026/05/14 04:47:00 DEBUG : potato3: Starting multipart upload
2026/05/14 04:47:00 DEBUG : potato3: Uploading segment 0/68 size 68
    sync_test.go:1736: Server side move (if possible) OneDrive root 'rclone-test-zocanoq9rage' -> OneDrive root 'rclone-test-celezub8cipu'
2026/05/14 04:47:02 DEBUG : empty space: Starting multipart upload
2026/05/14 04:47:02 DEBUG : empty space: Uploading segment 0/1 size 1
2026/05/14 04:47:03 DEBUG : potato3: Starting multipart upload
2026/05/14 04:47:04 DEBUG : potato3: Uploading segment 0/60 size 60
2026/05/14 04:47:05 DEBUG : empty space: Excluded (Size Filter)
2026/05/14 04:47:05 DEBUG : empty space: Excluded
2026/05/14 04:47:05 DEBUG : empty space: Excluded (Size Filter)
2026/05/14 04:47:05 DEBUG : empty space: Excluded
2026/05/14 04:47:05 DEBUG : potato2: Need to transfer - File not found at Destination
2026/05/14 04:47:05 DEBUG : potato3: size = 68 (OneDrive root 'rclone-test-zocanoq9rage')
2026/05/14 04:47:05 DEBUG : potato3: size = 60 (OneDrive root 'rclone-test-celezub8cipu')
2026/05/14 04:47:05 DEBUG : potato3: Sizes differ
2026/05/14 04:47:05 DEBUG : OneDrive root 'rclone-test-celezub8cipu': Waiting for checks to finish
2026/05/14 04:47:05 DEBUG : OneDrive root 'rclone-test-celezub8cipu': Waiting for transfers to finish
2026/05/14 04:47:05 INFO  : potato3: Deleted
2026/05/14 04:47:06 INFO  : potato2: Moved (server-side)
2026/05/14 04:47:06 INFO  : potato3: Moved (server-side)
2026/05/14 04:47:07 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-qoxiyoj8peka"
2026/05/14 04:47:07 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2026/05/14 04:47:08 DEBUG : empty space: Excluded (Size Filter)
2026/05/14 04:47:08 DEBUG : empty space: Excluded
2026/05/14 04:47:08 DEBUG : potato2: Need to transfer - File not found at Destination
2026/05/14 04:47:08 DEBUG : potato3: Need to transfer - File not found at Destination
2026/05/14 04:47:08 DEBUG : OneDrive root 'rclone-test-qoxiyoj8peka': Waiting for checks to finish
2026/05/14 04:47:08 DEBUG : OneDrive root 'rclone-test-qoxiyoj8peka': Waiting for transfers to finish
2026/05/14 04:47:10 INFO  : potato2: Moved (server-side)
2026/05/14 04:47:10 INFO  : potato3: Moved (server-side)
2026/05/14 04:47:10 DEBUG : OneDrive root 'rclone-test-qoxiyoj8peka': Purge remote
2026/05/14 04:47:11 DEBUG : OneDrive root 'rclone-test-celezub8cipu': Purge remote
--- PASS: TestServerSideMoveWithFilter (17.62s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:198: Remote "OneDrive root 'rclone-test-zocanoq9rage'", Local "Local file system at /tmp/rclone767920815", Modify Window "1s"
2026/05/14 04:47:12 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-nudecey6zalu"
2026/05/14 04:47:12 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2026/05/14 04:47:13 DEBUG : potato2: Starting multipart upload
2026/05/14 04:47:14 DEBUG : potato2: Uploading segment 0/60 size 60
2026/05/14 04:47:14 DEBUG : empty space: Starting multipart upload
2026/05/14 04:47:15 DEBUG : empty space: Uploading segment 0/1 size 1
2026/05/14 04:47:15 DEBUG : potato3: Starting multipart upload
2026/05/14 04:47:16 DEBUG : potato3: Uploading segment 0/68 size 68
2026/05/14 04:47:16 INFO  : tomatoDir: Making directory
    sync_test.go:1736: Server side move (if possible) OneDrive root 'rclone-test-zocanoq9rage' -> OneDrive root 'rclone-test-nudecey6zalu'
2026/05/14 04:47:19 DEBUG : empty space: Starting multipart upload
2026/05/14 04:47:20 DEBUG : empty space: Uploading segment 0/1 size 1
2026/05/14 04:47:20 DEBUG : potato3: Starting multipart upload
2026/05/14 04:47:21 DEBUG : potato3: Uploading segment 0/60 size 60
2026/05/14 04:47:22 DEBUG : OneDrive root 'rclone-test-nudecey6zalu': Using server-side directory move
2026/05/14 04:47:22 INFO  : OneDrive root 'rclone-test-nudecey6zalu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2026/05/14 04:47:22 DEBUG : potato2: Need to transfer - File not found at Destination
2026/05/14 04:47:22 DEBUG : Added delayed dir = "tomatoDir", newDst=<nil>
2026/05/14 04:47:22 DEBUG : empty space: size = 1 OK
2026/05/14 04:47:22 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s)
2026/05/14 04:47:22 DEBUG : empty space: Unchanged skipping
2026/05/14 04:47:22 DEBUG : potato3: size = 68 (OneDrive root 'rclone-test-zocanoq9rage')
2026/05/14 04:47:22 DEBUG : potato3: size = 60 (OneDrive root 'rclone-test-nudecey6zalu')
2026/05/14 04:47:22 DEBUG : potato3: Sizes differ
2026/05/14 04:47:22 INFO  : empty space: Deleted
2026/05/14 04:47:22 DEBUG : OneDrive root 'rclone-test-nudecey6zalu': Waiting for checks to finish
2026/05/14 04:47:22 DEBUG : OneDrive root 'rclone-test-nudecey6zalu': Waiting for transfers to finish
2026/05/14 04:47:23 INFO  : potato3: Deleted
2026/05/14 04:47:23 INFO  : potato2: Moved (server-side)
2026/05/14 04:47:24 INFO  : potato3: Moved (server-side)
2026/05/14 04:47:24 INFO  : tomatoDir: Removing directory
2026/05/14 04:47:24 DEBUG : OneDrive root 'rclone-test-zocanoq9rage': deleted 1 directories
2026/05/14 04:47:25 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-popaxep6sivi"
2026/05/14 04:47:25 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2026/05/14 04:47:27 INFO  : tomatoDir: Making directory
2026/05/14 04:47:27 DEBUG : OneDrive root 'rclone-test-popaxep6sivi': Using server-side directory move
2026/05/14 04:47:31 INFO  : OneDrive root 'rclone-test-popaxep6sivi': Server side directory move succeeded
2026/05/14 04:47:32 ERROR : error listing: directory not found
2026/05/14 04:47:32 ERROR : error listing: directory not found
2026/05/14 04:47:32 DEBUG : OneDrive root 'rclone-test-popaxep6sivi': Purge remote
2026/05/14 04:47:32 DEBUG : OneDrive root 'rclone-test-nudecey6zalu': Purge remote
2026/05/14 04:47:33 NOTICE: purge failed: directory not found
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (21.34s)
=== RUN   TestSyncCompareDest
    run.go:198: Remote "OneDrive root 'rclone-test-zocanoq9rage'", Local "Local file system at /tmp/rclone767920815", Modify Window "1s"
2026/05/14 04:47:33 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-zocanoq9rage/dst"
2026/05/14 04:47:33 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2026/05/14 04:47:35 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-zocanoq9rage/CompareDest"
2026/05/14 04:47:35 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2026/05/14 04:47:38 DEBUG : one: Need to transfer - File not found at Destination
2026/05/14 04:47:38 DEBUG : OneDrive root 'rclone-test-zocanoq9rage/dst': Waiting for checks to finish
2026/05/14 04:47:38 DEBUG : OneDrive root 'rclone-test-zocanoq9rage/dst': Waiting for transfers to finish
2026/05/14 04:47:39 DEBUG : one: Starting multipart upload
2026/05/14 04:47:40 DEBUG : one: Uploading segment 0/3 size 3
2026/05/14 04:47:41 DEBUG : one: size = 3 OK
2026/05/14 04:47:41 DEBUG : one: quickxor = 6f70431900000000000000000300000000000000 OK
2026/05/14 04:47:41 INFO  : one: Copied (new)
2026/05/14 04:47:41 DEBUG : Waiting for deletions to finish
2026/05/14 04:47:42 DEBUG : one: size = 5 (Local file system at /tmp/rclone767920815)
2026/05/14 04:47:42 DEBUG : one: size = 3 (OneDrive root 'rclone-test-zocanoq9rage/dst')
2026/05/14 04:47:42 DEBUG : one: Sizes differ
2026/05/14 04:47:42 DEBUG : OneDrive root 'rclone-test-zocanoq9rage/dst': Waiting for checks to finish
2026/05/14 04:47:43 DEBUG : OneDrive root 'rclone-test-zocanoq9rage/dst': Waiting for transfers to finish
2026/05/14 04:47:43 DEBUG : one: Starting multipart upload
2026/05/14 04:47:44 DEBUG : one: Uploading segment 0/5 size 5
2026/05/14 04:47:44 DEBUG : one: size = 5 OK
2026/05/14 04:47:44 DEBUG : one: quickxor = 6f704319e8200300000000000500000000000000 OK
2026/05/14 04:47:44 INFO  : one: Copied (replaced existing)
2026/05/14 04:47:44 DEBUG : Waiting for deletions to finish
2026/05/14 04:47:45 DEBUG : dst/one: Starting multipart upload
2026/05/14 04:47:46 DEBUG : dst/one: Uploading segment 0/3 size 3
2026/05/14 04:47:47 DEBUG : CompareDest/one: Starting multipart upload
2026/05/14 04:47:47 DEBUG : CompareDest/one: Uploading segment 0/5 size 5
2026/05/14 04:47:49 DEBUG : OneDrive root 'rclone-test-zocanoq9rage/dst': Waiting for checks to finish
2026/05/14 04:47:49 DEBUG : one: size = 5 (Local file system at /tmp/rclone767920815)
2026/05/14 04:47:49 DEBUG : one: size = 3 (OneDrive root 'rclone-test-zocanoq9rage/dst')
2026/05/14 04:47:49 DEBUG : one: Sizes differ
2026/05/14 04:47:50 DEBUG : one: size = 5 OK
2026/05/14 04:47:50 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/05/14 04:47:50 DEBUG : one: Destination found in --compare-dest, skipping
2026/05/14 04:47:50 DEBUG : OneDrive root 'rclone-test-zocanoq9rage/dst': Waiting for transfers to finish
2026/05/14 04:47:50 DEBUG : Waiting for deletions to finish
2026/05/14 04:47:50 INFO  : There was nothing to transfer
2026/05/14 04:47:50 DEBUG : CompareDest/two: Starting multipart upload
2026/05/14 04:47:51 DEBUG : CompareDest/two: Uploading segment 0/3 size 3
2026/05/14 04:47:52 DEBUG : one: size = 5 (Local file system at /tmp/rclone767920815)
2026/05/14 04:47:52 DEBUG : one: size = 3 (OneDrive root 'rclone-test-zocanoq9rage/dst')
2026/05/14 04:47:52 DEBUG : one: Sizes differ
2026/05/14 04:47:53 DEBUG : one: size = 5 OK
2026/05/14 04:47:53 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/05/14 04:47:53 DEBUG : one: Destination found in --compare-dest, skipping
2026/05/14 04:47:53 DEBUG : two: size = 3 OK
2026/05/14 04:47:53 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/05/14 04:47:53 DEBUG : two: Destination found in --compare-dest, skipping
2026/05/14 04:47:53 DEBUG : OneDrive root 'rclone-test-zocanoq9rage/dst': Waiting for checks to finish
2026/05/14 04:47:53 DEBUG : OneDrive root 'rclone-test-zocanoq9rage/dst': Waiting for transfers to finish
2026/05/14 04:47:53 DEBUG : Waiting for deletions to finish
2026/05/14 04:47:53 INFO  : There was nothing to transfer
2026/05/14 04:47:54 DEBUG : one: size = 5 (Local file system at /tmp/rclone767920815)
2026/05/14 04:47:54 DEBUG : one: size = 3 (OneDrive root 'rclone-test-zocanoq9rage/dst')
2026/05/14 04:47:54 DEBUG : one: Sizes differ
2026/05/14 04:47:54 DEBUG : two: size = 3 OK
2026/05/14 04:47:54 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/05/14 04:47:54 DEBUG : two: Destination found in --compare-dest, skipping
2026/05/14 04:47:54 DEBUG : OneDrive root 'rclone-test-zocanoq9rage/dst': Waiting for checks to finish
2026/05/14 04:47:54 DEBUG : one: size = 5 OK
2026/05/14 04:47:54 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/05/14 04:47:54 DEBUG : one: Destination found in --compare-dest, skipping
2026/05/14 04:47:54 DEBUG : OneDrive root 'rclone-test-zocanoq9rage/dst': Waiting for transfers to finish
2026/05/14 04:47:54 DEBUG : Waiting for deletions to finish
2026/05/14 04:47:54 INFO  : There was nothing to transfer
2026/05/14 04:47:55 DEBUG : one: size = 5 (Local file system at /tmp/rclone767920815)
2026/05/14 04:47:55 DEBUG : one: size = 3 (OneDrive root 'rclone-test-zocanoq9rage/dst')
2026/05/14 04:47:55 DEBUG : one: Sizes differ
2026/05/14 04:47:55 DEBUG : one: size = 5 OK
2026/05/14 04:47:55 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/05/14 04:47:55 DEBUG : one: Destination found in --compare-dest, skipping
2026/05/14 04:47:56 DEBUG : two: size = 3 OK
2026/05/14 04:47:56 DEBUG : two: Modification times differ by -120h0m0s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59 +0000 UTC
2026/05/14 04:47:56 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK
2026/05/14 04:47:56 DEBUG : two: Destination found in --compare-dest, skipping
2026/05/14 04:47:56 DEBUG : OneDrive root 'rclone-test-zocanoq9rage/dst': Waiting for checks to finish
2026/05/14 04:47:56 DEBUG : OneDrive root 'rclone-test-zocanoq9rage/dst': Waiting for transfers to finish
2026/05/14 04:47:56 DEBUG : Waiting for deletions to finish
2026/05/14 04:47:56 INFO  : There was nothing to transfer
2026/05/14 04:47:57 DEBUG : one: size = 5 (Local file system at /tmp/rclone767920815)
2026/05/14 04:47:57 DEBUG : one: size = 3 (OneDrive root 'rclone-test-zocanoq9rage/dst')
2026/05/14 04:47:57 DEBUG : one: Sizes differ
2026/05/14 04:47:57 DEBUG : two: size = 5 (Local file system at /tmp/rclone767920815)
2026/05/14 04:47:57 DEBUG : two: size = 3 (OneDrive root 'rclone-test-zocanoq9rage/CompareDest')
2026/05/14 04:47:57 DEBUG : two: Sizes differ
2026/05/14 04:47:57 DEBUG : two: Need to transfer - File not found at Destination
2026/05/14 04:47:57 DEBUG : OneDrive root 'rclone-test-zocanoq9rage/dst': Waiting for checks to finish
2026/05/14 04:47:57 DEBUG : two: Starting multipart upload
2026/05/14 04:47:57 DEBUG : one: size = 5 OK
2026/05/14 04:47:57 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/05/14 04:47:57 DEBUG : one: Destination found in --compare-dest, skipping
2026/05/14 04:47:57 DEBUG : OneDrive root 'rclone-test-zocanoq9rage/dst': Waiting for transfers to finish
2026/05/14 04:47:58 DEBUG : two: Uploading segment 0/5 size 5
2026/05/14 04:47:59 DEBUG : two: size = 5 OK
2026/05/14 04:47:59 DEBUG : two: quickxor = 74b8c31be8300300000000000500000000000000 OK
2026/05/14 04:47:59 INFO  : two: Copied (new)
2026/05/14 04:47:59 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCompareDest (29.19s)
PASS
2026/05/14 04:48:02 DEBUG : OneDrive root 'rclone-test-zocanoq9rage': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^(TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveWithFilter|TestSyncCompareDest)$'" - Finished OK in 1m9.834996289s (try 2/5)