"./operations.test -test.v -test.timeout 1h0m0s -remote TestHdfs: -verbose -test.run '^(TestCheckSumDownload|TestCopyFile|TestCopyFileBackupDir|TestCount|TestDirMove|TestListJSON|TestMultithreadCopy)$/^{size:131071_streams:2}$'" - Starting (try 2/5)
2022/06/12 06:31:14 DEBUG : Creating backend with remote "TestHdfs:rclone-test-duwayow6zofesuw2fopojuy1"
2022/06/12 06:31:14 DEBUG : Setting type="hdfs" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_TYPE
2022/06/12 06:31:14 DEBUG : Setting namenode="127.0.0.1:8020" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_NAMENODE
2022/06/12 06:31:14 DEBUG : Setting username="root" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_USERNAME
2022/06/12 06:31:14 DEBUG : TestHdfs: detected overridden config - adding "{cNNQW}" suffix to name
2022/06/12 06:31:14 DEBUG : Setting namenode="127.0.0.1:8020" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_NAMENODE
2022/06/12 06:31:14 DEBUG : Setting username="root" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_USERNAME
2022/06/12 06:31:14 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2022/06/12 06:31:14 DEBUG : Creating backend with remote "/tmp/rclone1536126666"
=== RUN   TestMultithreadCopy
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone1536126666", Modify Window "1s"
=== RUN   TestMultithreadCopy/{size:131071_streams:2}
2022/06/12 06:31:14 DEBUG : hdfs://127.0.0.1:8020: mkdir [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:14 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-duwayow6zofesuw2fopojuy1/file1]
=== CONT  TestMultithreadCopy
    run.go:287: Failed to put "file1" to "hdfs://127.0.0.1:8020": create /rclone-test-duwayow6zofesuw2fopojuy1/file1: replication in progress
=== CONT  TestMultithreadCopy/{size:131071_streams:2}
    testing.go:1169: test executed panic(nil) or runtime.Goexit: subtest may have called FailNow on a parent test
2022/06/12 06:31:14 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:14 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
--- FAIL: TestMultithreadCopy (0.23s)
    --- FAIL: TestMultithreadCopy/{size:131071_streams:2} (0.22s)
=== RUN   TestCheckSumDownload
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone1536126666", Modify Window "1s"
2022/06/12 06:31:14 DEBUG : Creating backend with remote "TestHdfs:rclone-test-duwayow6zofesuw2fopojuy1/data"
2022/06/12 06:31:14 DEBUG : Setting type="hdfs" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_TYPE
2022/06/12 06:31:14 DEBUG : Setting namenode="127.0.0.1:8020" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_NAMENODE
2022/06/12 06:31:14 DEBUG : Setting username="root" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_USERNAME
2022/06/12 06:31:14 DEBUG : TestHdfs: detected overridden config - adding "{cNNQW}" suffix to name
2022/06/12 06:31:14 DEBUG : Setting namenode="127.0.0.1:8020" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_NAMENODE
2022/06/12 06:31:14 DEBUG : Setting username="root" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_USERNAME
2022/06/12 06:31:14 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-duwayow6zofesuw2fopojuy1/data/banana]
    run.go:287: Failed to put "data/banana" to "hdfs://127.0.0.1:8020": create /rclone-test-duwayow6zofesuw2fopojuy1/data/banana: replication in progress
