"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestServerSideMoveOverSelf$'" - Starting (try 2/5)
2025/10/31 04:28:43 DEBUG : Creating backend with remote "TestB2:rclone-test-keloyut0diza"
2025/10/31 04:28:43 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/31 04:28:44 DEBUG : Creating backend with remote "/tmp/rclone1759668108"
=== RUN   TestServerSideMoveOverSelf
    run.go:185: Remote "B2 bucket rclone-test-keloyut0diza", Local "Local file system at /tmp/rclone1759668108", Modify Window "1ms"
2025/10/31 04:28:45 DEBUG : sub dir/hello world: Clearing upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s
2025/10/31 04:28:45 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable): trying again in 1s)
2025/10/31 04:28:45 DEBUG : pacer: Rate limited, increasing sleep to 1s
    run.go:286: Retry Put of "sub dir/hello world" to B2 bucket rclone-test-keloyut0diza: 1/10 (no tomes available (503 service_unavailable): trying again in 1s)
2025/10/31 04:28:48 DEBUG : pacer: Reducing sleep to 500ms
2025/10/31 04:28:49 DEBUG : pacer: Reducing sleep to 250ms
2025/10/31 04:28:49 DEBUG : pacer: Reducing sleep to 125ms
2025/10/31 04:28:49 DEBUG : Creating backend with remote "TestB2:rclone-test-vabiziv7goja"
    sync_test.go:701: Server side copy (if possible) B2 bucket rclone-test-keloyut0diza -> B2 bucket rclone-test-vabiziv7goja
2025/10/31 04:28:50 DEBUG : pacer: Reducing sleep to 62.5ms
2025/10/31 04:28:51 DEBUG : pacer: Reducing sleep to 31.25ms
2025/10/31 04:28:51 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/10/31 04:28:51 DEBUG : B2 bucket rclone-test-vabiziv7goja: Waiting for checks to finish
2025/10/31 04:28:51 DEBUG : B2 bucket rclone-test-vabiziv7goja: Waiting for transfers to finish
2025/10/31 04:28:52 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2025/10/31 04:28:52 INFO  : sub dir/hello world: Copied (server-side copy)
2025/10/31 04:28:52 DEBUG : pacer: Reducing sleep to 15.625ms
2025/10/31 04:28:52 DEBUG : pacer: Reducing sleep to 10ms
2025/10/31 04:28:53 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/10/31 04:28:53 DEBUG : B2 bucket rclone-test-vabiziv7goja: Waiting for checks to finish
2025/10/31 04:28:53 DEBUG : B2 bucket rclone-test-vabiziv7goja: Waiting for transfers to finish
2025/10/31 04:28:54 DEBUG : sub dir/hello world: sha1 = b84a9b2362aee3556081792f3b7abd3958da8deb OK
2025/10/31 04:28:54 INFO  : sub dir/hello world: Copied (server-side copy)
2025/10/31 04:28:54 INFO  : sub dir/hello world: Deleted
2025/10/31 04:28:55 DEBUG : testing file moves
2025/10/31 04:28:55 DEBUG : B2 bucket rclone-test-vabiziv7goja: Waiting for checks to finish
2025/10/31 04:28:55 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2025/10/31 04:28:55 DEBUG : B2 bucket rclone-test-vabiziv7goja: Waiting for transfers to finish
2025/10/31 04:28:56 DEBUG : sub dir/hello world: sha1 = 73716105e69866349759070d23c6e97b3acc55ff OK
2025/10/31 04:28:56 INFO  : sub dir/hello world: Copied (server-side copy)
2025/10/31 04:28:56 INFO  : sub dir/hello world: Deleted
2025/10/31 04:28:56 DEBUG : B2 bucket rclone-test-vabiziv7goja: Purge remote
2025/10/31 04:28:56 INFO  : B2 bucket rclone-test-vabiziv7goja: cleaning bucket "rclone-test-vabiziv7goja" of all files
2025/10/31 04:28:56 DEBUG : sub dir/hello world: Deleting (id "4_zbf328d43806d3b8b9fa80e1b_f11051bff958dc9d8_d20251031_m042855_c001_v0001101_t0033_u01761884935679")
2025/10/31 04:28:56 DEBUG : sub dir/hello world: Deleting (id "4_zbf328d43806d3b8b9fa80e1b_f1054671a7d7aa797_d20251031_m042853_c001_v0001106_t0019_u01761884933440")
2025/10/31 04:28:56 DEBUG : sub dir/hello world: Deleting (id "4_zbf328d43806d3b8b9fa80e1b_f119cb7f4b694df1c_d20251031_m042851_c001_v0001150_t0057_u01761884931658")
--- PASS: TestServerSideMoveOverSelf (13.72s)
PASS
2025/10/31 04:28:58 DEBUG : B2 bucket rclone-test-keloyut0diza: Purge remote
2025/10/31 04:28:58 INFO  : B2 bucket rclone-test-keloyut0diza: cleaning bucket "rclone-test-keloyut0diza" of all files
2025/10/31 04:28:58 DEBUG : sub dir/hello world: Deleting (id "4_zbfe26db3806d3b8b9fa80e1b_f41400e9a7e0a4678_d20251031_m042856_c001_v7007000_t0000_u01761884936098")
2025/10/31 04:28:58 DEBUG : sub dir/hello world: Deleting (id "4_zbfe26db3806d3b8b9fa80e1b_f100275b6276d940a_d20251031_m042854_c001_v0001113_t0046_u01761884934706")
2025/10/31 04:28:58 DEBUG : sub dir/hello world: Deleting (id "4_zbfe26db3806d3b8b9fa80e1b_f41400e9a7e0a4663_d20251031_m042854_c001_v7007000_t0000_u01761884934160")
2025/10/31 04:28:58 DEBUG : sub dir/hello world: Deleting (id "4_zbfe26db3806d3b8b9fa80e1b_f100275b6276d93f4_d20251031_m042852_c001_v0001113_t0004_u01761884932415")
2025/10/31 04:28:58 DEBUG : sub dir/hello world: Deleting (id "4_zbfe26db3806d3b8b9fa80e1b_f100275b6276d93e4_d20251031_m042849_c001_v0001113_t0048_u01761884929379")
"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestServerSideMoveOverSelf$'" - Finished OK in 15.554366865s (try 2/5)