Re: Files sometimes uploaded twice when using a console script
@StrongViking: Thanks for sharing your findings.
- martin
Post a reply
Topic review
- StrongViking
Re: Files sometimes uploaded twice when using a console script
Thanks Martin,
Your response guided me into the right direction. It had to do with the settings of the Windows Task Scheduler indeed. The "for a duration of" field was set to Indefinitely, causing an additional run for each repeat.
This is the website which explains this clearly and has exactly the same issue as we were having:
Task Scheduler starts multiple times
It looks like the problem is solved now. Thanks again!
Your response guided me into the right direction. It had to do with the settings of the Windows Task Scheduler indeed. The "for a duration of" field was set to Indefinitely, causing an additional run for each repeat.
This is the website which explains this clearly and has exactly the same issue as we were having:
Task Scheduler starts multiple times
It looks like the problem is solved now. Thanks again!
- martin
Re: Files sometimes uploaded twice when using a console script
Indeed, there seem to be three instances of WinSCP running, probably operating over the same files (I didn't check). Chaos ensues.
Nothing in your batch file or WinSCP script would cause that. It's probably your Task Scheduler configuration.
Nothing in your batch file or WinSCP script would cause that. It's probably your Task Scheduler configuration.
- StrongViking
Files sometimes uploaded twice when using a console script
Hi,
Since 1 or 2 months, we have problems with one of our scripts. We use WinSCP to upload .txt files using console / scripting, but the complaint is that sometimes a file is sent twice in more or less the same second. The Task scheduler executes the script.
The logging confirmed that this does happen sometimes, that a file is sent 2 times. But: not clear why. One of the errors was that the
To solve this error, we changed the script by adding
Uploading went well in some cases (same as before), but short after I modified the script, it ran again, no file to upload present, but it went wrong again. I can see the script is executed like 3 times in the script. May I share the anonymized script(s) and logging?
In the logging you can see when the task scheduler runs the script, it is somehow running 3 times. The
Perhaps there is an authentication issue during the process, that could be the reason that everything within one sessions is executed again. But we are still really searching for what could be the cause and solution. Is it related to authentication, to the firewall, or whatever can it be? Any advice is welcome.
First the script:
BAT file:
The Text file:
Here is the log, in this case no files were uploaded. But the script looks like it is trying to upload anything 3 times, if there was a file.
Since 1 or 2 months, we have problems with one of our scripts. We use WinSCP to upload .txt files using console / scripting, but the complaint is that sometimes a file is sent twice in more or less the same second. The Task scheduler executes the script.
The logging confirmed that this does happen sometimes, that a file is sent 2 times. But: not clear why. One of the errors was that the
Upload of file '…...tmp' was successful, but error occurred while setting the permissions and/or timestamp.
To solve this error, we changed the script by adding
-nopreservetime –nopermissions to the PUT line. It did not always help, and the problem of uploading duplicate files remained. After that I also added -resumesupport=off to the script.
Uploading went well in some cases (same as before), but short after I modified the script, it ran again, no file to upload present, but it went wrong again. I can see the script is executed like 3 times in the script. May I share the anonymized script(s) and logging?
In the logging you can see when the task scheduler runs the script, it is somehow running 3 times. The
PUT action in the middle is where I added an additional command but this also makes no difference.
Perhaps there is an authentication issue during the process, that could be the reason that everything within one sessions is executed again. But we are still really searching for what could be the cause and solution. Is it related to authentication, to the firewall, or whatever can it be? Any advice is welcome.
First the script:
BAT file:
REM comments
NET USE T: \\v%%%%%%%%%%er.file.core.windows.net\v%%%r\%%%\%%%%%%%\%%\%%%
MOVE /Y T:\*.* C:\..\..\Out
NET USE T: /DEL /Y
"C:\Program Files (x86)\WinSCP\WinSCP.EXE" /CONSOLE /SCRIPT="C:\Program Files (x86)\WinSCP\..\..\%%PUT.txt" /LOG="C:\Program Files (x86)\WinSCP\...\..\%%%PUT.log" /loglevel=1
IF %ERRORLEVEL% NEQ 0 GOTO ERROR
echo Upload succeeded, moving files
MOVE /Y C:\...\...\Out\*.* C:\...\...\Archive\Out
:ERROR
echo Upload failed
EXIT /b 1
The Text file:
OPEN sftp://%%%%%%%%%%/ -privatekey=C:\..\Keys\%%%priv.ppk -passphrase=%%%%% -hostkey="%%%%%%%%%%="
CD receive
LCD C:\..\..\Out
PUT -nopreservetime -nopermissions -append FILENAME1*.* FILENAME1_%TIMESTAMP%.tmp
PUT -nopreservetime -nopermissions -resumesupport=off -append FILENAME2 *.* FILENAME2 _%TIMESTAMP%.tmp
PUT -nopreservetime -nopermissions -append FILENAME3 *.* FILENAME3_%TIMESTAMP%.tmp
MV *.tmp *.txt
EXIT
Here is the log, in this case no files were uploaded. But the script looks like it is trying to upload anything 3 times, if there was a file.
. 2025-06-27 14:40:02.766 --------------------------------------------------------------------------
. 2025-06-27 14:40:02.766 --------------------------------------------------------------------------
. 2025-06-27 14:40:02.782 WinSCP Version 6.5.2 (Build 16354 2025-06-18) (OS 10.0.14393 – Windows Server 2016 Standard)
. 2025-06-27 14:40:02.782 Configuration: HKCU\Software\Martin Prikryl\WinSCP 2\
. 2025-06-27 14:40:02.782 WinSCP Version 6.5.2 (Build 16354 2025-06-18) (OS 10.0.14393 – Windows Server 2016 Standard)
. 2025-06-27 14:40:02.782 Configuration: HKCU\Software\Martin Prikryl\WinSCP 2\
. 2025-06-27 14:40:02.782 Log level: Debug 1
. 2025-06-27 14:40:02.782 Local account: %%%%\AdminWinSCP
. 2025-06-27 14:40:02.782 Working directory: C:\WINDOWS\system32
. 2025-06-27 14:40:02.782 Process ID: 1980
. 2025-06-27 14:40:02.782 Log level: Debug 1
. 2025-06-27 14:40:02.782 Local account: %%%%\AdminWinSCP
. 2025-06-27 14:40:02.782 Working directory: C:\WINDOWS\system32
. 2025-06-27 14:40:02.782 Process ID: 176
. 2025-06-27 14:40:02.782 Ancestor processes: ...
. 2025-06-27 14:40:02.782 Command-line: "C:\Program Files (x86)\WinSCP\WinSCP.EXE" /CONSOLE /SCRIPT="C:\Program Files (x86)\WinSCP\..\...\%%PUT.txt" /LOG="C:\Program Files (x86)\WinSCP\..\..\%%PUT.log" /loglevel=1
. 2025-06-27 14:40:02.782 Switch: /CONSOLE
. 2025-06-27 14:40:02.782 Switch: /SCRIPT=C:\Program Files (x86)\WinSCP\..\..\%%%PUT.txt
. 2025-06-27 14:40:02.782 Switch: /LOG=C:\Program Files (x86)\WinSCP\..\..\%%PUT.log
. 2025-06-27 14:40:02.782 Switch: /loglevel=1
. 2025-06-27 14:40:02.782 Time zone: Current: GMT+2, Standard: GMT+1 (W. Europe Standard Time), DST: GMT+2 (W. Europe Daylight Time), DST Start: 30-3-2025, DST End: 26-10-2025
. 2025-06-27 14:40:02.782 Login time: vrijdag 27 juni 2025 14:40:02
. 2025-06-27 14:40:02.782 --------------------------------------------------------------------------
. 2025-06-27 14:40:02.782 Script: Retrospectively logging previous script records:
> 2025-06-27 14:40:02.782 Script: OPEN SFTP TARGET/ -privatekey=%%%%.ppk -passphrase=*** -hostkey="HOSTKEYVALUE="
> 2025-06-27 14:40:02.782 Script: Parameter: SFTP TARGET/
> 2025-06-27 14:40:02.782 Script: Switch: -privatekey=%%%%.ppk
> 2025-06-27 14:40:02.782 Script: Switch: -passphrase=***
> 2025-06-27 14:40:02.782 Script: Switch: -hostkey=HOSTKEYVALUE=
. 2025-06-27 14:40:02.782 --------------------------------------------------------------------------
. 2025-06-27 14:40:02.782 Session name: SESSIONNAME(Ad-Hoc site)
. 2025-06-27 14:40:02.782 Host name: sftpsomething (Port: #####)
. 2025-06-27 14:40:02.782 User name: USERNAME (Password: No, Key file: Yes, Passphrase: Yes)
. 2025-06-27 14:40:02.782 Tunnel: No
. 2025-06-27 14:40:02.782 Transfer Protocol: SFTP
. 2025-06-27 14:40:02.782 Ping type: Off, Ping interval: 30 sec; Timeout: 15 sec
. 2025-06-27 14:40:02.782 Disable Nagle: No
. 2025-06-27 14:40:02.782 Proxy: None
. 2025-06-27 14:40:02.782 Send buffer: 262144
. 2025-06-27 14:40:02.782 Compression: No
. 2025-06-27 14:40:02.782 Bypass authentication: No
. 2025-06-27 14:40:02.782 Try agent: Yes; Agent forwarding: No; KI: Yes; GSSAPI: Yes
. 2025-06-27 14:40:02.782 GSSAPI: KEX: No; Forwarding: No; Libs: gssapi32,sspi,custom; Custom:
. 2025-06-27 14:40:02.782 Ciphers: aes,chacha20,aesgcm,3des,WARN,des,blowfish,arcfour; Ssh2DES: No
. 2025-06-27 14:40:02.782 KEX: ntru-curve25519,mlkem-curve25519,mlkem-nist,ecdh,dh-gex-sha1,dh-group18-sha512,dh-group17-sha512,dh-group16-sha512,dh-group15-sha512,dh-group14-sha1,rsa,WARN,dh-group1-sha1
. 2025-06-27 14:40:02.782 SSH Bugs: Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto
. 2025-06-27 14:40:02.782 Simple channel: Yes
. 2025-06-27 14:40:02.782 Return code variable: Autodetect; Lookup user groups: Auto
. 2025-06-27 14:40:02.782 Shell: default
. 2025-06-27 14:40:02.782 EOL: LF, UTF: Auto
. 2025-06-27 14:40:02.782 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes; Follow directory symlinks: No
. 2025-06-27 14:40:02.782 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No; Exit code 1 is error: No
. 2025-06-27 14:40:02.782 SFTP Bugs: Auto,Auto
. 2025-06-27 14:40:02.782 SFTP Server: default
. 2025-06-27 14:40:02.782 Local directory: default, Remote directory: home, Update: Yes, Cache: Yes
. 2025-06-27 14:40:02.782 Cache directory changes: Yes, Permanent: Yes
. 2025-06-27 14:40:02.782 Recycle bin: Delete to: No, Overwritten to: No, Bin path:
. 2025-06-27 14:40:02.782 DST mode: Unix
. 2025-06-27 14:40:02.782 --------------------------------------------------------------------------
< 2025-06-27 14:40:02.782 Script: Searching for host...
. 2025-06-27 14:40:02.782 Ancestor processes: cmd, ...
. 2025-06-27 14:40:02.782 Command-line: "C:\Program Files (x86)\WinSCP\WinSCP.EXE" /CONSOLE /SCRIPT="C:\Program Files (x86)\WinSCP\..\..\%%%PUT.txt" /LOG="C:\Program Files (x86)\WinSCP\..\.\%%PUT.log” /loglevel=1
. 2025-06-27 14:40:02.782 Switch: /CONSOLE
. 2025-06-27 14:40:02.782 Switch: /SCRIPT=C:\Program Files (x86)\WinSCP\..\..\%%%PUT.txt
. 2025-06-27 14:40:02.782 Switch: /LOG=C:\Program Files (x86)\WinSCP\..\..\%%PUT.log
. 2025-06-27 14:40:02.782 Switch: /loglevel=1
. 2025-06-27 14:40:02.782 Time zone: Current: GMT+2, Standard: GMT+1 (W. Europe Standard Time), DST: GMT+2 (W. Europe Daylight Time), DST Start: 30-3-2025, DST End: 26-10-2025
. 2025-06-27 14:40:02.782 Login time: vrijdag 27 juni 2025 14:40:02
. 2025-06-27 14:40:02.782 --------------------------------------------------------------------------
. 2025-06-27 14:40:02.782 Script: Retrospectively logging previous script records:
> 2025-06-27 14:40:02.782 Script: OPEN SFTP TARGET/ -privatekey=%%%%.ppk -passphrase=*** -hostkey="HOSTKEYVALUE="
> 2025-06-27 14:40:02.782 Script: Parameter: SFTP TARGET/
> 2025-06-27 14:40:02.782 Script: Switch: -privatekey=%%%%.ppk
> 2025-06-27 14:40:02.782 Script: Switch: -passphrase=***
> 2025-06-27 14:40:02.782 Script: Switch: -hostkey=HOSTKEYVALUE=
. 2025-06-27 14:40:02.782 --------------------------------------------------------------------------
. 2025-06-27 14:40:02.782 Session name: SESSIONNAME(Ad-Hoc site)
. 2025-06-27 14:40:02.782 Host name: sftpsomething (Port: #####)
. 2025-06-27 14:40:02.782 User name: USERNAME (Password: No, Key file: Yes, Passphrase: Yes)
. 2025-06-27 14:40:02.782 Tunnel: No
. 2025-06-27 14:40:02.782 Transfer Protocol: SFTP
. 2025-06-27 14:40:02.782 Ping type: Off, Ping interval: 30 sec; Timeout: 15 sec
. 2025-06-27 14:40:02.782 Disable Nagle: No
. 2025-06-27 14:40:02.782 Proxy: None
. 2025-06-27 14:40:02.782 Send buffer: 262144
. 2025-06-27 14:40:02.782 Compression: No
. 2025-06-27 14:40:02.782 Bypass authentication: No
. 2025-06-27 14:40:02.782 Try agent: Yes; Agent forwarding: No; KI: Yes; GSSAPI: Yes
. 2025-06-27 14:40:02.782 GSSAPI: KEX: No; Forwarding: No; Libs: gssapi32,sspi,custom; Custom:
. 2025-06-27 14:40:02.782 Ciphers: aes,chacha20,aesgcm,3des,WARN,des,blowfish,arcfour; Ssh2DES: No
. 2025-06-27 14:40:02.782 KEX: ntru-curve25519,mlkem-curve25519,mlkem-nist,ecdh,dh-gex-sha1,dh-group18-sha512,dh-group17-sha512,dh-group16-sha512,dh-group15-sha512,dh-group14-sha1,rsa,WARN,dh-group1-sha1
. 2025-06-27 14:40:02.782 SSH Bugs: Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto
. 2025-06-27 14:40:02.782 Simple channel: Yes
. 2025-06-27 14:40:02.782 Return code variable: Autodetect; Lookup user groups: Auto
. 2025-06-27 14:40:02.782 Shell: default
. 2025-06-27 14:40:02.782 EOL: LF, UTF: Auto
. 2025-06-27 14:40:02.782 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes; Follow directory symlinks: No
. 2025-06-27 14:40:02.782 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No; Exit code 1 is error: No
. 2025-06-27 14:40:02.782 SFTP Bugs: Auto,Auto
. 2025-06-27 14:40:02.782 SFTP Server: default
. 2025-06-27 14:40:02.782 Local directory: default, Remote directory: home, Update: Yes, Cache: Yes
. 2025-06-27 14:40:02.782 Cache directory changes: Yes, Permanent: Yes
. 2025-06-27 14:40:02.782 Recycle bin: Delete to: No, Overwritten to: No, Bin path:
. 2025-06-27 14:40:02.782 DST mode: Unix
. 2025-06-27 14:40:02.782 --------------------------------------------------------------------------
< 2025-06-27 14:40:02.782 Script: Searching for host...
. 2025-06-27 14:40:02.798 Looking up host “hostname” for SSH connection
. 2025-06-27 14:40:02.798 Looking up host “hostname” for SSH connection
. 2025-06-27 14:40:03.563 Connecting to %.%.%.% port ######
. 2025-06-27 14:40:03.563 Connecting to %.%.%.% port ######
. 2025-06-27 14:40:03.813 Connected to ip-SOMETHING (FROM IP-SOMETHING)
< 2025-06-27 14:40:03.813 Script: Connecting to host...
. 2025-06-27 14:40:03.813 Connected to ip-SOMETHING (FROM IP-SOMETHING)
< 2025-06-27 14:40:03.813 Script: Connecting to host...
. 2025-06-27 14:40:03.813 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:03.813 We claim version: SSH-2.0-WinSCP_release_6.5.2
. 2025-06-27 14:40:03.813 Detected network event
. 2025-06-27 14:40:03.813 Connected to ip-SOMETHING (FROM IP-SOMETHING)
. 2025-06-27 14:40:03.813 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:03.813 We claim version: SSH-2.0-WinSCP_release_6.5.2
. 2025-06-27 14:40:03.813 Detected network event
. 2025-06-27 14:40:03.813 Connected to ip-SOMETHING (FROM IP-SOMETHING)
. 2025-06-27 14:40:04.110 Detected network event
. 2025-06-27 14:40:04.110 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:04.110 Remote version: SSH-2.0-OpenSSH_5.3
. 2025-06-27 14:40:04.110 We believe remote version has SSH-2 channel request bug
. 2025-06-27 14:40:04.110 Using SSH protocol version 2
. 2025-06-27 14:40:04.110 Have a known host key of type rsa2
. 2025-06-27 14:40:04.126 Detected network event
. 2025-06-27 14:40:04.126 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:04.126 Remote version: SSH-2.0-OpenSSH_5.3
. 2025-06-27 14:40:04.126 We believe remote version has SSH-2 channel request bug
. 2025-06-27 14:40:04.126 Using SSH protocol version 2
. 2025-06-27 14:40:04.126 Have a known host key of type rsa2
. 2025-06-27 14:40:04.360 Detected network event
. 2025-06-27 14:40:04.360 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:04.360 Doing Diffie-Hellman group exchange
. 2025-06-27 14:40:04.376 Detected network event
. 2025-06-27 14:40:04.376 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:04.376 Doing Diffie-Hellman group exchange
. 2025-06-27 14:40:04.610 Detected network event
. 2025-06-27 14:40:04.610 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:04.610 Doing Diffie-Hellman key exchange using 4096-bit modulus and hash SHA-256 with a server-supplied group
. 2025-06-27 14:40:04.610 Detected network event
. 2025-06-27 14:40:04.610 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:04.610 Doing Diffie-Hellman key exchange using 4096-bit modulus and hash SHA-256 with a server-supplied group
. 2025-06-27 14:40:04.829 --------------------------------------------------------------------------
. 2025-06-27 14:40:04.829 WinSCP Version 6.5.2 (Build 16354 2025-06-18) (OS 10.0.14393 – Windows Server 2016 Standard)
. 2025-06-27 14:40:04.829 Configuration: HKCU\Software\Martin Prikryl\WinSCP 2\
. 2025-06-27 14:40:04.829 Log level: Debug 1
. 2025-06-27 14:40:04.829 Local account: %%%%\AdminWinSCP
. 2025-06-27 14:40:04.829 Working directory: C:\WINDOWS\system32
. 2025-06-27 14:40:04.829 Process ID: 5292
. 2025-06-27 14:40:04.845 Ancestor processes: cmd, ...
. 2025-06-27 14:40:04.845 Command-line: "C:\Program Files (x86)\WinSCP\WinSCP.EXE" /CONSOLE /SCRIPT="C:\Program Files (x86)\WinSCP\..\..\%%%PUT.txt" /LOG="C:\Program Files (x86)\WinSCP\..\.\%%PUT.log” /loglevel=1
. 2025-06-27 14:40:04.845 Switch: /CONSOLE
. 2025-06-27 14:40:04.845 Switch: /SCRIPT=C:\Program Files (x86)\WinSCP\..\..\%%%PUT.txt
. 2025-06-27 14:40:04.845 Switch: /LOG=C:\Program Files (x86)\WinSCP\..\..\%%PUT.log
. 2025-06-27 14:40:04.845 Switch: /loglevel=1
. 2025-06-27 14:40:04.845 Time zone: Current: GMT+2, Standard: GMT+1 (W. Europe Standard Time), DST: GMT+2 (W. Europe Daylight Time), DST Start: 30-3-2025, DST End: 26-10-2025
. 2025-06-27 14:40:04.845 Login time: vrijdag 27 juni 2025 14:40:04
. 2025-06-27 14:40:04.845 --------------------------------------------------------------------------
. 2025-06-27 14:40:04.845 Script: Retrospectively logging previous script records:
> 2025-06-27 14:40:04.845 Script: OPEN SFTP TARGET/ -privatekey=%%%%.ppk -passphrase=*** -hostkey="HOSTKEYVALUE="
> 2025-06-27 14:40:04.845 Script: Parameter: SFTP TARGET/
> 2025-06-27 14:40:04.845 Script: Switch: -privatekey=%%%%.ppk
> 2025-06-27 14:40:04.845 Script: Switch: -passphrase=***
> 2025-06-27 14:40:04.845 Script: Switch: -hostkey=HOSTKEYVALUE=
. 2025-06-27 14:40:04.845 --------------------------------------------------------------------------
. 2025-06-27 14:40:04.845 Session name: SESSIONNAME(Ad-Hoc site)
. 2025-06-27 14:40:04.845 Host name: sftpsomething (Port: #####)
. 2025-06-27 14:40:04.845 User name: USERNAME (Password: No, Key file: Yes, Passphrase: Yes)
. 2025-06-27 14:40:04.845 Tunnel: No
. 2025-06-27 14:40:04.845 Transfer Protocol: SFTP
. 2025-06-27 14:40:04.845 Ping type: Off, Ping interval: 30 sec; Timeout: 15 sec
. 2025-06-27 14:40:04.845 Disable Nagle: No
. 2025-06-27 14:40:04.845 Proxy: None
. 2025-06-27 14:40:04.845 Send buffer: 262144
. 2025-06-27 14:40:04.845 Compression: No
. 2025-06-27 14:40:04.845 Bypass authentication: No
. 2025-06-27 14:40:04.845 Try agent: Yes; Agent forwarding: No; KI: Yes; GSSAPI: Yes
. 2025-06-27 14:40:04.845 GSSAPI: KEX: No; Forwarding: No; Libs: gssapi32,sspi,custom; Custom:
. 2025-06-27 14:40:04.845 Ciphers: aes,chacha20,aesgcm,3des,WARN,des,blowfish,arcfour; Ssh2DES: No
. 2025-06-27 14:40:04.845 KEX: ntru-curve25519,mlkem-curve25519,mlkem-nist,ecdh,dh-gex-sha1,dh-group18-sha512,dh-group17-sha512,dh-group16-sha512,dh-group15-sha512,dh-group14-sha1,rsa,WARN,dh-group1-sha1
. 2025-06-27 14:40:04.845 SSH Bugs: Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto
. 2025-06-27 14:40:04.845 Simple channel: Yes
. 2025-06-27 14:40:04.845 Return code variable: Autodetect; Lookup user groups: Auto
. 2025-06-27 14:40:04.845 Shell: default
. 2025-06-27 14:40:04.845 EOL: LF, UTF: Auto
. 2025-06-27 14:40:04.845 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes; Follow directory symlinks: No
. 2025-06-27 14:40:04.845 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No; Exit code 1 is error: No
. 2025-06-27 14:40:04.845 SFTP Bugs: Auto,Auto
. 2025-06-27 14:40:04.845 SFTP Server: default
. 2025-06-27 14:40:04.845 Local directory: default, Remote directory: home, Update: Yes, Cache: Yes
. 2025-06-27 14:40:04.845 Cache directory changes: Yes, Permanent: Yes
. 2025-06-27 14:40:04.845 Recycle bin: Delete to: No, Overwritten to: No, Bin path:
. 2025-06-27 14:40:04.845 DST mode: Unix
. 2025-06-27 14:40:04.845 --------------------------------------------------------------------------
< 2025-06-27 14:40:04.845 Script: Searching for host...
. 2025-06-27 14:40:04.845 Looking up host “hostname” for SSH connection
. 2025-06-27 14:40:04.845 Connecting to %.%.%.% port ######
. 2025-06-27 14:40:05.110 Connected to IP-SOMETHING (FROM IPSOMETHING)
< 2025-06-27 14:40:05.110 Script: Connecting to host...
. 2025-06-27 14:40:05.110 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:05.110 We claim version: SSH-2.0-WinSCP_release_6.5.2
. 2025-06-27 14:40:05.110 Detected network event
. 2025-06-27 14:40:05.110 Connected to IP-SOMETHING (FROM IPSOMETHING)
. 2025-06-27 14:40:05.376 Detected network event
. 2025-06-27 14:40:05.376 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:05.376 Remote version: SSH-2.0-OpenSSH_5.3
. 2025-06-27 14:40:05.376 We believe remote version has SSH-2 channel request bug
. 2025-06-27 14:40:05.376 Using SSH protocol version 2
. 2025-06-27 14:40:05.376 Have a known host key of type rsa2
. 2025-06-27 14:40:05.641 Detected network event
. 2025-06-27 14:40:05.641 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:05.641 Doing Diffie-Hellman group exchange
. 2025-06-27 14:40:05.891 Detected network event
. 2025-06-27 14:40:05.891 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:05.891 Doing Diffie-Hellman key exchange using 4096-bit modulus and hash SHA-256 with a server-supplied group
. 2025-06-27 14:40:06.391 Detected network event
. 2025-06-27 14:40:06.391 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:06.391 Detected network event
. 2025-06-27 14:40:06.391 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:07.579 Detected network event
. 2025-06-27 14:40:07.579 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:07.829 Server also has ssh-dss host key, but we don't know it
. 2025-06-27 14:40:07.829 Host key fingerprint is:
. 2025-06-27 14:40:07.829 ssh-rsa SOMETHING
. 2025-06-27 14:40:07.829 Verifying host key …………….. with fingerprints ssh-rsa SOMETHING, ssh-rsa ……………………….
< 2025-06-27 14:40:07.829 Script: Authenticating...
. 2025-06-27 14:40:07.829 Host key matches configured key fingerprint
. 2025-06-27 14:40:07.829 Initialised AES-256 SDCTR (AES-NI accelerated) [aes256-ctr] outbound encryption
. 2025-06-27 14:40:07.829 Initialised HMAC-SHA-256 outbound MAC algorithm
. 2025-06-27 14:40:07.829 Initialised AES-256 SDCTR (AES-NI accelerated) [aes256-ctr] inbound encryption
. 2025-06-27 14:40:07.829 Initialised HMAC-SHA-256 inbound MAC algorithm
. 2025-06-27 14:40:07.829 Server also has ssh-dss host key, but we don't know it
. 2025-06-27 14:40:07.829 Host key fingerprint is:
. 2025-06-27 14:40:07.829 ssh-rsa SOMETHING
. 2025-06-27 14:40:07.829 Verifying host key …………….. with fingerprints ssh-rsa SOMETHING, ssh-rsa …………………….
< 2025-06-27 14:40:07.829 Script: Authenticating...
. 2025-06-27 14:40:07.829 Host key matches configured key fingerprint
. 2025-06-27 14:40:07.829 Initialised AES-256 SDCTR (AES-NI accelerated) [aes256-ctr] outbound encryption
. 2025-06-27 14:40:07.829 Initialised HMAC-SHA-256 outbound MAC algorithm
. 2025-06-27 14:40:07.829 Initialised AES-256 SDCTR (AES-NI accelerated) [aes256-ctr] inbound encryption
. 2025-06-27 14:40:07.829 Initialised HMAC-SHA-256 inbound MAC algorithm
. 2025-06-27 14:40:08.079 Detected network event
. 2025-06-27 14:40:08.079 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:08.079 Reading key file "keyfile"
. 2025-06-27 14:40:08.079 Detected network event
. 2025-06-27 14:40:08.079 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:08.079 Reading key file "keyfile"
! 2025-06-27 14:40:08.095 Using username "USERNAME".
< 2025-06-27 14:40:08.095 Script: Using username "USERNAME".
! 2025-06-27 14:40:08.095 Using username "USERNAME".
< 2025-06-27 14:40:08.095 Script: Using username "USERNAME".
. 2025-06-27 14:40:08.345 Detected network event
. 2025-06-27 14:40:08.345 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:08.345 Server offered these authentication methods: publickey,gssapi-keyex,gssapi-with-mic,password
. 2025-06-27 14:40:08.345 Offered public key
. 2025-06-27 14:40:08.579 Detected network event
. 2025-06-27 14:40:08.579 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:08.579 Server offered these authentication methods: publickey,gssapi-keyex,gssapi-with-mic,password
. 2025-06-27 14:40:08.579 Offered public key
. 2025-06-27 14:40:08.595 Detected network event
. 2025-06-27 14:40:08.595 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:08.595 Offer of public key accepted
! 2025-06-27 14:40:08.595 Authenticating with public key "%%%%%"
< 2025-06-27 14:40:08.595 Script: Authenticating with public key "%%%%%%".
. 2025-06-27 14:40:08.595 Prompt (passphrase, "SSH key passphrase", <no instructions>, "Passphrase for key "KEY": ")
. 2025-06-27 14:40:08.595 Using configured passphrase.
. 2025-06-27 14:40:08.688 Sent public key signature
. 2025-06-27 14:40:08.860 Detected network event
. 2025-06-27 14:40:08.860 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:08.860 Offer of public key accepted
! 2025-06-27 14:40:08.860 Authenticating with public key "%%%%%"
< 2025-06-27 14:40:08.860 Script: Authenticating with public key "%%%%%".
. 2025-06-27 14:40:08.860 Prompt (passphrase, "SSH key passphrase", <no instructions>, "Passphrase for key "%%%%%": ")
. 2025-06-27 14:40:08.860 Using configured passphrase.
. 2025-06-27 14:40:08.954 Detected network event
. 2025-06-27 14:40:08.954 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:08.954 Access granted
. 2025-06-27 14:40:08.954 Opening main session channel
. 2025-06-27 14:40:08.954 Sent public key signature
. 2025-06-27 14:40:09.048 Server also has ssh-dss host key, but we don't know it
. 2025-06-27 14:40:09.048 Host key fingerprint is:
. 2025-06-27 14:40:09.048 ssh-rsa SOMETHING
. 2025-06-27 14:40:09.048 Verifying host key %%%%% with fingerprints ssh-rsa SOMETHING, ssh-rsa %%%%%%%
< 2025-06-27 14:40:09.048 Script: Authenticating...
. 2025-06-27 14:40:09.048 Host key matches configured key fingerprint
. 2025-06-27 14:40:09.048 Initialised AES-256 SDCTR (AES-NI accelerated) [aes256-ctr] outbound encryption
. 2025-06-27 14:40:09.048 Initialised HMAC-SHA-256 outbound MAC algorithm
. 2025-06-27 14:40:09.048 Initialised AES-256 SDCTR (AES-NI accelerated) [aes256-ctr] inbound encryption
. 2025-06-27 14:40:09.048 Initialised HMAC-SHA-256 inbound MAC algorithm
. 2025-06-27 14:40:09.188 Detected network event
. 2025-06-27 14:40:09.188 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:09.188 Opened main channel
. 2025-06-27 14:40:09.220 Detected network event
. 2025-06-27 14:40:09.220 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:09.220 Access granted
. 2025-06-27 14:40:09.220 Opening main session channel
. 2025-06-27 14:40:09.298 Detected network event
. 2025-06-27 14:40:09.298 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:09.298 Reading key file "KEYFILE"
! 2025-06-27 14:40:09.298 Using username "USERNAME".
< 2025-06-27 14:40:09.298 Script: Using username "USERNAME".
. 2025-06-27 14:40:09.441 Detected network event
. 2025-06-27 14:40:09.441 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:09.441 Started a shell/command
< 2025-06-27 14:40:09.441 Script: Authenticated.
. 2025-06-27 14:40:09.441 --------------------------------------------------------------------------
. 2025-06-27 14:40:09.441 Using SFTP protocol.
. 2025-06-27 14:40:09.441 Doing startup conversation with host.
< 2025-06-27 14:40:09.441 Script: Starting the session...
. 2025-06-27 14:40:09.441 Well known server, allowing SFTP version 6.
> 2025-06-27 14:40:09.441 Type: SSH_FXP_INIT, Size: 5, Number: -1
. 2025-06-27 14:40:09.441 Sent 9 bytes
. 2025-06-27 14:40:09.441 There are 0 bytes remaining in the send buffer
. 2025-06-27 14:40:09.441 Waiting for another 4 bytes
. 2025-06-27 14:40:09.472 Detected network event
. 2025-06-27 14:40:09.472 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:09.472 Opened main channel
. 2025-06-27 14:40:09.566 Detected network event
. 2025-06-27 14:40:09.566 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:09.566 Server offered these authentication methods: publickey,gssapi-keyex,gssapi-with-mic,password
. 2025-06-27 14:40:09.566 Offered public key
. 2025-06-27 14:40:09.691 Detected network event
. 2025-06-27 14:40:09.691 Waiting for another 4 bytes
. 2025-06-27 14:40:09.691 Received 99 bytes
. 2025-06-27 14:40:09.691 Read 4 bytes (95 pending)
. 2025-06-27 14:40:09.691 Read 95 bytes (0 pending)
< 2025-06-27 14:40:09.691 Type: SSH_FXP_VERSION, Size: 95, Number: -1
. 2025-06-27 14:40:09.691 SFTP version 3 negotiated.
. 2025-06-27 14:40:09.691 Supports extension posix-rename@openssh.com="1"
. 2025-06-27 14:40:09.691 Supports statvfs@openssh.com extension version 2
. 2025-06-27 14:40:09.691 Unknown server extension fstatvfs@openssh.com="2"
. 2025-06-27 14:40:09.691 We believe the server has signed timestamps bug
. 2025-06-27 14:40:09.691 We will use UTF-8 strings until server sends an invalid UTF-8 string as with SFTP version 3 and older UTF-8 strings are not mandatory
. 2025-06-27 14:40:09.691 Limiting packet size to OpenSSH sftp-server limit of 262148 bytes
. 2025-06-27 14:40:09.691 Getting current directory name.
. 2025-06-27 14:40:09.691 Getting real path for '.'
> 2025-06-27 14:40:09.691 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
. 2025-06-27 14:40:09.691 Sent 14 bytes
. 2025-06-27 14:40:09.691 There are 0 bytes remaining in the send buffer
. 2025-06-27 14:40:09.691 Waiting for another 4 bytes
. 2025-06-27 14:40:09.722 Detected network event
. 2025-06-27 14:40:09.722 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:09.722 Started a shell/command
< 2025-06-27 14:40:09.722 Script: Authenticated.
. 2025-06-27 14:40:09.722 --------------------------------------------------------------------------
. 2025-06-27 14:40:09.722 Using SFTP protocol.
. 2025-06-27 14:40:09.722 Doing startup conversation with host.
< 2025-06-27 14:40:09.722 Script: Starting the session...
. 2025-06-27 14:40:09.722 Well known server, allowing SFTP version 6.
> 2025-06-27 14:40:09.722 Type: SSH_FXP_INIT, Size: 5, Number: -1
. 2025-06-27 14:40:09.722 Sent 9 bytes
. 2025-06-27 14:40:09.722 There are 0 bytes remaining in the send buffer
. 2025-06-27 14:40:09.722 Waiting for another 4 bytes
. 2025-06-27 14:40:09.816 Detected network event
. 2025-06-27 14:40:09.816 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:09.816 Offer of public key accepted
! 2025-06-27 14:40:09.816 Authenticating with public key "KEY"
< 2025-06-27 14:40:09.816 Script: Authenticating with public key "KEY".
. 2025-06-27 14:40:09.816 Prompt (passphrase, "SSH key passphrase", <no instructions>, "Passphrase for key "KEY": ")
. 2025-06-27 14:40:09.816 Using configured passphrase.
. 2025-06-27 14:40:09.909 Sent public key signature
. 2025-06-27 14:40:09.941 Detected network event
. 2025-06-27 14:40:09.941 Waiting for another 4 bytes
. 2025-06-27 14:40:09.941 Received 27 bytes
. 2025-06-27 14:40:09.941 Read 4 bytes (23 pending)
. 2025-06-27 14:40:09.941 Read 23 bytes (0 pending)
< 2025-06-27 14:40:09.941 Type: SSH_FXP_NAME, Size: 23, Number: 16
. 2025-06-27 14:40:09.941 Real path is '/'
. 2025-06-27 14:40:09.941 Startup conversation with host finished.
< 2025-06-27 14:40:09.941 Script: Session started.
< 2025-06-27 14:40:09.941 Script: Active session: [1] USERNAME@SFTPSOMETHING
. 2025-06-27 14:40:09.941 Session upkeep
> 2025-06-27 14:40:09.941 Script: CD receive
> 2025-06-27 14:40:09.941 Script: Parameter: receive
. 2025-06-27 14:40:09.941 Cached directory change via "receive" to "/receive".
. 2025-06-27 14:40:09.941 Getting current directory name.
< 2025-06-27 14:40:09.941 Script: /receive
. 2025-06-27 14:40:09.941 Session upkeep
> 2025-06-27 14:40:09.941 Script: LCD C:\..\..\Out
> 2025-06-27 14:40:09.941 Script: Parameter: C:\..\..\Out
< 2025-06-27 14:40:09.941 Script: C:\..\..\Out
. 2025-06-27 14:40:09.941 Session upkeep
> 2025-06-27 14:40:09.941 Script: PUT -nopreservetime -nopermissions -append FILENAME1*.* FILENAME1_20250627144009.tmp
> 2025-06-27 14:40:09.941 Script: Switch: -nopreservetime
> 2025-06-27 14:40:09.941 Script: Switch: -nopermissions
> 2025-06-27 14:40:09.941 Script: Switch: -append
> 2025-06-27 14:40:09.941 Script: Parameter: FILENAME1*.*
> 2025-06-27 14:40:09.941 Script: Parameter: FILENAME1_20250627144009.tmp
< 2025-06-27 14:40:09.941 Script: No file matching 'FILENAME1*.*' found.
. 2025-06-27 14:40:09.941 Size of 0 local files/folders calculated as 0
. 2025-06-27 14:40:09.941 Copying 0 files/directories to remote directory "/receive" - total size: 0
. 2025-06-27 14:40:09.941 PrTime: No; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0001; Resume: S (102400); CalcS: No; Mask: FILENAME1_20250627144009.tmp
. 2025-06-27 14:40:09.941 TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; EncryptNewFiles: Yes; ExcludeHiddenFiles: No; ExcludeEmptyDirectories: No; InclM: ; ResumeL: 0
. 2025-06-27 14:40:09.941 AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2025-06-27 14:40:09.941 Copying finished: Transferred: 0, Elapsed: 0:00:00, CPS: 0/s
. 2025-06-27 14:40:09.941 Session upkeep
> 2025-06-27 14:40:09.941 Script: PUT -nopreservetime -nopermissions -resumesupport=off -append FILENAME2*.* FILENAME2_20250627144009.tmp
> 2025-06-27 14:40:09.941 Script: Switch: -nopreservetime
> 2025-06-27 14:40:09.941 Script: Switch: -nopermissions
> 2025-06-27 14:40:09.941 Script: Switch: -resumesupport=off
> 2025-06-27 14:40:09.941 Script: Switch: -append
> 2025-06-27 14:40:09.941 Script: Parameter: FILENAME2*.*
> 2025-06-27 14:40:09.941 Script: Parameter: FILENAME2_20250627144009.tmp
< 2025-06-27 14:40:09.941 Script: No file matching 'FILENAME2*.*' found.
. 2025-06-27 14:40:09.941 Size of 0 local files/folders calculated as 0
. 2025-06-27 14:40:09.941 Copying 0 files/directories to remote directory "/receive" - total size: 0
. 2025-06-27 14:40:09.941 PrTime: No; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0001; Resume: N (102400); CalcS: No; Mask: FILENAME2_20250627144009.tmp
. 2025-06-27 14:40:09.941 TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; EncryptNewFiles: Yes; ExcludeHiddenFiles: No; ExcludeEmptyDirectories: No; InclM: ; ResumeL: 0
. 2025-06-27 14:40:09.941 AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2025-06-27 14:40:09.941 Copying finished: Transferred: 0, Elapsed: 0:00:00, CPS: 0/s
. 2025-06-27 14:40:09.941 Session upkeep
> 2025-06-27 14:40:09.941 Script: PUT -nopreservetime -nopermissions -append FILENAME3*.* FILENAME3_20250627144009.tmp
> 2025-06-27 14:40:09.941 Script: Switch: -nopreservetime
> 2025-06-27 14:40:09.941 Script: Switch: -nopermissions
> 2025-06-27 14:40:09.941 Script: Switch: -append
> 2025-06-27 14:40:09.941 Script: Parameter: FILENAME3*.*
> 2025-06-27 14:40:09.941 Script: Parameter: FILENAME3_20250627144009.tmp
< 2025-06-27 14:40:09.941 Script: No file matching 'FILENAME3*.*' found.
. 2025-06-27 14:40:09.941 Size of 0 local files/folders calculated as 0
. 2025-06-27 14:40:09.941 Copying 0 files/directories to remote directory "/receive" - total size: 0
. 2025-06-27 14:40:09.941 PrTime: No; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0001; Resume: S (102400); CalcS: No; Mask: FILENAME3_20250627144009.tmp
. 2025-06-27 14:40:09.941 TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; EncryptNewFiles: Yes; ExcludeHiddenFiles: No; ExcludeEmptyDirectories: No; InclM: ; ResumeL: 0
. 2025-06-27 14:40:09.941 AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2025-06-27 14:40:09.941 Copying finished: Transferred: 0, Elapsed: 0:00:00, CPS: 0/s
. 2025-06-27 14:40:09.941 Session upkeep
> 2025-06-27 14:40:09.941 Script: MV *.tmp *.txt
> 2025-06-27 14:40:09.941 Script: Parameter: *.tmp
> 2025-06-27 14:40:09.941 Script: Parameter: *.txt
. 2025-06-27 14:40:09.941 Listing directory "/receive".
> 2025-06-27 14:40:09.941 Type: SSH_FXP_OPENDIR, Size: 17, Number: 267
. 2025-06-27 14:40:09.941 Sent 21 bytes
. 2025-06-27 14:40:09.941 There are 0 bytes remaining in the send buffer
. 2025-06-27 14:40:09.941 Waiting for another 4 bytes
. 2025-06-27 14:40:09.972 Detected network event
. 2025-06-27 14:40:09.972 Waiting for another 4 bytes
. 2025-06-27 14:40:09.972 Received 99 bytes
. 2025-06-27 14:40:09.972 Read 4 bytes (95 pending)
. 2025-06-27 14:40:09.972 Read 95 bytes (0 pending)
< 2025-06-27 14:40:09.972 Type: SSH_FXP_VERSION, Size: 95, Number: -1
. 2025-06-27 14:40:09.972 SFTP version 3 negotiated.
. 2025-06-27 14:40:09.972 Supports extension posix-rename@openssh.com="1"
. 2025-06-27 14:40:09.972 Supports statvfs@openssh.com extension version 2
. 2025-06-27 14:40:09.972 Unknown server extension fstatvfs@openssh.com="2"
. 2025-06-27 14:40:09.972 We believe the server has signed timestamps bug
. 2025-06-27 14:40:09.972 We will use UTF-8 strings until server sends an invalid UTF-8 string as with SFTP version 3 and older UTF-8 strings are not mandatory
. 2025-06-27 14:40:09.972 Limiting packet size to OpenSSH sftp-server limit of 262148 bytes
. 2025-06-27 14:40:09.972 Getting current directory name.
. 2025-06-27 14:40:09.972 Getting real path for '.'
> 2025-06-27 14:40:09.972 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
. 2025-06-27 14:40:09.972 Sent 14 bytes
. 2025-06-27 14:40:09.972 There are 0 bytes remaining in the send buffer
. 2025-06-27 14:40:09.972 Waiting for another 4 bytes
. 2025-06-27 14:40:10.191 Detected network event
. 2025-06-27 14:40:10.191 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:10.191 Access granted
. 2025-06-27 14:40:10.191 Opening main session channel
. 2025-06-27 14:40:10.191 Detected network event
. 2025-06-27 14:40:10.191 Waiting for another 4 bytes
. 2025-06-27 14:40:10.191 Received 17 bytes
. 2025-06-27 14:40:10.191 Read 4 bytes (13 pending)
. 2025-06-27 14:40:10.191 Read 13 bytes (0 pending)
< 2025-06-27 14:40:10.191 Type: SSH_FXP_HANDLE, Size: 13, Number: 267
> 2025-06-27 14:40:10.191 Type: SSH_FXP_READDIR, Size: 13, Number: 524
. 2025-06-27 14:40:10.191 Sent 17 bytes
. 2025-06-27 14:40:10.191 There are 0 bytes remaining in the send buffer
. 2025-06-27 14:40:10.191 Waiting for another 4 bytes
. 2025-06-27 14:40:10.228 Detected network event
. 2025-06-27 14:40:10.228 Waiting for another 4 bytes
. 2025-06-27 14:40:10.228 Received 27 bytes
. 2025-06-27 14:40:10.228 Read 4 bytes (23 pending)
. 2025-06-27 14:40:10.228 Read 23 bytes (0 pending)
< 2025-06-27 14:40:10.228 Type: SSH_FXP_NAME, Size: 23, Number: 16
. 2025-06-27 14:40:10.228 Real path is '/'
. 2025-06-27 14:40:10.228 Startup conversation with host finished.
< 2025-06-27 14:40:10.228 Script: Session started.
< 2025-06-27 14:40:10.228 Script: Active session: [1] USERNAME@SFTPSOMETHING
. 2025-06-27 14:40:10.228 Session upkeep
> 2025-06-27 14:40:10.228 Script: CD receive
> 2025-06-27 14:40:10.228 Script: Parameter: receive
. 2025-06-27 14:40:10.228 Cached directory change via "receive" to "/receive".
. 2025-06-27 14:40:10.228 Getting current directory name.
< 2025-06-27 14:40:10.228 Script: /receive
. 2025-06-27 14:40:10.228 Session upkeep
> 2025-06-27 14:40:10.228 Script: LCD C:\..\..\Out
> 2025-06-27 14:40:10.228 Script: Parameter: C:\..\..\Out
< 2025-06-27 14:40:10.228 Script: C:\..\..\Out
. 2025-06-27 14:40:10.228 Session upkeep
> 2025-06-27 14:40:10.228 Script: PUT -nopreservetime -nopermissions -append FILENAME1*.* FILENAME1_20250627144010.tmp
> 2025-06-27 14:40:10.228 Script: Switch: -nopreservetime
> 2025-06-27 14:40:10.228 Script: Switch: -nopermissions
> 2025-06-27 14:40:10.228 Script: Switch: -append
> 2025-06-27 14:40:10.228 Script: Parameter: FILENAME1*.*
> 2025-06-27 14:40:10.228 Script: Parameter: FILENAME1_20250627144010.tmp
< 2025-06-27 14:40:10.228 Script: No file matching 'FILENAME1*.*' found.
. 2025-06-27 14:40:10.228 Size of 0 local files/folders calculated as 0
. 2025-06-27 14:40:10.228 Copying 0 files/directories to remote directory "/receive" - total size: 0
. 2025-06-27 14:40:10.228 PrTime: No; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0001; Resume: S (102400); CalcS: No; Mask: FILENAME1_20250627144010.tmp
. 2025-06-27 14:40:10.228 TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; EncryptNewFiles: Yes; ExcludeHiddenFiles: No; ExcludeEmptyDirectories: No; InclM: ; ResumeL: 0
. 2025-06-27 14:40:10.228 AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2025-06-27 14:40:10.228 Copying finished: Transferred: 0, Elapsed: 0:00:00, CPS: 0/s
. 2025-06-27 14:40:10.228 Session upkeep
> 2025-06-27 14:40:10.228 Script: PUT -nopreservetime -nopermissions -resumesupport=off -append FILENAME2*.* FILENAME2_20250627144010.tmp
> 2025-06-27 14:40:10.228 Script: Switch: -nopreservetime
> 2025-06-27 14:40:10.228 Script: Switch: -nopermissions
> 2025-06-27 14:40:10.228 Script: Switch: -resumesupport=off
> 2025-06-27 14:40:10.228 Script: Switch: -append
> 2025-06-27 14:40:10.228 Script: Parameter: FILENAME2*.*
> 2025-06-27 14:40:10.228 Script: Parameter: FILENAME2_20250627144010.tmp
< 2025-06-27 14:40:10.228 Script: No file matching 'FILENAME2*.*' found.
. 2025-06-27 14:40:10.228 Size of 0 local files/folders calculated as 0
. 2025-06-27 14:40:10.228 Copying 0 files/directories to remote directory "/receive" - total size: 0
. 2025-06-27 14:40:10.228 PrTime: No; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0001; Resume: N (102400); CalcS: No; Mask: FILENAME2_20250627144010.tmp
. 2025-06-27 14:40:10.228 TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; EncryptNewFiles: Yes; ExcludeHiddenFiles: No; ExcludeEmptyDirectories: No; InclM: ; ResumeL: 0
. 2025-06-27 14:40:10.228 AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2025-06-27 14:40:10.228 Copying finished: Transferred: 0, Elapsed: 0:00:00, CPS: 0/s
. 2025-06-27 14:40:10.228 Session upkeep
> 2025-06-27 14:40:10.228 Script: PUT -nopreservetime -nopermissions -append FILENAME3*.* FILENAME3_20250627144010.tmp
> 2025-06-27 14:40:10.228 Script: Switch: -nopreservetime
> 2025-06-27 14:40:10.228 Script: Switch: -nopermissions
> 2025-06-27 14:40:10.228 Script: Switch: -append
> 2025-06-27 14:40:10.228 Script: Parameter: FILENAME3*.*
> 2025-06-27 14:40:10.228 Script: Parameter: FILENAME3_20250627144010.tmp
< 2025-06-27 14:40:10.228 Script: No file matching 'FILENAME3*.*' found.
. 2025-06-27 14:40:10.228 Size of 0 local files/folders calculated as 0
. 2025-06-27 14:40:10.228 Copying 0 files/directories to remote directory "/receive" - total size: 0
. 2025-06-27 14:40:10.228 PrTime: No; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0001; Resume: S (102400); CalcS: No; Mask: FILENAME3_20250627144010.tmp
. 2025-06-27 14:40:10.228 TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; EncryptNewFiles: Yes; ExcludeHiddenFiles: No; ExcludeEmptyDirectories: No; InclM: ; ResumeL: 0
. 2025-06-27 14:40:10.228 AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2025-06-27 14:40:10.228 Copying finished: Transferred: 0, Elapsed: 0:00:00, CPS: 0/s
. 2025-06-27 14:40:10.228 Session upkeep
> 2025-06-27 14:40:10.228 Script: MV *.tmp *.txt
> 2025-06-27 14:40:10.228 Script: Parameter: *.tmp
> 2025-06-27 14:40:10.228 Script: Parameter: *.txt
. 2025-06-27 14:40:10.228 Listing directory "/receive".
> 2025-06-27 14:40:10.228 Type: SSH_FXP_OPENDIR, Size: 17, Number: 267
. 2025-06-27 14:40:10.228 Sent 21 bytes
. 2025-06-27 14:40:10.228 There are 0 bytes remaining in the send buffer
. 2025-06-27 14:40:10.228 Waiting for another 4 bytes
. 2025-06-27 14:40:10.441 Detected network event
. 2025-06-27 14:40:10.441 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:10.441 Opened main channel
. 2025-06-27 14:40:10.441 Detected network event
. 2025-06-27 14:40:10.441 Waiting for another 4 bytes
. 2025-06-27 14:40:10.441 Received 211 bytes
. 2025-06-27 14:40:10.441 Read 4 bytes (207 pending)
. 2025-06-27 14:40:10.441 Read 207 bytes (0 pending)
< 2025-06-27 14:40:10.441 Type: SSH_FXP_NAME, Size: 207, Number: 524
> 2025-06-27 14:40:10.441 Type: SSH_FXP_READDIR, Size: 13, Number: 780
. 2025-06-27 14:40:10.441 Sent 17 bytes
. 2025-06-27 14:40:10.441 There are 0 bytes remaining in the send buffer
. 2025-06-27 14:40:10.441 Read file '..' from listing
. 2025-06-27 14:40:10.441 Read file '.' from listing
. 2025-06-27 14:40:10.441 Waiting for another 4 bytes
. 2025-06-27 14:40:10.473 Detected network event
. 2025-06-27 14:40:10.473 Waiting for another 4 bytes
. 2025-06-27 14:40:10.473 Received 17 bytes
. 2025-06-27 14:40:10.473 Read 4 bytes (13 pending)
. 2025-06-27 14:40:10.473 Read 13 bytes (0 pending)
< 2025-06-27 14:40:10.473 Type: SSH_FXP_HANDLE, Size: 13, Number: 267
> 2025-06-27 14:40:10.473 Type: SSH_FXP_READDIR, Size: 13, Number: 524
. 2025-06-27 14:40:10.473 Sent 17 bytes
. 2025-06-27 14:40:10.473 There are 0 bytes remaining in the send buffer
. 2025-06-27 14:40:10.473 Waiting for another 4 bytes
. 2025-06-27 14:40:10.691 Detected network event
. 2025-06-27 14:40:10.691 Waiting for another 4 bytes
. 2025-06-27 14:40:10.691 Received 32 bytes
. 2025-06-27 14:40:10.691 Read 4 bytes (28 pending)
. 2025-06-27 14:40:10.691 Read 28 bytes (0 pending)
< 2025-06-27 14:40:10.691 Type: SSH_FXP_STATUS, Size: 28, Number: 780
< 2025-06-27 14:40:10.691 Status code: 1
> 2025-06-27 14:40:10.691 Type: SSH_FXP_CLOSE, Size: 13, Number: 1028
. 2025-06-27 14:40:10.691 Sent 17 bytes
. 2025-06-27 14:40:10.691 There are 0 bytes remaining in the send buffer
. 2025-06-27 14:40:10.691 ..;d;0;2025-02-04T00:47:54.000Z;4;"0" [0];"0" [0];rwxr-xr-x;0
. 2025-06-27 14:40:10.691 .;d;0;2025-06-27T12:33:13.000Z;4;"531" [531];"502" [502];rwxr-xr-x;0
< 2025-06-27 14:40:10.691 Script: No file matching '*.tmp' found.
. 2025-06-27 14:40:10.691 Session upkeep
> 2025-06-27 14:40:10.691 Script: EXIT
. 2025-06-27 14:40:10.691 Session upkeep
. 2025-06-27 14:40:10.691 Script: Exit code: 0
. 2025-06-27 14:40:10.691 Closing connection.
. 2025-06-27 14:40:10.691 Sending special code: 1
. 2025-06-27 14:40:10.691 Detected network event
. 2025-06-27 14:40:10.691 Waiting for the server to continue with the initialization
. 2025-06-27 14:40:10.691 Started a shell/command
< 2025-06-27 14:40:10.691 Script: Authenticated.
. 2025-06-27 14:40:10.691 --------------------------------------------------------------------------
. 2025-06-27 14:40:10.691 Using SFTP protocol.
. 2025-06-27 14:40:10.691 Doing startup conversation with host.
< 2025-06-27 14:40:10.691 Script: Starting the session...
. 2025-06-27 14:40:10.691 Well known server, allowing SFTP version 6.
> 2025-06-27 14:40:10.691 Type: SSH_FXP_INIT, Size: 5, Number: -1
. 2025-06-27 14:40:10.691 Sent 9 bytes
. 2025-06-27 14:40:10.691 There are 0 bytes remaining in the send buffer
. 2025-06-27 14:40:10.691 Waiting for another 4 bytes
. 2025-06-27 14:40:10.723 Detected network event
. 2025-06-27 14:40:10.723 Waiting for another 4 bytes
. 2025-06-27 14:40:10.723 Received 211 bytes
. 2025-06-27 14:40:10.723 Read 4 bytes (207 pending)
. 2025-06-27 14:40:10.723 Read 207 bytes (0 pending)
< 2025-06-27 14:40:10.723 Type: SSH_FXP_NAME, Size: 207, Number: 524
> 2025-06-27 14:40:10.723 Type: SSH_FXP_READDIR, Size: 13, Number: 780
. 2025-06-27 14:40:10.723 Sent 17 bytes
. 2025-06-27 14:40:10.723 There are 0 bytes remaining in the send buffer
. 2025-06-27 14:40:10.723 Read file '..' from listing
. 2025-06-27 14:40:10.723 Read file '.' from listing
. 2025-06-27 14:40:10.723 Waiting for another 4 bytes
. 2025-06-27 14:40:10.926 Detected network event
. 2025-06-27 14:40:10.926 Received 28 bytes
. 2025-06-27 14:40:10.957 Detected network event
. 2025-06-27 14:40:10.957 Waiting for another 4 bytes
. 2025-06-27 14:40:10.957 Received 99 bytes
. 2025-06-27 14:40:10.957 Read 4 bytes (95 pending)
. 2025-06-27 14:40:10.957 Read 95 bytes (0 pending)
< 2025-06-27 14:40:10.957 Type: SSH_FXP_VERSION, Size: 95, Number: -1
. 2025-06-27 14:40:10.957 SFTP version 3 negotiated.
. 2025-06-27 14:40:10.957 Supports extension posix-rename@openssh.com="1"
. 2025-06-27 14:40:10.957 Supports statvfs@openssh.com extension version 2
. 2025-06-27 14:40:10.957 Unknown server extension fstatvfs@openssh.com="2"
. 2025-06-27 14:40:10.957 We believe the server has signed timestamps bug
. 2025-06-27 14:40:10.957 We will use UTF-8 strings until server sends an invalid UTF-8 string as with SFTP version 3 and older UTF-8 strings are not mandatory
. 2025-06-27 14:40:10.957 Limiting packet size to OpenSSH sftp-server limit of 262148 bytes
. 2025-06-27 14:40:10.957 Getting current directory name.
. 2025-06-27 14:40:10.957 Getting real path for '.'
> 2025-06-27 14:40:10.957 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
. 2025-06-27 14:40:10.957 Sent 14 bytes
. 2025-06-27 14:40:10.957 There are 0 bytes remaining in the send buffer
. 2025-06-27 14:40:10.957 Waiting for another 4 bytes
. 2025-06-27 14:40:10.973 Detected network event
. 2025-06-27 14:40:10.973 Waiting for another 4 bytes
. 2025-06-27 14:40:10.973 Received 32 bytes
. 2025-06-27 14:40:10.973 Read 4 bytes (28 pending)
. 2025-06-27 14:40:10.973 Read 28 bytes (0 pending)
< 2025-06-27 14:40:10.973 Type: SSH_FXP_STATUS, Size: 28, Number: 780
< 2025-06-27 14:40:10.973 Status code: 1
> 2025-06-27 14:40:10.973 Type: SSH_FXP_CLOSE, Size: 13, Number: 1028
. 2025-06-27 14:40:10.973 Sent 17 bytes
. 2025-06-27 14:40:10.973 There are 0 bytes remaining in the send buffer
. 2025-06-27 14:40:10.973 ..;d;0;2025-02-04T00:47:54.000Z;4;"0" [0];"0" [0];rwxr-xr-x;0
. 2025-06-27 14:40:10.973 .;d;0;2025-06-27T12:33:13.000Z;4;"531" [531];"502" [502];rwxr-xr-x;0
< 2025-06-27 14:40:10.973 Script: No file matching '*.tmp' found.
. 2025-06-27 14:40:10.973 Session upkeep
> 2025-06-27 14:40:10.973 Script: EXIT
. 2025-06-27 14:40:10.973 Session upkeep
. 2025-06-27 14:40:10.973 Script: Exit code: 0
. 2025-06-27 14:40:10.973 Closing connection.
. 2025-06-27 14:40:10.973 Sending special code: 1
. 2025-06-27 14:40:11.176 Detected network event
. 2025-06-27 14:40:11.176 Session sent command exit status 0
. 2025-06-27 14:40:11.176 Main session channel closed
. 2025-06-27 14:40:11.176 All channels closed
. 2025-06-27 14:40:11.207 Detected network event
. 2025-06-27 14:40:11.207 Waiting for another 4 bytes
. 2025-06-27 14:40:11.207 Received 27 bytes
. 2025-06-27 14:40:11.207 Read 4 bytes (23 pending)
. 2025-06-27 14:40:11.207 Read 23 bytes (0 pending)
< 2025-06-27 14:40:11.207 Type: SSH_FXP_NAME, Size: 23, Number: 16
. 2025-06-27 14:40:11.207 Real path is '/'
. 2025-06-27 14:40:11.207 Startup conversation with host finished.
< 2025-06-27 14:40:11.207 Script: Session started.
< 2025-06-27 14:40:11.207 Script: Active session: [1] USERNAME@SFTPSOMETHING
. 2025-06-27 14:40:11.207 Session upkeep
> 2025-06-27 14:40:11.207 Script: CD receive
> 2025-06-27 14:40:11.207 Script: Parameter: receive
. 2025-06-27 14:40:11.207 Cached directory change via "receive" to "/receive".
. 2025-06-27 14:40:11.207 Getting current directory name.
< 2025-06-27 14:40:11.207 Script: /receive
. 2025-06-27 14:40:11.207 Session upkeep
> 2025-06-27 14:40:11.207 Script: LCD C:\..\..\Out
> 2025-06-27 14:40:11.207 Script: Parameter: C:\..\..\Out
< 2025-06-27 14:40:11.207 Script: C:\..\..\Out
. 2025-06-27 14:40:11.207 Session upkeep
> 2025-06-27 14:40:11.207 Script: PUT -nopreservetime -nopermissions -append FILENAME1*.* FILENAME1_20250627144011.tmp
> 2025-06-27 14:40:11.207 Script: Switch: -nopreservetime
> 2025-06-27 14:40:11.207 Script: Switch: -nopermissions
> 2025-06-27 14:40:11.207 Script: Switch: -append
> 2025-06-27 14:40:11.207 Script: Parameter: FILENAME1*.*
> 2025-06-27 14:40:11.207 Script: Parameter: FILENAME1_20250627144011.tmp
< 2025-06-27 14:40:11.207 Script: No file matching 'FILENAME1*.*' found.
. 2025-06-27 14:40:11.207 Size of 0 local files/folders calculated as 0
. 2025-06-27 14:40:11.207 Copying 0 files/directories to remote directory "/receive" - total size: 0
. 2025-06-27 14:40:11.207 PrTime: No; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0001; Resume: S (102400); CalcS: No; Mask: FILENAME1_20250627144011.tmp
. 2025-06-27 14:40:11.207 TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; EncryptNewFiles: Yes; ExcludeHiddenFiles: No; ExcludeEmptyDirectories: No; InclM: ; ResumeL: 0
. 2025-06-27 14:40:11.207 AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2025-06-27 14:40:11.207 Copying finished: Transferred: 0, Elapsed: 0:00:00, CPS: 0/s
. 2025-06-27 14:40:11.207 Session upkeep
> 2025-06-27 14:40:11.207 Script: PUT -nopreservetime -nopermissions -resumesupport=off -append FILENAME2*.* FILENAME2_20250627144011.tmp
> 2025-06-27 14:40:11.207 Script: Switch: -nopreservetime
> 2025-06-27 14:40:11.207 Script: Switch: -nopermissions
> 2025-06-27 14:40:11.207 Script: Switch: -resumesupport=off
> 2025-06-27 14:40:11.207 Script: Switch: -append
> 2025-06-27 14:40:11.207 Script: Parameter: FILENAME2*.*
> 2025-06-27 14:40:11.207 Script: Parameter: FILENAME2_20250627144011.tmp
< 2025-06-27 14:40:11.207 Script: No file matching 'FILENAME2*.*' found.
. 2025-06-27 14:40:11.207 Size of 0 local files/folders calculated as 0
. 2025-06-27 14:40:11.207 Copying 0 files/directories to remote directory "/receive" - total size: 0
. 2025-06-27 14:40:11.207 PrTime: No; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0001; Resume: N (102400); CalcS: No; Mask: FILENAME2_20250627144011.tmp
. 2025-06-27 14:40:11.207 TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; EncryptNewFiles: Yes; ExcludeHiddenFiles: No; ExcludeEmptyDirectories: No; InclM: ; ResumeL: 0
. 2025-06-27 14:40:11.207 AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2025-06-27 14:40:11.207 Copying finished: Transferred: 0, Elapsed: 0:00:00, CPS: 0/s
. 2025-06-27 14:40:11.207 Session upkeep
> 2025-06-27 14:40:11.207 Script: PUT -nopreservetime -nopermissions -append FILENAME3*.* FILENAME3_20250627144011.tmp
> 2025-06-27 14:40:11.207 Script: Switch: -nopreservetime
> 2025-06-27 14:40:11.207 Script: Switch: -nopermissions
> 2025-06-27 14:40:11.207 Script: Switch: -append
> 2025-06-27 14:40:11.207 Script: Parameter: FILENAME3*.*
> 2025-06-27 14:40:11.207 Script: Parameter: FILENAME3_20250627144011.tmp