.net not authenticating with same credentials that work for the native app :: Support Forum

amSteve
Joined:: 2015-10-07
Posts:: 4

.net not authenticating with same credentials that work for the native app

2015-10-07 00:35

I'm working from the (very welcome) C# samples with most recent assembly (via Nuget) but getting an Authentication Failed (full paste below) using the same credentials that I'm able to use successfully when connecting with the GUI version of of WinSCP.

Protocol = Protocol.Ftp,
HostName = "waws-prod-ch1-005.ftp.azurewebsites.windows.net",
UserName = "MyInstance\\$MyOpName",
Password = "P@ssWord",

I've copied the creds from my PublishProfile file and, like i say -- works with the interactive program. Only difference being the doubled '\\' required to escape the single slash. Obviously get a red squiggly without the escape.

Ideally I get to the point of using FTPS but first things first....

thanks much for the effort!

{WinSCP.SessionRemoteException: Authentication failed.
at WinSCP.SessionLogReader.Read(LogReadFlags flags)
at WinSCP.ElementLogReader.Read(LogReadFlags flags)
at WinSCP.SessionElementLogReader.Read(LogReadFlags flags)
at WinSCP.CustomLogReader.TryWaitForNonEmptyElement(String localName, LogReadFlags flags)
at WinSCP.CustomLogReader.WaitForNonEmptyElement(String localName, LogReadFlags flags)
at WinSCP.CustomLogReader.WaitForNonEmptyElementAndCreateLogReader(String localName, LogReadFlags flags)
at WinSCP.CustomLogReader.WaitForGroupAndCreateLogReader()
at WinSCP.Session.Open(SessionOptions sessionOptions)
at CUWebinars.Web.Controllers.Admin.AdminController.GetAppLog() in c:\Users\Steve\\AdminController.cs:line 132}

Reply with quote

martin◆ Site Admin
Joined:: 2002-12-10
Posts:: 41,454
Location:: Prague, Czechia

Re: .net not authenticating with same credentials that work for the native app

2015-10-09

Enable logging both in GUI and .NET assembly and compare. Attach the logs here if it does not help.

Reply with quote

amSteve
Joined:: 2015-10-07
Posts:: 4

2015-10-14 14:41

It might be worth showing how .net's FtpWebRequest needed credentials:

request.Credentials = new NetworkCredential("$Bar33", "...", "Bar33");

GUI's:

. 2015-10-14 07:59:40.701 --------------------------------------------------------------------------
. 2015-10-14 07:59:40.701 WinSCP Version 5.7.5 (Build 5665) (OS 6.3.9600 - Windows 8.1 Pro N)
. 2015-10-14 07:59:40.701 Configuration: HKCU\Software\Martin Prikryl\WinSCP 2\
. 2015-10-14 07:59:40.701 Log level: Debug 1
. 2015-10-14 07:59:40.701 Local account: DEVHOLMEN15\Foo
. 2015-10-14 07:59:40.701 Working directory: C:\Program Files (x86)\WinSCP
. 2015-10-14 07:59:40.701 Process ID: 9388
. 2015-10-14 07:59:40.701 Command-line: "C:\Program Files (x86)\WinSCP\WinSCP.exe"
. 2015-10-14 07:59:40.701 Time zone: Current: GMT-5, Standard: GMT-6 (Central Standard Time), DST: GMT-5 (Central Daylight Time), DST Start: 3/8/2015, DST End: 11/1/2015
. 2015-10-14 07:59:40.701 Login time: Wednesday, October 14, 2015 7:59:40 AM
. 2015-10-14 07:59:40.701 --------------------------------------------------------------------------
. 2015-10-14 07:59:40.701 Session name: bw33 (Site)
. 2015-10-14 07:59:40.701 Host name: waws-prod-ch1-005.ftp.azurewebsites.windows.net (Port: 21)
. 2015-10-14 07:59:40.701 User name: Bar33\$Bar33 (Password: Yes, Key file: No)
. 2015-10-14 07:59:40.701 Transfer Protocol: FTP
. 2015-10-14 07:59:40.701 Ping type: C, Ping interval: 30 sec; Timeout: 15 sec
. 2015-10-14 07:59:40.701 Disable Nagle: No
. 2015-10-14 07:59:40.701 Proxy: none
. 2015-10-14 07:59:40.701 Send buffer: 262144
. 2015-10-14 07:59:40.701 UTF: 2
. 2015-10-14 07:59:40.701 FTP: FTPS: None; Passive: No [Force IP: A]; MLSD: A [List all: A]
. 2015-10-14 07:59:40.701 Local directory: C:\Users\Foo\Documents, Remote directory: /LogFiles/http/RawLogs, Update: Yes, Cache: Yes
. 2015-10-14 07:59:40.701 Cache directory changes: Yes, Permanent: Yes
. 2015-10-14 07:59:40.701 Timezone offset: 0h 0m
. 2015-10-14 07:59:40.701 --------------------------------------------------------------------------
. 2015-10-14 07:59:40.704 Session upkeep
. 2015-10-14 07:59:40.774 Connecting to waws-prod-ch1-005.ftp.azurewebsites.windows.net ...
. 2015-10-14 07:59:40.774 Connected with waws-prod-ch1-005.ftp.azurewebsites.windows.net. Waiting for welcome message...
< 2015-10-14 07:59:40.803 220 Microsoft FTP Service
> 2015-10-14 07:59:40.803 USER Bar33\$Bar33
< 2015-10-14 07:59:40.836 331 Password required
> 2015-10-14 07:59:40.836 PASS ************************************************************
< 2015-10-14 07:59:40.962 230 User logged in.
> 2015-10-14 07:59:40.962 SYST
< 2015-10-14 07:59:40.995 215 Windows_NT
> 2015-10-14 07:59:40.995 FEAT
< 2015-10-14 07:59:41.027 211-Extended features supported:
< 2015-10-14 07:59:41.027 LANG EN*
< 2015-10-14 07:59:41.027 UTF8

[/code]
Assembly's:

