Please help interpreting TRAMP debug log

classic Classic list List threaded Threaded
2 messages Options
Reply | Threaded
Open this post in threaded view
|

Please help interpreting TRAMP debug log

Camden Narzt
I’m trying to use tramp to edit files on a remote host, and it’s not connecting. I see "Couldn't find local shell prompt for /bin/sh” as well as "Process has died” errors, and was just wondering whether the process is killed because it doesn’t find a prompt, or if it doesn’t find a prompt because it died.

Is there any way to see the output from the connection? Or am I missing something helpful in the logs?


Here’s the log:

;; Emacs: 27.1 Tramp: 2.4.3.27.1 -*- mode: outline; -*-
;; Location: /usr/local/Cellar/emacs/27.1/share/emacs/27.1/lisp/net/tramp.elc Git: /
13:53:47.404529 tramp-get-connection-property (7) # perl undef
13:53:47.404608 tramp-get-remote-perl (5) # Finding a suitable `perl' command
13:53:47.404663 tramp-get-connection-property (7) # process-buffer nil
13:53:47.404718 tramp-get-connection-property (7) # process-buffer nil
13:53:47.404762 tramp-set-connection-property (7) # process-buffer nil
13:53:47.404813 tramp-get-connection-property (7) # process-buffer nil
13:53:47.404896 tramp-get-connection-property (7) # remote-path undef
13:53:47.404955 tramp-file-name-handler (5) # Non-essential received in operation (file-name-all-completions  /ssh:secur-t.local:)
13:53:47.406185 tramp-get-connection-property (7) # perl undef
13:53:47.406245 tramp-get-remote-perl (5) # Finding a suitable `perl' command
13:53:47.406309 tramp-get-connection-property (7) # process-buffer nil
13:53:47.406356 tramp-get-connection-property (7) # process-buffer nil
13:53:47.406424 tramp-get-connection-property (7) # remote-path undef
13:53:47.406477 tramp-file-name-handler (5) # Non-essential received in operation (file-name-all-completions  /ssh:secur-t.local:)
13:53:47.407743 tramp-get-connection-property (7) # perl undef
13:53:47.407801 tramp-get-remote-perl (5) # Finding a suitable `perl' command
13:53:47.407848 tramp-get-connection-property (7) # process-buffer nil
13:53:47.407895 tramp-get-connection-property (7) # process-buffer nil
13:53:47.407963 tramp-get-connection-property (7) # remote-path undef
13:53:47.408017 tramp-file-name-handler (5) # Non-essential received in operation (file-name-all-completions  /ssh:secur-t.local:)
13:53:47.409563 tramp-get-connection-property (7) # perl undef
13:53:47.409622 tramp-get-remote-perl (5) # Finding a suitable `perl' command
13:53:47.409670 tramp-get-connection-property (7) # process-buffer nil
13:53:47.409718 tramp-get-connection-property (7) # process-buffer nil
13:53:47.409784 tramp-get-connection-property (7) # remote-path undef
13:53:47.409886 tramp-file-name-handler (5) # Non-essential received in operation (file-name-all-completions  /ssh:secur-t.local:)
13:54:13.382922 tramp-get-connection-property (7) # ~ undef
13:54:13.383086 tramp-get-connection-property (7) # process-name nil
13:54:13.383181 tramp-get-connection-property (7) # process-name nil
13:54:13.383267 tramp-get-connection-property (7) # process-buffer nil
13:54:13.383592 tramp-maybe-open-connection (3) # Opening connection for secur-t.local using ssh...
13:54:13.383901 tramp-get-connection-property (7) # login-args undef
13:54:13.383988 tramp-get-connection-property (7) # login-args undef
13:54:13.384101 tramp-get-connection-property (7) # process-name nil
13:54:13.384171 tramp-get-connection-property (7) # process-buffer nil
13:54:13.388443 tramp-maybe-open-connection (6) # /bin/sh -i
13:54:13.388600 tramp-get-connection-property (7) # check-remote-echo nil
13:54:13.388677 tramp-get-connection-property (7) # check-remote-echo nil
13:54:13.388861 tramp-get-connection-property (7) # prompt nil
13:54:13.388931 tramp-process-sentinel (5) # Sentinel called: `#<process *tramp/ssh secur-t.local*>' `killed: 9
'
13:54:13.389026 tramp-flush-connection-properties (7) # *tramp/ssh secur-t.local* nil
13:54:13.389121 tramp-get-file-property (8) #  file-truename nil
13:54:13.389197 tramp-flush-directory-properties (8) # 
13:54:13.389283 tramp-accept-process-output (10) # *tramp/ssh secur-t.local* nil signal nil
13:54:13.406741 tramp-wait-for-regexp (10) # 
  backtrace()
  tramp-error(#<process *tramp/ssh secur-t.local*> file-error "Process has died")
  apply(tramp-error #<process *tramp/ssh secur-t.local*> file-error "Process has died" nil)
  tramp-wait-for-regexp(#<process *tramp/ssh secur-t.local*> 10 "\\(^[^#$%>\n]*[#$%>] *\\|\\(?:^\\|\15\\)[^]#$%>\n]*#?[]#$%>...")
  tramp-barf-if-no-shell-prompt(#<process *tramp/ssh secur-t.local*> 10 "Couldn't find local shell prompt for %s" "/bin/sh")
  tramp-maybe-open-connection((tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil))
  tramp-send-command((tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil) "cd ~ && pwd")
  tramp-sh-handle-expand-file-name("/ssh:secur-t.local:" nil)
  apply(tramp-sh-handle-expand-file-name ("/ssh:secur-t.local:" nil))
  tramp-sh-file-name-handler(expand-file-name "/ssh:secur-t.local:" nil)
  apply(tramp-sh-file-name-handler expand-file-name ("/ssh:secur-t.local:" nil))
  tramp-file-name-handler(expand-file-name "/ssh:secur-t.local:" nil)
  file-name-all-completions("" "/ssh:secur-t.local:")
  ido-file-name-all-completions-1("/ssh:secur-t.local:")
  ido-file-name-all-completions("/ssh:secur-t.local:")
  ido-make-file-list-1("/ssh:secur-t.local:")
  ido-make-file-list("Developer/")
  ido-read-internal(file "Find file: " ido-file-history nil nil nil)
  ido-file-internal(raise-frame)
  ido-find-file()
  funcall-interactively(ido-find-file)
  call-interactively(ido-find-file nil nil)
  command-execute(ido-find-file)
13:54:13.406896 tramp-wait-for-regexp (1) # File error: Process has died
13:54:13.428593 tramp-wait-for-regexp (10) # 
  backtrace()
  tramp-signal-hook-function(error ("Cannot resize window #<window 312 on *Ibuffer*>"))
  signal(error ("Cannot resize window #<window 312 on *Ibuffer*>"))
  error("Cannot resize window %s" #<window 312 on *Ibuffer*>)
  window-resize(#<window 312 on *Ibuffer*> 46)
  display-buffer-use-some-window(#<buffer *tramp/ssh secur-t.local*> nil)
  display-buffer(#<buffer *tramp/ssh secur-t.local*> nil)
  pop-to-buffer(#<buffer *tramp/ssh secur-t.local*>)
  #f(compiled-function () #<bytecode 0x1fe997caeac9>)()
  tramp-wait-for-regexp(#<process *tramp/ssh secur-t.local*> 10 "\\(^[^#$%>\n]*[#$%>] *\\|\\(?:^\\|\15\\)[^]#$%>\n]*#?[]#$%>...")
  tramp-barf-if-no-shell-prompt(#<process *tramp/ssh secur-t.local*> 10 "Couldn't find local shell prompt for %s" "/bin/sh")
  tramp-maybe-open-connection((tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil))
  tramp-send-command((tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil) "cd ~ && pwd")
  tramp-sh-handle-expand-file-name("/ssh:secur-t.local:" nil)
  apply(tramp-sh-handle-expand-file-name ("/ssh:secur-t.local:" nil))
  tramp-sh-file-name-handler(expand-file-name "/ssh:secur-t.local:" nil)
  apply(tramp-sh-file-name-handler expand-file-name ("/ssh:secur-t.local:" nil))
  tramp-file-name-handler(expand-file-name "/ssh:secur-t.local:" nil)
  file-name-all-completions("" "/ssh:secur-t.local:")
  ido-file-name-all-completions-1("/ssh:secur-t.local:")
  ido-file-name-all-completions("/ssh:secur-t.local:")
  ido-make-file-list-1("/ssh:secur-t.local:")
  ido-make-file-list("Developer/")
  ido-read-internal(file "Find file: " ido-file-history nil nil nil)
  ido-file-internal(raise-frame)
  ido-find-file()
  funcall-interactively(ido-find-file)
  call-interactively(ido-find-file nil nil)
  command-execute(ido-find-file)
13:54:13.428762 tramp-wait-for-regexp (1) # error: "Cannot resize window #<window 312 on *Ibuffer*>"
13:54:15.576683 tramp-get-connection-property (7) # process-buffer nil
13:54:15.596031 tramp-barf-if-no-shell-prompt (10) # 
  backtrace()
  tramp-error((tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil) file-error "Couldn't find local shell prompt for %s" "/bin/sh")
  apply(tramp-error (tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil) file-error "Couldn't find local shell prompt for %s" "/bin/sh")
  tramp-error-with-buffer(#<buffer *tramp/ssh secur-t.local*> (tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil) file-error "Couldn't find local shell prompt for %s" "/bin/sh")
  apply(tramp-error-with-buffer #<buffer *tramp/ssh secur-t.local*> (tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil) file-error ("Couldn't find local shell prompt for %s" "/bin/sh"))
  tramp-barf-if-no-shell-prompt(#<process *tramp/ssh secur-t.local*> 10 "Couldn't find local shell prompt for %s" "/bin/sh")
  tramp-maybe-open-connection((tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil))
  tramp-send-command((tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil) "cd ~ && pwd")
  tramp-sh-handle-expand-file-name("/ssh:secur-t.local:" nil)
  apply(tramp-sh-handle-expand-file-name ("/ssh:secur-t.local:" nil))
  tramp-sh-file-name-handler(expand-file-name "/ssh:secur-t.local:" nil)
  apply(tramp-sh-file-name-handler expand-file-name ("/ssh:secur-t.local:" nil))
  tramp-file-name-handler(expand-file-name "/ssh:secur-t.local:" nil)
  file-name-all-completions("" "/ssh:secur-t.local:")
  ido-file-name-all-completions-1("/ssh:secur-t.local:")
  ido-file-name-all-completions("/ssh:secur-t.local:")
  ido-make-file-list-1("/ssh:secur-t.local:")
  ido-make-file-list("Developer/")
  ido-read-internal(file "Find file: " ido-file-history nil nil nil)
  ido-file-internal(raise-frame)
  ido-find-file()
  funcall-interactively(ido-find-file)
  call-interactively(ido-find-file nil nil)
  command-execute(ido-find-file)
13:54:15.596193 tramp-barf-if-no-shell-prompt (1) # File error: Couldn't find local shell prompt for /bin/sh
13:54:15.596537 tramp-maybe-open-connection (3) # Opening connection for secur-t.local using ssh...failed
13:54:15.596661 tramp-get-file-property (8) #  file-truename nil
13:54:15.596728 tramp-flush-directory-properties (8) # 
13:54:15.596803 tramp-get-connection-property (7) # process-name nil
13:54:15.596872 tramp-flush-connection-properties (7) # (tramp-file-name ssh nil nil secur-t.local nil nil nil) (process-buffer)
13:54:15.596956 tramp-get-connection-property (7) # process-buffer nil
13:54:15.620716 tramp-get-connection-property (7) # ~ undef
13:54:15.620824 tramp-get-connection-property (7) # process-name nil
13:54:15.620887 tramp-get-connection-property (7) # process-name nil
13:54:15.620951 tramp-get-connection-property (7) # process-buffer nil
13:54:15.621013 tramp-get-connection-property (7) # process-buffer nil
13:54:15.621064 tramp-set-connection-property (7) # process-buffer nil
13:54:15.621308 tramp-maybe-open-connection (3) # Opening connection for secur-t.local using ssh...
13:54:15.621558 tramp-get-connection-property (7) # login-args undef
13:54:15.621634 tramp-get-connection-property (7) # login-args undef
13:54:15.621726 tramp-get-connection-property (7) # process-name nil
13:54:15.621786 tramp-get-connection-property (7) # process-buffer nil
13:54:15.624367 tramp-maybe-open-connection (6) # /bin/sh -i
13:54:15.624504 tramp-get-connection-property (7) # check-remote-echo nil
13:54:15.624564 tramp-get-connection-property (7) # check-remote-echo nil
13:54:15.624709 tramp-get-connection-property (7) # prompt nil
13:54:15.624767 tramp-process-sentinel (5) # Sentinel called: `#<process *tramp/ssh secur-t.local*>' `killed: 9
'
13:54:15.624843 tramp-flush-connection-properties (7) # *tramp/ssh secur-t.local* nil
13:54:15.624926 tramp-get-file-property (8) #  file-truename nil
13:54:15.624987 tramp-flush-directory-properties (8) # 
13:54:15.625052 tramp-accept-process-output (10) # *tramp/ssh secur-t.local* nil signal nil
13:54:15.640082 tramp-wait-for-regexp (10) # 
  backtrace()
  tramp-error(#<process *tramp/ssh secur-t.local*> file-error "Process has died")
  apply(tramp-error #<process *tramp/ssh secur-t.local*> file-error "Process has died" nil)
  tramp-wait-for-regexp(#<process *tramp/ssh secur-t.local*> 10 "\\(^[^#$%>\n]*[#$%>] *\\|\\(?:^\\|\15\\)[^]#$%>\n]*#?[]#$%>...")
  tramp-barf-if-no-shell-prompt(#<process *tramp/ssh secur-t.local*> 10 "Couldn't find local shell prompt for %s" "/bin/sh")
  tramp-maybe-open-connection((tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil))
  tramp-send-command((tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil) "cd ~ && pwd")
  tramp-sh-handle-expand-file-name("/ssh:secur-t.local:" nil)
  apply(tramp-sh-handle-expand-file-name ("/ssh:secur-t.local:" nil))
  tramp-sh-file-name-handler(expand-file-name "/ssh:secur-t.local:" nil)
  apply(tramp-sh-file-name-handler expand-file-name ("/ssh:secur-t.local:" nil))
  tramp-file-name-handler(expand-file-name "/ssh:secur-t.local:" nil)
  file-name-case-insensitive-p("/ssh:secur-t.local:")
  abbreviate-file-name("/ssh:secur-t.local:")
  ido-make-prompt(file "Find file: ")
  ido-read-internal(file "Find file: " ido-file-history nil nil nil)
  ido-file-internal(raise-frame)
  ido-find-file()
  funcall-interactively(ido-find-file)
  call-interactively(ido-find-file nil nil)
  command-execute(ido-find-file)
13:54:15.640230 tramp-wait-for-regexp (1) # File error: Process has died
13:54:15.659720 tramp-wait-for-regexp (10) # 
  backtrace()
  tramp-signal-hook-function(error ("Cannot resize window #<window 312 on *debug tramp*..."))
  signal(error ("Cannot resize window #<window 312 on *debug tramp*..."))
  error("Cannot resize window %s" #<window 312 on *debug tramp*>)
  window-resize(#<window 312 on *debug tramp*> 46)
  display-buffer-use-some-window(#<buffer *tramp/ssh secur-t.local*> nil)
  display-buffer(#<buffer *tramp/ssh secur-t.local*> nil)
  pop-to-buffer(#<buffer *tramp/ssh secur-t.local*>)
  #f(compiled-function () #<bytecode 0x1fe997c66c35>)()
  tramp-wait-for-regexp(#<process *tramp/ssh secur-t.local*> 10 "\\(^[^#$%>\n]*[#$%>] *\\|\\(?:^\\|\15\\)[^]#$%>\n]*#?[]#$%>...")
  tramp-barf-if-no-shell-prompt(#<process *tramp/ssh secur-t.local*> 10 "Couldn't find local shell prompt for %s" "/bin/sh")
  tramp-maybe-open-connection((tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil))
  tramp-send-command((tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil) "cd ~ && pwd")
  tramp-sh-handle-expand-file-name("/ssh:secur-t.local:" nil)
  apply(tramp-sh-handle-expand-file-name ("/ssh:secur-t.local:" nil))
  tramp-sh-file-name-handler(expand-file-name "/ssh:secur-t.local:" nil)
  apply(tramp-sh-file-name-handler expand-file-name ("/ssh:secur-t.local:" nil))
  tramp-file-name-handler(expand-file-name "/ssh:secur-t.local:" nil)
  file-name-case-insensitive-p("/ssh:secur-t.local:")
  abbreviate-file-name("/ssh:secur-t.local:")
  ido-make-prompt(file "Find file: ")
  ido-read-internal(file "Find file: " ido-file-history nil nil nil)
  ido-file-internal(raise-frame)
  ido-find-file()
  funcall-interactively(ido-find-file)
  call-interactively(ido-find-file nil nil)
  command-execute(ido-find-file)
13:54:15.659875 tramp-wait-for-regexp (1) # error: "Cannot resize window #<window 312 on *debug tramp*>"
13:54:15.732786 tramp-get-connection-property (7) # process-buffer nil
13:54:15.749225 tramp-barf-if-no-shell-prompt (10) # 
  backtrace()
  tramp-error((tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil) file-error "Couldn't find local shell prompt for %s" "/bin/sh")
  apply(tramp-error (tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil) file-error "Couldn't find local shell prompt for %s" "/bin/sh")
  tramp-error-with-buffer(#<buffer *tramp/ssh secur-t.local*> (tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil) file-error "Couldn't find local shell prompt for %s" "/bin/sh")
  apply(tramp-error-with-buffer #<buffer *tramp/ssh secur-t.local*> (tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil) file-error ("Couldn't find local shell prompt for %s" "/bin/sh"))
  tramp-barf-if-no-shell-prompt(#<process *tramp/ssh secur-t.local*> 10 "Couldn't find local shell prompt for %s" "/bin/sh")
  tramp-maybe-open-connection((tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil))
  tramp-send-command((tramp-file-name "ssh" nil nil "secur-t.local" nil "" nil) "cd ~ && pwd")
  tramp-sh-handle-expand-file-name("/ssh:secur-t.local:" nil)
  apply(tramp-sh-handle-expand-file-name ("/ssh:secur-t.local:" nil))
  tramp-sh-file-name-handler(expand-file-name "/ssh:secur-t.local:" nil)
  apply(tramp-sh-file-name-handler expand-file-name ("/ssh:secur-t.local:" nil))
  tramp-file-name-handler(expand-file-name "/ssh:secur-t.local:" nil)
  file-name-case-insensitive-p("/ssh:secur-t.local:")
  abbreviate-file-name("/ssh:secur-t.local:")
  ido-make-prompt(file "Find file: ")
  ido-read-internal(file "Find file: " ido-file-history nil nil nil)
  ido-file-internal(raise-frame)
  ido-find-file()
  funcall-interactively(ido-find-file)
  call-interactively(ido-find-file nil nil)
  command-execute(ido-find-file)
13:54:15.749348 tramp-barf-if-no-shell-prompt (1) # File error: Couldn't find local shell prompt for /bin/sh
13:54:15.749647 tramp-maybe-open-connection (3) # Opening connection for secur-t.local using ssh...failed
13:54:15.749743 tramp-get-file-property (8) #  file-truename nil
13:54:15.749800 tramp-flush-directory-properties (8) # 
13:54:15.749861 tramp-get-connection-property (7) # process-name nil
13:54:15.749918 tramp-flush-connection-properties (7) # (tramp-file-name ssh nil nil secur-t.local nil nil nil) (process-buffer)
13:54:15.749998 tramp-get-connection-property (7) # process-buffer nil
Reply | Threaded
Open this post in threaded view
|

Re: Please help interpreting TRAMP debug log

Michael Albinus
Camden Narzt <[hidden email]> writes:

Hi Camden,

> I’m trying to use tramp to edit files on a remote host, and it’s not
> connecting. I see "Couldn't find local shell prompt for /bin/sh” as
> well as "Process has died” errors, and was just wondering whether the
> process is killed because it doesn’t find a prompt, or if it doesn’t
> find a prompt because it died.
>
> Is there any way to see the output from the connection? Or am I
> missing something helpful in the logs?

The log is not complete. Could you please start

# emacs -Q -l tramp --eval '(setq tramp-verbose 10)'

Open the remote connection, and if it still fails, show the Tramp traces
(preferred as attachment to the message).

Best regards, Michael.