"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^(TestServerSideMove|TestSyncCompareDest|TestSyncCopyDest)$'" - Starting (try 2/5)
2025/12/25 02:10:18 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-qikenig7kuzu"
2025/12/25 02:10:18 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/12/25 02:10:18 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/12/25 02:10:19 DEBUG : Creating backend with remote "/tmp/rclone3948011778"
=== RUN   TestServerSideMove
    run.go:185: Remote "OneDrive root 'rclone-test-qikenig7kuzu'", Local "Local file system at /tmp/rclone3948011778", Modify Window "1s"
2025/12/25 02:10:19 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-jikucic8vixo"
2025/12/25 02:10:19 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/12/25 02:10:21 DEBUG : potato2: Starting multipart upload
2025/12/25 02:10:21 DEBUG : potato2: Uploading segment 0/60 size 60
2025/12/25 02:10:22 DEBUG : empty space: Starting multipart upload
2025/12/25 02:10:22 DEBUG : empty space: Uploading segment 0/1 size 1
2025/12/25 02:10:22 DEBUG : potato3: Starting multipart upload
2025/12/25 02:10:23 DEBUG : potato3: Uploading segment 0/68 size 68
    sync_test.go:1736: Server side move (if possible) OneDrive root 'rclone-test-qikenig7kuzu' -> OneDrive root 'rclone-test-jikucic8vixo'
2025/12/25 02:10:24 DEBUG : empty space: Starting multipart upload
2025/12/25 02:10:25 DEBUG : empty space: Uploading segment 0/1 size 1
2025/12/25 02:10:25 DEBUG : potato3: Starting multipart upload
2025/12/25 02:10:25 DEBUG : potato3: Uploading segment 0/60 size 60
2025/12/25 02:10:26 DEBUG : OneDrive root 'rclone-test-jikucic8vixo': Using server-side directory move
2025/12/25 02:10:26 INFO  : OneDrive root 'rclone-test-jikucic8vixo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/12/25 02:10:26 DEBUG : potato2: Need to transfer - File not found at Destination
2025/12/25 02:10:26 DEBUG : empty space: size = 1 OK
2025/12/25 02:10:26 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s)
2025/12/25 02:10:26 DEBUG : empty space: Unchanged skipping
2025/12/25 02:10:26 DEBUG : potato3: size = 68 (OneDrive root 'rclone-test-qikenig7kuzu')
2025/12/25 02:10:26 DEBUG : potato3: size = 60 (OneDrive root 'rclone-test-jikucic8vixo')
2025/12/25 02:10:26 DEBUG : potato3: Sizes differ
2025/12/25 02:10:26 DEBUG : OneDrive root 'rclone-test-jikucic8vixo': Waiting for checks to finish
2025/12/25 02:10:27 INFO  : empty space: Deleted
2025/12/25 02:10:27 DEBUG : OneDrive root 'rclone-test-jikucic8vixo': Waiting for transfers to finish
2025/12/25 02:10:27 INFO  : potato3: Deleted
2025/12/25 02:10:27 INFO  : potato2: Moved (server-side)
2025/12/25 02:10:27 INFO  : potato3: Moved (server-side)
2025/12/25 02:10:28 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-doguvon7hipu"
2025/12/25 02:10:28 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/12/25 02:10:29 DEBUG : OneDrive root 'rclone-test-doguvon7hipu': Using server-side directory move
2025/12/25 02:10:30 INFO  : OneDrive root 'rclone-test-doguvon7hipu': Server side directory move succeeded
2025/12/25 02:10:31 ERROR : error listing: directory not found
2025/12/25 02:10:31 DEBUG : OneDrive root 'rclone-test-doguvon7hipu': Purge remote
2025/12/25 02:10:31 DEBUG : OneDrive root 'rclone-test-jikucic8vixo': Purge remote
2025/12/25 02:10:32 NOTICE: purge failed: directory not found
--- PASS: TestServerSideMove (13.15s)
=== RUN   TestSyncCompareDest
    run.go:185: Remote "OneDrive root 'rclone-test-qikenig7kuzu'", Local "Local file system at /tmp/rclone3948011778", Modify Window "1s"
