"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDriveBusiness: -verbose -test.run '^TestSyncConcurrentTruncate$'" - Starting (try 3/5) 2022/01/08 07:09:04 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-zajerob2retovip0jagiyar4" 2022/01/08 07:09:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/01/08 07:09:04 DEBUG : Creating backend with remote "/tmp/rclone118025437" === RUN TestSyncConcurrentTruncate run.go:181: Remote "One drive root 'rclone-test-zajerob2retovip0jagiyar4'", Local "Local file system at /tmp/rclone118025437", Modify Window "1s" 2022/01/08 07:09:05 DEBUG : both0: Starting multipart upload 2022/01/08 07:09:06 DEBUG : both0: Uploading segment 0/6 size 6 2022/01/08 07:09:07 DEBUG : only0: Starting multipart upload 2022/01/08 07:09:07 DEBUG : only0: Uploading segment 0/6 size 6 2022/01/08 07:09:08 DEBUG : both1: Starting multipart upload 2022/01/08 07:09:08 DEBUG : both1: Uploading segment 0/6 size 6 2022/01/08 07:09:09 DEBUG : only1: Starting multipart upload 2022/01/08 07:09:09 DEBUG : only1: Uploading segment 0/6 size 6 2022/01/08 07:09:10 DEBUG : both2: Starting multipart upload 2022/01/08 07:09:10 DEBUG : both2: Uploading segment 0/6 size 6 2022/01/08 07:09:11 DEBUG : only2: Starting multipart upload 2022/01/08 07:09:11 DEBUG : only2: Uploading segment 0/6 size 6 2022/01/08 07:09:12 DEBUG : both3: Starting multipart upload 2022/01/08 07:09:12 DEBUG : both3: Uploading segment 0/6 size 6 2022/01/08 07:09:13 DEBUG : only3: Starting multipart upload 2022/01/08 07:09:13 DEBUG : only3: Uploading segment 0/6 size 6 2022/01/08 07:09:14 DEBUG : both4: Starting multipart upload 2022/01/08 07:09:15 DEBUG : both4: Uploading segment 0/6 size 6 2022/01/08 07:09:15 DEBUG : only4: Starting multipart upload 2022/01/08 07:09:16 DEBUG : only4: Uploading segment 0/6 size 6 2022/01/08 07:09:16 DEBUG : both5: Starting multipart upload 2022/01/08 07:09:17 DEBUG : both5: Uploading segment 0/6 size 6 2022/01/08 07:09:18 DEBUG : only5: Starting multipart upload 2022/01/08 07:09:18 DEBUG : only5: Uploading segment 0/6 size 6 2022/01/08 07:09:19 DEBUG : both6: Starting multipart upload 2022/01/08 07:09:19 DEBUG : both6: Uploading segment 0/6 size 6 2022/01/08 07:09:20 DEBUG : only6: Starting multipart upload 2022/01/08 07:09:20 DEBUG : only6: Uploading segment 0/6 size 6 2022/01/08 07:09:21 DEBUG : both7: Starting multipart upload 2022/01/08 07:09:21 DEBUG : both7: Uploading segment 0/6 size 6 2022/01/08 07:09:22 DEBUG : only7: Starting multipart upload 2022/01/08 07:09:22 DEBUG : only7: Uploading segment 0/6 size 6 2022/01/08 07:09:23 DEBUG : both8: Starting multipart upload 2022/01/08 07:09:24 DEBUG : both8: Uploading segment 0/6 size 6 2022/01/08 07:09:24 DEBUG : only8: Starting multipart upload 2022/01/08 07:09:24 DEBUG : only8: Uploading segment 0/6 size 6 2022/01/08 07:09:25 DEBUG : both9: Starting multipart upload 2022/01/08 07:09:25 DEBUG : both9: Uploading segment 0/6 size 6 2022/01/08 07:09:26 DEBUG : only9: Starting multipart upload 2022/01/08 07:09:26 DEBUG : only9: Uploading segment 0/6 size 6 2022/01/08 07:09:27 DEBUG : both10: Starting multipart upload 2022/01/08 07:09:27 DEBUG : both10: Uploading segment 0/6 size 6 2022/01/08 07:09:28 DEBUG : only10: Starting multipart upload 2022/01/08 07:09:28 DEBUG : only10: Uploading segment 0/6 size 6 2022/01/08 07:09:29 DEBUG : both11: Starting multipart upload 2022/01/08 07:09:29 DEBUG : both11: Uploading segment 0/6 size 6 2022/01/08 07:09:30 DEBUG : only11: Starting multipart upload 2022/01/08 07:09:31 DEBUG : only11: Uploading segment 0/6 size 6 2022/01/08 07:09:31 DEBUG : both12: Starting multipart upload 2022/01/08 07:09:32 DEBUG : both12: Uploading segment 0/6 size 6 2022/01/08 07:09:32 DEBUG : only12: Starting multipart upload 2022/01/08 07:09:33 DEBUG : only12: Uploading segment 0/6 size 6 2022/01/08 07:09:33 DEBUG : both13: Starting multipart upload 2022/01/08 07:09:34 DEBUG : both13: Uploading segment 0/6 size 6 2022/01/08 07:09:34 DEBUG : only13: Starting multipart upload 2022/01/08 07:09:35 DEBUG : only13: Uploading segment 0/6 size 6 2022/01/08 07:09:35 DEBUG : both14: Starting multipart upload 2022/01/08 07:09:36 DEBUG : both14: Uploading segment 0/6 size 6 2022/01/08 07:09:36 DEBUG : Too many requests. Trying again in 5 seconds. 2022/01/08 07:09:36 DEBUG : pacer: low level retry 1/10 (error activityLimitReached: throttledRequest: The request has been throttled) 2022/01/08 07:09:36 DEBUG : pacer: Rate limited, increasing sleep to 5s 2022/01/08 07:09:36 DEBUG : Too many requests. Trying again in 7 seconds. 2022/01/08 07:09:36 DEBUG : pacer: low level retry 2/10 (error activityLimitReached: throttledRequest: The request has been throttled) 2022/01/08 07:09:36 DEBUG : pacer: Rate limited, increasing sleep to 7s 2022/01/08 07:09:41 DEBUG : Too many requests. Trying again in 34 seconds. 2022/01/08 07:09:41 DEBUG : pacer: low level retry 3/10 (error activityLimitReached: throttledRequest: The request has been throttled) 2022/01/08 07:09:41 DEBUG : pacer: Rate limited, increasing sleep to 34s 2022/01/08 07:09:48 DEBUG : pacer: Reducing sleep to 25.5s 2022/01/08 07:09:48 DEBUG : only14: Starting multipart upload 2022/01/08 07:10:22 DEBUG : pacer: Reducing sleep to 19.125s 2022/01/08 07:10:22 DEBUG : only14: Uploading segment 0/6 size 6 2022/01/08 07:10:48 DEBUG : pacer: Reducing sleep to 14.34375s 2022/01/08 07:10:48 DEBUG : both15: Starting multipart upload 2022/01/08 07:11:07 DEBUG : pacer: Reducing sleep to 10.7578125s 2022/01/08 07:11:07 DEBUG : both15: Uploading segment 0/6 size 6 2022/01/08 07:11:21 DEBUG : pacer: Reducing sleep to 8.068359375s 2022/01/08 07:11:21 DEBUG : only15: Starting multipart upload 2022/01/08 07:11:32 DEBUG : pacer: Reducing sleep to 6.051269531s 2022/01/08 07:11:32 DEBUG : only15: Uploading segment 0/6 size 6 2022/01/08 07:11:40 DEBUG : pacer: Reducing sleep to 4.538452148s 2022/01/08 07:11:40 DEBUG : both16: Starting multipart upload 2022/01/08 07:11:46 DEBUG : pacer: Reducing sleep to 3.403839111s 2022/01/08 07:11:46 DEBUG : both16: Uploading segment 0/6 size 6 2022/01/08 07:11:51 DEBUG : pacer: Reducing sleep to 2.552879333s 2022/01/08 07:11:51 DEBUG : only16: Starting multipart upload 2022/01/08 07:11:54 DEBUG : pacer: Reducing sleep to 1.914659499s 2022/01/08 07:11:54 DEBUG : only16: Uploading segment 0/6 size 6 2022/01/08 07:11:57 DEBUG : pacer: Reducing sleep to 1.435994624s 2022/01/08 07:11:57 DEBUG : both17: Starting multipart upload 2022/01/08 07:11:59 DEBUG : pacer: Reducing sleep to 1.076995968s 2022/01/08 07:11:59 DEBUG : both17: Uploading segment 0/6 size 6 2022/01/08 07:12:00 DEBUG : pacer: Reducing sleep to 807.746976ms 2022/01/08 07:12:00 DEBUG : only17: Starting multipart upload 2022/01/08 07:12:01 DEBUG : pacer: Reducing sleep to 605.810232ms 2022/01/08 07:12:01 DEBUG : only17: Uploading segment 0/6 size 6 2022/01/08 07:12:02 DEBUG : pacer: Reducing sleep to 454.357674ms 2022/01/08 07:12:02 DEBUG : both18: Starting multipart upload 2022/01/08 07:12:03 DEBUG : pacer: Reducing sleep to 340.768255ms 2022/01/08 07:12:03 DEBUG : both18: Uploading segment 0/6 size 6 2022/01/08 07:12:03 DEBUG : pacer: Reducing sleep to 255.576191ms 2022/01/08 07:12:03 DEBUG : only18: Starting multipart upload 2022/01/08 07:12:04 DEBUG : pacer: Reducing sleep to 191.682143ms 2022/01/08 07:12:04 DEBUG : only18: Uploading segment 0/6 size 6 2022/01/08 07:12:05 DEBUG : pacer: Reducing sleep to 143.761607ms 2022/01/08 07:12:05 DEBUG : both19: Starting multipart upload 2022/01/08 07:12:05 DEBUG : pacer: Reducing sleep to 107.821205ms 2022/01/08 07:12:05 DEBUG : both19: Uploading segment 0/6 size 6 2022/01/08 07:12:06 DEBUG : pacer: Reducing sleep to 80.865903ms 2022/01/08 07:12:06 DEBUG : only19: Starting multipart upload 2022/01/08 07:12:07 DEBUG : pacer: Reducing sleep to 60.649427ms 2022/01/08 07:12:07 DEBUG : only19: Uploading segment 0/6 size 6 2022/01/08 07:12:07 DEBUG : pacer: Reducing sleep to 45.48707ms 2022/01/08 07:12:08 DEBUG : pacer: Reducing sleep to 34.115302ms 2022/01/08 07:12:09 DEBUG : pacer: Reducing sleep to 25.586476ms 2022/01/08 07:12:09 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both0: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both10: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both11: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both1: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both14: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both15: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both16: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both17: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both12: Unchanged skipping 2022/01/08 07:12:09 DEBUG : One drive root 'rclone-test-zajerob2retovip0jagiyar4': Waiting for checks to finish 2022/01/08 07:12:09 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both19: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both18: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both3: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both4: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both5: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both6: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both7: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both8: Unchanged skipping 2022/01/08 07:12:09 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both9: Unchanged skipping 2022/01/08 07:12:09 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both2: Unchanged skipping 2022/01/08 07:12:09 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only0: Starting singlepart upload 2022/01/08 07:12:09 DEBUG : only1: Starting singlepart upload 2022/01/08 07:12:09 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/08 07:12:09 DEBUG : both13: Unchanged skipping 2022/01/08 07:12:09 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only11: Starting singlepart upload 2022/01/08 07:12:09 DEBUG : only12: Starting singlepart upload 2022/01/08 07:12:09 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/01/08 07:12:09 DEBUG : One drive root 'rclone-test-zajerob2retovip0jagiyar4': Waiting for transfers to finish 2022/01/08 07:12:10 DEBUG : pacer: Reducing sleep to 19.189857ms 2022/01/08 07:12:10 DEBUG : pacer: Reducing sleep to 14.392392ms 2022/01/08 07:12:10 DEBUG : pacer: Reducing sleep to 10.794294ms 2022/01/08 07:12:10 DEBUG : pacer: Reducing sleep to 10ms 2022/01/08 07:12:10 DEBUG : only0: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:10 DEBUG : only0: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:10 ERROR : only0: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:10 INFO : only0: Removing failed copy 2022/01/08 07:12:10 DEBUG : only12: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:10 DEBUG : only12: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:10 ERROR : only12: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:10 INFO : only12: Removing failed copy 2022/01/08 07:12:10 DEBUG : only11: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:10 DEBUG : only11: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:10 ERROR : only11: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:10 INFO : only11: Removing failed copy 2022/01/08 07:12:10 DEBUG : only1: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:10 DEBUG : only1: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:10 ERROR : only1: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:10 INFO : only1: Removing failed copy 2022/01/08 07:12:11 DEBUG : only13: Starting singlepart upload 2022/01/08 07:12:11 DEBUG : only14: Starting singlepart upload 2022/01/08 07:12:11 DEBUG : only16: Starting singlepart upload 2022/01/08 07:12:11 DEBUG : only17: Starting singlepart upload 2022/01/08 07:12:12 DEBUG : only13: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:12 DEBUG : only13: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:12 ERROR : only13: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:12 INFO : only13: Removing failed copy 2022/01/08 07:12:12 DEBUG : only16: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:12 DEBUG : only16: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:12 ERROR : only16: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:12 INFO : only16: Removing failed copy 2022/01/08 07:12:12 DEBUG : only14: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:12 DEBUG : only14: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:12 ERROR : only14: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:12 INFO : only14: Removing failed copy 2022/01/08 07:12:12 DEBUG : only18: Starting singlepart upload 2022/01/08 07:12:12 DEBUG : only19: Starting singlepart upload 2022/01/08 07:12:12 DEBUG : only17: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:12 DEBUG : only17: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:12 ERROR : only17: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:12 INFO : only17: Removing failed copy 2022/01/08 07:12:13 DEBUG : only2: Starting singlepart upload 2022/01/08 07:12:13 DEBUG : only18: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:13 DEBUG : only18: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:13 ERROR : only18: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:13 INFO : only18: Removing failed copy 2022/01/08 07:12:13 DEBUG : only3: Starting singlepart upload 2022/01/08 07:12:13 DEBUG : only4: Starting singlepart upload 2022/01/08 07:12:14 DEBUG : only19: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:14 DEBUG : only19: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:14 ERROR : only19: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:14 INFO : only19: Removing failed copy 2022/01/08 07:12:14 DEBUG : only2: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:14 DEBUG : only2: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:14 ERROR : only2: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:14 INFO : only2: Removing failed copy 2022/01/08 07:12:14 DEBUG : only5: Starting singlepart upload 2022/01/08 07:12:14 DEBUG : only6: Starting singlepart upload 2022/01/08 07:12:14 DEBUG : only3: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:14 DEBUG : only3: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:14 ERROR : only3: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:14 INFO : only3: Removing failed copy 2022/01/08 07:12:14 DEBUG : only4: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:14 DEBUG : only4: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:14 ERROR : only4: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:14 INFO : only4: Removing failed copy 2022/01/08 07:12:14 DEBUG : only10: Starting singlepart upload 2022/01/08 07:12:15 DEBUG : only6: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:15 DEBUG : only6: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:15 ERROR : only6: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:15 INFO : only6: Removing failed copy 2022/01/08 07:12:15 DEBUG : only15: Starting singlepart upload 2022/01/08 07:12:15 DEBUG : only7: Starting singlepart upload 2022/01/08 07:12:15 DEBUG : only5: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:15 DEBUG : only5: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:15 ERROR : only5: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:15 INFO : only5: Removing failed copy 2022/01/08 07:12:15 DEBUG : only10: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:15 DEBUG : only10: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:15 ERROR : only10: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:15 INFO : only10: Removing failed copy 2022/01/08 07:12:16 DEBUG : only8: Starting singlepart upload 2022/01/08 07:12:16 DEBUG : only9: Starting singlepart upload 2022/01/08 07:12:16 DEBUG : only7: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:16 DEBUG : only7: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:16 ERROR : only7: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:16 INFO : only7: Removing failed copy 2022/01/08 07:12:16 DEBUG : only15: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:16 DEBUG : only15: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:16 ERROR : only15: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:16 INFO : only15: Removing failed copy 2022/01/08 07:12:17 DEBUG : only9: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:17 DEBUG : only9: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:17 ERROR : only9: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:17 INFO : only9: Removing failed copy 2022/01/08 07:12:17 DEBUG : only8: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone118025437) 2022/01/08 07:12:17 DEBUG : only8: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-zajerob2retovip0jagiyar4') 2022/01/08 07:12:17 ERROR : only8: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/08 07:12:17 INFO : only8: Removing failed copy 2022/01/08 07:12:17 ERROR : One drive root 'rclone-test-zajerob2retovip0jagiyar4': not deleting files as there were IO errors 2022/01/08 07:12:17 ERROR : One drive root 'rclone-test-zajerob2retovip0jagiyar4': 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 "only7" fstest.go:204: Not found "only15" fstest.go:204: Not found "only1" fstest.go:204: Not found "only5" fstest.go:204: Not found "only4" fstest.go:204: Not found "only8" fstest.go:204: Not found "only17" fstest.go:204: Not found "only18" fstest.go:204: Not found "only9" fstest.go:204: Not found "only13" fstest.go:204: Not found "only16" fstest.go:204: Not found "only10" fstest.go:204: Not found "only0" fstest.go:204: Not found "only2" fstest.go:204: Not found "only12" fstest.go:204: Not found "only14" fstest.go:204: Not found "only19" fstest.go:204: Not found "only11" fstest.go:204: Not found "only6" 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 (206.57s) FAIL 2022/01/08 07:12:31 DEBUG : One drive root 'rclone-test-zajerob2retovip0jagiyar4': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDriveBusiness: -verbose -test.run '^TestSyncConcurrentTruncate$'" - Finished ERROR in 3m27.985037154s (try 3/5): exit status 1: Failed [TestSyncConcurrentTruncate]