occlum icon indicating copy to clipboard operation
occlum copied to clipboard
verified publisher icon occlum

[BUG]/opt/occlum/build/bin/occlum: line 354:  1095 Killed 

Open ialtery opened this issue 2 years ago • 5 comments

Describe the bug

/opt/occlum/build/bin/occlum: line 354:  1095 Killed  RUST_BACKTRACE=1 "$instance_dir/build/bin/occlum-run" "$@"

A server runs multiple occlum instances through docker, and the above error will be reported after running for a period of time.

Environment

  • HW: SGX2
  • OS: Ubuntu20.04
  • Occlum version: 0.29.5

ialtery avatar Aug 29 '23 18:08 ialtery

@ialtery Please run your application with env OCCLUM_LOG_LEVEL=debug occlum run xxx and send us the log.

qzheng527 avatar Aug 30 '23 05:08 qzheng527

@ialtery Please run your application with env OCCLUM_LOG_LEVEL=debug occlum run xxx and send us the log.

runtime: unexpected return pc for runtime.gopark called from 0x0 stack: frame={sp:0x7fa0c9243d40, fp:0x7fa0c9243d60} stack=[0x7fa0c9240000,0x7fa0c9244000) 0x00007fa0c9243c40: 0x0000000000000000 0x0000000000000000 0x00007fa0c9243c50: 0x0000000000000000 0x0000000000000000 0x00007fa0c9243c60: 0x0000000000000000 0x0000000000000000 0x00007fa0c9243c70: 0x0000000000000000 0x0000000000000000 0x00007fa0c9243c80: 0x0000000000000000 0x0000000000000000 0x00007fa0c9243c90: 0x0000000000000000 0x00007fa0b41174e0 0x00007fa0c9243ca0: 0x000000000000000d 0x00007fa078fc80c8 0x00007fa0c9243cb0: 0x00007fa0c53ed040 0x00007fa0c9243e08 0x00007fa0c9243cc0: 0x00007fa077b36612 <net.(*Resolver).lookupIP+0x0000000000000172> 0x326a3573672d6d72 0x00007fa0c9243cd0: 0x6539753936727631 0x73796d2e38316c37 0x00007fa0c9243ce0: 0x61676e69732e6c71 0x7364722e65726f70 0x00007fa0c9243cf0: 0x636e7579696c612e 0x00002e6d6f632e73 0x00007fa0c9243d00: 0x0000000000000000 0x0000000000000000 0x00007fa0c9243d10: 0x0000000000000000 0x0000000000000000 0x00007fa0c9243d20: 0x0000000000000000 0x0000000000000000 0x00007fa0c9243d30: 0x0000110000000000 0x0000000000000000 0x00007fa0c9243d40: <0x0000000000000001 0x00007fa0c4805b60 0x00007fa0c9243d50: 0x0000000000000000 !0x0000000000000000 0x00007fa0c9243d60: >0x00007fa0bee1df00 0x0000000000000002 0x00007fa0c9243d70: 0x0000000000000001 0x00007fa0bee1ded4 0x00007fa0c9243d80: 0x0000000000000002 0x00007fa077982ea5 <internal/poll.(*FD).writeUnlock+0x0000000000000025> 0x00007fa0c9243d90: 0x0001000100000009 0x0000000000000000 0x00007fa0c9243da0: 0x0000000000000002 0x00007fa077986706 <internal/poll.(*FD).Write.func1+0x0000000000000026> 0x00007fa0c9243db0: 0x0000000000000000 0x00007fa0bee1de60 0x00007fa0c9243dc0: 0x00007fa077986644 <internal/poll.(*FD).Write+0x0000000000000444> 0x00007fa0c934c880 0x00007fa0c9243dd0: 0x00007fa0bee34000 0x0000000000000000 0x00007fa0c9243de0: 0x0000000000000001 0x0000000000000000 0x00007fa0c9243df0: 0x0000000000000000 0x0000000000000000 0x00007fa0c9243e00: 0x0000000000010000 0x00007fa0c0ef43a0 0x00007fa0c9243e10: 0x0000000000000000 0x0000000000000000 0x00007fa0c9243e20: 0x0000000000000000 0x00007fa0c4805b60 0x00007fa0c9243e30: 0x00007fa0bfea8840 0x00007fa0c4805b60 0x00007fa0c9243e40: 0x0000000000000000 0x00007fa0c0bedc28 0x00007fa0c9243e50: 0x00007fa0c0bedce8 0x00007fa0bee1de48 fatal error: unknown caller pc

runtime stack: runtime.throw({0x7fa078781d5c?, 0x7fa0798a80a0?}) /opt/occlum/toolchains/golang/src/runtime/panic.go:992 +0x71 runtime.gentraceback(0x2c0?, 0x7fa08cfbd508?, 0x0?, 0x0?, 0x0, 0x0, 0x7fffffff, 0x7fa078fc0110, 0x48000eca3b?, 0x0) /opt/occlum/toolchains/golang/src/runtime/traceback.go:254 +0x1a36 runtime.copystack(0x7fa0c0bedba0, 0x7fa08cfbd800?) /opt/occlum/toolchains/golang/src/runtime/stack.go:930 +0x2f5 runtime.shrinkstack(0x7fa0c0bedba0) /opt/occlum/toolchains/golang/src/runtime/stack.go:1212 +0x126 runtime.scanstack(0x7fa0c0bedba0, 0x7fa0b4039238) /opt/occlum/toolchains/golang/src/runtime/mgcmark.go:754 +0xc7 runtime.markroot.func1() /opt/occlum/toolchains/golang/src/runtime/mgcmark.go:241 +0xc5 runtime.markroot(0x7fa0b4039238, 0x104f, 0x1) /opt/occlum/toolchains/golang/src/runtime/mgcmark.go:214 +0x1a5 runtime.gcDrain(0x7fa0b4039238, 0x2) /opt/occlum/toolchains/golang/src/runtime/mgcmark.go:1047 +0x39f runtime.gcBgMarkWorker.func2() /opt/occlum/toolchains/golang/src/runtime/mgc.go:1291 +0x154 runtime.systemstack() /opt/occlum/toolchains/golang/src/runtime/asm_amd64.s:469 +0x46

goroutine 50 [GC worker (idle)]: runtime.systemstack_switch() /opt/occlum/toolchains/golang/src/runtime/asm_amd64.s:436 fp=0x7fa0b4122758 sp=0x7fa0b4122750 pc=0x7fa077914b20 runtime.gcBgMarkWorker() /opt/occlum/toolchains/golang/src/runtime/mgc.go:1263 +0x1b3 fp=0x7fa0b41227e0 sp=0x7fa0b4122758 pc=0x7fa0778cc153 runtime.goexit() /opt/occlum/toolchains/golang/src/runtime/asm_amd64.s:1571 +0x1 fp=0x7fa0b41227e8 sp=0x7fa0b41227e0 pc=0x7fa077916dc1 created by runtime.gcBgMarkStartWorkers /opt/occlum/toolchains/golang/src/runtime/mgc.go:1131 +0x25

goroutine 1 [chan receive, 601 minutes]: main.main() /root/service/crontab/cmd/collection/collection.go:41 +0x245

goroutine 18 [chan receive, 601 minutes]: github.com/rjeczalik/notify.(*nonrecursiveTree).dispatch(0x7fa0b4380cc0, 0x7fa0b433d680?) /root/go/pkg/mod/github.com/rjeczalik/[email protected]/tree_nonrecursive.go:36 +0x45 created by github.com/rjeczalik/notify.newNonrecursiveTree /root/go/pkg/mod/github.com/rjeczalik/[email protected]/tree_nonrecursive.go:29 +0x16a

