Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

AIX: npm install gives IOT/Abort trap #4083

Closed
flynn1973 opened this issue Jan 30, 2023 · 75 comments
Closed

AIX: npm install gives IOT/Abort trap #4083

flynn1973 opened this issue Jan 30, 2023 · 75 comments
Labels
aix Aix specific issues npm

Comments

@flynn1973
Copy link

flynn1973 commented Jan 30, 2023

Details

installed from here -> https://nodejs.org/dist/v18.13.0/node-v18.13.0-aix-ppc64.tar.gz

npm install gives following error...

root@nimvie: /root # npm install -g n
Assertion failed: __EX, file  ../deps/uv/src/unix/aix.c, line 186
IOT/Abort trap

exporting LIBPATH is of no help

Node.js version

root@nimvie: /root # node -v
v18.13.0

root@nimvie: /root # npm version
{
  npm: '8.19.3',
  node: '18.13.0',
  v8: '10.2.154.23-node.21',
  uv: '1.44.2',
  zlib: '1.2.13',
  brotli: '1.0.9',
  ares: '1.18.1',
  modules: '108',
  nghttp2: '1.51.0',
  napi: '8',
  llhttp: '6.0.10',
  uvwasi: '0.0.13',
  openssl: '3.0.7+quic',
  cldr: '42.0',
  icu: '72.1',
  tz: '2022f',
  unicode: '15.0',
  ngtcp2: '0.8.1',
  nghttp3: '0.7.0'
}

Example code

No response

Operating system

root@nimvie: /root # oslevel -s
7300-01-01-2246

Scope

runtime i guess

Module and version

Not applicable.

@flynn1973
Copy link
Author

might be related
libuv/libuv#3465

@flynn1973
Copy link
Author

flynn1973 commented Jan 31, 2023

gcc libs

root@nimvie: /home/tremch/GIT/various_python_stuff # rpm -qa | grep libgcc
libgcc10-10.3.0-6.ppc
libgcc-10-2.ppc
root@nimvie: /home/tremch/GIT/various_python_stuff # rpm -qa | grep libstd
libstdc++-10-2.ppc
libstdc++-devel-10-2.ppc
libstdc++10-10.3.0-6.ppc
libstdc++10-devel-10.3.0-6.ppc

@flynn1973
Copy link
Author

flynn1973 commented Jan 31, 2023

dbx corefile analysis

seems something is wrong with pthread

root@nimvie: /opt/node-v18.13.0-aix-ppc64 # dbx bin/node  core
Type 'help' for help.
warning: The core file is not a fullcore. Some info may
not be available.
[using memory image in core]
reading symbolic information ...internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c
internal error: assertion failed at line 6693 in file object.c


IOT/Abort trap in pthread_kill at 0x9000000006a92f8
0x9000000006a92f8 (pthread_kill+0x98) e8410028             ld   r2,0x28(r1)
(dbx)
(dbx) where
pthread_kill(??, ??) at 0x9000000006a92f8
_p_raise(??) at 0x9000000006a8b84
raise.raise(??) at 0x900000000229a28
abort() at 0x900000000252108
__assert_c99(??, ??, ??, ??) at 0x9000000002e6510
internal error: 1283-228 expected char ',', found ';-31,448,256;;'
internal error: 1283-228 expected char ';', found ',256;;'
internal error: 1283-232 index("256;;", ':') failed
internal error: unexpected value 120 at line 5206 in file stabstring.c
...
yadayada removed
...
internal error: 1283-228 expected char ',', found '128,64;;'
internal error: unexpected value 120 at line 5206 in file stabstring.c
internal error: unexpected value 120 at line 5206 in file stabstring.c
internal error: unexpected value 120 at line 5206 in file stabstring.c
internal error: unexpected value 120 at line 5206 in file stabstring.c
internal error: unexpected value 120 at line 5206 in file stabstring.c
internal error: unexpected value 120 at line 5206 in file stabstring.c
internal error: unexpected value 120 at line 5206 in file stabstring.c
internal error: unexpected value 120 at line 5206 in file stabstring.c
uv_run(loop = 0x000000011030b790, mode = UV_RUN_DEFAULT), line 406 in "core.c"
node::SpinEventLoop(node::Environment*)(??) at 0x100a862e0
node::NodeMainInstance::Run()(??) at 0x101a82a4c
node::LoadSnapshotDataAndRun(node::SnapshotData const**, node::InitializationResult const*)(??, ??) at 0x100002110
node::Start(int, char**)(??, ??) at 0x100006b50
main(??, ??) at 0x100000580
(dbx)

@sumitradawn
Copy link

I had tried to install npm in AIX version "7300-01-00-0000" and it is working fine.

image

@flynn1973
Copy link
Author

flynn1973 commented Jan 31, 2023

i tried it on an older aix level (7.1), even worse than on 7.3

packagebuilder@aixbuildhostng: /home/packagebuilder/test/node-v18.13.0-aix-ppc64/bin # ./node  -v
terminate called after throwing an instance of 'std::system_error'
  what():  Invalid argument
IOT/Abort trap
packagebuilder@aixbuildhostng: /home/packagebuilder/test/node-v18.13.0-aix-ppc64/bin # ./npm version
terminate called after throwing an instance of 'std::system_error'
  what():  Invalid argument
IOT/Abort trap

also points to a pthread problem....

@sumitradawn
Copy link

I dont have 7.1 machine but I tried it on AIX 7.2 and there also I am not facing this issue. For me it is working fine for both AIX 7.2 and 7.3. I guess this might be an environment issue or gcc, libgcc package installation issue.
I see this in your post:
root@nimvie: /home/tremch/GIT/various_python_stuff # rpm -qa | grep libgcc
libgcc10-10.3.0-6.ppc
libgcc-10-2.ppc

root@nimvie: /home/tremch/GIT/various_python_stuff # rpm -qa | grep libstd
libstdc++-10-2.ppc
libstdc++-devel-10-2.ppc
libstdc++10-10.3.0-6.ppc
libstdc++10-devel-10.3.0-6.ppc
I see two different versions of both libgcc and libstdc++. I am not sure how it is working in your environment but we dont have any "10-2" version in our "AIX toolbox". Can you please uninstall these gcc sub packages and install the latest versions from AIX toolbox and try again.

@flynn1973
Copy link
Author

dnf wont let me do this...newest libgcc10 seems to be installed anyway

root@nimvie: /root/bin # dnf search libgcc
Last metadata expiration check: 1 day, 18:51:31 ago on Mon Jan 30 13:25:27 CET 2023.
=============================================================== Name Exactly Matched: libgcc ================================================================
libgcc.ppc : GCC version 10 shared support library
=================================================================== Name Matched: libgcc ====================================================================
libgcc8.ppc : GCC version 8.3.0 shared support library
libgcc10.ppc : GCC version 10.3.0 shared support library
libgcc11.ppc : GCC version 11.3.0 shared support library
root@nimvie: /root/bin # dnf install libgcc10
Last metadata expiration check: 1 day, 18:51:37 ago on Mon Jan 30 13:25:27 CET 2023.
Package libgcc10-10.3.0-6.ppc is already installed.
Dependencies resolved.
Nothing to do.
Complete!
root@nimvie: /root/bin # dnf erase libgcc
Error:
 Problem: The operation would result in removing the following protected packages: dnf
(try to add '--skip-broken' to skip uninstallable packages or '--nobest' to use not only best candidate packages)
root@nimvie: /root/bin # dnf erase libgcc --nobest
Error:
 Problem: The operation would result in removing the following protected packages: dnf
(try to add '--skip-broken' to skip uninstallable packages)

@sumitradawn
Copy link

Yes, you are right. We just figured out that "dnf" is having dependency on libgcc that's why it is not allowing you to uninstall it. Anyways, coming to the issue, I see you have given dbx core file analysis. Can you try that with gdb instead of dbx ? Are these freshly installed system because I tried on a fresh system. Now because it's working for me, I am guessing that this is more of and environment issue. Meanwhile, I will try this on AIX 7.1.

Thanks

@flynn1973
Copy link
Author

gdb run, as suspected...aix libpthreads.a

root@nimvie: /opt/node-v18.13.0-aix-ppc64 # gdb bin/node core
GNU gdb (GDB) 10.2
Copyright (C) 2021 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law.
Type "show copying" and "show warranty" for details.
This GDB was configured as "powerpc64-ibm-aix7.1.0.0".
Type "show configuration" for configuration details.
For bug reporting instructions, please see:
<https://www.gnu.org/software/gdb/bugs/>.
Find the GDB manual and other documentation resources online at:
    <http://www.gnu.org/software/gdb/documentation/>.

For help, type "help".
Type "apropos word" to search for commands related to "word"...
Reading symbols from bin/node...

warning: core file may not match specified executable file.
Core was generated by `node'.
Program terminated with signal SIGABRT, Aborted.
#0  0x09000000006a92f8 in pthread_kill () from /usr/lib/libpthreads.a(_shr_xpg5_64.o)
(gdb) bt
#0  0x09000000006a92f8 in pthread_kill () from /usr/lib/libpthreads.a(_shr_xpg5_64.o)
#1  0x09000000006a8b88 in _p_raise () from /usr/lib/libpthreads.a(_shr_xpg5_64.o)
#2  0x0900000000229a2c in raise () from /usr/lib/libc.a(_shr_64.o)
#3  0x090000000025210c in abort () from /usr/lib/libc.a(_shr_64.o)
#4  0x09000000002e6514 in __assert_c99 () from /usr/lib/libc.a(_shr_64.o)
#5  0x000000010000c6b4 in uv__io_poll (loop=0x11030b790 <_uvcommon.bss_3_+8>, timeout=39) at ../deps/uv/src/unix/aix.c:186
#6  0x0000000100007a60 in uv_run (loop=0x11030b790 <_uvcommon.bss_3_+8>, mode=UV_RUN_DEFAULT) at ../deps/uv/src/unix/core.c:25
#7  0x0000000100a862e4 in node::SpinEventLoop(node::Environment*) ()
#8  0x0000000101a82a50 in node::NodeMainInstance::Run() ()
#9  0x0000000100002114 in node::LoadSnapshotDataAndRun(node::SnapshotData const**, node::InitializationResult const*) ()
#10 0x0000000100006b54 in node::Start(int, char**) ()
#11 0x0000000100000584 in main ()
(gdb)

root@nimvie: /opt/node-v18.13.0-aix-ppc64 # lslpp -l | grep pthread
  bos.rte.libpthreads        7.3.1.0  APPLIED    libpthreads Library
  bos.rte.libpthreads        7.3.1.0  APPLIED    libpthreads Library

root@nimvie: /opt/node-v18.13.0-aix-ppc64 # lslpp -l | grep -i xlc
  xlC.aix61.rte            16.1.0.10  COMMITTED  IBM XL C++ Runtime for AIX 6.1
  xlC.cpp                    9.0.0.0  COMMITTED  C for AIX Preprocessor
  xlC.msg.en_US.cpp          9.0.0.0  COMMITTED  C for AIX Preprocessor
  xlC.msg.en_US.rte        16.1.0.10  COMMITTED  IBM XL C++ Runtime
  xlC.rte                  16.1.0.10  COMMITTED  IBM XL C++ Runtime for AIX
  xlC.sup.aix50.rte          9.0.0.1  COMMITTED  XL C/C++ Runtime for AIX 5.2

@flynn1973
Copy link
Author

meanwhile i built node from source...same error...i'm outta here...

export OBJECT_MODE=64
export CC=gcc -maix64
export CXX=g++ -maix
./configure
gmake
gmake install

root@nimvie: /usr/local/bin # ./npm install -g n
Assertion failed: __EX, file  ../deps/uv/src/unix/aix.c, line 186ms
IOT/Abort trap

@mhdawson
Copy link
Member

mhdawson commented Feb 7, 2023

@flynn1973 it's not quite clear to me which version of AIX you were trying to run the binaries on.

For 18.x the community binaries only support AIX 7.2 or later - https://github.com/nodejs/node/blob/main/BUILDING.md#official-binary-platforms-and-toolchains

It is also built with GCC 8 and needs the corresponding glibc installed. glibc versions should really be backward compatible but that has not always been the case from past experience.

@flynn1973
Copy link
Author

flynn1973 commented Feb 8, 2023

i did all my testing, installing and building on aix 7.3...just did a quick run on 7.1 but this can be ignored. as seen above a build on 7.3 with gcc8 was succesfull but binaries shows the same problems as the prebuilt ones.

@sumitradawn
Copy link

@flynn1973 We had tried to recreate the issue in our system but We are unable to do so, Even We tried with the source code, it is working fine for us in both AIX 7.2 and 7.3.
Can you please run "truss npm install g n" - command in your system and share the output with us, so that we can figure out the issue and work on it.

@flynn1973
Copy link
Author

truss_out.txt

@flynn1973
Copy link
Author

i skimmed through the truss output and it seems it tries to open "/root/package.json" which of course does not exist. i created an empty file then another error occurs later in the run.

kopen("/root/package.json", 040000000)          = 23
fstatx(23, 0x0FFFFFFFFFFFAEA0, 176, 0)          = 0
kread(23, "\0\0\00110881C  \0\0\0\0".., 8192)   = 0
close(23)                                       = 0
kwrite(21, " 4 4   e r r o r   J S O".., 76)    = 76
npmkwrite(20, "1B [ 3 7 ; 4 0 m n p m1B".., 15)       = 15
 kwrite(20, "  1B [ 0 m", 5)                 = 5
ERR!kwrite(20, "1B [ 3 1 ; 4 0 m E R R !".., 16)      = 16
 kwrite(20, "  1B [ 0 m", 5)                 = 5
JSON.parsekwrite(20, "1B [ 3 5 m J S O N . p a".., 19)   = 19
 Unexpected end of JSON input while parsing empty string
kwrite(20, "   U n e x p e c t e d  ".., 61) = 61
kwrite(21, " 4 5   e r r o r   J S O".., 128)   = 128
npmkwrite(20, "1B [ 3 7 ; 4 0 m n p m1B".., 15)       = 15
 kwrite(20, "  1B [ 0 m", 5)                 = 5
ERR!kwrite(20, "1B [ 3 1 ; 4 0 m E R R !".., 16)      = 16
 kwrite(20, "  1B [ 0 m", 5)                 = 5
JSON.parsekwrite(20, "1B [ 3 5 m J S O N . p a".., 19)   = 19
 Failed to parse JSON data.
kwrite(20, "   F a i l e d   t o   p".., 32) = 32
npmkwrite(20, "1B [ 3 7 ; 4 0 m n p m1B".., 15)       = 15
 kwrite(20, "  1B [ 0 m", 5)                 = 5
ERR!kwrite(20, "1B [ 3 1 ; 4 0 m E R R !".., 16)      = 16
 kwrite(20, "  1B [ 0 m", 5)                 = 5
JSON.parsekwrite(20, "1B [ 3 5 m J S O N . p a".., 19)   = 19
 Note: package.json must be actual JSON, not just JavaScript.
kwrite(20, "   N o t e :   p a c k a".., 66) = 66
kwrite(21, " 4 6   v e r b o s e   e".., 18)    = 18
kwrite(20, 0x0FFFFFFFFFFF6F90, 0)               = 0
kwrite(18, 0x0FFFFFFFFFFF6F90, 0)               = 0
kwrite(21, " 4 7   t i m i n g   n p".., 34)    = 34
kwrite(21, " 4 8   v e r b o s e   u".., 52)    = 52
kwrite(21, " 4 9   v e r b o s e   u".., 62)    = 62
kwrite(21, " 5 0   v e r b o s e   u".., 61)    = 61
kwrite(21, " 5 1   v e r b o s e   c".., 18)    = 18
mprotect(0x0A00000000083000, 245760, 7)         = 0
mprotect(0x0A00000000083000, 245760, 5)         = 0
mprotect(0x0A00000000083000, 245760, 7)         = 0
mprotect(0x0A00000000083000, 245760, 5)         = 0
mprotect(0x0A00000000083000, 245760, 7)         = 0
mprotect(0x0A00000000083000, 245760, 5)         = 0
mprotect(0x0A00000000083000, 245760, 7)         = 0
mprotect(0x0A00000000083000, 245760, 5)         = 0

kwrite(20, "\n", 1)                             = 1
kwrite(21, " 5 2   e r r o r   A   c".., 120)   = 120
npmkwrite(20, "1B [ 3 7 ; 4 0 m n p m1B".., 15)       = 15
 kwrite(20, "  1B [ 0 m", 5)                 = 5
ERR!kwrite(20, "1B [ 3 1 ; 4 0 m E R R !".., 16)      = 16
kwrite(20, "1B [ 3 5 m1B [ 0 m", 9)              = 9
 A complete log of this run can be found in:
kwrite(20, "   A   c o m p l e t e  ".., 49) = 49
npmkwrite(20, "1B [ 3 7 ; 4 0 m n p m1B".., 15)       = 15
 kwrite(20, "  1B [ 0 m", 5)                 = 5
ERR!kwrite(20, "1B [ 3 1 ; 4 0 m E R R !".., 16)      = 16
kwrite(20, "1B [ 3 5 m1B [ 0 m", 9)              = 9
     /root/.npm/_logs/2023-03-09T13_05_32_224Z-debug-0.log
kwrite(20, "           / r o o t / .".., 63) = 63

root@nimvie: /root # cat /root/.npm/_logs/2023-03-09T13_05_32_224Z-debug-0.log
0 verbose cli /opt/node-v18.13.0-aix-ppc64/bin/node /opt/node-v18.13.0-aix-ppc64/bin/npm
1 info using npm@8.19.3
2 info using node@v18.13.0
3 timing npm:load:whichnode Completed in 2ms
4 timing config:load:defaults Completed in 6ms
5 timing config:load:file:/opt/node-v18.13.0-aix-ppc64/lib/node_modules/npm/npmrc Completed in 11ms
6 timing config:load:builtin Completed in 11ms
7 timing config:load:cli Completed in 4ms
8 timing config:load:env Completed in 1ms
9 timing config:load:project Completed in 118ms
10 timing config:load:file:/root/.npmrc Completed in 3ms
11 timing config:load:user Completed in 4ms
12 timing config:load:file:/opt/node-v18.13.0-aix-ppc64/etc/npmrc Completed in 3ms
13 timing config:load:global Completed in 3ms
14 timing config:load:validate Completed in 0ms
15 timing config:load:credentials Completed in 2ms
16 timing config:load:setEnvs Completed in 2ms
17 timing config:load Completed in 151ms
18 timing npm:load:configload Completed in 151ms
19 timing npm:load:mkdirpcache Completed in 10ms
20 timing npm:load:mkdirplogs Completed in 8ms
21 verbose title npm install g n
22 verbose argv "install" "g" "n"
23 timing npm:load:setTitle Completed in 5ms
24 timing config:load:flatten Completed in 14ms
25 timing npm:load:display Completed in 41ms
26 verbose logfile logs-max:10 dir:/root/.npm/_logs
27 verbose logfile /root/.npm/_logs/2023-03-09T13_05_32_224Z-debug-0.log
28 timing npm:load:logFile Completed in 26ms
29 timing npm:load:timers Completed in 1ms
30 timing npm:load:configScope Completed in 0ms
31 timing npm:load Completed in 245ms
32 timing arborist:ctor Completed in 2ms
33 silly logfile start cleaning logs, removing 1 files
34 silly logfile done cleaning log files
35 timing idealTree Completed in 28ms
36 timing command:install Completed in 139ms
37 verbose stack JSONParseError: Unexpected end of JSON input while parsing empty string
37 verbose stack     at /opt/node-v18.13.0-aix-ppc64/lib/node_modules/npm/node_modules/read-package-json-fast/index.js:11:61
37 verbose stack     at async Arborist.buildIdealTree (/opt/node-v18.13.0-aix-ppc64/lib/node_modules/npm/node_modules/@npmcli/arborist/lib/arborist/build-ideal-tree.js:208:7)
37 verbose stack     at async Promise.all (index 1)
37 verbose stack     at async Arborist.reify (/opt/node-v18.13.0-aix-ppc64/lib/node_modules/npm/node_modules/@npmcli/arborist/lib/arborist/reify.js:154:5)
37 verbose stack     at async Install.exec (/opt/node-v18.13.0-aix-ppc64/lib/node_modules/npm/lib/commands/install.js:145:5)
37 verbose stack     at async module.exports (/opt/node-v18.13.0-aix-ppc64/lib/node_modules/npm/lib/cli.js:78:5)
38 verbose cwd /root
39 verbose AIX 7.3
40 verbose node v18.13.0
41 verbose npm  v8.19.3
42 error code EJSONPARSE
43 error path /root/package.json
44 error JSON.parse Unexpected end of JSON input while parsing empty string
45 error JSON.parse Failed to parse JSON data.
45 error JSON.parse Note: package.json must be actual JSON, not just JavaScript.
46 verbose exit 1
47 timing npm Completed in 1229ms
48 verbose unfinished npm timer reify 1678367133267
49 verbose unfinished npm timer reify:loadTrees 1678367133368
50 verbose unfinished npm timer idealTree:init 1678367133371
51 verbose code 1
52 error A complete log of this run can be found in:
52 error     /root/.npm/_logs/2023-03-09T13_05_32_224Z-debug-0.log

@sumitradawn
Copy link

@flynn1973 Can you please switch to frame number 5 and print the values of w,pc(pollset_ctl),pqry(pollset_query) from the gdb core dump file?

@flynn1973
Copy link
Author

could not get the function values...

root@nimvie: /opt/node-v18.13.0-aix-ppc64 # gdb bin/npm ./core
GNU gdb (GDB) 10.2
Copyright (C) 2021 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law.
Type "show copying" and "show warranty" for details.
This GDB was configured as "powerpc64-ibm-aix7.1.0.0".
Type "show configuration" for configuration details.
For bug reporting instructions, please see:
<https://www.gnu.org/software/gdb/bugs/>.
Find the GDB manual and other documentation resources online at:
    <http://www.gnu.org/software/gdb/documentation/>.

For help, type "help".
Type "apropos word" to search for commands related to "word"...
"fffffffffffefa8s": not in executable format: file format not recognized
Core was generated by `node'.
Program terminated with signal SIGABRT, Aborted.
#0  0x09000000006a92f8 in ?? ()
(gdb) info frame
Stack level 0, frame at 0xfffffffffffcb70:
 pc = 0x9000000006a92f8; saved pc = 0xffffffffffffffff
 called by frame at 0xfffffffffffcc50
 Arglist at 0xfffffffffffcb70, args:
 Locals at 0xfffffffffffcb70, Previous frame's sp is 0xfffffffffffcb70
