Skip to content

Wrong result when password input times out #2466

New issue
New issue
Open
Open
Wrong result when password input times out#2466
Assignees
roblourens
Labels
bugIssue identified by VS Code Team member as probable bugplan-reviewPM-highlighted item determined to be P1 or P2sshIssue in vscode-remote SSH
Milestone
Backlog

Description

@roblourens
roblourens
opened on Mar 2, 2020
  • Connect to a remote with a password
  • Don't enter the password, let the prompt sit for a few minutes
  • Log shows unhandled errors and "can't parse server output"
[10:21:31.739] Log Level: 1
[10:21:31.743] remote-ssh-nightly@2020.3.2402
[10:21:31.743] darwin x64
[10:21:31.746] SSH Resolver called for "ssh-remote+az-win", attempt 1
[10:21:31.746] SSH Resolver called for host: az-win
[10:21:31.746] Setting up SSH remote "az-win"
[10:21:31.750] Acquiring local install lock: /var/folders/tx/p0ycbfpj37786p760wwdg6y80000gn/T/vscode-remote-ssh-az-win-install.lock
[10:21:31.766] Looking for existing server data file at /Users/roblou/Library/Application Support/Code - Insiders/User/globalStorage/ms-vscode-remote.remote-ssh-nightly/vscode-ssh-host-az-win-d1c48c103cabc6e729f59787c0b9c86fb3d39e73/data.json
[10:21:31.768] Using commit id "d1c48c103cabc6e729f59787c0b9c86fb3d39e73" and quality "insider" for server
[10:21:31.770] Install and start server if needed
[10:21:31.778] Checking ssh with "ssh -V"
[10:21:31.794] > OpenSSH_7.9p1, LibreSSL 2.7.3
[10:21:31.798] askpass server listening on /var/folders/tx/p0ycbfpj37786p760wwdg6y80000gn/T/vscode-ssh-askpass-cc49f95f64867e27a29de56a247395e99f160ab8.sock
[10:21:31.798] Spawning local server with {"ipcHandlePath":"/var/folders/tx/p0ycbfpj37786p760wwdg6y80000gn/T/vscode-ssh-askpass-600ae27a9dd49ac49cc695c01b8cf71fa086a91a.sock","sshCommand":"ssh","sshArgs":["-v","-T","-D","64209","-o","ConnectTimeout=15","az-win"],"dataFilePath":"/Users/roblou/Library/Application Support/Code - Insiders/User/globalStorage/ms-vscode-remote.remote-ssh-nightly/vscode-ssh-host-az-win-d1c48c103cabc6e729f59787c0b9c86fb3d39e73/data.json"}
[10:21:31.799] Local server env: {"DISPLAY":"1","ELECTRON_RUN_AS_NODE":"1","SSH_ASKPASS":"/Users/roblou/.vscode-insiders/extensions/ms-vscode-remote.remote-ssh-nightly-2020.3.2402/out/local-server/askpass.sh","VSCODE_SSH_ASKPASS_NODE":"/Applications/Visual Studio Code - Insiders.app/Contents/Frameworks/Code - Insiders Helper (Renderer).app/Contents/MacOS/Code - Insiders Helper (Renderer)","VSCODE_SSH_ASKPASS_MAIN":"/Users/roblou/.vscode-insiders/extensions/ms-vscode-remote.remote-ssh-nightly-2020.3.2402/out/askpass-main.js","VSCODE_SSH_ASKPASS_HANDLE":"/var/folders/tx/p0ycbfpj37786p760wwdg6y80000gn/T/vscode-ssh-askpass-cc49f95f64867e27a29de56a247395e99f160ab8.sock"}
[10:21:31.803] Spawned 60407
[10:21:31.915] > local-server> Spawned ssh: 60408
[10:21:31.926] stderr> OpenSSH_7.9p1, LibreSSL 2.7.3
[10:21:31.926] stderr> debug1: Reading configuration data /Users/roblou/.ssh/config
[10:21:31.927] stderr> debug1: Reading configuration data /Users/roblou/.ssh/config2
[10:21:31.927] stderr> debug1: Reading configuration data /Users/roblou/.ssh/config3
[10:21:31.927] stderr> debug1: /Users/roblou/.ssh/config line 49: Applying options for az-win
[10:21:31.927] stderr> debug1: /Users/roblou/.ssh/config line 83: Applying options for *
[10:21:31.927] stderr> debug1: Reading configuration data /etc/ssh/ssh_config
[10:21:31.927] stderr> debug1: /etc/ssh/ssh_config line 48: Applying options for *
[10:21:31.928] stderr> debug1: /etc/ssh/ssh_config line 52: Applying options for *
[10:21:31.928] stderr> debug1: Connecting to 13.78.129.63 [13.78.129.63] port 4321.
[10:21:32.057] stderr> debug1: fd 3 clearing O_NONBLOCK
[10:21:32.057] stderr> debug1: Connection established.
[10:21:32.058] stderr> debug1: identity file /Users/roblou/.ssh/id_rsa type 0
[10:21:32.059] stderr> debug1: identity file /Users/roblou/.ssh/id_rsa-cert type -1
[10:21:32.059] stderr> debug1: identity file /Users/roblou/.ssh/id_dsa type -1
[10:21:32.059] stderr> debug1: identity file /Users/roblou/.ssh/id_dsa-cert type -1
[10:21:32.060] stderr> debug1: identity file /Users/roblou/.ssh/id_ecdsa type -1
[10:21:32.060] stderr> debug1: identity file /Users/roblou/.ssh/id_ecdsa-cert type -1
[10:21:32.060] stderr> debug1: identity file /Users/roblou/.ssh/id_ed25519 type -1
[10:21:32.060] stderr> debug1: identity file /Users/roblou/.ssh/id_ed25519-cert type -1
[10:21:32.060] stderr> debug1: identity file /Users/roblou/.ssh/id_xmss type -1
[10:21:32.061] stderr> debug1: identity file /Users/roblou/.ssh/id_xmss-cert type -1
[10:21:32.061] stderr> debug1: Local version string SSH-2.0-OpenSSH_7.9
[10:21:32.061] stderr> debug1: Remote protocol version 2.0, remote software version OpenSSH_for_Windows_7.7
[10:21:32.061] stderr> debug1: match: OpenSSH_for_Windows_7.7 pat OpenSSH* compat 0x04000000
[10:21:32.061] stderr> debug1: Authenticating to 13.78.129.63:4321 as 'roblou'
[10:21:32.062] stderr> debug1: SSH2_MSG_KEXINIT sent
[10:21:32.191] stderr> debug1: SSH2_MSG_KEXINIT received
[10:21:32.192] stderr> debug1: kex: algorithm: curve25519-sha256
[10:21:32.192] stderr> debug1: kex: host key algorithm: ecdsa-sha2-nistp256
[10:21:32.192] stderr> debug1: kex: server->client cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: none
[10:21:32.193] stderr> debug1: kex: client->server cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: none
[10:21:32.195] stderr> debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
[10:21:32.231] stderr> debug1: Server host key: ecdsa-sha2-nistp256 SHA256:B5u79nIKTsEPOb8BGU6RUwqUYDxG4pxlhFrvluvrMlA
[10:21:32.233] stderr> debug1: Host '[13.78.129.63]:4321' is known and matches the ECDSA host key.
[10:21:32.233] stderr> debug1: Found key in /Users/roblou/.ssh/known_hosts:2
[10:21:32.236] stderr> debug1: rekey after 134217728 blocks
[10:21:32.237] stderr> debug1: SSH2_MSG_NEWKEYS sent
[10:21:32.237] stderr> debug1: expecting SSH2_MSG_NEWKEYS
[10:21:32.237] stderr> debug1: SSH2_MSG_NEWKEYS received
[10:21:32.237] stderr> debug1: rekey after 134217728 blocks
[10:21:32.238] stderr> debug1: Will attempt key: /Users/roblou/.ssh/id_rsa RSA SHA256:roLWOAd4DY8UlCXthcUaIrEPdgK9nNzs/ixa+eugGJE agent
[10:21:32.238] stderr> debug1: Will attempt key: roblou@microsoft.com RSA SHA256:wu42apo8AMaqpKRVq3HRWtKIOHTUrIDTm9Klp8EiXZA agent
[10:21:32.238] stderr> debug1: Will attempt key: /Users/roblou/.ssh/id_dsa 
[10:21:32.238] stderr> debug1: Will attempt key: /Users/roblou/.ssh/id_ecdsa 
[10:21:32.238] stderr> debug1: Will attempt key: /Users/roblou/.ssh/id_ed25519 
[10:21:32.238] stderr> debug1: Will attempt key: /Users/roblou/.ssh/id_xmss 
[10:21:32.239] stderr> debug1: SSH2_MSG_EXT_INFO received
[10:21:32.239] stderr> debug1: kex_input_ext_info: server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
[10:21:32.342] stderr> debug1: SSH2_MSG_SERVICE_ACCEPT received
[10:21:32.374] stderr> debug1: Authentications that can continue: publickey,password,keyboard-interactive
[10:21:32.375] stderr> debug1: Next authentication method: publickey
[10:21:32.375] stderr> debug1: Offering public key: /Users/roblou/.ssh/id_rsa RSA SHA256:roLWOAd4DY8UlCXthcUaIrEPdgK9nNzs/ixa+eugGJE agent
[10:21:32.406] stderr> debug1: Authentications that can continue: publickey,password,keyboard-interactive
[10:21:32.406] stderr> debug1: Offering public key: roblou@microsoft.com RSA SHA256:wu42apo8AMaqpKRVq3HRWtKIOHTUrIDTm9Klp8EiXZA agent
[10:21:32.437] stderr> debug1: Authentications that can continue: publickey,password,keyboard-interactive
[10:21:32.438] stderr> debug1: Trying private key: /Users/roblou/.ssh/id_dsa
[10:21:32.438] stderr> debug1: Trying private key: /Users/roblou/.ssh/id_ecdsa
[10:21:32.438] stderr> debug1: Trying private key: /Users/roblou/.ssh/id_ed25519
[10:21:32.439] stderr> debug1: Trying private key: /Users/roblou/.ssh/id_xmss
[10:21:32.439] stderr> debug1: Next authentication method: keyboard-interactive
[10:21:32.467] stderr> debug1: Authentications that can continue: publickey,password,keyboard-interactive
[10:21:32.467] stderr> debug1: Next authentication method: password
[10:21:32.467] stderr> debug1: read_passphrase: can't open /dev/tty: Device not configured
[10:21:32.593] Got askpass request: {"request":"roblou@13.78.129.63's password:"}
[10:21:32.594] Showing password prompt
[10:21:32.597] Listening for interwindow password on /var/folders/tx/p0ycbfpj37786p760wwdg6y80000gn/T/vscode-ssh-askpass-5de4bdf9fa469330697718d18d6c9a6edf720995.sock
[10:21:32.597] Writing password prompt to globalState
[10:31:32.576] Askpass request timed out
[10:31:32.579] Cleaning up other-window auth server
[10:31:32.588] Handling auth request failed: Error: Canceled
	at /Users/roblou/.vscode-insiders/extensions/ms-vscode-remote.remote-ssh-nightly-2020.3.2402/out/extension.js:1:90621
	at l.fire (/Applications/Visual Studio Code - Insiders.app/Contents/Resources/app/out/vs/workbench/services/extensions/node/extensionHostProcess.js:48:845)
	at s.cancel (/Applications/Visual Studio Code - Insiders.app/Contents/Resources/app/out/vs/workbench/services/extensions/node/extensionHostProcess.js:52:698)
	at t.CancellationTokenSource.cancel (/Applications/Visual Studio Code - Insiders.app/Contents/Resources/app/out/vs/workbench/services/extensions/node/extensionHostProcess.js:53:239)
	at /Users/roblou/.vscode-insiders/extensions/ms-vscode-remote.remote-ssh-nightly-2020.3.2402/out/extension.js:1:374793
	at l.fire (/Applications/Visual Studio Code - Insiders.app/Contents/Resources/app/out/vs/workbench/services/extensions/node/extensionHostProcess.js:48:845)
	at s.cancel (/Applications/Visual Studio Code - Insiders.app/Contents/Resources/app/out/vs/workbench/services/extensions/node/extensionHostProcess.js:52:698)
	at t.CancellationTokenSource.cancel (/Applications/Visual Studio Code - Insiders.app/Contents/Resources/app/out/vs/workbench/services/extensions/node/extensionHostProcess.js:53:239)
	at ServerResponse.<anonymous> (/Users/roblou/.vscode-insiders/extensions/ms-vscode-remote.remote-ssh-nightly-2020.3.2402/out/extension.js:1:275664)
	at ServerResponse.emit (events.js:203:13)
	at Socket.socketOnTimeout (_http_server.js:472:33)
	at Socket.emit (events.js:203:13)
	at Socket._onTimeout (net.js:434:8)
	at listOnTimeout (internal/timers.js:531:17)
	at processTimers (internal/timers.js:475:7)
