• Bug#1108810: nodejs: FTBFS randomly: autobuilder hangs (2/3)

    From Santiago Vila@1:229/2 to All on Sat Jul 5 13:10:01 2025
    [continued from previous message]

    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),

    [continued in next message]

    --- SoupGate-Win32 v1.05
    * Origin: you cannot sedate... all the things you hate (1:229/2)