"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDriveBusiness: -verbose -test.run '^TestSyncConcurrentTruncate$'" - Starting (try 3/5)
2022/01/12 06:14:47 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-nuhorog5dahuweb5ritacex9"
2022/01/12 06:14:47 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2022/01/12 06:14:48 DEBUG : Creating backend with remote "/tmp/rclone2989497195"
=== RUN   TestSyncConcurrentTruncate
    run.go:181: Remote "One drive root 'rclone-test-nuhorog5dahuweb5ritacex9'", Local "Local file system at /tmp/rclone2989497195", Modify Window "1s"
2022/01/12 06:14:49 DEBUG : both0: Starting multipart upload
2022/01/12 06:14:49 DEBUG : both0: Uploading segment 0/6 size 6
2022/01/12 06:14:51 DEBUG : only0: Starting multipart upload
2022/01/12 06:14:51 DEBUG : only0: Uploading segment 0/6 size 6
2022/01/12 06:14:52 DEBUG : both1: Starting multipart upload
2022/01/12 06:14:52 DEBUG : both1: Uploading segment 0/6 size 6
2022/01/12 06:14:53 DEBUG : only1: Starting multipart upload
2022/01/12 06:14:53 DEBUG : only1: Uploading segment 0/6 size 6
2022/01/12 06:14:54 DEBUG : both2: Starting multipart upload
2022/01/12 06:14:55 DEBUG : both2: Uploading segment 0/6 size 6
2022/01/12 06:14:55 DEBUG : only2: Starting multipart upload
2022/01/12 06:14:56 DEBUG : only2: Uploading segment 0/6 size 6
2022/01/12 06:14:56 DEBUG : both3: Starting multipart upload
2022/01/12 06:14:57 DEBUG : both3: Uploading segment 0/6 size 6
2022/01/12 06:14:57 DEBUG : only3: Starting multipart upload
2022/01/12 06:14:58 DEBUG : only3: Uploading segment 0/6 size 6
2022/01/12 06:14:58 DEBUG : both4: Starting multipart upload
2022/01/12 06:14:59 DEBUG : both4: Uploading segment 0/6 size 6
2022/01/12 06:15:00 DEBUG : only4: Starting multipart upload
2022/01/12 06:15:00 DEBUG : only4: Uploading segment 0/6 size 6
2022/01/12 06:15:01 DEBUG : both5: Starting multipart upload
2022/01/12 06:15:01 DEBUG : both5: Uploading segment 0/6 size 6
2022/01/12 06:15:02 DEBUG : only5: Starting multipart upload
2022/01/12 06:15:02 DEBUG : only5: Uploading segment 0/6 size 6
2022/01/12 06:15:03 DEBUG : both6: Starting multipart upload
2022/01/12 06:15:03 DEBUG : both6: Uploading segment 0/6 size 6
2022/01/12 06:15:04 DEBUG : only6: Starting multipart upload
2022/01/12 06:15:04 DEBUG : only6: Uploading segment 0/6 size 6
2022/01/12 06:15:05 DEBUG : both7: Starting multipart upload
2022/01/12 06:15:05 DEBUG : both7: Uploading segment 0/6 size 6
2022/01/12 06:15:06 DEBUG : only7: Starting multipart upload
2022/01/12 06:15:06 DEBUG : only7: Uploading segment 0/6 size 6
2022/01/12 06:15:07 DEBUG : both8: Starting multipart upload
2022/01/12 06:15:07 DEBUG : both8: Uploading segment 0/6 size 6
2022/01/12 06:15:08 DEBUG : only8: Starting multipart upload
2022/01/12 06:15:09 DEBUG : only8: Uploading segment 0/6 size 6
2022/01/12 06:15:09 DEBUG : both9: Starting multipart upload
2022/01/12 06:15:10 DEBUG : both9: Uploading segment 0/6 size 6
2022/01/12 06:15:10 DEBUG : only9: Starting multipart upload
2022/01/12 06:15:11 DEBUG : only9: Uploading segment 0/6 size 6
2022/01/12 06:15:11 DEBUG : both10: Starting multipart upload
2022/01/12 06:15:12 DEBUG : both10: Uploading segment 0/6 size 6
2022/01/12 06:15:13 DEBUG : only10: Starting multipart upload
2022/01/12 06:15:13 DEBUG : only10: Uploading segment 0/6 size 6
2022/01/12 06:15:14 DEBUG : both11: Starting multipart upload
2022/01/12 06:15:14 DEBUG : both11: Uploading segment 0/6 size 6
2022/01/12 06:15:15 DEBUG : only11: Starting multipart upload
2022/01/12 06:15:15 DEBUG : only11: Uploading segment 0/6 size 6
2022/01/12 06:15:16 DEBUG : both12: Starting multipart upload
2022/01/12 06:15:16 DEBUG : both12: Uploading segment 0/6 size 6
2022/01/12 06:15:17 DEBUG : only12: Starting multipart upload
2022/01/12 06:15:17 DEBUG : only12: Uploading segment 0/6 size 6
2022/01/12 06:15:18 DEBUG : both13: Starting multipart upload
2022/01/12 06:15:18 DEBUG : both13: Uploading segment 0/6 size 6
2022/01/12 06:15:19 DEBUG : only13: Starting multipart upload
2022/01/12 06:15:19 DEBUG : only13: Uploading segment 0/6 size 6
2022/01/12 06:15:20 DEBUG : both14: Starting multipart upload
2022/01/12 06:15:20 DEBUG : both14: Uploading segment 0/6 size 6
2022/01/12 06:15:21 DEBUG : only14: Starting multipart upload
2022/01/12 06:15:21 DEBUG : only14: Uploading segment 0/6 size 6
2022/01/12 06:15:22 DEBUG : both15: Starting multipart upload
2022/01/12 06:15:23 DEBUG : both15: Uploading segment 0/6 size 6
2022/01/12 06:15:23 DEBUG : only15: Starting multipart upload
2022/01/12 06:15:24 DEBUG : only15: Uploading segment 0/6 size 6
2022/01/12 06:15:24 DEBUG : both16: Starting multipart upload
2022/01/12 06:15:25 DEBUG : both16: Uploading segment 0/6 size 6
2022/01/12 06:15:25 DEBUG : only16: Starting multipart upload
2022/01/12 06:15:26 DEBUG : only16: Uploading segment 0/6 size 6
2022/01/12 06:15:26 DEBUG : Too many requests. Trying again in 7 seconds.
2022/01/12 06:15:26 DEBUG : pacer: low level retry 1/10 (error activityLimitReached: throttledRequest: The request has been throttled)
2022/01/12 06:15:26 DEBUG : pacer: Rate limited, increasing sleep to 7s
2022/01/12 06:15:26 DEBUG : Too many requests. Trying again in 8 seconds.
2022/01/12 06:15:26 DEBUG : pacer: low level retry 2/10 (error activityLimitReached: throttledRequest: The request has been throttled)
2022/01/12 06:15:26 DEBUG : pacer: Rate limited, increasing sleep to 8s
2022/01/12 06:15:33 DEBUG : Too many requests. Trying again in 25 seconds.
2022/01/12 06:15:33 DEBUG : pacer: low level retry 3/10 (error activityLimitReached: throttledRequest: The request has been throttled)
2022/01/12 06:15:33 DEBUG : pacer: Rate limited, increasing sleep to 25s
2022/01/12 06:15:41 DEBUG : pacer: Reducing sleep to 18.75s
2022/01/12 06:15:41 DEBUG : both17: Starting multipart upload
2022/01/12 06:16:06 DEBUG : pacer: Reducing sleep to 14.0625s
2022/01/12 06:16:06 DEBUG : both17: Uploading segment 0/6 size 6
2022/01/12 06:16:25 DEBUG : pacer: Reducing sleep to 10.546875s
2022/01/12 06:16:25 DEBUG : only17: Starting multipart upload
2022/01/12 06:16:39 DEBUG : pacer: Reducing sleep to 7.91015625s
2022/01/12 06:16:39 DEBUG : only17: Uploading segment 0/6 size 6
2022/01/12 06:16:50 DEBUG : pacer: Reducing sleep to 5.932617187s
2022/01/12 06:16:50 DEBUG : both18: Starting multipart upload
2022/01/12 06:16:58 DEBUG : pacer: Reducing sleep to 4.44946289s
2022/01/12 06:16:58 DEBUG : both18: Uploading segment 0/6 size 6
2022/01/12 06:17:04 DEBUG : pacer: Reducing sleep to 3.337097167s
2022/01/12 06:17:04 DEBUG : only18: Starting multipart upload
2022/01/12 06:17:08 DEBUG : pacer: Reducing sleep to 2.502822875s
2022/01/12 06:17:08 DEBUG : only18: Uploading segment 0/6 size 6
2022/01/12 06:17:11 DEBUG : pacer: Reducing sleep to 1.877117156s
2022/01/12 06:17:11 DEBUG : both19: Starting multipart upload
2022/01/12 06:17:14 DEBUG : pacer: Reducing sleep to 1.407837867s
2022/01/12 06:17:14 DEBUG : both19: Uploading segment 0/6 size 6
2022/01/12 06:17:16 DEBUG : pacer: Reducing sleep to 1.0558784s
2022/01/12 06:17:16 DEBUG : only19: Starting multipart upload
2022/01/12 06:17:17 DEBUG : pacer: Reducing sleep to 791.9088ms
2022/01/12 06:17:17 DEBUG : only19: Uploading segment 0/6 size 6
2022/01/12 06:17:18 DEBUG : pacer: Reducing sleep to 593.9316ms
2022/01/12 06:17:19 DEBUG : pacer: Reducing sleep to 445.4487ms
2022/01/12 06:17:20 DEBUG : pacer: Reducing sleep to 334.086525ms
2022/01/12 06:17:20 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both0: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both1: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both10: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both11: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both12: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both13: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both14: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both15: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both16: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both17: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both18: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both19: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both2: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both3: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both4: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both5: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both6: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both7: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both8: Unchanged skipping
2022/01/12 06:17:20 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/01/12 06:17:20 DEBUG : both9: Unchanged skipping
2022/01/12 06:17:20 DEBUG : only0: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only1: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only10: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only11: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only12: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only13: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only14: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only15: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only16: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only17: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only18: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only19: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only2: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only3: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only4: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only5: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only6: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only0: Starting singlepart upload
2022/01/12 06:17:20 DEBUG : only1: Starting singlepart upload
2022/01/12 06:17:20 DEBUG : only7: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only8: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : only9: Sizes differ (src 0 vs dst 6)
2022/01/12 06:17:20 DEBUG : One drive root 'rclone-test-nuhorog5dahuweb5ritacex9': Waiting for checks to finish
2022/01/12 06:17:20 DEBUG : One drive root 'rclone-test-nuhorog5dahuweb5ritacex9': Waiting for transfers to finish
2022/01/12 06:17:20 DEBUG : only10: Starting singlepart upload
2022/01/12 06:17:20 DEBUG : only11: Starting singlepart upload
2022/01/12 06:17:21 DEBUG : pacer: Reducing sleep to 250.564893ms
2022/01/12 06:17:21 DEBUG : pacer: Reducing sleep to 187.923669ms
2022/01/12 06:17:21 DEBUG : pacer: Reducing sleep to 140.942751ms
2022/01/12 06:17:21 DEBUG : pacer: Reducing sleep to 105.707063ms
2022/01/12 06:17:22 DEBUG : pacer: Reducing sleep to 79.280297ms
2022/01/12 06:17:22 DEBUG : only1: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:22 DEBUG : only1: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:22 ERROR : only1: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:22 INFO  : only1: Removing failed copy
2022/01/12 06:17:22 DEBUG : pacer: Reducing sleep to 59.460222ms
2022/01/12 06:17:22 DEBUG : only0: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:22 DEBUG : only0: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:22 ERROR : only0: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:22 INFO  : only0: Removing failed copy
2022/01/12 06:17:22 DEBUG : pacer: Reducing sleep to 44.595166ms
2022/01/12 06:17:22 DEBUG : only10: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:22 DEBUG : only10: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:22 ERROR : only10: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:22 INFO  : only10: Removing failed copy
2022/01/12 06:17:22 DEBUG : pacer: Reducing sleep to 33.446374ms
2022/01/12 06:17:22 DEBUG : only11: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:22 DEBUG : only11: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:22 ERROR : only11: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:22 INFO  : only11: Removing failed copy
2022/01/12 06:17:22 DEBUG : pacer: Reducing sleep to 25.08478ms
2022/01/12 06:17:22 DEBUG : only12: Starting singlepart upload
2022/01/12 06:17:22 DEBUG : pacer: Reducing sleep to 18.813585ms
2022/01/12 06:17:22 DEBUG : only13: Starting singlepart upload
2022/01/12 06:17:22 DEBUG : pacer: Reducing sleep to 14.110188ms
2022/01/12 06:17:22 DEBUG : only14: Starting singlepart upload
2022/01/12 06:17:23 DEBUG : pacer: Reducing sleep to 10.582641ms
2022/01/12 06:17:23 DEBUG : pacer: Reducing sleep to 10ms
2022/01/12 06:17:23 DEBUG : only15: Starting singlepart upload
2022/01/12 06:17:23 DEBUG : only12: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:23 DEBUG : only12: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:23 ERROR : only12: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:23 INFO  : only12: Removing failed copy
2022/01/12 06:17:23 DEBUG : only13: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:23 DEBUG : only13: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:23 ERROR : only13: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:23 INFO  : only13: Removing failed copy
2022/01/12 06:17:24 DEBUG : only14: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:24 DEBUG : only14: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:24 ERROR : only14: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:24 INFO  : only14: Removing failed copy
2022/01/12 06:17:24 DEBUG : only16: Starting singlepart upload
2022/01/12 06:17:24 DEBUG : only17: Starting singlepart upload
2022/01/12 06:17:24 DEBUG : only18: Starting singlepart upload
2022/01/12 06:17:25 DEBUG : only15: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:25 DEBUG : only15: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:25 ERROR : only15: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:25 INFO  : only15: Removing failed copy
2022/01/12 06:17:25 DEBUG : only17: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:25 DEBUG : only17: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:25 ERROR : only17: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:25 INFO  : only17: Removing failed copy
2022/01/12 06:17:25 DEBUG : only19: Starting singlepart upload
2022/01/12 06:17:25 DEBUG : only2: Starting singlepart upload
2022/01/12 06:17:25 DEBUG : only16: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:25 DEBUG : only16: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:25 ERROR : only16: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:25 INFO  : only16: Removing failed copy
2022/01/12 06:17:26 DEBUG : only18: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:26 DEBUG : only18: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:26 ERROR : only18: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:26 INFO  : only18: Removing failed copy
2022/01/12 06:17:26 DEBUG : only3: Starting singlepart upload
2022/01/12 06:17:26 DEBUG : only2: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:26 DEBUG : only2: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:26 ERROR : only2: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:26 INFO  : only2: Removing failed copy
2022/01/12 06:17:26 DEBUG : only4: Starting singlepart upload
2022/01/12 06:17:26 DEBUG : only19: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:26 DEBUG : only19: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:26 ERROR : only19: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:26 INFO  : only19: Removing failed copy
2022/01/12 06:17:26 DEBUG : only5: Starting singlepart upload
2022/01/12 06:17:26 DEBUG : only6: Starting singlepart upload
2022/01/12 06:17:27 DEBUG : only4: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:27 DEBUG : only4: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:27 ERROR : only4: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:27 INFO  : only4: Removing failed copy
2022/01/12 06:17:27 DEBUG : only3: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:27 DEBUG : only3: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:27 ERROR : only3: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:27 INFO  : only3: Removing failed copy
2022/01/12 06:17:27 DEBUG : only5: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:27 DEBUG : only5: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:27 ERROR : only5: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:27 INFO  : only5: Removing failed copy
2022/01/12 06:17:27 DEBUG : only6: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:27 DEBUG : only6: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:27 ERROR : only6: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:27 INFO  : only6: Removing failed copy
2022/01/12 06:17:27 DEBUG : only7: Starting singlepart upload
2022/01/12 06:17:27 DEBUG : only8: Starting singlepart upload
2022/01/12 06:17:27 DEBUG : only9: Starting singlepart upload
2022/01/12 06:17:28 DEBUG : only9: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:28 DEBUG : only9: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:28 ERROR : only9: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:28 INFO  : only9: Removing failed copy
2022/01/12 06:17:28 DEBUG : only7: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:28 DEBUG : only7: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:28 ERROR : only7: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:28 INFO  : only7: Removing failed copy
2022/01/12 06:17:29 DEBUG : only8: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2989497195)
2022/01/12 06:17:29 DEBUG : only8: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-nuhorog5dahuweb5ritacex9')
2022/01/12 06:17:29 ERROR : only8: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/01/12 06:17:29 INFO  : only8: Removing failed copy
2022/01/12 06:17:29 ERROR : One drive root 'rclone-test-nuhorog5dahuweb5ritacex9': not deleting files as there were IO errors
2022/01/12 06:17:29 ERROR : One drive root 'rclone-test-nuhorog5dahuweb5ritacex9': not deleting directories as there were IO errors
    sync_test.go:2056: 
        	Error Trace:	sync_test.go:2056
        	            				sync_test.go:2066
        	Error:      	Received unexpected error:
        	            	corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	Sync must not return a error
    sync_test.go:2057: 
        	Error Trace:	sync_test.go:2057
        	            				sync_test.go:2066
        	Error:      	Should be false
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	Low level errors must not have happened
    fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:302: Flushing the directory cache
    fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:302: Flushing the directory cache
    fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:302: Flushing the directory cache
    fstest.go:306: 
        	Error Trace:	fstest.go:306
        	            				fstest.go:339
        	            				fstest.go:351
        	            				run.go:342
        	            				sync_test.go:2058
        	            				sync_test.go:2066
        	Error:      	Should be true
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	listing wrong, want
        	            	  both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (0), only1 (0), only10 (0), only11 (0), only12 (0), only13 (0), only14 (0), only15 (0), only16 (0), only17 (0), only18 (0), only19 (0), only2 (0), only3 (0), only4 (0), only5 (0), only6 (0), only7 (0), only8 (0), only9 (0) got
        	            	  both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6)
    fstest.go:204: Not found "only3"
    fstest.go:204: Not found "only17"
    fstest.go:204: Not found "only2"
    fstest.go:204: Not found "only1"
    fstest.go:204: Not found "only8"
    fstest.go:204: Not found "only13"
    fstest.go:204: Not found "only15"
    fstest.go:204: Not found "only14"
    fstest.go:204: Not found "only6"
    fstest.go:204: Not found "only7"
    fstest.go:204: Not found "only11"
    fstest.go:204: Not found "only16"
    fstest.go:204: Not found "only0"
    fstest.go:204: Not found "only4"
    fstest.go:204: Not found "only10"
    fstest.go:204: Not found "only18"
    fstest.go:204: Not found "only19"
    fstest.go:204: Not found "only5"
    fstest.go:204: Not found "only9"
    fstest.go:204: Not found "only12"
    fstest.go:207: 
        	Error Trace:	fstest.go:207
        	            				fstest.go:311
        	            				fstest.go:339
        	            				fstest.go:351
        	            				run.go:342
        	            				sync_test.go:2058
        	            				sync_test.go:2066
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 20
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	20 objects not found
--- FAIL: TestSyncConcurrentTruncate (175.71s)
FAIL
2022/01/12 06:17:44 DEBUG : One drive root 'rclone-test-nuhorog5dahuweb5ritacex9': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDriveBusiness: -verbose -test.run '^TestSyncConcurrentTruncate$'" - Finished ERROR in 2m56.935748679s (try 3/5): exit status 1: Failed [TestSyncConcurrentTruncate]