"./sync.test -test.v -test.timeout 30m0s -remote TestB2: -verbose -fast-list -test.run '^TestSyncSuffixKeepExtension$'" - Starting (try 5/5)
=== RUN   TestSyncSuffixKeepExtension
2019/10/02 05:22:55 DEBUG : one: Sizes differ (src 4 vs dst 3)
2019/10/02 05:22:56 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2019/10/02 05:22:56 INFO  : one: Copied (server side copy)
2019/10/02 05:23:07 INFO  : one: Deleted
2019/10/02 05:23:08 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2019/10/02 05:23:08 INFO  : one: Copied (new)
2019/10/02 05:23:09 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2019/10/02 05:23:09 DEBUG : two: Unchanged skipping
2019/10/02 05:23:09 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2019/10/02 05:23:10 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK
2019/10/02 05:23:10 INFO  : three.txt: Copied (server side copy)
2019/10/02 05:23:12 INFO  : three.txt: Deleted
2019/10/02 05:23:12 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK
2019/10/02 05:23:12 INFO  : three.txt: Copied (new)
2019/10/02 05:23:13 DEBUG : one: Sizes differ (src 5 vs dst 4)
2019/10/02 05:23:13 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2019/10/02 05:23:13 INFO  : one: Copied (server side copy)
2019/10/02 05:23:14 INFO  : one: Deleted
2019/10/02 05:23:15 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK
2019/10/02 05:23:15 INFO  : one: Copied (new)
2019/10/02 05:23:16 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2019/10/02 05:23:16 DEBUG : two: Unchanged skipping
2019/10/02 05:23:16 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2019/10/02 05:23:16 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK
2019/10/02 05:23:16 INFO  : three.txt: Copied (server side copy)
2019/10/02 05:23:19 INFO  : three.txt: Deleted
2019/10/02 05:23:20 DEBUG : three.txt: SHA-1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK
2019/10/02 05:23:20 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffixKeepExtension (39.37s)
    run.go:176: Remote "B2 bucket rclone-test-luwacuf6coxoqen0laluvec7", Local "Local file system at /tmp/rclone346251193", Modify Window "1ms"
PASS
2019/10/02 05:23:30 DEBUG : B2 bucket rclone-test-luwacuf6coxoqen0laluvec7: Purge remote
2019/10/02 05:23:30 DEBUG : dst/one: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f10816437e10ebe76_d20191002_m052320_c001_v0001042_t0008")
2019/10/02 05:23:30 DEBUG : dst/one: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f105d280c3ffb93a3_d20191002_m052315_c001_v0001120_t0055")
2019/10/02 05:23:30 DEBUG : dst/one: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f111dcfd142973406_d20191002_m052313_c001_v0001113_t0036")
2019/10/02 05:23:30 DEBUG : dst/one: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f105d280c3ffb939e_d20191002_m052308_c001_v0001120_t0055")
2019/10/02 05:23:30 DEBUG : dst/one: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f102d0059f4a93000_d20191002_m052256_c001_v0001009_t0034")
2019/10/02 05:23:30 DEBUG : dst/one: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f1167a2e1bf52853f_d20191002_m052252_c001_v0001102_t0003")
2019/10/02 05:23:30 DEBUG : dst/one-2019-01-01: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f1196ceb617eec58b_d20191002_m052325_c001_v0001091_t0035")
2019/10/02 05:23:30 DEBUG : dst/one-2019-01-01: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f10531a35c2dd51a7_d20191002_m052313_c001_v0001102_t0051")
2019/10/02 05:23:30 DEBUG : dst/one-2019-01-01: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f10524391ed1f8d73_d20191002_m052255_c001_v0001128_t0022")
2019/10/02 05:23:30 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f10264a6d2ec284a1_d20191002_m052326_c001_v0001128_t0011")
2019/10/02 05:23:30 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f117782402b6e46be_d20191002_m052316_c001_v0001006_t0033")
2019/10/02 05:23:30 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f10553064124047db_d20191002_m052309_c001_v0001023_t0001")
2019/10/02 05:23:30 DEBUG : dst/three.txt: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f11839327917c32ae_d20191002_m052327_c001_v0001128_t0017")
2019/10/02 05:23:30 DEBUG : dst/three.txt: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f105d280c3ffb93a5_d20191002_m052320_c001_v0001120_t0055")
2019/10/02 05:23:30 DEBUG : dst/three.txt: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f111fedbfa0f0fc5f_d20191002_m052316_c001_v0001029_t0019")
2019/10/02 05:23:30 DEBUG : dst/three.txt: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f105d280c3ffb939f_d20191002_m052312_c001_v0001120_t0055")
2019/10/02 05:23:31 DEBUG : dst/three.txt: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f111f2a6fd0fd25e6_d20191002_m052310_c001_v0001004_t0010")
2019/10/02 05:23:31 DEBUG : dst/three.txt: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f1167a2e1bf528541_d20191002_m052253_c001_v0001102_t0003")
2019/10/02 05:23:31 DEBUG : dst/two: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f106aa9ef09baf4e3_d20191002_m052329_c001_v0001128_t0028")
2019/10/02 05:23:31 DEBUG : dst/two: Deleting (id "4_zcfc2cd83e0bd8bab6fd80e1b_f1167a2e1bf528540_d20191002_m052253_c001_v0001102_t0003")
"./sync.test -test.v -test.timeout 30m0s -remote TestB2: -verbose -fast-list -test.run '^TestSyncSuffixKeepExtension$'" - Finished OK in 42.171648734s (try 5/5)