(gdb) frame 5
#5  0x09000000002e6514 in ?? ()
(gdb) info frame
Stack level 5, frame at 0xfffffffffffcef0:
 pc = 0x9000000002e6514; saved pc = 0x10000c6b4
 called by frame at 0xffffffffffff000, caller of frame at 0xfffffffffffce20
 Arglist at 0xfffffffffffce20, args:
 Locals at 0xfffffffffffce20, Previous frame's sp is 0xfffffffffffcef0
 Saved registers:
  pc at 0xfffffffffffcf00, lr at 0xfffffffffffcf00
(gdb) p 'w,pc(pollset_ctl)'
No symbol table is loaded.  Use the "file" command.
(gdb) p 'pqry(pollset_query)'
No symbol table is loaded.  Use the "file" command.

@gireeshpunathil
Copy link
Member

gireeshpunathil commented Apr 3, 2023

@flynn1973 - looks like the values are optimised out. One option is to dump the address of pc and pqry that are available at the frame. As we know the shape of these structures in

https://github.com/libuv/libuv/blob/244df24bf411a396ceaf69f8a80a98e5629ee584/src/unix/aix.c#L135
https://github.com/libuv/libuv/blob/244df24bf411a396ceaf69f8a80a98e5629ee584/src/unix/aix.c#L137

we can potentially get the value of fd from the memory. And then we can validate if it is a valid fd or not.

@flynn1973
Copy link
Author

seems there is no memory for pqry on the stack...

(gdb) info registers
r0             0xffffffffffffffff  18446744073709551615
r1             0xfffffffffffce20   1152921504606834208
r2             0xffffffffffffffff  18446744073709551615
r3             0x0                 0
r4             0xffffffffffffffff  18446744073709551615
r5             0xffffffffffffffff  18446744073709551615
r6             0xffffffffffffffff  18446744073709551615
r7             0xffffffffffffffff  18446744073709551615
r8             0xffffffffffffffff  18446744073709551615
r9             0xffffffffffffffff  18446744073709551615
r10            0xffffffffffffffff  18446744073709551615
r11            0xffffffffffffffff  18446744073709551615
r12            0xffffffffffffffff  18446744073709551615
r13            0x110333be0         4566760416
r14            0x5                 5
r15            0xffffffffffff620   1152921504606844448
r16            0xffffffffffff650   1152921504606844496
r17            0x800200140000000   576495942044221440
r18            0xffffffffffffed0   1152921504606846672
r19            0x9fffffff00107b8   720575940110911416
r20            0xbadc0ffee0ddf00d  13464654573299691533
r21            0x11030b960         4566595936
r22            0x0                 0
r23            0x0                 0
r24            0x9001000a1417550   648535941232817488
r25            0x2                 2
r26            0xffffffffffffffff  18446744073709551615
r27            0x0                 0
r28            0x6                 6
r29            0x0                 0
r30            0x6                 6
r31            0x1                 1
pc             0x9000000002e6514   0x9000000002e6514
msr            0x800000000200d032  9223372036888383538
cr             0x242000            2367488
lr             0x9000000002e6514   0x9000000002e6514
ctr            0xffffffff006b9c00  18446744069421636608
xer            0xffffffff          4294967295
fpscr          0xa6202100          -1507843840
vscr           0x0                 0
vrsave         0x5b5b5b5b          1532713819

query for pc

(gdb) x/xb $pc
0x9000000002e6514:        Cannot access memory at address 0x9000000002e6514

@sumitradawn
Copy link

Hello @flynn1973,
Sorry for the confusion earlier, as pc we are referring a variable not the program counter.

Can you please run this steps:
In gdb -

Go to frame number 5: (gdb) f 5
get the local variable store address: (gdb) i r $r31
get the local variable pc: (gdb) x/10x ($r31+0x2070)
get the local variable pqry: (gdb) x/10x ($r31+0x2078)

@flynn1973
Copy link
Author

flynn1973 commented Apr 13, 2023

here you go...