[10:31:32.589] Unhandled rejection: Error: Handling auth request failed: Error: Canceled
	at /Users/roblou/.vscode-insiders/extensions/ms-vscode-remote.remote-ssh-nightly-2020.3.2402/out/extension.js:1:90621
	at l.fire (/Applications/Visual Studio Code - Insiders.app/Contents/Resources/app/out/vs/workbench/services/extensions/node/extensionHostProcess.js:48:845)
	at s.cancel (/Applications/Visual Studio Code - Insiders.app/Contents/Resources/app/out/vs/workbench/services/extensions/node/extensionHostProcess.js:52:698)
	at t.CancellationTokenSource.cancel (/Applications/Visual Studio Code - Insiders.app/Contents/Resources/app/out/vs/workbench/services/extensions/node/extensionHostProcess.js:53:239)
	at /Users/roblou/.vscode-insiders/extensions/ms-vscode-remote.remote-ssh-nightly-2020.3.2402/out/extension.js:1:374793
	at l.fire (/Applications/Visual Studio Code - Insiders.app/Contents/Resources/app/out/vs/workbench/services/extensions/node/extensionHostProcess.js:48:845)
	at s.cancel (/Applications/Visual Studio Code - Insiders.app/Contents/Resources/app/out/vs/workbench/services/extensions/node/extensionHostProcess.js:52:698)
	at t.CancellationTokenSource.cancel (/Applications/Visual Studio Code - Insiders.app/Contents/Resources/app/out/vs/workbench/services/extensions/node/extensionHostProcess.js:53:239)
	at ServerResponse.<anonymous> (/Users/roblou/.vscode-insiders/extensions/ms-vscode-remote.remote-ssh-nightly-2020.3.2402/out/extension.js:1:275664)
	at ServerResponse.emit (events.js:203:13)
	at Socket.socketOnTimeout (_http_server.js:472:33)
	at Socket.emit (events.js:203:13)
	at Socket._onTimeout (net.js:434:8)
	at listOnTimeout (internal/timers.js:531:17)
	at processTimers (internal/timers.js:475:7)
	at IncomingMessage.<anonymous> (/Users/roblou/.vscode-insiders/extensions/ms-vscode-remote.remote-ssh-nightly-2020.3.2402/out/extension.js:1:275939)
