Integration test timeout on aarch64

[ggardet]

Integration test timeout on openSUSE Tumbleweed aarch64.
Error log:

[   26s] + /usr/bin/meson test -C aarch64-suse-linux --num-processes 8 --print-errorlogs
[   26s] ninja: Entering directory `/home/abuild/rpmbuild/BUILD/ip2unix-2.2.1-build/ip2unix-2.2.1/aarch64-suse-linux'
[   26s] ninja: no work to do.
[   26s] 1/4 unit-globpath OK              0.04s
[   26s] 2/4 unit-dynports OK              0.26s
[   32s] 3/4 unit-serial   OK              6.01s
[   86s] integration time out (After 60 seconds)
[   86s] 4/4 integration   TIMEOUT        60.12s   killed by signal 15 SIGTERM
[   86s] >>> MSAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1:print_stacktrace=1 PYTHONDONTWRITEBYTECODE=1 MESON_TEST_ITERATION=1 ASAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1 LD_LIBRARY_PATH=/home/abuild/rpmbuild/BUILD/ip2unix-2.2.1-build/ip2unix-2.2.1/aarch64-suse-linux/ UBSAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1:print_stacktrace=1 /usr/bin/pytest -p no:cacheprovider --ip2unix-path=/home/abuild/rpmbuild/BUILD/ip2unix-2.2.1-build/ip2unix-2.2.1/aarch64-suse-linux/ip2unix --libip2unix-path=/home/abuild/rpmbuild/BUILD/ip2unix-2.2.1-build/ip2unix-2.2.1/aarch64-suse-linux/libip2unix.so --helper-accept-no-peer-addr=/home/abuild/rpmbuild/BUILD/ip2unix-2.2.1-build/ip2unix-2.2.1/aarch64-suse-linux/tests/helpers/helper_accept_no_peer_addr --systemd-support --systemd-sa-path=/usr/bin/systemd-socket-activate
[   86s] ――――――――――――――――――――――――――――――――――――― ✀  ―――――――――――――――――――――――――――――――――――――
[   86s] ============================= test session starts ==============================
[   86s] platform linux -- Python 3.13.2, pytest-8.3.4, pluggy-1.5.0
[   86s] rootdir: /home/abuild/rpmbuild/BUILD/ip2unix-2.2.1-build/ip2unix-2.2.1/tests
[   86s] collected 105 items
[   86s] 
[   86s] test_abstract_socket.py s                                                [  0%]
[   86s] test_accept_no_peer_addr.py .                                            [  1%]
[   86s] test_addrinfo.py .                                                       [  2%]
[   86s] test_bind_any.py .                                                       [  3%]
[   86s] test_bind_samerule.py .                                                  [  4%]
[   86s] test_blackhole.py .                                                      [  5%]
[   86s] test_closeallfds.py F                                                    [  6%]
[   86s] test_connections.py s......
[   86s] ――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――
[   86s] 
[   86s] 
[   86s] Summary of Failures:
[   86s] 
[   86s] 4/4 integration   TIMEOUT        60.12s   killed by signal 15 SIGTERM
[   86s] 
[   86s] Ok:                 3   
[   86s] Expected Fail:      0   
[   86s] Fail:               0   
[   86s] Unexpected Pass:    0   
[   86s] Skipped:            0   
[   86s] Timeout:            1   
[   86s] 
[   86s] Full log written to /home/abuild/rpmbuild/BUILD/ip2unix-2.2.1-build/ip2unix-2.2.1/aarch64-suse-linux/meson-logs/testlog.txt

[aszlig]

@ggardet: Thanks for the report. Can you please provide an strace -f of the failing test?

[etam]

Here's what I used:

Index: ip2unix-2.2.1/tests/meson.build
===================================================================
--- ip2unix-2.2.1.orig/tests/meson.build
+++ ip2unix-2.2.1/tests/meson.build
@@ -1,9 +1,12 @@
+strace = find_program('strace')
 pytest_canidates = ['pytest-3', 'py.test-3', 'pytest', 'py.test']
 pytest = find_program(pytest_canidates, required: false)
 if pytest.found()
   subdir('helpers')
 
   pytest_args = [
+    '-f',
+    pytest.full_path(),
     '-p', 'no:cacheprovider',
     '--ip2unix-path=@0@'.format(ip2unix.full_path()),
     '--libip2unix-path=@0@'.format(libip2unix.full_path()),
@@ -34,7 +37,10 @@ if pytest.found()
               ' tests for socket activation.')
     endif
   endif
-  test('integration', pytest, args: pytest_args, timeout: timeout,
+
+  pytest_args += ['test_closeallfds.py']
+
+  test('integration', strace, args: pytest_args, timeout: timeout,
        workdir: meson.current_source_dir(),
        depends: [ip2unix, libip2unix, helper_accept_no_peer_addr],
        env: ['PYTHONDONTWRITEBYTECODE=1'])

I picked test_closeallfds.py, because that's the one failing. Although I don't know where the timeout comes from.

and here's what I've got: strace.zip

[ggardet]

@etam the timeout is from test_connections.py.
Tail of strace:

[   86s] integration time out (After 60 seconds)
[   86s] 4/4 integration   TIMEOUT        60.02s   killed by signal 15 SIGTERM
[   86s] >>> MSAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1:print_stacktrace=1 PYTHONDONTWRITEBYTECODE=1 MESON_TEST_ITERATION=1 ASAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1 LD_LIBRARY_PATH=/home/abuild/rpmbuild/BUILD/ip2unix-2.2.1-build/ip2unix-2.2.1/aarch64-suse-linux/ UBSAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1:print_stacktrace=1 /usr/bin/strace -f /usr/bin/pytest -p no:cacheprovider --ip2unix-path=/home/abuild/rpmbuild/BUILD/ip2unix-2.2.1-build/ip2unix-2.2.1/aarch64-suse-linux/ip2unix --libip2unix-path=/home/abuild/rpmbuild/BUILD/ip2unix-2.2.1-build/ip2unix-2.2.1/aarch64-suse-linux/libip2unix.so --helper-accept-no-peer-addr=/home/abuild/rpmbuild/BUILD/ip2unix-2.2.1-build/ip2unix-2.2.1/aarch64-suse-linux/tests/helpers/helper_accept_no_peer_addr --systemd-support --systemd-sa-path=/usr/bin/systemd-socket-activate test_connections.py
[   86s] ――――――――――――――――――――――――――――――――――――― ✀  ―――――――――――――――――――――――――――――――――――――
[   86s] Listing only the last 100 lines from a long log.
[   86s] [pid 183003] connect(7, {sa_family=AF_UNIX, sun_path="/tmp/tmp9fkjnp59/test.sock"}, 110) = -1 ECONNREFUSED (Connection refused)
[   86s] [pid 183002] <... clock_nanosleep resumed>NULL) = 0
[   86s] [pid 183003] clock_nanosleep(CLOCK_MONOTONIC, TIMER_ABSTIME, {tv_sec=137134, tv_nsec=679837201},  <unfinished ...>
[   86s] [pid 183002] write(2, "Still waiting for connection to "..., 63 <unfinished ...>
[   86s] [pid 182635] <... read resumed>"Still waiting for connection to "..., 31851) = 63
[   86s] [pid 183002] <... write resumed>)       = 63
[   86s] [pid 182635] read(11,  <unfinished ...>
[   86s] [pid 183000] <... clock_nanosleep resumed>NULL) = 0
[   86s] [pid 183002] connect(8, {sa_family=AF_UNIX, sun_path="/tmp/tmp9fkjnp59/test.sock"}, 110 <unfinished ...>
[   86s] [pid 183000] write(2, "Still waiting for connection to "..., 63 <unfinished ...>
[   86s] [pid 183002] <... connect resumed>)     = -1 ECONNREFUSED (Connection refused)
[   86s] [pid 183000] <... write resumed>)       = 63
[   86s] [pid 182635] <... read resumed>"Still waiting for connection to "..., 31788) = 63
[   86s] [pid 183002] clock_nanosleep(CLOCK_MONOTONIC, TIMER_ABSTIME, {tv_sec=137134, tv_nsec=679903334},  <unfinished ...>
[   86s] [pid 182635] read(11,  <unfinished ...>
[   86s] [pid 183000] connect(6, {sa_family=AF_UNIX, sun_path="/tmp/tmp9fkjnp59/test.sock"}, 110) = -1 ECONNREFUSED (Connection refused)
[   86s] [pid 183000] clock_nanosleep(CLOCK_MONOTONIC, TIMER_ABSTIME, {tv_sec=137134, tv_nsec=679941726},  <unfinished ...>
[   86s] [pid 182998] <... clock_nanosleep resumed>NULL) = 0
[   86s] [pid 182998] write(2, "Still waiting for connection to "..., 63 <unfinished ...>
[   86s] [pid 182635] <... read resumed>"Still waiting for connection to "..., 31725) = 63
[   86s] [pid 182998] <... write resumed>)       = 63
[   86s] [pid 182635] read(11,  <unfinished ...>
[   86s] [pid 182998] connect(5, {sa_family=AF_UNIX, sun_path="/tmp/tmp9fkjnp59/test.sock"}, 110) = -1 ECONNREFUSED (Connection refused)
[   86s] [pid 182998] clock_nanosleep(CLOCK_MONOTONIC, TIMER_ABSTIME, {tv_sec=137134, tv_nsec=680254168},  <unfinished ...>
[   86s] [pid 182639] <... ppoll resumed>)       = 0 (Timeout)
[   86s] [pid 182639] ppoll([{fd=3, events=POLLIN}], 1, {tv_sec=0, tv_nsec=500000000}, NULL, 0 <unfinished ...>
[   86s] [pid 182847] <... ppoll resumed>)       = 0 (Timeout)
[   86s] [pid 182847] ppoll([{fd=3, events=POLLIN}], 1, {tv_sec=0, tv_nsec=500000000}, NULL, 0 <unfinished ...>
[   86s] [pid 182779] <... ppoll resumed>)       = 0 (Timeout)
[   86s] [pid 182779] ppoll([{fd=3, events=POLLIN}], 1, {tv_sec=0, tv_nsec=500000000}, NULL, 0 <unfinished ...>
[   86s] [pid 182917] <... ppoll resumed>)       = 0 (Timeout)
[   86s] [pid 182917] ppoll([{fd=3, events=POLLIN}], 1, {tv_sec=0, tv_nsec=500000000}, NULL, 0 <unfinished ...>
[   86s] [pid 182814] <... ppoll resumed>)       = 0 (Timeout)
[   86s] [pid 182814] ppoll([{fd=3, events=POLLIN}], 1, {tv_sec=0, tv_nsec=500000000}, NULL, 0 <unfinished ...>
[   86s] [pid 182709] <... ppoll resumed>)       = 0 (Timeout)
[   86s] [pid 182709] ppoll([{fd=3, events=POLLIN}], 1, {tv_sec=0, tv_nsec=500000000}, NULL, 0 <unfinished ...>
[   86s] [pid 183005] <... clock_nanosleep resumed>NULL) = 0
[   86s] [pid 183005] write(2, "Still waiting for connection to "..., 63 <unfinished ...>
[   86s] [pid 182635] <... read resumed>"Still waiting for connection to "..., 31662) = 63
[   86s] [pid 183005] <... write resumed>)       = 63
[   86s] [pid 182635] read(11,  <unfinished ...>
[   86s] [pid 183005] connect(4, {sa_family=AF_UNIX, sun_path="/tmp/tmp9fkjnp59/test.sock"}, 110) = -1 ECONNREFUSED (Connection refused)
[   86s] [pid 183005] clock_nanosleep(CLOCK_MONOTONIC, TIMER_ABSTIME, {tv_sec=137135, tv_nsec=178269914},  <unfinished ...>
[   86s] [pid 182997] <... clock_nanosleep resumed>NULL) = 0
[   86s] [pid 182997] write(2, "Still waiting for connection to "..., 63 <unfinished ...>
[   86s] [pid 182635] <... read resumed>"Still waiting for connection to "..., 31599) = 63
[   86s] [pid 182997] <... write resumed>)       = 63
[   86s] [pid 182635] read(11,  <unfinished ...>
[   86s] [pid 182997] connect(3, {sa_family=AF_UNIX, sun_path="/tmp/tmp9fkjnp59/test.sock"}, 110) = -1 ECONNREFUSED (Connection refused)
[   86s] [pid 182997] clock_nanosleep(CLOCK_MONOTONIC, TIMER_ABSTIME, {tv_sec=137135, tv_nsec=178613896},  <unfinished ...>
[   86s] [pid 183003] <... clock_nanosleep resumed>NULL) = 0
[   86s] [pid 183003] write(2, "Still waiting for connection to "..., 63 <unfinished ...>
[   86s] [pid 182635] <... read resumed>"Still waiting for connection to "..., 31536) = 63
[   86s] [pid 183003] <... write resumed>)       = 63
[   86s] [pid 182635] read(11,  <unfinished ...>
[   86s] [pid 183003] connect(7, {sa_family=AF_UNIX, sun_path="/tmp/tmp9fkjnp59/test.sock"}, 110 <unfinished ...>
[   86s] [pid 183002] <... clock_nanosleep resumed>NULL) = 0
[   86s] [pid 183003] <... connect resumed>)     = -1 ECONNREFUSED (Connection refused)
[   86s] [pid 183002] write(2, "Still waiting for connection to "..., 63 <unfinished ...>
[   86s] [pid 183003] clock_nanosleep(CLOCK_MONOTONIC, TIMER_ABSTIME, {tv_sec=137135, tv_nsec=179975598},  <unfinished ...>
[   86s] [pid 182635] <... read resumed>"Still waiting for connection to "..., 31473) = 63
[   86s] [pid 183002] <... write resumed>)       = 63
[   86s] [pid 182635] read(11,  <unfinished ...>
[   86s] [pid 183000] <... clock_nanosleep resumed>NULL) = 0
[   86s] [pid 183002] connect(8, {sa_family=AF_UNIX, sun_path="/tmp/tmp9fkjnp59/test.sock"}, 110) = -1 ECONNREFUSED (Connection refused)
[   86s] [pid 183000] write(2, "Still waiting for connection to "..., 63 <unfinished ...>
[   86s] [pid 183002] clock_nanosleep(CLOCK_MONOTONIC, TIMER_ABSTIME, {tv_sec=137135, tv_nsec=180065780},  <unfinished ...>
[   86s] [pid 182635] <... read resumed>"Still waiting for connection to "..., 31410) = 63
[   86s] [pid 183000] <... write resumed>)       = 63
[   86s] [pid 182635] read(11,  <unfinished ...>
[   86s] [pid 183000] connect(6, {sa_family=AF_UNIX, sun_path="/tmp/tmp9fkjnp59/test.sock"}, 110) = -1 ECONNREFUSED (Connection refused)
[   86s] [pid 183000] clock_nanosleep(CLOCK_MONOTONIC, TIMER_ABSTIME, {tv_sec=137135, tv_nsec=180138895},  <unfinished ...>
[   86s] [pid 182998] <... clock_nanosleep resumed>NULL) = 0
[   86s] [pid 182998] write(2, "Still waiting for connection to "..., 63 <unfinished ...>
[   86s] [pid 182635] <... read resumed>"Still waiting for connection to "..., 31347) = 63
[   86s] [pid 182998] <... write resumed>)       = 63
[   86s] [pid 182635] read(11,  <unfinished ...>
[   86s] [pid 182998] connect(5, {sa_family=AF_UNIX, sun_path="/tmp/tmp9fkjnp59/test.sock"}, 110) = -1 ECONNREFUSED (Connection refused)
[   86s] [pid 182998] clock_nanosleep(CLOCK_MONOTONIC, TIMER_ABSTIME, {tv_sec=137135, tv_nsec=180393614},  <unfinished ...>
[   86s] [pid 182996] <... futex resumed>)       = ? ERESTARTSYS (To be restarted if SA_RESTART is set)
[   86s] /usr/bin/strace: Process 182635 detached
[   86s] /usr/bin/strace: Process 182638 detached
[   86s] /usr/bin/strace: Process 182708 detached
[   86s] /usr/bin/strace: Process 182778 detached
[   86s] /usr/bin/strace: Process 182813 detached
[   86s] /usr/bin/strace: Process 182846 detached
[   86s] /usr/bin/strace: Process 182916 detached
[   86s] /usr/bin/strace: Process 182996 detached
[   86s] /usr/bin/strace: Process 183003 detached
[   86s] /usr/bin/strace: Process 183005 detached
[   86s] /usr/bin/strace: Process 183002 detached
[   86s] /usr/bin/strace: Process 183000 detached
[   86s] /usr/bin/strace: Process 182998 detached
[   86s] /usr/bin/strace: Process 182997 detached
[   86s] /usr/bin/strace: Process 182917 detached
[   86s] /usr/bin/strace: Process 182847 detached
[   86s] /usr/bin/strace: Process 182814 detached
[   86s] /usr/bin/strace: Process 182779 detached
[   86s] /usr/bin/strace: Process 182709 detached
[   86s] /usr/bin/strace: Process 182639 detached
[   86s] ――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――

[aszlig]

I picked test_closeallfds.py, because that's the one failing. Although I don't know where the timeout comes from.

Thanks for the strace. Here is the bit that most likely caused the timeout:

[   57s] [pid  1788] write(2, "terminate called after throwing "..., 48) = 48
[   57s] [pid  1788] write(2, "std::bad_array_new_length", 25) = 25
[   57s] [pid  1788] write(2, "'\n", 2)          = 2
[   57s] [pid  1788] write(2, "  what():  ", 11) = 11
[   57s] [pid  1788] write(2, "std::bad_array_new_length", 25) = 25
[   57s] [pid  1788] write(2, "\n", 1)           = 1

Does coredumpctl (if you have it enabled) list anything about the above?

[mweinelt]

That test is now also blocking the build on nixos unstable.

https://hydra.nixos.org/job/nixos/unstable/nixpkgs.ip2unix.x86_64-linux

[aszlig]

That test is now also blocking the build on nixos unstable.

@mweinelt: Have you checked whether it's the exact same test case as above?

Anyway, I'll look into that later since it seems to be reproducible on x86_64-linux.

[mweinelt]

It it stuck here with some helper binary open..

ip2unix> ninja: no work to do.
ip2unix> mesonCheckPhase flags: --timeout-multiplier=0
ip2unix> 1/4 ip2unix:unit-globpath        OK              0.01s
ip2unix> 2/4 ip2unix:unit-dynports        OK              0.14s
ip2unix> 3/4 ip2unix:unit-serial          OK              7.53s

root      374239  0.0  0.0 638608 18368 ?        Ssl  May06   2:47 nix-daemon --daemon
root     3020110  0.0  0.0 2137748 27724 ?       Ssl  14:32   0:01  \_ nix-daemon 3016813
nixbld13 3022134  0.0  0.0   4568  3376 ?        Ss   14:32   0:00  |   \_ bash -e /nix/store/l622p70vy8k5sh7y5wizi5f2mic6ynpg-source-stdenv.sh /nix/store/shkw4qm9qcw5sc5n1k5jznc83ny02r39-default-builder.sh
nixbld13 3038056  0.0  0.0  50640 40648 ?        S    14:32   0:02  |       \_ /nix/store/0r6k8xa2kgqyp3r4v2w7yrb80ma2iawm-python3-3.13.12/bin/python3.13 /nix/store/vn448hbsgbh0ahk1bjv2l16f5lhambiq-meson-1.10.2/bin/meson test --no-rebuild --print-errorlogs --max-lines=1000000 --timeout-multiplier=0
nixbld13 3038254  0.0  0.0  37052 32488 ?        Ss   14:32   0:00  |           \_ /nix/store/0r6k8xa2kgqyp3r4v2w7yrb80ma2iawm-python3-3.13.12/bin/python3.13 /nix/store/wavm4b318kvk7xqy7qfam86i0r8znz1n-python3.13-pytest-9.0.2/bin/.pytest-wrapped -p no:cacheprovider --ip2unix-path=/build/source/build/ip2unix --libip2unix-path=/build/source/build/libip2unix.so --helper-accept-no-peer-addr=/build/source/build/tests/helpers/helper_accept_no_peer_addr --timeout=60 --systemd-support --systemd-sa-path=/nix/store/9rpism89x6lyjcwzzkp6kana25rs03nn-systemd-260.1/bin/systemd-socket-activate
nixbld13 3038432  0.0  0.0   7436  3088 ?        S    14:32   0:00  |               \_ /build/source/build/tests/helpers/helper_accept_no_peer_addr

[aszlig]

@ggardet, @etam: Can you please check whether #39 fixes the issue on OpenSUSE?

[etam]

I checked and with this change it still fails with timeout on aarch64

[aszlig]

@etam: Damn. Thanks a lot for testing. Just also confirmed the same issue on aarch64-linux with Nix, so I can dig into this further.

@mweinelt: Just hid your comments on the x86_64-linux failures (which should be fixed by a6a9640) since they're unrelated to this issue.

[aszlig]

Okay, just for reference - got the full backtrace:

#0  0x0000007ff75952ac in __pthread_kill_implementation () from /nix/store/m8ckqvgrqjipr958qckyvgcgjvfvjz94-glibc-2.42-61/lib/libc.so.6
#1  0x0000007ff753c5b8 in raise () from /nix/store/m8ckqvgrqjipr958qckyvgcgjvfvjz94-glibc-2.42-61/lib/libc.so.6
#2  0x0000007ff7525d38 in abort () from /nix/store/m8ckqvgrqjipr958qckyvgcgjvfvjz94-glibc-2.42-61/lib/libc.so.6
#3  0x0000007ff7299818 in __gnu_cxx::__verbose_terminate_handler() () from /nix/store/dcb4bsy8fcn51bw0qp3vwx8q0rzpghd5-gcc-15.2.0-lib/lib/libstdc++.so.6
#4  0x0000007ff729704c in __cxxabiv1::__terminate(void (*)()) () from /nix/store/dcb4bsy8fcn51bw0qp3vwx8q0rzpghd5-gcc-15.2.0-lib/lib/libstdc++.so.6
#5  0x0000007ff728e49c in std::terminate() () from /nix/store/dcb4bsy8fcn51bw0qp3vwx8q0rzpghd5-gcc-15.2.0-lib/lib/libstdc++.so.6
#6  0x0000007ff72973dc in __cxa_throw () from /nix/store/dcb4bsy8fcn51bw0qp3vwx8q0rzpghd5-gcc-15.2.0-lib/lib/libstdc++.so.6
#7  0x0000007ff728ed7c in std::__throw_bad_array_new_length() () from /nix/store/dcb4bsy8fcn51bw0qp3vwx8q0rzpghd5-gcc-15.2.0-lib/lib/libstdc++.so.6
#8  0x0000007ff7f10c54 in std::__new_allocator<std::__detail::_Hash_node_base*>::allocate (this=0x7fffffe890, __n=18446744073709551557) at /nix/store/r1y7h8ln5hdlrwzcyysih7vxqnwralj1-gcc-15.2.0/include/c++/15.2.0/bits/new_allocator.h:139
#9  0x0000007ff7f6e898 in std::allocator_traits<std::allocator<std::__detail::_Hash_node_base*> >::allocate (__a=..., __n=18446744073709551557)
    at /nix/store/r1y7h8ln5hdlrwzcyysih7vxqnwralj1-gcc-15.2.0/include/c++/15.2.0/bits/alloc_traits.h:614
#10 std::__detail::_Hashtable_alloc<std::allocator<std::__detail::_Hash_node<int, false> > >::_M_allocate_buckets (this=0x7ff7fb02a0 <all_fds>, __bkt_count=18446744073709551557)
    at /nix/store/r1y7h8ln5hdlrwzcyysih7vxqnwralj1-gcc-15.2.0/include/c++/15.2.0/bits/hashtable_policy.h:1605
#11 0x0000007ff7f6e500 in std::_Hashtable<int, int, std::allocator<int>, std::__detail::_Identity, std::equal_to<int>, std::hash<int>, std::__detail::_Mod_range_hashing, std::__detail::_Default_ranged_hash, std::__detail::_Prime_rehash_policy, std::__detail::_Hashtable_traits<false, true, true> >::_M_allocate_buckets (this=0x7ff7fb02a0 <all_fds>, __bkt_count=18446744073709551557)
    at /nix/store/r1y7h8ln5hdlrwzcyysih7vxqnwralj1-gcc-15.2.0/include/c++/15.2.0/bits/hashtable.h:413
#12 0x0000007ff7f6d944 in std::_Hashtable<int, int, std::allocator<int>, std::__detail::_Identity, std::equal_to<int>, std::hash<int>, std::__detail::_Mod_range_hashing, std::__detail::_Default_ranged_hash, std::__detail::_Prime_rehash_policy, std::__detail::_Hashtable_traits<false, true, true> >::_M_rehash (this=0x7ff7fb02a0 <all_fds>, __bkt_count=18446744073709551557) at /nix/store/r1y7h8ln5hdlrwzcyysih7vxqnwralj1-gcc-15.2.0/include/c++/15.2.0/bits/hashtable.h:2754
#13 0x0000007ff7f6c0b8 in std::_Hashtable<int, int, std::allocator<int>, std::__detail::_Identity, std::equal_to<int>, std::hash<int>, std::__detail::_Mod_range_hashing, std::__detail::_Default_ranged_hash, std::__detail::_Prime_rehash_policy, std::__detail::_Hashtable_traits<false, true, true> >::_M_insert_unique_node (this=0x7ff7fb02a0 <all_fds>, __bkt=3, __code=3, __node=0x5555583580, __n_elt=1)
    at /nix/store/r1y7h8ln5hdlrwzcyysih7vxqnwralj1-gcc-15.2.0/include/c++/15.2.0/bits/hashtable.h:2479
#14 0x0000007ff7f6a034 in std::_Hashtable<int, int, std::allocator<int>, std::__detail::_Identity, std::equal_to<int>, std::hash<int>, std::__detail::_Mod_range_hashing, std::__detail::_Default_ranged_hash, std::__detail::_Prime_rehash_policy, std::__detail::_Hashtable_traits<false, true, true> >::_M_emplace_uniq<int const&> (this=0x7ff7fb02a0 <all_fds>) at /nix/store/r1y7h8ln5hdlrwzcyysih7vxqnwralj1-gcc-15.2.0/include/c++/15.2.0/bits/hashtable.h:2365
#15 0x0000007ff7f685a8 in std::_Hashtable<int, int, std::allocator<int>, std::__detail::_Identity, std::equal_to<int>, std::hash<int>, std::__detail::_Mod_range_hashing, std::__detail::_Default_ranged_hash, std::__detail::_Prime_rehash_policy, std::__detail::_Hashtable_traits<false, true, true> >::insert (this=0x7ff7fb02a0 <all_fds>, __v=@0x7fffffebb8: 3) at /nix/store/r1y7h8ln5hdlrwzcyysih7vxqnwralj1-gcc-15.2.0/include/c++/15.2.0/bits/hashtable.h:1049
#16 0x0000007ff7f66b60 in std::unordered_set<int, std::hash<int>, std::equal_to<int>, std::allocator<int> >::insert (this=0x7ff7fb02a0 <all_fds>, __x=@0x7fffffebb8: 3)
    at /nix/store/r1y7h8ln5hdlrwzcyysih7vxqnwralj1-gcc-15.2.0/include/c++/15.2.0/bits/unordered_set.h:475
#17 0x0000007ff7f64c80 in Systemd::init (rules=...) at ../src/systemd.cc:213
#18 0x0000007ff7f14bd0 in init_rules () at ../src/preload.cc:77
#19 0x0000007ff7f19edc in ip2unix_wrap_close (fd=4) at ../src/preload.cc:677
#20 0x0000007ff78cf430 in mi_process_init.part () from /nix/store/36mns0lq3c2zgky08rsq0xvamfzglzvj-python3-3.13.12/lib/libpython3.13.so.1.0
#21 0x0000007ff7894ee8 in _mi_process_init () from /nix/store/36mns0lq3c2zgky08rsq0xvamfzglzvj-python3-3.13.12/lib/libpython3.13.so.1.0
#22 0x0000007ff7fc32c4 in call_init () from /nix/store/m8ckqvgrqjipr958qckyvgcgjvfvjz94-glibc-2.42-61/lib/ld-linux-aarch64.so.1
#23 0x0000007ff7fc341c in _dl_init () from /nix/store/m8ckqvgrqjipr958qckyvgcgjvfvjz94-glibc-2.42-61/lib/ld-linux-aarch64.so.1
#24 0x0000007ff7fdbea0 in _start () from /nix/store/m8ckqvgrqjipr958qckyvgcgjvfvjz94-glibc-2.42-61/lib/ld-linux-aarch64.so.1

So the issue here is that all_fds in systemd.cc:213 is not yet initialised at mimalloc initialisation point, which looks like it's run before .init_array and calls close.

The easiest fix for this would be to just short-circuit to real::close whenever all_fds (and friends) aren't initialised.

[aszlig]

@etam: I think #40 should address this issue. Can you please confirm?