Password prompt shown (after timeout)

[jrieken]

re #1255

Not really sure what I am doing here but according to the test plan item I shouldn't be prompted for my password which I was. This is what I can remember

• run client in ubuntu vm, connect to another linux vm
• test, go back to mac for 1 minute or 2
• go back to vm, prompt shows, log is below

[07:43:54.981] remote-ssh-nightly@2019.8.39780
[07:43:54.981] linux x64
[07:43:54.982] SSH Resolver called for "ssh-remote+52.247.215.15", attempt 1
[07:43:54.982] SSH Resolver called for host: 52.247.215.15
[07:43:54.982] Setting up SSH remote "52.247.215.15"
[07:43:54.998] Acquiring local install lock: /tmp/vscode-remote-ssh-52.247.215.15-install.lock
[07:43:55.009] Looking for existing server data file at /home/jrieken/.config/Code - Insiders/User/globalStorage/ms-vscode-remote.remote-ssh-nightly/vscode-ssh-host-52.247.215.15-8cd00a7935eabef4aad0b0130b1d46daca0a47aa/data.json
[07:43:55.009] Found local server running: {"agentPort":35177,"osReleaseId":"ubuntu","webUiAccessToken":"","pid":24497,"ipcHandlePath":"/run/user/1000/snap.code-insiders/vscode-ssh-askpass-ad180f10899fe82eede7cd2e1a8affb491b39190.sock","socksPort":42297}
[07:43:55.012] Found running server - short-circuiting install
[07:43:55.013] Starting forwarding server. localPort 39745 -> socksPort 42297 -> remotePort 35177
[07:43:55.013] Forwarding server listening on 39745
[07:43:55.014] Waiting for ssh tunnel to be ready
[07:43:55.015] [Forwarding server 39745] Got connection 0
[07:43:55.015] Tunneled remote port 35177 to local port 39745
[07:43:55.016] Resolved "ssh-remote+52.247.215.15" to "127.0.0.1:39745"
[07:43:55.016] TELEMETRY: {"eventName":"resolver","properties":{"outcome":"success","osReleaseId":"ubuntu"},"measures":{"resolveAttempts":1,"retries":0}}
[07:43:55.016] ------




[07:43:55.045] [Forwarding server 39745] Got connection 1
[07:43:55.046] [Forwarding server 39745] Got connection 2
[07:43:57.649] [Forwarding server 39745] Got connection 3
[07:45:47.580] SSH Resolver called for "ssh-remote+52.247.215.15", attempt 2
[07:45:47.581] SSH Resolver called for host: 52.247.215.15
[07:45:47.581] Setting up SSH remote "52.247.215.15"
[07:45:47.582] Acquiring local install lock: /tmp/vscode-remote-ssh-52.247.215.15-install.lock
[07:45:47.584] Looking for existing server data file at /home/jrieken/.config/Code - Insiders/User/globalStorage/ms-vscode-remote.remote-ssh-nightly/vscode-ssh-host-52.247.215.15-8cd00a7935eabef4aad0b0130b1d46daca0a47aa/data.json
[07:45:47.584] Using commit id "8cd00a7935eabef4aad0b0130b1d46daca0a47aa" and quality "insider" for server
[07:45:47.596] Testing ssh with ssh -V
[07:45:47.610] ssh exited with code: 0
[07:45:47.610] Got stderr from ssh: OpenSSH_7.6p1 Ubuntu-4ubuntu0.3, OpenSSL 1.0.2n  7 Dec 2017
[07:45:47.611] Running script with connection command: ssh -D 41053 -o ConnectTimeout=15 52.247.215.15 bash
[07:45:47.611] Install and start server if needed
[07:45:47.613] askpass server listening on /run/user/1000/snap.code-insiders/vscode-ssh-askpass-731ebf254b7e7a7e49f08f25f9b85d7011c7f84f.sock
[07:45:47.614] Spawning local server with {"ipcHandlePath":"/run/user/1000/snap.code-insiders/vscode-ssh-askpass-d6301d92733f30e208074275830a019fef69e36b.sock","sshCommand":"ssh","sshArgs":["52.247.215.15","-D","41053","bash"],"dataFilePath":"/home/jrieken/.config/Code - Insiders/User/globalStorage/ms-vscode-remote.remote-ssh-nightly/vscode-ssh-host-52.247.215.15-8cd00a7935eabef4aad0b0130b1d46daca0a47aa/data.json"}
[07:45:47.614] Local server env: {"DISPLAY":"1","ELECTRON_RUN_AS_NODE":"1","SSH_ASKPASS":"/home/jrieken/.vscode-insiders/extensions/ms-vscode-remote.remote-ssh-nightly-2019.8.39780/out/local-server/askpass.sh","VSCODE_SSH_ASKPASS_NODE":"/snap/code-insiders/234/usr/share/code-insiders/code-insiders","VSCODE_SSH_ASKPASS_MAIN":"/home/jrieken/.vscode-insiders/extensions/ms-vscode-remote.remote-ssh-nightly-2019.8.39780/out/askpass-main.js","VSCODE_SSH_ASKPASS_HANDLE":"/run/user/1000/snap.code-insiders/vscode-ssh-askpass-731ebf254b7e7a7e49f08f25f9b85d7011c7f84f.sock"}
[07:45:47.623] Spawned 25658
[07:45:48.120] > Spawned 25667
> 
[07:45:48.121] Got some output, clearing connection timeout
[07:45:49.247] Got askpass request: {"request":"roblou@52.247.215.15's password:"}
[07:45:55.016] HTTP GET failed: Error: connect ENOENT /run/user/1000/snap.code-insiders/vscode-ssh-askpass-ad180f10899fe82eede7cd2e1a8affb491b39190.sock
[07:45:55.016] Server delay-shutdown request failed: connect ENOENT /run/user/1000/snap.code-insiders/vscode-ssh-askpass-ad180f10899fe82eede7cd2e1a8affb491b39190.sock
[07:46:19.235] Interactor gave response: roblouubuntuVM1
[07:46:20.674] > Running remote connection script
[07:46:20.677] > Acquiring lock on /home/roblou/.vscode-server-insiders/bin/8cd00a7935eabef4aad0b0130b1d46daca0a47aa/vscode-remote-lock.8cd00a7935eabef4aad0b0130b1d46daca0a47aa
[07:46:20.681] > Found existing installation at /home/roblou/.vscode-server-insiders/bin/8cd00a7935eabef4aad0b0130b1d46daca0a47aa...
[07:46:20.695] > Found running server...
>  
> *
> * Reminder: You may only use this software with Visual Studio family products,
> * as described in the license (https://go.microsoft.com/fwlink/?linkid=2077057)
> *
>  
[07:46:20.712] > Checking server status on port 35177 with wget
[07:46:20.719] > c3f93289-95d9-4fa0-90ac-2f87220d27ac: start
[07:46:20.829] > sshAuthSock====
> agentPort==35177==
> webViewServerPort====
> osReleaseId==ubuntu==
> webUiAccessToken====
> c3f93289-95d9-4fa0-90ac-2f87220d27ac: end
[07:46:20.831] Received install output: 
sshAuthSock====
agentPort==35177==
webViewServerPort====
osReleaseId==ubuntu==
webUiAccessToken====

[07:46:20.834] Remote server is listening on port 35177
[07:46:20.836] Parsed server configuration: {"agentPort":35177,"osReleaseId":"ubuntu","webUiAccessToken":"","sshAuthSock":""}
[07:46:20.837] Persisting server connection details to /home/jrieken/.config/Code - Insiders/User/globalStorage/ms-vscode-remote.remote-ssh-nightly/vscode-ssh-host-52.247.215.15-8cd00a7935eabef4aad0b0130b1d46daca0a47aa/data.json
[07:46:20.841] Starting forwarding server. localPort 46775 -> socksPort 41053 -> remotePort 35177
[07:46:20.841] Forwarding server listening on 46775
[07:46:20.842] Waiting for ssh tunnel to be ready
[07:46:20.843] [Forwarding server 46775] Got connection 0
[07:46:20.844] Tunneled remote port 35177 to local port 46775
[07:46:20.844] Resolved "ssh-remote+52.247.215.15" to "127.0.0.1:46775"
[07:46:20.845] TELEMETRY: {"eventName":"resolver","properties":{"outcome":"success","osReleaseId":"ubuntu"},"measures":{"resolveAttempts":2,"retries":1}}
[07:46:20.845] ------




[07:46:20.984] [Forwarding server 46775] Got connection 1
[07:46:20.986] [Forwarding server 46775] Got connection 2

[roblourens]

Did the vm go offline briefly? It looks like the connection was lost and reconnection happened.

[jrieken]

Hard to tell... It sleeps pretty aggressively but I can't say for sure.

[roblourens]

Lots of changes, probably fixed... resetting for the next round of testing