[10:31:32.590] stderr> Failed to get SSH credentials: Error in request: socket hang up
[10:31:32.590] stderr> ["/Applications/Visual Studio Code - Insiders.app/Contents/Frameworks/Code - Insiders Helper (Renderer).app/Contents/MacOS/Code - Insiders Helper (Renderer)","/Users/roblou/.vscode-insiders/extensions/ms-vscode-remote.remote-ssh-nightly-2020.3.2402/out/askpass-main.js","roblou@13.78.129.63's","password:"]
[10:31:32.592] stderr> ssh_dispatch_run_fatal: Connection to 13.78.129.63 port 4321: Broken pipe
[10:31:32.594] > local-server> ssh child died, shutting down
[10:31:32.601] Local server exit: 0
[10:31:32.602] Received install output: OpenSSH_7.9p1, LibreSSL 2.7.3
debug1: Server host key: ecdsa-sha2-nistp256 SHA256:B5u79nIKTsEPOb8BGU6RUwqUYDxG4pxlhFrvluvrMlA
Failed to get SSH credentials: Error in request: socket hang up
["/Applications/Visual Studio Code - Insiders.app/Contents/Frameworks/Code - Insiders Helper (Renderer).app/Contents/MacOS/Code - Insiders Helper (Renderer)","/Users/roblou/.vscode-insiders/extensions/ms-vscode-remote.remote-ssh-nightly-2020.3.2402/out/askpass-main.js","roblou@13.78.129.63's","password:"]
ssh_dispatch_run_fatal: Connection to 13.78.129.63 port 4321: Broken pipe

