Re: remote tramp hangs

[Raphaël Berbain]

* Michael Albinus:

> raphael.berbain@gmail.com (Raphaël Berbain) writes:
>
>> In case it helps, here's the (minimal, I made no effort to do it
>> the right way) diff I use right now:
>
> I've committed a similar patch, handling other cases as well.

Hi again,

I tried my use-case with CVS head tramp (tramp-version 2.1.18-pre,
tramp.el CVS revision 2.757) and I am sorry to report that it doesn't
work for me.  What I am doing is: 

$ emacs -Q --eval "(progn (add-to-list 'load-path \
\"~/sandbox/sw/tramp/install/share/emacs/site-lisp\") (require \
'tramp) (setq debug-on-quit t tramp-verbose 10))" /root@harry:

it hangs with message "Tramp: Found remote shell prompt on `harry'".

Debug output (non-printable characters replaced with '.'):

;; GNU Emacs: 23.1.1 Tramp: 2.1.18-pre -*- mode: outline; -*-
15:06:46.365637 tramp-maybe-open-connection (3) # Opening connection for 
root@harry using scp...
15:06:46.365860 tramp-get-connection-property (7) # process-name nil
15:06:46.365982 tramp-get-connection-property (7) # process-buffer nil
15:06:46.366561 tramp-maybe-open-connection (6) # /bin/sh
15:06:46.366993 tramp-maybe-open-connection (3) # Waiting 60s for local shell 
to come up...
15:06:46.367154 tramp-get-connection-property (7) # check-remote-echo nil
15:06:46.367274 tramp-get-connection-property (7) # check-remote-echo nil
15:06:46.367425 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:46.367604 tramp-accept-process-output (10) # 
#$ 
15:06:46.367720 tramp-get-connection-property (7) # check-remote-echo nil
15:06:46.367837 tramp-get-connection-property (7) # check-remote-echo nil
15:06:46.367965 tramp-wait-for-regexp (6) # 
#$ 
15:06:46.368099 tramp-get-file-property (8) #  gateway nil
15:06:46.368263 tramp-set-connection-property (7) # temp-file /tmp/tramp.47922Zk
15:06:46.368688 tramp-maybe-open-connection (3) # Sending command `exec ssh 
harry -l root  -q -e none'
15:06:46.368809 tramp-get-connection-property (7) # process-name nil
15:06:46.368944 tramp-get-connection-property (7) # remote-echo nil
15:06:46.369058 tramp-send-command (6) # exec ssh harry -l root  -q -e none
15:06:46.369172 tramp-get-connection-property (7) # process-name nil
15:06:46.369303 tramp-get-connection-property (7) # chunksize nil
15:06:46.369429 tramp-set-connection-property (7) # last-cmd-time (19238 18038 
369379)
15:06:46.369552 tramp-send-string (10) # exec ssh harry -l root  -q -e none
15:06:46.369666 tramp-get-connection-property (7) # process-buffer nil
15:06:46.369831 tramp-set-connection-property (7) # first-password-request t
15:06:46.370050 tramp-process-actions (3) # Waiting for prompts from remote 
shell
15:06:46.370182 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:47.370526 tramp-accept-process-output (10) # 
15:06:47.370761 tramp-process-one-action (5) # Looking for regexp "\(.*ogin\( 
.*\)?: *\)\'" from remote shell
15:06:47.371018 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.371258 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.371507 tramp-process-one-action (5) # Looking for regexp 
"\(^.*\([pP]assword\|[pP]assphrase\).*:.? *\)\'" from remote shell
15:06:47.371782 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.372022 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.372287 tramp-process-one-action (5) # Looking for regexp 
"\(^.*\(Connection \(?:\(?:clo\|refu\)sed\)\|Host key verification 
failed\.\|Login \(?:[Ii]ncorrect\)\|N\(?:ame or service not known\|o supported 
authentication methods left to try!\)\|Permission denied\|Sorry, try 
again\.\).*\|^.*\(Received signal [0-9]+\).*\)\'" from remote shell
15:06:47.372546 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.372799 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.373082 tramp-process-one-action (5) # Looking for regexp "\(^[^#$%>
]*[#$%>] *\)\'" from remote shell
15:06:47.373325 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.373564 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.373805 tramp-process-one-action (5) # Looking for regexp 
"\(\(?:^\|.\)[^#$%>
]*#?[#$%>] *\(.\[[0-9;]*[a-zA-Z] *\)*\)\'" from remote shell
15:06:47.374048 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.374304 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.374554 tramp-process-one-action (5) # Looking for regexp "\(\(Are you 
sure you want to continue connecting (yes/no)\?\)\s-*\)\'" from remote shell
15:06:47.374807 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.375044 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.375288 tramp-process-one-action (5) # Looking for regexp 
"\(\(\(?:Store key in cache\? (y/\|Update cached key\? (y/n, Return cancels 
connectio\)n)\)\s-*\)\'" from remote shell
15:06:47.375564 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.375804 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.376050 tramp-process-one-action (5) # Looking for regexp "\(\(TERM = 
(.*)\|Terminal type\? \[.*\]\)\s-*\)\'" from remote shell
15:06:47.376295 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.376532 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.376790 tramp-process-one-action (5) # Looking for regexp "\(\)\'" from 
remote shell
15:06:47.377029 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.377269 tramp-get-connection-property (7) # check-remote-echo nil
15:06:47.377503 tramp-process-one-action (5) # Call `tramp-action-process-alive'
15:06:47.377750 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.331614 tramp-accept-process-output (10) # 
Terminal type? [dumb] 
15:06:48.331867 tramp-process-one-action (5) # Looking for regexp "\(.*ogin\( 
.*\)?: *\)\'" from remote shell
15:06:48.332120 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.332363 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.332648 tramp-process-one-action (5) # Looking for regexp 
"\(^.*\([pP]assword\|[pP]assphrase\).*:.? *\)\'" from remote shell
15:06:48.332896 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.333158 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.333424 tramp-process-one-action (5) # Looking for regexp 
"\(^.*\(Connection \(?:\(?:clo\|refu\)sed\)\|Host key verification 
failed\.\|Login \(?:[Ii]ncorrect\)\|N\(?:ame or service not known\|o supported 
authentication methods left to try!\)\|Permission denied\|Sorry, try 
again\.\).*\|^.*\(Received signal [0-9]+\).*\)\'" from remote shell
15:06:48.333689 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.333929 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.334184 tramp-process-one-action (5) # Looking for regexp "\(^[^#$%>
]*[#$%>] *\)\'" from remote shell
15:06:48.334456 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.334698 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.334944 tramp-process-one-action (5) # Looking for regexp 
"\(\(?:^\|.\)[^#$%>
]*#?[#$%>] *\(.\[[0-9;]*[a-zA-Z] *\)*\)\'" from remote shell
15:06:48.335190 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.335436 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.335712 tramp-process-one-action (5) # Looking for regexp "\(\(Are you 
sure you want to continue connecting (yes/no)\?\)\s-*\)\'" from remote shell
15:06:48.335962 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.336204 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.336447 tramp-process-one-action (5) # Looking for regexp 
"\(\(\(?:Store key in cache\? (y/\|Update cached key\? (y/n, Return cancels 
connectio\)n)\)\s-*\)\'" from remote shell
15:06:48.336698 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.336965 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.337213 tramp-process-one-action (5) # Looking for regexp "\(\(TERM = 
(.*)\|Terminal type\? \[.*\]\)\s-*\)\'" from remote shell
15:06:48.337461 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.337703 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.337950 tramp-process-one-action (5) # Call `tramp-action-terminal'
15:06:48.338206 tramp-action-terminal (5) # Setting `dumb' as terminal type.
15:06:48.338439 tramp-get-connection-property (7) # process-buffer nil
15:06:48.338692 tramp-action-terminal (6) # 
Terminal type? [dumb] 
15:06:48.338924 tramp-get-connection-property (7) # process-name nil
15:06:48.339205 tramp-get-connection-property (7) # chunksize nil
15:06:48.339488 tramp-set-connection-property (7) # last-cmd-time (19238 18040 
339357)
15:06:48.339742 tramp-send-string (10) # dumb
15:06:48.339977 tramp-get-connection-property (7) # process-buffer nil
15:06:48.340264 tramp-process-one-action (5) # Looking for regexp "\(\)\'" from 
remote shell
15:06:48.340526 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.340786 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.341022 tramp-process-one-action (5) # Call `tramp-action-process-alive'
15:06:48.341269 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.381492 tramp-accept-process-output (10) # 
# 
15:06:48.381732 tramp-process-one-action (5) # Looking for regexp "\(.*ogin\( 
.*\)?: *\)\'" from remote shell
15:06:48.381998 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.382248 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.382485 tramp-process-one-action (5) # Looking for regexp 
"\(^.*\([pP]assword\|[pP]assphrase\).*:.? *\)\'" from remote shell
15:06:48.382759 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.383002 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.383256 tramp-process-one-action (5) # Looking for regexp 
"\(^.*\(Connection \(?:\(?:clo\|refu\)sed\)\|Host key verification 
failed\.\|Login \(?:[Ii]ncorrect\)\|N\(?:ame or service not known\|o supported 
authentication methods left to try!\)\|Permission denied\|Sorry, try 
again\.\).*\|^.*\(Received signal [0-9]+\).*\)\'" from remote shell
15:06:48.383549 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.383792 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.384029 tramp-process-one-action (5) # Looking for regexp "\(^[^#$%>
]*[#$%>] *\)\'" from remote shell
15:06:48.384280 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.384522 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.384784 tramp-process-one-action (5) # Call `tramp-action-succeed'
15:06:48.385018 tramp-get-connection-property (7) # process-buffer nil
15:06:48.385266 tramp-process-actions (6) # 
# 
15:06:48.385810 tramp-maybe-open-connection (3) # Found remote shell prompt on 
`harry'
15:06:48.386127 tramp-get-connection-property (7) # process-name nil
15:06:48.386425 tramp-get-connection-property (7) # remote-echo nil
15:06:48.386653 tramp-send-command (6) # exec env ENV='' PROMPT_COMMAND='' 
PS1=\#\$\  PS2='' PS3='' /bin/sh
15:06:48.386882 tramp-get-connection-property (7) # process-name nil
15:06:48.387146 tramp-get-connection-property (7) # chunksize nil
15:06:48.387402 tramp-set-connection-property (7) # last-cmd-time (19238 18040 
387299)
15:06:48.387669 tramp-send-string (10) # exec env ENV='' PROMPT_COMMAND='' 
PS1=\#\$\  PS2='' PS3='' /bin/sh
15:06:48.387900 tramp-get-connection-property (7) # process-buffer nil
15:06:48.388203 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.388444 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.388699 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.421466 tramp-accept-process-output (10) # 
exec env ENV='' PROMPT_COMMAND='' PS1=\#\$\  PS2='' PS3='' /bin/sh.
15:06:48.421705 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.421963 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.422221 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.471518 tramp-accept-process-output (10) # 
exec env ENV='' PROMPT_COMMAND='' PS1=\#\$\  PS2='' PS3='' /bin/sh.
#$ 
15:06:48.471790 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.472041 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.472304 tramp-wait-for-regexp (6) # 
exec env ENV='' PROMPT_COMMAND='' PS1=\#\$\  PS2='' PS3='' /bin/sh.
#$ 
15:06:48.472556 tramp-open-connection-setup-interactive-shell (5) # Setting up 
remote shell environment
15:06:48.472797 tramp-get-connection-property (7) # process-name nil
15:06:48.473066 tramp-get-connection-property (7) # remote-echo nil
15:06:48.473291 tramp-send-command (6) # stty -inlcr -echo kill '^U' erase '^H'
15:06:48.473516 tramp-get-connection-property (7) # process-name nil
15:06:48.473782 tramp-get-connection-property (7) # chunksize nil
15:06:48.474066 tramp-set-connection-property (7) # last-cmd-time (19238 18040 
473963)
15:06:48.474313 tramp-send-string (10) # stty -inlcr -echo kill '^U' erase '^H'
15:06:48.474538 tramp-get-connection-property (7) # process-buffer nil
15:06:48.474840 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.475077 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.475310 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.501462 tramp-accept-process-output (10) # 
stty -inlcr -echo kill '^U' erase '^H'.
15:06:48.501676 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.501914 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.502161 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.511467 tramp-accept-process-output (10) # 
stty -inlcr -echo kill '^U' erase '^H'.
#$ 
15:06:48.511702 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.511938 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.512189 tramp-wait-for-regexp (6) # 
stty -inlcr -echo kill '^U' erase '^H'.
#$ 
15:06:48.512427 tramp-get-connection-property (7) # process-name nil
15:06:48.512703 tramp-get-connection-property (7) # remote-echo nil
15:06:48.512925 tramp-send-command (6) # echo foo
15:06:48.513148 tramp-get-connection-property (7) # process-name nil
15:06:48.513413 tramp-get-connection-property (7) # chunksize nil
15:06:48.513664 tramp-set-connection-property (7) # last-cmd-time (19238 18040 
513562)
15:06:48.513908 tramp-send-string (10) # echo foo
15:06:48.514134 tramp-get-connection-property (7) # process-buffer nil
15:06:48.514428 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.514664 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.514900 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.531453 tramp-accept-process-output (10) # 
echo foo.
foo
#$ 
15:06:48.531689 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.531925 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.532171 tramp-wait-for-regexp (6) # 
echo foo.
foo
#$ 
15:06:48.532427 tramp-set-connection-property (7) # remote-echo t
15:06:48.532654 tramp-open-connection-setup-interactive-shell (5) # Remote echo 
still on. Ok.
15:06:48.532870 tramp-get-connection-property (7) # process-name nil
15:06:48.533122 tramp-get-connection-property (7) # remote-echo t
15:06:48.533360 tramp-set-connection-property (7) # check-remote-echo t
15:06:48.533579 tramp-send-command (6) # _echostty icanon erase ^H cols 
32767_echo
15:06:48.533798 tramp-get-connection-property (7) # process-name nil
15:06:48.534048 tramp-get-connection-property (7) # chunksize nil
15:06:48.534287 tramp-set-connection-property (7) # last-cmd-time (19238 18040 
534195)
15:06:48.534519 tramp-send-string (10) # _echostty icanon erase ^H cols 
32767_echo
15:06:48.534739 tramp-get-connection-property (7) # process-buffer nil
15:06:48.535020 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.535267 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.535501 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.561471 tramp-accept-process-output (10) # 
_echo     stty icanon erase ^H cols 32767_echo     .
15:06:48.561688 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.561942 tramp-set-connection-property (7) # check-remote-echo nil
15:06:48.562182 tramp-check-for-regexp (5) # echo-mark found
15:06:48.562418 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.562673 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.571457 tramp-accept-process-output (10) # 
#$ 
15:06:48.571697 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.571939 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.572181 tramp-wait-for-regexp (6) # 
#$ 
15:06:48.572441 tramp-open-connection-setup-interactive-shell (5) # Setting 
shell prompt
15:06:48.572696 tramp-get-connection-property (7) # process-name nil
15:06:48.572964 tramp-get-connection-property (7) # remote-echo t
15:06:48.573213 tramp-set-connection-property (7) # check-remote-echo t
15:06:48.573444 tramp-send-command (6) # 
_echoPS1=///f91d08a5c9f7a0fe8dcbcc3043d7186b\#\$_echo
15:06:48.573666 tramp-get-connection-property (7) # process-name nil
15:06:48.573928 tramp-get-connection-property (7) # chunksize nil
15:06:48.574180 tramp-set-connection-property (7) # last-cmd-time (19238 18040 
574079)
15:06:48.574421 tramp-send-string (10) # 
_echoPS1=///f91d08a5c9f7a0fe8dcbcc3043d7186b\#\$_echo
15:06:48.574649 tramp-get-connection-property (7) # process-buffer nil
15:06:48.574949 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.575191 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.575428 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.601465 tramp-accept-process-output (10) # 
_echo     PS1=///f91d08a5c9f7a0fe8dcbcc3043d7186b\#\$_echo    
 .
///f91d08a5c9f7a0fe8dcbcc3043d7186b#$
15:06:48.601711 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.601963 tramp-set-connection-property (7) # check-remote-echo nil
15:06:48.602202 tramp-check-for-regexp (5) # echo-mark found
15:06:48.602441 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.602714 tramp-wait-for-regexp (6) # 
///f91d08a5c9f7a0fe8dcbcc3043d7186b#$
15:06:48.603048 tramp-get-connection-property (7) # process-name nil
15:06:48.603316 tramp-get-connection-property (7) # remote-echo t
15:06:48.603571 tramp-set-connection-property (7) # check-remote-echo t
15:06:48.603800 tramp-send-command (6) # _echoPS2=''_echo
15:06:48.604026 tramp-get-connection-property (7) # process-name nil
15:06:48.604291 tramp-get-connection-property (7) # chunksize nil
15:06:48.604546 tramp-set-connection-property (7) # last-cmd-time (19238 18040 
604441)
15:06:48.604789 tramp-send-string (10) # _echoPS2=''_echo
15:06:48.605016 tramp-get-connection-property (7) # process-buffer nil
15:06:48.605316 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.605557 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.605792 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.621456 tramp-accept-process-output (10) # 
_echo     PS2=''_echo     .
15:06:48.621670 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.621925 tramp-set-connection-property (7) # check-remote-echo nil
15:06:48.622168 tramp-check-for-regexp (5) # echo-mark found
15:06:48.622403 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.622656 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.631458 tramp-accept-process-output (10) # 
///f91d08a5c9f7a0fe8dcbcc3043d7186b#$
15:06:48.631699 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.631945 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.632192 tramp-wait-for-regexp (6) # 
///f91d08a5c9f7a0fe8dcbcc3043d7186b#$
15:06:48.632518 tramp-get-connection-property (7) # process-name nil
15:06:48.632796 tramp-get-connection-property (7) # remote-echo t
15:06:48.633048 tramp-set-connection-property (7) # check-remote-echo t
15:06:48.633275 tramp-send-command (6) # _echoPS3=''_echo
15:06:48.633501 tramp-get-connection-property (7) # process-name nil
15:06:48.633767 tramp-get-connection-property (7) # chunksize nil
15:06:48.634021 tramp-set-connection-property (7) # last-cmd-time (19238 18040 
633917)
15:06:48.634264 tramp-send-string (10) # _echoPS3=''_echo
15:06:48.634489 tramp-get-connection-property (7) # process-buffer nil
15:06:48.634786 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.635031 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.635266 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.651491 tramp-accept-process-output (10) # 
_echo     PS3=''_echo     .
///f91d08a5c9f7a0fe8dcbcc3043d7186b#$
15:06:48.651746 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.652002 tramp-set-connection-property (7) # check-remote-echo nil
15:06:48.652245 tramp-check-for-regexp (5) # echo-mark found
15:06:48.652485 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.652748 tramp-wait-for-regexp (6) # 
///f91d08a5c9f7a0fe8dcbcc3043d7186b#$
15:06:48.653080 tramp-get-connection-property (7) # process-name nil
15:06:48.653357 tramp-get-connection-property (7) # remote-echo t
15:06:48.653613 tramp-set-connection-property (7) # check-remote-echo t
15:06:48.653843 tramp-send-command (6) # _echoPROMPT_COMMAND=''_echo
15:06:48.654070 tramp-get-connection-property (7) # process-name nil
15:06:48.654338 tramp-get-connection-property (7) # chunksize nil
15:06:48.654599 tramp-set-connection-property (7) # last-cmd-time (19238 18040 
654496)
15:06:48.654852 tramp-send-string (10) # _echoPROMPT_COMMAND=''_echo
15:06:48.655084 tramp-get-connection-property (7) # process-buffer nil
15:06:48.655410 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.655656 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.655896 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.671471 tramp-accept-process-output (10) # 
_echo     PROMPT_COMMAND=''_echo     .
15:06:48.671693 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.671981 tramp-set-connection-property (7) # check-remote-echo nil
15:06:48.672229 tramp-check-for-regexp (5) # echo-mark found
15:06:48.672476 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.672741 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.673060 tramp-accept-process-output (10) # 
///f91d08a5c9f7a0fe8dcbcc3043d7186b#$
15:06:48.673335 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.673638 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.673894 tramp-wait-for-regexp (6) # 
///f91d08a5c9f7a0fe8dcbcc3043d7186b#$
15:06:48.674219 tramp-open-connection-setup-interactive-shell (5) # Determining 
coding system
15:06:48.674451 tramp-get-connection-property (7) # process-name nil
15:06:48.674737 tramp-get-connection-property (7) # remote-echo t
15:06:48.674997 tramp-set-connection-property (7) # check-remote-echo t
15:06:48.675229 tramp-send-command (6) # _echoecho foo ; echo bar_echo
15:06:48.675461 tramp-get-connection-property (7) # process-name nil
15:06:48.675740 tramp-get-connection-property (7) # chunksize nil
15:06:48.676030 tramp-set-connection-property (7) # last-cmd-time (19238 18040 
675903)
15:06:48.676280 tramp-send-string (10) # _echoecho foo ; echo bar_echo
15:06:48.676512 tramp-get-connection-property (7) # process-buffer nil
15:06:48.676818 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.677074 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.677315 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.691459 tramp-accept-process-output (10) # 
_echo     echo foo ; echo bar_echo     .
15:06:48.691679 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.711752 tramp-set-connection-property (7) # check-remote-echo nil
15:06:48.712037 tramp-check-for-regexp (5) # echo-mark found
15:06:48.712292 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.712537 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.713006 tramp-accept-process-output (10) # 
foo
bar
///f91d08a5c9f7a0fe8dcbcc3043d7186b#$
15:06:48.713249 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.713492 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.713744 tramp-wait-for-regexp (6) # 
foo
bar
///f91d08a5c9f7a0fe8dcbcc3043d7186b#$
15:06:48.714094 tramp-open-connection-setup-interactive-shell (5) # Setting 
coding system to `utf-8-unix' and `utf-8-unix'
15:06:48.714392 tramp-get-connection-property (7) # process-name nil
15:06:48.714661 tramp-get-connection-property (7) # remote-echo t
15:06:48.714914 tramp-set-connection-property (7) # check-remote-echo t
15:06:48.715147 tramp-send-command (6) # _echoset +o vi +o emacs_echo
15:06:48.715377 tramp-get-connection-property (7) # process-name nil
15:06:48.715640 tramp-get-connection-property (7) # chunksize nil
15:06:48.715895 tramp-set-connection-property (7) # last-cmd-time (19238 18040 
715792)
15:06:48.716142 tramp-send-string (10) # _echoset +o vi +o emacs_echo
15:06:48.716368 tramp-get-connection-property (7) # process-buffer nil
15:06:48.716681 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.716919 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.717155 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.741462 tramp-accept-process-output (10) # 
_echo     set +o vi +o emacs_echo     .
///f91d08a5c9f7a0fe8dcbcc3043d7186b#$
15:06:48.741705 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.741958 tramp-set-connection-property (7) # check-remote-echo nil
15:06:48.742201 tramp-check-for-regexp (5) # echo-mark found
15:06:48.742441 tramp-get-connection-property (7) # check-remote-echo nil
15:06:48.742700 tramp-wait-for-regexp (6) # 
///f91d08a5c9f7a0fe8dcbcc3043d7186b#$
15:06:48.743019 tramp-open-connection-setup-interactive-shell (5) # Checking 
system information
15:06:48.743242 tramp-get-connection-property (7) # uname OpenBSD 4.5
15:06:48.743476 tramp-get-connection-property (7) # process-name nil
15:06:48.743746 tramp-get-connection-property (7) # last-cmd-time (19238 18040 
715792)
15:06:48.744008 tramp-get-connection-property (7) # process-name nil
15:06:48.744274 tramp-get-connection-property (7) # remote-echo t
15:06:48.744524 tramp-set-connection-property (7) # check-remote-echo t
15:06:48.744758 tramp-send-command (6) # _echoecho \"`uname -sr`\" 
2>/dev/null; echo tramp_exit_status $?_echo
15:06:48.745002 tramp-get-connection-property (7) # process-name nil
15:06:48.745270 tramp-get-connection-property (7) # chunksize nil
15:06:48.745524 tramp-set-connection-property (7) # last-cmd-time (19238 18040 
745422)
15:06:48.745770 tramp-send-string (10) # _echoecho \"`uname -sr`\" 
2>/dev/null; echo tramp_exit_status $?_echo
15:06:48.746000 tramp-get-connection-property (7) # process-buffer nil
15:06:48.746297 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.746537 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.746775 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.811461 tramp-accept-process-output (10) # 
"OpenBSD 4.5"
tramp_exit_status 0
15:06:48.811679 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.811920 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.812153 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:48.821459 tramp-accept-process-output (10) # 
"OpenBSD 4.5"
tramp_exit_status 0
///f91d08a5c9f7a0fe8dcbcc3043d7186b#$
15:06:48.821698 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.821942 tramp-get-connection-property (7) # check-remote-echo t
15:06:48.822175 tramp-accept-process-output (10) # *tramp/scp root@harry* run
15:06:49.823553 tramp-accept-process-output (10) # 
"OpenBSD 4.5"
tramp_exit_status 0
///f91d08a5c9f7a0fe8dcbcc3043d7186b#$

[and it loops there.]