2025/12/25 02:10:32 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-qikenig7kuzu/dst"
2025/12/25 02:10:32 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/12/25 02:10:34 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-qikenig7kuzu/CompareDest"
2025/12/25 02:10:34 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/12/25 02:10:38 DEBUG : one: Need to transfer - File not found at Destination
2025/12/25 02:10:38 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for checks to finish
2025/12/25 02:10:38 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for transfers to finish
2025/12/25 02:10:38 DEBUG : one: Starting multipart upload
2025/12/25 02:10:39 DEBUG : one: Uploading segment 0/3 size 3
2025/12/25 02:10:39 DEBUG : one: size = 3 OK
2025/12/25 02:10:39 DEBUG : one: quickxor = 6f70431900000000000000000300000000000000 OK
2025/12/25 02:10:39 INFO  : one: Copied (new)
2025/12/25 02:10:39 DEBUG : Waiting for deletions to finish
2025/12/25 02:10:41 DEBUG : one: size = 5 (Local file system at /tmp/rclone3948011778)
2025/12/25 02:10:41 DEBUG : one: size = 3 (OneDrive root 'rclone-test-qikenig7kuzu/dst')
2025/12/25 02:10:41 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for checks to finish
2025/12/25 02:10:41 DEBUG : one: Sizes differ
2025/12/25 02:10:42 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for transfers to finish
2025/12/25 02:10:42 DEBUG : one: Starting multipart upload
2025/12/25 02:10:43 DEBUG : one: Uploading segment 0/5 size 5
2025/12/25 02:10:43 DEBUG : one: size = 5 OK
2025/12/25 02:10:43 DEBUG : one: quickxor = 6f704319e8200300000000000500000000000000 OK
2025/12/25 02:10:43 INFO  : one: Copied (replaced existing)
2025/12/25 02:10:43 DEBUG : Waiting for deletions to finish
2025/12/25 02:10:44 DEBUG : dst/one: Starting multipart upload
2025/12/25 02:10:45 DEBUG : dst/one: Uploading segment 0/3 size 3
2025/12/25 02:10:46 DEBUG : CompareDest/one: Starting multipart upload
2025/12/25 02:10:46 DEBUG : CompareDest/one: Uploading segment 0/5 size 5
2025/12/25 02:10:48 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for checks to finish
2025/12/25 02:10:48 DEBUG : one: size = 5 (Local file system at /tmp/rclone3948011778)
2025/12/25 02:10:48 DEBUG : one: size = 3 (OneDrive root 'rclone-test-qikenig7kuzu/dst')
2025/12/25 02:10:48 DEBUG : one: Sizes differ
2025/12/25 02:10:49 DEBUG : one: size = 5 OK
2025/12/25 02:10:49 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s)
2025/12/25 02:10:49 DEBUG : one: Destination found in --compare-dest, skipping
2025/12/25 02:10:49 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for transfers to finish
2025/12/25 02:10:49 DEBUG : Waiting for deletions to finish
2025/12/25 02:10:49 INFO  : There was nothing to transfer
2025/12/25 02:10:49 DEBUG : CompareDest/two: Starting multipart upload
2025/12/25 02:10:50 DEBUG : CompareDest/two: Uploading segment 0/3 size 3
2025/12/25 02:10:51 DEBUG : one: size = 5 (Local file system at /tmp/rclone3948011778)
2025/12/25 02:10:51 DEBUG : one: size = 3 (OneDrive root 'rclone-test-qikenig7kuzu/dst')
2025/12/25 02:10:51 DEBUG : one: Sizes differ
2025/12/25 02:10:51 DEBUG : two: size = 3 OK
2025/12/25 02:10:51 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1s)
2025/12/25 02:10:51 DEBUG : two: Destination found in --compare-dest, skipping
2025/12/25 02:10:51 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for checks to finish
2025/12/25 02:10:51 DEBUG : one: size = 5 OK
2025/12/25 02:10:51 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s)
2025/12/25 02:10:51 DEBUG : one: Destination found in --compare-dest, skipping
2025/12/25 02:10:51 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for transfers to finish
2025/12/25 02:10:51 DEBUG : Waiting for deletions to finish
2025/12/25 02:10:51 INFO  : There was nothing to transfer
2025/12/25 02:10:52 DEBUG : one: size = 5 (Local file system at /tmp/rclone3948011778)
2025/12/25 02:10:52 DEBUG : one: size = 3 (OneDrive root 'rclone-test-qikenig7kuzu/dst')
2025/12/25 02:10:52 DEBUG : one: Sizes differ
2025/12/25 02:10:52 DEBUG : one: size = 5 OK
2025/12/25 02:10:52 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s)
2025/12/25 02:10:52 DEBUG : one: Destination found in --compare-dest, skipping
2025/12/25 02:10:53 DEBUG : two: size = 3 OK
2025/12/25 02:10:53 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1s)
2025/12/25 02:10:53 DEBUG : two: Destination found in --compare-dest, skipping
2025/12/25 02:10:53 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for checks to finish
2025/12/25 02:10:53 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for transfers to finish
2025/12/25 02:10:53 DEBUG : Waiting for deletions to finish
2025/12/25 02:10:53 INFO  : There was nothing to transfer
2025/12/25 02:10:54 DEBUG : one: size = 5 (Local file system at /tmp/rclone3948011778)
2025/12/25 02:10:54 DEBUG : one: size = 3 (OneDrive root 'rclone-test-qikenig7kuzu/dst')
2025/12/25 02:10:54 DEBUG : one: Sizes differ
2025/12/25 02:10:54 DEBUG : two: size = 3 OK
2025/12/25 02:10:54 DEBUG : two: Modification times differ by -119h59m59.877s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59.123 +0000 UTC
2025/12/25 02:10:54 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK
2025/12/25 02:10:54 DEBUG : two: Destination found in --compare-dest, skipping
2025/12/25 02:10:54 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for checks to finish
2025/12/25 02:10:54 DEBUG : one: size = 5 OK
2025/12/25 02:10:54 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s)
2025/12/25 02:10:54 DEBUG : one: Destination found in --compare-dest, skipping
2025/12/25 02:10:54 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for transfers to finish
2025/12/25 02:10:54 DEBUG : Waiting for deletions to finish
2025/12/25 02:10:54 INFO  : There was nothing to transfer
2025/12/25 02:10:56 DEBUG : one: size = 5 (Local file system at /tmp/rclone3948011778)
2025/12/25 02:10:56 DEBUG : one: size = 3 (OneDrive root 'rclone-test-qikenig7kuzu/dst')
2025/12/25 02:10:56 DEBUG : one: Sizes differ
2025/12/25 02:10:56 DEBUG : two: size = 5 (Local file system at /tmp/rclone3948011778)
2025/12/25 02:10:56 DEBUG : two: size = 3 (OneDrive root 'rclone-test-qikenig7kuzu/CompareDest')
2025/12/25 02:10:56 DEBUG : two: Sizes differ
2025/12/25 02:10:56 DEBUG : two: Need to transfer - File not found at Destination
2025/12/25 02:10:56 DEBUG : two: Starting multipart upload
2025/12/25 02:10:56 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for checks to finish
2025/12/25 02:10:56 DEBUG : one: size = 5 OK
2025/12/25 02:10:56 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s)
2025/12/25 02:10:56 DEBUG : one: Destination found in --compare-dest, skipping
2025/12/25 02:10:56 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for transfers to finish
2025/12/25 02:10:56 DEBUG : two: Uploading segment 0/5 size 5
2025/12/25 02:10:57 DEBUG : two: size = 5 OK
2025/12/25 02:10:57 DEBUG : two: quickxor = 74b8c31be8300300000000000500000000000000 OK
2025/12/25 02:10:57 INFO  : two: Copied (new)
2025/12/25 02:10:57 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCompareDest (28.44s)
=== RUN   TestSyncCopyDest
    run.go:185: Remote "OneDrive root 'rclone-test-qikenig7kuzu'", Local "Local file system at /tmp/rclone3948011778", Modify Window "1s"