2022/06/12 06:31:14 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:14 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/data]
2022/06/12 06:31:14 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-duwayow6zofesuw2fopojuy1/data]
2022/06/12 06:31:14 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
--- FAIL: TestCheckSumDownload (0.03s)
=== RUN   TestListJSON
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone1536126666", Modify Window "1s"
2022/06/12 06:31:14 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-duwayow6zofesuw2fopojuy1/file1]
2022/06/12 06:31:14 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-duwayow6zofesuw2fopojuy1/sub/file2]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/sub]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-duwayow6zofesuw2fopojuy1/file1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/sub]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-duwayow6zofesuw2fopojuy1/sub/file2]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-duwayow6zofesuw2fopojuy1/sub]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
--- PASS: TestListJSON (0.09s)
=== RUN   TestCount
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone1536126666", Modify Window "1s"
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-duwayow6zofesuw2fopojuy1/potato2]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-duwayow6zofesuw2fopojuy1/empty space]
    run.go:287: Failed to put "empty space" to "hdfs://127.0.0.1:8020": create /rclone-test-duwayow6zofesuw2fopojuy1/empty space: replication in progress
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-duwayow6zofesuw2fopojuy1/potato2]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
--- FAIL: TestCount (0.06s)
=== RUN   TestCopyFile
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone1536126666", Modify Window "1s"
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-duwayow6zofesuw2fopojuy1/sub/file2]
2022/06/12 06:31:15 DEBUG : file1: Need to transfer - File not found at Destination
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-duwayow6zofesuw2fopojuy1/sub/file2]
2022/06/12 06:31:15 INFO  : file1: Copied (new) to: sub/file2
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/sub]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-duwayow6zofesuw2fopojuy1/sub/file2]
2022/06/12 06:31:15 DEBUG : file1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2022/06/12 06:31:15 DEBUG : file1: Unchanged skipping
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/sub]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: don't need to copy/move sub/file2, it is already at target location
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/sub]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/sub]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-duwayow6zofesuw2fopojuy1/sub/file2]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-duwayow6zofesuw2fopojuy1/sub]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
--- PASS: TestCopyFile (0.04s)
=== RUN   TestCopyFileBackupDir
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone1536126666", Modify Window "1s"
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-duwayow6zofesuw2fopojuy1/dst/file1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/dst]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-duwayow6zofesuw2fopojuy1/dst/file1]
2022/06/12 06:31:15 DEBUG : Creating backend with remote "TestHdfs:rclone-test-duwayow6zofesuw2fopojuy1/backup"
2022/06/12 06:31:15 DEBUG : Setting type="hdfs" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_TYPE
2022/06/12 06:31:15 DEBUG : Setting namenode="127.0.0.1:8020" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_NAMENODE
2022/06/12 06:31:15 DEBUG : Setting username="root" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_USERNAME
2022/06/12 06:31:15 DEBUG : TestHdfs: detected overridden config - adding "{cNNQW}" suffix to name
2022/06/12 06:31:15 DEBUG : Setting namenode="127.0.0.1:8020" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_NAMENODE
2022/06/12 06:31:15 DEBUG : Setting username="root" for "TestHdfs" from environment variable RCLONE_CONFIG_TESTHDFS_USERNAME
2022/06/12 06:31:15 DEBUG : fs cache: renaming cache item "TestHdfs:rclone-test-duwayow6zofesuw2fopojuy1/backup" to be canonical "TestHdfs{cNNQW}:rclone-test-duwayow6zofesuw2fopojuy1/backup"
2022/06/12 06:31:15 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18)
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: new [/rclone-test-duwayow6zofesuw2fopojuy1/backup/dst/file1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: rename [/rclone-test-duwayow6zofesuw2fopojuy1/dst/file1] to [/rclone-test-duwayow6zofesuw2fopojuy1/backup/dst/file1]
2022/06/12 06:31:15 INFO  : dst/file1: Moved (server-side)
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-duwayow6zofesuw2fopojuy1/dst/file1]
2022/06/12 06:31:15 INFO  : dst/file1: Copied (new)
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/dst]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/backup]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/backup/dst]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/dst]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/backup]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-duwayow6zofesuw2fopojuy1/dst/file1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/backup/dst]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-duwayow6zofesuw2fopojuy1/backup/dst/file1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-duwayow6zofesuw2fopojuy1/dst]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-duwayow6zofesuw2fopojuy1/backup/dst]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-duwayow6zofesuw2fopojuy1/backup]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
--- PASS: TestCopyFileBackupDir (0.11s)
=== RUN   TestDirMove
    run.go:181: Remote "hdfs://127.0.0.1:8020", Local "Local file system at /tmp/rclone1536126666", Modify Window "1s"
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: mkdir [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-duwayow6zofesuw2fopojuy1/A1/one]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-duwayow6zofesuw2fopojuy1/A1/two]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-duwayow6zofesuw2fopojuy1/A1/B1/three]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: update [/rclone-test-duwayow6zofesuw2fopojuy1/A1/B1/C1/four]
    run.go:287: Failed to put "A1/B1/C1/four" to "hdfs://127.0.0.1:8020": create /rclone-test-duwayow6zofesuw2fopojuy1/A1/B1/C1/four: replication in progress
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/A1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-duwayow6zofesuw2fopojuy1/A1/one]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-duwayow6zofesuw2fopojuy1/A1/two]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/A1/B1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: remove [/rclone-test-duwayow6zofesuw2fopojuy1/A1/B1/three]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1/A1/B1/C1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-duwayow6zofesuw2fopojuy1/A1/B1/C1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-duwayow6zofesuw2fopojuy1/A1/B1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: rmdir [/rclone-test-duwayow6zofesuw2fopojuy1/A1]
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: list [/rclone-test-duwayow6zofesuw2fopojuy1]
--- FAIL: TestDirMove (0.10s)
FAIL
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: Purge remote
2022/06/12 06:31:15 DEBUG : hdfs://127.0.0.1:8020: purge [/rclone-test-duwayow6zofesuw2fopojuy1]
"./operations.test -test.v -test.timeout 1h0m0s -remote TestHdfs: -verbose -test.run '^(TestCheckSumDownload|TestCopyFile|TestCopyFileBackupDir|TestCount|TestDirMove|TestListJSON|TestMultithreadCopy)$/^{size:131071_streams:2}$'" - Finished ERROR in 815.144868ms (try 2/5): exit status 1: Failed [TestMultithreadCopy/{size:131071_streams:2} TestCheckSumDownload TestCount TestDirMove]