Skip to content

Instantly share code, notes, and snippets.

@thomae

thomae/server-v1-57-0-without-vfs-cache-mode-writes-client-v1-57-0-client.log Secret

Created November 2, 2021 17:32
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save thomae/9073a1f7713451dce0dd9614e67c7854 to your computer and use it in GitHub Desktop.
Save thomae/9073a1f7713451dce0dd9614e67c7854 to your computer and use it in GitHub Desktop.
Download ZIP
server: v1.57.0 without `--vfs-cache-mode writes`, client: v1.57.0
Raw
server-v1-57-0-without-vfs-cache-mode-writes-client-v1-57-0-client.log
2021/11/02 18:17:12 DEBUG : rclone: Version "v1.57.0" starting with parameters ["./rclone-v1.57.0" "copy" "/home/debian/rclone/client-folder" "sftp-test:" "--log-file" "server-v1-57-0-without-vfs-cache-mode-writes-client-v1-57-0-client.log" "-vvv"]
2021/11/02 18:17:12 DEBUG : Creating backend with remote "/home/debian/rclone/client-folder"
2021/11/02 18:17:12 DEBUG : Using config file from "/home/debian/.config/rclone/rclone.conf"
2021/11/02 18:17:12 DEBUG : Creating backend with remote "sftp-test:"
2021/11/02 18:17:12 DEBUG : sftp://debian@127.0.0.1:22/: New connection 127.0.0.1:44956->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.9p1 Debian-10+deb10u2"
2021/11/02 18:17:12 DEBUG : sftp://debian@127.0.0.1:22/: Using absolute root directory "/"
2021/11/02 18:17:12 DEBUG : sftp://debian@127.0.0.1:22/: Waiting for checks to finish
2021/11/02 18:17:12 DEBUG : sftp://debian@127.0.0.1:22/: Waiting for transfers to finish
2021/11/02 18:17:12 DEBUG : sftp://debian@127.0.0.1:22/: New connection 127.0.0.1:44958->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.9p1 Debian-10+deb10u2"
2021/11/02 18:17:12 DEBUG : sftp://debian@127.0.0.1:22/: New connection 127.0.0.1:44960->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.9p1 Debian-10+deb10u2"
2021/11/02 18:17:12 DEBUG : sftp://debian@127.0.0.1:22/: New connection 127.0.0.1:44962->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.9p1 Debian-10+deb10u2"
2021/11/02 18:17:16 ERROR : 100MB-1.test: corrupted on transfer: sizes differ 100000000 vs 10481664
2021/11/02 18:17:16 INFO : 100MB-1.test: Removing failed copy
2021/11/02 18:17:16 ERROR : 100MB-2.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 18:17:16 ERROR : 100MB-3.test: corrupted on transfer: sizes differ 100000000 vs 5238784
2021/11/02 18:17:16 INFO : 100MB-3.test: Removing failed copy
2021/11/02 18:17:16 INFO : 100MB-3.test: Failed to remove failed copy: file does not exist
2021/11/02 18:17:17 ERROR : 100MB-4.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 18:17:20 ERROR : 100MB-5.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 18:17:20 ERROR : 100MB-6.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 18:17:20 ERROR : 100MB-7.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 18:17:20 ERROR : 100MB-8.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 18:17:22 ERROR : 100MB-9.test: Failed to copy: Update SetModTime failed: SetModTime stat failed: object not found
2021/11/02 18:17:22 ERROR : Attempt 1/3 failed with 9 errors and: Update SetModTime failed: SetModTime stat failed: object not found
2021/11/02 18:17:22 DEBUG : 100MB-1.test: Size and modification time the same (differ by -885.607532ms, within tolerance 1s)
2021/11/02 18:17:22 DEBUG : 100MB-1.test: Unchanged skipping
2021/11/02 18:17:22 DEBUG : sftp://debian@127.0.0.1:22/: Waiting for checks to finish
2021/11/02 18:17:22 DEBUG : sftp://debian@127.0.0.1:22/: Waiting for transfers to finish
2021/11/02 18:17:26 ERROR : 100MB-4.test: Failed to copy: Update SetModTime failed: SetModTime stat failed: object not found
2021/11/02 18:17:26 ERROR : 100MB-3.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 18:17:26 ERROR : 100MB-2.test: Failed to copy: Update SetModTime failed: SetModTime stat failed: object not found
2021/11/02 18:17:26 ERROR : 100MB-5.test: Failed to copy: Update SetModTime failed: SetModTime stat failed: object not found
2021/11/02 18:17:29 INFO : 100MB-8.test: Copied (new)
2021/11/02 18:17:30 ERROR : 100MB-7.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 18:17:30 INFO : 100MB-6.test: Copied (new)
2021/11/02 18:17:30 ERROR : 100MB-9.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 18:17:30 ERROR : Attempt 2/3 failed with 6 errors and: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 18:17:30 DEBUG : 100MB-1.test: Size and modification time the same (differ by -885.607532ms, within tolerance 1s)
2021/11/02 18:17:30 DEBUG : 100MB-1.test: Unchanged skipping
2021/11/02 18:17:30 DEBUG : 100MB-6.test: Size and modification time the same (differ by -913.607532ms, within tolerance 1s)
2021/11/02 18:17:30 DEBUG : 100MB-6.test: Unchanged skipping
2021/11/02 18:17:30 DEBUG : 100MB-9.test: Modification times differ by 17h33m26.074392468s: 2021-11-02 00:43:59.925607532 +0100 CET, 2021-11-02 18:17:26 +0100 CET
2021/11/02 18:17:30 DEBUG : 100MB-5.test: Modification times differ by 17h33m22.090392468s: 2021-11-02 00:43:59.909607532 +0100 CET, 2021-11-02 18:17:22 +0100 CET
2021/11/02 18:17:30 DEBUG : sftp://debian@127.0.0.1:22/: Waiting for checks to finish
2021/11/02 18:17:30 DEBUG : sftp://debian@127.0.0.1:22/: Waiting for transfers to finish
2021/11/02 18:17:34 INFO : 100MB-2.test: Copied (new)
2021/11/02 18:17:34 INFO : 100MB-4.test: Copied (new)
2021/11/02 18:17:34 ERROR : 100MB-7.test: Failed to copy: Update SetModTime failed: SetModTime stat failed: object not found
2021/11/02 18:17:34 ERROR : 100MB-3.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 18:17:37 ERROR : 100MB-8.test: Failed to copy: Update SetModTime failed: SetModTime stat failed: object not found
2021/11/02 18:17:37 INFO : 100MB-9.test: Copied (replaced existing)
2021/11/02 18:17:37 ERROR : 100MB-5.test: Failed to copy: Update SetModTime failed: SetModTime stat failed: object not found
2021/11/02 18:17:37 ERROR : Attempt 3/3 failed with 4 errors and: Update SetModTime failed: SetModTime stat failed: object not found
2021/11/02 18:17:37 INFO :
Transferred: 2.235 GiB / 2.235 GiB, 100%, 90.605 MiB/s, ETA 0s
Errors: 4 (retrying may help)
Checks: 5 / 5, 100%
Transferred: 5 / 5, 100%
Elapsed time: 25.5s
2021/11/02 18:17:37 DEBUG : 39 go routines active
2021/11/02 18:17:37 Failed to copy with 4 errors: last error was: Update SetModTime failed: SetModTime stat failed: object not found
Raw
server-v1-57-0-without-vfs-cache-mode-writes-client-v1-57-0-server.log
2021/11/02 18:17:12 DEBUG : rclone: Version "v1.57.0" starting with parameters ["/home/debian/rclone/rclone-v1.57.0" "serve" "sftp" "--log-file" "/home/debian/rclone/server-v1-57-0-without-vfs-cache-mode-writes-client-v1-57-0-server.log" "--stdio" "/home/debian/rclone/server-folder" "-vvv"]
2021/11/02 18:17:12 DEBUG : Creating backend with remote "/home/debian/rclone/server-folder"
2021/11/02 18:17:12 DEBUG : Using config file from "/home/debian/.config/rclone/rclone.conf"
2021/11/02 18:17:12 INFO : Local file system at /home/debian/rclone/server-folder: poll-interval is not supported by this remote
2021/11/02 18:17:12 DEBUG : stdio: Starting SFTP server
2021/11/02 18:17:12 DEBUG : /100MB-1.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:12 DEBUG : 100MB-1.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:12 DEBUG : : Added virtual directory entry vAddFile: "100MB-1.test"
2021/11/02 18:17:12 DEBUG : 100MB-1.test: >Open: fd=100MB-1.test (w), err=<nil>
2021/11/02 18:17:12 DEBUG : /100MB-1.test: >OpenFile: fd=100MB-1.test (w), err=<nil>
2021/11/02 18:17:12 DEBUG : : Added virtual directory entry vAddFile: "100MB-1.test"
2021/11/02 18:17:12 DEBUG : rclone: Version "v1.57.0" starting with parameters ["/home/debian/rclone/rclone-v1.57.0" "serve" "sftp" "--log-file" "/home/debian/rclone/server-v1-57-0-without-vfs-cache-mode-writes-client-v1-57-0-server.log" "--stdio" "/home/debian/rclone/server-folder" "-vvv"]
2021/11/02 18:17:12 DEBUG : Creating backend with remote "/home/debian/rclone/server-folder"
2021/11/02 18:17:12 DEBUG : Using config file from "/home/debian/.config/rclone/rclone.conf"
2021/11/02 18:17:12 INFO : Local file system at /home/debian/rclone/server-folder: poll-interval is not supported by this remote
2021/11/02 18:17:12 DEBUG : stdio: Starting SFTP server
2021/11/02 18:17:12 DEBUG : /100MB-2.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:12 DEBUG : 100MB-2.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:12 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 18:17:12 DEBUG : 100MB-2.test: >Open: fd=100MB-2.test (w), err=<nil>
2021/11/02 18:17:12 DEBUG : /100MB-2.test: >OpenFile: fd=100MB-2.test (w), err=<nil>
2021/11/02 18:17:12 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 18:17:13 DEBUG : rclone: Version "v1.57.0" starting with parameters ["/home/debian/rclone/rclone-v1.57.0" "serve" "sftp" "--log-file" "/home/debian/rclone/server-v1-57-0-without-vfs-cache-mode-writes-client-v1-57-0-server.log" "--stdio" "/home/debian/rclone/server-folder" "-vvv"]
2021/11/02 18:17:13 DEBUG : Creating backend with remote "/home/debian/rclone/server-folder"
2021/11/02 18:17:13 DEBUG : Using config file from "/home/debian/.config/rclone/rclone.conf"
2021/11/02 18:17:13 INFO : Local file system at /home/debian/rclone/server-folder: poll-interval is not supported by this remote
2021/11/02 18:17:13 DEBUG : stdio: Starting SFTP server
2021/11/02 18:17:13 DEBUG : /100MB-3.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:13 DEBUG : 100MB-3.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:13 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 18:17:13 DEBUG : 100MB-3.test: >Open: fd=100MB-3.test (w), err=<nil>
2021/11/02 18:17:13 DEBUG : /100MB-3.test: >OpenFile: fd=100MB-3.test (w), err=<nil>
2021/11/02 18:17:13 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 18:17:13 DEBUG : rclone: Version "v1.57.0" starting with parameters ["/home/debian/rclone/rclone-v1.57.0" "serve" "sftp" "--log-file" "/home/debian/rclone/server-v1-57-0-without-vfs-cache-mode-writes-client-v1-57-0-server.log" "--stdio" "/home/debian/rclone/server-folder" "-vvv"]
2021/11/02 18:17:13 DEBUG : Creating backend with remote "/home/debian/rclone/server-folder"
2021/11/02 18:17:13 DEBUG : Using config file from "/home/debian/.config/rclone/rclone.conf"
2021/11/02 18:17:13 INFO : Local file system at /home/debian/rclone/server-folder: poll-interval is not supported by this remote
2021/11/02 18:17:13 DEBUG : stdio: Starting SFTP server
2021/11/02 18:17:13 DEBUG : /100MB-4.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:13 DEBUG : 100MB-4.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:13 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 18:17:13 DEBUG : 100MB-4.test: >Open: fd=100MB-4.test (w), err=<nil>
2021/11/02 18:17:13 DEBUG : /100MB-4.test: >OpenFile: fd=100MB-4.test (w), err=<nil>
2021/11/02 18:17:13 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 18:17:16 DEBUG : 100MB-1.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:16 DEBUG : : Added virtual directory entry vAddFile: "100MB-1.test"
2021/11/02 18:17:16 DEBUG : 100MB-1.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 18:17:16 DEBUG : 100MB-1.test: Remove:
2021/11/02 18:17:16 DEBUG : : Added virtual directory entry vDel: "100MB-1.test"
2021/11/02 18:17:16 DEBUG : 100MB-1.test: >Remove: err=<nil>
2021/11/02 18:17:16 DEBUG : /100MB-5.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:16 DEBUG : 100MB-5.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:16 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 18:17:16 DEBUG : 100MB-5.test: >Open: fd=100MB-5.test (w), err=<nil>
2021/11/02 18:17:16 DEBUG : /100MB-5.test: >OpenFile: fd=100MB-5.test (w), err=<nil>
2021/11/02 18:17:16 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 18:17:16 DEBUG : 100MB-2.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:16 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 18:17:16 DEBUG : /100MB-6.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:16 DEBUG : 100MB-6.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:16 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 18:17:16 DEBUG : 100MB-6.test: >Open: fd=100MB-6.test (w), err=<nil>
2021/11/02 18:17:16 DEBUG : /100MB-6.test: >OpenFile: fd=100MB-6.test (w), err=<nil>
2021/11/02 18:17:16 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 18:17:16 DEBUG : 100MB-3.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:16 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 18:17:16 DEBUG : 100MB-3.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 18:17:16 DEBUG : /100MB-7.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:16 DEBUG : 100MB-7.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:16 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 18:17:16 DEBUG : 100MB-7.test: >Open: fd=100MB-7.test (w), err=<nil>
2021/11/02 18:17:16 DEBUG : /100MB-7.test: >OpenFile: fd=100MB-7.test (w), err=<nil>
2021/11/02 18:17:16 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 18:17:17 DEBUG : 100MB-4.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:17 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 18:17:17 DEBUG : /100MB-8.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:17 DEBUG : 100MB-8.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:17 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 18:17:17 DEBUG : 100MB-8.test: >Open: fd=100MB-8.test (w), err=<nil>
2021/11/02 18:17:17 DEBUG : /100MB-8.test: >OpenFile: fd=100MB-8.test (w), err=<nil>
2021/11/02 18:17:17 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 18:17:20 DEBUG : 100MB-5.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:20 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 18:17:20 DEBUG : /100MB-9.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:20 DEBUG : 100MB-9.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:20 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 18:17:20 DEBUG : 100MB-9.test: >Open: fd=100MB-9.test (w), err=<nil>
2021/11/02 18:17:20 DEBUG : /100MB-9.test: >OpenFile: fd=100MB-9.test (w), err=<nil>
2021/11/02 18:17:20 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 18:17:20 DEBUG : 100MB-6.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:20 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 18:17:20 DEBUG : 100MB-7.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:20 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 18:17:20 DEBUG : 100MB-8.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:20 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 18:17:22 DEBUG : 100MB-9.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:22 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 18:17:22 DEBUG : 100MB-9.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 18:17:22 DEBUG : /100MB-4.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:22 DEBUG : 100MB-4.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:22 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 18:17:22 DEBUG : 100MB-4.test: >Open: fd=100MB-4.test (w), err=<nil>
2021/11/02 18:17:22 DEBUG : /100MB-4.test: >OpenFile: fd=100MB-4.test (w), err=<nil>
2021/11/02 18:17:22 DEBUG : /100MB-5.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:22 DEBUG : /100MB-3.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:22 DEBUG : 100MB-5.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:22 DEBUG : 100MB-3.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:22 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 18:17:22 DEBUG : 100MB-5.test: >Open: fd=100MB-5.test (w), err=<nil>
2021/11/02 18:17:22 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 18:17:22 DEBUG : /100MB-5.test: >OpenFile: fd=100MB-5.test (w), err=<nil>
2021/11/02 18:17:22 DEBUG : 100MB-3.test: >Open: fd=100MB-3.test (w), err=<nil>
2021/11/02 18:17:22 DEBUG : /100MB-3.test: >OpenFile: fd=100MB-3.test (w), err=<nil>
2021/11/02 18:17:22 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 18:17:22 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 18:17:22 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 18:17:22 DEBUG : /100MB-2.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:22 DEBUG : 100MB-2.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:22 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 18:17:22 DEBUG : 100MB-2.test: >Open: fd=100MB-2.test (w), err=<nil>
2021/11/02 18:17:22 DEBUG : /100MB-2.test: >OpenFile: fd=100MB-2.test (w), err=<nil>
2021/11/02 18:17:22 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 18:17:26 DEBUG : 100MB-4.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:26 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 18:17:26 DEBUG : 100MB-4.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 18:17:26 DEBUG : /100MB-6.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:26 DEBUG : 100MB-6.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:26 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 18:17:26 DEBUG : 100MB-6.test: >Open: fd=100MB-6.test (w), err=<nil>
2021/11/02 18:17:26 DEBUG : /100MB-6.test: >OpenFile: fd=100MB-6.test (w), err=<nil>
2021/11/02 18:17:26 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 18:17:26 DEBUG : 100MB-3.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:26 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 18:17:26 DEBUG : /100MB-7.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:26 DEBUG : 100MB-7.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:26 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 18:17:26 DEBUG : 100MB-7.test: >Open: fd=100MB-7.test (w), err=<nil>
2021/11/02 18:17:26 DEBUG : /100MB-7.test: >OpenFile: fd=100MB-7.test (w), err=<nil>
2021/11/02 18:17:26 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 18:17:26 DEBUG : 100MB-2.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:26 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 18:17:26 DEBUG : 100MB-2.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 18:17:26 DEBUG : /100MB-8.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:26 DEBUG : 100MB-8.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:26 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 18:17:26 DEBUG : 100MB-8.test: >Open: fd=100MB-8.test (w), err=<nil>
2021/11/02 18:17:26 DEBUG : /100MB-8.test: >OpenFile: fd=100MB-8.test (w), err=<nil>
2021/11/02 18:17:26 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 18:17:26 DEBUG : 100MB-5.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:26 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 18:17:26 DEBUG : 100MB-5.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 18:17:26 DEBUG : /100MB-9.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:26 DEBUG : 100MB-9.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:26 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 18:17:26 DEBUG : 100MB-9.test: >Open: fd=100MB-9.test (w), err=<nil>
2021/11/02 18:17:26 DEBUG : /100MB-9.test: >OpenFile: fd=100MB-9.test (w), err=<nil>
2021/11/02 18:17:26 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 18:17:29 DEBUG : 100MB-8.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:29 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 18:17:29 DEBUG : 100MB-8.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 18:17:30 DEBUG : 100MB-7.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:30 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 18:17:30 DEBUG : 100MB-6.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:30 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 18:17:30 DEBUG : 100MB-6.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 18:17:30 DEBUG : 100MB-9.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:30 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 18:17:30 DEBUG : /100MB-4.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:30 DEBUG : /100MB-2.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:30 DEBUG : 100MB-4.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:30 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 18:17:30 DEBUG : 100MB-4.test: >Open: fd=100MB-4.test (w), err=<nil>
2021/11/02 18:17:30 DEBUG : 100MB-2.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:30 DEBUG : /100MB-4.test: >OpenFile: fd=100MB-4.test (w), err=<nil>
2021/11/02 18:17:30 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 18:17:30 DEBUG : 100MB-2.test: >Open: fd=100MB-2.test (w), err=<nil>
2021/11/02 18:17:30 DEBUG : /100MB-2.test: >OpenFile: fd=100MB-2.test (w), err=<nil>
2021/11/02 18:17:30 DEBUG : /100MB-3.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:30 DEBUG : 100MB-3.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:30 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 18:17:30 DEBUG : 100MB-3.test: >Open: fd=100MB-3.test (w), err=<nil>
2021/11/02 18:17:30 DEBUG : /100MB-3.test: >OpenFile: fd=100MB-3.test (w), err=<nil>
2021/11/02 18:17:30 DEBUG : /100MB-7.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:30 DEBUG : 100MB-7.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:30 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 18:17:30 DEBUG : 100MB-7.test: >Open: fd=100MB-7.test (w), err=<nil>
2021/11/02 18:17:30 DEBUG : /100MB-7.test: >OpenFile: fd=100MB-7.test (w), err=<nil>
2021/11/02 18:17:30 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 18:17:30 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 18:17:30 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 18:17:30 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 18:17:34 DEBUG : 100MB-2.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:34 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 18:17:34 DEBUG : 100MB-2.test: Not setting pending mod time 2021-11-02 00:43:59 +0100 CET as it is already set
2021/11/02 18:17:34 DEBUG : 100MB-4.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:34 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 18:17:34 DEBUG : 100MB-4.test: Not setting pending mod time 2021-11-02 00:43:59 +0100 CET as it is already set
2021/11/02 18:17:34 DEBUG : /100MB-9.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:34 DEBUG : 100MB-9.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:34 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 18:17:34 DEBUG : 100MB-9.test: >Open: fd=100MB-9.test (w), err=<nil>
2021/11/02 18:17:34 DEBUG : /100MB-9.test: >OpenFile: fd=100MB-9.test (w), err=<nil>
2021/11/02 18:17:34 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 18:17:34 DEBUG : 100MB-7.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:34 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 18:17:34 DEBUG : /100MB-8.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:34 DEBUG : 100MB-8.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:34 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 18:17:34 DEBUG : 100MB-8.test: >Open: fd=100MB-8.test (w), err=<nil>
2021/11/02 18:17:34 DEBUG : /100MB-8.test: >OpenFile: fd=100MB-8.test (w), err=<nil>
2021/11/02 18:17:34 DEBUG : 100MB-7.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 18:17:34 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 18:17:34 DEBUG : /100MB-5.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 18:17:34 DEBUG : 100MB-5.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 18:17:34 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 18:17:34 DEBUG : 100MB-5.test: >Open: fd=100MB-5.test (w), err=<nil>
2021/11/02 18:17:34 DEBUG : /100MB-5.test: >OpenFile: fd=100MB-5.test (w), err=<nil>
2021/11/02 18:17:34 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 18:17:34 DEBUG : 100MB-3.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:34 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 18:17:37 DEBUG : 100MB-8.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:37 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 18:17:37 DEBUG : 100MB-8.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 18:17:37 DEBUG : 100MB-9.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:37 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 18:17:37 DEBUG : 100MB-9.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 18:17:37 DEBUG : 100MB-5.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 18:17:37 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 18:17:37 DEBUG : 100MB-5.test: Not setting pending mod time 2021-11-02 00:43:59 +0100 CET as it is already set
2021/11/02 18:17:37 DEBUG : stdio: exited session
2021/11/02 18:17:37 INFO :
Transferred: 572.205 MiB / 572.205 MiB, 100%, 22.481 MiB/s, ETA 0s
Transferred: 6 / 6, 100%
Elapsed time: 24.8s
2021/11/02 18:17:37 DEBUG : 3 go routines active
2021/11/02 18:17:37 DEBUG : stdio: exited session
2021/11/02 18:17:37 INFO :
Transferred: 572.205 MiB / 572.205 MiB, 100%, 23.843 MiB/s, ETA 0s
Transferred: 6 / 6, 100%
Elapsed time: 25.3s
2021/11/02 18:17:37 DEBUG : 3 go routines active
2021/11/02 18:17:37 DEBUG : stdio: exited session
2021/11/02 18:17:37 INFO :
Transferred: 858.307 MiB / 858.307 MiB, 100%, 35.513 MiB/s, ETA 0s
Transferred: 9 / 9, 100%
Elapsed time: 25.1s
2021/11/02 18:17:37 DEBUG : 3 go routines active
2021/11/02 18:17:37 DEBUG : stdio: exited session
2021/11/02 18:17:37 INFO :
Transferred: 286.102 MiB / 286.102 MiB, 100%, 11.799 MiB/s, ETA 0s
Transferred: 3 / 3, 100%
Elapsed time: 25.0s
2021/11/02 18:17:37 DEBUG : 3 go routines active
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment