• Bug#1108810: nodejs: FTBFS randomly: autobuilder hangs

    From Santiago Vila@21:1/5 to All on Sat Jul 5 13:10:01 2025
    Package: src:nodejs
    Version: 20.19.2+dfsg-1
    Severity: important
    Tags: ftbfs trixie sid

    Dear maintainer:

    During a rebuild of all packages in unstable, your package failed to build:

    --------------------------------------------------------------------------------
    [...]
    debian/rules clean
    dh clean
    dh_auto_clean
    make -j2 distclean
    make[1]: Entering directory '/<<PKGBUILDDIR>>'
    rm -f -r out
    rm -f config.gypi icu_config.gypi
    rm -f config.mk
    rm -f -r node node_g
    rm -f -r node_modules
    rm -f -r deps/icu
    rm -f -r deps/icu4c*.tgz deps/icu4c*.zip deps/icu-tmp
    rm -f node-v20.19.2-linux-x64.tar.* node-v20.19.2.tar.*
    make[1]: Leaving directory '/<<PKGBUILDDIR>>'

    [... snipped ...]

    duration_ms: 134.88000
    ...
    ok 3462 sequential/test-child-process-emfile
    ---
    duration_ms: 261.53000
    ...
    ok 3463 sequential/test-child-process-execsync
    ---
    duration_ms: 1007.65400
    ...
    ok 3464 sequential/test-child-process-exit
    ---
    duration_ms: 807.32500
    ...
    ok 3465 sequential/test-child-process-pass-fd
    ---
    duration_ms: 5071.90400
    ...
    ok 3466 sequential/test-cli-syntax-bad
    ---
    duration_ms: 610.40800
    ...
    ok 3467 sequential/test-cli-syntax-file-not-found
    ---
    duration_ms: 411.66700
    ...
    ok 3468 sequential/test-cli-syntax-good
    ---
    duration_ms: 717.43300
    ...
    ok 3469 sequential/test-cli-syntax-require
    ---
    duration_ms: 610.42100
    ...
    ok 3470 sequential/test-cluster-inspect-brk
    ---
    duration_ms: 209.63100
    ...
    ok 3471 sequential/test-cluster-net-listen-ipv6only-none
    ---
    duration_ms: 412.92300
    ...
    ok 3472 sequential/test-cluster-net-listen-ipv6only-rr
    ---
    duration_ms: 408.84100
    ...
    ok 3473 sequential/test-cluster-send-handle-large-payload
    ---
    duration_ms: 326.08100
    ...
    ok 3474 sequential/test-cpu-prof-default
    ---
    duration_ms: 1308.33900
    ...
    ok 3475 sequential/test-cpu-prof-dir-and-name
    ---
    duration_ms: 1307.36300
    ...
    ok 3476 sequential/test-cpu-prof-dir-relative
    ---
    duration_ms: 1309.98700
    ...
    ok 3477 sequential/test-cpu-prof-dir-worker # TODO : Fix flaky test
    ---
    duration_ms: 1507.77000
    ...
    ok 3478 sequential/test-cpu-prof-drained
    ---
    duration_ms: 1307.76800
    ...
    ok 3479 sequential/test-cpu-prof-exit
    ---
    duration_ms: 406.98900
    ...
    ok 3480 sequential/test-cpu-prof-invalid-options
    ---
    duration_ms: 261.33200
    ...
    ok 3481 sequential/test-cpu-prof-kill
    ---
    duration_ms: 406.97100
    ...
    ok 3482 sequential/test-cpu-prof-name
    ---
    duration_ms: 1307.27800
    ...
    ok 3483 sequential/test-cpu-prof-worker-argv
    ---
    duration_ms: 1407.64600
    ...
    ok 3484 sequential/test-crypto-timing-safe-equal
    ---
    duration_ms: 134.94100
    ...
    ok 3485 sequential/test-debug-prompt
    ---
    duration_ms: 609.57200
    ...
    ok 3486 sequential/test-debugger-custom-port
    ---
    duration_ms: 707.24200
    ...
    ok 3487 sequential/test-debugger-debug-brk
    ---
    duration_ms: 209.56400
    ...
    ok 3488 sequential/test-debugger-invalid-args
    ---
    duration_ms: 5415.17500
    ...
    ok 3489 sequential/test-debugger-launch
    ---
    duration_ms: 707.14700
    ...
    ok 3490 sequential/test-debugger-pid
    ---
    duration_ms: 507.02900
    ...
    ok 3491 sequential/test-deprecation-flags
    ---
    duration_ms: 514.89100
    ...
    ok 3492 sequential/test-dgram-bind-shared-ports
    ---
    duration_ms: 409.66400
    ...
    ok 3493 sequential/test-dgram-implicit-bind-failure
    ---
    duration_ms: 134.92500
    ...
    ok 3494 sequential/test-dgram-pingpong
    ---
    duration_ms: 167.96300
    ...
    ok 3495 sequential/test-diagnostic-dir-cpu-prof
    ---
    duration_ms: 2408.13700
    ...
    ok 3496 sequential/test-diagnostic-dir-heap-prof
    ---
    duration_ms: 1808.32800
    ...
    ok 3497 sequential/test-fs-opendir-recursive
    ---
    duration_ms: 209.86200
    ...
    ok 3498 sequential/test-fs-readdir-recursive
    ---
    duration_ms: 209.55700
    ...
    ok 3499 sequential/test-fs-stat-sync-overflow
    ---
    duration_ms: 326.01600
    ...
    ok 3500 sequential/test-fs-watch # TODO : Fix flaky test
    ---
    duration_ms: 134.91800
    ...
    ok 3501 sequential/test-gc-http-client
    ---
    duration_ms: 209.89300
    ...
    ok 3502 sequential/test-gc-http-client-onerror
    ---
    duration_ms: 209.59300
    ...
    ok 3503 sequential/test-gc-http-client-timeout
    ---
    duration_ms: 609.66200
    ...
    ok 3504 sequential/test-get-heapsnapshot-options
    ---
    duration_ms: 1723.16600
    ...
    ok 3505 sequential/test-heapdump
    ---
    duration_ms: 908.88200
    ...
    ok 3506 sequential/test-heapdump-flag
    ---
    duration_ms: 807.53600
    ...
    ok 3507 sequential/test-heapdump-flag-custom-dir
    ---
    duration_ms: 808.58200
    ...
    ok 3508 sequential/test-http-econnrefused
    ---
    duration_ms: 168.01400
    ...
    ok 3509 sequential/test-http-keep-alive-large-write
    ---
    duration_ms: 168.05500
    ...
    ok 3510 sequential/test-http-keepalive-maxsockets
    ---
    duration_ms: 507.15900
    ...
    ok 3511 sequential/test-http-regr-gh-2928
    ---
    duration_ms: 168.05500
    ...
    ok 3512 sequential/test-http-server-keep-alive-timeout-slow-client-headers
    ---
    duration_ms: 3409.58200
    ...
    ok 3513 sequential/test-http-server-keep-alive-timeout-slow-server
    ---
    duration_ms: 1807.97200
    ...
    ok 3514 sequential/test-http-server-request-timeouts-mixed # TODO : Fix flaky test
    ---
    duration_ms: 19021.97500
    ...
    ok 3515 sequential/test-http2-large-file # TODO : Fix flaky test
    ---
    duration_ms: 326.49000
    ...
    ok 3516 sequential/test-http2-max-session-memory
    ---
    duration_ms: 168.14000
    ...
    ok 3517 sequential/test-http2-ping-flood
    ---
    duration_ms: 213.34200
    ...
    ok 3518 sequential/test-http2-settings-flood
    ---
    duration_ms: 212.19200
    ...
    ok 3519 sequential/test-http2-timeout-large-write
    ---
    duration_ms: 8113.28300
    ...
    ok 3520 sequential/test-http2-timeout-large-write-file
    ---
    duration_ms: 8113.78900
    ...
    ok 3521 sequential/test-https-connect-localport
    ---
    duration_ms: 6312.04500
    ...
    ok 3522 sequential/test-https-server-keep-alive-timeout
    ---
    duration_ms: 2909.29100
    ...
    ok 3523 sequential/test-init
    ---
    duration_ms: 414.96300
    ...
    ok 3524 sequential/test-inspector-open-dispose
    ---
    duration_ms: 326.82200
    ...
    ok 3525 sequential/test-inspector-port-cluster
    ---
    duration_ms: 2858.72600
    ...
    ok 3526 sequential/test-module-loading
    ---
    duration_ms: 209.51600
    ...
    ok 3527 sequential/test-net-GH-5504
    ---
    duration_ms: 407.08600
    ...
    ok 3528 sequential/test-net-better-error-messages-port
    ---
    duration_ms: 134.81200
    ...
    ok 3529 sequential/test-net-connect-econnrefused
    ---
    duration_ms: 261.35500
    ...
    ok 3530 sequential/test-net-connect-handle-econnrefused
    ---
    duration_ms: 168.45600
    ...
    ok 3531 sequential/test-net-connect-local-error
    ---
    duration_ms: 168.00900
    ...
    ok 3532 sequential/test-net-listen-shared-ports
    ---
    duration_ms: 407.41000
    ...
    ok 3533 sequential/test-net-localport
    ---
    duration_ms: 134.94100
    ...
    ok 3534 sequential/test-net-reconnect-error
    ---
    duration_ms: 168.13800
    ...
    ok 3535 sequential/test-net-response-size
    ---
    duration_ms: 1407.71600
    ...
    ok 3536 sequential/test-net-server-address
    ---
    duration_ms: 134.93400
    ...
    ok 3537 sequential/test-net-server-bind
    ---
    duration_ms: 261.20300
    ...
    ok 3538 sequential/test-next-tick-error-spin
    ---
    duration_ms: 261.34400
    ...
    ok 3539 sequential/test-perf-hooks
    ---
    duration_ms: 1207.61200
    ...
    ok 3540 sequential/test-pipe
    ---
    duration_ms: 4411.11600
    ...
    ok 3541 sequential/test-process-title
    ---
    duration_ms: 262.18200
    ...
    ok 3542 sequential/test-process-warnings
    ---
    duration_ms: 328.35200
    ...
    ok 3543 sequential/test-repl-timeout-throw
    ---
    duration_ms: 407.34900
    ...
    ok 3544 sequential/test-require-cache-without-stat
    ---
    duration_ms: 169.19100
    ...
    ok 3545 sequential/test-resolution-inspect-brk
    ---
    duration_ms: 209.58900
    ...
    ok 3546 sequential/test-runner-run-inspect
    ---
    duration_ms: 3511.56400
    ...
    ok 3547 sequential/test-single-executable-application # skip Running the resultant binary fails with `/home/iojs/node-tmp/.tmp.2366/sea: error while loading shared libraries: libnode.so.112: cannot open shared object file: No such file or directory`.
    ---
    duration_ms: 134.94500
    ...
    ok 3548 sequential/test-single-executable-application-assets # skip Running the resultant binary fails with `/home/iojs/node-tmp/.tmp.2366/sea: error while loading shared libraries: libnode.so.112: cannot open shared object file: No such file or
    directory`.
    ---
    duration_ms: 134.91000
    ...
    ok 3549 sequential/test-single-executable-application-assets-raw # skip Running the resultant binary fails with `/home/iojs/node-tmp/.tmp.2366/sea: error while loading shared libraries: libnode.so.112: cannot open shared object file: No such file or
    directory`.
    ---
    duration_ms: 134.99800
    ...
    ok 3550 sequential/test-single-executable-application-disable-experimental-sea-warning # skip Running the resultant binary fails with `/home/iojs/node-tmp/.tmp.2366/sea: error while loading shared libraries: libnode.so.112: cannot open shared object file:
    No such file or directory`.
    ---
    duration_ms: 134.83900
    ...
    ok 3551 sequential/test-single-executable-application-empty # skip Running the resultant binary fails with `/home/iojs/node-tmp/.tmp.2366/sea: error while loading shared libraries: libnode.so.112: cannot open shared object file: No such file or directory`
    .
    ---
    duration_ms: 134.86100
    ...
    ok 3552 sequential/test-single-executable-application-snapshot # skip Running the resultant binary fails with `/home/iojs/node-tmp/.tmp.2366/sea: error while loading shared libraries: libnode.so.112: cannot open shared object file: No such file or
    directory`.
    ---
    duration_ms: 134.84100
    ...
    ok 3553 sequential/test-single-executable-application-snapshot-and-code-cache # skip Running the resultant binary fails with `/home/iojs/node-tmp/.tmp.2366/sea: error while loading shared libraries: libnode.so.112: cannot open shared object file: No such
    file or directory`.
    ---
    duration_ms: 134.88300
    ...
    ok 3554 sequential/test-single-executable-application-snapshot-worker # skip Running the resultant binary fails with `/home/iojs/node-tmp/.tmp.2366/sea: error while loading shared libraries: libnode.so.112: cannot open shared object file: No such file or
    directory`.
    ---
    duration_ms: 134.86300
    ...
    ok 3555 sequential/test-single-executable-application-use-code-cache # skip Running the resultant binary fails with `/home/iojs/node-tmp/.tmp.2366/sea: error while loading shared libraries: libnode.so.112: cannot open shared object file: No such file or
    directory`.
    ---
    duration_ms: 134.90300
    ...
    ok 3556 sequential/test-stream2-fs
    ---
    duration_ms: 168.06200
    ...
    ok 3557 sequential/test-stream2-stderr-sync
    ---
    duration_ms: 515.13100
    ...
    ok 3558 sequential/test-timers-block-eventloop
    ---
    duration_ms: 168.04100
    ...
    ok 3559 sequential/test-timers-set-interval-excludes-callback-duration
    ---
    duration_ms: 506.98900
    ...
    ok 3560 sequential/test-tls-connect
    ---
    duration_ms: 168.51200
    ...
    ok 3561 sequential/test-tls-lookup
    ---
    duration_ms: 168.07200
    ...
    ok 3562 sequential/test-tls-psk-client # TODO : Fix flaky test
    ---
    duration_ms: 209.57200
    ...
    ok 3563 sequential/test-tls-securepair-client # TODO : Fix flaky test
    ---
    duration_ms: 1307.64300
    ...
    not ok 3564 sequential/test-tls-session-timeout # TODO : Fix flaky test
    ---
    duration_ms: 209.63500
    severity: flaky
    exitcode: 1
    stack: |-
    node:assert:90
    throw new AssertionError(obj);
    ^

    AssertionError [ERR_ASSERTION]: Expected values to be strictly equal:
    + actual - expected

    + 'New'
    - 'Reused'
    at /<<PKGBUILDDIR>>/test/sequential/test-tls-session-timeout.js:146:16
    at ChildProcess.<anonymous> (/<<PKGBUILDDIR>>/test/sequential/test-tls-session-timeout.js:130:7)
    at ChildProcess.emit (node:events:524:28)
    at ChildProcess._handle.onexit (node:internal/child_process:293:12) {
    generatedMessage: true,
    code: 'ERR_ASSERTION',
    actual: 'New',
    expected: 'Reused',
    operator: 'strictEqual'
    }

    Node.js v20.19.2
    ...
    ok 3565 sequential/test-util-debug
    ---
    duration_ms: 2435.10800
    ...
    ok 3566 sequential/test-vm-break-on-sigint
    ---
    duration_ms: 519.39100
    ...
    ok 3567 sequential/test-vm-timeout-escape-promise-module-2
    ---
    duration_ms: 167.92900
    ...
    ok 3568 sequential/test-vm-timeout-rethrow
    ---
    duration_ms: 261.18800
    ...
    ok 3569 sequential/test-worker-eventlooputil
    ---
    duration_ms: 325.96000
    ...
    ok 3570 sequential/test-worker-fshandles-error-on-termination
    ---
    duration_ms: 1115.90500
    ...
    ok 3571 sequential/test-worker-fshandles-open-close-on-termination
    ---
    duration_ms: 1019.83800
    ...
    ok 3572 sequential/test-worker-heapsnapshot-options # TODO : Fix flaky test
    ---
    duration_ms: 910.17200
    ...
    ok 3573 sequential/test-worker-prof
    ---
    duration_ms: 1511.67200
    ...
    ok 3574 sequential/test-write-heapsnapshot-options
    ---
    duration_ms: 1713.34700
    ...
    E: Build killed with signal TERM after 60 minutes of inactivity --------------------------------------------------------------------------------

    The above is just how the build ends and not necessarily the most relevant part.
    I've put several full build logs here:

    https://people.debian.org/~sanvila/build-logs/202507/

    About the archive rebuild: The build was made on virtual machines from AWS, using sbuild and a reduced chroot with only build-essential packages.

    Note: The build might not always fail (hence the "randomly" in the title),
    but I'm getting a high failure rate (around 36% here). However, I have not built the current version (20.19.2+dfsg-1) enough times to get a significant failure rate statistics but it might easily be above the usual thresholds
    used by the RT. If you could not reproduce these random failures please
    contact me privately, as I am willing to provide ssh access to a virtual machine where they happen.

    If this is really a bug in one of the build-depends, please use
    reassign and add an affects on src:nodejs, so that this is still
    visible in the BTS web page for this package.

    Thanks.

    --- SoupGate-Win32 v1.05
    * Origin: fsxNet Usenet Gateway (21:1/5)
  • From =?UTF-8?B?SsOpcsOpbXkgTGFs?=@21:1/5 to All on Mon Jul 21 19:30:01 2025
    Le sam. 5 juil. 2025 à 13:01, Santiago Vila <[email protected]> a écrit :

    Package: src:nodejs
    Version: 20.19.2+dfsg-1
    Severity: important
    Tags: ftbfs trixie sid

    Dear maintainer:

    During a rebuild of all packages in unstable, your package failed to build:


    --------------------------------------------------------------------------------
    [...]
    debian/rules clean
    dh clean
    dh_auto_clean
    make -j2 distclean
    make[1]: Entering directory '/<<PKGBUILDDIR>>'
    rm -f -r out
    rm -f config.gypi icu_config.gypi
    rm -f config.mk
    rm -f -r node node_g
    rm -f -r node_modules
    rm -f -r deps/icu
    rm -f -r deps/icu4c*.tgz deps/icu4c*.zip deps/icu-tmp
    rm -f node-v20.19.2-linux-x64.tar.* node-v20.19.2.tar.*
    make[1]: Leaving directory '/<<PKGBUILDDIR>>'

    [... snipped ...]



    Oddly, I can't reproduce (tried six times today).

    In any case, there is a mistake in debian/rules,
    --timeout=6000
    should be
    --timeout=1000
    to make sure tests have enough time on riscv64 but don't go above 60
    minutes which triggers a failure.

    <div dir="ltr"><div dir="ltr"><br></div><br><div class="gmail_quote gmail_quote_container"><div dir="ltr" class="gmail_attr">Le sam. 5 juil. 2025 à 13:01, Santiago Vila &lt;<a href="mailto:[email protected]">[email protected]</a>&gt; a écrit :<br><
    /div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex">Package: src:nodejs<br>
    Version: 20.19.2+dfsg-1<br>
    Severity: important<br>
    Tags: ftbfs trixie sid<br>

    Dear maintainer:<br>

    During a rebuild of all packages in unstable, your package failed to build:<br>

    --------------------------------------------------------------------------------<br>
    [...]<br>
     debian/rules clean<br>
    dh clean <br>
       dh_auto_clean<br>
            make -j2 distclean<br>
    make[1]: Entering directory &#39;/&lt;&lt;PKGBUILDDIR&gt;&gt;&#39;<br>
    rm -f -r out<br>
    rm -f config.gypi icu_config.gypi<br>
    rm -f <a href="http://config.mk" rel="noreferrer" target="_blank">config.mk</a><br>
    rm -f -r node node_g<br>
    rm -f -r node_modules<br>
    rm -f -r deps/icu<br>
    rm -f -r deps/icu4c*.tgz deps/icu4c*.zip deps/icu-tmp<br>
    rm -f node-v20.19.2-linux-x64.tar.* node-v20.19.2.tar.*<br>
    make[1]: Leaving directory &#39;/&lt;&lt;PKGBUILDDIR&gt;&gt;&#39;<br>

    [... snipped ...]<br></blockquote><div><br></div><div><br></div><div>Oddly, I can&#39;t reproduce (tried six times today).</div><div><br></div><div>In any case, there is a mistake in debian/rules,</div><div>--timeout=6000</div><div>should be</div><div>--
    timeout=1000</div><div>to make sure tests have enough time on riscv64 but don&#39;t go above 60 minutes which triggers a failure.</div><div><br></div></div></div>

    --- SoupGate-Win32 v1.05
    * Origin: fsxNet Usenet Gateway (21:1/5)