[10:31:32.603] Stopped parsing output early. Remaining text: OpenSSH_7.9p1, LibreSSL 2.7.3debug1: Server host key: ecdsa-sha2-nistp256 SHA256:B5u79nIKTsEPOb8BGU6RUwqUYDxG4pxlhFrvluvrMlAFailed to get SSH credentials: Error in request: socket hang up["/Applications/Visual Studio Code - Insiders.app/Contents/Frameworks/Code - Insiders Helper (Renderer).app/Contents/MacOS/Code - Insiders Helper (Renderer)","/Users/roblou/.vscode-insiders/extensions/ms-vscode-remote.remote-ssh-nightly-2020.3.2402/out/askpass-main.js","roblou@13.78.129.63's","password:"]ssh_dispatch_run_fatal: Connection to 13.78.129.63 port 4321: Broken pipe
[10:31:32.604] Failed to parse remote port from server output
[10:31:32.605] Resolver error: 
[10:31:32.608] TELEMETRY: {"eventName":"resolver","properties":{"outcome":"failure","reason":"UnparsableOutput","askedPw":"0","askedPassphrase":"0","asked2fa":"0","askedHostKey":"0","gotUnrecognizedPrompt":"0","remoteInConfigFile":"1"},"measures":{"resolveAttempts":1,"retries":1}}
[10:31:32.610] ------

Activity

  1. roblourens
    added
    bugIssue identified by VS Code Team member as probable bug
    sshIssue in vscode-remote SSH
    on Mar 2, 2020
  2. roblourens
    added this to the March 2020 milestone on Mar 2, 2020
  3. roblourens
    self-assigned this
    on Mar 2, 2020
  4. roblourens
    modified the milestones: March 2020, April 2020 on Mar 31, 2020
  5. roblourens
    modified the milestones: April 2020, May 2020 on Apr 28, 2020
  6. roblourens
    modified the milestones: May 2020, June 2020 on May 31, 2020
  7. roblourens
    modified the milestones: June 2020, July 2020 on Jul 1, 2020
  8. roblourens
    modified the milestones: July 2020, Backlog on Jul 27, 2020
  9. Brigit Murtaugh (bamurtaugh)
    added
    plan-reviewPM-highlighted item determined to be P1 or P2
    on Nov 20, 2020
  10. tanhakabir
    self-assigned this
    on Feb 8, 2022
  11. tanhakabir
    removed their assignment
    on Sep 2, 2022
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment

Metadata

Metadata

Assignees

Labels

bugIssue identified by VS Code Team member as probable bugplan-reviewPM-highlighted item determined to be P1 or P2sshIssue in vscode-remote SSH

Type

No type

Projects

No projects

    Milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions