"./sync.test -test.v -test.timeout 1h0m0s -remote TestHdfs: -verbose -test.run '^(TestSyncAfterChangingModtimeOnly|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncConcurrentTruncate|TestSyncIgnoreErrors)$'" - Starting (try 3/5)
2022/06/12 05:03:47 DEBUG : Creating backend with remote "TestHdfs:rclone-test-busehud9gafuwop6rejipin7"
2022/06/12 05:03:47 DEBUG : Setting type="hdfs" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_TYPE
2022/06/12 05:03:47 DEBUG : Setting namenode="127.0.0.1:8020" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_NAMENODE
2022/06/12 05:03:47 DEBUG : Setting username="root" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_USERNAME
2022/06/12 05:03:47 DEBUG : TestHdfs: detected overridden config - adding "{cNNQW}" suffix to name
2022/06/12 05:03:47 DEBUG : Setting namenode="127.0.0.1:8020" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_NAMENODE
2022/06/12 05:03:47 DEBUG : Setting username="root" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_USERNAME
2022/06/12 05:03:47 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2022/06/12 05:03:47 DEBUG : Creating backend with remote "/tmp/rclone3463721517"
=== RUN   TestSyncIgnoreErrors
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone3463721517", Modify Window "1s"
2022/06/12 05:03:47 DEBUG : hdfs://127.0.0.1:8020: mkdir [/rclone-test-busehud9gafuwop6rejipin7]
2022/06/12 05:03:47 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-busehud9gafuwop6rejipin7/b/potato]
    run.go:287: Failed to put "b/potato" to "hdfs://127.0.0.1:8020": create /rclone-test-busehud9gafuwop6rejipin7/b/potato: replication in progress
2022/06/12 05:03:47 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
2022/06/12 05:03:47 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7/b]
2022/06/12 05:03:47 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-busehud9gafuwop6rejipin7/b]
2022/06/12 05:03:47 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
--- FAIL: TestSyncIgnoreErrors (0.25s)
=== RUN   TestSyncAfterChangingModtimeOnly
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone3463721517", Modify Window "1s"
2022/06/12 05:03:47 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-busehud9gafuwop6rejipin7/empty space]
2022/06/12 05:03:47 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
2022/06/12 05:03:47 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
2022/06/12 05:03:47 DEBUG : hdfs://127.0.0.1:8020: Waiting for checks to finish
2022/06/12 05:03:47 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC
2022/06/12 05:03:47 DEBUG : hdfs://127.0.0.1:8020: Waiting for transfers to finish
2022/06/12 05:03:47 NOTICE: empty space: Skipped copy as --dry-run is set (size 1)
2022/06/12 05:03:47 DEBUG : Waiting for deletions to finish
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
2022/06/12 05:03:48 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: Waiting for checks to finish
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: Waiting for transfers to finish
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-busehud9gafuwop6rejipin7/empty space]
2022/06/12 05:03:48 INFO  : empty space: Copied (replaced existing)
2022/06/12 05:03:48 DEBUG : Waiting for deletions to finish
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-busehud9gafuwop6rejipin7/empty space]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
--- PASS: TestSyncAfterChangingModtimeOnly (0.07s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFile
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone3463721517", Modify Window "1s"
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-busehud9gafuwop6rejipin7/potato]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-busehud9gafuwop6rejipin7/empty space]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-busehud9gafuwop6rejipin7/potato2]
2022/06/12 05:03:48 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2022/06/12 05:03:48 DEBUG : empty space: Unchanged skipping
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: Waiting for checks to finish
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: Waiting for transfers to finish
2022/06/12 05:03:48 INFO  : potato2: Copied (new)
2022/06/12 05:03:48 DEBUG : Waiting for deletions to finish
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-busehud9gafuwop6rejipin7/potato]
2022/06/12 05:03:48 INFO  : potato: Deleted
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-busehud9gafuwop6rejipin7/empty space]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-busehud9gafuwop6rejipin7/potato2]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
--- PASS: TestSyncAfterRemovingAFileAndAddingAFile (0.07s)
=== RUN   TestSyncConcurrentTruncate
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone3463721517", Modify Window "1s"
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-busehud9gafuwop6rejipin7/both0]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-busehud9gafuwop6rejipin7/only0]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-busehud9gafuwop6rejipin7/both1]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-busehud9gafuwop6rejipin7/only1]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-busehud9gafuwop6rejipin7/both2]
    run.go:287: Failed to put "both2" to "hdfs://127.0.0.1:8020": create /rclone-test-busehud9gafuwop6rejipin7/both2: replication in progress
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-busehud9gafuwop6rejipin7/both0]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-busehud9gafuwop6rejipin7/both1]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-busehud9gafuwop6rejipin7/only0]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-busehud9gafuwop6rejipin7/only1]
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-busehud9gafuwop6rejipin7]
--- FAIL: TestSyncConcurrentTruncate (0.09s)
FAIL
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: Purge remote
2022/06/12 05:03:48 DEBUG : hdfs://127.0.0.1:8020: purge [/rclone-test-busehud9gafuwop6rejipin7]
"./sync.test -test.v -test.timeout 1h0m0s -remote TestHdfs: -verbose -test.run '^(TestSyncAfterChangingModtimeOnly|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncConcurrentTruncate|TestSyncIgnoreErrors)$'" - Finished ERROR in 671.64823ms (try 3/5): exit status 1: Failed [TestSyncIgnoreErrors TestSyncConcurrentTruncate]