[2015-10-14 08:27:02.039Z] [0005] Executing Assembly: WinSCPnet, Version=1.2.9.5665, Culture=neutral, PublicKeyToken=2271ec4a3c56d0bf; Path: C:\Users\Foo\Source\Repos\Bar2\CUWebinars.Web\bin\WinSCPnet.DLL; Location: C:\Users\Foo\AppData\Local\Temp\Temporary ASP.NET Files\root\605c3628\cb2eacb5\assembly\dl3\9b6ae5ed\3ac5262a_8700d101\WinSCPnet.dll; Product: 5.7.5.0
[2015-10-14 08:27:02.039Z] [0005] Entry Assembly: 
[2015-10-14 08:27:02.039Z] [0005] Operating system: Microsoft Windows NT 6.2.9200.0
[2015-10-14 08:27:02.039Z] [0005] User: Foo@DEVHOLMEN15@DEVHOLMEN15; Interactive: True
[2015-10-14 08:27:02.039Z] [0005] Runtime: 4.0.30319.42000
[2015-10-14 08:27:02.039Z] [0005] Console encoding: Input:  United States (437); Output:  United States (437)
[2015-10-14 08:27:02.039Z] [0005] Working directory: C:\Program Files (x86)\IIS Express
[2015-10-14 08:27:09.153Z] [0005] Session.Open entering
[2015-10-14 08:27:09.154Z] [0005]   Session.SetupTempPath entering
[2015-10-14 08:27:09.154Z] [0005]     Temporary folder: C:\Users\Foo\AppData\Local\Temp\
[2015-10-14 08:27:09.154Z] [0005]     Temporary file [C:\Users\Foo\AppData\Local\Temp\wscp248C.01740AC8.tmp] - Exists [False]
[2015-10-14 08:27:09.154Z] [0005]   Session.SetupTempPath leaving
[2015-10-14 08:27:09.155Z] [0005]   ExeSessionProcess..ctor entering
[2015-10-14 08:27:09.156Z] [0005]     ExeSessionProcess.GetExecutablePath entering
[2015-10-14 08:27:09.156Z] [0005]       Executable found in C:\Users\Foo\Source\Repos\Bar2\CUWebinars.Web\bin\winscp.exe
[2015-10-14 08:27:09.156Z] [0005]     ExeSessionProcess.GetExecutablePath leaving
[2015-10-14 08:27:09.156Z] [0005]     EXE executable path resolved to C:\Users\Foo\Source\Repos\Bar2\CUWebinars.Web\bin\winscp.exe
[2015-10-14 08:27:09.157Z] [0005]     ExeSessionProcess.CheckVersion entering
[2015-10-14 08:27:09.157Z] [0005]       Version of C:\Users\Foo\Source\Repos\Bar2\CUWebinars.Web\bin\winscp.exe is 5.7.5.5665, product WinSCP version is 5.7.5.0
[2015-10-14 08:27:09.157Z] [0005]     ExeSessionProcess.CheckVersion leaving
[2015-10-14 08:27:09.158Z] [0005]   ExeSessionProcess..ctor leaving
[2015-10-14 08:27:09.159Z] [0005]   ExeSessionProcess.Start entering
[2015-10-14 08:27:09.160Z] [0005]     ExeSessionProcess.InitializeConsole entering
[2015-10-14 08:27:09.160Z] [0005]       Trying event _9356_5574031_287
[2015-10-14 08:27:09.160Z] [0005]       Creating event WinSCPConsoleEventRequest_9356_5574031_287
[2015-10-14 08:27:09.160Z] [0005]       Created event WinSCPConsoleEventRequest_9356_5574031_287 with handle 4452, new True
[2015-10-14 08:27:09.160Z] [0005]       Event _9356_5574031_287 is unique
[2015-10-14 08:27:09.160Z] [0005]       Creating event WinSCPConsoleEventResponse_9356_5574031_287
[2015-10-14 08:27:09.160Z] [0005]       Created event WinSCPConsoleEventResponse_9356_5574031_287 with handle 5772, new True
[2015-10-14 08:27:09.160Z] [0005]       Creating event WinSCPConsoleEventCancel_9356_5574031_287
[2015-10-14 08:27:09.160Z] [0005]       Created event WinSCPConsoleEventCancel_9356_5574031_287 with handle 5776, new True
[2015-10-14 08:27:09.162Z] [0005]       Job created
[2015-10-14 08:27:09.163Z] [0005]       Job set to kill all processes
[2015-10-14 08:27:09.163Z] [0005]     ExeSessionProcess.InitializeConsole leaving
[2015-10-14 08:27:09.163Z] [0005]     ExeSessionProcess.InitializeChild entering
[2015-10-14 08:27:09.163Z] [0005]       Starting "C:\Users\Foo\Source\Repos\Bar2\CUWebinars.Web\bin\winscp.exe" /xmllog="C:\Users\Foo\AppData\Local\Temp\wscp248C.01740AC8.tmp" /xmlgroups /nointeractiveinput /dotnet=575  /ini=nul  /console /consoleinstance=_9356_5574031_287
[2015-10-14 08:27:09.164Z] [0005]       Started process 7316
[2015-10-14 08:27:09.165Z] [0005]     ExeSessionProcess.InitializeChild leaving
[2015-10-14 08:27:09.165Z] [0005]   ExeSessionProcess.Start leaving
[2015-10-14 08:27:09.166Z] [0005]   Command: [option batch on]
[2015-10-14 08:27:09.166Z] [000e] ExeSessionProcess.ProcessEvents entering
[2015-10-14 08:27:09.166Z] [0005]   ExeSessionProcess.ExecuteCommand entering
[2015-10-14 08:27:09.167Z] [0005]   ExeSessionProcess.ExecuteCommand leaving
[2015-10-14 08:27:09.167Z] [0005]   Command: [option confirm off]
[2015-10-14 08:27:09.168Z] [0005]   ExeSessionProcess.ExecuteCommand entering
[2015-10-14 08:27:09.168Z] [0005]   ExeSessionProcess.ExecuteCommand leaving
[2015-10-14 08:27:09.168Z] [0005]   Command: [option reconnecttime 120]
[2015-10-14 08:27:09.168Z] [0005]   ExeSessionProcess.ExecuteCommand entering
[2015-10-14 08:27:09.168Z] [0005]   ExeSessionProcess.ExecuteCommand leaving
[2015-10-14 08:27:09.169Z] [0005]   Session.SessionOptionsToOpenCommand entering
[2015-10-14 08:27:09.170Z] [0005]     Session.SessionOptionsToOpenSwitches entering
[2015-10-14 08:27:09.171Z] [0005]     Session.SessionOptionsToOpenSwitches leaving
[2015-10-14 08:27:09.171Z] [0005]   Session.SessionOptionsToOpenCommand leaving
[2015-10-14 08:27:09.171Z] [0005]   Command: [open "ftp://%24BarOp:***@waws-prod-ch1-005.ftp.azurewebsites.windows.net" -passive=1 -timeout=15]
[2015-10-14 08:27:09.171Z] [0005]   ExeSessionProcess.ExecuteCommand entering
[2015-10-14 08:27:09.171Z] [0005]   ExeSessionProcess.ExecuteCommand leaving
[2015-10-14 08:27:09.228Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.230Z] [000e]     ExeSessionProcess.ProcessInitEvent entering
[2015-10-14 08:27:09.230Z] [000e]     ExeSessionProcess.ProcessInitEvent leaving
[2015-10-14 08:27:09.230Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.233Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.234Z] [000e]     ExeSessionProcess.ProcessTitleEvent entering
[2015-10-14 08:27:09.234Z] [000e]       Not-supported title event [WinSCP]
[2015-10-14 08:27:09.234Z] [000e]     ExeSessionProcess.ProcessTitleEvent leaving
[2015-10-14 08:27:09.234Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.234Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.234Z] [000e]     ExeSessionProcess.ProcessTitleEvent entering
[2015-10-14 08:27:09.234Z] [000e]       Not-supported title event [WinSCP]
[2015-10-14 08:27:09.234Z] [000e]     ExeSessionProcess.ProcessTitleEvent leaving
[2015-10-14 08:27:09.234Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.234Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.235Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.235Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.235Z] [000e]     ExeSessionProcess.ProcessInputEvent entering
[2015-10-14 08:27:09.235Z] [000e]       Scheduling output: [winscp> option batch on]
[2015-10-14 08:27:09.236Z] [000e]     ExeSessionProcess.ProcessInputEvent leaving
[2015-10-14 08:27:09.236Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.236Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.236Z] [000e]     Scheduling output: [batch           on        ]
[2015-10-14 08:27:09.236Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.236Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.236Z] [000e]     Scheduling output: [reconnecttime   120       ]
[2015-10-14 08:27:09.236Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.236Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.236Z] [000e]     ExeSessionProcess.ProcessTitleEvent entering
[2015-10-14 08:27:09.236Z] [000e]       Not-supported title event [WinSCP]
[2015-10-14 08:27:09.236Z] [000e]     ExeSessionProcess.ProcessTitleEvent leaving
[2015-10-14 08:27:09.236Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.236Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.237Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.237Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.237Z] [000e]     ExeSessionProcess.ProcessInputEvent entering
[2015-10-14 08:27:09.237Z] [000e]       Scheduling output: [winscp> option confirm off]
[2015-10-14 08:27:09.237Z] [000e]     ExeSessionProcess.ProcessInputEvent leaving
[2015-10-14 08:27:09.237Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.237Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.237Z] [000e]     Scheduling output: [confirm         off       ]
[2015-10-14 08:27:09.237Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.237Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.237Z] [000e]     ExeSessionProcess.ProcessTitleEvent entering
[2015-10-14 08:27:09.237Z] [000e]       Not-supported title event [WinSCP]
[2015-10-14 08:27:09.237Z] [000e]     ExeSessionProcess.ProcessTitleEvent leaving
[2015-10-14 08:27:09.237Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.237Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.237Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.237Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.238Z] [000e]     ExeSessionProcess.ProcessInputEvent entering
[2015-10-14 08:27:09.238Z] [000e]       Scheduling output: [winscp> option reconnecttime 120]
[2015-10-14 08:27:09.238Z] [000e]     ExeSessionProcess.ProcessInputEvent leaving
[2015-10-14 08:27:09.238Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.238Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.238Z] [000e]     Scheduling output: [reconnecttime   120       ]
[2015-10-14 08:27:09.238Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.238Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.238Z] [000e]     ExeSessionProcess.ProcessTitleEvent entering
[2015-10-14 08:27:09.238Z] [000e]       Not-supported title event [WinSCP]
[2015-10-14 08:27:09.238Z] [000e]     ExeSessionProcess.ProcessTitleEvent leaving
[2015-10-14 08:27:09.238Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.238Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.238Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.238Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.238Z] [000e]     ExeSessionProcess.ProcessInputEvent entering
[2015-10-14 08:27:09.238Z] [000e]       Scheduling output: [winscp> open "ftp://%24BarOp:FDcehM4K2WbSuxEplrG2B7uJxqrMbeqJ6MdDm3GLyraYTmzWnmLDQAkllu0t@waws-prod-ch1-005.ftp.azurewebsites.windows.net" -passive=1 -timeout=15]
[2015-10-14 08:27:09.238Z] [000e]     ExeSessionProcess.ProcessInputEvent leaving
[2015-10-14 08:27:09.238Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.247Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.247Z] [000e]     Scheduling output: [Connecting to waws-prod-ch1-005.ftp.azurewebsites.windows.net ...]
[2015-10-14 08:27:09.247Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.278Z] [0005]   SessionLogReader.Read entering
[2015-10-14 08:27:09.279Z] [0005]     Opening log without write sharing
[2015-10-14 08:27:09.295Z] [0005]     Opening log with write sharing
[2015-10-14 08:27:09.296Z] [0005]     Log opened
[2015-10-14 08:27:09.296Z] [0005]     Skipping 0 nodes
[2015-10-14 08:27:09.296Z] [0005]     Read node 1: XmlDeclaration xml=version="1.0" encoding="UTF-8"
[2015-10-14 08:27:09.296Z] [0005]     Log contents:
<?xml version="1.0" encoding="UTF-8"?>
<session xmlns="http://winscp.net/schema/session/1.0" name="$BarOp@waws-prod-ch1-005.ftp.azurewebsites.windows.net" start="2015-10-14T13:27:09.240Z">