Core was generated by `node'.
Program terminated with signal SIGABRT, Aborted.
#0  0x09000000006a92f8 in ?? ()
(gdb)  f 5
#5  0x09000000002e6514 in ?? ()
(gdb) i r $r31
r31            0x1                 1
(gdb)  x/10x ($r31+0x2070)
0x2071:   Cannot access memory at address 0x2071
(gdb)  x/10x ($r31+0x2078)
0x2079:   Cannot access memory at address 0x2079

@gireeshpunathil
Copy link
Member

@flynn1973 - looks like $r31 is corrupt / core file is truncated. Is there any way to figure out if the core file is full? alternatively, is there any way you can pass the file for my inspection?

@flynn1973
Copy link
Author

core_node_sigabort.zip

@sumitradawn
Copy link

@flynn1973 : $r31 is probably not re-populated with its original value in the current frame. I am not sure about why that can happen despite it is a non-volatile register: probably because the control hasn't return to the frame yet.

So we cannot rely on its value. However, the truss output provides the address of pc and pqry for the pollset_ctl and pollset_query calls respectively:

pollset_ctl(2, 0x0FFFFFFFFFFFEF50, 1)       Err#22 EINVAL
pollset_query(2, 0x0FFFFFFFFFFFEF58)        = 0

and at those locations we have:

(gdb) x/10x 0xFFFFFFFFFFFEF50
0xfffffffffffef50:        0x00000001      0x1030b790      0x0fffffff      0xffffef60
0xfffffffffffef60:        0x00000001      0x00000017      0x00000017      0xffffef70
0xfffffffffffef70:        0x00000000      0x00000005

this means:

backend_fd: 2
pc.cmd: 0
pc.events = 0x1
pc.fd = 0x1030b790

this shows invalid fd. And that can explain the assertion too.


Now We are trying to match your system and recreate the issue locally.

@sumitradawn
Copy link

@flynn1973 : We tried to recreate the issue with same AIX version 7300-01-01-2246 and other libraries but We are not able to do so. Now we want to do further debugging So, Can you please share the faulty nodejs executable.

@flynn1973
Copy link
Author

basically yes, but the file is bigger than what github allows me to upload.

@flynn1973
Copy link
Author

the same issue was reported by another user on the aix open source forums

@vandysn
Copy link

vandysn commented Jun 21, 2023

I see the same error 'Assertion failed: __EX, file ../deps/uv/src/unix/aix.c, line 186
IOT/Abort trap' with tls.connect() call of our node application. Works fine when I run from a terminal but errors when starting from inittab. There is no error caught with tls.connect() but the callback function does not get invoked. I also tried enableTrace() but that does not log anything either. Worked fine with AIX7.3 TL0 and AIX 7.2 versions. Issue started with AIX7.3 TL1. Get the same error when I start the node app with forever. I think the forever error is for a different reason and may not be related to tls.connect()

@gireeshpunathil
Copy link
Member

basically yes, but the file is bigger than what github allows me to upload.

google drive, may be?

@flynn1973
Copy link
Author

as the problematic binary is from the official distribution...do you really need this binary uploaded?

@gireeshpunathil
Copy link
Member

thanks @vandysn , that was quick. will analyse this to see what this means and get back.

@gireeshpunathil
Copy link
Member

tldr; we got some leads, but not any great revelations.

will share detailed analysis later.

@vandysn - I have uploaded a new revision of the instrument. @vandysn - could you pls try again with this one?

https://gist.github.com/gireeshpunathil/82185356bdc5356a68f4e65bd7c3699e

@vandysn
Copy link

vandysn commented Aug 2, 2023

@gireeshpunathil - The new one works well. The issue is gone. Below are the debug messages around the same area in case you need them.

LIBUV: before pollset_ctl 20 1
LIBUV: after pollset_ctl2 20 1 0 22
LIBUV: before pollset_query 20
LIBUV: after pollset_query3 20 2 22 1

@gireeshpunathil
Copy link
Member

@vandysn - thanks. this is interesting! we haven't made anything to fix the issue, the changes were only to add more instruments to get the input and output to various system calls such as socket, connect, pollsetv_ctl and pollsetv_query.

however, this brings up an important aspect of the problem - looks like the issue is time dependent. This can also explain why my systems (which have the same h/w, os config as the problematic ones) did not fail even once.

from the result (instrument changing the socket behaviour), it is reasonable to believe that the timing window can be related to n/w settings, system load, cpu config, or something else - we don't know.

pls stay tuned, I am going to try with a small C test case simulating the polling sequence. I will update you what I find. If something interesting comes up, will raise it up with @nodejs/libuv too.

@gireeshpunathil
Copy link
Member

https://gist.github.com/gireeshpunathil/d3c4b15f5a9dcea39979295f520484e3
@vandysn - we devised a new program that attempt to simulate this behaviour. could you run this and share the output?

  1. output of ./sim1
  2. output of truss -f ./sim1 (you can paste only the 10-15 lines that starts from pollset_create to pollset_query)

(depending on how truly this simulates node behaviour, this can be an iterative process, just want to let you know)

@flynn1973
Copy link
Author

i fired up the simulation.

  1. running sim1 gives no output
  2. truss output
root@nimvie: /home/tremch # truss -f ./sim1
55509328: execve("./sim1", 0x2FF228C8, 0x2FF228D0)       argc: 1
55509328: 78709137: read_sysconfig(0xF16F6760, 0x00000018, 0x00000004, 0x10000000, 0x2000031D, 0x00000088, 0x06010000, 0xF173A1FC) = 0x00000000
55509328: 78709137: __loadx(0x0A040000, 0xD052EF74, 0x00000088, 0x20000440, 0x00000041) = 0x00000000
55509328: 78709137: pollset_create(32)                  = 0
55509328: 78709137: socket(2, 1, 0)                     = 3
55509328: 78709137: kioctl(3, -2147195266, 0x2FE20760, 0x00000000) = 0
55509328: 78709137: kioctl(3, -2147195267, 0x2FE20760, 0x00000000) = 0
55509328: 78709137: kfcntl(3, F_SETFL, 0x00000006)      = 0
55509328: 78709137: kfcntl(3, F_SETFD, 0x00000001)      = 0
55509328: 78709137: pollset_ctl(0, 0x2FE20864, 1)               = 0
55509328: 78709137: getsockopt(3, 65535, 4104, 0x2FE207A4, 0x2FE207A0) = 0
55509328: 78709137: connext(3, 0x2FE2084C, 16)          Err#55 EINPROGRESS
55509328: 78709137: _pollset_poll(0, 0x2FE20790) (sleeping...)
55509328: 78709137: _pollset_poll(0, 0x2FE20790)                = 1
55509328: 78709137: kwrite(3, "\0\0\0\0\0\0\0\0\0\0\0\0".., 1048576) Err#32 EPIPE
55509328:     Received signal #13, SIGPIPE [default]
55509328: *** process killed ***

@gireeshpunathil
Copy link
Member

@flynn1973 - thanks for checking! the output is definitely unexpected. An EPIPE would indicate the peer socket is not connected for a SOCK_STREAM type socket, but here we are writing only after a pollset_poll that checks for the readiness of the socket. (An ENOTCONN would have been little comforting even though that too does not make sense after the poll)

Our intent is to see what happens at pollset_query and not at write, so I have removed the write call and uploaded a sim2, can you pls test?

@vandysn - feel free to test both sim1 and sim2, curious to know whether you get EPIPE too!

@vandysn
Copy link

vandysn commented Aug 8, 2023

@gireeshpunathil - Below is what I see

  1. ./sim1 - No output
  2. ./sim2 - No output
  3. truss -f ./sim1 - Output from pollset_create is below

4587900: 24510759: pollset_create(32) = 6
4587900: 24510759: socket(2, 1, 0) = 3
4587900: 24510759: kioctl(3, -2147195266, 0x2FE209D0, 0x00000000) = 0
4587900: 24510759: kioctl(3, -2147195267, 0x2FE209D0, 0x00000000) = 0
4587900: 24510759: kfcntl(3, F_SETFL, 0x00000006) = 0
4587900: 24510759: kfcntl(3, F_SETFD, 0x00000001) = 0
4587900: 24510759: pollset_ctl(6, 0x2FE20AD4, 1) = 0
4587900: 24510759: getsockopt(3, 65535, 4104, 0x2FE20A14, 0x2FE20A10) = 0
4587900: 24510759: connext(3, 0x2FE20ABC, 16) Err#55 EINPROGRESS
4587900: 24510759: _pollset_poll(6, 0x2FE20A00) (sleeping...)

  1. truss -f ./sim2 - Output from pollset_create is below

4587924: 24576311: pollset_create(32) = 6
4587924: 24576311: socket(2, 1, 0) = 3
4587924: 24576311: kioctl(3, -2147195266, 0x2F5209D0, 0x00000000) = 0
4587924: 24576311: kioctl(3, -2147195267, 0x2F5209D0, 0x00000000) = 0
4587924: 24576311: kfcntl(3, F_SETFL, 0x00000006) = 0
4587924: 24576311: kfcntl(3, F_SETFD, 0x00000001) = 0
4587924: 24576311: pollset_ctl(6, 0x2FF20AE8, 1) = 0
4587924: 24576311: getsockopt(3, 65535, 4104, 0x2F520A14, 0x2F520A10) = 0
4587924: 24576311: connext(3, 0x2FF20AD0, 16) Err#55 EINPROGRESS
4587924: 24576311: _pollset_poll(6, 0x2F520A00) (sleeping...)

@flynn1973
Copy link
Author

truss -f of sim2:

root@nimvie: /home/tremch # truss -f sim2
22610420: execve("/usr/bin/sim2", 0x2FF228A8, 0x2FF228B0)       Err#2  ENOENT
22610420: 59965763: execve("/etc/sim2", 0x2FF228A8, 0x2FF228B0) Err#2  ENOENT
22610420: 59965763: execve("/usr/sbin/sim2", 0x2FF228A8, 0x2FF228B0) Err#2  ENOENT
22610420: 59965763: execve("/usr/ucb/sim2", 0x2FF228A8, 0x2FF228B0) Err#2  ENOENT
22610420: 59965763: execve("/usr/bin/X11/sim2", 0x2FF228A8, 0x2FF228B0) Err#2  ENOENT
22610420: 59965763: execve("/sbin/sim2", 0x2FF228A8, 0x2FF228B0) Err#2  ENOENT
22610420: 59965763: execve("/usr/java8_64/jre/bin/sim2", 0x2FF228A8, 0x2FF228B0) Err#2  ENOENT
22610420: 59965763: execve("/opt/freeware/bin/sim2", 0x2FF228A8, 0x2FF228B0) Err#2  ENOENT
22610420: 59965763: execve("/opt/freeware/sbin/sim2", 0x2FF228A8, 0x2FF228B0) Err#2  ENOENT
22610420: 59965763: execve("./sim2", 0x2FF228A8, 0x2FF228B0)  argc: 1
22610420: 59965763: read_sysconfig(0xF16F6760, 0x00000018, 0x00000004, 0x10000000, 0x2000035D, 0x00000088, 0x06010000, 0xF173A1FC) = 0x00000000
22610420: 59965763: __loadx(0x0A040000, 0xD052EF74, 0x00000088, 0x20000480, 0x00000041) = 0x00000000
22610420: 59965763: pollset_create(32)                  = 0
22610420: 59965763: socket(2, 1, 0)                     = 3
22610420: 59965763: kioctl(3, -2147195266, 0x2F520740, 0x00000000) = 0
22610420: 59965763: kioctl(3, -2147195267, 0x2F520740, 0x00000000) = 0
22610420: 59965763: kfcntl(3, F_SETFL, 0x00000006)      = 0
22610420: 59965763: kfcntl(3, F_SETFD, 0x00000001)      = 0
22610420: 59965763: pollset_ctl(0, 0x2FF20858, 1)               = 0
22610420: 59965763: getsockopt(3, 65535, 4104, 0x2F520784, 0x2F520780) = 0
22610420: 59965763: connext(3, 0x2FF20840, 16)          Err#55 EINPROGRESS
22610420: 59965763: _pollset_poll(0, 0x2F520770) (sleeping...)
22610420: 59965763: _pollset_poll(0, 0x2F520770)                = 1
22610420: 59965763: pollset_ctl(0, 0x2FF20858, 1)               Err#22 EINVAL
22610420: 59965763: pollset_query(0, 0x2FF20850)                = 1
pcr1: 22610420: 59965763: kwrite(2, " p c r 1 :  ", 6)          = 6
022610420: 59965763: kwrite(2, " 0", 1)                 = 1
, cr: 22610420: 59965763: kwrite(2, " ,   c r :  ", 6)          = 6
-22610420: 59965763: kwrite(2, " -", 1)                 = 1
122610420: 59965763: kwrite(2, " 1", 1)                 = 1
, nfds: 22610420: 59965763: kwrite(2, " ,   n f d s :  ", 8)    = 8
122610420: 59965763: kwrite(2, " 1", 1)                 = 1

22610420: 59965763: kwrite(2, "\n", 1)                  = 1
wr: 22610420: 59965763: kwrite(2, " w r :  ", 4)                = 4
022610420: 59965763: kwrite(2, " 0", 1)                 = 1
, pcr2: 22610420: 59965763: kwrite(2, " ,   p c r 2 :  ", 8)    = 8
-22610420: 59965763: kwrite(2, " -", 1)                 = 1
122610420: 59965763: kwrite(2, " 1", 1)                 = 1
, pqr: 22610420: 59965763: kwrite(2, " ,   p q r :  ", 7)       = 7
122610420: 59965763: kwrite(2, " 1", 1)                 = 1

22610420: 59965763: kwrite(2, "\n", 1)                  = 1
pce1: 22610420: 59965763: kwrite(2, " p c e 1 :  ", 6)          = 6
022610420: 59965763: kwrite(2, " 0", 1)                 = 1
, ce: 22610420: 59965763: kwrite(2, " ,   c e :  ", 6)          = 6
5522610420: 59965763: kwrite(2, " 5 5", 2)                      = 2
, ppe: 22610420: 59965763: kwrite(2, " ,   p p e :  ", 7)       = 7
022610420: 59965763: kwrite(2, " 0", 1)                 = 1

22610420: 59965763: kwrite(2, "\n", 1)                  = 1
we: 22610420: 59965763: kwrite(2, " w e :  ", 4)                = 4
022610420: 59965763: kwrite(2, " 0", 1)                 = 1
, pce2: 22610420: 59965763: kwrite(2, " ,   p c e 2 :  ", 8)    = 8
2222610420: 59965763: kwrite(2, " 2 2", 2)                      = 2
, pqe: 22610420: 59965763: kwrite(2, " ,   p q e :  ", 7)       = 7
022610420: 59965763: kwrite(2, " 0", 1)                 = 1

22610420: 59965763: kwrite(2, "\n", 1)                  = 1
22610420: 59965763: kfcntl(1, F_GETFL, 0x0000000C)      = 67110914
22610420: 59965763: kfcntl(2, F_GETFL, 0x2FF22FFC)      = 67110914
22610420: 59965763: _exit(0)

@gireeshpunathil
Copy link
Member

@vandysn - looks like your process is unable to establish connection with registry.npmjs.org . What you get when you do ping registry.npmjs.org ?

@gireeshpunathil
Copy link
Member

thanks @flynn1973. the output is similar to what we get - that is, pollset_query returns the fd that is already in the polling set. In the bad case, when pollset_ctl returns EINVAL, pollset_query was returning 0, and that was the root cause of the assertion.

so in summary, this program does not look like mimic the actual scenario! let us see what happens in @vandysn 's case.

@vandysn
Copy link

vandysn commented Aug 8, 2023

@gireeshpunathil - I did not think it was connecting to registry.npmjs.org. Ping to registry.npmjs.org does not return a reply. The AIX server is setup with proxy.

@gireeshpunathil
Copy link
Member

ok - then how does rpm install supposed to work? is the proxy configured to allow node / npm binaries to connect? or some other mechanism? (sorry, I don't have enough understanding of how proxies work)

@vandysn
Copy link

vandysn commented Aug 8, 2023

Sorry, I am not an expert on proxies either. But it is setup so that 'npm install' works but ping does not. I do not have a way to bypass the proxy. So is it possible to just go with what flynn1973 provided.

@flynn1973
Copy link
Author

i am in full control of my aix machine...so i can do/test whatever is necessary

@mhdawson
Copy link
Member

I don't think it's necessarily related but since it only occurs with an npm proxy I'll mention nodejs/node#48969 which was also only seen when using a proxy. The main branch now has this fix and this is the backport - nodejs/node#49016 for 18.x.

If @flynn1973 recreates without the proxy, then never mind since it should not be related.

@gireeshpunathil
Copy link
Member

thanks @mhdawson for this, looks like critical piece of info in this context. I will try to locally back port this fix and provide a binary for @flynn1973 and @vandysn to test. Meanwhile, I have also developed a third simulation that is a middle ground between the full blown node (fails) and the C socket connecting code (passes) that I would like to be tested in the failing systems.

@flynn1973 and @vandysn - could you try this and let me know what do you get? (with and without truss)

https://gist.github.com/gireeshpunathil/d3c4b15f5a9dcea39979295f520484e3#file-foo-js

@flynn1973
Copy link
Author

without truss:

root@nimvie: /home/tremch # /opt/node-v18.13.0-aix-ppc64/bin/node foo.js
connect ETIMEDOUT 104.16.27.34:443

with truss:

root@nimvie: /home/tremch # truss -f /opt/node-v18.13.0-aix-ppc64/bin/node foo.js
56361298: execve("/opt/node-v18.13.0-aix-ppc64/bin/node", 0x2FF22888, 0x2FF22894)  argc: 2
56361298: 83624347: kusla(6, 0x09FFFFFFF0001170)                = 0
56361298: 83624347: read_sysconfig(0x09001000A17FB2C0, 0x0000000000000018, 0x0000000000000079, 0x0000000000000071, 0x08FFFFFFF00000D0, 0x0000000000000091, 0x0000000000000089, 0x00000000000000B1) = 0x0000000000000000
56361298: 83624347: sbrk(0x0000000000000000)            = 0x0000000110329830
56361298: 83624347: vmgetinfo(0x0FFFFFFFFFFFECE0, 7, 16)        = 0
56361298: 83624347: sbrk(0x0000000000000000)            = 0x0000000110329830
56361298: 83624347: sbrk(0x0000000000000010)            = 0x0000000110329830
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110329840
56361298: 83624347: checkpnt_block(0x0000000000000000, 17) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 17) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 18) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 18) = 0
56361298: 83624347: thread_init(0x09000000006A28E0, 0x09001000A0222F98) =
56361298: 83624347: sbrk(0x0000000000000000)            = 0x0000000110339860
56361298: 83624347: vmgetinfo(0x0FFFFFFFFFFFF348, 7, 16)        = 0
56361298: 83624347: smcr_procattr(0, 1, 0x0FFFFFFFFFFFF340) = 0
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: checkpnt_block(0x0000000000000000, 17) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 17) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 18) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 18) = 0
56361298: 83624347: appulimit(1005, 0)                  = 0x0FFFFFFFF6000000
56361298: 83624347: _thread_self()                      = 83624347
56361298: 83624347: thread_setmystate(0x0000000000000000, 0x0FFFFFFFFFFFEE80) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEAA0, 0x0FFFFFFFFFFFEE28) = 0
56361298: 83624347: _sigaction(3, 0x0FFFFFFFFFFFF240, 0x0FFFFFFFFFFFF270) = 0
56361298: 83624347: _sigaction(4, 0x0FFFFFFFFFFFF240, 0x0FFFFFFFFFFFF270) = 0
56361298: 83624347: _sigaction(5, 0x0FFFFFFFFFFFF240, 0x0FFFFFFFFFFFF270) = 0
56361298: 83624347: _sigaction(6, 0x0FFFFFFFFFFFF240, 0x0FFFFFFFFFFFF270) = 0
56361298: 83624347: _sigaction(7, 0x0FFFFFFFFFFFF240, 0x0FFFFFFFFFFFF270) = 0
56361298: 83624347: _sigaction(8, 0x0FFFFFFFFFFFF240, 0x0FFFFFFFFFFFF270) = 0
56361298: 83624347: _sigaction(10, 0x0FFFFFFFFFFFF240, 0x0FFFFFFFFFFFF270) = 0
56361298: 83624347: _sigaction(11, 0x0FFFFFFFFFFFF240, 0x0FFFFFFFFFFFF270) = 0
56361298: 83624347: _sigaction(12, 0x0FFFFFFFFFFFF240, 0x0FFFFFFFFFFFF270) = 0
56361298: 83624347: _sigaction(36, 0x0FFFFFFFFFFFF240, 0x0FFFFFFFFFFFF270) = 0
56361298: 83624347: _sigaction(39, 0x0FFFFFFFFFFFF240, 0x0FFFFFFFFFFFF270) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 17) = 0
56361298: 83624347: kloadquery(238, 0x0FFFFFFFFFFFF280, 24) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 18) = 0
56361298: 83624347: __libc_sbrk(0x0000000000020020)     = 0x0000000110339860
56361298: 83624347: getuidx(4)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(4)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: getuidx(4)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(4)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: getuidx(4)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(4)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: getuidx(4)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(4)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: getuidx(4)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(4)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: getuidx(4)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(4)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: sem_init(0x00000001100D7008, 0, 0)  = 0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110359880
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001103698A0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001103798C0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001103898E0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110399900
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001103A9920
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001103B9940
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001103C9960
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001103D9980
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001103E99A0
56361298: 83624347: __libc_sbrk(0x0000000000020020)     = 0x00000001103F99C0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001104199E0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110429A00
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110439A20
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110449A40
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110459A60
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110469A80
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110479AA0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110489AC0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110499AE0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001104A9B00
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001104B9B20
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001104C9B40
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001104D9B60
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001104E9B80
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001104F9BA0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110509BC0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110519BE0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110529C00
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110539C20
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110549C40
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110559C60
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110569C80
56361298: 83624347: __libc_sbrk(0x0000000000020020)     = 0x0000000110579CA0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110599CC0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001105A9CE0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001105B9D00
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001105C9D20
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001105D9D40
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001105E9D60
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001105F9D80
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110609DA0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110619DC0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110629DE0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110639E00
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110649E20
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110659E40
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110669E60
56361298: 83624347: checkpnt_block(0x0000000000000000, 18) = 0
56361298: 83624347: accessx("/opt/node-v18.13.0-aix-ppc64/bin/node", 0, 0) = 0
56361298: 83624347: statx("/", 0x0FFFFFFFFFFFD640, 176, 011) = 0
56361298: 83624347: statx("/opt", 0x0FFFFFFFFFFFD640, 176, 011) = 0
56361298: 83624347: statx("/opt/node-v18.13.0-aix-ppc64", 0x0FFFFFFFFFFFD640, 176, 011) = 0
56361298: 83624347: statx("/opt/node-v18.13.0-aix-ppc64/bin", 0x0FFFFFFFFFFFD640, 176, 011) = 0
56361298: 83624347: statx("/opt/node-v18.13.0-aix-ppc64/bin/node", 0x0FFFFFFFFFFFD640, 176, 011) = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: fstatx(0, 0x0000000110327F00, 176, 0)       = 0
56361298: 83624347: fstatx(1, 0x0000000110327FD8, 176, 0)       = 0
56361298: 83624347: fstatx(2, 0x00000001103280B0, 176, 0)       = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF70, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(1, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(2, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(3, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(4, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(5, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(6, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(7, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(8, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(10, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(11, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(12, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(13, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(14, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(15, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(16, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(18, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(19, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(20, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(21, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(22, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(23, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(24, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(25, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(26, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(27, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(28, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(29, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(30, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(31, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: kfcntl(0, F_GETFL, 0x000000011032BE98) = 67110914
56361298: 83624347: kioctl(0, 22528, 0x0000000000000000, 0x0000000000000000) = 0
56361298: 83624347: kioctl(0, 22528, 0x0000000000000000, 0x0000000000000000) = 0
56361298: 83624347: kioctl(0, 21505, 0x0000000110327FB0, 0x0000000000000000) = 0
56361298: 83624347: kfcntl(1, F_GETFL, 0x000000011032BE98) = 67110914
56361298: 83624347: kioctl(1, 22528, 0x0000000000000000, 0x0000000000000000) = 0
56361298: 83624347: kioctl(1, 22528, 0x0000000000000000, 0x0000000000000000) = 0
56361298: 83624347: kioctl(1, 21505, 0x0000000110328088, 0x0000000000000000) = 0
56361298: 83624347: kfcntl(2, F_GETFL, 0x000000011032BE98) = 67110914
56361298: 83624347: kioctl(2, 22528, 0x0000000000000000, 0x0000000000000000) = 0
56361298: 83624347: kioctl(2, 22528, 0x0000000000000000, 0x0000000000000000) = 0
56361298: 83624347: kioctl(2, 21505, 0x0000000110328160, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(2, 0x0FFFFFFFFFFFF3E0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF330) = 0
56361298: 83624347: _sigaction(15, 0x0FFFFFFFFFFFF410, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEF30, 0x0000000000000000) = 0
56361298: 83624347: appgetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: appsetrlimit(7, 0x0FFFFFFFFFFFF3E0) = 0
56361298: 83624347: kfcntl(0, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: kfcntl(1, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: kfcntl(2, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: kfcntl(3, F_SETFD, 0x0000000000000001) Err#9  EBADF
56361298: 83624347: kfcntl(4, F_SETFD, 0x0000000000000001) Err#9  EBADF
56361298: 83624347: kfcntl(5, F_SETFD, 0x0000000000000001) Err#9  EBADF
56361298: 83624347: kfcntl(6, F_SETFD, 0x0000000000000001) Err#9  EBADF
56361298: 83624347: kfcntl(7, F_SETFD, 0x0000000000000001) Err#9  EBADF
56361298: 83624347: kfcntl(8, F_SETFD, 0x0000000000000001) Err#9  EBADF
56361298: 83624347: kfcntl(9, F_SETFD, 0x0000000000000001) Err#9  EBADF
56361298: 83624347: kfcntl(10, F_SETFD, 0x0000000000000001) Err#9  EBADF
56361298: 83624347: kfcntl(11, F_SETFD, 0x0000000000000001) Err#9  EBADF
56361298: 83624347: kfcntl(12, F_SETFD, 0x0000000000000001) Err#9  EBADF
56361298: 83624347: kfcntl(13, F_SETFD, 0x0000000000000001) Err#9  EBADF
56361298: 83624347: kfcntl(14, F_SETFD, 0x0000000000000001) Err#9  EBADF
56361298: 83624347: kfcntl(15, F_SETFD, 0x0000000000000001) Err#9  EBADF
56361298: 83624347: kfcntl(16, F_SETFD, 0x0000000000000001) Err#9  EBADF
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: kopen("/etc/ssl/openssl.cnf", O_RDONLY) Err#2  ENOENT
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: checkpnt_block(0x0000000000000000, 17) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 17) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 18) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 18) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 17) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 17) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 18) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 18) = 0
56361298: 83624347: kopen("/dev/urandom", O_RDONLY)     = 3
56361298: 83624347: fstatx(3, 0x0FFFFFFFFFFFEA80, 176, 0)       = 0
56361298: 83624347: kread(3, "891C81 ▒ 0 ▒ ▒9F M k05 ▒".., 48) = 48
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: pollset_create(-1)                  = 0
56361298: 83624347: pipe(0x0FFFFFFFFFFFEFC0)            = 0
56361298: 83624347: kfcntl(4, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: kfcntl(5, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: kwrite(5, " *", 1)                  = 1
56361298: 83624347: pipe(0x0FFFFFFFFFFFF170)            = 0
56361298: 83624347: kfcntl(6, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: kfcntl(7, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: kfcntl(6, F_GETFL, 0x0000000000000000) = 0
56361298: 83624347: kfcntl(6, F_SETFL, 0x0000000000000004) = 0
56361298: 83624347: kioctl(6, -2147195266, 0x0FFFFFFFFFFFF138, 0x0000000000000000) = 0
56361298: 83624347: kfcntl(7, F_GETFL, 0x0000000000000000) = 1
56361298: 83624347: kfcntl(7, F_SETFL, 0x0000000000000005) = 0
56361298: 83624347: kioctl(7, -2147195266, 0x0FFFFFFFFFFFF138, 0x0000000000000000) = 0
56361298: 83624347: pipe(0x0FFFFFFFFFFFF150)            = 0
56361298: 83624347: kfcntl(8, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: kfcntl(9, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: kfcntl(8, F_GETFL, 0x0000000000000000) = 0
56361298: 83624347: kfcntl(8, F_SETFL, 0x0000000000000004) = 0
56361298: 83624347: kioctl(8, -2147195266, 0x0FFFFFFFFFFFF118, 0x0000000000000000) = 0
56361298: 83624347: kfcntl(9, F_GETFL, 0x0000000000000000) = 1
56361298: 83624347: kfcntl(9, F_SETFL, 0x0000000000000005) = 0
56361298: 83624347: kioctl(9, -2147195266, 0x0FFFFFFFFFFFF118, 0x0000000000000000) = 0
56361298: 83624347: sem_init(0x00000001103E20F0, 0, 0)  = 0
56361298: 83624347: thread_setmystate(0x0000000000000000, 0x0FFFFFFFFFFFEBA0) = 0
56361298: 83624347: thread_create()                     = 90571153
56361298: 83624347: mprotect(0x00000001103E5000, 4096, 0)       = 0
56361298: 83624347: thread_setstate(90571153, 0x0FFFFFFFFFFFEC50, 0x0000000000000000) = 0
56361298: 90571153: pollset_create(-1)                  = 1
56361298: 90571153: pipe(0x0000000110417440)            = 0
56361298: 90571153: kfcntl(10, F_SETFD, 0x0000000000000001) = 0
56361298: 90571153: kfcntl(11, F_SETFD, 0x0000000000000001) = 0
56361298: 90571153: kfcntl(10, F_GETFL, 0x0000000000000000) = 0
56361298: 90571153: kfcntl(10, F_SETFL, 0x0000000000000004) = 0
56361298: 90571153: kioctl(10, -2147195266, 0x0000000110417408, 0x0000000000000000) = 0
56361298: 90571153: kfcntl(11, F_GETFL, 0x0000000000000000) = 1
56361298: 90571153: kfcntl(11, F_SETFL, 0x0000000000000005) = 0
56361298: 90571153: kioctl(11, -2147195266, 0x0000000110417408, 0x0000000000000000) = 0
56361298: 90571153: pipe(0x0000000110417420)            = 0
56361298: 90571153: kfcntl(12, F_SETFD, 0x0000000000000001) = 0
56361298: 90571153: kfcntl(13, F_SETFD, 0x0000000000000001) = 0
56361298: 90571153: kfcntl(12, F_GETFL, 0x0000000000000000) = 0
56361298: 90571153: kfcntl(12, F_SETFL, 0x0000000000000004) = 0
56361298: 90571153: kioctl(12, -2147195266, 0x00000001104173E8, 0x0000000000000000) = 0
56361298: 90571153: kfcntl(13, F_GETFL, 0x0000000000000000) = 1
56361298: 90571153: kfcntl(13, F_SETFL, 0x0000000000000005) = 0
56361298: 90571153: kioctl(13, -2147195266, 0x00000001104173E8, 0x0000000000000000) = 0
56361298: 83624347: _sem_wait(0x00000001103E20F0, 0x0000000000000000, 0) = 0
56361298: 90571153: sem_post(0x00000001103E20F0)                = 0
56361298: 83624347: sem_destroy(0x00000001103E20F0)     = 0
56361298: 90571153: pollset_ctl(1, 0x0000000110417420, 1)       = 0
56361298: 83624347: thread_setmystate(0x0000000000000000, 0x0FFFFFFFFFFFEBA0) = 0
56361298: 83624347: thread_create()                     = 77791685
56361298: 90571153: pollset_ctl(1, 0x0000000110417420, 1)       = 0
56361298: 83624347: mprotect(0x000000011041B000, 4096, 0)       = 0
56361298: 83624347: thread_setstate(77791685, 0x0FFFFFFFFFFFEC50, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0000000000000000, 0x0FFFFFFFFFFFEBA0) = 0
56361298: 83624347: thread_create()                     = 113049905
56361298: 83624347: mprotect(0x0000000110451000, 4096, 0)       = 0
56361298: 83624347: thread_setstate(113049905, 0x0FFFFFFFFFFFEC50, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0000000000000000, 0x0FFFFFFFFFFFEBA0) = 0
56361298: 83624347: thread_create()                     = 73597307
56361298: 83624347: mprotect(0x0000000110487000, 4096, 0)       = 0
56361298: 83624347: thread_setstate(73597307, 0x0FFFFFFFFFFFEC50, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0000000000000000, 0x0FFFFFFFFFFFEBA0) = 0
56361298: 83624347: thread_create()                     = 63242633
56361298: 83624347: mprotect(0x00000001104BC000, 4096, 0)       = 0
56361298: 83624347: thread_setstate(63242633, 0x0FFFFFFFFFFFEC50, 0x0000000000000000) = 0
56361298: 83624347: thread_unlock_(0x0FFFFFFFFFFFF1C8)  = 0
56361298: 73597307: thread_waitlock_(0x0FFFFFFFFFFFF1C8, 257, 0x0000000000000000) = 1
56361298: 63242633: thread_waitlock_(0x09001000A1B57A10, 257, 0x0000000000000000) = 1
56361298: 63242633: thread_twakeup(83624347, 1073741824)        = 0
56361298: 83624347: thread_tsleep(0, 0x09001000A1B57A10, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_waitlock_(0x0FFFFFFFFFFFF1C8, 257, 0x0000000000000000) = 0
56361298: 63242633: thread_unlock_(0x0FFFFFFFFFFFF1C8)  = 0
56361298: 73597307: thread_waitlock_(0x0FFFFFFFFFFFF1C8, 257, 0x0000000000000000) = 1
56361298: 83624347: thread_unlock_(0x0FFFFFFFFFFFF1C8)  = 0
56361298: 113049905: thread_waitlock_(0x0FFFFFFFFFFFF1C8, 257, 0x0000000000000000) = 1
56361298: 113049905: thread_waitlock_(0x09001000A1B57A10, 257, 0x0000000000000000) = 0
56361298: 113049905: thread_twakeup(83624347, 1073741824)       = 0
56361298: 83624347: thread_tsleep(0, 0x09001000A1B57A10, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_waitlock_(0x0FFFFFFFFFFFF1C8, 257, 0x0000000000000000) = 1
56361298: 113049905: thread_unlock_(0x0FFFFFFFFFFFF1C8) = 0
56361298: 83624347: thread_unlock_(0x0FFFFFFFFFFFF1C8)  = 0
56361298: 73597307: thread_waitlock_(0x0FFFFFFFFFFFF1C8, 257, 0x0000000000000000) = 1
56361298: 73597307: thread_twakeup(83624347, 1073741824)        = 0
56361298: 83624347: thread_tsleep(0, 0x09001000A1B57A10, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_waitlock_(0x0FFFFFFFFFFFF1C8, 257, 0x0000000000000000) = 1
56361298: 73597307: thread_unlock_(0x0FFFFFFFFFFFF1C8)  = 0
56361298: 83624347: thread_unlock_(0x0FFFFFFFFFFFF1C8)  = 0
56361298: 77791685: thread_waitlock_(0x0FFFFFFFFFFFF1C8, 257, 0x0000000000000000) = 1
56361298: 77791685: thread_twakeup(83624347, 1073741824)        = 0
56361298: 83624347: thread_tsleep(0, 0x09001000A1B57A10, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: pollset_create(-1)                  = 2
56361298: 83624347: pipe(0x0FFFFFFFFFFFF2E0)            = 0
56361298: 83624347: kfcntl(14, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: kfcntl(15, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: kfcntl(14, F_GETFL, 0x0000000000000000) = 0
56361298: 83624347: kfcntl(14, F_SETFL, 0x0000000000000004) = 0
56361298: 83624347: kioctl(14, -2147195266, 0x0FFFFFFFFFFFF2A8, 0x0000000000000000) = 0
56361298: 83624347: kfcntl(15, F_GETFL, 0x0000000000000000) = 1
56361298: 83624347: kfcntl(15, F_SETFL, 0x0000000000000005) = 0
56361298: 83624347: kioctl(15, -2147195266, 0x0FFFFFFFFFFFF2A8, 0x0000000000000000) = 0
56361298: 83624347: pipe(0x0FFFFFFFFFFFF2C0)            = 0
56361298: 83624347: kfcntl(16, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: kfcntl(17, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: kfcntl(16, F_GETFL, 0x0000000000000000) = 0
56361298: 83624347: kfcntl(16, F_SETFL, 0x0000000000000004) = 0
56361298: 83624347: kioctl(16, -2147195266, 0x0FFFFFFFFFFFF288, 0x0000000000000000) = 0
56361298: 83624347: kfcntl(17, F_GETFL, 0x0000000000000000) = 1
56361298: 83624347: kfcntl(17, F_SETFL, 0x0000000000000005) = 0
56361298: 83624347: kioctl(17, -2147195266, 0x0FFFFFFFFFFFF288, 0x0000000000000000) = 0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110679E80
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110689EA0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110699EC0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001106A9EE0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001106B9F00
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001106C9F20
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001106D9F40
56361298: 83624347: __libc_sbrk(0x0000000000020020)     = 0x00000001106E9F60
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110709F80
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110719FA0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110729FC0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110739FE0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011074A000
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011075A020
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011076A040
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011077A060
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011078A080
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011079A0A0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001107AA0C0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001107BA0E0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001107CA100
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001107DA120
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001107EA140
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001107FA160
56361298: 83624347: wpar_vmgetinfo(0, 0x0FFFFFFFFFFFE960, 0, 800) = 0
56361298: 83624347: ptx_get_wparvmker(0x0000000000000000, 0x0FFFFFFFFFFFE770, 0x0000000000423634, 0x000000000023E4EA, 0x0000000000003728, 0x0000000000000000, 0xF1000B0159D00000, 0x0000000000000000) = 0x0000000000000000
56361298: 83624347: lpar_get_info(0x0000000000000002, 0x0FFFFFFFFFFFE858, 0x0000000000000108, 0x800000000000D032, 0x0000000000003728, 0x0000000000000000, 0xF1000B0159D00000, 0x0000000000000000) = 0x0000000000000000
56361298: 83624347: lpar_get_info(0x0000000000000008, 0x0FFFFFFFFFFFE7D0, 0x0000000000000048, 0x800000000000D032, 0x0000000000003728, 0x0000000000000000, 0xF1000B0159D00000, 0x0000000000000000) = 0x0000000000000000
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011080A180
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011081A1A0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011082A1C0
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: mmap(0x0000000110182000, 134217728, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000000000000
56361298: 83624347: sem_init(0x00000001108385A0, 0, 0)  = 0
56361298: 83624347: sem_init(0x0000000110838D70, 0, 0)  = 0
56361298: 83624347: sem_init(0x0000000110838E30, 0, 0)  = 0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011083A1E0
56361298: 83624347: mmap(0x000040000CAC0000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008000000
56361298: 83624347: munmap(0x0A00000008040000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008000000, 262144, 3) = 0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011084A200
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: munmap(0x0A0000000803E000, 8192)    = 0
56361298: 83624347: mprotect(0x0A00000008000000, 253952, 1) = 0
56361298: 83624347: mmap(0x0000400014E80000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A0000000803E000
56361298: 83624347: munmap(0x0A0000000803E000, 8192)    = 0
56361298: 83624347: munmap(0x0A00000008080000, 249856)  = 0
56361298: 83624347: mprotect(0x0A00000008040000, 262144, 3) = 0
56361298: 83624347: mmap(0x0000400033E80000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008080000
56361298: 83624347: munmap(0x0A000000080C0000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008080000, 262144, 3) = 0
56361298: 83624347: mmap(0x000040002FA40000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A000000080C0000
56361298: 83624347: munmap(0x0A00000008100000, 258048)  = 0
56361298: 83624347: mprotect(0x0A000000080C0000, 262144, 3) = 0
56361298: 83624347: mmap(0x0000400022D00000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008100000
56361298: 83624347: munmap(0x0A00000008140000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008100000, 262144, 3) = 0
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: mmap(0x00004000106C0000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008140000
56361298: 83624347: munmap(0x0A00000008180000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008140000, 262144, 3) = 0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011085A220
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011086A240
56361298: 83624347: __libc_sbrk(0x0000000000020020)     = 0x000000011087A260
56361298: 83624347: __libc_sbrk(0x0000000000020020)     = 0x000000011089A280
56361298: 83624347: __libc_sbrk(0x0000000000030020)     = 0x00000001108BA2A0
56361298: 83624347: mmap(0x0000400008800000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008180000
56361298: 83624347: munmap(0x0A000000081C0000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008180000, 262144, 3) = 0
56361298: 83624347: mmap(0x000040000CB40000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A000000081C0000
56361298: 83624347: munmap(0x0A00000008200000, 258048)  = 0
56361298: 83624347: mprotect(0x0A000000081C0000, 262144, 3) = 0
56361298: 83624347: mmap(0x0000400025BC0000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008200000
56361298: 83624347: munmap(0x0A00000008240000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008200000, 262144, 3) = 0
56361298: 83624347: mmap(0x00004000023C0000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008240000
56361298: 83624347: munmap(0x0A00000008280000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008240000, 262144, 3) = 0
56361298: 83624347: mmap(0x000040001C740000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008280000
56361298: 83624347: munmap(0x0A000000082C0000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008280000, 262144, 3) = 0
56361298: 83624347: mmap(0x000040003C9C0000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A000000082C0000
56361298: 83624347: munmap(0x0A00000008300000, 258048)  = 0
56361298: 83624347: mprotect(0x0A000000082C0000, 262144, 3) = 0
56361298: 83624347: mmap(0x000040000EE00000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008300000
56361298: 83624347: munmap(0x0A00000008340000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008300000, 262144, 3) = 0
56361298: 83624347: mmap(0x0000400032380000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008340000
56361298: 83624347: munmap(0x0A00000008380000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008340000, 262144, 3) = 0
56361298: 83624347: mmap(0x000040002C700000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008380000
56361298: 83624347: munmap(0x0A000000083C0000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008380000, 262144, 3) = 0
56361298: 83624347: mprotect(0x0A00000000000000, 262144, 0) = 0
56361298: 83624347: madvise(0x0A00000000000000, 262144, 4) = 0
56361298: 83624347: mprotect(0x0A00000000000000, 8192, 3)       = 0
56361298: 83624347: mprotect(0x0A00000000002000, 4096, 0)       = 0
56361298: 83624347: madvise(0x0A00000000002000, 4096, 4)        = 0
56361298: 83624347: mprotect(0x0A00000000003000, 245760, 7) = 0
56361298: 83624347: mprotect(0x0A0000000003F000, 4096, 0)       = 0
56361298: 83624347: madvise(0x0A0000000003F000, 4096, 4)        = 0
56361298: 83624347: mprotect(0x0A00000000003000, 245760, 7) = 0
56361298: 83624347: __libc_sbrk(0x0000000000030020)     = 0x00000001108EA2C0
56361298: 83624347: mmap(0x0000400022740000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A000000083C0000
56361298: 83624347: munmap(0x0A00000008400000, 258048)  = 0
56361298: 83624347: mprotect(0x0A000000083C0000, 262144, 3) = 0
56361298: 83624347: munmap(0x0A000000083DD000, 143360)  = 0
56361298: 83624347: mprotect(0x0A00000000031000, 4096, 0)       = 0
56361298: 83624347: madvise(0x0A00000000031000, 4096, 4)        = 0
56361298: 83624347: mprotect(0x0A00000000032000, 57344, 0) = 0
56361298: 83624347: madvise(0x0A00000000032000, 57344, 4)       = 0
56361298: 83624347: munmap(0x0A00000008182000, 253952)  = 0
56361298: 83624347: mprotect(0x0A00000000003000, 188416, 5) = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: mmap(0x000040002EA40000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A000000083DD000
56361298: 83624347: munmap(0x0A000000083DD000, 143360)  = 0
56361298: 83624347: munmap(0x0A00000008440000, 114688)  = 0
56361298: 83624347: mprotect(0x0A00000008400000, 262144, 3) = 0
56361298: 83624347: mmap(0x00004000147C0000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008440000
56361298: 83624347: munmap(0x0A00000008480000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008440000, 262144, 3) = 0
56361298: 83624347: mmap(0x000040001DEC0000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008480000
56361298: 83624347: munmap(0x0A000000084C0000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008480000, 262144, 3) = 0
56361298: 83624347: __libc_sbrk(0x0000000000030020)     = 0x000000011091A2E0
56361298: 83624347: mmap(0x0000400000EC0000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A000000084C0000
56361298: 83624347: munmap(0x0A00000008500000, 258048)  = 0
56361298: 83624347: mprotect(0x0A000000084C0000, 262144, 3) = 0
56361298: 83624347: mmap(0x000040002AC80000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008500000
56361298: 83624347: munmap(0x0A00000008540000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008500000, 262144, 3) = 0
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: sem_init(0x000000011032118C, 0, 0)  = 0
56361298: 83624347: sigprocmask(2, 0x0FFFFFFFFFFFEF08, 0x0FFFFFFFFFFFED90) = 0
56361298: 83624347: thread_setmystate(0x0000000000000000, 0x0FFFFFFFFFFFE7F0) = 0
56361298: 83624347: thread_create()                     = 127992219
56361298: 83624347: mprotect(0x00000001108C0000, 4096, 0)       = 0
56361298: 83624347: thread_setstate(127992219, 0x0FFFFFFFFFFFE8A0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFE920, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFEC30) = 0
56361298: 83624347: _sigaction(30, 0x0FFFFFFFFFFFECE0, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFE830, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(1, 0x0FFFFFFFFFFFEF08, 0x0000000000000000) = 0
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: statx(".", 0x0FFFFFFFFFFFD340, 176, 010) = 0
56361298: 83624347: kopen(".", O_RDONLY|O_LARGEFILE)    = 18
56361298: 83624347: getdirent64(18, 0x000000011087C750, 4096) = 2448
56361298: 83624347: klseek(18, 0, 0, 0x0FFFFFFFFFFFD240)        = 0
56361298: 83624347: kfcntl(18, F_GETFD, 0x000000011032BE98) = 0
56361298: 83624347: kfcntl(18, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: close(18)                           = 0
56361298: 83624347: statx("/", 0x0FFFFFFFFFFFD550, 176, 020) = 0
56361298: 83624347: statx("./", 0x0FFFFFFFFFFFD550, 176, 020) = 0
56361298: 83624347: statx("./../", 0x0FFFFFFFFFFFD340, 176, 010) = 0
56361298: 83624347: kopen("./../", O_RDONLY|O_LARGEFILE)        = 18
56361298: 83624347: getdirent64(18, 0x000000011087C750, 4096) = 944
56361298: 83624347: klseek(18, 0, 0, 0x0FFFFFFFFFFFD240)        = 0
56361298: 83624347: kfcntl(18, F_GETFD, 0x000000011032BE98) = 0
56361298: 83624347: kfcntl(18, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: fstatx(18, 0x0FFFFFFFFFFFD550, 176, 020) = 0
56361298: 83624347: mntctl(20, 16, "")                  = 0
56361298: 83624347: getdirent64(18, 0x000000011087C750, 4096) = 944
56361298: 83624347: close(18)                           = 0
56361298: 83624347: statx("./../../", 0x0FFFFFFFFFFFD340, 176, 010) = 0
56361298: 83624347: kopen("./../../", O_RDONLY|O_LARGEFILE) = 18
56361298: 83624347: getdirent64(18, 0x000000011087C750, 4096) = 1768
56361298: 83624347: klseek(18, 0, 0, 0x0FFFFFFFFFFFD240)        = 0
56361298: 83624347: kfcntl(18, F_GETFD, 0x000000011032BE98) = 0
56361298: 83624347: kfcntl(18, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: fstatx(18, 0x0FFFFFFFFFFFD550, 176, 020) = 0
56361298: 83624347: mntctl(20, 16, "")                  = 0
56361298: 83624347: getdirent64(18, 0x000000011087C750, 4096) = 1768
56361298: 83624347: close(18)                           = 0
56361298: 83624347: mmap(0x00004000297C0000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008540000
56361298: 83624347: munmap(0x0A00000008580000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008540000, 262144, 3) = 0
56361298: 83624347: mmap(0x00004000309C0000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008580000
56361298: 83624347: munmap(0x0A000000085C0000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008580000, 262144, 3) = 0
56361298: 83624347: mmap(0x0000400020700000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A000000085C0000
56361298: 83624347: munmap(0x0A00000008600000, 258048)  = 0
56361298: 83624347: mprotect(0x0A000000085C0000, 262144, 3) = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: getuidx(2)                          = 0
56361298: 83624347: getuidx(1)                          = 0
56361298: 83624347: getgidx(2)                          = 0
56361298: 83624347: getgidx(1)                          = 0
56361298: 83624347: statx("/home/tremch/foo.js", 0x0FFFFFFFFFFFDA50, 176, 0) = 0
56361298: 83624347: statx("/home", 0x0FFFFFFFFFFFD7D0, 176, 01) = 0
56361298: 83624347: statx("/home/tremch", 0x0FFFFFFFFFFFD7D0, 176, 01) = 0
56361298: 83624347: statx("/home/tremch/foo.js", 0x0FFFFFFFFFFFD7D0, 176, 01) = 0
56361298: 83624347: kopen("/home/tremch/package.json", 040000000) Err#2  ENOENT
56361298: 83624347: kopen("/home/package.json", 040000000) Err#2  ENOENT
56361298: 83624347: kopen("/package.json", 040000000)   Err#2  ENOENT
56361298: 83624347: kopen("/home/tremch/foo.js", 040000000) = 18
56361298: 83624347: fstatx(18, 0x0FFFFFFFFFFFDB90, 176, 0) = 0
56361298: 83624347: kread(18, " c o n s t   h   =   r e".., 998) = 998
56361298: 83624347: close(18)                           = 0
56361298: 83624347: mmap(0x0000400013040000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008600000
56361298: 83624347: munmap(0x0A00000008640000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008600000, 262144, 3) = 0
56361298: 83624347: mprotect(0x0A00000000040000, 262144, 0) = 0
56361298: 83624347: madvise(0x0A00000000040000, 262144, 4) = 0
56361298: 83624347: mprotect(0x0A00000000040000, 8192, 3)       = 0
56361298: 83624347: mprotect(0x0A00000000042000, 4096, 0)       = 0
56361298: 83624347: madvise(0x0A00000000042000, 4096, 4)        = 0
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 7) = 0
56361298: 83624347: mprotect(0x0A0000000007F000, 4096, 0)       = 0
56361298: 83624347: madvise(0x0A0000000007F000, 4096, 4)        = 0
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 7) = 0
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 5) = 0
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 7) = 0
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 5) = 0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011094A300
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011095A320
56361298: 83624347: mmap(0x000040000FFC0000, 528384, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008640000
56361298: 83624347: munmap(0x0A00000008682000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008640000, 270336, 3) = 0
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: _getpid()                           = 56361298
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011096A340
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011097A360
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011098A380
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x000000011099A3A0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001109AA3C0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001109BA3E0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001109CA400
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001109DA420
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001109EA440
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x00000001109FA460
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110A0A480
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110A1A4A0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110A2A4C0
56361298: 83624347: kopen("/dev/null", 040000000)               = 18
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 7) = 0
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 5) = 0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110A3A4E0
56361298: 83624347: __libc_sbrk(0x0000000000020020)     = 0x0000000110A4A500
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 7) = 0
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 5) = 0
56361298: 83624347: kopen("/etc/resolv.conf", O_RDONLY|O_LARGEFILE) = 19
56361298: 83624347: kioctl(19, 22528, 0x0000000000000000, 0x0000000000000000) Err#25 ENOTTY
56361298: 83624347: kioctl(19, 22528, 0x0000000000000000, 0x0000000000000000) Err#25 ENOTTY
56361298: 83624347: kread(19, " #\n #   t h i s   f i l".., 4096) = 413
56361298: 83624347: kread(19, " #\n #   t h i s   f i l".., 4096) = 0
56361298: 83624347: close(19)                           = 0
56361298: 83624347: kopen("/etc/nsswitch.conf", O_RDONLY|O_LARGEFILE) Err#2  ENOENT
56361298: 83624347: kopen("/etc/host.conf", O_RDONLY|O_LARGEFILE) Err#2  ENOENT
56361298: 83624347: kopen("/etc/svc.conf", O_RDONLY|O_LARGEFILE) Err#2  ENOENT
56361298: 83624347: sem_init(0x0FFFFFFFFFFFCEF0, 0, 0)  = 0
56361298: 83624347: thread_setmystate(0x0000000000000000, 0x0FFFFFFFFFFFC8D0) = 0
56361298: 83624347: thread_create()                     = 58851605
56361298: 83624347: mprotect(0x0000000110A31000, 4096, 0)       = 0
56361298: 83624347: thread_setstate(58851605, 0x0FFFFFFFFFFFC980, 0x0000000000000000) = 0
56361298: 58851605: sem_post(0x0FFFFFFFFFFFCEF0)                = 0
56361298: 83624347: __libc_sbrk(0x0000000000040020)     = 0x0000000110A6A520
56361298: 83624347: thread_setmystate(0x0000000000000000, 0x0FFFFFFFFFFFC8D0) = 0
56361298: 83624347: thread_create()                     = 80413145
56361298: 83624347: mprotect(0x0000000110A66000, 4096, 0)       = 0
56361298: 83624347: thread_setstate(80413145, 0x0FFFFFFFFFFFC980, 0x0000000000000000) = 0
56361298: 80413145: sem_post(0x0FFFFFFFFFFFCEF0)                = 0
56361298: 83624347: __libc_sbrk(0x0000000000040020)     = 0x0000000110AAA540
56361298: 83624347: thread_setmystate(0x0000000000000000, 0x0FFFFFFFFFFFC8D0) = 0
56361298: 83624347: thread_create()                     = 94437833
56361298: 83624347: mprotect(0x0000000110A9C000, 4096, 0)       = 0
56361298: 83624347: thread_setstate(94437833, 0x0FFFFFFFFFFFC980, 0x0000000000000000) = 0
56361298: 94437833: sem_post(0x0FFFFFFFFFFFCEF0)                = 0
56361298: 83624347: __libc_sbrk(0x0000000000040020)     = 0x0000000110AEA560
56361298: 83624347: thread_setmystate(0x0000000000000000, 0x0FFFFFFFFFFFC8D0) = 0
56361298: 83624347: thread_create()                     = 33554733
56361298: 83624347: mprotect(0x0000000110AD1000, 4096, 0)       = 0
56361298: 83624347: thread_setstate(33554733, 0x0FFFFFFFFFFFC980, 0x0000000000000000) = 0
56361298: 33554733: sem_post(0x0FFFFFFFFFFFCEF0)                = 0
56361298: 83624347: _sem_wait(0x0FFFFFFFFFFFCEF0, 0x0000000000000000, 0) = 0
56361298: 83624347: _sem_wait(0x0FFFFFFFFFFFCEF0, 0x0000000000000000, 0) = 0
56361298: 83624347: _sem_wait(0x0FFFFFFFFFFFCEF0, 0x0000000000000000, 0) = 0
56361298: 83624347: _sem_wait(0x0FFFFFFFFFFFCEF0, 0x0000000000000000, 0) = 0
56361298: 83624347: sem_destroy(0x0FFFFFFFFFFFCEF0)     = 0
56361298: 83624347: thread_twakeup(58851605, 1073741824)        = 0
56361298: 58851605: thread_tsleep(0, 0x09001000A1B57A80, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_unlock_(0x000000011030BBB8)  = 0
56361298: 58851605: thread_waitlock_(0x000000011030BBB8, 257, 0x0000000000000000) = 1
56361298: 58851605: _getpid()                           = 56361298
56361298: 58851605: kopen("/etc/resolv.conf", O_RDONLY) = 19
56361298: 58851605: kioctl(19, 22528, 0x0000000000000000, 0x0000000000000000) Err#25 ENOTTY
56361298: 58851605: lseek(19, 0, 2)                     = 413
56361298: 58851605: lseek(19, 0, 1)                     = 413
56361298: 58851605: lseek(19, 0, 0)                     = 0
56361298: 58851605: kioctl(19, 22528, 0x0000000000000000, 0x0000000000000000) Err#25 ENOTTY
56361298: 58851605: kread(19, " #\n #   t h i s   f i l".., 4096) = 413
56361298: 58851605: kread(19, " #\n #   t h i s   f i l".., 4096) = 0
56361298: 58851605: statx("/etc/resolv.conf", 0x0000000110A5FFE0, 176, 0) = 0
56361298: 58851605: close(19)                           = 0
56361298: 58851605: _getpid()                           = 56361298
56361298: 58851605: statx("/dev/netcd", 0x0000000110A60FB8, 176, 010) = 0
56361298: 58851605: socket(1, 1, 0)                     = 19
56361298: 58851605: kfcntl(19, F_SETFD, 0x0000000000000001) = 0
56361298: 58851605: connext(19, 0x0000000110A60A28, 1025)       = 0
56361298: 58851605: setsockopt(19, 65535, 128, 0x0000000110A60A10, 8) = 0
56361298: 58851605: setsockopt(19, 65535, 4102, 0x0000000110A60A18, 16) = 0
56361298: 58851605: kopen("/etc/netsvc.conf", O_RDONLY) = 20
56361298: 58851605: kioctl(20, 22528, 0x0000000000000000, 0x0000000000000000) Err#25 ENOTTY
56361298: 58851605: kioctl(20, 22528, 0x0000000000000000, 0x0000000000000000) Err#25 ENOTTY
56361298: 58851605: kread(20, " #   @ ( # ) 4 3        ".., 4096) = 4096
56361298: 58851605: kread(20, " n g   a l i a s e s .  ".., 4096) = 449
56361298: 58851605: close(20)                           = 0
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D98, 0x0000000110A60D90) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D98, 0x0000000110A60D90) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: kopen("/etc/hesiod.conf", O_RDONLY) Err#2  ENOENT
56361298: 58851605: kopen("/etc/irs.conf", O_RDONLY)    Err#2  ENOENT
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getdomainname(0x09001000A1911F78, 1024) = 0
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getdomainname(0x09001000A1911F78, 1024) = 0
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getdomainname(0x09001000A1911F78, 1024) = 0
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getdomainname(0x09001000A1911F78, 1024) = 0
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A60D08, 0x0000000110A60D00) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110B2A580
56361298: 83624347: thread_unlock_(0x000000011032F058)  = 0
56361298: 58851605: thread_waitlock_(0x000000011032F058, 257, 0x0000000000000000) = 1
56361298: 83624347: pollset_ctl(2, 0x0FFFFFFFFFFFEFF0, 1)       = 0
56361298: 83624347: pollset_ctl(2, 0x0FFFFFFFFFFFEFF0, 1)       = 0
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 0
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A5FC38, 0x0000000110A5FC30) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: _esend(19, 0x0000000110A5FC50, 74, 256, 0x0000000000000000) = 74
56361298: 58851605: _erecv(19, 0x0000000110A5FC50, 8484, 256, 0x0000000000000000) = 92
56361298: 58851605: kopen("/etc/hosts", O_RDONLY)               = 20
56361298: 58851605: kioctl(20, 22528, 0x0000000000000000, 0x0000000000000000) Err#25 ENOTTY
56361298: 58851605: kfcntl(20, F_SETFD, 0x0000000000000001) = 0
56361298: 58851605: kioctl(20, 22528, 0x0000000000000000, 0x0000000000000000) Err#25 ENOTTY
56361298: 58851605: kread(20, " #   @ ( # ) 4 7\t 1 . 2".., 4096) = 4096
56361298: 58851605: kread(20, " 2 p 0 4 1\t v i o d c 2".., 4096) = 4096
56361298: 58851605: kread(20, " a m   4 . 8 . 2 0 2 2  ".., 4096) = 4096
56361298: 58851605: kread(20, "     k u g 0 1 1 1 2 _ z".., 4096) = 4096
56361298: 58851605: kread(20, " v a p 3 _ h a _ a\n #\n".., 4096) = 4096
56361298: 58851605: kread(20, " s t p 1 2 b 1         a".., 4096) = 4096
56361298: 58851605: kread(20, " t s m k t n\t k t n t s".., 4096) = 4096
56361298: 58851605: kread(20, "\n 1 7 2 . 1 7 . 9 . 2 1".., 4096) = 1934
56361298: 58851605: kread(20, "\n 1 7 2 . 1 7 . 9 . 2 1".., 4096) = 0
56361298: 58851605: getsockopt(19, 65535, 4105, 0x0000000110A5FC38, 0x0000000110A5FC30) = 0
56361298: 58851605: _thread_self()                      = 58851605
56361298: 58851605: _esend(19, 0x0000000110A5FC50, 74, 256, 0x0000000000000000) = 74
56361298: 58851605: _erecv(19, 0x0000000110A5FC50, 8484, 256, 0x0000000000000000) = 104
56361298: 58851605: close(20)                           = 0
56361298: 58851605: getkerninfo(1073742083, 0x0000000000000000, 0x0000000000000000, 0) = 770
56361298: 58851605: getkerninfo(1073742083, 0x0000000110B15670, 0x0000000110A623E0, 0) = 700
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 58851605: kwrite(17, "\0", 1)                 = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 83624347: socket(2, 1, 0)                     = 20
56361298: 83624347: kfcntl(20, F_GETFL, 0x0000000000000000) = 2
56361298: 83624347: kfcntl(20, F_SETFL, 0x0000000000000006) = 0
56361298: 83624347: kioctl(20, -2147195266, 0x0FFFFFFFFFFFAFB0, 0x0000000000000000) = 0
56361298: 83624347: kfcntl(20, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: getsockopt(20, 65535, 4104, 0x0FFFFFFFFFFFB044, 0x0FFFFFFFFFFFB040) = 0
56361298: 83624347: connext(20, 0x0FFFFFFFFFFFB668, 16) Err#55 EINPROGRESS
56361298: 83624347: pollset_ctl(2, 0x0FFFFFFFFFFFEFF0, 1)       = 0
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 0
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30) (sleeping...)
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 0
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 0
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30) (sleeping...)
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 0
56361298: 83624347: __libc_sbrk(0x0000000000010020)     = 0x0000000110B3A5A0
56361298: 83624347: thread_twakeup(63242633, 1073741824)        = 0
56361298: 63242633: thread_tsleep(0, 0x09001000A1B57A60, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_twakeup(73597307, 1073741824)        = 0
56361298: 73597307: thread_tsleep(0, 0x09001000A1B57A50, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_twakeup(113049905, 1073741824)       = 0
56361298: 83624347: thread_twakeup(77791685, 1073741824)        = 0
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 113049905: thread_tsleep(0, 0x09001000A1B57A40, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 77791685: thread_tsleep(0, 0x09001000A1B57A30, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_twakeup(63242633, 1073741824)        = 0
56361298: 83624347: thread_twakeup(73597307, 1073741824)        = 0
56361298: 77791685: thread_waitlock_(0x00000001103E1D98, 257, 0x0000000000000000) = 0
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 73597307: thread_tsleep(0, 0x09001000A1B57A50, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 63242633: thread_tsleep(0, 0x09001000A1B57A60, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 83624347: mmap(0x000040002B8C0000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008682000
56361298: 83624347: munmap(0x0A00000008682000, 253952)  = 0
56361298: 83624347: munmap(0x0A00000008700000, 4096)    = 0
56361298: 83624347: mprotect(0x0A000000086C0000, 262144, 3) = 0
56361298: 83624347: mmap(0x0000400001740000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008700000
56361298: 83624347: munmap(0x0A00000008740000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008700000, 262144, 3) = 0
56361298: 83624347: mmap(0x000040000D780000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008740000
56361298: 83624347: munmap(0x0A00000008780000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008740000, 262144, 3) = 0
56361298: 83624347: mmap(0x000040000BB40000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008780000
56361298: 83624347: munmap(0x0A000000087C0000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008780000, 262144, 3) = 0
56361298: 83624347: mprotect(0x0A00000000003000, 188416, 7) = 0
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 7) = 0
56361298: 83624347: thread_twakeup(113049905, 1073741824)       = 0
56361298: 113049905: thread_tsleep(0, 0x09001000A1B57A40, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_twakeup(77791685, 1073741824)        = 0
56361298: 77791685: thread_tsleep(0, 0x09001000A1B57A30, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_twakeup(63242633, 1073741824)        = 0
56361298: 63242633: thread_tsleep(0, 0x09001000A1B57A60, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: madvise(0x0A00000008632000, 57344, 4)       = 0
56361298: 83624347: mmap(0x0000400019440000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A000000087C0000
56361298: 83624347: munmap(0x0A00000008800000, 258048)  = 0
56361298: 83624347: mprotect(0x0A000000087C0000, 262144, 3) = 0
56361298: 83624347: thread_twakeup(73597307, 1073741824)        = 0
56361298: 83624347: thread_twakeup(77791685, 1073741824)        = 0
56361298: 77791685: thread_tsleep(0, 0x09001000A1B57A30, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 73597307: thread_tsleep(0, 0x09001000A1B57A50, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_twakeup(113049905, 1073741824)       = 0
56361298: 83624347: thread_twakeup(63242633, 1073741824)        = 0
56361298: 113049905: thread_tsleep(0, 0x09001000A1B57A40, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 63242633: thread_tsleep(0, 0x09001000A1B57A60, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_twakeup(73597307, 1073741824)        = 0
56361298: 83624347: thread_twakeup(63242633, 1073741824)        = 0
56361298: 73597307: thread_tsleep(0, 0x09001000A1B57A50, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 63242633: thread_tsleep(0, 0x09001000A1B57A60, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_twakeup(113049905, 1073741824)       = 0
56361298: 83624347: thread_unlock_(0x00000001103E1D98)  = 0
56361298: 73597307: thread_waitlock_(0x00000001103E1D98, 257, 0x0000000000000000) = 1
56361298: 113049905: thread_tsleep(0, 0x09001000A1B57A40, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 63242633: thread_waitlock_(0x00000001103E1D98, 257, 0x0000000000000000) = 1
56361298: 73597307: thread_unlock_(0x00000001103E1D98)  = 0
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 63242633: madvise(0x0A0000000851E000, 12288, 4)       = 0
56361298: 83624347: mprotect(0x0A00000000003000, 188416, 5) = 0
56361298: 73597307: madvise(0x0A000000085CF000, 200704, 4) = 0
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 5) = 0
56361298: 63242633: madvise(0x0A000000084D4000, 8192, 4)        = 0
56361298: 83624347: thread_twakeup(77791685, 1073741824)        = 0
56361298: 63242633: madvise(0x0A0000000844E000, 4096, 4)        = 0
56361298: 83624347: thread_unlock_(0x00000001103E1D48)  = 0
56361298: 73597307: thread_waitlock_(0x00000001103E1D48, 257, 0x0000000000000000) = 1
56361298: 63242633: mprotect(0x0A00000008100000, 262144, 0) = 0
56361298: 63242633: madvise(0x0A00000008100000, 262144, 4) = 0
56361298: 63242633: mprotect(0x0A000000080C0000, 262144, 0) = 0
56361298: 77791685: thread_tsleep(0, 0x09001000A1B57A30, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 63242633: madvise(0x0A000000080C0000, 262144, 4) = 0
56361298: 63242633: mprotect(0x0A00000008080000, 262144, 0) = 0
56361298: 83624347: _getpid()                           = 56361298
56361298: 63242633: madvise(0x0A00000008080000, 262144, 4) = 0
56361298: 63242633: mprotect(0x0A00000008040000, 262144, 0) = 0
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 7) = 0
56361298: 83624347: madvise(0x0A0000000004F000, 196608, 4) = 0
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 5) = 0
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 83624347: mprotect(0x0A00000000003000, 188416, 7) = 0
56361298: 83624347: mprotect(0x0A00000000003000, 188416, 5) = 0
56361298: 63242633: madvise(0x0A00000008040000, 262144, 4) = 0
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 0
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 0
56361298: 83624347: thread_twakeup(73597307, 1073741824)        = 0
56361298: 83624347: thread_twakeup(113049905, 1073741824)       = 0
56361298: 83624347: thread_twakeup(63242633, 1073741824)        = 0
56361298: 83624347: thread_twakeup(77791685, 1073741824)        = 0
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 63242633: thread_tsleep(0, 0x09001000A1B57A60, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 73597307: thread_tsleep(0, 0x09001000A1B57A50, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 77791685: thread_tsleep(0, 0x09001000A1B57A30, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 113049905: thread_tsleep(0, 0x09001000A1B57A40, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 83624347: mprotect(0x0A00000008040000, 262144, 3) = 0
56361298: 83624347: mprotect(0x0A00000008080000, 262144, 3) = 0
56361298: 83624347: mprotect(0x0A000000080C0000, 262144, 3) = 0
56361298: 83624347: mprotect(0x0A00000008100000, 262144, 3) = 0
56361298: 83624347: mprotect(0x0A00000000003000, 188416, 7) = 0
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 7) = 0
56361298: 83624347: thread_twakeup(63242633, 1073741824)        = 0
56361298: 63242633: thread_tsleep(0, 0x09001000A1B57A60, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: mmap(0x0000400020D00000, 520192, PROT_NONE, MAP_ANONYMOUS|MAP_VARIABLE|MAP_PRIVATE, -1, 0) = 0x0A00000008800000
56361298: 83624347: munmap(0x0A00000008840000, 258048)  = 0
56361298: 83624347: mprotect(0x0A00000008800000, 262144, 3) = 0
56361298: 83624347: thread_twakeup(73597307, 1073741824)        = 0
56361298: 73597307: thread_tsleep(0, 0x09001000A1B57A50, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_twakeup(113049905, 1073741824)       = 0
56361298: 83624347: thread_twakeup(77791685, 1073741824)        = 0
56361298: 83624347: thread_twakeup(63242633, 1073741824)        = 0
56361298: 63242633: thread_tsleep(0, 0x09001000A1B57A60, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 83624347: mprotect(0x0A00000000003000, 188416, 5) = 0
56361298: 63242633: madvise(0x0A000000087E3000, 118784, 4) = 0
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 5) = 0
56361298: 83624347: _getpid()                           = 56361298
56361298: 63242633: madvise(0x0A0000000851E000, 12288, 4)       = 0
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 63242633: madvise(0x0A000000084D4000, 8192, 4)        = 0
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 7) = 0
56361298: 63242633: madvise(0x0A0000000844E000, 4096, 4)        = 0
56361298: 83624347: madvise(0x0A0000000004F000, 196608, 4) = 0
56361298: 63242633: madvise(0x0A000000085CF000, 200704, 4) = 0
56361298: 83624347: mprotect(0x0A00000000043000, 245760, 5) = 0
56361298: 63242633: mprotect(0x0A00000008780000, 262144, 0) = 0
56361298: 83624347: kwrite(17, "\0", 1)                 = 1
56361298: 63242633: madvise(0x0A00000008780000, 262144, 4) = 0
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: kread(16, "\0\0\0\0\0\0\0\v\0\0\001".., 1024) = 1
56361298: 83624347: mprotect(0x0A00000000003000, 188416, 7) = 0
56361298: 83624347: mprotect(0x0A00000000003000, 188416, 5) = 0
56361298: 63242633: mprotect(0x0A00000008740000, 262144, 0) = 0
56361298: 63242633: madvise(0x0A00000008740000, 262144, 4) = 0
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 0
56361298: 63242633: mprotect(0x0A00000008700000, 262144, 0) = 0
56361298: 63242633: madvise(0x0A00000008700000, 262144, 4) = 0
56361298: 63242633: mprotect(0x0A000000086C0000, 262144, 0) = 0
56361298: 63242633: madvise(0x0A000000086C0000, 262144, 4) = 0
56361298: 113049905: thread_tsleep(0, 0x09001000A1B57A40, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 77791685: thread_tsleep(0, 0x09001000A1B57A30, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30) (sleeping...)
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 1
56361298: 83624347: getsockopt(20, 65535, 4103, 0x0FFFFFFFFFFFCF40, 0x0FFFFFFFFFFFCF30) = 0
56361298: 83624347: kioctl(2, 22528, 0x0000000000000000, 0x0000000000000000) = 0
56361298: 83624347: kioctl(2, 22528, 0x0000000000000000, 0x0000000000000000) = 0
56361298: 83624347: kfcntl(2, F_GETFL, 0x000000011032BE98) = 67110914
56361298: 83624347: kioctl(2, 536900679, 0x0FFFFFFFFFFFB7B0, 0x0000000000000000) Err#22 EINVAL
56361298: 83624347: kioctl(2, 22529, 0x0FFFFFFFFFFFB3E8, 0x0000000000000000) = 0
56361298: 83624347: statx("/dev/pts/49", 0x0FFFFFFFFFFFB508, 176, 0) = 0
56361298: 83624347: kopen("/dev/pts/49", 040004002)     = 21
56361298: 83624347: kfcntl(21, 14, 0x0000000000000002)  = 2
56361298: 83624347: kfcntl(2, F_SETFD, 0x0000000000000001) = 0
56361298: 83624347: kfcntl(21, F_GETFL, 0x0000000000000000) = 67110914
56361298: 83624347: kfcntl(21, F_SETFL, 0x0000000004000806) = 0
56361298: 83624347: kioctl(21, -2147195266, 0x0FFFFFFFFFFFB8A8, 0x0000000000000000) Err#123 ENOSTR
56361298: 83624347: kfcntl(21, F_GETFL, 0x0000000000000000) = 67110918
56361298: 83624347: kfcntl(21, F_SETFL, 0x0000000004000802) = 0
56361298: 83624347: kioctl(21, -2147195266, 0x0FFFFFFFFFFFBAB8, 0x0000000000000000) Err#123 ENOSTR
56361298: 83624347: kioctl(21, 1074295912, 0x0FFFFFFFFFFFBA80, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x0FFFFFFFFFFFB860, 0x0FFFFFFFFFFFB940) = 0
56361298: 83624347: kread(4, " *", 1)                   = 1
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFB860) = 0
56361298: 83624347: _sigaction(28, 0x0FFFFFFFFFFFB910, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFB460, 0x0000000000000000) = 0
56361298: 83624347: kwrite(5, " *", 1)                  = 1
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFB4A0, 0x0000000000000000) = 0
56361298: 83624347: pollset_ctl(2, 0x0FFFFFFFFFFFB9E0, 1)       = 0
56361298: 83624347: close(20)                           = 0
connect ETIMEDOUT 104.16.27.34:443
56361298: 83624347: kwrite(21, " c o n n e c t   E T I M".., 35) = 35
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCF30)        = 0
56361298: 83624347: pollset_ctl(2, 0x0FFFFFFFFFFFEBB0, 1)       Err#22 EINVAL
56361298: 83624347: close(21)                           = 0
56361298: 83624347: sigprocmask(2, 0x0FFFFFFFFFFFEB40, 0x0FFFFFFFFFFFEC50) = 0
56361298: 83624347: kread(4, " *", 1)                   = 1
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFEB40) = 0
56361298: 83624347: _sigaction(28, 0x0FFFFFFFFFFFEC20, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFE740, 0x0000000000000000) = 0
56361298: 83624347: kwrite(5, " *", 1)                  = 1
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFE780, 0x0000000000000000) = 0
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCBF0)        = 0
56361298: 83624347: _pollset_poll(2, 0x0FFFFFFFFFFFCBF0)        = 0
56361298: 83624347: munmap(0x0A000000086C0000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008700000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008740000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008780000, 262144)  = 0
56361298: 83624347: sem_destroy(0x0000000110838D70)     = 0
56361298: 83624347: sem_destroy(0x00000001108385A0)     = 0
56361298: 83624347: sem_destroy(0x0000000110838E30)     = 0
56361298: 83624347: munmap(0x0A00000008140000, 262144)  = 0
56361298: 83624347: munmap(0x0A000000081C0000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008200000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008240000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008280000, 262144)  = 0
56361298: 83624347: munmap(0x0A000000082C0000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008300000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008340000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008380000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008440000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008480000, 262144)  = 0
56361298: 83624347: munmap(0x0A000000084C0000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008500000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008540000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008600000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008580000, 262144)  = 0
56361298: 83624347: munmap(0x0A000000087C0000, 262144)  = 0
56361298: 83624347: munmap(0x0A000000083C0000, 118784)  = 0
56361298: 83624347: munmap(0x0A00000008800000, 262144)  = 0
56361298: 83624347: mprotect(0x0A00000000000000, 262144, 0) = 0
56361298: 83624347: madvise(0x0A00000000000000, 262144, 4) = 0
56361298: 83624347: mprotect(0x0A00000000040000, 262144, 0) = 0
56361298: 83624347: madvise(0x0A00000000040000, 262144, 4) = 0
56361298: 83624347: munmap(0x0A00000008180000, 8192)    = 0
56361298: 83624347: munmap(0x0A00000008400000, 262144)  = 0
56361298: 83624347: munmap(0x0A000000085C0000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008640000, 270336)  = 0
56361298: 83624347: mprotect(0x0A00000008100000, 262144, 0) = 0
56361298: 83624347: madvise(0x0A00000008100000, 262144, 4) = 0
56361298: 83624347: mprotect(0x0A000000080C0000, 262144, 0) = 0
56361298: 83624347: madvise(0x0A000000080C0000, 262144, 4) = 0
56361298: 83624347: mprotect(0x0A00000008080000, 262144, 0) = 0
56361298: 83624347: madvise(0x0A00000008080000, 262144, 4) = 0
56361298: 83624347: mprotect(0x0A00000008040000, 262144, 0) = 0
56361298: 83624347: madvise(0x0A00000008040000, 262144, 4) = 0
56361298: 83624347: munmap(0x0A00000008040000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008080000, 262144)  = 0
56361298: 83624347: munmap(0x0A000000080C0000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008100000, 262144)  = 0
56361298: 83624347: munmap(0x0A00000008000000, 253952)  = 0
56361298: 83624347: munmap(0x0A00000000000000, 134217728)       = 0
56361298: 83624347: fstatx(0, 0x0FFFFFFFFFFFF300, 176, 0)       = 0
56361298: 83624347: kfcntl(0, F_GETFL, 0x000000011032BE98) = 67110914
56361298: 83624347: sigprocmask(0, 0x0FFFFFFFFFFFF3B0, 0x0000000000000000) = 0
56361298: 83624347: kioctl(0, 22528, 0x0000000000000000, 0x0000000000000000) = 0
56361298: 83624347: kioctl(0, 21506, 0x0000000110327FB0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(1, 0x0FFFFFFFFFFFF3B0, 0x0000000000000000) = 0
56361298: 83624347: fstatx(1, 0x0FFFFFFFFFFFF300, 176, 0)       = 0
56361298: 83624347: kfcntl(1, F_GETFL, 0x000000011032BE98) = 67110914
56361298: 83624347: sigprocmask(0, 0x0FFFFFFFFFFFF3B0, 0x0000000000000000) = 0
56361298: 83624347: kioctl(1, 22528, 0x0000000000000000, 0x0000000000000000) = 0
56361298: 83624347: kioctl(1, 21506, 0x0000000110328088, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(1, 0x0FFFFFFFFFFFF3B0, 0x0000000000000000) = 0
56361298: 83624347: fstatx(2, 0x0FFFFFFFFFFFF300, 176, 0)       = 0
56361298: 83624347: kfcntl(2, F_GETFL, 0x000000011032BE98) = 67110914
56361298: 83624347: sigprocmask(0, 0x0FFFFFFFFFFFF3B0, 0x0000000000000000) = 0
56361298: 83624347: kioctl(2, 22528, 0x0000000000000000, 0x0000000000000000) = 0
56361298: 83624347: kioctl(2, 21506, 0x0000000110328160, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(1, 0x0FFFFFFFFFFFF3B0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(1, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(2, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(3, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(4, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(5, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(6, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(7, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(8, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(10, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(11, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(12, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(13, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(14, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(15, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(16, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(18, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(19, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(20, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(21, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(22, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(23, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(24, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(25, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(26, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(27, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(28, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(29, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(30, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(2, 0x09001000A1BDBCF8, 0x0FFFFFFFFFFFF3D0) = 0
56361298: 83624347: _sigaction(31, 0x0FFFFFFFFFFFF480, 0x0000000000000000) = 0
56361298: 83624347: thread_setmystate(0x0FFFFFFFFFFFEFD0, 0x0000000000000000) = 0
56361298: 83624347: thread_twakeup(73597307, 1073741824)        = 0
56361298: 73597307: thread_tsleep(0, 0x09001000A1B57A50, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_twakeup(63242633, 1073741824)        = 0
56361298: 63242633: thread_tsleep(0, 0x09001000A1B57A60, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_twakeup(113049905, 1073741824)       = 0
56361298: 83624347: thread_twakeup(77791685, 1073741824)        = 0
56361298: 83624347: thread_unlock_(0x00000001103E1D98)  = 0
56361298: 73597307: thread_waitlock_(0x00000001103E1D98, 257, 0x0000000000000000) = 1
56361298: 83624347: kwrite(13, "\0", 1)                 = 1
56361298: 90571153: _pollset_poll(1, 0x0000000110415360)        = 1
56361298: 73597307: kthread_ctl(2, 0x0000000000000000)  = 0
56361298: 63242633: kthread_ctl(2, 0x0000000000000000)  = 0
56361298: 73597307: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000003728, 0x0000000000000000, 0xF1000B0159AFD000, 0x0000000000000000) = 0x0000000000000000
56361298: 63242633: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000003728, 0x0000000000000000, 0xF1000B015229A800, 0x0000000000000000) = 0x0000000000000000
56361298: 63242633: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000013C50189, 0x0000000013C50189, 0x0000000000000000) = 0x0000000000000000
56361298: 73597307: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x000000001463017B, 0x000000001463017B, 0xFFFFFFFFFFFFFFFF) = 0x0000000000000000
56361298: 63242633: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000003728, 0x0000000000000000, 0xF1000B015229A800, 0x0000000000000000) = 0x0000000000000000
56361298: 73597307: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF) = 0x0000000000000000
56361298: 63242633: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000013C50189, 0x0000000013C50189, 0x0000000000000000) = 0x0000000000000000
56361298: 90571153: kread(12, "\0 ▒ ▒ ▒ ▒07 ▒0604 ▒0101".., 1024) = 1
56361298: 77791685: thread_tsleep(0, 0x09001000A1B57A30, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 90571153: close(10)                           = 0
56361298: 73597307: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x000000001463017B, 0x000000001463017B, 0xFFFFFFFFFFFFFFFF) = 0x0000000000000000
56361298: 77791685: kthread_ctl(2, 0x0000000000000000)  = 0
56361298: 90571153: close(11)                           = 0
56361298: 113049905: thread_tsleep(0, 0x09001000A1B57A40, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 90571153: pollset_destroy(1)                  = 0
56361298: 113049905: kthread_ctl(2, 0x0000000000000000) = 0
56361298: 113049905: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000003728, 0x0000000000000000, 0xF1000B01560BF000, 0x0000000000000000) = 0x0000000000000000
56361298: 90571153: close(13)                           = 0
56361298: 113049905: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000016BD0131, 0x0000000016BD0131, 0x0000000000000000) = 0x0000000000000000
56361298: 113049905: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000003728, 0x0000000000000000, 0xF1000B01560BF000, 0x0000000000000000) = 0x0000000000000000
56361298: 90571153: close(12)                           = 0
56361298: 113049905: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000016BD0131, 0x0000000016BD0131, 0x0000000000000000) = 0x0000000000000000
56361298: 77791685: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF) = 0x0000000000000000
56361298: 90571153: kthread_ctl(2, 0x0000000000000000)  = 0
56361298: 90571153: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000003728, 0x0000000000000000, 0xF1000B01560BF800, 0x0000000000000000) = 0x0000000000000000
56361298: 77791685: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000014A301C5, 0x0000000014A301C5, 0xFFFFFFFFFFFFFFFF) = 0x0000000000000000
56361298: 90571153: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000015660191, 0x0000000015660191, 0x0000000000000000) = 0x0000000000000000
56361298: 90571153: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000003728, 0x0000000000000000, 0xF1000B01560BF800, 0x0000000000000000) = 0x0000000000000000
56361298: 77791685: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF) = 0x0000000000000000
56361298: 90571153: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000015660191, 0x0000000015660191, 0x0000000000000000) = 0x0000000000000000
56361298: 77791685: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000014A301C5, 0x0000000014A301C5, 0xFFFFFFFFFFFFFFFF) = 0x0000000000000000
56361298: 90571153: thread_twakeup(83624347, 1073741824)        = 0
56361298: 83624347: thread_tsleep(0, 0x09001000A1B57A10, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_waitlock_(0x09001000A1BDB6C8, 257, 0x0000000000000000) = 0
56361298: 77791685: thread_waitlock_(0x09001000A1BDB6C8, 257, 0x0000000000000000) = 1
56361298: 83624347: mprotect(0x00000001103E5000, 4096, 3)       = 0
56361298: 77791685: thread_waitlock_(0x09001000A1BDB6C8, 257, 0x0000000000000000) = 0
56361298: 77791685: thread_twakeup(83624347, 1073741824)        = 0
56361298: 83624347: thread_tsleep(0, 0x09001000A1B57A10, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_waitlock_(0x09001000A1BDB6C8, 257, 0x0000000000000000) = 0
56361298: 83624347: mprotect(0x000000011041B000, 4096, 3)       = 0
56361298: 83624347: mprotect(0x0000000110451000, 4096, 3)       = 0
56361298: 83624347: mprotect(0x0000000110487000, 4096, 3)       = 0
56361298: 83624347: mprotect(0x00000001104BC000, 4096, 3)       = 0
56361298: 83624347: pollset_ctl(0, 0x0FFFFFFFFFFFF1D0, 1)       = 0
56361298: 83624347: pollset_ctl(0, 0x0FFFFFFFFFFFF1D0, 1)       = 0
56361298: 83624347: _pollset_poll(0, 0x0FFFFFFFFFFFD110)        = 0
56361298: 83624347: close(6)                            = 0
56361298: 83624347: close(7)                            = 0
56361298: 83624347: pollset_destroy(0)                  = 0
56361298: 83624347: close(9)                            = 0
56361298: 83624347: close(8)                            = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 17) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 17) = 0
56361298: 83624347: fstatx(3, 0x0FFFFFFFFFFFEDF0, 176, 0)       = 0
56361298: 83624347: close(3)                            = 0
56361298: 83624347: fstatx(0, 0x0FFFFFFFFFFFEEE0, 176, 0)       = 0
56361298: 83624347: kfcntl(0, F_GETFL, 0x000000011032BE98) = 67110914
56361298: 83624347: sigprocmask(0, 0x0FFFFFFFFFFFEF90, 0x0000000000000000) = 0
56361298: 83624347: kioctl(0, 22528, 0x0000000000000000, 0x0000000000000000) = 0
56361298: 83624347: kioctl(0, 21506, 0x0000000110327FB0, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(1, 0x0FFFFFFFFFFFEF90, 0x0000000000000000) = 0
56361298: 83624347: fstatx(1, 0x0FFFFFFFFFFFEEE0, 176, 0)       = 0
56361298: 83624347: kfcntl(1, F_GETFL, 0x000000011032BE98) = 67110914
56361298: 83624347: sigprocmask(0, 0x0FFFFFFFFFFFEF90, 0x0000000000000000) = 0
56361298: 83624347: kioctl(1, 22528, 0x0000000000000000, 0x0000000000000000) = 0
56361298: 83624347: kioctl(1, 21506, 0x0000000110328088, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(1, 0x0FFFFFFFFFFFEF90, 0x0000000000000000) = 0
56361298: 83624347: fstatx(2, 0x0FFFFFFFFFFFEEE0, 176, 0)       = 0
56361298: 83624347: kfcntl(2, F_GETFL, 0x000000011032BE98) = 67110914
56361298: 83624347: sigprocmask(0, 0x0FFFFFFFFFFFEF90, 0x0000000000000000) = 0
56361298: 83624347: kioctl(2, 22528, 0x0000000000000000, 0x0000000000000000) = 0
56361298: 83624347: kioctl(2, 21506, 0x0000000110328160, 0x0000000000000000) = 0
56361298: 83624347: sigprocmask(1, 0x0FFFFFFFFFFFEF90, 0x0000000000000000) = 0
56361298: 83624347: sem_destroy(0x00000001100D7008)     = 0
56361298: 83624347: kfcntl(1, F_GETFL, 0x000000011032BD58) = 67110914
56361298: 83624347: kfcntl(2, F_GETFL, 0x000000011032BD58) = 67110914
56361298: 83624347: kfcntl(2, F_GETFL, 0x000000011032BD58) = 67110914
56361298: 83624347: kfcntl(1, F_GETFL, 0x000000011032BD58) = 67110914
56361298: 83624347: kfcntl(2, F_GETFL, 0x000000011032BD58) = 67110914
56361298: 83624347: kfcntl(2, F_GETFL, 0x000000011032BD58) = 67110914
56361298: 83624347: close(4)                            = 0
56361298: 83624347: close(5)                            = 0
56361298: 83624347: thread_twakeup(80413145, 1073741824)        = 0
56361298: 80413145: thread_tsleep(0, 0x09001000A1B57A90, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 80413145: thread_twakeup(94437833, 1073741824)        = 0
56361298: 94437833: thread_tsleep(0, 0x09001000A1B57AA0, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 94437833: thread_waitlock_(0x000000011030BBB8, 257, 0x0000000000000000) = 1
56361298: 80413145: thread_unlock_(0x000000011030BBB8)  = 0
56361298: 94437833: thread_twakeup(33554733, 1073741824)        = 0
56361298: 80413145: kthread_ctl(2, 0x0000000000000000)  = 0
56361298: 33554733: thread_tsleep(0, 0x09001000A1B57AB0, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 94437833: kthread_ctl(2, 0x0000000000000000)  = 0
56361298: 33554733: thread_twakeup(58851605, 1073741824)        = 0
56361298: 80413145: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF) = 0x0000000000000000
56361298: 33554733: kthread_ctl(2, 0x0000000000000000)  = 0
56361298: 80413145: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000014CB01D9, 0x0000000014CB01D9, 0x0000000000000000) = 0x0000000000000000
56361298: 33554733: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000003728, 0x0000000000000000, 0xF1000B0158F89000, 0x0000000000000000) = 0x0000000000000000
56361298: 80413145: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000003728, 0x0000000000000000, 0xF1000B0158F80000, 0x0000000000000000) = 0x0000000000000000
56361298: 80413145: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000014CB01D9, 0x0000000014CB01D9, 0x0000000000000000) = 0x0000000000000000
56361298: 33554733: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x000000001200012D, 0x000000001200012D, 0xFFFFFFFFFFFFFFFF) = 0x0000000000000000
56361298: 33554733: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000003728, 0x0000000000000000, 0xF1000B0158F89000, 0x0000000000000000) = 0x0000000000000000
56361298: 33554733: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x000000001200012D, 0x000000001200012D, 0x0000000000000000) = 0x0000000000000000
56361298: 58851605: thread_tsleep(0, 0x09001000A1B57A80, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 58851605: kthread_ctl(2, 0x0000000000000000)  = 0
56361298: 58851605: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000003728, 0x0000000000000000, 0xF1000B0152297800, 0x0000000000000000) = 0x0000000000000000
56361298: 58851605: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000013820115, 0x0000000013820115, 0x0000000000000000) = 0x0000000000000000
56361298: 58851605: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000003728, 0x0000000000000000, 0xF1000B0152297800, 0x0000000000000000) = 0x0000000000000000
56361298: 58851605: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000013820115, 0x0000000013820115, 0x0000000000000000) = 0x0000000000000000
56361298: 58851605: shutdown(19, 2)                     = 0
56361298: 58851605: close(19)                           = 0
56361298: 58851605: thread_twakeup(83624347, 1073741824)        = 0
56361298: 83624347: thread_tsleep(0, 0x09001000A1B57A10, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_waitlock_(0x0000000110A629E0, 257, 0x0000000000000000) = 1
56361298: 58851605: thread_unlock_(0x0000000110A629E0)  = 0
56361298: 83624347: thread_waitlock_(0x09001000A1BDB6C8, 257, 0x0000000000000000) = 1
56361298: 83624347: mprotect(0x0000000110A31000, 4096, 3)       = 0
56361298: 83624347: mprotect(0x0000000110A66000, 4096, 3)       = 0
56361298: 94437833: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF, 0xFFFFFFFFFFFFFFFF) = 0x0000000000000000
56361298: 94437833: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000015A101C9, 0x0000000015A101C9, 0x0000000000000000) = 0x0000000000000000
56361298: 94437833: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000003728, 0x0000000000000000, 0xF1000B0158F83000, 0x0000000000000000) = 0x0000000000000000
56361298: 94437833: thread_setmystate_fast(0x000000004000000C, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000000000000, 0x0000000015A101C9, 0x0000000015A101C9, 0x0000000000000000) = 0x0000000000000000
56361298: 94437833: thread_twakeup(83624347, 1073741824)        = 0
56361298: 83624347: thread_tsleep(0, 0x09001000A1B57A10, 0x0000000000000000, 0x0000000000000000) = 1073741824
56361298: 83624347: thread_waitlock_(0x09001000A1BDB6C8, 257, 0x0000000000000000) = 0
56361298: 83624347: mprotect(0x0000000110A9C000, 4096, 3)       = 0
56361298: 83624347: mprotect(0x0000000110AD1000, 4096, 3)       = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 18) = 0
56361298: 83624347: checkpnt_block(0x0000000000000000, 18) = 0
56361298: 83624347: kfcntl(1, F_GETFL, 0x000000011032BD58) = 67110914
56361298: 83624347: kfcntl(2, F_GETFL, 0x000000011032BD58) = 67110914
56361298: 83624347: _exit(0)

@gireeshpunathil
Copy link
Member

thanks @flynn1973 for the run, unfortunately this took a totally different route:

  • there is no trace of pollset_ctl failure followed by pollset_query sequence
  • the connection itself is timing out!

not sure what is going on. I am going to upload the back-port of double TLS bug fix to see if that makes things better. stay tuned.

@gireeshpunathil
Copy link
Member

https://gist.github.com/gireeshpunathil/d3c4b15f5a9dcea39979295f520484e3/revisions#diff-1f5f048181df93b4babc9b3f367d9e2f89f0d686516acf335978fb6dfe18f91d

@flynn1973 - could you please test your original test case (rpm install) with this node binary and let me know how it goes?

@flynn1973
Copy link
Author

here you go...

root@nimvie: /opt/node-v18.13.0-aix-ppc64/bin # ls -ltr
total 575408
-rwxr-xr-x    1 git      203       147890618 Jan 06 2023  node.orig
lrwxrwxrwx    1 git      203              45 Jan 30 2023  corepack -> ../lib/node_modules/corepack/dist/corepack.js
lrwxrwxrwx    1 git      203              38 Jan 30 2023  npm -> ../lib/node_modules/npm/bin/npm-cli.js
lrwxrwxrwx    1 git      203              38 Jan 30 2023  npx -> ../lib/node_modules/npm/bin/npx-cli.js
-rwxr-xr-x    1 root     system    146707881 Aug 02 09:21 node_tls_bug_fixed
lrwxrwxrwx    1 root     system           18 Aug 24 10:39 node -> node_tls_bug_fixed
root@nimvie: /opt/node-v18.13.0-aix-ppc64/bin # ls
corepack            node                node.orig           node_tls_bug_fixed  npm                 npx
root@nimvie: /opt/node-v18.13.0-aix-ppc64/bin # ./npm install -g n
LIBUV: before pollset_ctl 10 1
LIBUV: before pollset_ctl 12 1
LIBUV: before pollset_ctl 14 1
LIBUV: before pollset_ctl 16 1
LIBUV: In new_socket  23 1 0: timing arborist:ctor Completed in 1ms
LIBUV: In tcp_connect 23 -1 55 1 55
LIBUV: before pollset_ctl 23 2
LIBUV: before pollset_ctl 23 1
LIBUV: after pollset_ctl2 23 1 0 22
LIBUV: before pollset_query  23
LIBUV: after pollset_query3 23 2 22 1
LIBUV: In new_socket  25 1 0Tree:lib: sill idealTree buildDeps
LIBUV: In tcp_connect 25 -1 55 1 55
LIBUV: before pollset_ctl 25 2
LIBUV: before pollset_ctl 25 1
LIBUV: after pollset_ctl2 25 1 0 22
LIBUV: before pollset_query  25
LIBUV: after pollset_query3 25 2 22 1
LIBUV: In new_socket  25 1 0:n: sill audit bulk request { n: [ '9.1.0' ] }
LIBUV: In tcp_connect 25 -1 55 1 55
LIBUV: before pollset_ctl 25 2
LIBUV: before pollset_ctl 25 1
LIBUV: after pollset_ctl2 25 1 0 22
LIBUV: before pollset_query  25
LIBUV: after pollset_query3 25 2 22 1
LIBUV: In new_socket  27 1 0
LIBUV: In tcp_connect 27 -1 55 1 55
LIBUV: before pollset_ctl 27 2
LIBUV: before pollset_ctl 27 1
LIBUV: after pollset_ctl2 27 1 0 22
LIBUV: before pollset_query  27
LIBUV: after pollset_query3 27 2 22 1

added 1 package, and audited 2 packages in 929ms

found 0 vulnerabilities
LIBUV: before pollset_ctl 6 1
LIBUV: before pollset_ctl 8 1

@gireeshpunathil
Copy link
Member

@flynn1973 - looks like you picked up a wrong binary? could you do cksum node_tls_bug_fixed ? either way, there are 5 revisions under https://gist.github.com/gireeshpunathil/d3c4b15f5a9dcea39979295f520484e3/revisions , and the latest is the 5th revision with the tis fix, and that has this cksum:

$ cksum node
3039139667 146707847 node

@flynn1973
Copy link
Author

hmm...i see..but there is nothing else for download...

@flynn1973
Copy link
Author

downloaded the whole container...seems this includes the correct file..

root@nimvie: /tmp # unzip main.zip
Archive:  main.zip
60bf444f6d31490b53c66602c0f459da5d15a671
   creating: d3c4b15f5a9dcea39979295f520484e3-main/
  inflating: d3c4b15f5a9dcea39979295f520484e3-main/foo.js
  inflating: d3c4b15f5a9dcea39979295f520484e3-main/node.tar.gz
  inflating: d3c4b15f5a9dcea39979295f520484e3-main/sim1
  inflating: d3c4b15f5a9dcea39979295f520484e3-main/sim2
root@nimvie: /tmp # /opt/freeware/bin/tar xfvz d3c4b15f5a9dcea39979295f520484e3-main/node.tar.gz
node
root@nimvie: /tmp # cksum node
3039139667 146707847 node

@flynn1973
Copy link
Author

seems that its working now...

root@nimvie: /opt/node-v18.13.0-aix-ppc64/bin # ./npm install -g n

changed 1 package, and audited 2 packages in 888ms

found 0 vulnerabilities
npm notice
npm notice New major version of npm available! 8.19.3 -> 9.8.1
npm notice Changelog: https://github.com/npm/cli/releases/tag/v9.8.1
npm notice Run npm install -g npm@9.8.1 to update!
npm notice
root@nimvie: /opt/node-v18.13.0-aix-ppc64/bin # ./npm install -g  npm@9.8.1

removed 17 packages, changed 102 packages, and audited 250 packages in 34s

28 packages are looking for funding
  run `npm fund` for details

found 0 vulnerabilities

@gireeshpunathil
Copy link
Member

wow - thanks for sharing the news, @flynn1973 ! the only missing puzzle is how the double tis bug manifested as an assertion failure in libuv. @mhdawson - do you have additional insights on the bug?

either way, this is back ported to 18.x line now, hopefully this will make it to the next v18.x release.

@mhdawson
Copy link
Member

@gireeshpunathil I don't know how it would have ended up with an assertion in libuv, my only guess might be corrupting some memory that then later resulted in a crash versus crashing earlier as we saw on other platforms.

@gireeshpunathil
Copy link
Member

thanks @mhdawson . I agree, that would be a good explanation of what we saw in numerous number of debug iterations - an fd that was all good at the time when it was created, connected (with the remote rpm server), polled for connection establishment, suddenly becomes invalid when polled for reading!

With that, we conclude this issue. Thanks everyone involved in the problem determination, and special thanks to @flynn1973 for being immensely patient and helpful throughout!

Closing as resolved.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
aix Aix specific issues npm
Projects
None yet
Development

No branches or pull requests

6 participants