"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -verbose -fast-list -test.run '^(TestSyncAfterChangingContentsOnly|TestSyncDoesntUpdateModtime)$'" - Starting (try 5/5)
=== RUN   TestSyncDoesntUpdateModtime
2020/07/24 10:02:12 DEBUG : B2 bucket rclone-test-qawisol5judijug5tuseyap6: Waiting for checks to finish
2020/07/24 10:02:12 DEBUG : foo: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC
2020/07/24 10:02:12 DEBUG : foo: SHA-1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone138708605)
2020/07/24 10:02:12 DEBUG : foo: SHA-1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-qawisol5judijug5tuseyap6)
2020/07/24 10:02:12 DEBUG : foo: SHA-1 differ
2020/07/24 10:02:12 DEBUG : B2 bucket rclone-test-qawisol5judijug5tuseyap6: Waiting for transfers to finish
2020/07/24 10:02:13 DEBUG : foo: SHA-1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK
2020/07/24 10:02:13 INFO  : foo: Copied (replaced existing)
2020/07/24 10:02:13 DEBUG : Waiting for deletions to finish
--- FAIL: TestSyncDoesntUpdateModtime (10.77s)
    run.go:176: Remote "B2 bucket rclone-test-qawisol5judijug5tuseyap6", Local "Local file system at /tmp/rclone138708605", Modify Window "1ms"
    fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:302: 
        	Error Trace:	fstest.go:302
        	            				fstest.go:335
        	            				run.go:169
        	            				run.go:339
        	            				sync_test.go:621
        	Error:      	Should be true
        	Test:       	TestSyncDoesntUpdateModtime
        	Messages:   	listing wrong, want
        	            	   got
        	            	  foo (3)
    fstest.go:188: 
        	Error Trace:	fstest.go:188
        	            				fstest.go:305
        	            				fstest.go:335
        	            				run.go:169
        	            				run.go:339
        	            				sync_test.go:621
        	Error:      	Should be true
        	Test:       	TestSyncDoesntUpdateModtime
        	Messages:   	Unexpected file "foo"
=== RUN   TestSyncAfterChangingContentsOnly
2020/07/24 10:02:22 DEBUG : pacer: low level retry 1/10 (error Post https://api001.backblazeb2.com/b2api/v1/b2_list_file_names: EOF)
2020/07/24 10:02:22 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2020/07/24 10:02:23 DEBUG : pacer: Reducing sleep to 10ms
2020/07/24 10:02:30 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC
2020/07/24 10:02:30 DEBUG : B2 bucket rclone-test-qawisol5judijug5tuseyap6: Waiting for checks to finish
2020/07/24 10:02:30 DEBUG : potato: SHA-1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone138708605)
2020/07/24 10:02:30 DEBUG : potato: SHA-1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-qawisol5judijug5tuseyap6)
2020/07/24 10:02:30 DEBUG : potato: SHA-1 differ
2020/07/24 10:02:30 DEBUG : B2 bucket rclone-test-qawisol5judijug5tuseyap6: Waiting for transfers to finish
2020/07/24 10:02:31 DEBUG : potato: SHA-1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK
2020/07/24 10:02:31 INFO  : potato: Copied (replaced existing)
2020/07/24 10:02:31 DEBUG : Waiting for deletions to finish
2020/07/24 10:02:31 INFO  : foo: Deleted
--- FAIL: TestSyncAfterChangingContentsOnly (10.88s)
    run.go:176: Remote "B2 bucket rclone-test-qawisol5judijug5tuseyap6", Local "Local file system at /tmp/rclone138708605", Modify Window "1ms"
    fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:302: 
        	Error Trace:	fstest.go:302
        	            				fstest.go:335
        	            				fstest.go:347
        	            				sync_test.go:667
        	Error:      	Should be true
        	Test:       	TestSyncAfterChangingContentsOnly
        	Messages:   	listing wrong, want
        	            	  potato (21) got
        	            	  foo (3), potato (21)
    fstest.go:188: 
        	Error Trace:	fstest.go:188
        	            				fstest.go:305
        	            				fstest.go:335
        	            				fstest.go:347
        	            				sync_test.go:667
        	Error:      	Should be true
        	Test:       	TestSyncAfterChangingContentsOnly
        	Messages:   	Unexpected file "foo"
FAIL
2020/07/24 10:02:32 DEBUG : B2 bucket rclone-test-qawisol5judijug5tuseyap6: Purge remote
2020/07/24 10:02:32 DEBUG : foo: Deleting (id "4_zafb2ed53405d8b0b7f380e1b_f1111deaa698441be_d20200724_m100231_c001_v0001033_t0030")
2020/07/24 10:02:32 DEBUG : foo: Deleting (id "4_zafb2ed53405d8b0b7f380e1b_f11628056d206b1ba_d20200724_m100213_c001_v0001137_t0016")
2020/07/24 10:02:32 DEBUG : foo: Deleting (id "4_zafb2ed53405d8b0b7f380e1b_f105c8d69dbc79c2a_d20200724_m100213_c001_v0001137_t0032")
2020/07/24 10:02:32 DEBUG : foo: Deleting (id "4_zafb2ed53405d8b0b7f380e1b_f11628056d206b1ad_d20200724_m100212_c001_v0001137_t0016")
2020/07/24 10:02:32 DEBUG : potato: Deleting (id "4_zafb2ed53405d8b0b7f380e1b_f118896981311ff06_d20200724_m100232_c001_v0001093_t0021")
2020/07/24 10:02:32 DEBUG : potato: Deleting (id "4_zafb2ed53405d8b0b7f380e1b_f11628056d206b2bb_d20200724_m100231_c001_v0001137_t0016")
2020/07/24 10:02:32 DEBUG : potato: Deleting (id "4_zafb2ed53405d8b0b7f380e1b_f11628056d206b246_d20200724_m100221_c001_v0001137_t0016")
"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -verbose -fast-list -test.run '^(TestSyncAfterChangingContentsOnly|TestSyncDoesntUpdateModtime)$'" - Finished ERROR in 23.581695763s (try 5/5): exit status 1: Failed [TestSyncDoesntUpdateModtime TestSyncAfterChangingContentsOnly]