[2015-10-14 08:27:09.297Z] [0005]   SessionLogReader.Read leaving
[2015-10-14 08:27:09.297Z] [0005]   SessionLogReader.Read entering
[2015-10-14 08:27:09.297Z] [0005]     Read node 2: Whitespace 

[2015-10-14 08:27:09.297Z] [0005]     Log contents has not changed
[2015-10-14 08:27:09.297Z] [0005]   SessionLogReader.Read leaving
[2015-10-14 08:27:09.297Z] [0005]   SessionLogReader.Read entering
[2015-10-14 08:27:09.297Z] [0005]     Read node 3: Element session
[2015-10-14 08:27:09.297Z] [0005]     Log contents has not changed
[2015-10-14 08:27:09.297Z] [0005]   SessionLogReader.Read leaving
[2015-10-14 08:27:09.298Z] [0005]   SessionLogReader.Read entering
[2015-10-14 08:27:09.298Z] [0005]     Read node 4: Whitespace 

[2015-10-14 08:27:09.298Z] [0005]     Log contents has not changed
[2015-10-14 08:27:09.298Z] [0005]   SessionLogReader.Read leaving
[2015-10-14 08:27:09.298Z] [0005]   SessionLogReader.Read entering
[2015-10-14 08:27:09.315Z] [0005]     Closing log
[2015-10-14 08:27:09.316Z] [0005]     Error parsing session log file, but it is not closed yet, will retry
[2015-10-14 08:27:09.316Z] [0005]     Waiting for log update and dispatching events for 125
[2015-10-14 08:27:09.316Z] [0005]     Output: [winscp> option batch on]
[2015-10-14 08:27:09.316Z] [0005]     Output: [batch           on        ]
[2015-10-14 08:27:09.316Z] [0005]     Output: [reconnecttime   120       ]
[2015-10-14 08:27:09.316Z] [0005]     Output: [winscp> option confirm off]
[2015-10-14 08:27:09.316Z] [0005]     Output: [confirm         off       ]
[2015-10-14 08:27:09.316Z] [0005]     Output: [winscp> option reconnecttime 120]
[2015-10-14 08:27:09.316Z] [0005]     Output: [reconnecttime   120       ]
[2015-10-14 08:27:09.316Z] [0005]     Output: [winscp> open "ftp://%24BarOp:FDcehM4K2WbSuxEplrG2B7uJxqrMbeqJ6MdDm3GLyraYTmzWnmLDQAkllu0t@waws-prod-ch1-005.ftp.azurewebsites.windows.net" -passive=1 -timeout=15]
[2015-10-14 08:27:09.316Z] [0005]     Output: [Connecting to waws-prod-ch1-005.ftp.azurewebsites.windows.net ...]
[2015-10-14 08:27:09.442Z] [0005]     Opening log without write sharing
[2015-10-14 08:27:09.457Z] [0005]     Opening log with write sharing
[2015-10-14 08:27:09.457Z] [0005]     Log opened
[2015-10-14 08:27:09.457Z] [0005]     Skipping 4 nodes
[2015-10-14 08:27:09.470Z] [0005]     Closing log
[2015-10-14 08:27:09.470Z] [0005]     Error parsing session log file, but it is not closed yet, will retry
[2015-10-14 08:27:09.470Z] [0005]     Waiting for log update and dispatching events for 250
[2015-10-14 08:27:09.681Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.681Z] [000e]     Scheduling output: [Access denied.]
[2015-10-14 08:27:09.681Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.681Z] [0005]     Output: [Access denied.]
[2015-10-14 08:27:09.681Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.682Z] [000e]     Scheduling output: [Authentication failed.]
[2015-10-14 08:27:09.682Z] [0005]     Output: [Authentication failed.]
[2015-10-14 08:27:09.682Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.686Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.687Z] [000e]     ExeSessionProcess.ProcessTitleEvent entering
[2015-10-14 08:27:09.687Z] [000e]       Not-supported title event [WinSCP]
[2015-10-14 08:27:09.687Z] [000e]     ExeSessionProcess.ProcessTitleEvent leaving
[2015-10-14 08:27:09.687Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.687Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.687Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.687Z] [000e]   ExeSessionProcess.ProcessEvent entering
[2015-10-14 08:27:09.687Z] [000e]     ExeSessionProcess.ProcessInputEvent entering
[2015-10-14 08:27:09.720Z] [0005]     Opening log without write sharing
[2015-10-14 08:27:09.720Z] [0005]     Log contents:
<?xml version="1.0" encoding="UTF-8"?>
<session xmlns="http://winscp.net/schema/session/1.0" name="$BarOp@waws-prod-ch1-005.ftp.azurewebsites.windows.net" start="2015-10-14T13:27:09.240Z">
  <failure>
    <message>Authentication failed.</message>
  </failure>
