API tools faq
paste
Advertisement
Guest User

Untitled

a guest
Aug 4th, 2020
55
0
Never
Add comment
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
text 82.06 KB | None | 0 0
raw download clone embed print report
  1. DEBUG1 - 08:04:44.431006915 [574/574]: [sessiond configuration]
  2. DEBUG1 - 08:04:44.431168140 [574/574]: version 2.12.1
  3. DEBUG1 - 08:04:44.431184006 [574/574]: verbose: 3
  4. DEBUG1 - 08:04:44.431198472 [574/574]: verbose consumer: 1
  5. DEBUG1 - 08:04:44.431211421 [574/574]: quiet mode: False
  6. DEBUG1 - 08:04:44.431227987 [574/574]: agent_tcp_port: [5345, 5354]
  7. DEBUG1 - 08:04:44.431241053 [574/574]: application socket timeout: 5
  8. DEBUG1 - 08:04:44.431255985 [574/574]: no-kernel: False
  9. DEBUG1 - 08:04:44.431271501 [574/574]: background: True
  10. DEBUG1 - 08:04:44.431287717 [574/574]: daemonize: False
  11. DEBUG1 - 08:04:44.431303466 [574/574]: signal parent on start: False
  12. DEBUG1 - 08:04:44.431318165 [574/574]: tracing group name: tracing
  13. DEBUG1 - 08:04:44.431335198 [574/574]: kmod_probe_list: None
  14. DEBUG1 - 08:04:44.431349897 [574/574]: kmod_extra_probe_list: None
  15. DEBUG1 - 08:04:44.431366929 [574/574]: rundir: /var/run/lttng
  16. DEBUG1 - 08:04:44.431383845 [574/574]: application socket path: /var/run/lttng/lttng-ust-sock-8
  17. DEBUG1 - 08:04:44.431401694 [574/574]: client socket path: /var/run/lttng/client-lttng-sessiond
  18. DEBUG1 - 08:04:44.431417560 [574/574]: wait shm path: /lttng-ust-wait-8
  19. DEBUG1 - 08:04:44.431435409 [574/574]: health socket path: /var/run/lttng/sessiond-health
  20. DEBUG1 - 08:04:44.431451508 [574/574]: LTTNG_UST_CLOCK_PLUGIN: None
  21. DEBUG1 - 08:04:44.431467607 [574/574]: pid file path: /var/run/lttng/lttng-sessiond.pid
  22. DEBUG1 - 08:04:44.431483240 [574/574]: lock file path: /var/run/lttng/lttng-sessiond.lck
  23. DEBUG1 - 08:04:44.431496306 [574/574]: session load path: None
  24. DEBUG1 - 08:04:44.431513105 [574/574]: agent port file path: /var/run/lttng/agent.port
  25. DEBUG1 - 08:04:44.431531187 [574/574]: consumerd32 path: /var/run/lttng/ustconsumerd32
  26. DEBUG1 - 08:04:44.431549620 [574/574]: consumerd32 bin path: Unknown
  27. DEBUG1 - 08:04:44.431567935 [574/574]: consumerd32 lib dir: Unknown
  28. DEBUG1 - 08:04:44.431585318 [574/574]: consumerd32 err unix sock path:/var/run/lttng/ustconsumerd32/error
  29. DEBUG1 - 08:04:44.431599434 [574/574]: consumerd32 cmd unix sock path:/var/run/lttng/ustconsumerd32/command
  30. DEBUG1 - 08:04:44.431614250 [574/574]: consumerd64 path: /var/run/lttng/ustconsumerd64
  31. DEBUG1 - 08:04:44.431631865 [574/574]: consumerd64 bin path: /./lib/lttng/libexec/lttng-consumerd
  32. DEBUG1 - 08:04:44.431649481 [574/574]: consumerd64 lib dir: /./lib
  33. DEBUG1 - 08:04:44.431666980 [574/574]: consumerd64 err unix sock path:/var/run/lttng/ustconsumerd64/error
  34. DEBUG1 - 08:04:44.431684596 [574/574]: consumerd64 cmd unix sock path:/var/run/lttng/ustconsumerd64/command
  35. DEBUG1 - 08:04:44.431702095 [574/574]: kconsumerd path: /var/run/lttng/kconsumerd
  36. DEBUG1 - 08:04:44.431718077 [574/574]: kconsumerd err unix sock path: /var/run/lttng/kconsumerd/error
  37. DEBUG1 - 08:04:44.431732077 [574/574]: kconsumerd cmd unix sock path: /var/run/lttng/kconsumerd/command
  38. DEBUG1 - 08:04:44.431760308 [574/574]: Starting lttng-sessiond {89705a1e-4b6e-4041-9bb9-907f583b5af9} (in sessiond_uuid_log() at main.c:1282)
  39. DEBUG3 - 08:04:44.431774424 [574/574]: Creating LTTng run directory: /var/run/lttng (in create_lttng_rundir() at main.c:995)
  40. DEBUG1 - 08:04:44.433684156 [576/576]: Resetting run_as worker signal handlers to default (in reset_sighandler() at runas.c:1117)
  41. DEBUG1 - 08:04:44.433952942 [576/576]: run_as signal handler set for SIGTERM and SIGINT (in set_worker_sighandlers() at runas.c:1178)
  42. DEBUG1 - 08:04:44.434949572 [575/575]: Health check time delta in seconds set to 20 (in health_init() at health.c:62)
  43. DEBUG1 - 08:04:44.435250673 [575/577]: Launching "HT cleanup" thread (in launch_thread() at thread.c:64)
  44. DEBUG1 - 08:04:44.435298970 [575/577]: [ht-thread] startup. (in thread_ht_cleanup() at ht-cleanup.c:98)
  45. DEBUG1 - 08:04:44.435441529 [575/577]: epoll set max size is 621260 (in compat_epoll_set_max_size() at compat-epoll.c:346)
  46. DEBUG3 - 08:04:44.435518175 [575/575]: Created hashtable size 4 at 0x1bd18350 of type 1 (in lttng_ht_new() at hashtable.c:135)
  47. DEBUG3 - 08:04:44.435536841 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  48. DEBUG3 - 08:04:44.435548507 [575/575]: Created hashtable size 4 at 0x1bd18790 of type 1 (in lttng_ht_new() at hashtable.c:135)
  49. DEBUG3 - 08:04:44.435568106 [575/575]: Created hashtable size 4 at 0x1bd18bd0 of type 1 (in lttng_ht_new() at hashtable.c:135)
  50. DEBUG3 - 08:04:44.435594121 [575/575]: Created hashtable size 4 at 0x1bd19010 of type 1 (in lttng_ht_new() at hashtable.c:135)
  51. DEBUG2 - 08:04:44.435611737 [575/575]: Creating consumer directory: /var/run/lttng/kconsumerd (in set_consumer_sockets() at main.c:1037)
  52. Warning: No tracing group detected
  53. DEBUG1 - 08:04:44.440715515 [575/575]: Modprobe successfully lttng-ring-buffer-client-discard (in modprobe_lttng() at modprobe.c:337)
  54. DEBUG1 - 08:04:44.444733652 [575/575]: Modprobe successfully lttng-ring-buffer-client-overwrite (in modprobe_lttng() at modprobe.c:337)
  55. DEBUG1 - 08:04:44.448701741 [575/575]: Modprobe successfully lttng-ring-buffer-metadata-client (in modprobe_lttng() at modprobe.c:337)
  56. DEBUG1 - 08:04:44.452645681 [575/575]: Modprobe successfully lttng-ring-buffer-client-mmap-discard (in modprobe_lttng() at modprobe.c:337)
  57. DEBUG1 - 08:04:44.456479610 [575/575]: Modprobe successfully lttng-ring-buffer-client-mmap-overwrite (in modprobe_lttng() at modprobe.c:337)
  58. DEBUG1 - 08:04:44.460704352 [575/575]: Modprobe successfully lttng-ring-buffer-metadata-mmap-client (in modprobe_lttng() at modprobe.c:337)
  59. DEBUG2 - 08:04:44.460848194 [575/575]: Kernel tracer version validated (2.12, ABI 2.5) (in kernel_validate_version() at kernel.c:1386)
  60. modprobe: module 'lttng_probe_asoc' not found
  61. DEBUG1 - 08:04:44.466304171 [575/575]: Unable to load optional module lttng-probe-asoc; continuing (in modprobe_lttng() at modprobe.c:332)
  62. DEBUG1 - 08:04:44.485333825 [575/575]: Modprobe successfully lttng-probe-block (in modprobe_lttng() at modprobe.c:337)
  63. DEBUG1 - 08:04:44.517069903 [575/575]: Modprobe successfully lttng-probe-btrfs (in modprobe_lttng() at modprobe.c:337)
  64. DEBUG1 - 08:04:44.522741585 [575/575]: Modprobe successfully lttng-probe-compaction (in modprobe_lttng() at modprobe.c:337)
  65. modprobe: module 'lttng_probe_ext3' not found
  66. DEBUG1 - 08:04:44.542587746 [575/575]: Unable to load optional module lttng-probe-ext3; continuing (in modprobe_lttng() at modprobe.c:332)
  67. DEBUG1 - 08:04:44.569290275 [575/575]: Modprobe successfully lttng-probe-ext4 (in modprobe_lttng() at modprobe.c:337)
  68. DEBUG1 - 08:04:44.574724202 [575/575]: Modprobe successfully lttng-probe-gpio (in modprobe_lttng() at modprobe.c:337)
  69. DEBUG1 - 08:04:44.600954140 [575/575]: Modprobe successfully lttng-probe-i2c (in modprobe_lttng() at modprobe.c:337)
  70. DEBUG1 - 08:04:44.606344904 [575/575]: Modprobe successfully lttng-probe-irq (in modprobe_lttng() at modprobe.c:337)
  71. modprobe: module 'lttng_probe_jbd' not found
  72. DEBUG1 - 08:04:44.627039653 [575/575]: Unable to load optional module lttng-probe-jbd; continuing (in modprobe_lttng() at modprobe.c:332)
  73. DEBUG1 - 08:04:44.653596706 [575/575]: Modprobe successfully lttng-probe-jbd2 (in modprobe_lttng() at modprobe.c:337)
  74. DEBUG1 - 08:04:44.659220907 [575/575]: Modprobe successfully lttng-probe-kmem (in modprobe_lttng() at modprobe.c:337)
  75. DEBUG1 - 08:04:44.690332969 [575/575]: Modprobe successfully lttng-probe-kvm (in modprobe_lttng() at modprobe.c:337)
  76. modprobe: module 'lttng_probe_kvm_x86' not found
  77. DEBUG1 - 08:04:44.697949030 [575/575]: Unable to load optional module lttng-probe-kvm-x86; continuing (in modprobe_lttng() at modprobe.c:332)
  78. modprobe: module 'lttng_probe_kvm_x86_mmu' not found
  79. DEBUG1 - 08:04:44.723451123 [575/575]: Unable to load optional module lttng-probe-kvm-x86-mmu; continuing (in modprobe_lttng() at modprobe.c:332)
  80. modprobe: module 'lttng_probe_lock' not found
  81. DEBUG1 - 08:04:44.759975763 [575/575]: Unable to load optional module lttng-probe-lock; continuing (in modprobe_lttng() at modprobe.c:332)
  82. DEBUG1 - 08:04:44.785584601 [575/575]: Modprobe successfully lttng-probe-module (in modprobe_lttng() at modprobe.c:337)
  83. DEBUG1 - 08:04:44.791110107 [575/575]: Modprobe successfully lttng-probe-napi (in modprobe_lttng() at modprobe.c:337)
  84. DEBUG1 - 08:04:44.814005889 [575/575]: Modprobe successfully lttng-probe-net (in modprobe_lttng() at modprobe.c:337)
  85. DEBUG1 - 08:04:44.819657738 [575/575]: Modprobe successfully lttng-probe-power (in modprobe_lttng() at modprobe.c:337)
  86. modprobe: module 'lttng_probe_preemptirq' not found
  87. DEBUG1 - 08:04:44.838995609 [575/575]: Unable to load optional module lttng-probe-preemptirq; continuing (in modprobe_lttng() at modprobe.c:332)
  88. DEBUG1 - 08:04:44.865347107 [575/575]: Modprobe successfully lttng-probe-printk (in modprobe_lttng() at modprobe.c:337)
  89. DEBUG1 - 08:04:44.870902012 [575/575]: Modprobe successfully lttng-probe-random (in modprobe_lttng() at modprobe.c:337)
  90. DEBUG1 - 08:04:44.884917366 [575/575]: Modprobe successfully lttng-probe-rcu (in modprobe_lttng() at modprobe.c:337)
  91. DEBUG1 - 08:04:44.925931217 [575/575]: Modprobe successfully lttng-probe-regmap (in modprobe_lttng() at modprobe.c:337)
  92. DEBUG1 - 08:04:44.950400049 [575/575]: Modprobe successfully lttng-probe-regulator (in modprobe_lttng() at modprobe.c:337)
  93. modprobe: module 'lttng_probe_rpm' not found
  94. DEBUG1 - 08:04:44.974939577 [575/575]: Unable to load optional module lttng-probe-rpm; continuing (in modprobe_lttng() at modprobe.c:332)
  95. DEBUG1 - 08:04:45.007432898 [575/575]: Modprobe successfully lttng-probe-sched (in modprobe_lttng() at modprobe.c:337)
  96. DEBUG1 - 08:04:45.039469494 [575/575]: Modprobe successfully lttng-probe-scsi (in modprobe_lttng() at modprobe.c:337)
  97. DEBUG1 - 08:04:45.065912104 [575/575]: Modprobe successfully lttng-probe-signal (in modprobe_lttng() at modprobe.c:337)
  98. DEBUG1 - 08:04:45.102117677 [575/575]: Modprobe successfully lttng-probe-skb (in modprobe_lttng() at modprobe.c:337)
  99. DEBUG1 - 08:04:45.134739558 [575/575]: Modprobe successfully lttng-probe-sock (in modprobe_lttng() at modprobe.c:337)
  100. DEBUG1 - 08:04:45.167602693 [575/575]: Modprobe successfully lttng-probe-statedump (in modprobe_lttng() at modprobe.c:337)
  101. DEBUG1 - 08:04:45.186260084 [575/575]: Modprobe successfully lttng-probe-sunrpc (in modprobe_lttng() at modprobe.c:337)
  102. DEBUG1 - 08:04:45.217819188 [575/575]: Modprobe successfully lttng-probe-timer (in modprobe_lttng() at modprobe.c:337)
  103. DEBUG1 - 08:04:45.250356141 [575/575]: Modprobe successfully lttng-probe-udp (in modprobe_lttng() at modprobe.c:337)
  104. DEBUG1 - 08:04:45.273993416 [575/575]: Modprobe successfully lttng-probe-vmscan (in modprobe_lttng() at modprobe.c:337)
  105. DEBUG1 - 08:04:45.306415808 [575/575]: Modprobe successfully lttng-probe-v4l2 (in modprobe_lttng() at modprobe.c:337)
  106. DEBUG1 - 08:04:45.334469849 [575/575]: Modprobe successfully lttng-probe-workqueue (in modprobe_lttng() at modprobe.c:337)
  107. DEBUG1 - 08:04:45.413594806 [575/575]: Modprobe successfully lttng-probe-writeback (in modprobe_lttng() at modprobe.c:337)
  108. modprobe: module 'lttng_probe_x86_irq_vectors' not found
  109. DEBUG1 - 08:04:45.421682409 [575/575]: Unable to load optional module lttng-probe-x86-irq-vectors; continuing (in modprobe_lttng() at modprobe.c:332)
  110. modprobe: module 'lttng_probe_x86_exceptions' not found
  111. DEBUG1 - 08:04:45.455010319 [575/575]: Unable to load optional module lttng-probe-x86-exceptions; continuing (in modprobe_lttng() at modprobe.c:332)
  112. DEBUG1 - 08:04:45.455074483 [575/575]: Kernel tracer fd 21 (in init_kernel_tracer() at kernel.c:1836)
  113. DEBUG3 - 08:04:45.455098981 [575/575]: Syscall init system call table (in syscall_init_table() at lttng-syscall.c:42)
  114. DEBUG1 - 08:04:45.457168888 [575/575]: Reallocating syscall table from 256 to 512 entries (in syscall_init_table() at lttng-syscall.c:87)
  115. DEBUG1 - 08:04:45.459236578 [575/575]: Reallocating syscall table from 512 to 1024 entries (in syscall_init_table() at lttng-syscall.c:87)
  116. DEBUG2 - 08:04:45.460622854 [575/575]: Creating consumer directory: /var/run/lttng/ustconsumerd64 (in set_consumer_sockets() at main.c:1037)
  117. DEBUG2 - 08:04:45.461118544 [575/575]: Creating consumer directory: /var/run/lttng/ustconsumerd32 (in set_consumer_sockets() at main.c:1037)
  118. DEBUG3 - 08:04:45.461408912 [575/575]: Created hashtable size 4 at 0x1bd1a2e0 of type 2 (in lttng_ht_new() at hashtable.c:135)
  119. DEBUG3 - 08:04:45.461436327 [575/575]: Global buffer per UID registry initialized (in buffer_reg_init_uid_registry() at buffer-registry.c:90)
  120. DEBUG3 - 08:04:45.461466426 [575/575]: Created hashtable size 4 at 0x1bd1a720 of type 2 (in lttng_ht_new() at hashtable.c:135)
  121. DEBUG3 - 08:04:45.461489758 [575/575]: Global buffer per PID registry initialized (in buffer_reg_init_pid_registry() at buffer-registry.c:219)
  122. DEBUG1 - 08:04:45.461513907 [575/575]: Command subsystem initialized (in cmd_init() at cmd.c:5502)
  123. DEBUG1 - 08:04:45.461667898 [575/575]: Pid 575 written in file /var/run/lttng/lttng-sessiond.pid (in utils_create_pid_file() at utils.c:619)
  124. DEBUG1 - 08:04:45.461790392 [575/575]: TCP inet operation timeout set to 216 sec (in lttcomm_inet_init() at inet.c:545)
  125. DEBUG1 - 08:04:45.462012747 [575/665]: Launching "Health management" thread (in launch_thread() at thread.c:64)
  126. DEBUG1 - 08:04:45.462060111 [575/665]: [thread] Manage health check started (in thread_manage_health() at health.c:65)
  127. DEBUG1 - 08:04:45.462164872 [575/575]: Waiting for health management thread to be ready (in wait_until_thread_is_ready() at health.c:35)
  128. DEBUG1 - 08:04:45.462286432 [575/665]: Marking health management thread as ready (in mark_thread_as_ready() at health.c:28)
  129. DEBUG1 - 08:04:45.462317931 [575/665]: Health check ready (in thread_manage_health() at health.c:136)
  130. DEBUG1 - 08:04:45.462320264 [575/575]: Health management thread is ready (in wait_until_thread_is_ready() at health.c:37)
  131. DEBUG1 - 08:04:45.462416275 [575/575]: Waiting for notification thread to be ready (in wait_until_thread_is_ready() at notification-thread.c:387)
  132. DEBUG1 - 08:04:45.462935181 [575/666]: Launching "Notification" thread (in launch_thread() at thread.c:64)
  133. DEBUG1 - 08:04:45.462964463 [575/666]: [notification-thread] Started notification thread (in thread_notification() at notification-thread.c:521)
  134. DEBUG1 - 08:04:45.463007510 [575/666]: [notification-thread] Creating notification channel UNIX socket at /var/run/lttng/sessiond-notification (in notification_channel_socket_create() at notification-thread.c:214)
  135. DEBUG1 - 08:04:45.463149720 [575/666]: [notification-thread] Notification channel UNIX socket created (fd = 36) (in notification_channel_socket_create() at notification-thread.c:249)
  136. DEBUG1 - 08:04:45.463190201 [575/666]: [notification-thread] Listening on notification channel socket (in init_thread_state() at notification-thread.c:414)
  137. DEBUG1 - 08:04:45.463229982 [575/666]: Marking notification thread as ready (in mark_thread_as_ready() at notification-thread.c:380)
  138. DEBUG1 - 08:04:45.463251098 [575/666]: [notification-thread] Entering poll wait (in thread_notification() at notification-thread.c:543)
  139. DEBUG1 - 08:04:45.463252264 [575/575]: Notification thread is ready (in wait_until_thread_is_ready() at notification-thread.c:389)
  140. DEBUG1 - 08:04:45.463839066 [575/668]: Launching "Rotation" thread (in launch_thread() at thread.c:64)
  141. DEBUG1 - 08:04:45.463865665 [575/668]: [rotation-thread] Started rotation thread (in thread_rotation() at rotation-thread.c:772)
  142. DEBUG1 - 08:04:45.463906029 [575/575]: Created client socket (fd = 42) (in create_client_sock() at client.c:2144)
  143. DEBUG1 - 08:04:45.463967276 [575/575]: Waiting for client thread to be ready (in wait_thread_status() at client.c:56)
  144. DEBUG1 - 08:04:45.463978009 [575/669]: Launching "Client management" thread (in launch_thread() at thread.c:64)
  145. DEBUG1 - 08:04:45.463993175 [575/667]: Launching "Timer" thread (in launch_thread() at thread.c:64)
  146. DEBUG1 - 08:04:45.464019423 [575/669]: [thread] Manage client started (in thread_manage_clients() at client.c:2178)
  147. DEBUG1 - 08:04:45.464117418 [575/666]: [notification-thread] Poll wait returned (1) (in thread_notification() at notification-thread.c:545)
  148. DEBUG1 - 08:04:45.464134684 [575/669]: Marking client thread's state as running (in set_thread_status() at client.c:49)
  149. DEBUG1 - 08:04:45.464167582 [575/666]: [notification-thread] Handling fd (36) activity (1) (in thread_notification() at notification-thread.c:563)
  150. DEBUG1 - 08:04:45.464196164 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  151. DEBUG1 - 08:04:45.464196397 [575/666]: [notification-thread] Handling new notification channel client connection (in handle_notification_thread_client_connect() at notification-thread-events.c:2414)
  152. DEBUG1 - 08:04:45.464263594 [575/666]: Client socket (fd = 46) set as non-blocking (in socket_set_non_blocking() at notification-thread-events.c:2382)
  153. DEBUG1 - 08:04:45.464295792 [575/666]: [notification-thread] Added new notification channel client socket (46) to poll set (in handle_notification_thread_client_connect() at notification-thread-events.c:2463)
  154. DEBUG1 - 08:04:45.464335806 [575/666]: [notification-thread] Entering poll wait (in thread_notification() at notification-thread.c:543)
  155. DEBUG1 - 08:04:45.464353889 [575/666]: [notification-thread] Poll wait returned (1) (in thread_notification() at notification-thread.c:545)
  156. DEBUG1 - 08:04:45.464367771 [575/666]: [notification-thread] Handling fd (46) activity (1) (in thread_notification() at notification-thread.c:563)
  157. DEBUG1 - 08:04:45.464406153 [575/666]: [notification-thread] Entering poll wait (in thread_notification() at notification-thread.c:543)
  158. DEBUG1 - 08:04:45.464426452 [575/666]: [notification-thread] Poll wait returned (1) (in thread_notification() at notification-thread.c:545)
  159. DEBUG1 - 08:04:45.464440684 [575/666]: [notification-thread] Handling fd (46) activity (1) (in thread_notification() at notification-thread.c:563)
  160. DEBUG1 - 08:04:45.464469966 [575/666]: [notification-thread] Received handshake from client (uid = 0, gid = 0) with version 1.1 (in client_dispatch_message() at notification-thread-events.c:2763)
  161. DEBUG1 - 08:04:45.464490265 [575/666]: [notification-thread] Flushing client (socket fd = 46) outgoing queue (in client_flush_outgoing_queue() at notification-thread-events.c:2561)
  162. DEBUG1 - 08:04:45.464523630 [575/666]: [notification-thread] Send command reply (0) (in client_send_command_reply() at notification-thread-events.c:2646)
  163. DEBUG1 - 08:04:45.464538912 [575/666]: [notification-thread] Flushing client (socket fd = 46) outgoing queue (in client_flush_outgoing_queue() at notification-thread-events.c:2561)
  164. DEBUG1 - 08:04:45.464561778 [575/666]: [notification-thread] Entering poll wait (in thread_notification() at notification-thread.c:543)
  165. DEBUG1 - 08:04:45.464586743 [575/668]: [rotation-thread] Entering poll wait (in thread_rotation() at rotation-thread.c:796)
  166. DEBUG1 - 08:04:45.464673655 [575/575]: Client thread is ready (in wait_thread_status() at client.c:59)
  167. Error: Unable to set group on /var/run/lttng/kconsumerd/error
  168. PERROR - 08:04:45.464807815 [575/575]: chown: No such file or directory (in set_permissions() at main.c:966)
  169. DEBUG1 - 08:04:45.464847246 [575/575]: All permissions are set (in set_permissions() at main.c:983)
  170. DEBUG1 - 08:04:45.464918992 [575/670]: Launching "UST registration dispatch" thread (in launch_thread() at thread.c:64)
  171. DEBUG1 - 08:04:45.464947341 [575/670]: [thread] Dispatch UST command started (in thread_dispatch_ust_registration() at dispatch.c:244)
  172. DEBUG1 - 08:04:45.464963556 [575/670]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:67)
  173. DEBUG3 - 08:04:45.464980356 [575/575]: Session daemon application socket created (fd = 49) (in create_application_socket() at register.c:69)
  174. DEBUG1 - 08:04:45.465010104 [575/670]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at dispatch.c:273)
  175. DEBUG1 - 08:04:45.465047902 [575/575]: Waiting for application registration thread to be ready (in wait_thread_status() at register.c:129)
  176. DEBUG1 - 08:04:45.465054552 [575/671]: Launching "UST application registration" thread (in launch_thread() at thread.c:64)
  177. DEBUG1 - 08:04:45.465080334 [575/671]: [thread] Manage application registration started (in thread_application_registration() at register.c:166)
  178. DEBUG1 - 08:04:45.465141814 [575/671]: Marking application registration thread's state as running (in set_thread_status() at register.c:122)
  179. DEBUG1 - 08:04:45.465165379 [575/671]: Accepting application registration (in thread_application_registration() at register.c:205)
  180. DEBUG1 - 08:04:45.465167129 [575/575]: Application registration thread is ready (in wait_thread_status() at register.c:132)
  181. DEBUG1 - 08:04:45.465198627 [575/575]: Notifying applications of session daemon state: 1 (in notify_ust_apps() at register.c:90)
  182. PERROR - 08:04:45.465478146 [575/575]: Failed to open wait shm at /lttng-ust-wait-8: Function not implemented (in get_wait_shm() at shm.c:98)
  183. DEBUG1 - 08:04:45.465496345 [575/575]: Failing to get the wait shm fd (in get_wait_shm() at shm.c:143)
  184. Error: Failed to notify applications or create the wait shared memory.
  185. Execution continues but there might be problems for already
  186. running applications that wishes to register.
  187. DEBUG1 - 08:04:45.465655120 [575/673]: Launching "Application notification" thread (in launch_thread() at thread.c:64)
  188. DEBUG1 - 08:04:45.465685335 [575/673]: [ust-thread] Manage application notify command (in thread_application_notification() at notify-apps.c:39)
  189. DEBUG1 - 08:04:45.466156293 [575/575]: Waiting for agent management thread to be ready (in wait_until_thread_is_ready() at agent-thread.c:301)
  190. DEBUG1 - 08:04:45.466179275 [575/674]: Launching "Agent management" thread (in launch_thread() at thread.c:64)
  191. DEBUG3 - 08:04:45.466166909 [575/673]: [ust-thread] Manage notify polling (in thread_application_notification() at notify-apps.c:74)
  192. DEBUG1 - 08:04:45.466203540 [575/674]: [agent-thread] Manage agent application registration. (in thread_agent_management() at agent-thread.c:319)
  193. DEBUG1 - 08:04:45.466196308 [575/672]: Launching "UST application management" thread (in launch_thread() at thread.c:64)
  194. DEBUG1 - 08:04:45.466248921 [575/672]: [thread] Manage application started (in thread_application_management() at manage-apps.c:52)
  195. DEBUG3 - 08:04:45.466266070 [575/674]: URI string: tcp://localhost (in uri_parse() at uri.c:298)
  196. DEBUG1 - 08:04:45.466335600 [575/672]: Apps thread polling (in thread_application_management() at manage-apps.c:88)
  197. DEBUG2 - 08:04:45.469281743 [575/674]: IP address resolved to 127.0.0.1 (in set_ip_address() at uri.c:155)
  198. DEBUG3 - 08:04:45.469311142 [575/674]: URI dtype: 1, proto: 1, host: localhost, subdir: , ctrl: 0, data: 0 (in uri_parse() at uri.c:510)
  199. DEBUG3 - 08:04:45.469356756 [575/674]: [agent-thread] Trying to bind on port 5345 (in init_tcp_socket() at agent-thread.c:119)
  200. DEBUG1 - 08:04:45.469391871 [575/674]: [agent-thread] Listening on TCP port 5345 and socket 60 (in init_tcp_socket() at agent-thread.c:155)
  201. DEBUG1 - 08:04:45.469545979 [575/674]: Pid 5345 written in file /var/run/lttng/agent.port (in utils_create_pid_file() at utils.c:619)
  202. DEBUG1 - 08:04:45.469562545 [575/674]: Marking agent management thread as ready (in mark_thread_as_ready() at agent-thread.c:294)
  203. DEBUG1 - 08:04:45.469590544 [575/575]: Agent management thread is ready (in wait_until_thread_is_ready() at agent-thread.c:303)
  204. DEBUG3 - 08:04:45.469592293 [575/674]: [agent-thread] Manage agent polling (in thread_agent_management() at agent-thread.c:373)
  205. DEBUG1 - 08:04:45.475836078 [575/675]: Launching "Kernel management" thread (in launch_thread() at thread.c:64)
  206. DEBUG1 - 08:04:45.475911091 [575/675]: [thread] Thread manage kernel started (in thread_kernel_management() at manage-kernel.c:173)
  207. DEBUG1 - 08:04:45.476018768 [575/675]: Updating kernel poll set (in update_kernel_poll() at manage-kernel.c:37)
  208. DEBUG1 - 08:04:45.476043850 [575/675]: Thread kernel polling (in thread_kernel_management() at manage-kernel.c:227)
  209. DEBUG1 - 08:04:45.517111482 [575/669]: Wait for client response (in thread_manage_clients() at client.c:2276)
  210. DEBUG1 - 08:04:45.517258941 [575/669]: Receiving data from client ... (in thread_manage_clients() at client.c:2321)
  211. DEBUG1 - 08:04:45.517295806 [575/669]: Nothing recv() from client... continuing (in thread_manage_clients() at client.c:2325)
  212. DEBUG1 - 08:04:45.517380501 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  213. DEBUG1 - 08:04:45.517407450 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  214. DEBUG1 - 08:04:45.517436031 [575/669]: Wait for client response (in thread_manage_clients() at client.c:2276)
  215. DEBUG1 - 08:04:45.517475579 [575/669]: Receiving data from client ... (in thread_manage_clients() at client.c:2321)
  216. DEBUG1 - 08:04:45.517543009 [575/669]: Processing client command 49 (in process_client_msg() at client.c:719)
  217. DEBUG3 - 08:04:45.517698517 [575/669]: Created hashtable size 4 at 0xffff8000c3b0 of type 1 (in lttng_ht_new() at hashtable.c:135)
  218. DEBUG2 - 08:04:45.517784380 [575/669]: Trying to find session by name auto-20200804-080445 (in session_find_by_name() at session.c:1000)
  219. DEBUG1 - 08:04:45.517808762 [575/669]: Generated session name "auto-20200804-080445" (in session_create() at session.c:1157)
  220. DEBUG3 - 08:04:45.517848193 [575/669]: Created hashtable size 4 at 0xffff8000c9e0 of type 1 (in lttng_ht_new() at hashtable.c:135)
  221. DEBUG1 - 08:04:45.517873625 [575/669]: Allocating ltt_sessions_ht_by_id (in ltt_sessions_ht_alloc() at session.c:304)
  222. DEBUG3 - 08:04:45.517908156 [575/669]: Created hashtable size 4 at 0xffff8000ce20 of type 2 (in lttng_ht_new() at hashtable.c:135)
  223. DEBUG1 - 08:04:45.517945254 [575/669]: Tracing session auto-20200804-080445 created with ID 0 by uid = 0, gid = 0 (in session_create() at session.c:1205)
  224. DEBUG3 - 08:04:45.517979436 [575/669]: URI string: file:///root/lttng-traces/auto-20200804-080445 (in uri_parse() at uri.c:298)
  225. DEBUG3 - 08:04:45.518027850 [575/669]: URI file destination: /root/lttng-traces/auto-20200804-080445 (in uri_parse() at uri.c:335)
  226. DEBUG3 - 08:04:45.518052815 [575/669]: URI dtype: 3, proto: 0, host: , subdir: , ctrl: 0, data: 0 (in uri_parse() at uri.c:510)
  227. DEBUG2 - 08:04:45.518093180 [575/669]: Setting trace directory path from URI to /root/lttng-traces/auto-20200804-080445 (in add_uri_to_consumer() at cmd.c:906)
  228. DEBUG1 - 08:04:45.518169009 [575/669]: Sending response (size: 8258, retcode: Success (10)) (in thread_manage_clients() at client.c:2383)
  229. DEBUG1 - 08:04:45.518226989 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  230. DEBUG1 - 08:04:45.518251255 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  231. DEBUG1 - 08:04:45.518360216 [575/669]: Wait for client response (in thread_manage_clients() at client.c:2276)
  232. DEBUG1 - 08:04:45.518399064 [575/669]: Receiving data from client ... (in thread_manage_clients() at client.c:2321)
  233. DEBUG1 - 08:04:45.518567871 [575/669]: Processing client command 13 (in process_client_msg() at client.c:719)
  234. DEBUG1 - 08:04:45.518585254 [575/669]: Counting number of available session for UID 0 GID 0 (in lttng_sessions_count() at client.c:517)
  235. DEBUG1 - 08:04:45.518607069 [575/669]: Getting all available session for UID 0 GID 0 (in cmd_list_lttng_sessions() at cmd.c:3792)
  236. DEBUG1 - 08:04:45.518631218 [575/669]: Sending response (size: 4412, retcode: Success (10)) (in thread_manage_clients() at client.c:2383)
  237. DEBUG1 - 08:04:45.518666916 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  238. DEBUG1 - 08:04:45.518682548 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  239. DEBUG1 - 08:04:45.524283300 [575/669]: Wait for client response (in thread_manage_clients() at client.c:2276)
  240. DEBUG1 - 08:04:45.524399261 [575/669]: Receiving data from client ... (in thread_manage_clients() at client.c:2321)
  241. DEBUG1 - 08:04:45.524450008 [575/669]: Processing client command 6 (in process_client_msg() at client.c:719)
  242. DEBUG1 - 08:04:45.524472990 [575/669]: Getting session auto-20200804-080445 by name (in process_client_msg() at client.c:817)
  243. DEBUG2 - 08:04:45.524489206 [575/669]: Trying to find session by name auto-20200804-080445 (in session_find_by_name() at session.c:1000)
  244. DEBUG1 - 08:04:45.524530737 [575/669]: Creating UST session (in create_ust_session() at client.c:432)
  245. DEBUG3 - 08:04:45.524609716 [575/669]: Created hashtable size 4 at 0xffff80002ed0 of type 0 (in lttng_ht_new() at hashtable.c:135)
  246. DEBUG3 - 08:04:45.524631999 [575/669]: Created hashtable size 4 at 0xffff80003310 of type 2 (in lttng_ht_new() at hashtable.c:135)
  247. DEBUG3 - 08:04:45.524677613 [575/669]: Created hashtable size 4 at 0xffff80016ad0 of type 1 (in lttng_ht_new() at hashtable.c:135)
  248. DEBUG2 - 08:04:45.524693945 [575/669]: UST trace session create successful (in trace_ust_create_session() at trace-ust.c:324)
  249. DEBUG3 - 08:04:45.524709344 [575/669]: Copying tracing session consumer output in UST session (in copy_session_consumer() at client.c:376)
  250. DEBUG3 - 08:04:45.524783891 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  251. DEBUG3 - 08:04:45.524788324 [575/669]: Created hashtable size 4 at 0xffff80016f10 of type 1 (in lttng_ht_new() at hashtable.c:135)
  252. DEBUG3 - 08:04:45.524850970 [575/669]: Copy session consumer subdir ust (in copy_session_consumer() at client.c:398)
  253. DEBUG1 - 08:04:45.524873019 [575/669]: Spawning consumerd (in spawn_consumerd() at client.c:138)
  254. DEBUG3 - 08:04:45.524909650 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  255. DEBUG2 - 08:04:45.525660127 [575/669]: Consumer pid 680 (in start_consumerd() at client.c:312)
  256. DEBUG2 - 08:04:45.525693492 [575/669]: Spawning consumer control thread (in start_consumerd() at client.c:315)
  257. DEBUG1 - 08:04:45.525901964 [575/669]: Waiting for consumer management thread to be ready (in wait_until_thread_is_ready() at manage-consumer.c:46)
  258. DEBUG1 - 08:04:45.525941746 [575/681]: Launching "Consumer management" thread (in launch_thread() at thread.c:64)
  259. DEBUG1 - 08:04:45.525969627 [575/681]: [thread] Manage consumer started (in thread_consumer_management() at manage-consumer.c:65)
  260. DEBUG1 - 08:04:45.530097424 [680/680]: Using 64-bit UST consumer at: /./lib/lttng/libexec/lttng-consumerd (in spawn_consumerd() at client.c:216)
  261. DEBUG1 - 08:04:45.588038359 [680/680]: Health check time delta in seconds set to 20 (in health_init() at health.c:62)
  262. DEBUG3 - 08:04:45.588592379 [680/680]: Created hashtable size 4 at 0x2367f7d0 of type 2 (in lttng_ht_new() at hashtable.c:135)
  263. DEBUG3 - 08:04:45.588622827 [680/680]: Created hashtable size 4 at 0x2367fc10 of type 2 (in lttng_ht_new() at hashtable.c:135)
  264. DEBUG3 - 08:04:45.588642193 [680/680]: Created hashtable size 4 at 0x23680050 of type 2 (in lttng_ht_new() at hashtable.c:135)
  265. DEBUG3 - 08:04:45.588660509 [680/680]: Created hashtable size 4 at 0x23680490 of type 2 (in lttng_ht_new() at hashtable.c:135)
  266. DEBUG3 - 08:04:45.588678241 [680/680]: Created hashtable size 4 at 0x236808d0 of type 2 (in lttng_ht_new() at hashtable.c:135)
  267. DEBUG3 - 08:04:45.588699940 [680/680]: Created hashtable size 4 at 0x23680d10 of type 2 (in lttng_ht_new() at hashtable.c:135)
  268. DEBUG3 - 08:04:45.588731438 [680/680]: Created hashtable size 4 at 0x23681150 of type 2 (in lttng_ht_new() at hashtable.c:135)
  269. DEBUG1 - 08:04:45.588831650 [680/680]: TCP inet operation timeout set to 216 sec (in lttcomm_inet_init() at inet.c:545)
  270. DEBUG1 - 08:04:45.588940144 [680/680]: Connecting to error socket /var/run/lttng/ustconsumerd64/error (in main() at lttng-consumerd.c:454)
  271. DEBUG1 - 08:04:45.589136600 [680/685]: [thread] Manage health check started (in thread_manage_health() at health-consumerd.c:157)
  272. DEBUG2 - 08:04:45.589561711 [575/681]: Receiving code from consumer err_sock (in thread_consumer_management() at manage-consumer.c:162)
  273. Warning: No tracing group detected
  274. DEBUG1 - 08:04:45.590038152 [680/685]: epoll set max size is 621260 (in compat_epoll_set_max_size() at compat-epoll.c:346)
  275. DEBUG1 - 08:04:45.590087266 [680/685]: Health check ready (in thread_manage_health() at health-consumerd.c:237)
  276. DEBUG3 - 08:04:45.689969386 [680/688]: Created hashtable size 4 at 0xffff84000b80 of type 2 (in lttng_ht_new() at hashtable.c:135)
  277. DEBUG1 - 08:04:45.690068664 [680/688]: Thread channel poll started (in consumer_thread_channel_poll() at consumer.c:3016)
  278. DEBUG1 - 08:04:45.690105645 [680/689]: Thread metadata poll started (in consumer_thread_metadata_poll() at consumer.c:2426)
  279. DEBUG1 - 08:04:45.690182758 [680/688]: Channel main loop started (in consumer_thread_channel_poll() at consumer.c:3031)
  280. DEBUG1 - 08:04:45.690205390 [680/688]: Channel poll wait (in consumer_thread_channel_poll() at consumer.c:3036)
  281. DEBUG1 - 08:04:45.690216706 [680/689]: Metadata main loop started (in consumer_thread_metadata_poll() at consumer.c:2442)
  282. DEBUG1 - 08:04:45.690241088 [680/689]: Metadata poll wait (in consumer_thread_metadata_poll() at consumer.c:2448)
  283. DEBUG1 - 08:04:45.690458893 [680/690]: Updating poll fd array (in update_poll_array() at consumer.c:1225)
  284. DEBUG1 - 08:04:45.690518040 [680/690]: polling on 2 fd (in consumer_thread_data_poll() at consumer.c:2705)
  285. DEBUG1 - 08:04:45.690526789 [680/691]: Creating command socket /var/run/lttng/ustconsumerd64/command (in consumer_thread_sessiond_poll() at consumer.c:3279)
  286. DEBUG1 - 08:04:45.690714496 [680/691]: Sending ready command to lttng-sessiond (in consumer_thread_sessiond_poll() at consumer.c:3292)
  287. DEBUG1 - 08:04:45.690924485 [575/681]: Consumer command socket ready (fd: 70) (in thread_consumer_management() at manage-consumer.c:204)
  288. DEBUG1 - 08:04:45.690959250 [575/681]: Consumer metadata socket ready (fd: 71) (in thread_consumer_management() at manage-consumer.c:205)
  289. DEBUG1 - 08:04:45.691006264 [575/681]: Sending consumer initialization command (in consumer_init() at consumer.c:1758)
  290. DEBUG1 - 08:04:45.691408159 [680/691]: Connection on client_socket (in consumer_thread_sessiond_poll() at consumer.c:3314)
  291. DEBUG1 - 08:04:45.691468473 [680/691]: Metadata connection on client_socket (in set_metadata_socket() at consumer.c:3240)
  292. DEBUG1 - 08:04:45.691531586 [680/691]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:3360)
  293. DEBUG1 - 08:04:45.691624214 [680/691]: Received session daemon UUID: 89705a1e-4b6e-4041-9bb9-907f583b5af9 (in lttng_consumer_init_command() at consumer.c:4535)
  294. DEBUG1 - 08:04:45.691670062 [680/691]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:3376)
  295. DEBUG3 - 08:04:45.691677645 [575/681]: Sending SET_CHANNEL_MONITOR_PIPE command to consumer (in consumer_send_pipe() at consumer.c:1223)
  296. DEBUG1 - 08:04:45.691732942 [680/691]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:3360)
  297. DEBUG3 - 08:04:45.691803988 [575/681]: Sending channel monitor pipe 18 to consumer on socket 70 (in consumer_send_pipe() at consumer.c:1229)
  298. DEBUG1 - 08:04:45.691870368 [680/691]: Received channel monitor pipe (30) (in lttng_ustconsumer_recv_cmd() at ust-consumer.c:1930)
  299. DEBUG1 - 08:04:45.691900700 [680/691]: Channel monitor pipe set as non-blocking (in lttng_ustconsumer_recv_cmd() at ust-consumer.c:1951)
  300. DEBUG1 - 08:04:45.691938498 [680/691]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:3376)
  301. DEBUG2 - 08:04:45.691944214 [575/681]: channel monitor pipe successfully sent (in consumer_send_pipe() at consumer.c:1237)
  302. DEBUG1 - 08:04:45.692131337 [575/681]: Marking consumer management thread as ready (in mark_thread_as_ready() at manage-consumer.c:31)
  303. DEBUG1 - 08:04:45.692683375 [575/669]: Consumer management thread is ready (in wait_until_thread_is_ready() at manage-consumer.c:48)
  304. DEBUG3 - 08:04:45.692747188 [575/669]: Consumer socket created (fd: 70) and added to output (in consumer_create_socket() at consumer.c:350)
  305. DEBUG1 - 08:04:45.692775070 [575/669]: Setting relayd for session auto-20200804-080445 (in cmd_setup_relayd() at cmd.c:1191)
  306. DEBUG1 - 08:04:45.692816251 [575/669]: Enable event command for event '*' (in _cmd_enable_event() at cmd.c:2196)
  307. DEBUG2 - 08:04:45.692850666 [575/669]: Trace UST channel channel0 not found by name (in trace_ust_find_channel_by_name() at trace-ust.c:188)
  308. DEBUG1 - 08:04:45.692895113 [575/669]: Enabling channel for session auto-20200804-080445 (in cmd_enable_channel() at cmd.c:1465)
  309. DEBUG2 - 08:04:45.692923462 [575/669]: Trace UST channel channel0 not found by name (in trace_ust_find_channel_by_name() at trace-ust.c:188)
  310. DEBUG3 - 08:04:45.693009324 [575/669]: Created hashtable size 4 at 0xffff80017e30 of type 0 (in lttng_ht_new() at hashtable.c:135)
  311. DEBUG3 - 08:04:45.693041639 [575/669]: Created hashtable size 4 at 0xffff80018270 of type 1 (in lttng_ht_new() at hashtable.c:135)
  312. DEBUG2 - 08:04:45.693066837 [575/669]: Trace UST channel channel0 created (in trace_ust_create_channel() at trace-ust.c:402)
  313. DEBUG2 - 08:04:45.693092736 [575/669]: Channel channel0 is being created for UST with buffer 1 and id 0 (in channel_ust_create() at channel.c:451)
  314. DEBUG2 - 08:04:45.693124234 [575/669]: Channel channel0 created successfully (in channel_ust_create() at channel.c:481)
  315. DEBUG2 - 08:04:45.693151416 [575/669]: Trace UST channel channel0 found by name (in trace_ust_find_channel_by_name() at trace-ust.c:183)
  316. DEBUG2 - 08:04:45.693182215 [575/669]: Trace UST event * NOT found (in trace_ust_find_event() at trace-ust.c:226)
  317. DEBUG2 - 08:04:45.693211030 [575/669]: Trace UST event *, loglevel (0,-1) created (in trace_ust_create_event() at trace-ust.c:523)
  318. DEBUG1 - 08:04:45.693255011 [575/669]: Sending response (size: 20, retcode: Success (10)) (in thread_manage_clients() at client.c:2383)
  319. DEBUG1 - 08:04:45.693326057 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  320. DEBUG1 - 08:04:45.693356622 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  321. DEBUG1 - 08:04:45.700165276 [575/669]: Wait for client response (in thread_manage_clients() at client.c:2276)
  322. DEBUG1 - 08:04:45.700327551 [575/669]: Receiving data from client ... (in thread_manage_clients() at client.c:2321)
  323. DEBUG1 - 08:04:45.700410497 [575/669]: Processing client command 16 (in process_client_msg() at client.c:719)
  324. DEBUG1 - 08:04:45.700433712 [575/669]: Getting session auto-20200804-080445 by name (in process_client_msg() at client.c:817)
  325. DEBUG2 - 08:04:45.700450745 [575/669]: Trying to find session by name auto-20200804-080445 (in session_find_by_name() at session.c:1000)
  326. DEBUG1 - 08:04:45.700498459 [575/669]: Creating initial trace chunk of session "auto-20200804-080445" (in cmd_start_trace() at cmd.c:2738)
  327. DEBUG1 - 08:04:45.700523541 [575/669]: Creating trace chunk: chunk_id = 0, creation time = 20200804-080445 (in lttng_trace_chunk_create() at trace-chunk.c:421)
  328. DEBUG1 - 08:04:45.700544890 [575/669]: Chunk name set to "(none)" (in lttng_trace_chunk_create() at trace-chunk.c:452)
  329. DEBUG1 - 08:04:45.700563322 [575/669]: Creating base output directory of session "auto-20200804-080445" at /root/lttng-traces/auto-20200804-080445 (in session_create_new_trace_chunk() at session.c:652)
  330. DEBUG3 - 08:04:45.700586187 [575/669]: mkdirat() recursive fd = -100 (AT_FDCWD), path = /root/lttng-traces/auto-20200804-080445, mode = 504, uid = 0, gid = 0 (in run_as_mkdirat_recursive() at runas.c:1426)
  331. DEBUG1 - 08:04:45.700614653 [575/669]: Using run_as worker (in run_as() at runas.c:1383)
  332. DEBUG1 - 08:04:45.701074061 [575/669]: Creating trace chunk subdirectory "ust" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1212)
  333. DEBUG3 - 08:04:45.701097044 [575/669]: mkdirat() recursive fd = 72, path = ust, mode = 504, uid = 0, gid = 0 (in run_as_mkdirat_recursive() at runas.c:1426)
  334. DEBUG1 - 08:04:45.701122476 [575/669]: Using run_as worker (in run_as() at runas.c:1383)
  335. DEBUG1 - 08:04:45.701277284 [575/669]: Adding new top-level directory "ust" to trace chunk "(unnamed)" (in add_top_level_directory_unique() at trace-chunk.c:1185)
  336. DEBUG1 - 08:04:45.701309482 [575/669]: Sending consumer create trace chunk command: relayd_id = -1, session_id = 0, chunk_id = 0, creation_timestamp = 20200804T080445+0000 (in consumer_create_trace_chunk() at consumer.c:1908)
  337. DEBUG1 - 08:04:45.701372712 [680/691]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:3360)
  338. DEBUG1 - 08:04:45.701442709 [575/669]: Sending trace chunk domain directory fd to consumer (in consumer_create_trace_chunk() at consumer.c:1923)
  339. DEBUG1 - 08:04:45.701484240 [680/691]: Received trace chunk domain directory fd (33) (in lttng_ustconsumer_recv_cmd() at ust-consumer.c:2083)
  340. DEBUG1 - 08:04:45.701610583 [680/691]: Consumer create trace chunk command: relay_id = (none), session_id = 0, chunk_id = 0, chunk_override_name = (none), chunk_creation_timestamp = 20200804T080445+0000 (in lttng_consumer_create_trace_chunk() at consumer.c:4578)
  341. DEBUG1 - 08:04:45.701637065 [680/691]: Creating trace chunk: chunk_id = 0, creation time = 20200804-080445 (in lttng_trace_chunk_create() at trace-chunk.c:421)
  342. DEBUG1 - 08:04:45.701655964 [680/691]: Chunk name set to "(none)" (in lttng_trace_chunk_create() at trace-chunk.c:452)
  343. DEBUG1 - 08:04:45.701735993 [680/691]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:3376)
  344. DEBUG1 - 08:04:45.701750226 [575/669]: Starting all UST traces (in ust_app_start_trace_all() at ust-app.c:4853)
  345. DEBUG1 - 08:04:45.701767141 [575/669]: Clearing stream quiescent state for all ust apps (in ust_app_clear_quiescent_session() at ust-app.c:4729)
  346. DEBUG1 - 08:04:45.701790240 [575/669]: Sending response (size: 20, retcode: Success (10)) (in thread_manage_clients() at client.c:2383)
  347. DEBUG1 - 08:04:45.701841337 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  348. DEBUG1 - 08:04:45.701858953 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  349. DEBUG1 - 08:04:45.731447546 [575/669]: Wait for client response (in thread_manage_clients() at client.c:2276)
  350. DEBUG1 - 08:04:45.731579489 [575/669]: Receiving data from client ... (in thread_manage_clients() at client.c:2321)
  351. DEBUG1 - 08:04:45.731629769 [575/669]: Processing client command 17 (in process_client_msg() at client.c:719)
  352. DEBUG1 - 08:04:45.731651351 [575/669]: Getting session auto-20200804-080445 by name (in process_client_msg() at client.c:817)
  353. DEBUG2 - 08:04:45.731669317 [575/669]: Trying to find session by name auto-20200804-080445 (in session_find_by_name() at session.c:1000)
  354. DEBUG1 - 08:04:45.731691133 [575/669]: Begin stop session "auto-20200804-080445" (id 0) (in cmd_stop_trace() at cmd.c:2840)
  355. DEBUG1 - 08:04:45.731707815 [575/669]: Stopping all UST traces (in ust_app_stop_trace_all() at ust-app.c:4890)
  356. DEBUG1 - 08:04:45.731727297 [575/669]: Flushing session buffers for all ust apps (in ust_app_flush_session() at ust-app.c:4584)
  357. DEBUG1 - 08:04:45.731743863 [575/669]: Completed stop session "auto-20200804-080445" (id 0) (in cmd_stop_trace() at cmd.c:2864)
  358. DEBUG1 - 08:04:45.731764395 [575/669]: Sending response (size: 20, retcode: Success (10)) (in thread_manage_clients() at client.c:2383)
  359. DEBUG1 - 08:04:45.731814676 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  360. DEBUG1 - 08:04:45.731831358 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  361. DEBUG1 - 08:04:45.731903805 [575/669]: Wait for client response (in thread_manage_clients() at client.c:2276)
  362. DEBUG1 - 08:04:45.731941253 [575/669]: Receiving data from client ... (in thread_manage_clients() at client.c:2321)
  363. DEBUG1 - 08:04:45.731974851 [575/669]: Processing client command 24 (in process_client_msg() at client.c:719)
  364. DEBUG1 - 08:04:45.731999116 [575/669]: Getting session auto-20200804-080445 by name (in process_client_msg() at client.c:817)
  365. DEBUG2 - 08:04:45.732014282 [575/669]: Trying to find session by name auto-20200804-080445 (in session_find_by_name() at session.c:1000)
  366. DEBUG1 - 08:04:45.732033064 [575/669]: Data pending for session auto-20200804-080445 (in cmd_data_pending() at cmd.c:3853)
  367. DEBUG3 - 08:04:45.732051963 [575/669]: Consumer data pending for id 0 (in consumer_is_data_pending() at consumer.c:1265)
  368. DEBUG1 - 08:04:45.732187290 [680/691]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:3360)
  369. DEBUG1 - 08:04:45.732241070 [680/691]: UST consumer data pending command for id 0 (in lttng_ustconsumer_recv_cmd() at ust-consumer.c:1413)
  370. DEBUG1 - 08:04:45.732260202 [680/691]: Consumer data pending command on session id 0 (in consumer_data_pending() at consumer.c:3767)
  371. DEBUG1 - 08:04:45.732296134 [680/691]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:3376)
  372. DEBUG1 - 08:04:45.732300683 [575/669]: Consumer data is NOT pending for session id 0 (in consumer_is_data_pending() at consumer.c:1300)
  373. DEBUG1 - 08:04:45.732367180 [575/669]: Sending response (size: 21, retcode: Success (10)) (in thread_manage_clients() at client.c:2383)
  374. DEBUG1 - 08:04:45.732400195 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  375. DEBUG1 - 08:04:45.732415944 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  376. DEBUG1 - 08:04:45.732462375 [575/669]: Wait for client response (in thread_manage_clients() at client.c:2276)
  377. DEBUG1 - 08:04:45.732488973 [575/669]: Receiving data from client ... (in thread_manage_clients() at client.c:2321)
  378. DEBUG1 - 08:04:45.732519305 [575/669]: Processing client command 13 (in process_client_msg() at client.c:719)
  379. DEBUG1 - 08:04:45.732537271 [575/669]: Counting number of available session for UID 0 GID 0 (in lttng_sessions_count() at client.c:517)
  380. DEBUG1 - 08:04:45.732565386 [575/669]: Getting all available session for UID 0 GID 0 (in cmd_list_lttng_sessions() at cmd.c:3792)
  381. DEBUG1 - 08:04:45.732595601 [575/669]: Sending response (size: 4412, retcode: Success (10)) (in thread_manage_clients() at client.c:2383)
  382. DEBUG1 - 08:04:45.732628499 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  383. DEBUG1 - 08:04:45.732644482 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  384. DEBUG1 - 08:04:45.732659881 [575/669]: Wait for client response (in thread_manage_clients() at client.c:2276)
  385. DEBUG1 - 08:04:45.732684846 [575/669]: Receiving data from client ... (in thread_manage_clients() at client.c:2321)
  386. DEBUG1 - 08:04:45.732714361 [575/669]: Processing client command 11 (in process_client_msg() at client.c:719)
  387. DEBUG1 - 08:04:45.732730927 [575/669]: Getting session auto-20200804-080445 by name (in process_client_msg() at client.c:817)
  388. DEBUG2 - 08:04:45.732748193 [575/669]: Trying to find session by name auto-20200804-080445 (in session_find_by_name() at session.c:1000)
  389. DEBUG3 - 08:04:45.732763476 [575/669]: Listing domains found UST global domain (in cmd_list_domains() at cmd.c:3593)
  390. DEBUG1 - 08:04:45.732787391 [575/669]: Sending response (size: 328, retcode: Success (10)) (in thread_manage_clients() at client.c:2383)
  391. DEBUG1 - 08:04:45.732821222 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  392. DEBUG1 - 08:04:45.732838255 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  393. DEBUG1 - 08:04:45.732874303 [575/669]: Wait for client response (in thread_manage_clients() at client.c:2276)
  394. DEBUG1 - 08:04:45.732902185 [575/669]: Receiving data from client ... (in thread_manage_clients() at client.c:2321)
  395. DEBUG1 - 08:04:45.732934150 [575/669]: Processing client command 10 (in process_client_msg() at client.c:719)
  396. DEBUG1 - 08:04:45.732949316 [575/669]: Getting session auto-20200804-080445 by name (in process_client_msg() at client.c:817)
  397. DEBUG2 - 08:04:45.732962848 [575/669]: Trying to find session by name auto-20200804-080445 (in session_find_by_name() at session.c:1000)
  398. DEBUG3 - 08:04:45.732987464 [575/669]: Consumer socket created (fd: 70) and added to output (in consumer_create_socket() at consumer.c:350)
  399. DEBUG1 - 08:04:45.733004729 [575/669]: Setting relayd for session auto-20200804-080445 (in cmd_setup_relayd() at cmd.c:1191)
  400. DEBUG3 - 08:04:45.733028528 [575/669]: Number of UST global channels 1 (in cmd_list_channels() at cmd.c:3676)
  401. DEBUG1 - 08:04:45.733046144 [575/669]: Listing channels for session auto-20200804-080445 (in list_lttng_channels() at cmd.c:294)
  402. DEBUG1 - 08:04:45.733068193 [575/669]: Sending response (size: 676, retcode: Success (10)) (in thread_manage_clients() at client.c:2383)
  403. DEBUG1 - 08:04:45.733099924 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  404. DEBUG1 - 08:04:45.733115207 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  405. DEBUG1 - 08:04:45.738016696 [575/669]: Wait for client response (in thread_manage_clients() at client.c:2276)
  406. DEBUG1 - 08:04:45.738187604 [575/669]: Receiving data from client ... (in thread_manage_clients() at client.c:2321)
  407. DEBUG1 - 08:04:45.738235668 [575/669]: Processing client command 13 (in process_client_msg() at client.c:719)
  408. DEBUG1 - 08:04:45.738257717 [575/669]: Counting number of available session for UID 0 GID 0 (in lttng_sessions_count() at client.c:517)
  409. DEBUG1 - 08:04:45.738280349 [575/669]: Getting all available session for UID 0 GID 0 (in cmd_list_lttng_sessions() at cmd.c:3792)
  410. DEBUG1 - 08:04:45.738310447 [575/669]: Sending response (size: 4412, retcode: Success (10)) (in thread_manage_clients() at client.c:2383)
  411. DEBUG1 - 08:04:45.738367261 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  412. DEBUG1 - 08:04:45.738385110 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  413. DEBUG1 - 08:04:45.738531169 [575/669]: Wait for client response (in thread_manage_clients() at client.c:2276)
  414. DEBUG1 - 08:04:45.738557534 [575/669]: Receiving data from client ... (in thread_manage_clients() at client.c:2321)
  415. DEBUG1 - 08:04:45.738589032 [575/669]: Processing client command 17 (in process_client_msg() at client.c:719)
  416. DEBUG1 - 08:04:45.738605131 [575/669]: Getting session auto-20200804-080445 by name (in process_client_msg() at client.c:817)
  417. DEBUG2 - 08:04:45.738621114 [575/669]: Trying to find session by name auto-20200804-080445 (in session_find_by_name() at session.c:1000)
  418. DEBUG1 - 08:04:45.738642696 [575/669]: Begin stop session "auto-20200804-080445" (id 0) (in cmd_stop_trace() at cmd.c:2840)
  419. DEBUG1 - 08:04:45.738662995 [575/669]: Sending response (size: 20, retcode: Tracing has already been stopped (81)) (in thread_manage_clients() at client.c:2383)
  420. DEBUG1 - 08:04:45.738692627 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  421. DEBUG1 - 08:04:45.738708026 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  422. DEBUG1 - 08:04:45.738724592 [575/669]: Wait for client response (in thread_manage_clients() at client.c:2276)
  423. DEBUG1 - 08:04:45.738748157 [575/669]: Receiving data from client ... (in thread_manage_clients() at client.c:2321)
  424. DEBUG1 - 08:04:45.738777789 [575/669]: Processing client command 24 (in process_client_msg() at client.c:719)
  425. DEBUG1 - 08:04:45.738794471 [575/669]: Getting session auto-20200804-080445 by name (in process_client_msg() at client.c:817)
  426. DEBUG2 - 08:04:45.738808704 [575/669]: Trying to find session by name auto-20200804-080445 (in session_find_by_name() at session.c:1000)
  427. DEBUG1 - 08:04:45.738824453 [575/669]: Data pending for session auto-20200804-080445 (in cmd_data_pending() at cmd.c:3853)
  428. DEBUG3 - 08:04:45.738844635 [575/669]: Consumer data pending for id 0 (in consumer_is_data_pending() at consumer.c:1265)
  429. DEBUG1 - 08:04:45.738909265 [680/691]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:3360)
  430. DEBUG1 - 08:04:45.738960596 [680/691]: UST consumer data pending command for id 0 (in lttng_ustconsumer_recv_cmd() at ust-consumer.c:1413)
  431. DEBUG1 - 08:04:45.738978095 [680/691]: Consumer data pending command on session id 0 (in consumer_data_pending() at consumer.c:3767)
  432. DEBUG1 - 08:04:45.739015426 [680/691]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:3376)
  433. DEBUG1 - 08:04:45.739020209 [575/669]: Consumer data is NOT pending for session id 0 (in consumer_is_data_pending() at consumer.c:1300)
  434. DEBUG1 - 08:04:45.739042258 [575/669]: Sending response (size: 21, retcode: Success (10)) (in thread_manage_clients() at client.c:2383)
  435. DEBUG1 - 08:04:45.739074456 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  436. DEBUG1 - 08:04:45.739090089 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  437. DEBUG1 - 08:04:45.739122637 [575/669]: Wait for client response (in thread_manage_clients() at client.c:2276)
  438. DEBUG1 - 08:04:45.739148069 [575/669]: Receiving data from client ... (in thread_manage_clients() at client.c:2321)
  439. DEBUG1 - 08:04:45.739262396 [575/669]: Processing client command 9 (in process_client_msg() at client.c:719)
  440. DEBUG1 - 08:04:45.739299961 [575/669]: Getting session auto-20200804-080445 by name (in process_client_msg() at client.c:817)
  441. DEBUG2 - 08:04:45.739315944 [575/669]: Trying to find session by name auto-20200804-080445 (in session_find_by_name() at session.c:1000)
  442. DEBUG1 - 08:04:45.739335426 [575/669]: Begin destroy session auto-20200804-080445 (id 0) (in cmd_destroy_session() at cmd.c:3350)
  443. DEBUG1 - 08:04:45.739367508 [575/669]: Setting trace chunk close command to "no operation" (in lttng_trace_chunk_set_close_command() at trace-chunk.c:1783)
  444. DEBUG1 - 08:04:45.739387806 [575/669]: lttng_trace_chunk_rename_path from to (in lttng_trace_chunk_rename_path_no_lock() at trace-chunk.c:740)
  445. DEBUG1 - 08:04:45.739425954 [575/669]: Sending consumer close trace chunk command: relayd_id = -1, session_id = 0, chunk_id = 0, close command = "none" (in consumer_close_trace_chunk() at consumer.c:2007)
  446. DEBUG1 - 08:04:45.739462586 [680/691]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:3360)
  447. DEBUG1 - 08:04:45.739495134 [680/691]: Consumer close trace chunk command: relayd_id = (none), session_id = 0, chunk_id = 0, close command = none (in lttng_consumer_close_trace_chunk() at consumer.c:4760)
  448. DEBUG1 - 08:04:45.740211546 [680/691]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:3376)
  449. DEBUG1 - 08:04:45.740235228 [575/669]: Enabling session rotation pending check timer on session 0 (in timer_session_rotation_pending_check_start() at timer.c:212)
  450. DEBUG1 - 08:04:45.740316190 [575/669]: Cmd rotate session auto-20200804-080445, archive_id 0 sent (in cmd_rotate_session() at cmd.c:5138)
  451. DEBUG1 - 08:04:45.740337423 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  452. DEBUG1 - 08:04:45.740353405 [575/669]: Accepting client command ... (in thread_manage_clients() at client.c:2234)
  453. DEBUG1 - 08:04:46.241195309 [575/668]: [rotation-thread] Poll wait returned (1) (in thread_rotation() at rotation-thread.c:798)
  454. DEBUG1 - 08:04:46.241252239 [575/668]: [rotation-thread] Handling fd (15) activity (1) (in thread_rotation() at rotation-thread.c:816)
  455. DEBUG1 - 08:04:46.241280121 [575/668]: [rotation-thread] Checking for pending rotation on session "auto-20200804-080445", trace archive 0 (in check_session_rotation_pending() at rotation-thread.c:442)
  456. DEBUG1 - 08:04:46.241306953 [575/668]: Disabling session rotation pending check timer on session 0 (in timer_session_rotation_pending_check_stop() at timer.c:244)
  457. DEBUG1 - 08:04:46.241934703 [575/668]: Sending consumer trace chunk exists command: relayd_id = -1, session_id = 0, chunk_id = 0 (in consumer_trace_chunk_exists() at consumer.c:2101)
  458. DEBUG1 - 08:04:46.242526988 [680/691]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:3360)
  459. DEBUG1 - 08:04:46.242600718 [680/691]: Consumer trace chunk exists command: relayd_id = (none), chunk_id = 0 (in lttng_consumer_trace_chunk_exists() at consumer.c:4881)
  460. DEBUG1 - 08:04:46.242633149 [680/691]: Trace chunk does not exist locally (in lttng_consumer_trace_chunk_exists() at consumer.c:4893)
  461. DEBUG1 - 08:04:46.242676197 [680/691]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:3376)
  462. DEBUG1 - 08:04:46.242681097 [575/668]: Consumer ret code -128 (in consumer_recv_status_reply() at consumer.c:200)
  463. DEBUG1 - 08:04:46.242714345 [575/668]: Consumer reply to TRACE_CHUNK_EXISTS command: unknown trace chunk (in consumer_trace_chunk_exists() at consumer.c:2125)
  464. DEBUG1 - 08:04:46.242740477 [575/668]: [rotation-thread] Rotation of trace archive 0 of session "auto-20200804-080445" is complete on all consumers (in check_session_rotation_pending_on_consumers() at rotation-thread.c:402)
  465. DEBUG3 - 08:04:46.242808723 [575/668]: No kernel session when tearing down session (in kernel_destroy_session() at kernel.c:1440)
  466. DEBUG1 - 08:04:46.242836722 [575/668]: Destroy all UST traces (in ust_app_destroy_trace_all() at ust-app.c:4924)
  467. DEBUG2 - 08:04:46.242866937 [575/668]: Trace UST destroy session 0 (in trace_ust_destroy_session() at trace-ust.c:1398)
  468. DEBUG3 - 08:04:46.243016146 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  469. DEBUG3 - 08:04:46.243583232 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  470. DEBUG3 - 08:04:46.243613914 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  471. DEBUG1 - 08:04:46.243630946 [575/668]: Destroying session auto-20200804-080445 (id 0) (in session_release() at session.c:885)
  472. DEBUG3 - 08:04:46.243671427 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  473. DEBUG1 - 08:04:46.243671777 [575/668]: Empty ltt_sessions_ht_by_id, destroying it (in del_session_ht() at session.c:391)
  474. DEBUG3 - 08:04:46.243708759 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  475. DEBUG1 - 08:04:46.243741890 [575/668]: No implicit rotation performed during the destruction of session "auto-20200804-080445", sending reply (in cmd_destroy_session_reply() at cmd.c:3281)
  476. DEBUG1 - 08:04:46.243853651 [575/668]: [rotation-thread] Entering poll wait (in thread_rotation() at rotation-thread.c:796)
  477. DEBUG1 - 08:04:46.243922014 [575/675]: Thread kernel return from poll on 2 fds (in thread_kernel_management() at manage-kernel.c:233)
  478. DEBUG1 - 08:04:46.244027825 [575/675]: Updating kernel poll set (in update_kernel_poll() at manage-kernel.c:37)
  479. DEBUG1 - 08:04:46.244053957 [575/675]: Thread kernel polling (in thread_kernel_management() at manage-kernel.c:227)
  480. DEBUG3 - 08:04:46.244213665 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  481. DEBUG3 - 08:04:46.244246680 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  482. DEBUG3 - 08:04:46.244749953 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  483. DEBUG3 - 08:04:46.244777719 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  484. DEBUG3 - 08:04:46.245342822 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  485. DEBUG3 - 08:04:46.245369537 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  486. DEBUG3 - 08:04:46.245942223 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  487. DEBUG3 - 08:04:46.245969172 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  488. DEBUG3 - 08:04:46.246022719 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  489. DEBUG3 - 08:04:46.246048034 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  490. DEBUG3 - 08:04:46.246102398 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  491. DEBUG2 - 08:04:46.253565168 [575/692]: Trace destroy UST event * (in trace_ust_destroy_event() at trace-ust.c:1241)
  492. DEBUG2 - 08:04:46.253629798 [575/692]: Trace destroy UST channel channel0 (in _trace_ust_destroy_channel() at trace-ust.c:1306)
  493. DEBUG1 - 08:04:52.944995319 [576/576]: run_as worker received signal SIGTERM (in worker_sighandler() at runas.c:1147)
  494. DEBUG1 - 08:04:52.945365716 [575/575]: SIGTERM caught (in sighandler() at main.c:1109)
  495. DEBUG1 - 08:04:52.945410863 [575/575]: Terminating all threads (in stop_threads() at main.c:163)
  496. DEBUG1 - 08:04:52.945481793 [575/575]: Shutting down "Client management" thread (in _lttng_thread_shutdown() at thread.c:152)
  497. DEBUG1 - 08:04:52.945540123 [575/669]: Clean command context structure (in clean_command_ctx() at client.c:655)
  498. DEBUG1 - 08:04:52.945695165 [575/669]: Client thread dying (in thread_manage_clients() at client.c:2433)
  499. DEBUG1 - 08:04:52.945719897 [575/669]: Thread "Client management" has returned (in launch_thread() at thread.c:66)
  500. DEBUG1 - 08:04:52.945856739 [575/575]: Initiating destruction of all sessions (in destroy_all_sessions_and_wait() at main.c:1224)
  501. DEBUG1 - 08:04:52.945877038 [575/575]: Waiting for the destruction of all sessions to complete (in destroy_all_sessions_and_wait() at main.c:1252)
  502. DEBUG1 - 08:04:52.945931285 [575/575]: Destruction of all sessions completed (in destroy_all_sessions_and_wait() at main.c:1254)
  503. DEBUG1 - 08:04:52.945948901 [575/575]: Shutting down "UST application registration" thread (in _lttng_thread_shutdown() at thread.c:152)
  504. DEBUG1 - 08:04:52.945991599 [575/671]: Notifying applications of session daemon state: 0 (in notify_ust_apps() at register.c:90)
  505. PERROR - 08:04:52.946027880 [575/671]: Failed to open wait shm at /lttng-ust-wait-8: Function not implemented (in get_wait_shm() at shm.c:98)
  506. DEBUG1 - 08:04:52.946049929 [575/671]: Failing to get the wait shm fd (in get_wait_shm() at shm.c:143)
  507. DEBUG1 - 08:04:52.946130658 [575/671]: UST Registration thread cleanup complete (in thread_application_registration() at register.c:362)
  508. DEBUG1 - 08:04:52.946147691 [575/671]: Thread "UST application registration" has returned (in launch_thread() at thread.c:66)
  509. DEBUG1 - 08:04:52.946205088 [575/575]: Shutting down "Consumer management" thread (in _lttng_thread_shutdown() at thread.c:152)
  510. DEBUG1 - 08:04:52.946291416 [680/691]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:3360)
  511. DEBUG1 - 08:04:52.946329448 [680/691]: Consumer received unexpected message size 0 (expects 12689) (in lttng_ustconsumer_recv_cmd() at ust-consumer.c:1340)
  512. DEBUG1 - 08:04:52.946346597 [680/691]: Communication interrupted on command socket (in consumer_thread_sessiond_poll() at consumer.c:3367)
  513. DEBUG1 - 08:04:52.946360713 [680/691]: Consumer thread sessiond poll exiting (in consumer_thread_sessiond_poll() at consumer.c:3382)
  514. DEBUG1 - 08:04:52.946374945 [680/691]: UST consumer closing all metadata streams (in lttng_ustconsumer_close_all_metadata() at ust-consumer.c:3153)
  515. DEBUG1 - 08:04:52.946377978 [575/681]: consumer thread cleanup completed (in thread_consumer_management() at manage-consumer.c:410)
  516. DEBUG1 - 08:04:52.946400027 [575/681]: Thread "Consumer management" has returned (in launch_thread() at thread.c:66)
  517. DEBUG1 - 08:04:52.946443192 [680/690]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2712)
  518. DEBUG1 - 08:04:52.946453691 [680/688]: Channel poll return from wait with 1 fd(s) (in consumer_thread_channel_poll() at consumer.c:3039)
  519. DEBUG1 - 08:04:52.946479240 [575/575]: Shutting down "Kernel management" thread (in _lttng_thread_shutdown() at thread.c:152)
  520. DEBUG1 - 08:04:52.946487406 [680/688]: Channel event caught in thread (in consumer_thread_channel_poll() at consumer.c:3042)
  521. DEBUG1 - 08:04:52.946517388 [680/688]: Channel poll wait (in consumer_thread_channel_poll() at consumer.c:3036)
  522. DEBUG1 - 08:04:52.946521354 [575/675]: Thread kernel return from poll on 2 fds (in thread_kernel_management() at manage-kernel.c:233)
  523. DEBUG1 - 08:04:52.946533137 [680/688]: Channel poll return from wait with 0 fd(s) (in consumer_thread_channel_poll() at consumer.c:3039)
  524. DEBUG1 - 08:04:52.946552269 [680/688]: Channel event caught in thread (in consumer_thread_channel_poll() at consumer.c:3042)
  525. DEBUG1 - 08:04:52.946558102 [575/675]: Kernel thread dying (in thread_kernel_management() at manage-kernel.c:309)
  526. DEBUG1 - 08:04:52.946574085 [575/675]: Thread "Kernel management" has returned (in launch_thread() at thread.c:66)
  527. DEBUG1 - 08:04:52.946641514 [575/575]: Shutting down "Agent management" thread (in _lttng_thread_shutdown() at thread.c:152)
  528. DEBUG1 - 08:04:52.946655864 [680/685]: Health check thread dying (in thread_manage_health() at health-consumerd.c:334)
  529. DEBUG1 - 08:04:52.946692495 [680/688]: Channel poll thread exiting (in consumer_thread_channel_poll() at consumer.c:3218)
  530. DEBUG3 - 08:04:52.946718977 [575/674]: [agent-thread] Manage agent return from poll on 2 fds (in thread_agent_management() at agent-thread.c:378)
  531. DEBUG3 - 08:04:52.946738809 [575/674]: [agent-thread] 1 fd ready (in thread_agent_management() at agent-thread.c:390)
  532. DEBUG3 - 08:04:52.946760391 [575/674]: [agent-thread] Destroy TCP socket on port 5345 (in destroy_tcp_socket() at agent-thread.c:183)
  533. DEBUG1 - 08:04:52.946788507 [680/690]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2742)
  534. DEBUG1 - 08:04:52.946790490 [575/674]: [agent-thread] Cleaning up and stopping. (in thread_agent_management() at agent-thread.c:462)
  535. DEBUG1 - 08:04:52.946840887 [680/690]: Consumer delete flagged data stream (in validate_endpoint_status_data_stream() at consumer.c:2356)
  536. DEBUG1 - 08:04:52.946843454 [575/674]: Thread "Agent management" has returned (in launch_thread() at thread.c:66)
  537. DEBUG1 - 08:04:52.946860136 [680/690]: polling thread exiting (in consumer_thread_data_poll() at consumer.c:2884)
  538. DEBUG1 - 08:04:52.946903417 [680/689]: Metadata poll return from wait with 1 fd(s) (in consumer_thread_metadata_poll() at consumer.c:2450)
  539. DEBUG1 - 08:04:52.946929199 [680/689]: Metadata event caught in thread (in consumer_thread_metadata_poll() at consumer.c:2453)
  540. DEBUG1 - 08:04:52.946947398 [680/689]: Metadata thread pipe hung up (in consumer_thread_metadata_poll() at consumer.c:2508)
  541. DEBUG1 - 08:04:52.946982396 [680/689]: Metadata poll wait (in consumer_thread_metadata_poll() at consumer.c:2448)
  542. DEBUG1 - 08:04:52.947004912 [680/689]: Metadata poll return from wait with 0 fd(s) (in consumer_thread_metadata_poll() at consumer.c:2450)
  543. DEBUG1 - 08:04:52.947018444 [680/689]: Metadata event caught in thread (in consumer_thread_metadata_poll() at consumer.c:2453)
  544. DEBUG1 - 08:04:52.947032910 [680/689]: Metadata poll thread exiting (in consumer_thread_metadata_poll() at consumer.c:2602)
  545. DEBUG1 - 08:04:52.947300296 [575/575]: Shutting down "Application notification" thread (in _lttng_thread_shutdown() at thread.c:152)
  546. DEBUG3 - 08:04:52.947344627 [575/673]: [ust-thread] Manage notify return from poll on 2 fds (in thread_application_notification() at notify-apps.c:80)
  547. DEBUG1 - 08:04:52.947379042 [575/673]: Application notify communication apps thread cleanup complete (in thread_application_notification() at notify-apps.c:183)
  548. DEBUG1 - 08:04:52.947397124 [575/673]: Thread "Application notification" has returned (in launch_thread() at thread.c:66)
  549. DEBUG1 - 08:04:52.947464321 [575/575]: Shutting down "UST application management" thread (in _lttng_thread_shutdown() at thread.c:152)
  550. DEBUG1 - 08:04:52.947508302 [575/672]: Apps thread return from poll on 2 fds (in thread_application_management() at manage-apps.c:94)
  551. DEBUG1 - 08:04:52.947543650 [575/672]: Application communication apps thread cleanup complete (in thread_application_management() at manage-apps.c:194)
  552. DEBUG1 - 08:04:52.947559282 [575/672]: Thread "UST application management" has returned (in launch_thread() at thread.c:66)
  553. DEBUG1 - 08:04:52.947627412 [575/575]: Shutting down "UST registration dispatch" thread (in _lttng_thread_shutdown() at thread.c:152)
  554. DEBUG1 - 08:04:52.947654244 [575/575]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:112)
  555. DEBUG1 - 08:04:52.947662293 [575/670]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:95)
  556. DEBUG1 - 08:04:52.947686325 [575/670]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:67)
  557. DEBUG1 - 08:04:52.947704174 [575/670]: Dispatch thread dying (in thread_dispatch_ust_registration() at dispatch.c:478)
  558. DEBUG1 - 08:04:52.947744539 [575/670]: Thread "UST registration dispatch" has returned (in launch_thread() at thread.c:66)
  559. DEBUG1 - 08:04:52.947800886 [575/575]: Shutting down "Rotation" thread (in _lttng_thread_shutdown() at thread.c:152)
  560. DEBUG1 - 08:04:52.947838567 [575/668]: [rotation-thread] Poll wait returned (1) (in thread_rotation() at rotation-thread.c:798)
  561. DEBUG1 - 08:04:52.947859216 [575/668]: [rotation-thread] Handling fd (38) activity (1) (in thread_rotation() at rotation-thread.c:816)
  562. DEBUG1 - 08:04:52.947876949 [575/668]: [rotation-thread] Quit pipe activity (in thread_rotation() at rotation-thread.c:856)
  563. DEBUG1 - 08:04:52.947892114 [575/668]: [rotation-thread] Exit (in thread_rotation() at rotation-thread.c:864)
  564. DEBUG1 - 08:04:52.947973310 [575/668]: Thread "Rotation" has returned (in launch_thread() at thread.c:66)
  565. DEBUG1 - 08:04:52.947973660 [575/666]: [notification-thread] Poll wait returned (1) (in thread_notification() at notification-thread.c:545)
  566. DEBUG1 - 08:04:52.948012625 [575/666]: [notification-thread] Handling fd (46) activity (8209) (in thread_notification() at notification-thread.c:563)
  567. DEBUG1 - 08:04:52.948052289 [575/666]: [notification-thread] Closing client connection (socket fd = 46) (in handle_notification_thread_client_disconnect() at notification-thread-events.c:2486)
  568. DEBUG1 - 08:04:52.948069788 [575/575]: Shutting down "Timer" thread (in _lttng_thread_shutdown() at thread.c:152)
  569. DEBUG1 - 08:04:52.948141185 [575/667]: [timer-thread] Exit (in thread_timer() at timer.c:402)
  570. DEBUG1 - 08:04:52.948145618 [575/666]: [notification-thread] Entering poll wait (in thread_notification() at notification-thread.c:543)
  571. DEBUG1 - 08:04:52.948171050 [575/667]: Thread "Timer" has returned (in launch_thread() at thread.c:66)
  572. DEBUG1 - 08:04:52.948546346 [575/575]: Shutting down "Health management" thread (in _lttng_thread_shutdown() at thread.c:152)
  573. DEBUG1 - 08:04:52.948591494 [575/665]: Health check thread dying (in thread_manage_health() at health.c:231)
  574. DEBUG1 - 08:04:52.948706055 [575/665]: Thread "Health management" has returned (in launch_thread() at thread.c:66)
  575. DEBUG1 - 08:04:52.957466438 [680/680]: Releasing trace chunk registry to all trace chunks (in lttng_trace_chunk_registry_put_each_chunk() at trace-chunk.c:2144)
  576. DEBUG1 - 08:04:52.957509836 [680/680]: Released reference to 0 trace chunks in lttng_trace_chunk_registry_put_each_chunk() (in lttng_trace_chunk_registry_put_each_chunk() at trace-chunk.c:2177)
  577. DEBUG1 - 08:04:52.958244914 [575/575]: Cleanup sessiond (in sessiond_cleanup() at main.c:268)
  578. DEBUG1 - 08:04:52.958496667 [575/575]: Removing sessiond and consumerd content of directory /var/run/lttng (in sessiond_cleanup() at main.c:284)
  579. DEBUG1 - 08:04:52.958523499 [575/575]: Removing /var/run/lttng/lttng-sessiond.pid (in sessiond_cleanup() at main.c:288)
  580. DEBUG1 - 08:04:52.958551614 [575/575]: Removing /var/run/lttng/agent.port (in sessiond_cleanup() at main.c:291)
  581. DEBUG1 - 08:04:52.958663841 [575/575]: Removing /var/run/lttng/kconsumerd/error (in sessiond_cleanup() at main.c:295)
  582. DEBUG1 - 08:04:52.958694640 [575/575]: Removing directory /var/run/lttng/kconsumerd (in sessiond_cleanup() at main.c:298)
  583. DEBUG1 - 08:04:52.958736521 [575/575]: Removing /var/run/lttng/ustconsumerd32/error (in sessiond_cleanup() at main.c:302)
  584. DEBUG1 - 08:04:52.958805934 [575/575]: Removing directory /var/run/lttng/ustconsumerd32 (in sessiond_cleanup() at main.c:305)
  585. DEBUG1 - 08:04:52.958863797 [575/575]: Removing /var/run/lttng/ustconsumerd64/error (in sessiond_cleanup() at main.c:309)
  586. DEBUG1 - 08:04:52.958896579 [575/575]: Removing directory /var/run/lttng/ustconsumerd64 (in sessiond_cleanup() at main.c:312)
  587. DEBUG1 - 08:04:52.958957942 [575/575]: Cleaning up all agent apps (in sessiond_cleanup() at main.c:317)
  588. DEBUG1 - 08:04:52.959031089 [575/575]: Closing all UST sockets (in sessiond_cleanup() at main.c:319)
  589. DEBUG2 - 08:04:52.959055587 [575/575]: UST app cleaning registered apps hash table (in ust_app_clean_list() at ust-app.c:3849)
  590. DEBUG3 - 08:04:52.959110768 [575/575]: Buffer registry destroy all registry (in buffer_reg_destroy_registries() at buffer-registry.c:743)
  591. DEBUG3 - 08:04:52.959111934 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  592. DEBUG3 - 08:04:52.959205379 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  593. DEBUG3 - 08:04:52.959235244 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  594. DEBUG3 - 08:04:52.959287042 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  595. DEBUG3 - 08:04:52.959312707 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  596. DEBUG3 - 08:04:52.959364737 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  597. DEBUG1 - 08:04:52.959384570 [575/575]: Waiting for complete teardown of consumerd (PID: 680) (in wait_consumer() at main.c:248)
  598. DEBUG3 - 08:04:52.959390286 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  599. DEBUG3 - 08:04:52.959464482 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  600. DEBUG3 - 08:04:52.959516513 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  601. DEBUG3 - 08:04:52.959574026 [575/577]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
  602. DEBUG1 - 08:04:52.968780986 [680/680]: Consumer destroying it. Closing everything. (in lttng_consumer_destroy() at consumer.c:1595)
  603. DEBUG1 - 08:04:52.980324306 [680/680]: Destroying run_as worker (in run_as_destroy_worker_no_lock() at runas.c:1313)
  604. DEBUG1 - 08:04:52.980356387 [680/680]: Closing run_as worker socket (in run_as_destroy_worker_no_lock() at runas.c:1318)
  605. DEBUG1 - 08:04:52.982134293 [680/680]: lttng-runas terminated with status code 0 (in run_as_destroy_worker_no_lock() at runas.c:1338)
  606. DEBUG1 - 08:04:52.983383843 [575/666]: [notification-thread] Poll wait returned (3) (in thread_notification() at notification-thread.c:545)
  607. DEBUG1 - 08:04:52.983423741 [575/666]: [notification-thread] Handling fd (17) activity (16) (in thread_notification() at notification-thread.c:563)
  608. DEBUG1 - 08:04:52.983457922 [575/666]: [notification-thread] Handling fd (13) activity (16) (in thread_notification() at notification-thread.c:563)
  609. DEBUG2 - 08:04:52.983474138 [575/575]: Closing kernel fd (in cleanup_kernel_tracer() at kernel.c:1878)
  610. DEBUG1 - 08:04:52.983482888 [575/666]: [notification-thread] Handling fd (11) activity (16) (in thread_notification() at notification-thread.c:563)
  611. DEBUG1 - 08:04:52.983521852 [575/575]: Unloading kernel modules (in cleanup_kernel_tracer() at kernel.c:1886)
  612. DEBUG1 - 08:04:52.983533402 [575/666]: [notification-thread] Entering poll wait (in thread_notification() at notification-thread.c:543)
  613. DEBUG1 - 08:04:53.009646212 [575/575]: Modprobe removal successful lttng-probe-writeback (in modprobe_remove_lttng() at modprobe.c:372)
  614. DEBUG1 - 08:04:53.037214596 [575/575]: Modprobe removal successful lttng-probe-workqueue (in modprobe_remove_lttng() at modprobe.c:372)
  615. DEBUG1 - 08:04:53.061600598 [575/575]: Modprobe removal successful lttng-probe-v4l2 (in modprobe_remove_lttng() at modprobe.c:372)
  616. DEBUG1 - 08:04:53.085602205 [575/575]: Modprobe removal successful lttng-probe-vmscan (in modprobe_remove_lttng() at modprobe.c:372)
  617. DEBUG1 - 08:04:53.109175201 [575/575]: Modprobe removal successful lttng-probe-udp (in modprobe_remove_lttng() at modprobe.c:372)
  618. DEBUG1 - 08:04:53.133300117 [575/575]: Modprobe removal successful lttng-probe-timer (in modprobe_remove_lttng() at modprobe.c:372)
  619. DEBUG1 - 08:04:53.169879004 [575/575]: Modprobe removal successful lttng-probe-sunrpc (in modprobe_remove_lttng() at modprobe.c:372)
  620. DEBUG1 - 08:04:53.192836150 [575/575]: Modprobe removal successful lttng-probe-statedump (in modprobe_remove_lttng() at modprobe.c:372)
  621. DEBUG1 - 08:04:53.217346629 [575/575]: Modprobe removal successful lttng-probe-sock (in modprobe_remove_lttng() at modprobe.c:372)
  622. DEBUG1 - 08:04:53.241209293 [575/575]: Modprobe removal successful lttng-probe-skb (in modprobe_remove_lttng() at modprobe.c:372)
  623. DEBUG1 - 08:04:53.265177884 [575/575]: Modprobe removal successful lttng-probe-signal (in modprobe_remove_lttng() at modprobe.c:372)
  624. DEBUG1 - 08:04:53.288839542 [575/575]: Modprobe removal successful lttng-probe-scsi (in modprobe_remove_lttng() at modprobe.c:372)
  625. DEBUG1 - 08:04:53.313325989 [575/575]: Modprobe removal successful lttng-probe-sched (in modprobe_remove_lttng() at modprobe.c:372)
  626. DEBUG1 - 08:04:53.337330046 [575/575]: Modprobe removal successful lttng-probe-regulator (in modprobe_remove_lttng() at modprobe.c:372)
  627. DEBUG1 - 08:04:53.489712638 [575/575]: Modprobe removal successful lttng-probe-regmap (in modprobe_remove_lttng() at modprobe.c:372)
  628. DEBUG1 - 08:04:53.496070517 [575/575]: Modprobe removal successful lttng-probe-rcu (in modprobe_remove_lttng() at modprobe.c:372)
  629. DEBUG1 - 08:04:53.501598589 [575/575]: Modprobe removal successful lttng-probe-random (in modprobe_remove_lttng() at modprobe.c:372)
  630. DEBUG1 - 08:04:53.505330441 [575/575]: Modprobe removal successful lttng-probe-printk (in modprobe_remove_lttng() at modprobe.c:372)
  631. DEBUG1 - 08:04:53.509128905 [575/575]: Modprobe removal successful lttng-probe-power (in modprobe_remove_lttng() at modprobe.c:372)
  632. DEBUG1 - 08:04:53.512875223 [575/575]: Modprobe removal successful lttng-probe-net (in modprobe_remove_lttng() at modprobe.c:372)
  633. DEBUG1 - 08:04:53.516685003 [575/575]: Modprobe removal successful lttng-probe-napi (in modprobe_remove_lttng() at modprobe.c:372)
  634. DEBUG1 - 08:04:53.520399706 [575/575]: Modprobe removal successful lttng-probe-module (in modprobe_remove_lttng() at modprobe.c:372)
  635. DEBUG1 - 08:04:53.524128641 [575/575]: Modprobe removal successful lttng-probe-kvm (in modprobe_remove_lttng() at modprobe.c:372)
  636. DEBUG1 - 08:04:53.527803912 [575/575]: Modprobe removal successful lttng-probe-kmem (in modprobe_remove_lttng() at modprobe.c:372)
  637. DEBUG1 - 08:04:53.531491666 [575/575]: Modprobe removal successful lttng-probe-jbd2 (in modprobe_remove_lttng() at modprobe.c:372)
  638. DEBUG1 - 08:04:53.535218034 [575/575]: Modprobe removal successful lttng-probe-irq (in modprobe_remove_lttng() at modprobe.c:372)
  639. DEBUG1 - 08:04:53.538991300 [575/575]: Modprobe removal successful lttng-probe-i2c (in modprobe_remove_lttng() at modprobe.c:372)
  640. DEBUG1 - 08:04:53.542678237 [575/575]: Modprobe removal successful lttng-probe-gpio (in modprobe_remove_lttng() at modprobe.c:372)
  641. DEBUG1 - 08:04:53.546444270 [575/575]: Modprobe removal successful lttng-probe-ext4 (in modprobe_remove_lttng() at modprobe.c:372)
  642. DEBUG1 - 08:04:53.550204003 [575/575]: Modprobe removal successful lttng-probe-compaction (in modprobe_remove_lttng() at modprobe.c:372)
  643. DEBUG1 - 08:04:53.553905990 [575/575]: Modprobe removal successful lttng-probe-btrfs (in modprobe_remove_lttng() at modprobe.c:372)
  644. DEBUG1 - 08:04:53.557743302 [575/575]: Modprobe removal successful lttng-probe-block (in modprobe_remove_lttng() at modprobe.c:372)
  645. DEBUG1 - 08:04:53.561504202 [575/575]: Modprobe removal successful lttng-ring-buffer-metadata-mmap-client (in modprobe_remove_lttng() at modprobe.c:372)
  646. DEBUG1 - 08:04:53.565229871 [575/575]: Modprobe removal successful lttng-ring-buffer-client-mmap-overwrite (in modprobe_remove_lttng() at modprobe.c:372)
  647. DEBUG1 - 08:04:53.568948656 [575/575]: Modprobe removal successful lttng-ring-buffer-client-mmap-discard (in modprobe_remove_lttng() at modprobe.c:372)
  648. DEBUG1 - 08:04:53.572690540 [575/575]: Modprobe removal successful lttng-ring-buffer-metadata-client (in modprobe_remove_lttng() at modprobe.c:372)
  649. DEBUG1 - 08:04:53.576974313 [575/575]: Modprobe removal successful lttng-ring-buffer-client-overwrite (in modprobe_remove_lttng() at modprobe.c:372)
  650. DEBUG1 - 08:04:53.580701731 [575/575]: Modprobe removal successful lttng-ring-buffer-client-discard (in modprobe_remove_lttng() at modprobe.c:372)
  651. DEBUG1 - 08:04:53.580827141 [575/575]: Shutting down "Notification" thread (in _lttng_thread_shutdown() at thread.c:152)
  652. DEBUG1 - 08:04:53.580873338 [575/575]: Beginning of waiter wait period (in lttng_waiter_wait() at waiter.c:46)
  653. DEBUG1 - 08:04:53.580882438 [575/666]: [notification-thread] Poll wait returned (1) (in thread_notification() at notification-thread.c:545)
  654. DEBUG1 - 08:04:53.580910203 [575/666]: [notification-thread] Handling fd (34) activity (1) (in thread_notification() at notification-thread.c:563)
  655. DEBUG1 - 08:04:53.580935985 [575/666]: [notification-thread] Received quit command (in handle_notification_thread_command() at notification-thread-events.c:2331)
  656. DEBUG1 - 08:04:53.580961884 [575/666]: [notification-thread] Closing all client connections (in handle_notification_thread_client_disconnect_all() at notification-thread-events.c:2517)
  657. DEBUG1 - 08:04:53.580962000 [575/575]: End of waiter wait period (in lttng_waiter_wait() at waiter.c:89)
  658. DEBUG1 - 08:04:53.581264734 [575/666]: [notification-thread] Destroying notification channel socket (in notification_channel_socket_destroy() at notification-thread.c:192)
  659. PERROR - 08:04:53.581319915 [575/666]: unlink notification channel socket: No such file or directory (in notification_channel_socket_destroy() at notification-thread.c:198)
  660. DEBUG1 - 08:04:53.581455824 [575/666]: Thread "Notification" has returned (in launch_thread() at thread.c:66)
  661. DEBUG1 - 08:04:53.591779225 [575/575]: Shutting down "HT cleanup" thread (in _lttng_thread_shutdown() at thread.c:152)
  662. DEBUG3 - 08:04:53.591840122 [575/577]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
  663. DEBUG1 - 08:04:53.591875236 [575/577]: [ht-cleanup] quit. (in thread_ht_cleanup() at ht-cleanup.c:206)
  664. DEBUG1 - 08:04:53.591911518 [575/577]: [ht-cleanup] Thread terminates. (in thread_ht_cleanup() at ht-cleanup.c:217)
  665. DEBUG1 - 08:04:53.591938233 [575/577]: Thread "HT cleanup" has returned (in launch_thread() at thread.c:66)
  666. PERROR - 08:04:53.592294164 [575/575]: remove lock file: No such file or directory (in sessiond_cleanup_lock_file() at main.c:911)
  667. DEBUG1 - 08:04:53.592367777 [575/575]: Cleaning up options (in sessiond_cleanup_options() at main.c:345)
  668. DEBUG1 - 08:04:53.592405925 [575/575]: Destroying run_as worker (in run_as_destroy_worker_no_lock() at runas.c:1313)
  669. DEBUG1 - 08:04:53.592430424 [575/575]: Closing run_as worker socket (in run_as_destroy_worker_no_lock() at runas.c:1318)
  670. DEBUG1 - 08:04:53.593032158 [576/576]: run_as worker exiting (ret = 0) (in run_as_create_worker_no_lock() at runas.c:1263)
  671. DEBUG1 - 08:04:53.594423568 [575/575]: lttng-runas terminated with status code 0 (in run_as_destroy_worker_no_lock() at runas.c:1338)
  672.  
Advertisement
Add Comment
Please, Sign In to add comment
Advertisement
Public Pastes
Advertisement
We use cookies for various purposes including analytics. By continuing to use Pastebin, you agree to our use of cookies as described in the Cookies Policy.  OK, I Understand
Not a member of Pastebin yet?
Sign Up, it unlocks many cool features!