2025/12/25 02:11:01 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-qikenig7kuzu/dst"
2025/12/25 02:11:01 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/12/25 02:11:02 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-qikenig7kuzu/CopyDest"
2025/12/25 02:11:02 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/12/25 02:11:09 DEBUG : one: Need to transfer - File not found at Destination
2025/12/25 02:11:09 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for checks to finish
2025/12/25 02:11:09 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for transfers to finish
2025/12/25 02:11:09 DEBUG : one: Starting multipart upload
2025/12/25 02:11:10 DEBUG : one: Uploading segment 0/3 size 3
2025/12/25 02:11:10 DEBUG : one: size = 3 OK
2025/12/25 02:11:10 DEBUG : one: quickxor = 6f70431900000000000000000300000000000000 OK
2025/12/25 02:11:10 INFO  : one: Copied (new)
2025/12/25 02:11:10 DEBUG : Waiting for deletions to finish
2025/12/25 02:11:12 DEBUG : one: size = 5 (Local file system at /tmp/rclone3948011778)
2025/12/25 02:11:12 DEBUG : one: size = 3 (OneDrive root 'rclone-test-qikenig7kuzu/dst')
2025/12/25 02:11:12 DEBUG : one: Sizes differ
2025/12/25 02:11:12 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for checks to finish
2025/12/25 02:11:13 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for transfers to finish
2025/12/25 02:11:13 DEBUG : one: Starting multipart upload
2025/12/25 02:11:13 DEBUG : one: Uploading segment 0/5 size 5
2025/12/25 02:11:13 DEBUG : one: size = 5 OK
2025/12/25 02:11:13 DEBUG : one: quickxor = 6f704319e8200300000000000500000000000000 OK
2025/12/25 02:11:13 INFO  : one: Copied (replaced existing)
2025/12/25 02:11:13 DEBUG : Waiting for deletions to finish
2025/12/25 02:11:14 DEBUG : dst/one: Starting multipart upload
2025/12/25 02:11:14 DEBUG : dst/one: Uploading segment 0/3 size 3
2025/12/25 02:11:15 DEBUG : CopyDest/one: Starting multipart upload
2025/12/25 02:11:16 DEBUG : CopyDest/one: Uploading segment 0/5 size 5
2025/12/25 02:11:17 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-qikenig7kuzu/BackupDir"
2025/12/25 02:11:17 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/12/25 02:11:18 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for checks to finish
2025/12/25 02:11:18 DEBUG : one: size = 5 (Local file system at /tmp/rclone3948011778)
2025/12/25 02:11:18 DEBUG : one: size = 3 (OneDrive root 'rclone-test-qikenig7kuzu/dst')
2025/12/25 02:11:18 DEBUG : one: Sizes differ
2025/12/25 02:11:19 DEBUG : one: size = 5 OK
2025/12/25 02:11:19 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s)
2025/12/25 02:11:19 DEBUG : one: size = 5 (Local file system at /tmp/rclone3948011778)
2025/12/25 02:11:19 DEBUG : one: size = 3 (OneDrive root 'rclone-test-qikenig7kuzu/dst')
2025/12/25 02:11:19 DEBUG : one: Sizes differ
2025/12/25 02:11:21 INFO  : one: Moved (server-side)
2025/12/25 02:11:22 DEBUG : one: size = 5 OK
2025/12/25 02:11:22 DEBUG : one: quickxor = 6f704319e8200300000000000500000000000000 OK
2025/12/25 02:11:22 INFO  : one: Copied (server-side copy)
2025/12/25 02:11:22 DEBUG : one: Destination found in --copy-dest, using server-side copy
2025/12/25 02:11:22 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for transfers to finish
2025/12/25 02:11:22 DEBUG : Waiting for deletions to finish
2025/12/25 02:11:23 DEBUG : CopyDest/two: Starting multipart upload
2025/12/25 02:11:24 DEBUG : CopyDest/two: Uploading segment 0/3 size 3
2025/12/25 02:11:25 DEBUG : one: size = 5 OK
2025/12/25 02:11:25 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s)
2025/12/25 02:11:25 DEBUG : one: Unchanged skipping
2025/12/25 02:11:25 DEBUG : two: size = 3 OK
2025/12/25 02:11:25 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1s)
2025/12/25 02:11:27 DEBUG : two: size = 3 OK
2025/12/25 02:11:27 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK
2025/12/25 02:11:27 INFO  : two: Copied (server-side copy)
2025/12/25 02:11:27 DEBUG : two: Destination found in --copy-dest, using server-side copy
2025/12/25 02:11:27 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for checks to finish
2025/12/25 02:11:27 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for transfers to finish
2025/12/25 02:11:27 DEBUG : Waiting for deletions to finish
2025/12/25 02:11:28 DEBUG : one: size = 5 OK
2025/12/25 02:11:28 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s)
2025/12/25 02:11:28 DEBUG : one: Unchanged skipping
2025/12/25 02:11:28 DEBUG : two: size = 3 OK
2025/12/25 02:11:28 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1s)
2025/12/25 02:11:28 DEBUG : two: Unchanged skipping
2025/12/25 02:11:28 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for checks to finish
2025/12/25 02:11:28 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for transfers to finish
2025/12/25 02:11:28 DEBUG : Waiting for deletions to finish
2025/12/25 02:11:28 INFO  : There was nothing to transfer
2025/12/25 02:11:28 DEBUG : CopyDest/three: Starting multipart upload
2025/12/25 02:11:29 DEBUG : CopyDest/three: Uploading segment 0/5 size 5
2025/12/25 02:11:30 DEBUG : one: size = 5 OK
2025/12/25 02:11:30 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s)
2025/12/25 02:11:30 DEBUG : one: Unchanged skipping
2025/12/25 02:11:30 DEBUG : three: size = 7 (Local file system at /tmp/rclone3948011778)
2025/12/25 02:11:30 DEBUG : three: size = 5 (OneDrive root 'rclone-test-qikenig7kuzu/CopyDest')
2025/12/25 02:11:30 DEBUG : three: Sizes differ
2025/12/25 02:11:30 DEBUG : three: Destination not found in --copy-dest
2025/12/25 02:11:30 DEBUG : three: Need to transfer - File not found at Destination
2025/12/25 02:11:30 DEBUG : two: size = 3 OK
2025/12/25 02:11:30 DEBUG : three: Starting multipart upload
2025/12/25 02:11:30 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1s)
2025/12/25 02:11:30 DEBUG : two: Unchanged skipping
2025/12/25 02:11:30 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for checks to finish
2025/12/25 02:11:30 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu/dst': Waiting for transfers to finish
2025/12/25 02:11:31 DEBUG : three: Uploading segment 0/7 size 7
2025/12/25 02:11:31 DEBUG : three: size = 7 OK
2025/12/25 02:11:31 DEBUG : three: quickxor = 7440831cca50063acc0000000700000000000000 OK
2025/12/25 02:11:31 INFO  : three: Copied (new)
2025/12/25 02:11:31 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCopyDest (36.10s)
PASS
2025/12/25 02:11:37 DEBUG : OneDrive root 'rclone-test-qikenig7kuzu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^(TestServerSideMove|TestSyncCompareDest|TestSyncCopyDest)$'" - Finished OK in 1m19.24552145s (try 2/5)