Sync command looping/stuck in batch file?

Help and Support
1

I’m running rclone 1.38 on Windows 10 Pro. I’m attempting to sync one file at the moment and it seems to keep looping on the same file until I manually exit the process.

For instance, my command:

rclone sync “C:/Users/MyUserName/Documents/graphics2.psd” “b2enc:my_bucket/Documents/graphics2.psd” --stats 30s --fast-list --transfers 32 -v

Some of the output, notice my manual intervention at the end:

C:\Users\MyUserName\Documents\MyCustomApps\rclone\scripts>rclone sync “C:/Users/MyUserName/Documents/graphics2.psd” “b2enc:my_bucket/Documents/graphics2.psd” --stats 30s --fast-list --transfers 32 -v
2017/10/06 09:10:24 INFO : Encrypted drive ‘b2enc:my_bucket/Documents/graphics2.psd’: Modify window is 1ms
2017/10/06 09:10:24 ERROR : Ignoring --no-traverse with sync
2017/10/06 09:10:24 INFO : Encrypted drive ‘b2enc:my_bucket/Documents/graphics2.psd’: Waiting for checks to finish
2017/10/06 09:10:24 INFO : Encrypted drive ‘b2enc:my_bucket/Documents/graphics2.psd’: Waiting for transfers to finish
2017/10/06 09:10:54 INFO :
Transferred: 500 MBytes (15.649 MBytes/s)
Errors: 0
Checks: 0
Transferred: 0
Elapsed time: 31.9s
Transferring:

  •                             graphics2.psd: 100% done, 13.029 MBytes/s, ETA: 0s

2017/10/06 09:11:24 INFO :
Transferred: 500 MBytes (8.071 MBytes/s)
Errors: 0
Checks: 0
Transferred: 0
Elapsed time: 1m1.9s
Transferring:

  •                             graphics2.psd: 100% done, 1.762 MBytes/s, ETA: 0s

Terminate batch job (Y/N)? n

2

Is that a one off, or does it get stuck every time? Or every time on the same file?

If you can reproduce it then can you post a log with -vv of it happening?

3

Perhaps I just didn’t give it enough time (seems to have gone through)? Here’s with the log as per your request:

rclone sync “C:/Users/LocalUser/Documents/graphics2.psd” “b2enc:my_bucket/Documents/graphics2.psd” --stats 30s --fast-list --transfers 32 -vv
2017/10/09 20:13:39 DEBUG : Using config file from “C:\Users\LocalUser\.config\rclone\rclone.conf”
2017/10/09 20:13:39 DEBUG : rclone: Version “v1.38” starting with parameters [“rclone” “sync” “C:/Users/LocalUser/Documents/graphics2.psd” “b2enc:my_bucket/Documents/graphics2.psd” “–stats” “30s” “–fast-list” “–transfers” “32” “-vv”]
2017/10/09 20:13:41 INFO : Encrypted drive ‘b2enc:my_bucket/Documents/graphics2.psd’: Modify window is 1ms
2017/10/09 20:13:41 INFO : Encrypted drive ‘b2enc:my_bucket/Documents/graphics2.psd’: Waiting for checks to finish
2017/10/09 20:13:41 INFO : Encrypted drive ‘b2enc:my_bucket/Documents/graphics2.psd’: Waiting for transfers to finish
2017/10/09 20:13:42 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Starting upload of large file in 6 chunks (id “4_zeba88173ca1839a153e1041d_f203c2c87bcf1247c_d20171009_m231343_c001_v0001044_t0025”)
2017/10/09 20:13:42 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Didn’t find object to account part transfer
2017/10/09 20:13:43 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Sending chunk 1 length 100663296
2017/10/09 20:13:43 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Didn’t find object to account part transfer
2017/10/09 20:13:43 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Sending chunk 2 length 100663296
2017/10/09 20:13:43 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Didn’t find object to account part transfer
2017/10/09 20:13:44 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Sending chunk 3 length 100663296
2017/10/09 20:13:44 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Didn’t find object to account part transfer
2017/10/09 20:13:44 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Sending chunk 4 length 100663296
2017/10/09 20:13:44 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Didn’t find object to account part transfer
2017/10/09 20:13:45 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Sending chunk 5 length 100663296
2017/10/09 20:13:45 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Sending chunk 6 length 21099552
2017/10/09 20:13:45 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Didn’t find object to account part transfer
2017/10/09 20:13:45 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Didn’t find object to account part transfer
2017/10/09 20:14:11 INFO :
Transferred: 500 MBytes (15.415 MBytes/s)
Errors: 0
Checks: 0
Transferred: 0
Elapsed time: 32.4s
Transferring:

  •                             graphics2.psd: 100% done, 9.378 MBytes/s, ETA: 0s