</session>

[2015-10-14 08:27:09.720Z] [0005]     Log opened
[2015-10-14 08:27:09.720Z] [0005]     Skipping 4 nodes
[2015-10-14 08:27:09.720Z] [0005]     Read node 5: Element failure
[2015-10-14 08:27:09.720Z] [0005]     Log contents has not changed
[2015-10-14 08:27:09.721Z] [0005]     SessionLogReader.Read entering
[2015-10-14 08:27:09.721Z] [0005]       Read node 6: Whitespace 
    
[2015-10-14 08:27:09.721Z] [0005]       Log contents has not changed
[2015-10-14 08:27:09.721Z] [0005]     SessionLogReader.Read leaving
[2015-10-14 08:27:09.721Z] [0005]     SessionLogReader.Read entering
[2015-10-14 08:27:09.721Z] [0005]       Read node 7: Element message
[2015-10-14 08:27:09.721Z] [0005]       Log contents has not changed
[2015-10-14 08:27:09.721Z] [0005]     SessionLogReader.Read leaving
[2015-10-14 08:27:09.721Z] [0005]     SessionLogReader.Read entering
[2015-10-14 08:27:09.721Z] [0005]       Read node 8: Text Authentication failed.
[2015-10-14 08:27:09.721Z] [0005]       Log contents has not changed
[2015-10-14 08:27:09.721Z] [0005]     SessionLogReader.Read leaving
[2015-10-14 08:27:09.721Z] [0005]     SessionLogReader.Read entering
[2015-10-14 08:27:09.721Z] [0005]       Read node 9: EndElement message
[2015-10-14 08:27:09.721Z] [0005]       Log contents has not changed
[2015-10-14 08:27:09.721Z] [0005]     SessionLogReader.Read leaving
[2015-10-14 08:27:09.722Z] [0005]     SessionLogReader.Read entering
[2015-10-14 08:27:09.722Z] [0005]       Read node 10: Whitespace 
  
