WinSCP errors in Azure WebApps enviroment throwing errors

Advertisement

lfilim
Joined:
Posts:
4
Location:
Dublin

WinSCP errors in Azure WebApps enviroment throwing errors

I'm using winscp to sync folders using FTP between 2 locations.
I'm using WinSCP portable in version : Product version : 5.17.9.0 , File version: 5.17.9.10905

Here is the command line :
winscp.com /log=winscp.log /timeout=30 /script=sync-live-images.txt
exit
Script (sync-live-images.txt) content , I've removed any sensitve date from the script below:
option confirm off
open ftp\user:password@IP
synchronize remote -criteria=either "path1" "path2"
close
exit
We are using Azure Web Apps enviroment and we run WinSCP as webjob.
We have tried to find solution on your forum already using below links, however we are not using Windows Server or IIS so solutions provided don't help in our situation:
https://winscp.net/forum/viewtopic.php?t=22802
https://winscp.net/forum/viewtopic.php?t=22323

Script is usually working correctly but every 2 to 12 executions which are in delay of 1 minute we are getting errors :
[02/23/2021 11:21:00 > f345ae: SYS INFO] Status changed to Initializing
[02/23/2021 11:21:00 > f345ae: SYS INFO] Job directory change detected: Job file 'winscp.log' timestamp differs between source and working directories.
[02/23/2021 11:21:00 > f345ae: SYS INFO] Run script 'sync-live-images.bat' with script host - 'WindowsScriptHost'
[02/23/2021 11:21:00 > f345ae: SYS INFO] Status changed to Running
[02/23/2021 11:21:00 > f345ae: INFO] 
[02/23/2021 11:21:00 > f345ae: INFO] D:\local\Temp\jobs\triggered\SyncImagesEvery1minute\u5yfg3zy.mk1>winscp.com /log=winscp.log /timeout=30 /script=sync-live-images.txt 
[02/23/2021 11:21:01 > f345ae: INFO] 
[02/23/2021 11:21:01 > f345ae: INFO] D:\local\Temp\jobs\triggered\SyncImagesEvery1minute\u5yfg3zy.mk1>exit
[02/23/2021 11:21:01 > f345ae: SYS INFO] Status changed to Failed
[02/23/2021 11:21:01 > f345ae: SYS ERR ] Job failed due to exit code -1073741819

[02/23/2021 11:24:00 > f345ae: SYS INFO] Status changed to Initializing
[02/23/2021 11:24:00 > f345ae: SYS INFO] Job directory change detected: Job file 'winscp.log' timestamp differs between source and working directories.
[02/23/2021 11:24:01 > f345ae: SYS INFO] Run script 'sync-live-images.bat' with script host - 'WindowsScriptHost'
[02/23/2021 11:24:01 > f345ae: SYS INFO] Status changed to Running
[02/23/2021 11:24:01 > f345ae: INFO] 
[02/23/2021 11:24:01 > f345ae: INFO] D:\local\Temp\jobs\triggered\SyncImagesEvery1minute\rho4kko4.ga3>winscp.com /log=winscp.log /timeout=30 /script=sync-live-images.txt 
[02/23/2021 11:24:01 > f345ae: INFO] 
[02/23/2021 11:24:01 > f345ae: INFO] D:\local\Temp\jobs\triggered\SyncImagesEvery1minute\rho4kko4.ga3>exit
[02/23/2021 11:24:01 > f345ae: SYS INFO] Status changed to Failed
[02/23/2021 11:24:01 > f345ae: SYS ERR ] Job failed due to exit code -1073741819

