Skip to content

Instantly share code, notes, and snippets.

@thomae

thomae/rclone-client.log Secret

Created November 2, 2021 00:25
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/24f5197602916a8c34e39c420fdad2c4 to your computer and use it in GitHub Desktop.
Save thomae/24f5197602916a8c34e39c420fdad2c4 to your computer and use it in GitHub Desktop.
Download ZIP
serve sftp --stdio: upload fails ("corrupted on transfer", v1.57.0-beta.5823.da8f9be84)
Raw
rclone-client.log
2021/11/02 00:48:08 DEBUG : rclone: Version "v1.57.0-beta.5823.da8f9be84" starting with parameters ["./rclone" "copy" "/home/debian/rclone/client-folder" "sftp-test:" "--log-file" "/home/debian/rclone/rclone-client.log" "-vvv"]
2021/11/02 00:48:08 DEBUG : Creating backend with remote "/home/debian/rclone/client-folder"
2021/11/02 00:48:08 DEBUG : Using config file from "/home/debian/.config/rclone/rclone.conf"
2021/11/02 00:48:08 DEBUG : Creating backend with remote "sftp-test:"
2021/11/02 00:48:08 DEBUG : sftp://debian@127.0.0.1:22/: New connection 127.0.0.1:44908->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.9p1 Debian-10+deb10u2"
2021/11/02 00:48:08 DEBUG : sftp://debian@127.0.0.1:22/: Using absolute root directory "/"
2021/11/02 00:48:08 DEBUG : sftp://debian@127.0.0.1:22/: Waiting for checks to finish
2021/11/02 00:48:08 DEBUG : sftp://debian@127.0.0.1:22/: Waiting for transfers to finish
2021/11/02 00:48:08 DEBUG : sftp://debian@127.0.0.1:22/: New connection 127.0.0.1:44910->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.9p1 Debian-10+deb10u2"
2021/11/02 00:48:08 DEBUG : sftp://debian@127.0.0.1:22/: New connection 127.0.0.1:44912->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.9p1 Debian-10+deb10u2"
2021/11/02 00:48:08 DEBUG : sftp://debian@127.0.0.1:22/: New connection 127.0.0.1:44914->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.9p1 Debian-10+deb10u2"
2021/11/02 00:48:11 ERROR : 100MB-1.test: corrupted on transfer: sizes differ 100000000 vs 11530240
2021/11/02 00:48:11 INFO : 100MB-1.test: Removing failed copy
2021/11/02 00:48:12 ERROR : 100MB-2.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 00:48:12 ERROR : 100MB-3.test: corrupted on transfer: sizes differ 100000000 vs 7335936
2021/11/02 00:48:12 INFO : 100MB-3.test: Removing failed copy
2021/11/02 00:48:12 INFO : 100MB-3.test: Failed to remove failed copy: file does not exist
2021/11/02 00:48:12 ERROR : 100MB-4.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 00:48:15 ERROR : 100MB-5.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 00:48:15 ERROR : 100MB-6.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 00:48:15 ERROR : 100MB-7.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 00:48:15 ERROR : 100MB-8.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 00:48:17 ERROR : 100MB-9.test: Failed to copy: Update SetModTime failed: SetModTime stat failed: object not found
2021/11/02 00:48:17 ERROR : Attempt 1/3 failed with 9 errors and: Update SetModTime failed: SetModTime stat failed: object not found
2021/11/02 00:48:17 DEBUG : 100MB-1.test: Size and modification time the same (differ by -885.607532ms, within tolerance 1s)
2021/11/02 00:48:17 DEBUG : 100MB-1.test: Unchanged skipping
2021/11/02 00:48:17 DEBUG : sftp://debian@127.0.0.1:22/: Waiting for checks to finish
2021/11/02 00:48:17 DEBUG : sftp://debian@127.0.0.1:22/: Waiting for transfers to finish
2021/11/02 00:48:21 INFO : 100MB-3.test: Copied (new)
2021/11/02 00:48:21 ERROR : 100MB-4.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 00:48:21 ERROR : 100MB-5.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 00:48:21 ERROR : 100MB-2.test: corrupted on transfer: sizes differ 100000000 vs 6287360
2021/11/02 00:48:21 INFO : 100MB-2.test: Removing failed copy
2021/11/02 00:48:23 INFO : 100MB-7.test: Copied (new)
2021/11/02 00:48:24 ERROR : 100MB-6.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 00:48:24 ERROR : 100MB-8.test: Failed to copy: Update SetModTime failed: SetModTime stat failed: object not found
2021/11/02 00:48:24 ERROR : 100MB-9.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 00:48:24 ERROR : Attempt 2/3 failed with 6 errors and: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 00:48:24 DEBUG : sftp://debian@127.0.0.1:22/: Waiting for checks to finish
2021/11/02 00:48:24 DEBUG : 100MB-1.test: Size and modification time the same (differ by -885.607532ms, within tolerance 1s)
2021/11/02 00:48:24 DEBUG : 100MB-1.test: Unchanged skipping
2021/11/02 00:48:24 DEBUG : 100MB-5.test: Modification times differ by 4m17.090392468s: 2021-11-02 00:43:59.909607532 +0100 CET, 2021-11-02 00:48:17 +0100 CET
2021/11/02 00:48:24 DEBUG : 100MB-6.test: Modification times differ by 4m21.086392468s: 2021-11-02 00:43:59.913607532 +0100 CET, 2021-11-02 00:48:21 +0100 CET
2021/11/02 00:48:24 DEBUG : 100MB-8.test: Size and modification time the same (differ by -921.607532ms, within tolerance 1s)
2021/11/02 00:48:24 DEBUG : 100MB-8.test: Unchanged skipping
2021/11/02 00:48:24 DEBUG : 100MB-9.test: Modification times differ by 4m21.074392468s: 2021-11-02 00:43:59.925607532 +0100 CET, 2021-11-02 00:48:21 +0100 CET
2021/11/02 00:48:24 DEBUG : sftp://debian@127.0.0.1:22/: Waiting for transfers to finish
2021/11/02 00:48:28 INFO : 100MB-7.test: Copied (new)
2021/11/02 00:48:28 ERROR : 100MB-3.test: Failed to copy: Update SetModTime failed: SetModTime failed: file does not exist
2021/11/02 00:48:28 INFO : 100MB-4.test: Copied (new)
2021/11/02 00:48:28 ERROR : 100MB-2.test: corrupted on transfer: sizes differ 100000000 vs 6287360
2021/11/02 00:48:28 INFO : 100MB-2.test: Removing failed copy
2021/11/02 00:48:28 INFO : 100MB-2.test: Failed to remove failed copy: file does not exist
2021/11/02 00:48:31 INFO : 100MB-9.test: Copied (replaced existing)
2021/11/02 00:48:31 INFO : 100MB-5.test: Copied (replaced existing)
2021/11/02 00:48:31 INFO : 100MB-6.test: Copied (replaced existing)
2021/11/02 00:48:31 ERROR : Attempt 3/3 failed with 2 errors and: corrupted on transfer: sizes differ 100000000 vs 6287360
2021/11/02 00:48:31 INFO :
Transferred: 2.235 GiB / 2.235 GiB, 100%, 100.937 MiB/s, ETA 0s
Errors: 2 (retrying may help)
Checks: 6 / 6, 100%
Transferred: 7 / 7, 100%
Elapsed time: 23.1s
2021/11/02 00:48:31 DEBUG : 39 go routines active
2021/11/02 00:48:31 Failed to copy with 2 errors: last error was: corrupted on transfer: sizes differ 100000000 vs 6287360
Raw
rclone-server-stdio.log
2021/11/02 00:48:08 DEBUG : rclone: Version "v1.57.0-beta.5823.da8f9be84" starting with parameters ["/home/debian/rclone/rclone" "serve" "sftp" "--log-file" "/home/debian/rclone/rclone-server-stdio.log" "--stdio" "/home/debian/rclone/server-folder" "-vvv"]
2021/11/02 00:48:08 DEBUG : Creating backend with remote "/home/debian/rclone/server-folder"
2021/11/02 00:48:08 DEBUG : Using config file from "/home/debian/.config/rclone/rclone.conf"
2021/11/02 00:48:08 INFO : Local file system at /home/debian/rclone/server-folder: poll-interval is not supported by this remote
2021/11/02 00:48:08 DEBUG : stdio: Starting SFTP server
2021/11/02 00:48:08 DEBUG : /100MB-1.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:08 DEBUG : 100MB-1.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:08 DEBUG : : Added virtual directory entry vAddFile: "100MB-1.test"
2021/11/02 00:48:08 DEBUG : 100MB-1.test: >Open: fd=100MB-1.test (w), err=<nil>
2021/11/02 00:48:08 DEBUG : /100MB-1.test: >OpenFile: fd=100MB-1.test (w), err=<nil>
2021/11/02 00:48:08 DEBUG : : Added virtual directory entry vAddFile: "100MB-1.test"
2021/11/02 00:48:08 DEBUG : rclone: Version "v1.57.0-beta.5823.da8f9be84" starting with parameters ["/home/debian/rclone/rclone" "serve" "sftp" "--log-file" "/home/debian/rclone/rclone-server-stdio.log" "--stdio" "/home/debian/rclone/server-folder" "-vvv"]
2021/11/02 00:48:08 DEBUG : Creating backend with remote "/home/debian/rclone/server-folder"
2021/11/02 00:48:08 DEBUG : Using config file from "/home/debian/.config/rclone/rclone.conf"
2021/11/02 00:48:08 INFO : Local file system at /home/debian/rclone/server-folder: poll-interval is not supported by this remote
2021/11/02 00:48:08 DEBUG : stdio: Starting SFTP server
2021/11/02 00:48:08 DEBUG : /100MB-3.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:08 DEBUG : 100MB-3.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:08 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 00:48:08 DEBUG : 100MB-3.test: >Open: fd=100MB-3.test (w), err=<nil>
2021/11/02 00:48:08 DEBUG : /100MB-3.test: >OpenFile: fd=100MB-3.test (w), err=<nil>
2021/11/02 00:48:08 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 00:48:08 DEBUG : rclone: Version "v1.57.0-beta.5823.da8f9be84" starting with parameters ["/home/debian/rclone/rclone" "serve" "sftp" "--log-file" "/home/debian/rclone/rclone-server-stdio.log" "--stdio" "/home/debian/rclone/server-folder" "-vvv"]
2021/11/02 00:48:08 DEBUG : Creating backend with remote "/home/debian/rclone/server-folder"
2021/11/02 00:48:08 DEBUG : Using config file from "/home/debian/.config/rclone/rclone.conf"
2021/11/02 00:48:08 INFO : Local file system at /home/debian/rclone/server-folder: poll-interval is not supported by this remote
2021/11/02 00:48:08 DEBUG : stdio: Starting SFTP server
2021/11/02 00:48:08 DEBUG : /100MB-2.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:08 DEBUG : 100MB-2.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:08 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 00:48:08 DEBUG : 100MB-2.test: >Open: fd=100MB-2.test (w), err=<nil>
2021/11/02 00:48:08 DEBUG : /100MB-2.test: >OpenFile: fd=100MB-2.test (w), err=<nil>
2021/11/02 00:48:08 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 00:48:09 DEBUG : rclone: Version "v1.57.0-beta.5823.da8f9be84" starting with parameters ["/home/debian/rclone/rclone" "serve" "sftp" "--log-file" "/home/debian/rclone/rclone-server-stdio.log" "--stdio" "/home/debian/rclone/server-folder" "-vvv"]
2021/11/02 00:48:09 DEBUG : Creating backend with remote "/home/debian/rclone/server-folder"
2021/11/02 00:48:09 DEBUG : Using config file from "/home/debian/.config/rclone/rclone.conf"
2021/11/02 00:48:09 INFO : Local file system at /home/debian/rclone/server-folder: poll-interval is not supported by this remote
2021/11/02 00:48:09 DEBUG : stdio: Starting SFTP server
2021/11/02 00:48:09 DEBUG : /100MB-4.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:09 DEBUG : 100MB-4.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:09 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 00:48:09 DEBUG : 100MB-4.test: >Open: fd=100MB-4.test (w), err=<nil>
2021/11/02 00:48:09 DEBUG : /100MB-4.test: >OpenFile: fd=100MB-4.test (w), err=<nil>
2021/11/02 00:48:09 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 00:48:11 DEBUG : 100MB-1.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:11 DEBUG : : Added virtual directory entry vAddFile: "100MB-1.test"
2021/11/02 00:48:11 DEBUG : 100MB-1.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 00:48:11 DEBUG : 100MB-1.test: Remove:
2021/11/02 00:48:11 DEBUG : : Added virtual directory entry vDel: "100MB-1.test"
2021/11/02 00:48:11 DEBUG : 100MB-1.test: >Remove: err=<nil>
2021/11/02 00:48:11 DEBUG : /100MB-5.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:11 DEBUG : 100MB-5.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:11 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 00:48:11 DEBUG : 100MB-5.test: >Open: fd=100MB-5.test (w), err=<nil>
2021/11/02 00:48:11 DEBUG : /100MB-5.test: >OpenFile: fd=100MB-5.test (w), err=<nil>
2021/11/02 00:48:11 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 00:48:12 DEBUG : 100MB-2.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:12 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 00:48:12 DEBUG : /100MB-6.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:12 DEBUG : 100MB-6.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:12 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 00:48:12 DEBUG : 100MB-6.test: >Open: fd=100MB-6.test (w), err=<nil>
2021/11/02 00:48:12 DEBUG : /100MB-6.test: >OpenFile: fd=100MB-6.test (w), err=<nil>
2021/11/02 00:48:12 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 00:48:12 DEBUG : 100MB-3.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:12 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 00:48:12 DEBUG : 100MB-3.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 00:48:12 DEBUG : /100MB-7.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:12 DEBUG : 100MB-7.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:12 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 00:48:12 DEBUG : 100MB-7.test: >Open: fd=100MB-7.test (w), err=<nil>
2021/11/02 00:48:12 DEBUG : /100MB-7.test: >OpenFile: fd=100MB-7.test (w), err=<nil>
2021/11/02 00:48:12 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 00:48:12 DEBUG : 100MB-4.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:12 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 00:48:12 DEBUG : /100MB-8.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:12 DEBUG : 100MB-8.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:12 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 00:48:12 DEBUG : 100MB-8.test: >Open: fd=100MB-8.test (w), err=<nil>
2021/11/02 00:48:12 DEBUG : /100MB-8.test: >OpenFile: fd=100MB-8.test (w), err=<nil>
2021/11/02 00:48:12 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 00:48:15 DEBUG : 100MB-5.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:15 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 00:48:15 DEBUG : /100MB-9.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:15 DEBUG : 100MB-9.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:15 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 00:48:15 DEBUG : 100MB-9.test: >Open: fd=100MB-9.test (w), err=<nil>
2021/11/02 00:48:15 DEBUG : /100MB-9.test: >OpenFile: fd=100MB-9.test (w), err=<nil>
2021/11/02 00:48:15 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 00:48:15 DEBUG : 100MB-6.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:15 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 00:48:15 DEBUG : 100MB-7.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:15 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 00:48:15 DEBUG : 100MB-8.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:15 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 00:48:17 DEBUG : 100MB-9.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:17 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 00:48:17 DEBUG : 100MB-9.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 00:48:17 DEBUG : /100MB-2.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:17 DEBUG : 100MB-2.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:17 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 00:48:17 DEBUG : 100MB-2.test: >Open: fd=100MB-2.test (w), err=<nil>
2021/11/02 00:48:17 DEBUG : /100MB-2.test: >OpenFile: fd=100MB-2.test (w), err=<nil>
2021/11/02 00:48:17 DEBUG : /100MB-3.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:17 DEBUG : 100MB-3.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:17 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 00:48:17 DEBUG : 100MB-3.test: >Open: fd=100MB-3.test (w), err=<nil>
2021/11/02 00:48:17 DEBUG : /100MB-3.test: >OpenFile: fd=100MB-3.test (w), err=<nil>
2021/11/02 00:48:17 DEBUG : /100MB-4.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:17 DEBUG : 100MB-4.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:17 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 00:48:17 DEBUG : 100MB-4.test: >Open: fd=100MB-4.test (w), err=<nil>
2021/11/02 00:48:17 DEBUG : /100MB-4.test: >OpenFile: fd=100MB-4.test (w), err=<nil>
2021/11/02 00:48:17 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 00:48:17 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 00:48:17 DEBUG : /100MB-5.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:17 DEBUG : 100MB-5.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:17 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 00:48:17 DEBUG : 100MB-5.test: >Open: fd=100MB-5.test (w), err=<nil>
2021/11/02 00:48:17 DEBUG : /100MB-5.test: >OpenFile: fd=100MB-5.test (w), err=<nil>
2021/11/02 00:48:17 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 00:48:17 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 00:48:21 DEBUG : 100MB-3.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:21 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 00:48:21 DEBUG : 100MB-3.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 00:48:21 DEBUG : /100MB-6.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:21 DEBUG : 100MB-6.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:21 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 00:48:21 DEBUG : 100MB-6.test: >Open: fd=100MB-6.test (w), err=<nil>
2021/11/02 00:48:21 DEBUG : /100MB-6.test: >OpenFile: fd=100MB-6.test (w), err=<nil>
2021/11/02 00:48:21 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 00:48:21 DEBUG : 100MB-4.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:21 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 00:48:21 DEBUG : /100MB-7.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:21 DEBUG : 100MB-7.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:21 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 00:48:21 DEBUG : 100MB-7.test: >Open: fd=100MB-7.test (w), err=<nil>
2021/11/02 00:48:21 DEBUG : /100MB-7.test: >OpenFile: fd=100MB-7.test (w), err=<nil>
2021/11/02 00:48:21 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 00:48:21 DEBUG : 100MB-5.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:21 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 00:48:21 DEBUG : /100MB-8.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:21 DEBUG : 100MB-8.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:21 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 00:48:21 DEBUG : 100MB-8.test: >Open: fd=100MB-8.test (w), err=<nil>
2021/11/02 00:48:21 DEBUG : /100MB-8.test: >OpenFile: fd=100MB-8.test (w), err=<nil>
2021/11/02 00:48:21 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 00:48:21 DEBUG : 100MB-2.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:21 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 00:48:21 DEBUG : 100MB-2.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 00:48:21 DEBUG : 100MB-2.test: Remove:
2021/11/02 00:48:21 DEBUG : : Added virtual directory entry vDel: "100MB-2.test"
2021/11/02 00:48:21 DEBUG : 100MB-2.test: >Remove: err=<nil>
2021/11/02 00:48:21 DEBUG : /100MB-9.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:21 DEBUG : 100MB-9.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:21 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 00:48:21 DEBUG : 100MB-9.test: >Open: fd=100MB-9.test (w), err=<nil>
2021/11/02 00:48:21 DEBUG : /100MB-9.test: >OpenFile: fd=100MB-9.test (w), err=<nil>
2021/11/02 00:48:21 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 00:48:23 DEBUG : 100MB-7.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:23 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 00:48:23 DEBUG : 100MB-7.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 00:48:24 DEBUG : 100MB-6.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:24 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 00:48:24 DEBUG : 100MB-8.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:24 DEBUG : : Added virtual directory entry vAddFile: "100MB-8.test"
2021/11/02 00:48:24 DEBUG : 100MB-8.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 00:48:24 DEBUG : 100MB-9.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:24 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 00:48:24 DEBUG : /100MB-2.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:24 DEBUG : 100MB-2.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:24 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 00:48:24 DEBUG : 100MB-2.test: >Open: fd=100MB-2.test (w), err=<nil>
2021/11/02 00:48:24 DEBUG : /100MB-2.test: >OpenFile: fd=100MB-2.test (w), err=<nil>
2021/11/02 00:48:24 DEBUG : /100MB-3.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:24 DEBUG : 100MB-3.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:24 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 00:48:24 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 00:48:24 DEBUG : 100MB-3.test: >Open: fd=100MB-3.test (w), err=<nil>
2021/11/02 00:48:24 DEBUG : /100MB-3.test: >OpenFile: fd=100MB-3.test (w), err=<nil>
2021/11/02 00:48:24 DEBUG : /100MB-4.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:24 DEBUG : 100MB-4.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:24 DEBUG : /100MB-7.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:24 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 00:48:24 DEBUG : 100MB-4.test: >Open: fd=100MB-4.test (w), err=<nil>
2021/11/02 00:48:24 DEBUG : /100MB-4.test: >OpenFile: fd=100MB-4.test (w), err=<nil>
2021/11/02 00:48:24 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 00:48:24 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 00:48:24 DEBUG : 100MB-7.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:24 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 00:48:24 DEBUG : 100MB-7.test: >Open: fd=100MB-7.test (w), err=<nil>
2021/11/02 00:48:24 DEBUG : /100MB-7.test: >OpenFile: fd=100MB-7.test (w), err=<nil>
2021/11/02 00:48:24 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 00:48:28 DEBUG : 100MB-7.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:28 DEBUG : : Added virtual directory entry vAddFile: "100MB-7.test"
2021/11/02 00:48:28 DEBUG : 100MB-7.test: Not setting pending mod time 2021-11-02 00:43:59 +0100 CET as it is already set
2021/11/02 00:48:28 DEBUG : /100MB-5.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:28 DEBUG : 100MB-5.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:28 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 00:48:28 DEBUG : 100MB-5.test: >Open: fd=100MB-5.test (w), err=<nil>
2021/11/02 00:48:28 DEBUG : /100MB-5.test: >OpenFile: fd=100MB-5.test (w), err=<nil>
2021/11/02 00:48:28 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 00:48:28 DEBUG : 100MB-3.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:28 DEBUG : : Added virtual directory entry vAddFile: "100MB-3.test"
2021/11/02 00:48:28 DEBUG : /100MB-6.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:28 DEBUG : 100MB-6.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:28 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 00:48:28 DEBUG : 100MB-6.test: >Open: fd=100MB-6.test (w), err=<nil>
2021/11/02 00:48:28 DEBUG : /100MB-6.test: >OpenFile: fd=100MB-6.test (w), err=<nil>
2021/11/02 00:48:28 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 00:48:28 DEBUG : 100MB-4.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:28 DEBUG : : Added virtual directory entry vAddFile: "100MB-4.test"
2021/11/02 00:48:28 DEBUG : 100MB-4.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 00:48:28 DEBUG : /100MB-9.test: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx
2021/11/02 00:48:28 DEBUG : 100MB-9.test: Open: flags=O_WRONLY|O_CREATE|O_TRUNC
2021/11/02 00:48:28 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 00:48:28 DEBUG : 100MB-9.test: >Open: fd=100MB-9.test (w), err=<nil>
2021/11/02 00:48:28 DEBUG : /100MB-9.test: >OpenFile: fd=100MB-9.test (w), err=<nil>
2021/11/02 00:48:28 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 00:48:28 DEBUG : 100MB-2.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:28 DEBUG : : Added virtual directory entry vAddFile: "100MB-2.test"
2021/11/02 00:48:28 DEBUG : 100MB-2.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 00:48:31 DEBUG : 100MB-9.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:31 DEBUG : : Added virtual directory entry vAddFile: "100MB-9.test"
2021/11/02 00:48:31 DEBUG : 100MB-9.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 00:48:31 DEBUG : 100MB-5.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:31 DEBUG : : Added virtual directory entry vAddFile: "100MB-5.test"
2021/11/02 00:48:31 DEBUG : 100MB-5.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 00:48:31 DEBUG : 100MB-6.test: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/11/02 00:48:31 DEBUG : : Added virtual directory entry vAddFile: "100MB-6.test"
2021/11/02 00:48:31 DEBUG : 100MB-6.test: Applied pending mod time 2021-11-02 00:43:59 +0100 CET OK
2021/11/02 00:48:31 DEBUG : stdio: exited session
2021/11/02 00:48:31 INFO :
Transferred: 572.205 MiB / 572.205 MiB, 100%, 24.814 MiB/s, ETA 0s
Transferred: 6 / 6, 100%
Elapsed time: 22.4s
2021/11/02 00:48:31 DEBUG : 3 go routines active
2021/11/02 00:48:31 DEBUG : stdio: exited session
2021/11/02 00:48:31 INFO :
Transferred: 381.470 MiB / 381.470 MiB, 100%, 17.600 MiB/s, ETA 0s
Transferred: 4 / 4, 100%
Elapsed time: 22.6s
2021/11/02 00:48:31 DEBUG : 3 go routines active
2021/11/02 00:48:31 DEBUG : stdio: exited session
2021/11/02 00:48:31 INFO :
Transferred: 667.572 MiB / 667.572 MiB, 100%, 29.547 MiB/s, ETA 0s
Transferred: 7 / 7, 100%
Elapsed time: 22.9s
2021/11/02 00:48:31 DEBUG : 3 go routines active
2021/11/02 00:48:31 DEBUG : stdio: exited session
2021/11/02 00:48:31 INFO :
Transferred: 667.572 MiB / 667.572 MiB, 100%, 29.420 MiB/s, ETA 0s
Transferred: 7 / 7, 100%
Elapsed time: 22.6s
2021/11/02 00:48:31 DEBUG : 3 go routines active
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment