"./sync.test -test.v -test.timeout 1h0m0s -remote TestHdfs: -verbose -test.run '^(TestCopy|TestSyncBackupDirSuffixOnly|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncIgnoreErrors|TestSyncSuffixKeepExtension)$'" - Starting (try 3/5)
2022/04/15 06:14:15 DEBUG : Creating backend with remote "TestHdfs:rclone-test-cuzohih8hisiten8xigosuc0"
2022/04/15 06:14:15 DEBUG : Setting type="hdfs" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_TYPE
2022/04/15 06:14:15 DEBUG : Setting namenode="127.0.0.1:8020" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_NAMENODE
2022/04/15 06:14:15 DEBUG : Setting username="root" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_USERNAME
2022/04/15 06:14:15 DEBUG : TestHdfs: detected overridden config - adding "{cNNQW}" suffix to name
2022/04/15 06:14:15 DEBUG : Setting namenode="127.0.0.1:8020" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_NAMENODE
2022/04/15 06:14:15 DEBUG : Setting username="root" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_USERNAME
2022/04/15 06:14:15 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2022/04/15 06:14:15 DEBUG : Creating backend with remote "/tmp/rclone375557270"
=== RUN   TestCopy
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone375557270", Modify Window "1s"
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: mkdir [/rclone-test-cuzohih8hisiten8xigosuc0]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/sub dir/hello world]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: Waiting for checks to finish
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: Waiting for transfers to finish
2022/04/15 06:14:15 ERROR : sub dir/hello world: Failed to copy: create /rclone-test-cuzohih8hisiten8xigosuc0/sub dir/hello world: replication in progress
    sync_test.go:65: 
        	Error Trace:	sync_test.go:65
        	Error:      	Received unexpected error:
        	            	create /rclone-test-cuzohih8hisiten8xigosuc0/sub dir/hello world: replication in progress
        	Test:       	TestCopy
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0/sub dir]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-cuzohih8hisiten8xigosuc0/sub dir]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
--- FAIL: TestCopy (0.19s)
=== RUN   TestSyncIgnoreErrors
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone375557270", Modify Window "1s"
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/b/potato]
    run.go:287: Failed to put "b/potato" to "hdfs://127.0.0.1:8020": create /rclone-test-cuzohih8hisiten8xigosuc0/b/potato: replication in progress
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0/b]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-cuzohih8hisiten8xigosuc0/b]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
--- FAIL: TestSyncIgnoreErrors (0.03s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone375557270", Modify Window "1s"
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one]
    run.go:287: Failed to put "dst/one" to "hdfs://127.0.0.1:8020": create /rclone-test-cuzohih8hisiten8xigosuc0/dst/one: replication in progress
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0/dst]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-cuzohih8hisiten8xigosuc0/dst]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
--- FAIL: TestSyncBackupDirSuffixOnly (0.03s)
=== RUN   TestSyncSuffixKeepExtension
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone375557270", Modify Window "1s"
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/dst/two]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/dst/three.txt]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0/dst]
2022/04/15 06:14:15 DEBUG : Creating backend with remote "TestHdfs:rclone-test-cuzohih8hisiten8xigosuc0/dst"
2022/04/15 06:14:15 DEBUG : Setting type="hdfs" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_TYPE
2022/04/15 06:14:15 DEBUG : Setting namenode="127.0.0.1:8020" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_NAMENODE
2022/04/15 06:14:15 DEBUG : Setting username="root" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_USERNAME
2022/04/15 06:14:15 DEBUG : TestHdfs: detected overridden config - adding "{cNNQW}" suffix to name
2022/04/15 06:14:15 DEBUG : Setting namenode="127.0.0.1:8020" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_NAMENODE
2022/04/15 06:14:15 DEBUG : Setting username="root" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_USERNAME
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one]
2022/04/15 06:14:15 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one-2019-01-01]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: rename [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one] to [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one-2019-01-01]
2022/04/15 06:14:15 INFO  : one: Moved (server-side) to: one-2019-01-01
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one]
2022/04/15 06:14:15 INFO  : one: Copied (new)
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-cuzohih8hisiten8xigosuc0/dst/two]
2022/04/15 06:14:15 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/04/15 06:14:15 DEBUG : two: Unchanged skipping
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-cuzohih8hisiten8xigosuc0/dst/three.txt]
2022/04/15 06:14:15 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-cuzohih8hisiten8xigosuc0/dst/three-2019-01-01.txt]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: rename [/rclone-test-cuzohih8hisiten8xigosuc0/dst/three.txt] to [/rclone-test-cuzohih8hisiten8xigosuc0/dst/three-2019-01-01.txt]
2022/04/15 06:14:15 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/dst/three.txt]
2022/04/15 06:14:15 INFO  : three.txt: Copied (new)
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0/dst]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0/dst]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one]
2022/04/15 06:14:15 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one-2019-01-01]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one-2019-01-01]
2022/04/15 06:14:15 INFO  : one-2019-01-01: Deleted
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: rename [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one] to [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one-2019-01-01]
2022/04/15 06:14:15 INFO  : one: Moved (server-side) to: one-2019-01-01
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one]
2022/04/15 06:14:15 INFO  : one: Copied (new)
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-cuzohih8hisiten8xigosuc0/dst/two]
2022/04/15 06:14:15 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/04/15 06:14:15 DEBUG : two: Unchanged skipping
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-cuzohih8hisiten8xigosuc0/dst/three.txt]
2022/04/15 06:14:15 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-cuzohih8hisiten8xigosuc0/dst/three-2019-01-01.txt]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/dst/three-2019-01-01.txt]
2022/04/15 06:14:15 INFO  : three-2019-01-01.txt: Deleted
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: rename [/rclone-test-cuzohih8hisiten8xigosuc0/dst/three.txt] to [/rclone-test-cuzohih8hisiten8xigosuc0/dst/three-2019-01-01.txt]
2022/04/15 06:14:15 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/dst/three.txt]
2022/04/15 06:14:15 INFO  : three.txt: Copied (new)
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0/dst]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0/dst]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/dst/one-2019-01-01]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/dst/three-2019-01-01.txt]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/dst/three.txt]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/dst/two]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-cuzohih8hisiten8xigosuc0/dst]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
--- PASS: TestSyncSuffixKeepExtension (0.16s)
=== RUN   TestSyncConcurrentDelete
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone375557270", Modify Window "1s"
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/both0]
    run.go:287: Failed to put "both0" to "hdfs://127.0.0.1:8020": create /rclone-test-cuzohih8hisiten8xigosuc0/both0: replication in progress
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
--- FAIL: TestSyncConcurrentDelete (0.02s)
=== RUN   TestSyncConcurrentTruncate
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone375557270", Modify Window "1s"
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/both0]
2022/04/15 06:14:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/only0]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/both1]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/only1]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/both2]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/only2]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/both3]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/only3]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-cuzohih8hisiten8xigosuc0/both4]
    run.go:287: Failed to put "both4" to "hdfs://127.0.0.1:8020": create /rclone-test-cuzohih8hisiten8xigosuc0/both4: replication in progress
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/both0]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/both1]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/both2]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/both3]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/only0]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/only1]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/only2]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-cuzohih8hisiten8xigosuc0/only3]
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-cuzohih8hisiten8xigosuc0]
--- FAIL: TestSyncConcurrentTruncate (0.13s)
FAIL
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: Purge remote
2022/04/15 06:14:16 DEBUG : hdfs://127.0.0.1:8020: purge [/rclone-test-cuzohih8hisiten8xigosuc0]
"./sync.test -test.v -test.timeout 1h0m0s -remote TestHdfs: -verbose -test.run '^(TestCopy|TestSyncBackupDirSuffixOnly|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncIgnoreErrors|TestSyncSuffixKeepExtension)$'" - Finished ERROR in 689.13382ms (try 3/5): exit status 1: Failed [TestCopy TestSyncIgnoreErrors TestSyncBackupDirSuffixOnly TestSyncConcurrentDelete TestSyncConcurrentTruncate]