goroutine 19 [chan receive, 601 minutes]: github.com/rjeczalik/notify.(*nonrecursiveTree).internal(0x7fa0b4380cc0, 0x7fa0b4380c60) /root/go/pkg/mod/github.com/rjeczalik/[email protected]/tree_nonrecursive.go:81 +0x5a created by github.com/rjeczalik/notify.newNonrecursiveTree /root/go/pkg/mod/github.com/rjeczalik/[email protected]/tree_nonrecursive.go:30 +0x1be

goroutine 36 [syscall, 601 minutes]: os/signal.signal_recv() /opt/occlum/toolchains/golang/src/runtime/sigqueue.go:151 +0x2f os/signal.loop() /opt/occlum/toolchains/golang/src/os/signal/signal_unix.go:23 +0x19 created by os/signal.Notify.func1.1 /opt/occlum/toolchains/golang/src/os/signal/signal.go:151 +0x2a

goroutine 37 [select, 601 minutes]: database/sql.(*DB).connectionOpener(0x7fa0b47bad00, {0x7fa078fcf4e8, 0x7fa0b48303c0}) /opt/occlum/toolchains/golang/src/database/sql/sql.go:1226 +0x8d created by database/sql.OpenDB /opt/occlum/toolchains/golang/src/database/sql/sql.go:794 +0x18d

goroutine 1260700 [select]: google.golang.org/grpc.(*ccBalancerWrapper).watcher(0x7fa0c0161040) /root/go/pkg/mod/google.golang.org/[email protected]/balancer_conn_wrappers.go:69 +0x8f created by google.golang.org/grpc.newCCBalancerWrapper /root/go/pkg/mod/google.golang.org/[email protected]/balancer_conn_wrappers.go:60 +0x1d0

goroutine 12 [select]: database/sql.(*DB).connectionCleaner(0x7fa0b47bad00, 0x0?) /opt/occlum/toolchains/golang/src/database/sql/sql.go:1069 +0xa7 created by database/sql.(*DB).startCleanerLocked /opt/occlum/toolchains/golang/src/database/sql/sql.go:1056 +0xff

goroutine 13 [select, 601 minutes]: database/sql.(*DB).connectionOpener(0x7fa0b41541a0, {0x7fa078fcf4e8, 0x7fa0b438a3c0}) /opt/occlum/toolchains/golang/src/database/sql/sql.go:1226 +0x8d created by database/sql.OpenDB /opt/occlum/toolchains/golang/src/database/sql/sql.go:794 +0x18d

goroutine 2049029 [select]: google.golang.org/grpc.(*ccBalancerWrapper).watcher(0x7fa0c1648340) /root/go/pkg/mod/google.golang.org/[email protected]/balancer_conn_wrappers.go:69 +0x8f created by google.golang.org/grpc.newCCBalancerWrapper /root/go/pkg/mod/google.golang.org/[email protected]/balancer_conn_wrappers.go:60 +0x1d0

goroutine 2565955 [IO wait, 1 minutes]: internal/poll.runtime_pollWait(0x7fa08dc7d2e8, 0x72) /opt/occlum/toolchains/golang/src/runtime/netpoll.go:302 +0x89 internal/poll.(*pollDesc).wait(0x7fa0c812f980?, 0x7fa0bf408000?, 0x0) /opt/occlum/toolchains/golang/src/internal/poll/fd_poll_runtime.go:83 +0x32 internal/poll.(*pollDesc).waitRead(...) /opt/occlum/toolchains/golang/src/internal/poll/fd_poll_runtime.go:88 internal/poll.(*FD).Read(0x7fa0c812f980, {0x7fa0bf408000, 0x8000, 0x8000}) /opt/occlum/toolchains/golang/src/internal/poll/fd_unix.go:167 +0x25a net.(*netFD).Read(0x7fa0c812f980, {0x7fa0bf408000?, 0x3?, 0x7fa0c70aed30?}) /opt/occlum/toolchains/golang/src/net/fd_posix.go:55 +0x29 net.(*conn).Read(0x7fa0c80dd9f0, {0x7fa0bf408000?, 0x30?, 0xb8010401?}) /opt/occlum/toolchains/golang/src/net/net.go:183 +0x45 bufio.(*Reader).Read(0x7fa0b593d320, {0x7fa0c89b6f20, 0x9, 0x7fa0b4588000?}) /opt/occlum/toolchains/golang/src/bufio/bufio.go:236 +0x1b4 io.ReadAtLeast({0x7fa078fc4ee8, 0x7fa0b593d320}, {0x7fa0c89b6f20, 0x9, 0x9}, 0x9)

help!!@

ialtery avatar Sep 01 '23 07:09 ialtery

@ialtery It is not Occlum log. But from the go panic backtrace, it is more like a go problem. I assume you used occlum-go to build your application which it is musl-lib. There is a similar reported issue with alpine. I suggest you try gcc occlum-go as below.

CC=gcc occlum-go build

You can refer to doc for detail.

qzheng527 avatar Sep 01 '23 07:09 qzheng527

@ialtery It is not Occlum log. But from the go panic backtrace, it is more like a go problem. I assume you used occlum-go to build your application which it is musl-lib. There is a similar reported issue with alpine. I suggest you try gcc occlum-go as below.

CC=gcc occlum-go build

You can refer to doc for detail.