[2015-10-14 08:27:09.722Z] [0005]       Log contents has not changed
[2015-10-14 08:27:09.722Z] [0005]     SessionLogReader.Read leaving
[2015-10-14 08:27:09.722Z] [0005]     SessionLogReader.Read entering
[2015-10-14 08:27:09.722Z] [0005]       Read node 11: EndElement failure
[2015-10-14 08:27:09.722Z] [0005]       Log contents has not changed
[2015-10-14 08:27:09.722Z] [0005]     SessionLogReader.Read leaving
[2015-10-14 08:27:09.722Z] [0005]     Exception: WinSCP.SessionRemoteException: Authentication failed.
[2015-10-14 08:27:09.723Z] [0005]     ElementLogReader.Dispose entering
[2015-10-14 08:27:09.723Z] [0005]       ElementLogReader.ReadToEnd entering
[2015-10-14 08:27:09.723Z] [0005]       ElementLogReader.ReadToEnd leaving
[2015-10-14 08:27:09.723Z] [0005]     ElementLogReader.Dispose leaving
[2015-10-14 08:27:09.723Z] [0005]     Failed: [WinSCP.SessionRemoteException: Authentication failed.]
[2015-10-14 08:27:09.737Z] [0005]   SessionLogReader.Read leaving
[2015-10-14 08:27:09.738Z] [0005]   Exception: WinSCP.SessionRemoteException: Authentication failed.
   at WinSCP.SessionLogReader.Read(LogReadFlags flags)
   at WinSCP.ElementLogReader.Read(LogReadFlags flags)
   at WinSCP.SessionElementLogReader.Read(LogReadFlags flags)
   at WinSCP.CustomLogReader.TryWaitForNonEmptyElement(String localName, LogReadFlags flags)
   at WinSCP.CustomLogReader.WaitForNonEmptyElement(String localName, LogReadFlags flags)
   at WinSCP.CustomLogReader.WaitForNonEmptyElementAndCreateLogReader(String localName, LogReadFlags flags)
   at WinSCP.CustomLogReader.WaitForGroupAndCreateLogReader()
   at WinSCP.Session.Open(SessionOptions sessionOptions)
[2015-10-14 08:27:09.738Z] [0005]   Session.Cleanup entering
[2015-10-14 08:27:09.738Z] [0005]     Terminating process
[2015-10-14 08:27:09.738Z] [0005]     Command: [exit]
[2015-10-14 08:27:09.739Z] [0005]     ExeSessionProcess.ExecuteCommand entering
[2015-10-14 08:27:09.739Z] [0005]     ExeSessionProcess.ExecuteCommand leaving
[2015-10-14 08:27:09.739Z] [000e]       Scheduling output: [winscp> exit]
[2015-10-14 08:27:09.739Z] [000e]     ExeSessionProcess.ProcessInputEvent leaving
[2015-10-14 08:27:09.739Z] [000e]   ExeSessionProcess.ProcessEvent leaving
[2015-10-14 08:27:09.739Z] [0005]     ExeSessionProcess.Close entering
[2015-10-14 08:27:09.739Z] [0005]       Waiting for process to exit (2000 ms)
[2015-10-14 08:27:09.761Z] [0005]     ExeSessionProcess.Close leaving
[2015-10-14 08:27:09.762Z] [0005]     ExeSessionProcess.Dispose entering
[2015-10-14 08:27:09.840Z] [000e]   Aborted
[2015-10-14 08:27:09.840Z] [000e] ExeSessionProcess.ProcessEvents leaving
[2015-10-14 08:27:09.841Z] [0005]       Closing job
[2015-10-14 08:27:09.841Z] [0005]     ExeSessionProcess.Dispose leaving
[2015-10-14 08:27:09.841Z] [0005]     Disposing log readers
[2015-10-14 08:27:09.841Z] [0005]     ElementLogReader.Dispose entering
[2015-10-14 08:27:09.842Z] [0005]       ElementLogReader.ReadToEnd entering
[2015-10-14 08:27:09.842Z] [0005]         SessionLogReader.Read entering
[2015-10-14 08:27:09.842Z] [0005]           Read node 12: Whitespace 