2017/10/09 20:14:41 INFO :
Transferred: 500 MBytes (8.008 MBytes/s)
Errors: 0
Checks: 0
Transferred: 0
Elapsed time: 1m2.4s
Transferring:

  •                             graphics2.psd: 100% done, 1.268 MBytes/s, ETA: 0s

2017/10/09 20:14:50 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Done sending chunk 6
2017/10/09 20:15:11 INFO :
Transferred: 500 MBytes (5.409 MBytes/s)
Errors: 0
Checks: 0
Transferred: 0
Elapsed time: 1m32.4s
Transferring:

  •                             graphics2.psd: 100% done, 175.618 kBytes/s, ETA: 0s

2017/10/09 20:15:41 INFO :
Transferred: 500 MBytes (4.084 MBytes/s)
Errors: 0
Checks: 0
Transferred: 0
Elapsed time: 2m2.4s
Transferring:

  •                             graphics2.psd: 100% done, 23.749 kBytes/s, ETA: 0s

2017/10/09 20:16:11 INFO :
Transferred: 500 MBytes (3.280 MBytes/s)
Errors: 0
Checks: 0
Transferred: 0
Elapsed time: 2m32.4s
Transferring:

  •                             graphics2.psd: 100% done, 3.211 kBytes/s, ETA: 0s

2017/10/09 20:16:41 INFO :
Transferred: 500 MBytes (2.741 MBytes/s)
Errors: 0
Checks: 0
Transferred: 0
Elapsed time: 3m2.4s
Transferring:

  •                             graphics2.psd: 100% done, 444 Bytes/s, ETA: 0s

2017/10/09 20:16:42 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Done sending chunk 3
2017/10/09 20:16:42 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Done sending chunk 2
2017/10/09 20:16:55 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Done sending chunk 1
2017/10/09 20:17:03 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Done sending chunk 5
2017/10/09 20:17:11 INFO :
Transferred: 500 MBytes (2.354 MBytes/s)
Errors: 0
Checks: 0
Transferred: 0
Elapsed time: 3m32.4s
Transferring:

  •                             graphics2.psd: 100% done, 60 Bytes/s, ETA: 0s

2017/10/09 20:17:29 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Done sending chunk 4
2017/10/09 20:17:29 DEBUG : 6b1i58obeq8updtv6562ufr0p0: Finishing large file upload with 6 parts
2017/10/09 20:17:32 INFO : graphics2.psd: Copied (new)
2017/10/09 20:17:32 INFO : Waiting for deletions to finish
2017/10/09 20:17:32 INFO :
Transferred: 500 MBytes (2.145 MBytes/s)
Errors: 0
Checks: 0
Transferred: 1
Elapsed time: 3m53.1s

2017/10/09 20:17:32 DEBUG : Go routines at exit 6
2017/10/09 20:17:32 DEBUG : rclone: Version “v1.38” finishing with parameters [“rclone” “sync” “C:/Users/LocalUser/Documents/graphics2.psd” “b2enc:my_bucket/Documents/graphics2.psd” “–stats” “30s” “–fast-list” “–transfers” “32” “-vv”]

4

B2 has quite a lot of internal buffering, so probably what happened is that the internal buffers were emptying. The 100% done is mis-leading - I haven’t figured out a way of fixing that yet!