"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDriveBusiness: -verbose -test.run '^TestSyncConcurrentTruncate$'" - Starting (try 4/5) 2022/01/09 05:53:59 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-zaxojuw2zihoyeb5jatemar7" 2022/01/09 05:53:59 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/01/09 05:54:00 DEBUG : Creating backend with remote "/tmp/rclone2171627475" === RUN TestSyncConcurrentTruncate run.go:181: Remote "One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7'", Local "Local file system at /tmp/rclone2171627475", Modify Window "1s" 2022/01/09 05:54:01 DEBUG : both0: Starting multipart upload 2022/01/09 05:54:01 DEBUG : both0: Uploading segment 0/6 size 6 2022/01/09 05:54:03 DEBUG : only0: Starting multipart upload 2022/01/09 05:54:04 DEBUG : only0: Uploading segment 0/6 size 6 2022/01/09 05:54:05 DEBUG : both1: Starting multipart upload 2022/01/09 05:54:05 DEBUG : both1: Uploading segment 0/6 size 6 2022/01/09 05:54:06 DEBUG : only1: Starting multipart upload 2022/01/09 05:54:07 DEBUG : only1: Uploading segment 0/6 size 6 2022/01/09 05:54:07 DEBUG : both2: Starting multipart upload 2022/01/09 05:54:08 DEBUG : both2: Uploading segment 0/6 size 6 2022/01/09 05:54:09 DEBUG : only2: Starting multipart upload 2022/01/09 05:54:10 DEBUG : only2: Uploading segment 0/6 size 6 2022/01/09 05:54:10 DEBUG : both3: Starting multipart upload 2022/01/09 05:54:11 DEBUG : both3: Uploading segment 0/6 size 6 2022/01/09 05:54:12 DEBUG : only3: Starting multipart upload 2022/01/09 05:54:12 DEBUG : only3: Uploading segment 0/6 size 6 2022/01/09 05:54:13 DEBUG : both4: Starting multipart upload 2022/01/09 05:54:14 DEBUG : both4: Uploading segment 0/6 size 6 2022/01/09 05:54:14 DEBUG : only4: Starting multipart upload 2022/01/09 05:54:15 DEBUG : only4: Uploading segment 0/6 size 6 2022/01/09 05:54:16 DEBUG : both5: Starting multipart upload 2022/01/09 05:54:17 DEBUG : both5: Uploading segment 0/6 size 6 2022/01/09 05:54:17 DEBUG : only5: Starting multipart upload 2022/01/09 05:54:18 DEBUG : only5: Uploading segment 0/6 size 6 2022/01/09 05:54:19 DEBUG : both6: Starting multipart upload 2022/01/09 05:54:20 DEBUG : both6: Uploading segment 0/6 size 6 2022/01/09 05:54:20 DEBUG : only6: Starting multipart upload 2022/01/09 05:54:21 DEBUG : only6: Uploading segment 0/6 size 6 2022/01/09 05:54:21 DEBUG : both7: Starting multipart upload 2022/01/09 05:54:22 DEBUG : both7: Uploading segment 0/6 size 6 2022/01/09 05:54:23 DEBUG : only7: Starting multipart upload 2022/01/09 05:54:24 DEBUG : only7: Uploading segment 0/6 size 6 2022/01/09 05:54:24 DEBUG : both8: Starting multipart upload 2022/01/09 05:54:25 DEBUG : both8: Uploading segment 0/6 size 6 2022/01/09 05:54:25 DEBUG : only8: Starting multipart upload 2022/01/09 05:54:26 DEBUG : only8: Uploading segment 0/6 size 6 2022/01/09 05:54:27 DEBUG : both9: Starting multipart upload 2022/01/09 05:54:28 DEBUG : both9: Uploading segment 0/6 size 6 2022/01/09 05:54:28 DEBUG : only9: Starting multipart upload 2022/01/09 05:54:29 DEBUG : only9: Uploading segment 0/6 size 6 2022/01/09 05:54:29 DEBUG : both10: Starting multipart upload 2022/01/09 05:54:30 DEBUG : both10: Uploading segment 0/6 size 6 2022/01/09 05:54:31 DEBUG : only10: Starting multipart upload 2022/01/09 05:54:31 DEBUG : only10: Uploading segment 0/6 size 6 2022/01/09 05:54:32 DEBUG : both11: Starting multipart upload 2022/01/09 05:54:33 DEBUG : both11: Uploading segment 0/6 size 6 2022/01/09 05:54:33 DEBUG : only11: Starting multipart upload 2022/01/09 05:54:34 DEBUG : only11: Uploading segment 0/6 size 6 2022/01/09 05:54:35 DEBUG : both12: Starting multipart upload 2022/01/09 05:54:35 DEBUG : both12: Uploading segment 0/6 size 6 2022/01/09 05:54:36 DEBUG : Too many requests. Trying again in 33 seconds. 2022/01/09 05:54:36 DEBUG : pacer: low level retry 1/10 (error activityLimitReached: throttledRequest: The request has been throttled) 2022/01/09 05:54:36 DEBUG : pacer: Rate limited, increasing sleep to 33s 2022/01/09 05:54:36 DEBUG : Too many requests. Trying again in 32 seconds. 2022/01/09 05:54:36 DEBUG : pacer: low level retry 2/10 (error activityLimitReached: throttledRequest: The request has been throttled) 2022/01/09 05:54:36 DEBUG : pacer: Rate limited, increasing sleep to 32s 2022/01/09 05:55:09 DEBUG : pacer: Reducing sleep to 24s 2022/01/09 05:55:09 DEBUG : only12: Starting multipart upload 2022/01/09 05:55:41 DEBUG : pacer: Reducing sleep to 18s 2022/01/09 05:55:41 DEBUG : only12: Uploading segment 0/6 size 6 2022/01/09 05:56:05 DEBUG : pacer: Reducing sleep to 13.5s 2022/01/09 05:56:05 DEBUG : both13: Starting multipart upload 2022/01/09 05:56:23 DEBUG : pacer: Reducing sleep to 10.125s 2022/01/09 05:56:23 DEBUG : both13: Uploading segment 0/6 size 6 2022/01/09 05:56:37 DEBUG : pacer: Reducing sleep to 7.59375s 2022/01/09 05:56:37 DEBUG : only13: Starting multipart upload 2022/01/09 05:56:47 DEBUG : pacer: Reducing sleep to 5.6953125s 2022/01/09 05:56:47 DEBUG : only13: Uploading segment 0/6 size 6 2022/01/09 05:56:54 DEBUG : pacer: Reducing sleep to 4.271484375s 2022/01/09 05:56:54 DEBUG : both14: Starting multipart upload 2022/01/09 05:57:00 DEBUG : pacer: Reducing sleep to 3.203613281s 2022/01/09 05:57:00 DEBUG : both14: Uploading segment 0/6 size 6 2022/01/09 05:57:04 DEBUG : pacer: Reducing sleep to 2.40270996s 2022/01/09 05:57:04 DEBUG : only14: Starting multipart upload 2022/01/09 05:57:08 DEBUG : pacer: Reducing sleep to 1.80203247s 2022/01/09 05:57:08 DEBUG : only14: Uploading segment 0/6 size 6 2022/01/09 05:57:10 DEBUG : pacer: Reducing sleep to 1.351524352s 2022/01/09 05:57:10 DEBUG : both15: Starting multipart upload 2022/01/09 05:57:12 DEBUG : pacer: Reducing sleep to 1.013643264s 2022/01/09 05:57:12 DEBUG : both15: Uploading segment 0/6 size 6 2022/01/09 05:57:13 DEBUG : pacer: Reducing sleep to 760.232448ms 2022/01/09 05:57:13 DEBUG : only15: Starting multipart upload 2022/01/09 05:57:14 DEBUG : pacer: Reducing sleep to 570.174336ms 2022/01/09 05:57:14 DEBUG : only15: Uploading segment 0/6 size 6 2022/01/09 05:57:15 DEBUG : pacer: Reducing sleep to 427.630752ms 2022/01/09 05:57:15 DEBUG : both16: Starting multipart upload 2022/01/09 05:57:16 DEBUG : pacer: Reducing sleep to 320.723064ms 2022/01/09 05:57:16 DEBUG : both16: Uploading segment 0/6 size 6 2022/01/09 05:57:16 DEBUG : pacer: Reducing sleep to 240.542298ms 2022/01/09 05:57:16 DEBUG : only16: Starting multipart upload 2022/01/09 05:57:17 DEBUG : pacer: Reducing sleep to 180.406723ms 2022/01/09 05:57:17 DEBUG : only16: Uploading segment 0/6 size 6 2022/01/09 05:57:18 DEBUG : pacer: Reducing sleep to 135.305042ms 2022/01/09 05:57:18 DEBUG : both17: Starting multipart upload 2022/01/09 05:57:18 DEBUG : pacer: Reducing sleep to 101.478781ms 2022/01/09 05:57:18 DEBUG : both17: Uploading segment 0/6 size 6 2022/01/09 05:57:19 DEBUG : pacer: Reducing sleep to 76.109085ms 2022/01/09 05:57:19 DEBUG : only17: Starting multipart upload 2022/01/09 05:57:20 DEBUG : pacer: Reducing sleep to 57.081813ms 2022/01/09 05:57:20 DEBUG : only17: Uploading segment 0/6 size 6 2022/01/09 05:57:20 DEBUG : pacer: Reducing sleep to 42.811359ms 2022/01/09 05:57:20 DEBUG : both18: Starting multipart upload 2022/01/09 05:57:21 DEBUG : pacer: Reducing sleep to 32.108519ms 2022/01/09 05:57:21 DEBUG : both18: Uploading segment 0/6 size 6 2022/01/09 05:57:21 DEBUG : pacer: Reducing sleep to 24.081389ms 2022/01/09 05:57:21 DEBUG : only18: Starting multipart upload 2022/01/09 05:57:22 DEBUG : pacer: Reducing sleep to 18.061041ms 2022/01/09 05:57:22 DEBUG : only18: Uploading segment 0/6 size 6 2022/01/09 05:57:22 DEBUG : pacer: Reducing sleep to 13.54578ms 2022/01/09 05:57:22 DEBUG : both19: Starting multipart upload 2022/01/09 05:57:23 DEBUG : pacer: Reducing sleep to 10.159335ms 2022/01/09 05:57:23 DEBUG : both19: Uploading segment 0/6 size 6 2022/01/09 05:57:24 DEBUG : pacer: Reducing sleep to 10ms 2022/01/09 05:57:24 DEBUG : only19: Starting multipart upload 2022/01/09 05:57:24 DEBUG : only19: Uploading segment 0/6 size 6 2022/01/09 05:57:26 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both0: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both10: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both11: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both12: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both13: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both14: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both15: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both16: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both17: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both18: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both19: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both2: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both3: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both4: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both6: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both7: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both8: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both9: Unchanged skipping 2022/01/09 05:57:26 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both5: Unchanged skipping 2022/01/09 05:57:26 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7': Waiting for checks to finish 2022/01/09 05:57:26 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/09 05:57:26 DEBUG : both1: Unchanged skipping 2022/01/09 05:57:26 DEBUG : only1: Starting singlepart upload 2022/01/09 05:57:26 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/01/09 05:57:26 DEBUG : One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7': Waiting for transfers to finish 2022/01/09 05:57:26 DEBUG : only10: Starting singlepart upload 2022/01/09 05:57:26 DEBUG : only11: Starting singlepart upload 2022/01/09 05:57:26 DEBUG : only12: Starting singlepart upload 2022/01/09 05:57:27 DEBUG : only12: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:27 DEBUG : only12: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:27 ERROR : only12: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:27 INFO : only12: Removing failed copy 2022/01/09 05:57:27 DEBUG : only1: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:27 DEBUG : only1: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:27 ERROR : only1: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:27 INFO : only1: Removing failed copy 2022/01/09 05:57:27 DEBUG : only11: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:27 DEBUG : only11: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:27 ERROR : only11: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:27 INFO : only11: Removing failed copy 2022/01/09 05:57:27 DEBUG : only10: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:27 DEBUG : only10: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:27 ERROR : only10: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:27 INFO : only10: Removing failed copy 2022/01/09 05:57:28 DEBUG : only13: Starting singlepart upload 2022/01/09 05:57:28 DEBUG : only14: Starting singlepart upload 2022/01/09 05:57:28 DEBUG : only15: Starting singlepart upload 2022/01/09 05:57:28 DEBUG : only16: Starting singlepart upload 2022/01/09 05:57:29 DEBUG : only15: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:29 DEBUG : only15: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:29 ERROR : only15: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:29 INFO : only15: Removing failed copy 2022/01/09 05:57:29 DEBUG : only13: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:29 DEBUG : only13: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:29 ERROR : only13: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:29 INFO : only13: Removing failed copy 2022/01/09 05:57:29 DEBUG : only14: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:29 DEBUG : only14: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:29 ERROR : only14: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:29 INFO : only14: Removing failed copy 2022/01/09 05:57:29 DEBUG : only16: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:29 DEBUG : only16: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:29 ERROR : only16: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:29 INFO : only16: Removing failed copy 2022/01/09 05:57:29 DEBUG : only17: Starting singlepart upload 2022/01/09 05:57:29 DEBUG : only18: Starting singlepart upload 2022/01/09 05:57:29 DEBUG : only19: Starting singlepart upload 2022/01/09 05:57:29 DEBUG : only2: Starting singlepart upload 2022/01/09 05:57:30 DEBUG : only17: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:30 DEBUG : only17: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:30 ERROR : only17: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:30 INFO : only17: Removing failed copy 2022/01/09 05:57:30 DEBUG : only18: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:30 DEBUG : only18: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:30 ERROR : only18: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:30 INFO : only18: Removing failed copy 2022/01/09 05:57:30 DEBUG : only19: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:30 DEBUG : only19: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:30 ERROR : only19: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:30 INFO : only19: Removing failed copy 2022/01/09 05:57:30 DEBUG : only2: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:30 DEBUG : only2: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:30 ERROR : only2: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:30 INFO : only2: Removing failed copy 2022/01/09 05:57:30 DEBUG : only3: Starting singlepart upload 2022/01/09 05:57:30 DEBUG : only4: Starting singlepart upload 2022/01/09 05:57:30 DEBUG : only5: Starting singlepart upload 2022/01/09 05:57:31 DEBUG : only6: Starting singlepart upload 2022/01/09 05:57:31 DEBUG : only3: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:31 DEBUG : only3: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:31 ERROR : only3: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:31 INFO : only3: Removing failed copy 2022/01/09 05:57:31 DEBUG : only5: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:31 DEBUG : only5: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:31 ERROR : only5: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:31 INFO : only5: Removing failed copy 2022/01/09 05:57:31 DEBUG : only4: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:31 DEBUG : only4: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:31 ERROR : only4: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:31 INFO : only4: Removing failed copy 2022/01/09 05:57:31 DEBUG : only7: Starting singlepart upload 2022/01/09 05:57:31 DEBUG : only6: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:31 DEBUG : only6: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:31 ERROR : only6: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:31 INFO : only6: Removing failed copy 2022/01/09 05:57:31 DEBUG : only8: Starting singlepart upload 2022/01/09 05:57:32 DEBUG : only9: Starting singlepart upload 2022/01/09 05:57:32 DEBUG : only0: Starting singlepart upload 2022/01/09 05:57:32 DEBUG : only8: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:32 DEBUG : only8: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:32 ERROR : only8: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:32 INFO : only8: Removing failed copy 2022/01/09 05:57:32 DEBUG : only0: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:32 DEBUG : only0: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:32 ERROR : only0: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:32 INFO : only0: Removing failed copy 2022/01/09 05:57:32 DEBUG : only9: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:32 DEBUG : only9: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:32 ERROR : only9: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:32 INFO : only9: Removing failed copy 2022/01/09 05:57:33 DEBUG : only7: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone2171627475) 2022/01/09 05:57:33 DEBUG : only7: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7') 2022/01/09 05:57:33 ERROR : only7: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/09 05:57:33 INFO : only7: Removing failed copy 2022/01/09 05:57:33 ERROR : One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7': not deleting files as there were IO errors 2022/01/09 05:57:33 ERROR : One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7': 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 "only4" fstest.go:204: Not found "only10" fstest.go:204: Not found "only13" fstest.go:204: Not found "only17" fstest.go:204: Not found "only0" fstest.go:204: Not found "only15" fstest.go:204: Not found "only19" fstest.go:204: Not found "only3" fstest.go:204: Not found "only6" fstest.go:204: Not found "only2" fstest.go:204: Not found "only9" fstest.go:204: Not found "only7" fstest.go:204: Not found "only8" fstest.go:204: Not found "only14" fstest.go:204: Not found "only16" fstest.go:204: Not found "only5" fstest.go:204: Not found "only11" fstest.go:204: Not found "only18" fstest.go:204: Not found "only1" 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 (226.46s) FAIL 2022/01/09 05:57:47 DEBUG : One drive root 'rclone-test-zaxojuw2zihoyeb5jatemar7': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDriveBusiness: -verbose -test.run '^TestSyncConcurrentTruncate$'" - Finished ERROR in 3m47.528452734s (try 4/5): exit status 1: Failed [TestSyncConcurrentTruncate]