[02/23/2021 11:40:00 > f345ae: SYS INFO] Status changed to Initializing
[02/23/2021 11:40:00 > f345ae: SYS INFO] Job directory change detected: Job file 'winscp.log' timestamp differs between source and working directories.
[02/23/2021 11:40:00 > f345ae: SYS INFO] Run script 'sync-live-images.bat' with script host - 'WindowsScriptHost'
[02/23/2021 11:40:00 > f345ae: SYS INFO] Status changed to Running
[02/23/2021 11:40:00 > f345ae: INFO] 
[02/23/2021 11:40:00 > f345ae: INFO] D:\local\Temp\jobs\triggered\SyncImagesEvery1minute\zd1eaccq.c5d>winscp.com /log=winscp.log /timeout=30 /script=sync-live-images.txt 
[02/23/2021 11:40:01 > f345ae: INFO] 
[02/23/2021 11:40:01 > f345ae: INFO] D:\local\Temp\jobs\triggered\SyncImagesEvery1minute\zd1eaccq.c5d>exit
[02/23/2021 11:40:01 > f345ae: SYS INFO] Status changed to Failed
[02/23/2021 11:40:01 > f345ae: SYS ERR ] Job failed due to exit code -1073740791

[02/23/2021 11:40:00 > f345ae: SYS INFO] Status changed to Initializing
[02/23/2021 11:40:00 > f345ae: SYS INFO] Job directory change detected: Job file 'winscp.log' timestamp differs between source and working directories.
[02/23/2021 11:40:00 > f345ae: SYS INFO] Run script 'sync-live-images.bat' with script host - 'WindowsScriptHost'
[02/23/2021 11:40:00 > f345ae: SYS INFO] Status changed to Running
[02/23/2021 11:40:00 > f345ae: INFO] 
[02/23/2021 11:40:00 > f345ae: INFO] D:\local\Temp\jobs\triggered\SyncImagesEvery1minute\zd1eaccq.c5d>winscp.com /log=winscp.log /timeout=30 /script=sync-live-images.txt 
[02/23/2021 11:40:01 > f345ae: INFO] 
[02/23/2021 11:40:01 > f345ae: INFO] D:\local\Temp\jobs\triggered\SyncImagesEvery1minute\zd1eaccq.c5d>exit
[02/23/2021 11:40:01 > f345ae: SYS INFO] Status changed to Failed
[02/23/2021 11:40:01 > f345ae: SYS ERR ] Job failed due to exit code -1073740791
I'm attaching Win SCP log file for your reference.
  • winscp.log (6.5 MB, Private file)
Description: File is only visible for author and moderators only.

Reply with quote

Advertisement

martin
Site Admin
martin avatar
Joined:
Posts:
41,276
Location:
Prague, Czechia

Re: WinSCP errors in Azure WebApps enviroment throwing errors

I do not see any error in the log. Did you post a correct log for the execution that reported the error in your parent process (Azure WebJob log?) log?

Reply with quote

lfilim
Joined:
Posts:
4
Location:
Dublin

Hi Martin,

I've posted the only WinSCP.log that was created.
In regards to Azure logs I've posted only those giving me an error.
In my scenario WinSCP is used to sync 2 FTP folders between 2 Azure Web Apps and for 60 executions within 1 hours I get from, 8-12 executions ending up wiht an error , one of the ones I've sent in first post.

I don't understand why only some of them are ending with an erorr while others complete just fine. Because it is Azure WebApps I can't modify application pool settings because I don't have them accessible for me in this model.

Best Regards,
Lukas

Reply with quote

lfilim
Joined:
Posts:
4
Location:
Dublin

Hi Martin,

I'm attaching again what was produced in the logs in a while.
Can you see anything that could indicate winscp issue?

Best Regards,
Lukas
  • winscp.log (6.5 MB, Private file)
  • azure_logs_webjob_sync_images_18032021.txt (11.2 KB, Private file)

Reply with quote

Advertisement

martin
Site Admin
martin avatar

Sorry, but you didn't provide the logs I have asked for. The Azure log is from March 18. And the WinSCP logs are from January and February.

Reply with quote

lfilim
Joined:
Posts:
4
Location:
Dublin

Hi Martin,

I've only provided what WinSCP put in the log, when I'm using below command to run WINSCP :
winscp.com /log=winscp.log /timeout=30 /script=sync-live-images.txt
I've only see once since few months that WIN SCP is actually creating a log, and the log that I've sent is the only one that was ever made by Win SCP running under web job in Azure web app enviroment.

Do you have any suggestion how I can force WIN SCP to produce the log?

Best Regards,
Lukas

Reply with quote

Advertisement

You can post new topics in this forum