^[[1;34mocclum run /bin/collection^[[0m^M ^[[0m[2023-09-05T14:38:10.640Z][DEBUG][T0][#0] lookup_inode: cwd: "/", path: "/etc/resolv.conf"^[[0m^M ^[[0m[2023-09-05T14:38:10.642Z][DEBUG][T0][#0] lookup_inode: cwd: "/", path: "/etc"^[[0m^M ^[[0m[2023-09-05T14:38:10.642Z][DEBUG][T0][#0] real_path: cwd: "/", path: "/etc/resolv.conf"^[[0m^M ^[[0m[2023-09-05T14:38:10.642Z][DEBUG][T0][#0] lookup_inode: cwd: "/", path: "/etc"^[[0m^M ^[[0m[2023-09-05T14:38:10.643Z][DEBUG][T0][#0] convert_to_abs_path: cwd: "/", path: "/etc/resolv.conf"^[[0m^M ^[[0m[2023-09-05T14:38:10.644Z][DEBUG][T0][#0] lookup_inode: cwd: "/", path: "/etc/hostname"^[[0m^M ^[[0m[2023-09-05T14:38:10.644Z][DEBUG][T0][#0] lookup_inode: cwd: "/", path: "/etc"^[[0m^M ^[[0m[2023-09-05T14:38:10.644Z][DEBUG][T0][#0] real_path: cwd: "/", path: "/etc/hostname"^[[0m^M ^[[0m[2023-09-05T14:38:10.644Z][DEBUG][T0][#0] lookup_inode: cwd: "/", path: "/etc"^[[0m^M ^[[0m[2023-09-05T14:38:10.644Z][DEBUG][T0][#0] convert_to_abs_path: cwd: "/", path: "/etc/hostname"^[[0m^M ^[[0m[2023-09-05T14:38:10.644Z][DEBUG][T0][#0] lookup_inode: cwd: "/", path: "/etc/hosts"^[[0m^M ^[[0m[2023-09-05T14:38:10.644Z][DEBUG][T0][#0] convert_to_abs_path: cwd: "/", path: "/etc/hosts"^[[0m^M ^[[0m[2023-09-05T14:38:10.645Z][DEBUG][T0][#0] lookup_inode: cwd: "/", path: "/bin/init"^[[0m^M ^[[0m[2023-09-05T14:38:10.645Z][DEBUG][T0][#0] convert_to_abs_path: cwd: "/", path: "/bin/init"^[[0m^M ^[[0m[2023-09-05T14:38:10.645Z][DEBUG][T0][#0] lookup_inode: cwd: "/", path: "/lib/ld-musl-x86_64.so.1"^[[0m^M ^[[0m[2023-09-05T14:38:10.646Z][DEBUG][T0][#0] convert_to_abs_path: cwd: "/", path: "/lib/ld-musl-x86_64.so.1"^[[0m^M ^[[0m[2023-09-05T14:38:10.646Z][DEBUG][T0][#0] allocated rsrv addr is 0x7d0085437000, len is 0x380000000^[[0m^M ^[[0m[2023-09-05T14:38:10.650Z][DEBUG][T0][#0] process group:ProcessGrp { inner: RwLock { inner: RwLockInner { status: AtomicRwLockStatus(0), rw_waiters: 0 }, data: PgrpInner { pgid: 1, process_group: {1: Process { pid: 1, exec_path: "/bin/init", ppid: Some(0), pgid: 1, inner: ProcessInner::Live { status: Running, child_pids: [], thread_tids: [1] } }}, leader_process: Some(Process { pid: 1, exec_path: "/bin/init", ppid: Some(0), pgid: 1, inner: ProcessInner::Live { status: Running, child_pids: [], thread_tids: [1] } }) } } }^[[0m^M ^[[0m[2023-09-05T14:38:10.650Z][DEBUG][T0][#0] non idle process all pgrp: [ProcessGrp { inner: RwLock { inner: RwLockInner { status: AtomicRwLockStatus(0), rw_waiters: 0 }, data: PgrpInner { pgid: 0, process_group: {}, leader_process: Some(Process { pid: 0, exec_path: "", ppid: None, pgid: 0, inner: ProcessInner::Live { status: Running, child_pids: [1], thread_tids: [0] } }) } } }, ProcessGrp { inner: RwLock { inner: RwLockInner { status: AtomicRwLockStatus(0), rw_waiters: 0 }, data: PgrpInner { pgid: 1, process_group: {1: Process { pid: 1, exec_path: "/bin/init", ppid: Some(0), pgid: 1, inner: ProcessInner::Live { status: Running, child_pids: [], thread_tids: [1] } }}, leader_process: Some(Process { pid: 1, exec_path: "/bin/init", ppid: Some(0), pgid: 1, inner: ProcessInner::Live { status: Running, child_pids: [], thread_tids: [1] } }) } } }]^[[0m^M ^[[32m[2023-09-05T14:38:10.650Z][ INFO][T0][#0] Process created: elf = /bin/init, pid = 1^[[0m^M ^[[0m[2023-09-05T14:38:10.650Z][DEBUG][T1][#1][ArchPrctl] do_arch_prctl: code: ARCH_SET_FS, addr: 0x7d0085c94d88^[[0m^M ^[[0m[2023-09-05T14:38:10.650Z][DEBUG][T1][#2][SetTidAddress] set_tid_address: tidptr: 0x7d0085c95364^[[0m^M ^[[0m[2023-09-05T14:38:10.650Z][DEBUG][T1][#3][····Open] openat: fs_path: FsPath { Absolute("/opt/occlum/toolchains/gcc/x86_64-linux-musl/lib/libgcc_s.so.1") }, flags: 0o2000000, mode: 0o0^[[0m^M ^[[0m[2023-09-05T14:38:10.650Z][DEBUG][T1][#3][····Open] lookup_inode: cwd: "/", path: "/opt/occlum/toolchains/gcc/x86_64-linux-musl/lib/libgcc_s.so.1"^[[0m^M ^[[31m[2023-09-05T14:38:10.650Z][ERROR][T1][#3][····Open] Error = ENOENT (#2, No such file or directory): EntryNotFound^[[0m^M ^[[0m[2023-09-05T14:38:10.650Z][DEBUG][T1][#4][····Open] openat: fs_path: FsPath { Absolute("/etc/ld-musl-x86_64.path") }, flags: 0o2000000, mode: 0o666^[[0m^M ^[[0m[2023-09-05T14:38:10.650Z][DEBUG][T1][#4][····Open] lookup_inode: cwd: "/", path: "/etc/ld-musl-x86_64.path"^[[0m^M ^[[31m[2023-09-05T14:38:10.650Z][ERROR][T1][#4][····Open] Error = ENOENT (#2, No such file or directory): EntryNotFound^[[0m^M ^[[0m[2023-09-05T14:38:10.650Z][DEBUG][T1][#5][····Open] openat: fs_path: FsPath { Absolute("/lib/libgcc_s.so.1") }, flags: 0o2000000, mode: 0o0^[[0m^M ^[[0m[2023-09-05T14:38:10.650Z][DEBUG][T1][#5][····Open] lookup_inode: cwd: "/", path: "/lib/libgcc_s.so.1"^[[0m^M ^[[0m[2023-09-05T14:38:10.650Z][DEBUG][T1][#5][····Open] convert_to_abs_path: cwd: "/", path: "/lib/libgcc_s.so.1"^[[0m^M ^[[0m[2023-09-05T14:38:10.650Z][DEBUG][T1][#6][···Fcntl] fcntl: fd: 3, cmd: SetFd(1)^[[0m^M ^[[0m[2023-09-05T14:38:10.651Z][DEBUG][T1][#7][···Fstat] fstat: fd: 3^[[0m^M ^[[0m[2023-09-05T14:38:10.651Z][DEBUG][T1][#8][····Read] read: fd: 3^[[0m^M ^[[0m[2023-09-05T14:38:10.651Z][DEBUG][T1][#9][····Mmap] mmap: addr: 0x0, size: 0x217000, perms: READ | EXEC, flags: MAP_PRIVATE, fd: 3, offset: 0^[[0m^M ^[[0m[2023-09-05T14:38:10.653Z][DEBUG][T1][#10][····Mmap] mmap: addr: 0x7d00d6015000, size: 0x2000, perms: READ | WRITE | DEFAULT, flags: MAP_PRIVATE | MAP_FIXED, fd: 3, offset: 86016^[[0m^M ^[[0m[2023-09-05T14:38:10.653Z][DEBUG][T1][#11][···Close] close: fd: 3^[[0m^M ^[[0m[2023-09-05T14:38:10.653Z][DEBUG][T1][#12][Mprotect] mprotect: addr: 0x7d00d6015000, size: 0x1000, perms: READ^[[0m^M ^[[0m[2023-09-05T14:38:10.653Z][DEBUG][T1][#13][Mprotect] mprotect: addr: 0x7d0085c91000, size: 0x1000, perms: READ^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#14][Mprotect] mprotect: addr: 0x7d0085859000, size: 0x4000, perms: READ^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#15][ArchPrctl] do_arch_prctl: code: ARCH_SET_FS, addr: 0x7d00d60166a0^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#16][SetTidAddress] set_tid_address: tidptr: 0x7d0085c95364^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#17][····Poll] poll: poll_fds: [PollFd { fd: 0, events: ERR | HUP, revents: Cell { value: (empty) } }, PollFd { fd: 1, events: ERR | HUP, revents: Cell
{ value: (empty) } }, PollFd { fd: 2, events: ERR | HUP, revents: Cell { value: (empty) } }], timeout: Some(0ns)^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#18][RtSigaction] do_rt_sigaction: signum: SigNum (#13 = SIGPIPE), new_sa: Some(Ign)^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#19][RtSigaction] do_rt_sigaction: signum: SigNum (#11 = SIGSEGV), new_sa: None^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#20][RtSigprocmask] do_rt_sigprocmask: op_and_set: Some((Unblock, SigSet { [SigNum (#33, real-time), SigNum (#34, real-time)] })), oldset: None^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#21][RtSigaction] do_rt_sigaction: signum: SigNum (#11 = SIGSEGV), new_sa: Some(User { handler_addr: 137441191300768, flags: SA_SIGINFO | SA_ONSTACK | SA_RESTORER, restorer_addr: 137441195694675, mask: SigSet { [] } })^[[0m^M STORER, restorer_addr: 137441195694675, mask: SigSet { [] } })^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#22][RtSigaction] do_rt_sigaction: signum: SigNum (#7 = SIGBUS), new_sa: None^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#23][RtSigaction] do_rt_sigaction: signum: SigNum (#7 = SIGBUS), new_sa: Some(User { handler_addr: 137441191300768, flags: SA_SIGINFO | SA_ONSTACK | SA_RESTORER, restorer_addr: 137441195694675, mask: SigSet { [] } })^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#24][Sigaltstack] do_sigaltstack: new_ss:None^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#25][····Mmap] mmap: addr: 0x0, size: 0x3000, perms: READ | WRITE | DEFAULT, flags: MAP_PRIVATE | MAP_ANONYMOUS | MAP_STACK^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#26][Mprotect] mprotect: addr: 0x7d00d6017000, size: 0x1000, perms: NONE^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#27][Sigaltstack] do_sigaltstack: new_ss:Some(SigStack { sp: 137442543894528, flags: EMPTY, size: 8192 })^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#28][RtSigprocmask] do_rt_sigprocmask: op_and_set: Some((Block, SigSet { All except [SigNum (#32, real-time), SigNum (#33, real-time), SigNum (#34, real-time)] })), oldset: Some(28360702)^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#29][RtSigprocmask] do_rt_sigprocmask: op_and_set: Some((SetMask, SigSet { [] })), oldset: None^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#30][····Open] openat: fs_path: FsPath { Absolute("/etc/image_config.json") }, flags: 0o2000000, mode: 0o0^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#30][····Open] lookup_inode: cwd: "/", path: "/etc/image_config.json"^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#30][····Open] convert_to_abs_path: cwd: "/", path: "/etc/image_config.json"^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#31][···Fcntl] fcntl: fd: 3, cmd: SetFd(1)^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#32][···Fstat] fstat: fd: 3^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#34][····Read] read: fd: 3^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#35][····Read] read: fd: 3^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#36][···Close] close: fd: 3^[[0m^M ^[[0m[2023-09-05T14:38:10.654Z][DEBUG][T1][#37][MountRootFS] mount rootfs^[[0m^M ^[[0m[2023-09-05T14:38:10.656Z][DEBUG][T1][#37][MountRootFS] lookup_inode: cwd: "/", path: "/etc/resolv.conf"^[[0m^M ^[[0m[2023-09-05T14:38:10.656Z][DEBUG][T1][#37][MountRootFS] lookup_inode: cwd: "/", path: "/etc"^[[0m^M ^[[0m[2023-09-05T14:38:10.656Z][DEBUG][T1][#37][MountRootFS] real_path: cwd: "/", path: "/etc/resolv.conf"^[[0m^M ^[[0m[2023-09-05T14:38:10.656Z][DEBUG][T1][#37][MountRootFS] lookup_inode: cwd: "/", path: "/etc"^[[0m^M ^[[0m[2023-09-05T14:38:10.656Z][DEBUG][T1][#37][MountRootFS] convert_to_abs_path: cwd: "/", path: "/etc/resolv.conf"^[[0m^M ^[[0m[2023-09-05T14:38:10.656Z][DEBUG][T1][#37][MountRootFS] lookup_inode: cwd: "/", path: "/etc/hostname"^[[0m^M ^[[0m[2023-09-05T14:38:10.656Z][DEBUG][T1][#37][MountRootFS] lookup_inode: cwd: "/", path: "/etc"^[[0m^M ^[[0m[2023-09-05T14:38:10.656Z][DEBUG][T1][#37][MountRootFS] real_path: cwd: "/", path: "/etc/hostname"^[[0m^M ^[[0m[2023-09-05T14:38:10.656Z][DEBUG][T1][#37][MountRootFS] lookup_inode: cwd: "/", path: "/etc"^[[0m^M ^[[0m[2023-09-05T14:38:10.657Z][DEBUG][T1][#37][MountRootFS] convert_to_abs_path: cwd: "/", path: "/etc/hostname"^[[0m^M ^[[0m[2023-09-05T14:38:10.657Z][DEBUG][T1][#37][MountRootFS] lookup_inode: cwd: "/", path: "/etc/hosts"^[[0m^M ^[[0m[2023-09-05T14:38:10.657Z][DEBUG][T1][#37][MountRootFS] lookup_inode: cwd: "/", path: "/etc"^[[0m^M ^[[0m[2023-09-05T14:38:10.657Z][DEBUG][T1][#37][MountRootFS] real_path: cwd: "/", path: "/etc/hosts"^[[0m^M ^[[0m[2023-09-05T14:38:10.657Z][DEBUG][T1][#37][MountRootFS] lookup_inode: cwd: "/", path: "/etc"^[[0m^M ^[[0m[2023-09-05T14:38:10.657Z][DEBUG][T1][#37][MountRootFS] convert_to_abs_path: cwd: "/", path: "/etc/hosts"^[[0m^M ^[[0m[2023-09-05T14:38:10.657Z][DEBUG][T1][#38][Sigaltstack] do_sigaltstack: new_ss:Some(SigStack { sp: 0, flags: SS_DISABLE, size: 8192 })^[[0m^M ^[[0m[2023-09-05T14:38:10.657Z][DEBUG][T1][#39][··Munmap] munmap: addr: 0x7d00d6017000, size: 0x3000^[[0m^M ^[[0m[2023-09-05T14:38:10.657Z][DEBUG][T1][#40][ExitGroup] exit_group: 0^[[0m^M ^[[0m[2023-09-05T14:38:10.657Z][DEBUG][T1][#40][ExitGroup] futex_wake_bitset addr: 0x7d0085c95364, max_count: 1, bitset: 0xffffffff^[[0m^M ^[[0m[2023-09-05T14:38:10.771Z][DEBUG][T1][#40][ExitGroup] futex_wake_bitset addr: 0x7c00009c1c30, max_count: 1, bitset: 0xffffffff^[[0m^M ^[[32m[2023-09-05T14:38:10.771Z][ INFO][T1][#40][ExitGroup] Thread exited: tid = 1, status = 0^[[0m^M ^[[32m[2023-09-05T14:38:10.771Z][ INFO][T0][#40][ExitGroup] Process VM dropped^[[0m^M ^[[32m[2023-09-05T14:38:10.771Z][ INFO][T0][#40][ExitGroup] Idle process reaps zombie children pid = []^[[0m^M ^[[33m[2023-09-05T14:38:10.771Z][ WARN][T0][#40][ExitGroup] HostFS: sync is unimplemented^[[0m^M ^[[0m[2023-09-05T14:38:10.771Z][DEBUG][T0][#0] lookup_inode: cwd: "/", path: "/bin/collection"^[[0m^M ^[[0m[2023-09-05T14:38:10.771Z][DEBUG][T0][#0] convert_to_abs_path: cwd: "/", path: "/bin/collection"^[[0m^M ^[[0m[2023-09-05T14:38:10.775Z][DEBUG][T0][#0] lookup_inode: cwd: "/", path: "/lib64/ld-linux-x86-64.so.2"^[[0m^M ^[[0m[2023-09-05T14:38:10.775Z][DEBUG][T0][#0] convert_to_abs_path: cwd: "/", path: "/lib64/ld-linux-x86-64.so.2"^[[0m^M ^[[0m[2023-09-05T14:38:10.881Z][DEBUG][T0][#0] process group:ProcessGrp { inner: RwLock { inner: RwLockInner { status: AtomicRwLockStatus(0), rw_waiters: 0 }, data: PgrpInner { pgid: 2, process_group: {2: Process { pid: 2, exec_path: "/bin/collection", ppid: Some(0), pgid: 2, inner: ProcessInner::Live { status: Running, child_pids: [], thread_tids: [2] } }}, leader_process: Some(Process { pid: 2, exec_path: "/bin/collection", ppid: Some(0), pgid: 2, inner: ProcessInner::Live { status: Running, child_pids: [], thread_tids: [2] } }) } } }^[[0m^M ^[[0m[2023-09-05T14:38:10.881Z][DEBUG][T0][#0] non idle process all pgrp: [ProcessGrp { inner: RwLock { inner: RwLockInner { status: AtomicRwLockStatus(0), rw_waiters: 0 }, data: PgrpInner { pgid: 0, process_group: {}, leader_process: Some(Process { pid: 0, exec_path: "", ppid: None, pgid: 0, inner: ProcessInner::Live { status: Running, child_pids: [2], thread_tids: [0] } }) } } }, ProcessGrp { inner: RwLock { inner: RwLockInner { status: AtomicRwLockStatus(0), rw_waiters: 0 }, data: PgrpInner { pgid: 2, process_group: {2: Process { pid: 2, exec_path: "/bin/collection", ppid: Some(0), pgid: 2, inner: ProcessInner::Live { status: Running, child_pids: [], thread_tids: [2] } }}, leader_process: Some(Process { pid: 2, exec_path: "/bin/collection", ppid: Some(0), pgid: 2, inner: ProcessInner::Live { status: Running, child_pids: [], thread_tids: [2] } }) } } }]^[[0m^M ^[[32m[2023-09-05T14:38:10.881Z][ INFO][T0][#0] Process created: elf = /bin/collection, pid = 2^[[0m^M ^[[0m[2023-09-05T14:38:10.881Z][DEBUG][T2][#1][·····Brk] brk: addr: 0x0^[[0m^M ^[[31m[2023-09-05T14:38:10.881Z][ERROR][T2][#1][·····Brk] New brk address is too low^[[0m^M ^[[0m[2023-09-05T14:38:10.881Z][DEBUG][T2][#2][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.881Z][DEBUG][T2][#3][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.881Z][DEBUG][T2][#4][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.881Z][DEBUG][T2][#5][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.881Z][DEBUG][T2][#6][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.881Z][DEBUG][T2][#7][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.881Z][DEBUG][T2][#8][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.881Z][DEBUG][T2][#9][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#10][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#11][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#12][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#13][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#14][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#15][HandleException] handle CPUID exception^[[0m^M ^[[31m[2023-09-05T14:38:10.882Z][ERROR][T2][#16][ArchPrctl] Error = EINVAL (#22, Invalid argument): Unknown code for arch_prctl [line = 35, file = src/process/do_arch_prctl.rs]^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#17][··Access] faccessat: fs_path: FsPath { Absolute("/etc/ld.so.preload") }, mode: R_OK, flags: (empty)^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#17][··Access] lookup_inode: cwd: "/", path: "/etc/ld.so.preload"^[[0m^M ^[[31m[2023-09-05T14:38:10.882Z][ERROR][T2][#17][··Access] Error = ENOENT (#2, No such file or directory): EntryNotFound^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#18][··Openat] openat: fs_path: FsPath { Absolute("/opt/occlum/glibc/etc/ld.so.cache") }, flags: 0o2000000, mode: 0o0^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#18][··Openat] lookup_inode: cwd: "/", path: "/opt/occlum/glibc/etc/ld.so.cache"^[[0m^M ^[[31m[2023-09-05T14:38:10.882Z][ERROR][T2][#18][··Openat] Error = ENOENT (#2, No such file or directory): EntryNotFound^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#19][··Openat] openat: fs_path: FsPath { Absolute("/opt/occlum/glibc/lib/tls/haswell/avx512_1/x86_64/libpthread.so.0") }, flags: 0o2000000, mode: 0o0^[[0mm ^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#19][··Openat] lookup_inode: cwd: "/", path: "/opt/occlum/glibc/lib/tls/haswell/avx512_1/x86_64/libpthread.so.0"^[[0m^M ^[[31m[2023-09-05T14:38:10.882Z][ERROR][T2][#19][··Openat] Error = ENOENT (#2, No such file or directory): EntryNotFound^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#20][····Stat] fstatat: fs_path: FsPath { Absolute("/opt/occlum/glibc/lib/tls/haswell/avx512_1/x86_64") }, flags: (empty)^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#20][····Stat] lookup_inode: cwd: "/", path: "/opt/occlum/glibc/lib/tls/haswell/avx512_1/x86_64"^[[0m^M ^[[31m[2023-09-05T14:38:10.882Z][ERROR][T2][#20][····Stat] Error = ENOENT (#2, No such file or directory): EntryNotFound^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#21][··Openat] openat: fs_path: FsPath { Absolute("/opt/occlum/glibc/lib/tls/haswell/avx512_1/libpthread.so.0") }, flags: 0o2000000, mode: 0o0^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#21][··Openat] lookup_inode: cwd: "/", path: "/opt/occlum/glibc/lib/tls/haswell/avx512_1/libpthread.so.0"^[[0m^M ^[[31m[2023-09-05T14:38:10.882Z][ERROR][T2][#21][··Openat] Error = ENOENT (#2, No such file or directory): EntryNotFound^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#22][····Stat] fstatat: fs_path: FsPath { Absolute("/opt/occlum/glibc/lib/tls/haswell/avx512_1") }, flags: (empty)^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#22][····Stat] lookup_inode: cwd: "/", path: "/opt/occlum/glibc/lib/tls/haswell/avx512_1"^[[0m^M ^[[31m[2023-09-05T14:38:10.882Z][ERROR][T2][#22][····Stat] Error = ENOENT (#2, No such file or directory): EntryNotFound^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#23][··Openat] openat: fs_path: FsPath { Absolute("/opt/occlum/glibc/lib/tls/haswell/x86_64/libpthread.so.0") }, flags: 0o2000000, mode: 0o0^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#23][··Openat] lookup_inode: cwd: "/", path: "/opt/occlum/glibc/lib/tls/haswell/x86_64/libpthread.so.0"^[[0m^M ^[[31m[2023-09-05T14:38:10.882Z][ERROR][T2][#23][··Openat] Error = ENOENT (#2, No such file or directory): EntryNotFound^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#24][····Stat] fstatat: fs_path: FsPath { Absolute("/opt/occlum/glibc/lib/tls/haswell/x86_64") }, flags: (empty)^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#24][····Stat] lookup_inode: cwd: "/", path: "/opt/occlum/glibc/lib/tls/haswell/x86_64"^[[0m^M ^[[31m[2023-09-05T14:38:10.882Z][ERROR][T2][#24][····Stat] Error = ENOENT (#2, No such file or directory): EntryNotFound^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#25][··Openat] openat: fs_path: FsPath { Absolute("/opt/occlum/glibc/lib/tls/haswell/libpthread.so.0") }, flags: 0o2000000, mode: 0o0^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#25][··Openat] lookup_inode: cwd: "/", path: "/opt/occlum/glibc/lib/tls/haswell/libpthread.so.0"^[[0m^M ^[[31m[2023-09-05T14:38:10.882Z][ERROR][T2][#25][··Openat] Error = ENOENT (#2, No such file or directory): EntryNotFound^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#26][····Stat] fstatat: fs_path: FsPath { Absolute("/opt/occlum/glibc/lib/tls/haswell") }, flags: (empty)^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#26][····Stat] lookup_inode: cwd: "/", path: "/opt/occlum/glibc/lib/tls/haswell"^[[0m^M ^[[31m[2023-09-05T14:38:10.882Z][ERROR][T2][#26][····Stat] Error = ENOENT (#2, No such file or directory): EntryNotFound^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#27][··Openat] openat: fs_path: FsPath { Absolute("/opt/occlum/glibc/lib/tls/avx512_1/x86_64/libpthread.so.0") }, flags: 0o2000000, mode: 0o0^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#27][··Openat] lookup_inode: cwd: "/", path: "/opt/occlum/glibc/lib/tls/avx512_1/x86_64/libpthread.so.0"^[[0m^M ^[[31m[2023-09-05T14:38:10.882Z][ERROR][T2][#27][··Openat] Error = ENOENT (#2, No such file or directory): EntryNotFound^[[0m^M ^[[0m[2023-09-05T14:38:10.882Z][DEBUG][T2][#28][····Stat] fstatat: fs_path: FsPath { Absolute("/opt/occlum/glibc/lib/tls/avx512_1/x86_64") }, flags: (empty)^[[0m^M ^[[0m[2023-09-05T14:38:10.883Z][DEBUG][T2][#28][····Stat] lookup_inode: cwd: "/", path: "/opt/occlum/glibc/lib/tls/avx512_1/x86_64"^[[0m^M ^[[31m[2023-09-05T14:38:10.883Z][ERROR][T2][#28][····Stat] Error = ENOENT (#2, No such file or directory): EntryNotFound^[[0m^M ^[[0m[2023-09-05T14:38:10.883Z][DEBUG][T2][#29][··Openat] openat: fs_path: FsPath { Absolute("/opt/occlum/glibc/lib/tls/avx512_1/libpthread.so.0") }, flags: 0o2000000, mode: 0o0^[[0m^M ^[[0m[2023-09-05T14:38:10.883Z][DEBUG][T2][#29][··Openat] lookup_inode: cwd: "/", path: "/opt/occlum/glibc/lib/tls/avx512_1/libpthread.so.0"^[[0m^M ^[[0m[2023-09-05T14:38:10.883Z][DEBUG][T2][#52][···Fstat] fstat: fd: 3^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#53][·Pread64] pread: fd: 3, offset: 824^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#54][····Mmap] mmap: addr: 0x0, size: 0x23478, perms: READ, flags: MAP_PRIVATE | MAP_DENYWRITE, fd: 3, offset: 0^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#55][····Mmap] mmap: addr: 0x7d00d75ae000, size: 0x12000, perms: READ | EXEC, flags: MAP_PRIVATE | MAP_FIXED | MAP_DENYWRITE, fd: 3, offset: 28672^[[0m^^ M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#56][····Mmap] mmap: addr: 0x7d00d75c0000, size: 0x5000, perms: READ, flags: MAP_PRIVATE | MAP_FIXED | MAP_DENYWRITE, fd: 3, offset: 102400^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#57][····Mmap] mmap: addr: 0x7d00d75c5000, size: 0x2000, perms: READ | WRITE | DEFAULT, flags: MAP_PRIVATE | MAP_FIXED | MAP_DENYWRITE, fd: 3, offset: 11 18784^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#58][····Mmap] mmap: addr: 0x7d00d75c7000, size: 0x3478, perms: READ | WRITE | DEFAULT, flags: MAP_PRIVATE | MAP_FIXED | MAP_ANONYMOUS^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#59][···Close] close: fd: 3^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#60][··Openat] openat: fs_path: FsPath { Absolute("/opt/occlum/glibc/lib/libc.so.6") }, flags: 0o2000000, mode: 0o0^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#60][··Openat] lookup_inode: cwd: "/", path: "/opt/occlum/glibc/lib/libc.so.6"^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#60][··Openat] convert_to_abs_path: cwd: "/", path: "/opt/occlum/glibc/lib/libc.so.6"^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#61][····Read] read: fd: 3^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#62][·Pread64] pread: fd: 3, offset: 64^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#63][·Pread64] pread: fd: 3, offset: 848^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#64][·Pread64] pread: fd: 3, offset: 880^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#65][···Fstat] fstat: fd: 3^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#66][····Mmap] mmap: addr: 0x0, size: 0x2000, perms: READ | WRITE | DEFAULT, flags: MAP_PRIVATE | MAP_ANONYMOUS^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#67][·Pread64] pread: fd: 3, offset: 64^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#68][·Pread64] pread: fd: 3, offset: 848^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#69][·Pread64] pread: fd: 3, offset: 880^[[0m^M ^[[0m[2023-09-05T14:38:10.884Z][DEBUG][T2][#70][····Mmap] mmap: addr: 0x0, size: 0x1c7420, perms: READ, flags: MAP_PRIVATE | MAP_DENYWRITE, fd: 3, offset: 0^[[0m^M ^[[0m[2023-09-05T14:38:10.890Z][DEBUG][T2][#71][Mprotect] mprotect: addr: 0x7d00d75f2000, size: 0x199000, perms: NONE^[[0m^M ^[[0m[2023-09-05T14:38:10.890Z][DEBUG][T2][#72][····Mmap] mmap: addr: 0x7d00d75f2000, size: 0x14e000, perms: READ | EXEC, flags: MAP_PRIVATE | MAP_FIXED | MAP_DENYWRITE, fd: 3, offset: 151552^[[00 m^M ^[[0m[2023-09-05T14:38:10.894Z][DEBUG][T2][#73][····Mmap] mmap: addr: 0x7d00d7740000, size: 0x4a000, perms: READ, flags: MAP_PRIVATE | MAP_FIXED | MAP_DENYWRITE, fd: 3, offset: 1519616^[[0m^M ^[[0m[2023-09-05T14:38:10.895Z][DEBUG][T2][#74][····Mmap] mmap: addr: 0x7d00d778b000, size: 0x6000, perms: READ | WRITE | DEFAULT, flags: MAP_PRIVATE | MAP_FIXED | MAP_DENYWRITE, fd: 3, offset: 11 822720^[[0m^M ^[[0m[2023-09-05T14:38:10.895Z][DEBUG][T2][#75][····Mmap] mmap: addr: 0x7d00d7791000, size: 0x3420, perms: READ | WRITE | DEFAULT, flags: MAP_PRIVATE | MAP_FIXED | MAP_ANONYMOUS^[[0m^M ^[[0m[2023-09-05T14:38:10.895Z][DEBUG][T2][#76][···Close] close: fd: 3^[[0m^M ^[[0m[2023-09-05T14:38:10.895Z][DEBUG][T2][#77][····Mmap] mmap: addr: 0x0, size: 0x3000, perms: READ | WRITE | DEFAULT, flags: MAP_PRIVATE | MAP_ANONYMOUS^[[0m^M ^[[0m[2023-09-05T14:38:10.895Z][DEBUG][T2][#78][ArchPrctl] do_arch_prctl: code: ARCH_SET_FS, addr: 0x7d00d7795740^[[0m^M ^[[0m[2023-09-05T14:38:10.895Z][DEBUG][T2][#79][Mprotect] mprotect: addr: 0x7d00d778b000, size: 0x3000, perms: READ^[[0m^M ^[[0m[2023-09-05T14:38:10.895Z][DEBUG][T2][#80][Mprotect] mprotect: addr: 0x7d00d75c5000, size: 0x1000, perms: READ^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#81][Mprotect] mprotect: addr: 0x7d0086917000, size: 0xb9d000, perms: READ^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#82][Mprotect] mprotect: addr: 0x7d00875a4000, size: 0x1000, perms: READ^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#83][SetTidAddress] set_tid_address: tidptr: 0x7d00d7795a10^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#84][SetRobustList] set_robust_list: list_head_ptr: 0x7d00d7795a20, len: 24^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#85][RtSigaction] do_rt_sigaction: signum: SigNum (#32, real-time), new_sa: Some(User { handler_addr: 137442566532096, flags: SA_SIGINFO | SA_RESTORER, restorer_addr: 137442566590032, mask: SigSet { [] } })^[[0m^M ^[[33m[2023-09-05T14:38:10.896Z][ WARN][T2][#86][RtSigaction] SA_RESTART is not supported^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#86][RtSigaction] do_rt_sigaction: signum: SigNum (#33, real-time), new_sa: Some(User { handler_addr: 137442566532256, flags: SA_SIGINFO | SA_RESTART | SA_RESTORER, restorer_addr: 137442566590032, mask: SigSet { [] } })^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#87][RtSigprocmask] do_rt_sigprocmask: op_and_set: Some((Unblock, SigSet { [SigNum (#32, real-time), SigNum (#33, real-time)] })), oldset: None^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#89][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#90][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#91][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#92][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#93][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#94][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#95][HandleException] handle CPUID exception^[[0m^M ^[[0m[2023-09-05T14:38:10.896Z][DEBUG][T2][#96][HandleException] handle CPUID exception^[[0m^M

ialtery avatar Sep 05 '23 14:09 ialtery

^[[0m[2023-09-05T14:38:10.910Z][DEBUG][T2][#276][····Mmap] mmap: addr: 0x0, size: 0x10001000, perms: NONE, flags: MAP_PRIVATE | MAP_ANONYMOUS | MAP_STACK^[[0m^M ^[[0m[2023-09-05T14:38:10.912Z][DEBUG][T2][#277][Mprotect] mprotect: addr: 0x7d0104001000, size: 0x10000000, perms: READ | WRITE | DEFAULT^[[0m^M ^[[0m[2023-09-05T14:38:10.913Z][DEBUG][T2][#278][···Clone] clone: flags: CLONE_VM | CLONE_FS | CLONE_FILES | CLONE_SIGHAND | CLONE_THREAD | CLONE_SYSVSEM | CLONE_SETTLS | CLONE_PARENT_SETTID | CLOO NE_CHILD_CLEARTID, stack_addr: 137443583983528, ptid: Some(0x7d01140009d0), ctid: Some(0x7d01140009d0), new_tls: Some(137443583985408)^[[0m^M ^[[32m[2023-09-05T14:38:10.913Z][ INFO][T2][#278][···Clone] Thread created: tid = 3^[[0m^M ^[[0m[2023-09-05T14:38:10.913Z][DEBUG][T2][#279][RtSigprocmask] do_rt_sigprocmask: op_and_set: Some((SetMask, SigSet { [] })), oldset: None^[[0m^M ^[[0m[2023-09-05T14:38:10.913Z][DEBUG][T2][#281][····Mmap] mmap: addr: 0x0, size: 0x15f8f8, perms: READ | WRITE | DEFAULT, flags: MAP_PRIVATE | MAP_ANONYMOUS^[[0m^M ^[[0m[2023-09-05T14:38:10.913Z][DEBUG][T2][#282][····Mmap] mmap: addr: 0x0, size: 0x40000, perms: READ | WRITE | DEFAULT, flags: MAP_PRIVATE | MAP_ANONYMOUS^[[0m^M ^[[0m[2023-09-05T14:38:10.913Z][DEBUG][T2][#284][RtSigprocmask] do_rt_sigprocmask: op_and_set: Some((SetMask, SigSet { All except [SigNum (#32, real-time), SigNum (#33, real-time)] })), oldset: Some(416)^[[0m^M ^[[0m[2023-09-05T14:38:10.914Z][DEBUG][T2][#285][····Mmap] mmap: addr: 0x0, size: 0x10001000, perms: NONE, flags: MAP_PRIVATE | MAP_ANONYMOUS | MAP_STACK^[[0m^M ^[[0m[2023-09-05T14:38:10.914Z][DEBUG][T3][#1][SetRobustList] set_robust_list: list_head_ptr: 0x7d01140009e0, len: 24^[[0m^M ^[[0m[2023-09-05T14:38:10.915Z][DEBUG][T2][#286][Mprotect] mprotect: addr: 0x7d0114002000, size: 0x10000000, perms: READ | WRITE | DEFAULT^[[0m^M ^[[0m[2023-09-05T14:38:10.915Z][DEBUG][T3][#2][····Mmap] mmap: addr: 0x0, size: 0x8000000, perms: NONE, flags: MAP_PRIVATE | MAP_ANONYMOUS | MAP_NORESERVE^[[0m^M ^[[0m[2023-09-05T14:38:10.916Z][DEBUG][T2][#287][···Clone] clone: flags: CLONE_VM | CLONE_FS | CLONE_FILES | CLONE_SIGHAND | CLONE_THREAD | CLONE_SYSVSEM | CLONE_SETTLS | CLONE_PARENT_SETTID | CLOO NE_CHILD_CLEARTID, stack_addr: 137443852423080, ptid: Some(0x7d01240019d0), ctid: Some(0x7d01240019d0), new_tls: Some(137443852424960)^[[0m^M ^[[32m[2023-09-05T14:38:10.916Z][ INFO][T2][#287][···Clone] Thread created: tid = 4^[[0m^M ^[[0m[2023-09-05T14:38:10.917Z][DEBUG][T3][#3][··Munmap] munmap: addr: 0x7d0124002000, size: 0x3ffe000^[[0m^M ^[[0m[2023-09-05T14:38:10.917Z][DEBUG][T2][#288][RtSigprocmask] do_rt_sigprocmask: op_and_set: Some((SetMask, SigSet { [] })), oldset: None^[[0m^M ^[[0m[2023-09-05T14:38:10.917Z][DEBUG][T2][#289][RtSigprocmask] do_rt_sigprocmask: op_and_set: Some((SetMask, SigSet { All except [SigNum (#32, real-time), SigNum (#33, real-time)] })), oldset: Some(416)^[[0m^M ^[[0m[2023-09-05T14:38:10.917Z][DEBUG][T2][#290][····Mmap] mmap: addr: 0x0, size: 0x10001000, perms: NONE, flags: MAP_PRIVATE | MAP_ANONYMOUS | MAP_STACK^[[0m^M ^[[0m[2023-09-05T14:38:10.918Z][DEBUG][T4][#1][SetRobustList] set_robust_list: list_head_ptr: 0x7d01240019e0, len: 24^[[0m^M ^[[0m[2023-09-05T14:38:10.918Z][DEBUG][T4][#2][····Mmap] mmap: addr: 0x0, size: 0x8000000, perms: NONE, flags: MAP_PRIVATE | MAP_ANONYMOUS | MAP_NORESERVE^[[0m^M ^[[0m[2023-09-05T14:38:10.923Z][DEBUG][T3][#4][··Munmap] munmap: addr: 0x7d012c000000, size: 0x2000^[[0m^M ^[[0m[2023-09-05T14:38:10.925Z][DEBUG][T2][#291][Mprotect] mprotect: addr: 0x7d012c003000, size: 0x10000000, perms: READ | WRITE | DEFAULT^[[0m^M ^[[0m[2023-09-05T14:38:10.925Z][DEBUG][T4][#3][··Munmap] munmap: addr: 0x7d013c003000, size: 0x3ffd000^[[0m^M ^[[0m[2023-09-05T14:38:10.925Z][DEBUG][T3][#5][Mprotect] mprotect: addr: 0x7d0128000000, size: 0x21000, perms: READ | WRITE | DEFAULT^[[0m^M ^[[0m[2023-09-05T14:38:10.927Z][DEBUG][T2][#292][···Clone] clone: flags: CLONE_VM | CLONE_FS | CLONE_FILES | CLONE_SIGHAND | CLONE_THREAD | CLONE_SYSVSEM | CLONE_SETTLS | CLONE_PARENT_SETTID | CLOO NE_CHILD_CLEARTID, stack_addr: 137444255080360, ptid: Some(0x7d013c0029d0), ctid: Some(0x7d013c0029d0), new_tls: Some(137444255082240)^[[0m^M ^[[0m[2023-09-05T14:38:10.932Z][DEBUG][T4][#4][··Munmap] munmap: addr: 0x7d0144000000, size: 0x3000^[[0m^M ^[[32m[2023-09-05T14:38:10.932Z][ INFO][T2][#292][···Clone] Thread created: tid = 5^[[0m^M ^[[0m[2023-09-05T14:38:10.932Z][DEBUG][T3][#6][Sigaltstack] do_sigaltstack: new_ss:None^[[0m^M ^[[0m[2023-09-05T14:38:10.932Z][DEBUG][T4][#5][Mprotect] mprotect: addr: 0x7d0140000000, size: 0x21000, perms: READ | WRITE | DEFAULT^[[0m^M ^[[0m[2023-09-05T14:38:10.932Z][DEBUG][T3][#7][Sigaltstack] do_sigaltstack: new_ss:Some(SigStack { sp: 137443248963584, flags: EMPTY, size: 32768 })^[[0m^M ^[[0m[2023-09-05T14:38:10.933Z][DEBUG][T3][#8][RtSigprocmask] do_rt_sigprocmask: op_and_set: Some((SetMask, SigSet { [] })), oldset: None^[[0m^M ^[[0m[2023-09-05T14:38:10.933Z][DEBUG][T4][#6][Sigaltstack] do_sigaltstack: new_ss:None^[[0m^M ^[[0m[2023-09-05T14:38:10.933Z][DEBUG][T4][#7][Sigaltstack] do_sigaltstack: new_ss:Some(SigStack { sp: 137443248996352, flags: EMPTY, size: 32768 })^[[0m^M ^[[0m[2023-09-05T14:38:10.933Z][DEBUG][T4][#8][RtSigprocmask] do_rt_sigprocmask: op_and_set: Some((SetMask, SigSet { [] })), oldset: None^[[0m^M ^[[0m[2023-09-05T14:38:10.933Z][DEBUG][T2][#293][RtSigprocmask] do_rt_sigprocmask: op_and_set: Some((SetMask, SigSet { [] })), oldset: None^[[0m^M ^[[0m[2023-09-05T14:38:10.933Z][DEBUG][T4][#10][····Mmap] mmap: addr: 0x0, size: 0x40000, perms: READ | WRITE | DEFAULT, flags: MAP_PRIVATE | MAP_ANONYMOUS^[[0m^M ^[[0m[2023-09-05T14:38:10.933Z][DEBUG][T2][#294][···Futex] futex_wait_bitset addr: 0x7d0087535648, val: 0, timeout: None, bitset: 0xffffffff^[[0m^M ^[[0m[2023-09-05T14:38:10.933Z][DEBUG][T4][#11][RtSigprocmask] do_rt_sigprocmask: op_and_set: Some((SetMask, SigSet { All except [SigNum (#32, real-time), SigNum (#33, real-time)] })), oldset: Some(416)^[[0m^M ^[[0m[2023-09-05T14:38:10.933Z][DEBUG][T4][#12][····Mmap] mmap: addr: 0x0, size: 0x10001000, perms: NONE, flags: MAP_PRIVATE | MAP_ANONYMOUS | MAP_STACK^[[0m^M ^[[0m[2023-09-05T14:38:10.934Z][DEBUG][T4][#13][Mprotect] mprotect: addr: 0x7d0144001000, size: 0x10000000, perms: READ | WRITE | DEFAULT^[[0m^M ^[[0m[2023-09-05T14:38:10.936Z][DEBUG][T5][#1][SetRobustList] set_robust_list: list_head_ptr: 0x7d013c0029e0, len: 24^[[0m^M ^[[0m[2023-09-05T14:38:10.936Z][DEBUG][T4][#14][···Clone] clone: flags: CLONE_VM | CLONE_FS | CLONE_FILES | CLONE_SIGHAND | CLONE_THREAD | CLONE_SYSVSEM | CLONE_SETTLS | CLONE_PARENT_SETTID | CLONN E_CHILD_CLEARTID, stack_addr: 137444657725352, ptid: Some(0x7d01540009d0), ctid: Some(0x7d01540009d0), new_tls: Some(137444657727232)^[[0m^M ^[[0m[2023-09-05T14:38:10.936Z][DEBUG][T5][#2][····Mmap] mmap: addr: 0x0, size: 0x8000000, perms: NONE, flags: MAP_PRIVATE | MAP_ANONYMOUS | MAP_NORESERVE^[[0m^M ^[[32m[2023-09-05T14:38:10.936Z][ INFO][T4][#14][···Clone] Thread created: tid = 6^[[0m^M ^[[0m[2023-09-05T14:38:10.936Z][DEBUG][T5][#3][··Munmap] munmap: addr: 0x7d0154001000, size: 0x3fff000^[[0m^M ^[[0m[2023-09-05T14:38:10.936Z][DEBUG][T4][#15][RtSigprocmask] do_rt_sigprocmask: op_and_set: Some((SetMask, SigSet { [] })), oldset: None^[[0m^M ^[[0m[2023-09-05T14:38:10.936Z][DEBUG][T3][#137][··Tgkill] do_tgkill: pid: Some(2), tid: 4, signum: SigNum (#23 = SIGURG)^[[0m^M ^[[0m[2023-09-05T14:38:10.937Z][DEBUG][T4][#17][···Futex] futex_wake_bitset addr: 0x7d0087535648, max_count: 1, bitset: 0xffffffff^[[0m^M

ialtery avatar Sep 05 '23 14:09 ialtery