"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDriveBusiness: -verbose -test.run '^TestSyncConcurrentTruncate$'" - Starting (try 5/5)
2022/03/03 05:39:41 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-yeqihof9pefozut6behoseg5"
2022/03/03 05:39:41 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2022/03/03 05:39:42 DEBUG : Creating backend with remote "/tmp/rclone401373191"
=== RUN   TestSyncConcurrentTruncate
    run.go:181: Remote "One drive root 'rclone-test-yeqihof9pefozut6behoseg5'", Local "Local file system at /tmp/rclone401373191", Modify Window "1s"
2022/03/03 05:39:43 DEBUG : both0: Starting multipart upload
2022/03/03 05:39:43 DEBUG : both0: Uploading segment 0/6 size 6
2022/03/03 05:39:44 DEBUG : only0: Starting multipart upload
2022/03/03 05:39:45 DEBUG : only0: Uploading segment 0/6 size 6
2022/03/03 05:39:45 DEBUG : both1: Starting multipart upload
2022/03/03 05:39:46 DEBUG : both1: Uploading segment 0/6 size 6
2022/03/03 05:39:46 DEBUG : only1: Starting multipart upload
2022/03/03 05:39:47 DEBUG : only1: Uploading segment 0/6 size 6
2022/03/03 05:39:48 DEBUG : both2: Starting multipart upload
2022/03/03 05:39:48 DEBUG : both2: Uploading segment 0/6 size 6
2022/03/03 05:39:49 DEBUG : only2: Starting multipart upload
2022/03/03 05:39:49 DEBUG : only2: Uploading segment 0/6 size 6
2022/03/03 05:39:50 DEBUG : both3: Starting multipart upload
2022/03/03 05:39:51 DEBUG : both3: Uploading segment 0/6 size 6
2022/03/03 05:39:51 DEBUG : only3: Starting multipart upload
2022/03/03 05:39:52 DEBUG : only3: Uploading segment 0/6 size 6
2022/03/03 05:39:52 DEBUG : both4: Starting multipart upload
2022/03/03 05:39:53 DEBUG : both4: Uploading segment 0/6 size 6
2022/03/03 05:39:53 DEBUG : only4: Starting multipart upload
2022/03/03 05:39:54 DEBUG : only4: Uploading segment 0/6 size 6
2022/03/03 05:39:55 DEBUG : both5: Starting multipart upload
2022/03/03 05:39:55 DEBUG : both5: Uploading segment 0/6 size 6
2022/03/03 05:39:56 DEBUG : only5: Starting multipart upload
2022/03/03 05:39:57 DEBUG : only5: Uploading segment 0/6 size 6
2022/03/03 05:39:57 DEBUG : both6: Starting multipart upload
2022/03/03 05:39:58 DEBUG : both6: Uploading segment 0/6 size 6
2022/03/03 05:39:59 DEBUG : only6: Starting multipart upload
2022/03/03 05:39:59 DEBUG : only6: Uploading segment 0/6 size 6
2022/03/03 05:40:00 DEBUG : both7: Starting multipart upload
2022/03/03 05:40:00 DEBUG : both7: Uploading segment 0/6 size 6
2022/03/03 05:40:01 DEBUG : only7: Starting multipart upload
2022/03/03 05:40:02 DEBUG : only7: Uploading segment 0/6 size 6
2022/03/03 05:40:02 DEBUG : both8: Starting multipart upload
2022/03/03 05:40:03 DEBUG : both8: Uploading segment 0/6 size 6
2022/03/03 05:40:03 DEBUG : only8: Starting multipart upload
2022/03/03 05:40:04 DEBUG : only8: Uploading segment 0/6 size 6
2022/03/03 05:40:05 DEBUG : both9: Starting multipart upload
2022/03/03 05:40:05 DEBUG : both9: Uploading segment 0/6 size 6
2022/03/03 05:40:06 DEBUG : only9: Starting multipart upload
2022/03/03 05:40:07 DEBUG : only9: Uploading segment 0/6 size 6
2022/03/03 05:40:07 DEBUG : both10: Starting multipart upload
2022/03/03 05:40:08 DEBUG : both10: Uploading segment 0/6 size 6
2022/03/03 05:40:08 DEBUG : Too many requests. Trying again in 13 seconds.
2022/03/03 05:40:08 DEBUG : pacer: low level retry 1/10 (error activityLimitReached: throttledRequest: The request has been throttled)
2022/03/03 05:40:08 DEBUG : pacer: Rate limited, increasing sleep to 13s
2022/03/03 05:40:09 DEBUG : pacer: Reducing sleep to 9.75s
2022/03/03 05:40:09 DEBUG : only10: Starting multipart upload
2022/03/03 05:40:22 DEBUG : pacer: Reducing sleep to 7.3125s
2022/03/03 05:40:22 DEBUG : only10: Uploading segment 0/6 size 6
2022/03/03 05:40:31 DEBUG : Too many requests. Trying again in 63 seconds.
2022/03/03 05:40:31 DEBUG : pacer: low level retry 1/10 (error activityLimitReached: throttledRequest: The request has been throttled)
2022/03/03 05:40:31 DEBUG : pacer: Rate limited, increasing sleep to 1m3s
2022/03/03 05:40:39 DEBUG : pacer: Reducing sleep to 47.25s
2022/03/03 05:40:39 DEBUG : both11: Starting multipart upload
2022/03/03 05:41:42 DEBUG : pacer: Reducing sleep to 35.4375s
2022/03/03 05:41:42 DEBUG : both11: Uploading segment 0/6 size 6
2022/03/03 05:42:30 DEBUG : pacer: Reducing sleep to 26.578125s
2022/03/03 05:42:30 DEBUG : only11: Starting multipart upload
2022/03/03 05:43:05 DEBUG : pacer: Reducing sleep to 19.93359375s
2022/03/03 05:43:05 DEBUG : only11: Uploading segment 0/6 size 6
2022/03/03 05:43:32 DEBUG : pacer: Reducing sleep to 14.950195312s
2022/03/03 05:43:32 DEBUG : both12: Starting multipart upload
2022/03/03 05:43:51 DEBUG : pacer: Reducing sleep to 11.212646484s
2022/03/03 05:43:51 DEBUG : both12: Uploading segment 0/6 size 6
2022/03/03 05:44:06 DEBUG : pacer: Reducing sleep to 8.409484863s
2022/03/03 05:44:06 DEBUG : only12: Starting multipart upload
2022/03/03 05:44:17 DEBUG : pacer: Reducing sleep to 6.307113647s
2022/03/03 05:44:17 DEBUG : only12: Uploading segment 0/6 size 6
2022/03/03 05:44:25 DEBUG : pacer: Reducing sleep to 4.730335235s
2022/03/03 05:44:25 DEBUG : both13: Starting multipart upload
2022/03/03 05:44:32 DEBUG : pacer: Reducing sleep to 3.547751426s
2022/03/03 05:44:32 DEBUG : both13: Uploading segment 0/6 size 6
2022/03/03 05:44:37 DEBUG : pacer: Reducing sleep to 2.660813569s
2022/03/03 05:44:37 DEBUG : only13: Starting multipart upload
2022/03/03 05:44:40 DEBUG : pacer: Reducing sleep to 1.995610176s
2022/03/03 05:44:40 DEBUG : only13: Uploading segment 0/6 size 6
2022/03/03 05:44:43 DEBUG : pacer: Reducing sleep to 1.496707632s
2022/03/03 05:44:43 DEBUG : both14: Starting multipart upload
2022/03/03 05:44:45 DEBUG : pacer: Reducing sleep to 1.122530724s
2022/03/03 05:44:45 DEBUG : both14: Uploading segment 0/6 size 6
2022/03/03 05:44:46 DEBUG : pacer: Reducing sleep to 841.898043ms
2022/03/03 05:44:46 DEBUG : only14: Starting multipart upload
2022/03/03 05:44:47 DEBUG : pacer: Reducing sleep to 631.423532ms
2022/03/03 05:44:47 DEBUG : only14: Uploading segment 0/6 size 6
2022/03/03 05:44:48 DEBUG : pacer: Reducing sleep to 473.567649ms
2022/03/03 05:44:48 DEBUG : both15: Starting multipart upload
2022/03/03 05:44:49 DEBUG : pacer: Reducing sleep to 355.175736ms
2022/03/03 05:44:49 DEBUG : both15: Uploading segment 0/6 size 6
2022/03/03 05:44:50 DEBUG : pacer: Reducing sleep to 266.381802ms
2022/03/03 05:44:50 DEBUG : only15: Starting multipart upload
2022/03/03 05:44:50 DEBUG : pacer: Reducing sleep to 199.786351ms
2022/03/03 05:44:50 DEBUG : only15: Uploading segment 0/6 size 6
2022/03/03 05:44:51 DEBUG : pacer: Reducing sleep to 149.839763ms
2022/03/03 05:44:51 DEBUG : both16: Starting multipart upload
2022/03/03 05:44:52 DEBUG : pacer: Reducing sleep to 112.379822ms
2022/03/03 05:44:52 DEBUG : both16: Uploading segment 0/6 size 6
2022/03/03 05:44:52 DEBUG : pacer: Reducing sleep to 84.284866ms
2022/03/03 05:44:52 DEBUG : only16: Starting multipart upload
2022/03/03 05:44:53 DEBUG : pacer: Reducing sleep to 63.213649ms
2022/03/03 05:44:53 DEBUG : only16: Uploading segment 0/6 size 6
2022/03/03 05:44:53 DEBUG : pacer: Reducing sleep to 47.410236ms
2022/03/03 05:44:53 DEBUG : both17: Starting multipart upload
2022/03/03 05:44:54 DEBUG : pacer: Reducing sleep to 35.557677ms
2022/03/03 05:44:54 DEBUG : both17: Uploading segment 0/6 size 6
2022/03/03 05:44:54 DEBUG : pacer: Reducing sleep to 26.668257ms
2022/03/03 05:44:54 DEBUG : only17: Starting multipart upload
2022/03/03 05:44:55 DEBUG : pacer: Reducing sleep to 20.001192ms
2022/03/03 05:44:55 DEBUG : only17: Uploading segment 0/6 size 6
2022/03/03 05:44:56 DEBUG : pacer: Reducing sleep to 15.000894ms
2022/03/03 05:44:56 DEBUG : both18: Starting multipart upload
2022/03/03 05:44:56 DEBUG : pacer: Reducing sleep to 11.25067ms
2022/03/03 05:44:56 DEBUG : both18: Uploading segment 0/6 size 6
2022/03/03 05:44:57 DEBUG : pacer: Reducing sleep to 10ms
2022/03/03 05:44:57 DEBUG : only18: Starting multipart upload
2022/03/03 05:44:57 DEBUG : only18: Uploading segment 0/6 size 6
2022/03/03 05:44:58 DEBUG : both19: Starting multipart upload
2022/03/03 05:44:59 DEBUG : both19: Uploading segment 0/6 size 6
2022/03/03 05:44:59 DEBUG : only19: Starting multipart upload
2022/03/03 05:45:01 DEBUG : only19: Uploading segment 0/6 size 6
2022/03/03 05:45:02 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both0: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both1: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both10: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both11: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both12: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both13: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both14: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both15: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both16: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both17: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both18: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both19: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both2: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both3: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both4: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both5: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both6: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both7: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both8: Unchanged skipping
2022/03/03 05:45:02 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/03/03 05:45:02 DEBUG : both9: Unchanged skipping
2022/03/03 05:45:02 DEBUG : only0: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only1: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only10: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only11: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only12: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only13: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only14: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only15: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only16: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only17: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only18: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only19: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only2: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only3: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only4: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only5: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only6: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only0: Starting singlepart upload
2022/03/03 05:45:02 DEBUG : only7: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only8: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only9: Sizes differ (src 0 vs dst 6)
2022/03/03 05:45:02 DEBUG : only1: Starting singlepart upload
2022/03/03 05:45:02 DEBUG : only10: Starting singlepart upload
2022/03/03 05:45:02 DEBUG : One drive root 'rclone-test-yeqihof9pefozut6behoseg5': Waiting for checks to finish
2022/03/03 05:45:02 DEBUG : One drive root 'rclone-test-yeqihof9pefozut6behoseg5': Waiting for transfers to finish
2022/03/03 05:45:02 DEBUG : only11: Starting singlepart upload
2022/03/03 05:45:03 DEBUG : only11: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:03 DEBUG : only11: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:03 ERROR : only11: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:03 INFO  : only11: Removing failed copy
2022/03/03 05:45:03 DEBUG : only0: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:03 DEBUG : only0: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:03 ERROR : only0: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:03 INFO  : only0: Removing failed copy
2022/03/03 05:45:03 DEBUG : only10: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:03 DEBUG : only10: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:03 ERROR : only10: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:03 INFO  : only10: Removing failed copy
2022/03/03 05:45:03 DEBUG : only1: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:03 DEBUG : only1: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:03 ERROR : only1: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:03 INFO  : only1: Removing failed copy
2022/03/03 05:45:03 DEBUG : only12: Starting singlepart upload
2022/03/03 05:45:03 DEBUG : only13: Starting singlepart upload
2022/03/03 05:45:03 DEBUG : only14: Starting singlepart upload
2022/03/03 05:45:03 DEBUG : only15: Starting singlepart upload
2022/03/03 05:45:04 DEBUG : only12: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:04 DEBUG : only12: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:04 ERROR : only12: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:04 INFO  : only12: Removing failed copy
2022/03/03 05:45:04 DEBUG : only14: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:04 DEBUG : only14: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:04 ERROR : only14: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:04 INFO  : only14: Removing failed copy
2022/03/03 05:45:04 DEBUG : only13: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:04 DEBUG : only13: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:04 ERROR : only13: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:04 INFO  : only13: Removing failed copy
2022/03/03 05:45:04 DEBUG : only16: Starting singlepart upload
2022/03/03 05:45:04 DEBUG : only15: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:04 DEBUG : only15: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:04 ERROR : only15: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:04 INFO  : only15: Removing failed copy
2022/03/03 05:45:04 DEBUG : only17: Starting singlepart upload
2022/03/03 05:45:04 DEBUG : only18: Starting singlepart upload
2022/03/03 05:45:04 DEBUG : only19: Starting singlepart upload
2022/03/03 05:45:05 DEBUG : only16: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:05 DEBUG : only16: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:05 ERROR : only16: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:05 INFO  : only16: Removing failed copy
2022/03/03 05:45:05 DEBUG : only17: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:05 DEBUG : only17: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:05 ERROR : only17: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:05 INFO  : only17: Removing failed copy
2022/03/03 05:45:05 DEBUG : only18: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:05 DEBUG : only18: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:05 ERROR : only18: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:05 INFO  : only18: Removing failed copy
2022/03/03 05:45:05 DEBUG : only2: Starting singlepart upload
2022/03/03 05:45:05 DEBUG : only19: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:05 DEBUG : only19: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:05 ERROR : only19: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:05 INFO  : only19: Removing failed copy
2022/03/03 05:45:05 DEBUG : only3: Starting singlepart upload
2022/03/03 05:45:05 DEBUG : only4: Starting singlepart upload
2022/03/03 05:45:06 DEBUG : only5: Starting singlepart upload
2022/03/03 05:45:06 DEBUG : only2: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:06 DEBUG : only2: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:06 ERROR : only2: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:06 INFO  : only2: Removing failed copy
2022/03/03 05:45:06 DEBUG : only3: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:06 DEBUG : only3: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:06 ERROR : only3: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:06 INFO  : only3: Removing failed copy
2022/03/03 05:45:06 DEBUG : only5: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:06 DEBUG : only5: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:06 ERROR : only5: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:06 INFO  : only5: Removing failed copy
2022/03/03 05:45:06 DEBUG : only6: Starting singlepart upload
2022/03/03 05:45:06 DEBUG : only7: Starting singlepart upload
2022/03/03 05:45:07 DEBUG : only8: Starting singlepart upload
2022/03/03 05:45:07 DEBUG : only4: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:07 DEBUG : only4: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:07 ERROR : only4: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:07 INFO  : only4: Removing failed copy
2022/03/03 05:45:07 DEBUG : only9: Starting singlepart upload
2022/03/03 05:45:07 DEBUG : only7: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:07 DEBUG : only7: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:07 ERROR : only7: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:07 INFO  : only7: Removing failed copy
2022/03/03 05:45:07 DEBUG : only8: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:07 DEBUG : only8: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:07 ERROR : only8: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:07 INFO  : only8: Removing failed copy
2022/03/03 05:45:07 DEBUG : only6: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:07 DEBUG : only6: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:07 ERROR : only6: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:07 INFO  : only6: Removing failed copy
2022/03/03 05:45:08 DEBUG : only9: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone401373191)
2022/03/03 05:45:08 DEBUG : only9: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-yeqihof9pefozut6behoseg5')
2022/03/03 05:45:08 ERROR : only9: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000"
2022/03/03 05:45:08 INFO  : only9: Removing failed copy
2022/03/03 05:45:08 ERROR : One drive root 'rclone-test-yeqihof9pefozut6behoseg5': not deleting files as there were IO errors
2022/03/03 05:45:08 ERROR : One drive root 'rclone-test-yeqihof9pefozut6behoseg5': 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 "only15"
    fstest.go:204: Not found "only3"
    fstest.go:204: Not found "only6"
    fstest.go:204: Not found "only8"
    fstest.go:204: Not found "only9"
    fstest.go:204: Not found "only16"
    fstest.go:204: Not found "only17"
    fstest.go:204: Not found "only2"
    fstest.go:204: Not found "only19"
    fstest.go:204: Not found "only0"
    fstest.go:204: Not found "only10"
    fstest.go:204: Not found "only14"
    fstest.go:204: Not found "only7"
    fstest.go:204: Not found "only13"
    fstest.go:204: Not found "only18"
    fstest.go:204: Not found "only1"
    fstest.go:204: Not found "only4"
    fstest.go:204: Not found "only11"
    fstest.go:204: Not found "only12"
    fstest.go:204: Not found "only5"
    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 (339.22s)
FAIL
2022/03/03 05:45:21 DEBUG : One drive root 'rclone-test-yeqihof9pefozut6behoseg5': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDriveBusiness: -verbose -test.run '^TestSyncConcurrentTruncate$'" - Finished ERROR in 5m40.339158443s (try 5/5): exit status 1: Failed [TestSyncConcurrentTruncate]