bug#44183: tramp-test31-interrupt-process test fails intermittently

bug#44183: tramp-test31-interrupt-process test fails intermittently

[Stefan Kangas]

[-- Attachment #1: Type: text/plain, Size: 1966 bytes --]

I got the below error when running "make -j8 check-expensive".  This
seems to be intermittent.  It didn't show up on a second run or several
runs previous to the failing one.

I've attached "tramp-tests.log".  This is running on latest master on
Debian GNU/Linux.

Please let me know if you need any more information.

cmd: (/bin/sh -i)
buf:

---
Test tramp-test31-interrupt-process backtrace:
  signal(ert-test-failed ("`tramp-test31-interrupt-process' timed out"
  ert-fail("`tramp-test31-interrupt-process' timed out")
  tramp--test-timeout-handler()
  (progn (tramp--test-timeout-handler))
  (if (eq -with-timeout-value- 'timeout) (progn (tramp--test-timeout-h
  (let ((-with-timeout-value- (catch 'timeout (let* ((-with-timeout-ti
  (progn (setq proc (start-file-process-shell-command "test" (current-
  (unwind-protect (progn (setq proc (start-file-process-shell-command
  (save-current-buffer (set-buffer temp-buffer) (unwind-protect (progn
  (let ((temp-buffer (generate-new-buffer " *temp*"))) (save-current-b
  (unwind-protect (let ((temp-buffer (generate-new-buffer " *temp*")))
  (let ((default-directory (file-truename tramp-test-temporary-file-di
  (closure (ange-ftp-make-backup-files t) nil (let* ((fn-5416 #'tramp-
  ert--run-test-internal(#s(ert--test-execution-info :test #s(ert-test
  ert-run-test(#s(ert-test :name tramp-test31-interrupt-process :docum
  ert-run-or-rerun-test(#s(ert--stats :selector ... :tests ... :test-m
  ert-run-tests((not (tag :unstable)) #f(compiled-function (event-type
  ert-run-tests-batch((not (tag :unstable)))
  ert-run-tests-batch-and-exit((not (tag :unstable)))
  eval((ert-run-tests-batch-and-exit '(not (tag :unstable))) t)
  command-line-1(("-L" ":." "-l" "ert" "-l" "lisp/net/tramp-tests.el"
  command-line()
  normal-top-level()
Test tramp-test31-interrupt-process condition:
    (ert-test-failed "`tramp-test31-interrupt-process' timed out")
   FAILED  44/69  tramp-test31-interrupt-process (10.422236 sec)

[-- Attachment #2: tramp-tests.log --]
[-- Type: application/octet-stream, Size: 6656 bytes --]

Running 69 tests (2020-10-23 20:19:10+0200, selector ‘(not (tag :unstable))’)
Remote directory: ‘/mock::/tmp/’
   passed   1/69  tramp-test00-availability (0.337754 sec)
   passed   2/69  tramp-test01-file-name-syntax (0.492140 sec)
   passed   3/69  tramp-test01-file-name-syntax-separate (0.002326 sec)
   passed   4/69  tramp-test01-file-name-syntax-simplified (0.001719 sec)
   passed   5/69  tramp-test02-file-name-dissect (0.908861 sec)
   passed   6/69  tramp-test02-file-name-dissect-separate (1.218706 sec)
   passed   7/69  tramp-test02-file-name-dissect-simplified (0.844506 sec)
   passed   8/69  tramp-test03-file-name-defaults (0.473277 sec)
   passed   9/69  tramp-test03-file-name-host-rules (0.348745 sec)
   passed  10/69  tramp-test03-file-name-method-rules (0.002843 sec)
   passed  11/69  tramp-test04-substitute-in-file-name (0.264680 sec)
   passed  12/69  tramp-test05-expand-file-name (0.012516 sec)
   passed  13/69  tramp-test05-expand-file-name-relative (0.006628 sec)
   passed  14/69  tramp-test06-directory-file-name (0.031506 sec)
   passed  15/69  tramp-test07-file-exists-p (0.836516 sec)
   passed  16/69  tramp-test08-file-local-copy (2.313973 sec)
   passed  17/69  tramp-test09-insert-file-contents (2.637754 sec)
Wrote /mock:joffe:/tmp/tramp-testPlVQCZ
Wrote /mock:joffe:/tmp/tramp-testPlVQCZ
Wrote /mock:joffe:/tmp/tramp-testPlVQCZ
Wrote /mock:joffe:/:/tmp/tramp-testiDylwS
Wrote /mock:joffe:/:/tmp/tramp-testiDylwS
Wrote /mock:joffe:/:/tmp/tramp-testiDylwS
   passed  18/69  tramp-test10-write-region (9.776666 sec)
   passed  19/69  tramp-test10-write-region-file-precious-flag (3.624317 sec)
   passed  20/69  tramp-test11-copy-file (18.070439 sec)
   passed  21/69  tramp-test12-rename-file (18.092686 sec)
   passed  22/69  tramp-test13-make-directory (0.842976 sec)
   passed  23/69  tramp-test14-delete-directory (1.565225 sec)
   passed  24/69  tramp-test15-copy-directory (6.976930 sec)
   passed  25/69  tramp-test16-directory-files (10.090230 sec)
   passed  26/69  tramp-test16-file-expand-wildcards (2.722140 sec)
   passed  27/69  tramp-test17-dired-with-wildcards (5.402157 sec)
   passed  28/69  tramp-test17-insert-directory (3.094776 sec)
   passed  29/69  tramp-test18-file-attributes (2.953850 sec)
   passed  30/69  tramp-test19-directory-files-and-attributes (4.952385 sec)
   passed  31/69  tramp-test20-file-modes (6.525914 sec)
   passed  32/69  tramp-test21-file-links (11.646606 sec)
   passed  33/69  tramp-test22-file-times (1.627374 sec)
   passed  34/69  tramp-test23-visited-file-modtime (1.211262 sec)
   passed  35/69  tramp-test24-file-acl (2.697843 sec)
  skipped  36/69  tramp-test25-file-selinux (0.297781 sec)
   passed  37/69  tramp-test26-file-name-completion (4.788498 sec)
   passed  38/69  tramp-test27-load (2.055317 sec)
   passed  39/69  tramp-test28-process-file (1.280916 sec)
   passed  40/69  tramp-test29-start-file-process (1.290830 sec)
   passed  41/69  tramp-test29-start-file-process-direct-async (1.183789 sec)
   passed  42/69  tramp-test30-make-process (3.536518 sec)
   passed  43/69  tramp-test30-make-process-direct-async (0.894929 sec)
cmd: (/bin/sh -i)
buf:

---
Test tramp-test31-interrupt-process backtrace:
  signal(ert-test-failed ("`tramp-test31-interrupt-process' timed out"
  ert-fail("`tramp-test31-interrupt-process' timed out")
  tramp--test-timeout-handler()
  (progn (tramp--test-timeout-handler))
  (if (eq -with-timeout-value- 'timeout) (progn (tramp--test-timeout-h
  (let ((-with-timeout-value- (catch 'timeout (let* ((-with-timeout-ti
  (progn (setq proc (start-file-process-shell-command "test" (current-
  (unwind-protect (progn (setq proc (start-file-process-shell-command 
  (save-current-buffer (set-buffer temp-buffer) (unwind-protect (progn
  (let ((temp-buffer (generate-new-buffer " *temp*"))) (save-current-b
  (unwind-protect (let ((temp-buffer (generate-new-buffer " *temp*")))
  (let ((default-directory (file-truename tramp-test-temporary-file-di
  (closure (ange-ftp-make-backup-files t) nil (let* ((fn-5416 #'tramp-
  ert--run-test-internal(#s(ert--test-execution-info :test #s(ert-test
  ert-run-test(#s(ert-test :name tramp-test31-interrupt-process :docum
  ert-run-or-rerun-test(#s(ert--stats :selector ... :tests ... :test-m
  ert-run-tests((not (tag :unstable)) #f(compiled-function (event-type
  ert-run-tests-batch((not (tag :unstable)))
  ert-run-tests-batch-and-exit((not (tag :unstable)))
  eval((ert-run-tests-batch-and-exit '(not (tag :unstable))) t)
  command-line-1(("-L" ":." "-l" "ert" "-l" "lisp/net/tramp-tests.el" 
  command-line()
  normal-top-level()
Test tramp-test31-interrupt-process condition:
    (ert-test-failed "`tramp-test31-interrupt-process' timed out")
   FAILED  44/69  tramp-test31-interrupt-process (10.422236 sec)
   passed  45/69  tramp-test32-shell-command (4.722391 sec)
   passed  46/69  tramp-test33-environment-variables (3.071510 sec)
   passed  47/69  tramp-test33-environment-variables-and-port-numbers (0.512791 sec)
   passed  48/69  tramp-test34-connection-local-variables (6.024752 sec)
   passed  49/69  tramp-test34-explicit-shell-file-name (0.756013 sec)
   passed  50/69  tramp-test35-exec-path (1.528201 sec)
   passed  51/69  tramp-test35-remote-path (13.902260 sec)
(Git)
   passed  52/69  tramp-test36-vc-registered (2.688309 sec)
   passed  53/69  tramp-test37-make-auto-save-file-name (0.637230 sec)
   passed  54/69  tramp-test38-find-backup-file-name (1.281205 sec)
   passed  55/69  tramp-test39-make-nearby-temp-file (0.532483 sec)
   passed  56/69  tramp-test40-special-characters (56.880728 sec)
   passed  57/69  tramp-test40-special-characters-with-ls (50.802070 sec)
   passed  58/69  tramp-test40-special-characters-with-perl (47.841894 sec)
   passed  59/69  tramp-test40-special-characters-with-stat (44.585322 sec)
   passed  60/69  tramp-test41-utf8 (135.159183 sec)
   passed  61/69  tramp-test41-utf8-with-ls (143.176671 sec)
   passed  62/69  tramp-test41-utf8-with-perl (133.007537 sec)
   passed  63/69  tramp-test41-utf8-with-stat (122.673295 sec)
   passed  64/69  tramp-test42-file-system-info (0.295798 sec)
   passed  65/69  tramp-test44-auto-load (0.850100 sec)
   passed  66/69  tramp-test44-delay-load (0.799157 sec)
   passed  67/69  tramp-test44-recursive-load (1.489788 sec)
   passed  68/69  tramp-test44-remote-load-path (0.879649 sec)
   passed  69/69  tramp-test45-unload (1.077518 sec)

Ran 69 tests, 67 results as expected, 1 unexpected, 1 skipped (2020-10-23 20:34:39+0200, 928.852612 sec)

1 unexpected results:
   FAILED  tramp-test31-interrupt-process

1 skipped results:
  SKIPPED  tramp-test25-file-selinux

bug#44183: tramp-test31-interrupt-process test fails intermittently

[Michael Albinus]

Stefan Kangas <stefan@marxist.se> writes:

Hi Stefan,

> I got the below error when running "make -j8 check-expensive".  This
> seems to be intermittent.  It didn't show up on a second run or several
> runs previous to the failing one.

I have seen this error as well, but rarely. And it is not
reproducible. So I'm a little bit helpless what to do.

But thanks for the bug report. It shall be a reminder for doing
something, even if I don't know yet what.

> Please let me know if you need any more information.

A recipe for reproduction would be great :-)

Best regards, Michael.

bug#44183: tramp-test31-interrupt-process test fails intermittently

[Stefan Kangas]

Michael Albinus <michael.albinus@gmx.de> writes:

> Stefan Kangas <stefan@marxist.se> writes:
>
> Hi Stefan,
>
>> I got the below error when running "make -j8 check-expensive".  This
>> seems to be intermittent.  It didn't show up on a second run or several
>> runs previous to the failing one.
>
> I have seen this error as well, but rarely. And it is not
> reproducible. So I'm a little bit helpless what to do.
>
> But thanks for the bug report. It shall be a reminder for doing
> something, even if I don't know yet what.

I can't remember having seen this in a while.  Is it still an issue?

>> Please let me know if you need any more information.
>
> A recipe for reproduction would be great :-)

I wish I had one.  :-)

bug#44183: tramp-test31-interrupt-process test fails intermittently

[Michael Albinus]

Stefan Kangas <stefan@marxist.se> writes:

Hi Stefan,

>>> I got the below error when running "make -j8 check-expensive".  This
>>> seems to be intermittent.  It didn't show up on a second run or several
>>> runs previous to the failing one.
>>
>> I have seen this error as well, but rarely. And it is not
>> reproducible. So I'm a little bit helpless what to do.
>>
>> But thanks for the bug report. It shall be a reminder for doing
>> something, even if I don't know yet what.
>
> I can't remember having seen this in a while.  Is it still an issue?

It happens from time to time during my excessive regression tests. Still
rarely, so I have no chance to debug.

(Prior a Tramp release, which happens monthly, I run tramp-tests.el in
100+ different configurations. Controlled by ansible, so I don't have to
do it manually)

Best regards, Michael.

bug#44183: tramp-test31-interrupt-process test fails intermittently

[Stefan Kangas]

Michael Albinus <michael.albinus@gmx.de> writes:

>> I can't remember having seen this in a while.  Is it still an issue?
>
> It happens from time to time during my excessive regression tests. Still
> rarely, so I have no chance to debug.

I haven't seen this in quite a while, so this is either fixed or even
more rare now.  So it seems unlikely that we will make any progress
here, and I'm closing this bug.  Thanks.