Project

General

Profile

Actions
Copy link

Bug #1091

closed

lttng list --kernel --syscall fails on s390x with "Not enough memory" error

Added by Colin King over 7 years ago. Updated over 7 years ago.

Status:
Resolved
Priority:
Normal
Assignee:
-
Target version:
-
Start date:
03/13/2017
Due date:
% Done:

100%

Estimated time:

Description

lttng tools, tip commit d30b2041e3a5db2d1671219ea845057cb3dd3082, built on s390x (Ubuntu 4.10.0-13-generic).

How to reproduce:

sudo ./src/bin/lttng/lttng list --kernel --syscall
Error: Unable to list system calls: Not enough memory
Error: Command error

Expected result:

list of syscalls, not an out of memory error.

strace of failure (near the end):

socket(AF_UNIX, SOCK_STREAM, 0) = 3
connect(3, {sa_family=AF_UNIX, sun_path="/var/run/lttng/client-lttng-sessiond"}, 110) = 0
geteuid() = 0
getegid() = 0
sendmsg(3, {msg_name=NULL, msg_namelen=0, msg_iov=[{iov_base="\0\0\0\4\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., iov_len=13156}], msg_iovlen=1, msg_control=[{cmsg_len=28, cmsg_level=SOL_SOCKET, cmsg_type=SCM_CREDENTIALS, cmsg_data={pid=9883, uid=0, gid=0}}], msg_controllen=28, msg_flags=0}, 0) = 13156
recvmsg(3, {msg_name=NULL, msg_namelen=0, msg_iov=[{iov_base="\0\0\0\4\0\0\0\32\0\0\0\0\0\0\0\0\0\0\0\0", iov_len=20}], msg_iovlen=1, msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 20
shutdown(3, SHUT_RDWR) = 0
close(3) = 0
write(2, "Error: Unable to list system cal"..., 54Error: Unable to list system calls: Not enough memory
) = 54
geteuid() = 0
getuid() = 0
getegid() = 0
getgid() = 0
write(2, "Error: Command error\n", 21Error: Command error
) = 21
exit_group(1) = ?

gcc v
Using built-in specs.
COLLECT_GCC=gcc
COLLECT_LTO_WRAPPER=/usr/lib/gcc/s390x-linux-gnu/6/lto-wrapper
Target: s390x-linux-gnu
Configured with: ../src/configure -v --with-pkgversion='Ubuntu 6.3.0-8ubuntu1' --with-bugurl=file:///usr/share/doc/gcc-6/README.Bugs --enable-languages=c,ada,c++,java,go,d,fortran,objc,obj-c++ --prefix=/usr --program-suffix=-6 --program-prefix=s390x-linux-gnu --enable-shared --enable-linker-build-id --libexecdir=/usr/lib --without-included-gettext --enable-threads=posix --libdir=/usr/lib --enable-nls --with-sysroot=/ --enable-clocale=gnu --enable-libstdcxx-debug --enable-libstdcxx-time=yes --with-default-libstdcxx-abi=new --enable-gnu-unique-object --disable-libsanitizer --disable-libquadmath --enable-plugin --enable-default-pie --with-system-zlib --disable-browser-plugin --enable-java-awt=gtk --enable-gtk-cairo --with-java-home=/usr/lib/jvm/java-1.5.0-gcj-6-s390x/jre --enable-java-home --with-jvm-root-dir=/usr/lib/jvm/java-1.5.0-gcj-6-s390x --with-jvm-jar-dir=/usr/lib/jvm-exports/java-1.5.0-gcj-6-s390x --with-arch-directory=s390x --with-ecj-jar=/usr/share/java/eclipse-ecj.jar --enable-objc-gc=auto --enable-multiarch --disable-werror --with-arch=zEC12 --with-long-double-128 --enable-multilib --enable-checking=release --build=s390x-linux-gnu --host=s390x-linux-gnu --target=s390x-linux-gnu
Thread model: posix
gcc version 6.3.0 20170221 (Ubuntu 6.3.0-8ubuntu1)

end of sudo lttng-sessiond -vvv shows that we have a vmalloc failure:

DEBUG1 - 14:25:37.954060 [10037/10081]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at main.c:1891)
DEBUG1 - 14:25:37.954072 [10037/10082]: Got the wait shm fd 33 (in get_wait_shm() at shm.c:115)
DEBUG1 - 14:25:37.954074 [10037/10086]: Updating kernel poll set (in update_kernel_poll() at main.c:889)
DEBUG1 - 14:25:37.954100 [10037/10086]: Thread kernel polling (in thread_manage_kernel() at main.c:1099)
DEBUG1 - 14:25:37.953832 [10037/10087]: [load-session-thread] Load session (in thread_load_session() at load-session-thread.c:91)
DEBUG1 - 14:25:37.954079 [10037/10082]: Futex wait update active 1 (in futex_wait_update() at futex.c:66)
DEBUG1 - 14:25:37.954132 [10037/10082]: Accepting application registration (in thread_registration_apps() at main.c:2145)
DEBUG3 - 14:25:37.953889 [10037/10084]: [ust-thread] Manage notify polling (in ust_thread_manage_notify() at ust-thread.c:69)
DEBUG1 - 14:25:42.577842 [10037/10080]: Wait for client response (in thread_manage_clients() at main.c:4444)
DEBUG1 - 14:25:42.577865 [10037/10080]: Receiving data from client ... (in thread_manage_clients() at main.c:4489)
DEBUG1 - 14:25:42.577999 [10037/10080]: Processing client command 4 (in process_client_msg() at main.c:2980)
DEBUG1 - 14:25:42.578010 [10037/10080]: Syscall table listing. (in syscall_table_list() at syscall.c:280)
PERROR - 14:25:42.578021 [10037/10080]: syscall table list zmalloc: Cannot allocate memory (in syscall_table_list() at syscall.c:291)
DEBUG3 - 14:25:42.578027 [10037/10080]: Destroying syscall hash table. (in destroy_syscall_ht() at syscall.c:159)
DEBUG1 - 14:25:42.578030 [10037/10080]: Missing llm structure. Allocating one. (in process_client_msg() at main.c:4124)
DEBUG1 - 14:25:42.578032 [10037/10080]: Sending response (size: 20, retcode: Not enough memory (26)) (in thread_manage_clients() at main.c:4539)
DEBUG1 - 14:25:42.578041 [10037/10080]: Clean command context structure (in clean_command_ctx() at main.c:785)
DEBUG1 - 14:25:42.578043 [10037/10080]: Accepting client command ... (in thread_manage_clients() at main.c:4392)

Actions
Copy link
 #2

Updated by Jonathan Rajotte Julien over 7 years ago

  • Status changed from New to Feedback

Hi Colin,

As you indicated it seems that an actual allocation problem occurred on:

syscall table list zmalloc: Cannot allocate memory (in syscall_table_list() at syscall.c:291)

From the code:

                                                                        
/*                                                                      
 * Allocate at least the number of total syscall we have even if some of
 * them might not be valid. The count below will make sure to return the
 * right size of the events array.                                      
 */                                                                     
events = zmalloc(syscall_table_nb_entry * sizeof(*events));             
if (!events) {                                                          
        PERROR("syscall table list zmalloc");                           
        ret = -LTTNG_ERR_NOMEM;                                         
        goto error;                                                     
}

syscall_table_nb_entry is assigned in syscall_init_table.

Could you link the full sessiond log and strace log? Also if possible determine the value of syscall_table_nb_entry?

Cheers

Actions
Copy link
 #3

Updated by Jonathan Rajotte Julien over 7 years ago

Hi Colin,

Please take a look at the following patch https://lists.lttng.org/pipermail/lttng-dev/2017-March/026959.html

Could you report to us if it fixes your issue?

Please note that s390 is not supported/tested by LTTng-modules.

You probably encountered this bug since no syscalls tracepoints are getting defined on lttng-modules side. Please take a look at this git subtree [1] and the following readme [2] on how to generate it and add the headers for the S390 architecture if you wish to.

Cheers

[1] https://github.com/lttng/lttng-modules/tree/master/instrumentation/syscalls
[2] https://github.com/lttng/lttng-modules/blob/master/instrumentation/syscalls/README

Actions
Copy link
 #4

Updated by Colin King over 7 years ago

OK, this fixing things, I'm now seeing:

$ sudo lttng list --kernel --syscall
System calls:
-------------

..but no system calls. Is that expected?

Actions
Copy link
 #5

Updated by Colin King over 7 years ago

Log when running sudo lttng list --kernel --syscall

---------------- 8< --------------------
sudo lttng-sessiond -vvv | tee sessiond.log
DEBUG1 - 16:27:22.327577 [3564/3564]: Resetting run_as worker signal handlers to default (in reset_sighandler() at runas.c:537)
DEBUG1 - 16:27:22.327666 [3564/3564]: run_as signal handler set for SIGTERM and SIGINT (in set_worker_sighandlers() at runas.c:598)
DEBUG1 - 16:27:22.327718 [3563/3563]: Health check time delta in seconds set to 20 (in health_init() at health.c:73)
DEBUG3 - 16:27:22.327803 [3563/3563]: Creating LTTng run directory: /var/run/lttng (in create_lttng_rundir() at main.c:5263)
DEBUG1 - 16:27:22.327814 [3563/3565]: [ht-thread] startup. (in thread_ht_cleanup() at ht-cleanup.c:101)
DEBUG2 - 16:27:22.327852 [3563/3563]: Kernel consumer err path: /var/run/lttng/kconsumerd/error (in main() at main.c:5686)
DEBUG2 - 16:27:22.327910 [3563/3563]: Kernel consumer cmd path: /var/run/lttng/kconsumerd/command (in main() at main.c:5688)
DEBUG1 - 16:27:22.327891 [3563/3565]: epoll set max size is 417320 (in compat_epoll_set_max_size() at compat-epoll.c:288)
DEBUG1 - 16:27:22.327947 [3563/3563]: Client socket path /var/run/lttng/client-lttng-sessiond (in main() at main.c:5768)
DEBUG1 - 16:27:22.328017 [3563/3563]: Application socket path /var/run/lttng/lttng-ust-sock-7 (in main() at main.c:5769)
DEBUG1 - 16:27:22.328044 [3563/3563]: Application wait path /lttng-ust-wait-7 (in main() at main.c:5770)
DEBUG1 - 16:27:22.328070 [3563/3563]: LTTng run directory path: /var/run/lttng (in main() at main.c:5771)
DEBUG3 - 16:27:22.328003 [3563/3565]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:124)
DEBUG2 - 16:27:22.328098 [3563/3563]: UST consumer 32 bits err path: /var/run/lttng/ustconsumerd32/error (in main() at main.c:5790)
DEBUG2 - 16:27:22.328150 [3563/3563]: UST consumer 32 bits cmd path: /var/run/lttng/ustconsumerd32/command (in main() at main.c:5792)
DEBUG2 - 16:27:22.328181 [3563/3563]: UST consumer 64 bits err path: /var/run/lttng/ustconsumerd64/error (in main() at main.c:5811)
DEBUG2 - 16:27:22.328205 [3563/3563]: UST consumer 64 bits cmd path: /var/run/lttng/ustconsumerd64/command (in main() at main.c:5813)
DEBUG3 - 16:27:22.328267 [3563/3563]: Created hashtable size 4 at 0x2aa6aa4af40 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 16:27:22.328294 [3563/3563]: Created hashtable size 4 at 0x2aa6aa4b380 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 16:27:22.328319 [3563/3563]: Created hashtable size 4 at 0x2aa6aa4b7c0 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 16:27:22.328344 [3563/3563]: Created hashtable size 4 at 0x2aa6aa4bc00 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG2 - 16:27:22.328370 [3563/3563]: Creating consumer directory: /var/run/lttng/kconsumerd (in set_consumer_sockets() at main.c:5305)
DEBUG1 - 16:27:22.351843 [3563/3563]: Modprobe successfully lttng-ring-buffer-client-discard (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.353959 [3563/3563]: Modprobe successfully lttng-ring-buffer-client-overwrite (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.356029 [3563/3563]: Modprobe successfully lttng-ring-buffer-metadata-client (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.358123 [3563/3563]: Modprobe successfully lttng-ring-buffer-client-mmap-discard (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.360218 [3563/3563]: Modprobe successfully lttng-ring-buffer-client-mmap-overwrite (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.362349 [3563/3563]: Modprobe successfully lttng-ring-buffer-metadata-mmap-client (in modprobe_lttng() at modprobe.c:200)
DEBUG2 - 16:27:22.362460 [3563/3563]: Kernel tracer version validated (2.9, ABI 2.2) (in kernel_validate_version() at kernel.c:840)
DEBUG1 - 16:27:22.362679 [3563/3563]: libkmod: could not find module by name='lttng_probe_asoc'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.362711 [3563/3563]: Unable to load optional module lttng-probe-asoc; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.363832 [3563/3563]: Modprobe successfully lttng-probe-block (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.363878 [3563/3563]: libkmod: could not find module by name='lttng_probe_btrfs'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.363909 [3563/3563]: Unable to load optional module lttng-probe-btrfs; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.364976 [3563/3563]: Modprobe successfully lttng-probe-compaction (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.365021 [3563/3563]: libkmod: could not find module by name='lttng_probe_ext3'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.365052 [3563/3563]: Unable to load optional module lttng-probe-ext3; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.365088 [3563/3563]: libkmod: could not find module by name='lttng_probe_ext4'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.365119 [3563/3563]: Unable to load optional module lttng-probe-ext4; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.366209 [3563/3563]: Modprobe successfully lttng-probe-gpio (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.366255 [3563/3563]: libkmod: could not find module by name='lttng_probe_i2c'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.366284 [3563/3563]: Unable to load optional module lttng-probe-i2c; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.367385 [3563/3563]: Modprobe successfully lttng-probe-irq (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.367432 [3563/3563]: libkmod: could not find module by name='lttng_probe_jbd'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.367463 [3563/3563]: Unable to load optional module lttng-probe-jbd; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.368572 [3563/3563]: Modprobe successfully lttng-probe-jbd2 (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.369693 [3563/3563]: Modprobe successfully lttng-probe-kmem (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.370925 [3563/3563]: Modprobe successfully lttng-probe-kvm (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.370997 [3563/3563]: libkmod: could not find module by name='lttng_probe_kvm_x86'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.371031 [3563/3563]: Unable to load optional module lttng-probe-kvm-x86; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.371083 [3563/3563]: libkmod: could not find module by name='lttng_probe_kvm_x86_mmu'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.371124 [3563/3563]: Unable to load optional module lttng-probe-kvm-x86-mmu; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.371178 [3563/3563]: libkmod: could not find module by name='lttng_probe_lock'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.371219 [3563/3563]: Unable to load optional module lttng-probe-lock; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.372471 [3563/3563]: Modprobe successfully lttng-probe-module (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.373575 [3563/3563]: Modprobe successfully lttng-probe-napi (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.374686 [3563/3563]: Modprobe successfully lttng-probe-net (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.375961 [3563/3563]: Modprobe successfully lttng-probe-power (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.377047 [3563/3563]: Modprobe successfully lttng-probe-printk (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.378167 [3563/3563]: Modprobe successfully lttng-probe-random (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.379273 [3563/3563]: Modprobe successfully lttng-probe-rcu (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.379319 [3563/3563]: libkmod: could not find module by name='lttng_probe_regmap'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.379347 [3563/3563]: Unable to load optional module lttng-probe-regmap; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.379387 [3563/3563]: libkmod: could not find module by name='lttng_probe_regulator'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.379419 [3563/3563]: Unable to load optional module lttng-probe-regulator; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.379454 [3563/3563]: libkmod: could not find module by name='lttng_probe_rpm'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.379486 [3563/3563]: Unable to load optional module lttng-probe-rpm; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.380714 [3563/3563]: Modprobe successfully lttng-probe-sched (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.381837 [3563/3563]: Modprobe successfully lttng-probe-scsi (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.382949 [3563/3563]: Modprobe successfully lttng-probe-signal (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.384055 [3563/3563]: Modprobe successfully lttng-probe-skb (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.385162 [3563/3563]: Modprobe successfully lttng-probe-sock (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.386287 [3563/3563]: Modprobe successfully lttng-probe-statedump (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.387410 [3563/3563]: Modprobe successfully lttng-probe-sunrpc (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.388535 [3563/3563]: Modprobe successfully lttng-probe-timer (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.389642 [3563/3563]: Modprobe successfully lttng-probe-udp (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.390772 [3563/3563]: Modprobe successfully lttng-probe-vmscan (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.390817 [3563/3563]: libkmod: could not find module by name='lttng_probe_v4l2'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.390847 [3563/3563]: Unable to load optional module lttng-probe-v4l2; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.391951 [3563/3563]: Modprobe successfully lttng-probe-workqueue (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.393112 [3563/3563]: Modprobe successfully lttng-probe-writeback (in modprobe_lttng() at modprobe.c:200)
DEBUG1 - 16:27:22.393158 [3563/3563]: libkmod: could not find module by name='lttng_probe_x86_irq_vectors'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.393189 [3563/3563]: Unable to load optional module lttng-probe-x86-irq-vectors; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.393225 [3563/3563]: libkmod: could not find module by name='lttng_probe_x86_exceptions'
(in log_kmod() at modprobe.c:116)
DEBUG1 - 16:27:22.393256 [3563/3563]: Unable to load optional module lttng-probe-x86-exceptions; continuing (in modprobe_lttng() at modprobe.c:196)
DEBUG1 - 16:27:22.393302 [3563/3563]: Kernel tracer fd 13 (in init_kernel_tracer() at main.c:2728)
DEBUG3 - 16:27:22.393334 [3563/3563]: Syscall init system call table (in syscall_init_table() at syscall.c:53)
DEBUG2 - 16:27:22.393388 [3563/3563]: Creating consumer directory: /var/run/lttng/ustconsumerd64 (in set_consumer_sockets() at main.c:5305)
DEBUG2 - 16:27:22.393453 [3563/3563]: Creating consumer directory: /var/run/lttng/ustconsumerd32 (in set_consumer_sockets() at main.c:5305)
DEBUG3 - 16:27:22.393521 [3563/3563]: Session daemon client socket 16 and application socket 17 created (in init_daemon_socket() at main.c:5171)
DEBUG1 - 16:27:22.393576 [3563/3563]: All permissions are set (in set_permissions() at main.c:5251)
DEBUG3 - 16:27:22.393614 [3563/3563]: Created hashtable size 4 at 0x2aa6aa4ec90 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 16:27:22.393644 [3563/3563]: Global buffer per UID registry initialized (in buffer_reg_init_uid_registry() at buffer-registry.c:99)
DEBUG3 - 16:27:22.393672 [3563/3563]: Created hashtable size 4 at 0x2aa6aa4f0a0 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 16:27:22.393702 [3563/3563]: Global buffer per PID registry initialized (in buffer_reg_init_pid_registry() at buffer-registry.c:228)
DEBUG1 - 16:27:22.393728 [3563/3563]: Command subsystem initialized (in cmd_init() at cmd.c:4006)
DEBUG1 - 16:27:22.393770 [3563/3563]: Pid 3563 written in file /var/run/lttng/lttng-sessiond.pid (in utils_create_pid_file() at utils.c:507)
DEBUG1 - 16:27:22.393806 [3563/3563]: Pid 5345 written in file /var/run/lttng/agent.port (in utils_create_pid_file() at utils.c:507)
DEBUG1 - 16:27:22.393848 [3563/3563]: TCP inet operation timeout set to 216 sec (in lttcomm_inet_init() at inet.c:547)
DEBUG1 - 16:27:22.393904 [3563/3603]: [thread] Manage health check started (in thread_manage_health() at main.c:4154)
DEBUG1 - 16:27:22.394006 [3563/3606]: [thread] Manage application registration started (in thread_registration_apps() at main.c:2108)
DEBUG1 - 16:27:22.394007 [3563/3604]: [thread] Manage client started (in thread_manage_clients() at main.c:4345)
DEBUG1 - 16:27:22.394010 [3563/3605]: [thread] Dispatch UST command started (in thread_dispatch_ust_registration() at main.c:1868)
DEBUG1 - 16:27:22.394591 [3563/3605]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:78)
DEBUG1 - 16:27:22.394614 [3563/3605]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at main.c:1891)
DEBUG1 - 16:27:22.394160 [3563/3607]: [thread] Manage application started (in thread_manage_apps() at main.c:1556)
DEBUG1 - 16:27:22.394736 [3563/3607]: Apps thread polling (in thread_manage_apps() at main.c:1586)
DEBUG1 - 16:27:22.394317 [3563/3606]: Notifying applications of session daemon state: 1 (in notify_ust_apps() at main.c:805)
DEBUG1 - 16:27:22.394949 [3563/3606]: Got the wait shm fd 29 (in get_wait_shm() at shm.c:115)
DEBUG1 - 16:27:22.394984 [3563/3606]: Futex wait update active 1 (in futex_wait_update() at futex.c:66)
DEBUG1 - 16:27:22.395013 [3563/3606]: Accepting application registration (in thread_registration_apps() at main.c:2145)
DEBUG1 - 16:27:22.394341 [3563/3608]: [ust-thread] Manage application notify command (in ust_thread_manage_notify() at ust-thread.c:40)
DEBUG3 - 16:27:22.395083 [3563/3608]: [ust-thread] Manage notify polling (in ust_thread_manage_notify() at ust-thread.c:69)
DEBUG1 - 16:27:22.394547 [3563/3609]: [agent-thread] Manage agent application registration. (in agent_thread_manage_registration() at agent-thread.c:236)
DEBUG1 - 16:27:22.394557 [3563/3603]: Health check ready (in thread_manage_health() at main.c:4216)
DEBUG1 - 16:27:22.394562 [3563/3604]: Accepting client command ... (in thread_manage_clients() at main.c:4392)
DEBUG1 - 16:27:22.394900 [3563/3611]: [load-session-thread] Load session (in thread_load_session() at load-session-thread.c:91)
DEBUG3 - 16:27:22.395150 [3563/3609]: URI string: tcp://localhost (in uri_parse() at uri.c:324)
DEBUG2 - 16:27:22.395321 [3563/3609]: IP address resolved to 127.0.0.1 (in set_ip_address() at uri.c:165)
DEBUG3 - 16:27:22.395346 [3563/3609]: URI dtype: 1, proto: 1, host: localhost, subdir: , ctrl: 0, data: 0 (in uri_parse() at uri.c:538)
DEBUG1 - 16:27:22.395382 [3563/3609]: [agent-thread] Listening on TCP port 5345 and socket 33 (in init_tcp_socket() at agent-thread.c:119)
DEBUG3 - 16:27:22.395411 [3563/3609]: [agent-thread] Manage agent polling (in agent_thread_manage_registration() at agent-thread.c:263)
DEBUG1 - 16:27:22.395154 [3563/3610]: [thread] Thread manage kernel started (in thread_manage_kernel() at main.c:1054)
DEBUG1 - 16:27:22.395477 [3563/3610]: Updating kernel poll set (in update_kernel_poll() at main.c:889)
DEBUG1 - 16:27:22.395507 [3563/3610]: Thread kernel polling (in thread_manage_kernel() at main.c:1099)
DEBUG1 - 16:27:26.333639 [3563/3604]: Wait for client response (in thread_manage_clients() at main.c:4444)
DEBUG1 - 16:27:26.333803 [3563/3604]: Receiving data from client ... (in thread_manage_clients() at main.c:4489)
DEBUG1 - 16:27:26.333846 [3563/3604]: Processing client command 4 (in process_client_msg() at main.c:2980)
DEBUG1 - 16:27:26.333879 [3563/3604]: Syscall table listing. (in syscall_table_list() at syscall.c:287)
DEBUG3 - 16:27:26.333910 [3563/3604]: Created hashtable size 4 at 0x3ff70003ce0 of type 0 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 16:27:26.333937 [3563/3604]: Destroying syscall hash table. (in destroy_syscall_ht() at syscall.c:166)
DEBUG1 - 16:27:26.333968 [3563/3604]: Sending response (size: 20, retcode: Success (10)) (in thread_manage_clients() at main.c:4539)
DEBUG3 - 16:27:26.333986 [3563/3565]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:128)
DEBUG1 - 16:27:26.333995 [3563/3604]: Clean command context structure (in clean_command_ctx() at main.c:785)
DEBUG1 - 16:27:26.334127 [3563/3604]: Accepting client command ... (in thread_manage_clients() at main.c:4392)
DEBUG3 - 16:27:26.334113 [3563/3565]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:124)

Actions
Copy link
 #6

Updated by Colin King over 7 years ago

sudo strace f lttng list --kernel --syscall
execve("/usr/bin/lttng", ["lttng", "list", "--kernel", "--syscall"], [/* 14 vars */]) = 0
brk(NULL) = 0x2aa2bf7c000
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3ffbd67c000
mmap(NULL, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3ffbd676000
access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=45075, ...}) = 0
mmap(NULL, 45075, PROT_READ, MAP_PRIVATE, 3, 0) = 0x3ffbd580000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/usr/lib/s390x-linux-gnu/liblttng-ctl.so.0", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\0\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\0\324`"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=280584, ...}) = 0
mmap(NULL, 287248, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbd500000
mmap(0x3ffbd543000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x42000) = 0x3ffbd543000
mmap(0x3ffbd546000, 528, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3ffbd546000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/usr/lib/s390x-linux-gnu/libxml2.so.2", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\0\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\2\342\210"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=1907568, ...}) = 0
mmap(NULL, 1914704, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbd300000
mmap(0x3ffbd4c9000, 40960, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1c8000) = 0x3ffbd4c9000
mmap(0x3ffbd4d3000, 1872, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3ffbd4d3000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/s390x-linux-gnu/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\3\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\0d\350"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=144144, ...}) = 0
mmap(NULL, 128040, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbd280000
mmap(0x3ffbd29a000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x19000) = 0x3ffbd29a000
mmap(0x3ffbd29c000, 13352, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3ffbd29c000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/s390x-linux-gnu/librt.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\3\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\0$P"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=35712, ...}) = 0
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3ffbd674000
mmap(NULL, 39784, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbd200000
mmap(0x3ffbd208000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7000) = 0x3ffbd208000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/s390x-linux-gnu/libpopt.so.0", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\0\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\0#\320"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=52208, ...}) = 0
mmap(NULL, 54400, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbd180000
mmap(0x3ffbd18c000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xb000) = 0x3ffbd18c000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/s390x-linux-gnu/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\3\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\2)P"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=1660056, ...}) = 0
mmap(NULL, 1669768, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbcf80000
mmap(0x3ffbd10e000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x18d000) = 0x3ffbd10e000
mmap(0x3ffbd114000, 14984, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3ffbd114000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/usr/lib/s390x-linux-gnu/liburcu-common.so.4", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\0\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\0\23\330"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=18432, ...}) = 0
mmap(NULL, 20744, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbcf00000
mmap(0x3ffbcf04000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3000) = 0x3ffbcf04000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/usr/lib/s390x-linux-gnu/liburcu.so.4", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\0\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\0\33\360"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=31512, ...}) = 0
mmap(NULL, 33960, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbce80000
mmap(0x3ffbce87000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6000) = 0x3ffbce87000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/usr/lib/s390x-linux-gnu/liburcu-cds.so.4", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\0\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\0\30\350"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=30944, ...}) = 0
mmap(NULL, 33184, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbce00000
mmap(0x3ffbce07000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6000) = 0x3ffbce07000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/s390x-linux-gnu/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\0\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\0\16\360"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=14600, ...}) = 0
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3ffbd672000
mmap(NULL, 16712, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbcd80000
mmap(0x3ffbcd83000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x3ffbcd83000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/usr/lib/s390x-linux-gnu/libicuuc.so.57", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\0\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\5\341H"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=1859720, ...}) = 0
mmap(NULL, 1872448, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbcb80000
mprotect(0x3ffbcd34000, 4096, PROT_NONE) = 0
mmap(0x3ffbcd35000, 73728, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1b4000) = 0x3ffbcd35000
mmap(0x3ffbcd47000, 8768, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3ffbcd47000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/s390x-linux-gnu/libz.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\0\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\0 \210"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=116928, ...}) = 0
mmap(NULL, 119000, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbcb00000
mmap(0x3ffbcb1c000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1b000) = 0x3ffbcb1c000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/s390x-linux-gnu/liblzma.so.5", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\0\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\0,p"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=166320, ...}) = 0
mmap(NULL, 168392, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbca80000
mmap(0x3ffbcaa8000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x27000) = 0x3ffbcaa8000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/s390x-linux-gnu/libm.so.6", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\0\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\0[\330"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=684248, ...}) = 0
mmap(NULL, 684784, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbc980000
mmap(0x3ffbca26000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xa5000) = 0x3ffbca26000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/usr/lib/s390x-linux-gnu/libicudata.so.57", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\0\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\0\5\200"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=25675608, ...}) = 0
mmap(NULL, 25677912, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbb100000
mmap(0x3ffbc97c000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x187b000) = 0x3ffbc97c000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/usr/lib/s390x-linux-gnu/libstdc++.so.6", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\3\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\t\227\250"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=1850264, ...}) = 0
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3ffbd670000
mmap(NULL, 1865080, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbaf00000
mprotect(0x3ffbb0b7000, 4096, PROT_NONE) = 0
mmap(0x3ffbb0b8000, 53248, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1b7000) = 0x3ffbb0b8000
mmap(0x3ffbb0c5000, 9592, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3ffbb0c5000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/s390x-linux-gnu/libgcc_s.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\2\1\0\0\0\0\0\0\0\0\0\0\3\0\26\0\0\0\1\0\0\0\0\0\0$\270"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=67864, ...}) = 0
mmap(NULL, 70616, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3ffbae80000
mmap(0x3ffbae90000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xf000) = 0x3ffbae90000
close(3) = 0
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3ffbd66e000
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3ffbd66c000
mprotect(0x3ffbd10e000, 16384, PROT_READ) = 0
mprotect(0x3ffbae90000, 4096, PROT_READ) = 0
mprotect(0x3ffbca26000, 4096, PROT_READ) = 0
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3ffbd66a000
mmap(NULL, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3ffbd667000
mprotect(0x3ffbb0b8000, 36864, PROT_READ) = 0
mprotect(0x3ffbc97c000, 4096, PROT_READ) = 0
mprotect(0x3ffbd29a000, 4096, PROT_READ) = 0
mprotect(0x3ffbcd83000, 4096, PROT_READ) = 0
mprotect(0x3ffbcaa8000, 4096, PROT_READ) = 0
mprotect(0x3ffbcb1c000, 4096, PROT_READ) = 0
mprotect(0x3ffbcd35000, 69632, PROT_READ) = 0
mprotect(0x3ffbce07000, 4096, PROT_READ) = 0
mprotect(0x3ffbce87000, 4096, PROT_READ) = 0
mprotect(0x3ffbcf04000, 4096, PROT_READ) = 0
mprotect(0x3ffbd18c000, 4096, PROT_READ) = 0
mprotect(0x3ffbd208000, 4096, PROT_READ) = 0
mprotect(0x3ffbd4c9000, 32768, PROT_READ) = 0
mprotect(0x3ffbd543000, 8192, PROT_READ) = 0
mprotect(0x2aa22a45000, 12288, PROT_READ) = 0
mprotect(0x3ffbd624000, 4096, PROT_READ) = 0
munmap(0x3ffbd580000, 45075) = 0
set_tid_address(0x3ffbd66c9d0) = 3618
set_robust_list(0x3ffbd66c9e0, 24) = 0
rt_sigaction(SIGRTMIN, {sa_handler=0x3ffbd285f38, sa_mask=[], sa_flags=SA_SIGINFO}, NULL, 8) = 0
rt_sigaction(SIGRT_1, {sa_handler=0x3ffbd285ff0, sa_mask=[], sa_flags=SA_RESTART|SA_SIGINFO}, NULL, 8) = 0
rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
brk(NULL) = 0x2aa2bf7c000
brk(0x2aa2bfae000) = 0x2aa2bfae000
membarrier(MEMBARRIER_CMD_QUERY, 0) = 0x1 (MEMBARRIER_CMD_SHARED)
getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
rt_sigaction(SIGTERM, {sa_handler=0x2aa22a24eb0, sa_mask=[], sa_flags=0}, NULL, 8) = 0
geteuid() = 0
getuid() = 0
getegid() = 0
getgid() = 0
getuid() = 0
socket(AF_UNIX, SOCK_STREAM, 0) = 3
connect(3, {sa_family=AF_UNIX, sun_path="/var/run/lttng/client-lttng-sessiond"}, 110) = 0
geteuid() = 0
getegid() = 0
sendmsg(3, {msg_name=NULL, msg_namelen=0, msg_iov=[{iov_base="\0\0\0\4\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., iov_len=13156}], msg_iovlen=1, msg_control=[{cmsg_len=28, cmsg_level=SOL_SOCKET, cmsg_type=SCM_CREDENTIALS, cmsg_data={pid=3618, uid=0, gid=0}}], msg_controllen=28, msg_flags=0}, 0) = 13156
recvmsg(3, {msg_name=NULL, msg_namelen=0, msg_iov=[{iov_base="\0\0\0\4\0\0\0\n\0\0\0\0\0\0\0\0\0\0\0\0", iov_len=20}], msg_iovlen=1, msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 20
shutdown(3, SHUT_RDWR) = 0
close(3) = 0
fstat(1, {st_mode=S_IFCHR|0620, st_rdev=makedev(136, 0), ...}) = 0
write(1, "System calls:\n", 14System calls:
) = 14
write(1, "------------\n", 14-------------
) = 14
write(1, "\n", 1
) = 1
exit_group(0) = ?
++ exited with 0 ++

Actions
Copy link
 #7

Updated by Jonathan Rajotte Julien over 7 years ago

Colin King wrote:

OK, this fixing things, I'm now seeing:

$ sudo lttng list --kernel --syscall
System calls:
-------------

..but no system calls. Is that expected?

Yes since the s390/x architecture does not have a syscalls defined in lttng-modules. See previous links I sent.


Please take a look at this git subtree [1] and the following readme [2] on how to generate it and add the headers for the S390/x architecture if you wish to.

Cheers

[1] https://github.com/lttng/lttng-modules/tree/master/instrumentation/syscalls
[2] https://github.com/lttng/lttng-modules/blob/master/instrumentation/syscalls/README
Actions
Copy link
 #8

Updated by Jonathan Rajotte Julien over 7 years ago

Hi Colin,

Could you reply on the mailing list [1] with a tested-by signature regarding the fix?

[1] https://lists.lttng.org/pipermail/lttng-dev/2017-March/026966.html

Cheers

Actions
Copy link
 #9

Updated by Jonathan Rajotte Julien over 7 years ago

  • Status changed from Feedback to Resolved
  • % Done changed from 0 to 100
Actions
Copy link

Also available in: Atom PDF