[2015-10-14 08:27:09.842Z] [0005]           Log contents has not changed
[2015-10-14 08:27:09.842Z] [0005]         SessionLogReader.Read leaving
[2015-10-14 08:27:09.842Z] [0005]         SessionLogReader.Read entering
[2015-10-14 08:27:09.842Z] [0005]           Read node 13: EndElement session
[2015-10-14 08:27:09.843Z] [0005]           Log contents has not changed
[2015-10-14 08:27:09.843Z] [0005]         SessionLogReader.Read leaving
[2015-10-14 08:27:09.843Z] [0005]       ElementLogReader.ReadToEnd leaving
[2015-10-14 08:27:09.843Z] [0005]     ElementLogReader.Dispose leaving
[2015-10-14 08:27:09.843Z] [0005]     SessionLogReader.Dispose entering
[2015-10-14 08:27:09.843Z] [0005]       Closing log
[2015-10-14 08:27:09.843Z] [0005]     SessionLogReader.Dispose leaving
[2015-10-14 08:27:09.843Z] [0005]     Deleting XML log file [C:\Users\Foo\AppData\Local\Temp\wscp248C.01740AC8.tmp]
[2015-10-14 08:27:09.845Z] [0005]   Session.Cleanup leaving
[2015-10-14 08:27:09.860Z] [0005] Session.Open leaving
[2015-10-14 08:27:09.861Z] [0005] Session.Dispose entering
[2015-10-14 08:27:09.861Z] [0005]   Session.Cleanup entering
[2015-10-14 08:27:09.861Z] [0005]     Disposing log readers
[2015-10-14 08:27:09.861Z] [0005]   Session.Cleanup leaving
[2015-10-14 08:27:09.862Z] [0005]   % Processor Time/2 = [25.11029]
[2015-10-14 08:27:09.862Z] [0005]   % Processor Time/3 = [31.5478]
[2015-10-14 08:27:09.862Z] [0005]   % Processor Time/0 = [30.90405]
[2015-10-14 08:27:09.862Z] [0005]   % Processor Time/1 = [28.31917]
[2015-10-14 08:27:09.862Z] [0005]   % Processor Time/_Total = [28.96301]
[2015-10-14 08:27:09.862Z] [0005]   Available KBytes = [7022340]
[2015-10-14 08:27:09.900Z] [0005]   1128:Start8Srv - ??? - ???
[2015-10-14 08:27:09.900Z] [0005]   1180:conhost - 10/14/2015 7:12:06 AM - 00:00:00.0156250
[2015-10-14 08:27:09.900Z] [0005]   11236:livecomm - 10/14/2015 8:25:10 AM - 00:00:00.2812500
[2015-10-14 08:27:09.900Z] [0005]   1372:MMix_64 - 10/13/2015 1:28:32 AM - 00:01:00.3750000
[2015-10-14 08:27:09.900Z] [0005]   11184:AzureManagementStudio - 10/13/2015 1:51:07 PM - 01:28:51.5625000
[2015-10-14 08:27:09.900Z] [0005]   8068:Microsoft.VisualStudio.Web.Host - 10/13/2015 1:30:07 AM - 00:00:37.4375000
[2015-10-14 08:27:09.900Z] [0005]   1960:taskhostex - 10/13/2015 1:28:32 AM - 00:00:37.1406250
[2015-10-14 08:27:09.900Z] [0005]   6228:conhost - 10/13/2015 1:50:00 AM - 00:00:00
[2015-10-14 08:27:09.928Z] [0005]   1564:svchost - ??? - ???
[2015-10-14 08:27:09.928Z] [0005]   11784:powershell_ise - 10/14/2015 7:36:01 AM - 00:00:03.8593750
[2015-10-14 08:27:09.928Z] [0005]   3724:conhost - 10/14/2015 7:11:46 AM - 00:00:00
[2015-10-14 08:27:09.956Z] [0005]   1164:svchost - ??? - ???
[2015-10-14 08:27:09.985Z] [0005]   1360:spoolsv - ??? - ???
[2015-10-14 08:27:09.985Z] [0005]   11208:conhost - 10/14/2015 8:17:00 AM - 00:00:00
[2015-10-14 08:27:10.013Z] [0005]   1172:svchost - ??? - ???
[2015-10-14 08:27:10.013Z] [0005]   10280:notepad - 10/14/2015 8:00:41 AM - 00:00:00.7343750
[2015-10-14 08:27:10.013Z] [0005]   4760:firefox - 10/14/2015 7:08:37 AM - 00:04:22.4218750
[2015-10-14 08:27:10.013Z] [0005]   5884:robotaskbaricon - 10/13/2015 1:28:46 AM - 00:09:57.8906250
[2015-10-14 08:27:10.013Z] [0005]   3712:nvtray - 10/13/2015 1:28:35 AM - 00:00:00.0468750
[2015-10-14 08:27:10.041Z] [0005]   1856:sqlservr - ??? - ???
[2015-10-14 08:27:10.070Z] [0005]   1148:Start8_64 - ??? - ???
[2015-10-14 08:27:10.070Z] [0005]   6268:PdaNetPC - 10/13/2015 1:28:49 AM - 00:00:01.0937500
[2015-10-14 08:27:10.110Z] [0005]   1932:sqlbrowser - ??? - ???
[2015-10-14 08:27:10.110Z] [0005]   9416:iexplore - 10/13/2015 1:10:34 PM - 00:01:21.9843750
[2015-10-14 08:27:10.140Z] [0005]   9016:dwm - ??? - ???
[2015-10-14 08:27:10.140Z] [0005]   4292:NvBackend - 10/13/2015 1:28:36 AM - 00:00:00.5625000
[2015-10-14 08:27:10.170Z] [0005]   548:winlogon - ??? - ???
[2015-10-14 08:27:10.198Z] [0005]   1532:MsMpEng - ??? - ???
[2015-10-14 08:27:10.227Z] [0005]   1728:LMIGuardianSvc - ??? - ???
[2015-10-14 08:27:10.227Z] [0005]   5140:iexplore - 10/13/2015 11:43:24 AM - 01:21:26.5937500
[2015-10-14 08:27:10.227Z] [0005]   5468:ClipMate - 10/13/2015 1:28:45 AM - 00:05:21.2656250
[2015-10-14 08:27:10.227Z] [0005]   9356:iisxpress - 10/14/2015 8:26:04 AM - 00:00:10.8750000
[2015-10-14 08:27:10.227Z] [0005]   3888:plugin_host - 10/13/2015 6:54:34 AM - 00:00:01.0468750
[2015-10-14 08:27:10.227Z] [0005]   12752:CUWebinars.Azure.OrderConfirmNotifier.vshost - 10/14/2015 7:11:55 AM - 00:00:00.0781250
[2015-10-14 08:27:10.254Z] [0005]   1916:hamachi-2 - ??? - ???
[2015-10-14 08:27:10.254Z] [0005]   6840:Microsoft.VisualStudio.Web.Host - 10/14/2015 7:12:06 AM - 00:00:00.7656250
[2015-10-14 08:27:10.254Z] [0005]   7100:conhost - 10/13/2015 1:29:28 AM - 00:00:00.0937500
[2015-10-14 08:27:10.254Z] [0005]   6444:GWX - 10/13/2015 1:28:50 AM - 00:00:00.2343750
[2015-10-14 08:27:10.254Z] [0005]   4276:groove - 10/13/2015 1:28:46 AM - 00:03:13.3281250
[2015-10-14 08:27:10.281Z] [0005]   4472:svchost - ??? - ???
[2015-10-14 08:27:10.307Z] [0005]   728:svchost - ??? - ???
[2015-10-14 08:27:10.335Z] [0005]   332:WUDFHost - ??? - ???
[2015-10-14 08:27:10.365Z] [0005]   920:nvvsvc - ??? - ???
[2015-10-14 08:27:10.365Z] [0005]   9980:iexplore - 10/13/2015 3:55:27 PM - 00:00:12.2187500
[2015-10-14 08:27:10.393Z] [0005]   1508:officeclicktorun - ??? - ???
[2015-10-14 08:27:10.393Z] [0005]   5680:onenote - 10/13/2015 10:08:56 AM - 00:00:13.1562500
[2015-10-14 08:27:10.420Z] [0005]   1112:MMixSrv - ??? - ???
[2015-10-14 08:27:10.449Z] [0005]   3868:SearchIndexer - ??? - ???
[2015-10-14 08:27:10.476Z] [0005]   912:nvxdsync - ??? - ???
[2015-10-14 08:27:10.476Z] [0005]   2684:vstest.executionengine.x86 - 10/13/2015 1:50:00 AM - 00:00:00.3281250
[2015-10-14 08:27:10.503Z] [0005]   1600:dasHost - ??? - ???
[2015-10-14 08:27:10.503Z] [0005]   8076:conhost - 10/13/2015 1:30:07 AM - 00:00:00.0156250
[2015-10-14 08:27:10.503Z] [0005]   8984:conhost - 10/14/2015 7:17:43 AM - 00:00:00.0156250
[2015-10-14 08:27:10.503Z] [0005]   512:explorer - 10/13/2015 1:28:33 AM - 00:04:25.5312500
[2015-10-14 08:27:10.503Z] [0005]   6420:onenotem - 10/13/2015 1:28:50 AM - 00:00:00.4062500
[2015-10-14 08:27:10.503Z] [0005]   12932:msosync - 10/14/2015 8:26:15 AM - 00:00:00.5156250
[2015-10-14 08:27:10.503Z] [0005]   776:vstest.discoveryengine.x86 - 10/13/2015 1:50:00 AM - 00:00:01.2343750
[2015-10-14 08:27:10.531Z] [0005]   308:smss - ??? - ???
[2015-10-14 08:27:10.531Z] [0005]   13424:PowerShellToolsProcessHost - 10/14/2015 7:11:45 AM - 00:00:00.4843750
[2015-10-14 08:27:10.531Z] [0005]   7092:PowerShellToolsProcessHost - 10/13/2015 1:29:28 AM - 00:00:00.5625000
[2015-10-14 08:27:10.531Z] [0005]   5820:g2mlauncher - 10/13/2015 1:28:46 AM - 00:00:01.3281250
[2015-10-14 08:27:10.561Z] [0005]   6016:SearchFilterHost - ??? - ???
[2015-10-14 08:27:10.590Z] [0005]   6212:dllhost - ??? - ???
[2015-10-14 08:27:10.590Z] [0005]   4240:SkyDrive - 10/13/2015 1:28:36 AM - 00:00:23.1093750
[2015-10-14 08:27:10.590Z] [0005]   7728:node - 10/13/2015 1:29:55 AM - 00:00:03.4687500
[2015-10-14 08:27:10.590Z] [0005]   8620:iexplore - 10/13/2015 1:10:30 PM - 00:07:24.2031250
[2015-10-14 08:27:10.633Z] [0005]   492:svchost - ??? - ???
[2015-10-14 08:27:10.666Z] [0005]   688:svchost - ??? - ???
[2015-10-14 08:27:10.666Z] [0005]   5612:g2mcomm - 10/13/2015 1:28:45 AM - 00:47:12.6093750
[2015-10-14 08:27:10.705Z] [0005]   488:csrss - ??? - ???
[2015-10-14 08:27:10.736Z] [0005]   5016:sppsvc - ??? - ???
[2015-10-14 08:27:10.765Z] [0005]   876:nvSCPAPISvr - ??? - ???
[2015-10-14 08:27:10.795Z] [0005]   13808:SearchProtocolHost - ??? - ???
[2015-10-14 08:27:10.823Z] [0005]   480:wininit - ??? - ???
[2015-10-14 08:27:10.823Z] [0005]   11388:PowerShellToolsProcessHost - 10/14/2015 7:17:40 AM - 00:00:00.4531250
[2015-10-14 08:27:10.854Z] [0005]   280:svchost - ??? - ???
[2015-10-14 08:27:10.854Z] [0005]   6780:iexplore - 10/13/2015 8:56:58 AM - 00:00:31.8437500
[2015-10-14 08:27:10.854Z] [0005]   1656:iexplore - 10/13/2015 8:56:58 AM - 00:38:50.5937500
[2015-10-14 08:27:10.854Z] [0005]   11308:iisxpresstray - 10/14/2015 8:26:05 AM - 00:00:00.3593750
[2015-10-14 08:27:10.854Z] [0005]   5000:SettingSyncHost - 10/13/2015 1:28:38 AM - 00:00:15.1562500
[2015-10-14 08:27:10.854Z] [0005]   10812:dllhost - 10/14/2015 8:26:15 AM - 00:00:09.8437500
[2015-10-14 08:27:10.854Z] [0005]   12484:audiodg - 10/14/2015 2:55:56 AM - 00:00:13.8281250
[2015-10-14 08:27:10.854Z] [0005]   13768:conhost - 10/14/2015 8:26:04 AM - 00:00:00.0156250
[2015-10-14 08:27:10.884Z] [0005]   2040:ss_conn_service - ??? - ???
[2015-10-14 08:27:10.884Z] [0005]   9388:WinSCP - 10/14/2015 7:59:26 AM - 00:00:04.7031250
[2015-10-14 08:27:10.884Z] [0005]   13856:Taskmgr - 10/14/2015 3:03:08 AM - 00:01:42.9531250
[2015-10-14 08:27:10.884Z] [0005]   6368:dllhost - 10/13/2015 9:00:14 AM - 00:00:05.5312500
[2015-10-14 08:27:10.915Z] [0005]   3408:VsEtwService - ??? - ???
[2015-10-14 08:27:10.945Z] [0005]   2320:WmiPrvSE - ??? - ???
[2015-10-14 08:27:10.945Z] [0005]   10300:ConfigAssignUtility.vshost - 10/14/2015 8:17:49 AM - 00:00:00.0312500
[2015-10-14 08:27:10.945Z] [0005]   7736:conhost - 10/13/2015 1:29:55 AM - 00:00:00
[2015-10-14 08:27:10.973Z] [0005]   3992:NisSrv - ??? - ???
[2015-10-14 08:27:10.973Z] [0005]   10708:Ssms - 10/13/2015 4:11:23 PM - 00:01:28.5781250
[2015-10-14 08:27:10.974Z] [0005]   6944:devenv - 10/13/2015 1:29:25 AM - 03:29:14.6562500
[2015-10-14 08:27:10.974Z] [0005]   12260:node - 10/14/2015 7:11:46 AM - 00:00:00.0468750
[2015-10-14 08:27:11.003Z] [0005]   3788:WmiPrvSE - ??? - ???
[2015-10-14 08:27:11.003Z] [0005]   7136:conhost - 10/14/2015 7:11:45 AM - 00:00:00.0312500
[2015-10-14 08:27:11.003Z] [0005]   3980:devenv - 10/14/2015 7:11:41 AM - 00:02:06.3125000
[2015-10-14 08:27:11.003Z] [0005]   2992:hamachi-2-ui - 10/13/2015 1:28:46 AM - 00:00:02.1250000
[2015-10-14 08:27:11.032Z] [0005]   824:nvvsvc - ??? - ???
[2015-10-14 08:27:11.032Z] [0005]   10148:devenv - 10/14/2015 7:17:39 AM - 00:01:47.8906250
[2015-10-14 08:27:11.032Z] [0005]   7716:conhost - 10/13/2015 1:50:00 AM - 00:00:00.0156250
[2015-10-14 08:27:11.062Z] [0005]   1608:IpOverUsbSvc - ??? - ???
[2015-10-14 08:27:11.062Z] [0005]   6680:conhost - 10/14/2015 7:17:40 AM - 00:00:00.0156250
[2015-10-14 08:27:11.062Z] [0005]   6924:RuntimeBroker - 10/13/2015 3:50:52 PM - 00:00:08.7656250
[2015-10-14 08:27:11.062Z] [0005]   5544:sublime_text - 10/13/2015 6:54:34 AM - 00:00:24.2343750
[2015-10-14 08:27:11.091Z] [0005]   1800:MsDtsSrvr - ??? - ???
[2015-10-14 08:27:11.121Z] [0005]   616:lsass - ??? - ???
[2015-10-14 08:27:11.150Z] [0005]   1012:svchost - ??? - ???
[2015-10-14 08:27:11.150Z] [0005]   4160:MMix_32 - 10/13/2015 1:28:36 AM - 00:00:00.2968750
[2015-10-14 08:27:11.178Z] [0005]   1400:svchost - ??? - ???
[2015-10-14 08:27:11.178Z] [0005]   12628:MSBuild - 10/14/2015 8:17:00 AM - 00:00:02.9843750
[2015-10-14 08:27:11.206Z] [0005]   1988:sqlwriter - ??? - ???
[2015-10-14 08:27:11.237Z] [0005]   608:services - ??? - ???
[2015-10-14 08:27:11.264Z] [0005]   9620:ieetwcollector - ??? - ???
[2015-10-14 08:27:11.264Z] [0005]   3364:identities - 10/13/2015 4:12:25 PM - 00:00:00.7812500
[2015-10-14 08:27:11.264Z] [0005]   5528:g2mstart - 10/13/2015 1:28:45 AM - 00:00:00.2656250
[2015-10-14 08:27:11.264Z] [0005]   13396:node - 10/14/2015 7:17:43 AM - 00:00:00.0625000
[2015-10-14 08:27:11.290Z] [0005]   3948:svchost - ??? - ???
[2015-10-14 08:27:11.319Z] [0005]   4144:WUDFHost - ??? - ???
[2015-10-14 08:27:11.348Z] [0005]   4:System - ??? - ???
[2015-10-14 08:27:11.376Z] [0005]   988:svchost - ??? - ???
[2015-10-14 08:27:11.404Z] [0005]   396:csrss - ??? - ???
[2015-10-14 08:27:11.429Z] [0005]   0:Idle - ??? - ???

