"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDriveBusiness: -verbose -test.run '^TestSyncConcurrentTruncate$'" - Starting (try 4/5) 2022/01/13 05:41:10 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-wanavoc7zuvugog8fupojoj7" 2022/01/13 05:41:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/01/13 05:41:11 DEBUG : Creating backend with remote "/tmp/rclone378356748" === RUN TestSyncConcurrentTruncate run.go:181: Remote "One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7'", Local "Local file system at /tmp/rclone378356748", Modify Window "1s" 2022/01/13 05:41:12 DEBUG : both0: Starting multipart upload 2022/01/13 05:41:13 DEBUG : both0: Uploading segment 0/6 size 6 2022/01/13 05:41:14 DEBUG : only0: Starting multipart upload 2022/01/13 05:41:14 DEBUG : only0: Uploading segment 0/6 size 6 2022/01/13 05:41:15 DEBUG : both1: Starting multipart upload 2022/01/13 05:41:15 DEBUG : both1: Uploading segment 0/6 size 6 2022/01/13 05:41:16 DEBUG : only1: Starting multipart upload 2022/01/13 05:41:17 DEBUG : only1: Uploading segment 0/6 size 6 2022/01/13 05:41:17 DEBUG : both2: Starting multipart upload 2022/01/13 05:41:18 DEBUG : both2: Uploading segment 0/6 size 6 2022/01/13 05:41:18 DEBUG : only2: Starting multipart upload 2022/01/13 05:41:19 DEBUG : only2: Uploading segment 0/6 size 6 2022/01/13 05:41:19 DEBUG : both3: Starting multipart upload 2022/01/13 05:41:20 DEBUG : both3: Uploading segment 0/6 size 6 2022/01/13 05:41:21 DEBUG : only3: Starting multipart upload 2022/01/13 05:41:21 DEBUG : only3: Uploading segment 0/6 size 6 2022/01/13 05:41:22 DEBUG : both4: Starting multipart upload 2022/01/13 05:41:22 DEBUG : both4: Uploading segment 0/6 size 6 2022/01/13 05:41:23 DEBUG : only4: Starting multipart upload 2022/01/13 05:41:23 DEBUG : only4: Uploading segment 0/6 size 6 2022/01/13 05:41:24 DEBUG : both5: Starting multipart upload 2022/01/13 05:41:25 DEBUG : both5: Uploading segment 0/6 size 6 2022/01/13 05:41:25 DEBUG : only5: Starting multipart upload 2022/01/13 05:41:26 DEBUG : only5: Uploading segment 0/6 size 6 2022/01/13 05:41:26 DEBUG : both6: Starting multipart upload 2022/01/13 05:41:27 DEBUG : both6: Uploading segment 0/6 size 6 2022/01/13 05:41:28 DEBUG : only6: Starting multipart upload 2022/01/13 05:41:28 DEBUG : only6: Uploading segment 0/6 size 6 2022/01/13 05:41:29 DEBUG : both7: Starting multipart upload 2022/01/13 05:41:29 DEBUG : both7: Uploading segment 0/6 size 6 2022/01/13 05:41:30 DEBUG : only7: Starting multipart upload 2022/01/13 05:41:31 DEBUG : only7: Uploading segment 0/6 size 6 2022/01/13 05:41:31 DEBUG : both8: Starting multipart upload 2022/01/13 05:41:32 DEBUG : both8: Uploading segment 0/6 size 6 2022/01/13 05:41:32 DEBUG : only8: Starting multipart upload 2022/01/13 05:41:33 DEBUG : only8: Uploading segment 0/6 size 6 2022/01/13 05:41:33 DEBUG : both9: Starting multipart upload 2022/01/13 05:41:34 DEBUG : both9: Uploading segment 0/6 size 6 2022/01/13 05:41:34 DEBUG : only9: Starting multipart upload 2022/01/13 05:41:37 DEBUG : only9: Uploading segment 0/6 size 6 2022/01/13 05:41:38 DEBUG : both10: Starting multipart upload 2022/01/13 05:41:39 DEBUG : both10: Uploading segment 0/6 size 6 2022/01/13 05:41:39 DEBUG : only10: Starting multipart upload 2022/01/13 05:41:40 DEBUG : only10: Uploading segment 0/6 size 6 2022/01/13 05:41:40 DEBUG : both11: Starting multipart upload 2022/01/13 05:41:41 DEBUG : both11: Uploading segment 0/6 size 6 2022/01/13 05:41:41 DEBUG : only11: Starting multipart upload 2022/01/13 05:41:42 DEBUG : only11: Uploading segment 0/6 size 6 2022/01/13 05:41:42 DEBUG : both12: Starting multipart upload 2022/01/13 05:41:43 DEBUG : both12: Uploading segment 0/6 size 6 2022/01/13 05:41:43 DEBUG : only12: Starting multipart upload 2022/01/13 05:41:44 DEBUG : only12: Uploading segment 0/6 size 6 2022/01/13 05:41:44 DEBUG : both13: Starting multipart upload 2022/01/13 05:41:45 DEBUG : both13: Uploading segment 0/6 size 6 2022/01/13 05:41:45 DEBUG : only13: Starting multipart upload 2022/01/13 05:41:46 DEBUG : only13: Uploading segment 0/6 size 6 2022/01/13 05:41:46 DEBUG : both14: Starting multipart upload 2022/01/13 05:41:47 DEBUG : both14: Uploading segment 0/6 size 6 2022/01/13 05:41:47 DEBUG : only14: Starting multipart upload 2022/01/13 05:41:48 DEBUG : only14: Uploading segment 0/6 size 6 2022/01/13 05:41:48 DEBUG : both15: Starting multipart upload 2022/01/13 05:41:49 DEBUG : both15: Uploading segment 0/6 size 6 2022/01/13 05:41:49 DEBUG : only15: Starting multipart upload 2022/01/13 05:41:50 DEBUG : only15: Uploading segment 0/6 size 6 2022/01/13 05:41:50 DEBUG : both16: Starting multipart upload 2022/01/13 05:41:51 DEBUG : both16: Uploading segment 0/6 size 6 2022/01/13 05:41:51 DEBUG : only16: Starting multipart upload 2022/01/13 05:41:52 DEBUG : only16: Uploading segment 0/6 size 6 2022/01/13 05:41:53 DEBUG : both17: Starting multipart upload 2022/01/13 05:41:53 DEBUG : both17: Uploading segment 0/6 size 6 2022/01/13 05:41:54 DEBUG : only17: Starting multipart upload 2022/01/13 05:41:54 DEBUG : only17: Uploading segment 0/6 size 6 2022/01/13 05:41:55 DEBUG : both18: Starting multipart upload 2022/01/13 05:41:55 DEBUG : both18: Uploading segment 0/6 size 6 2022/01/13 05:41:56 DEBUG : only18: Starting multipart upload 2022/01/13 05:41:56 DEBUG : only18: Uploading segment 0/6 size 6 2022/01/13 05:41:57 DEBUG : both19: Starting multipart upload 2022/01/13 05:41:58 DEBUG : both19: Uploading segment 0/6 size 6 2022/01/13 05:41:58 DEBUG : only19: Starting multipart upload 2022/01/13 05:41:59 DEBUG : only19: Uploading segment 0/6 size 6 2022/01/13 05:42:00 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both1: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both0: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both10: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both11: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both12: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both13: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both14: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both15: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both16: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both17: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both18: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both19: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both2: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both4: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both3: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both5: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both6: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both7: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/13 05:42:00 DEBUG : both9: Unchanged skipping 2022/01/13 05:42:00 DEBUG : both8: Unchanged skipping 2022/01/13 05:42:00 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only10: Starting singlepart upload 2022/01/13 05:42:00 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only13: Starting singlepart upload 2022/01/13 05:42:00 DEBUG : One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7': Waiting for checks to finish 2022/01/13 05:42:00 DEBUG : only1: Starting singlepart upload 2022/01/13 05:42:00 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only0: Starting singlepart upload 2022/01/13 05:42:00 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/01/13 05:42:00 DEBUG : One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7': Waiting for transfers to finish 2022/01/13 05:42:01 DEBUG : only13: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:42:01 DEBUG : only13: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:42:01 ERROR : only13: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:42:01 INFO : only13: Removing failed copy 2022/01/13 05:42:01 DEBUG : only10: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:42:01 DEBUG : only10: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:42:01 ERROR : only10: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:42:01 INFO : only10: Removing failed copy 2022/01/13 05:42:01 DEBUG : only0: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:42:01 DEBUG : only0: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:42:01 ERROR : only0: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:42:01 INFO : only0: Removing failed copy 2022/01/13 05:42:01 DEBUG : only1: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:42:01 DEBUG : only1: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:42:01 ERROR : only1: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:42:01 INFO : only1: Removing failed copy 2022/01/13 05:42:01 DEBUG : only14: Starting singlepart upload 2022/01/13 05:42:01 DEBUG : only15: Starting singlepart upload 2022/01/13 05:42:01 DEBUG : only12: Starting singlepart upload 2022/01/13 05:42:01 DEBUG : only18: Starting singlepart upload 2022/01/13 05:42:02 DEBUG : only14: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:42:02 DEBUG : only14: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:42:02 ERROR : only14: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:42:02 INFO : only14: Removing failed copy 2022/01/13 05:42:02 DEBUG : only15: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:42:02 DEBUG : only15: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:42:02 ERROR : only15: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:42:02 INFO : only15: Removing failed copy 2022/01/13 05:42:02 DEBUG : only12: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:42:02 DEBUG : only12: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:42:02 ERROR : only12: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:42:02 INFO : only12: Removing failed copy 2022/01/13 05:42:02 DEBUG : only18: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:42:02 DEBUG : only18: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:42:02 ERROR : only18: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:42:02 INFO : only18: Removing failed copy 2022/01/13 05:42:02 DEBUG : only19: Starting singlepart upload 2022/01/13 05:42:02 DEBUG : only2: Starting singlepart upload 2022/01/13 05:42:02 DEBUG : only3: Starting singlepart upload 2022/01/13 05:42:02 DEBUG : only4: Starting singlepart upload 2022/01/13 05:42:03 DEBUG : only2: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:42:03 DEBUG : only2: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:42:03 ERROR : only2: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:42:03 INFO : only2: Removing failed copy 2022/01/13 05:42:03 DEBUG : only19: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:42:03 DEBUG : only19: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:42:03 ERROR : only19: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:42:03 INFO : only19: Removing failed copy 2022/01/13 05:42:03 DEBUG : only3: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:42:03 DEBUG : only3: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:42:03 ERROR : only3: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:42:03 INFO : only3: Removing failed copy 2022/01/13 05:42:03 DEBUG : only4: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:42:03 DEBUG : only4: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:42:03 ERROR : only4: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:42:03 INFO : only4: Removing failed copy 2022/01/13 05:42:03 DEBUG : Too many requests. Trying again in 8 seconds. 2022/01/13 05:42:03 DEBUG : pacer: low level retry 1/10 (error activityLimitReached: throttledRequest: The request has been throttled) 2022/01/13 05:42:03 DEBUG : pacer: Rate limited, increasing sleep to 8s 2022/01/13 05:42:03 DEBUG : pacer: Reducing sleep to 6s 2022/01/13 05:42:03 DEBUG : only5: Starting singlepart upload 2022/01/13 05:42:03 DEBUG : pacer: Reducing sleep to 4.5s 2022/01/13 05:42:03 DEBUG : only6: Starting singlepart upload 2022/01/13 05:42:03 DEBUG : pacer: Reducing sleep to 3.375s 2022/01/13 05:42:03 DEBUG : only7: Starting singlepart upload 2022/01/13 05:42:04 DEBUG : pacer: Reducing sleep to 2.53125s 2022/01/13 05:42:04 DEBUG : only8: Starting singlepart upload 2022/01/13 05:42:11 DEBUG : pacer: Reducing sleep to 1.8984375s 2022/01/13 05:42:14 DEBUG : Too many requests. Trying again in 64 seconds. 2022/01/13 05:42:14 DEBUG : pacer: low level retry 1/10 (error activityLimitReached: throttledRequest: The request has been throttled) 2022/01/13 05:42:14 DEBUG : pacer: Rate limited, increasing sleep to 1m4s 2022/01/13 05:42:16 DEBUG : pacer: Reducing sleep to 48s 2022/01/13 05:43:20 DEBUG : pacer: Reducing sleep to 36s 2022/01/13 05:44:08 DEBUG : pacer: Reducing sleep to 27s 2022/01/13 05:44:08 DEBUG : only5: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:44:08 DEBUG : only5: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:44:08 ERROR : only5: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:44:08 INFO : only5: Removing failed copy 2022/01/13 05:44:44 DEBUG : pacer: Reducing sleep to 20.25s 2022/01/13 05:45:11 DEBUG : pacer: Reducing sleep to 15.1875s 2022/01/13 05:45:11 DEBUG : only7: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:45:11 DEBUG : only7: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:45:11 ERROR : only7: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:45:11 INFO : only7: Removing failed copy 2022/01/13 05:45:31 DEBUG : pacer: Reducing sleep to 11.390625s 2022/01/13 05:45:31 DEBUG : only8: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:45:31 DEBUG : only8: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:45:31 ERROR : only8: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:45:31 INFO : only8: Removing failed copy 2022/01/13 05:45:47 DEBUG : pacer: Reducing sleep to 8.54296875s 2022/01/13 05:45:47 DEBUG : only9: Starting singlepart upload 2022/01/13 05:45:58 DEBUG : pacer: Reducing sleep to 6.407226562s 2022/01/13 05:45:58 DEBUG : only6: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:45:58 DEBUG : only6: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:45:58 ERROR : only6: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:45:58 INFO : only6: Removing failed copy 2022/01/13 05:46:06 DEBUG : pacer: Reducing sleep to 4.805419921s 2022/01/13 05:46:06 DEBUG : only17: Starting singlepart upload 2022/01/13 05:46:13 DEBUG : pacer: Reducing sleep to 3.60406494s 2022/01/13 05:46:13 DEBUG : only11: Starting singlepart upload 2022/01/13 05:46:17 DEBUG : pacer: Reducing sleep to 2.703048705s 2022/01/13 05:46:21 DEBUG : pacer: Reducing sleep to 2.027286528s 2022/01/13 05:46:21 DEBUG : only16: Starting singlepart upload 2022/01/13 05:46:24 DEBUG : pacer: Reducing sleep to 1.520464896s 2022/01/13 05:46:26 DEBUG : pacer: Reducing sleep to 1.140348672s 2022/01/13 05:46:28 DEBUG : pacer: Reducing sleep to 855.261504ms 2022/01/13 05:46:28 DEBUG : only9: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:46:28 DEBUG : only9: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:46:28 ERROR : only9: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:46:28 INFO : only9: Removing failed copy 2022/01/13 05:46:29 DEBUG : pacer: Reducing sleep to 641.446128ms 2022/01/13 05:46:30 DEBUG : pacer: Reducing sleep to 481.084596ms 2022/01/13 05:46:30 DEBUG : only17: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:46:30 DEBUG : only17: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:46:30 ERROR : only17: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:46:30 INFO : only17: Removing failed copy 2022/01/13 05:46:30 DEBUG : pacer: Reducing sleep to 360.813447ms 2022/01/13 05:46:30 DEBUG : only11: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:46:30 DEBUG : only11: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:46:30 ERROR : only11: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:46:30 INFO : only11: Removing failed copy 2022/01/13 05:46:31 DEBUG : pacer: Reducing sleep to 270.610085ms 2022/01/13 05:46:31 DEBUG : pacer: Reducing sleep to 202.957563ms 2022/01/13 05:46:31 DEBUG : only16: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone378356748) 2022/01/13 05:46:31 DEBUG : only16: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7') 2022/01/13 05:46:31 ERROR : only16: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/13 05:46:31 INFO : only16: Removing failed copy 2022/01/13 05:46:31 DEBUG : pacer: Reducing sleep to 152.218172ms 2022/01/13 05:46:32 DEBUG : pacer: Reducing sleep to 114.163629ms 2022/01/13 05:46:32 DEBUG : pacer: Reducing sleep to 85.622721ms 2022/01/13 05:46:32 ERROR : One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7': not deleting files as there were IO errors 2022/01/13 05:46:32 ERROR : One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7': 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 2022/01/13 05:46:32 DEBUG : pacer: Reducing sleep to 64.21704ms fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache 2022/01/13 05:46:33 DEBUG : pacer: Reducing sleep to 48.16278ms 2022/01/13 05:46:34 DEBUG : pacer: Reducing sleep to 36.122085ms fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:302: Flushing the directory cache 2022/01/13 05:46:37 DEBUG : pacer: Reducing sleep to 27.091563ms 2022/01/13 05:46:37 DEBUG : pacer: Reducing sleep to 20.318672ms 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 "only5" fstest.go:204: Not found "only10" fstest.go:204: Not found "only18" fstest.go:204: Not found "only8" fstest.go:204: Not found "only14" fstest.go:204: Not found "only3" fstest.go:204: Not found "only6" fstest.go:204: Not found "only2" fstest.go:204: Not found "only4" fstest.go:204: Not found "only12" fstest.go:204: Not found "only15" fstest.go:204: Not found "only1" fstest.go:204: Not found "only13" fstest.go:204: Not found "only16" fstest.go:204: Not found "only17" fstest.go:204: Not found "only0" fstest.go:204: Not found "only7" fstest.go:204: Not found "only11" fstest.go:204: Not found "only9" fstest.go:204: Not found "only19" 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 2022/01/13 05:46:42 DEBUG : pacer: Reducing sleep to 15.239004ms 2022/01/13 05:46:42 DEBUG : pacer: Reducing sleep to 11.429253ms 2022/01/13 05:46:43 DEBUG : pacer: Reducing sleep to 10ms --- FAIL: TestSyncConcurrentTruncate (335.10s) FAIL 2022/01/13 05:46:46 DEBUG : One drive root 'rclone-test-wanavoc7zuvugog8fupojoj7': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDriveBusiness: -verbose -test.run '^TestSyncConcurrentTruncate$'" - Finished ERROR in 5m36.767802664s (try 4/5): exit status 1: Failed [TestSyncConcurrentTruncate]