Reply with quote

martin◆ Site Admin

2015-10-16

Session log file please (Session.SessionLogPath), not a debug log.

Reply with quote

martin◆ Site Admin
Joined:: 2002-12-10
Posts:: 41,454
Location:: Prague, Czechia

2015-10-16

Though even from the debug log, I can see that the username is not correct:

[2015-10-14 08:27:09.171Z] [0005] Command: [open "ftp://%24BarOp:***@waws-prod-ch1-005.ftp.azurewebsites.windows.net" -passive=1 -timeout=15]

When I use the code from your first post (UserName = "MyInstance\\$MyOpName"), I get correctly:

open ftp://MyInstance%5C%24MyOpName:***@waws-prod-ch1-005.ftp.azurewebsites.windows.net -passive=1 -timeout=15

So I assume the problem has to be in the way to set the UserName in your code.

Reply with quote

amSteve
Joined:: 2015-10-07
Posts:: 4

2015-10-19 14:57

Sry to not have been explicit enough - my code is cloaking my credentials. I'm copying and pasting the string from Azure's dashboard to my code adding only the extra \ to escape the required slash.

here's a dump of the options (w/cloaked credentials) as they exist at the point where the .Open is executed :

FtpMode: Passive
FtpSecure: None
GiveUpSecurityAndAcceptAnySshHostKey: false
GiveUpSecurityAndAcceptAnyTlsHostCertificate: false
HostName: "waws-prod-ch1-005.ftp.azurewebsites.windows.net"
Password: "Foo"
PortNumber: 0
Protocol: Ftp
SecurePassword: {System.Security.SecureString}
SshHostKeyFingerprint: null
SshPrivateKeyPassphrase: null
SshPrivateKeyPath: null
Timeout: {00:00:15}
TimeoutInMilliseconds: 15000
TlsHostCertificateFingerprint: null
UserName: "BAR\\$BAR"
WebdavRoot: null
WebdavSecure: false

Reply with quote

martin◆ Site Admin
Joined:: 2002-12-10
Posts:: 41,454
Location:: Prague, Czechia

2015-10-20

What does it mean "my code is cloaking my credentials"? Does it mean the debug log file you have posted is altered? Because I was taking the example from your log file.

The problem is obviously with the credentials. If you want us to help you, we need to see the exact logs. If you cannot share these, you have to solve this on your own.

Reply with quote

amSteve

2015-10-20 13:00

sorry - this would be the first time i've been asked to provide live credentials to a public forum. Is it not sufficient to tell you that i'm pasting the same credentials to the assembly code that I'm pasting to the GUI? and further -- I'm using the same credentials in the assembly that is making a valid connection for the .net FtpWebRequest and NetworkCredential object.

Reply with quote