Console Output

Skipping 344 KB.. Full Log
[ 11%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/bpf.c.o
[ 11%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/libbpf_probes.c.o
[ 13%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/netlink.c.o
[ 13%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/xsk.c.o
[ 16%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/btf_dump.c.o
[ 16%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/bpf_prog_linfo.c.o
[ 16%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/nlattr.c.o
[ 19%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/hashmap.c.o
[ 19%] Building CXX object src/cc/frontends/clang/CMakeFiles/clang_frontend.dir/b_frontend_action.cc.o
[ 19%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/libbpf.c.o
[ 19%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/ringbuf.c.o
[ 22%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/btf.c.o
[ 22%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/str_error.c.o
[ 22%] Linking C static library libbcc_bpf.a
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 22%] Built target bpf-static
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
Scanning dependencies of target api-static
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 22%] Building CXX object src/cc/api/CMakeFiles/api-static.dir/BPF.cc.o
[ 22%] Building CXX object src/cc/api/CMakeFiles/api-static.dir/BPFTable.cc.o
[ 25%] Linking CXX static library libapi-static.a
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 25%] Built target api-static
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
Scanning dependencies of target usdt-static
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 25%] Building CXX object src/cc/usdt/CMakeFiles/usdt-static.dir/usdt_args.cc.o
[ 25%] Building CXX object src/cc/usdt/CMakeFiles/usdt-static.dir/usdt.cc.o
[ 27%] Linking CXX static library libusdt-static.a
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 27%] Built target usdt-static
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 27%] [FLEX][Lexer] Building scanner with flex 2.6.0
lexer.ll:110: warning, -s option given but default rule can be matched
[ 27%] [BISON][Parser] Building parser with bison 3.0.4
parser.yy:19.9-17: warning: deprecated directive, use ‘%define api.namespace ebpf::cc’ [-Wdeprecated]
 %define namespace "ebpf::cc"
         ^^^^^^^^^
parser.yy:19.9-17: warning: %define variable 'api.namespace' requires '{...}' values [-Wdeprecated]
 %define namespace "ebpf::cc"
         ^^^^^^^^^
parser.yy:20.9-25: warning: %define variable 'parser_class_name' requires '{...}' values [-Wdeprecated]
 %define parser_class_name "BisonParser"
         ^^^^^^^^^^^^^^^^^
Scanning dependencies of target b_frontend
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 30%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/loader.cc.o
[ 30%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/codegen_llvm.cc.o
[ 33%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/node.cc.o
[ 33%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/parser.cc.o
[ 33%] Building CXX object src/cc/frontends/clang/CMakeFiles/clang_frontend.dir/tp_frontend_action.cc.o
[ 33%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/printer.cc.o
[ 36%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/type_check.cc.o
[ 36%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/parser.yy.cc.o
[ 38%] Building CXX object src/cc/frontends/clang/CMakeFiles/clang_frontend.dir/kbuild_helper.cc.o
/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/src/cc/frontends/clang/kbuild_helper.cc:80:3: warning: multi-line comment [-Wcomment]
   // USERINCLUDE    := \
   ^
/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/src/cc/frontends/clang/kbuild_helper.cc:89:3: warning: multi-line comment [-Wcomment]
   // LINUXINCLUDE    := \
   ^
[ 38%] Building CXX object src/cc/frontends/clang/CMakeFiles/clang_frontend.dir/__/__/common.cc.o
[ 38%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/lexer.ll.cc.o
/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/src/cc/frontends/b/lexer.ll.cc: In member function ‘virtual int ebpf::cc::Lexer::yylex()’:
/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/src/cc/frontends/b/lexer.ll.cc:764:23: warning: comparison between signed and unsigned integer expressions [-Wsign-compare]
    for ( yyl = 0; yyl < yyleng; ++yyl )
                       ^
/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/src/cc/frontends/b/lexer.ll.cc: In member function ‘int ebpfccFlexLexer::yy_get_next_buffer()’:
/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/src/cc/frontends/b/lexer.ll.cc:1506:44: warning: comparison between signed and unsigned integer expressions [-Wsign-compare]
  if ((int) ((yy_n_chars) + number_to_move) > YY_CURRENT_BUFFER_LVALUE->yy_buf_size) {
                                            ^
[ 38%] Linking CXX static library libclang_frontend.a
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 38%] Built target clang_frontend
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
Scanning dependencies of target usdt_test_lib
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 38%] Building C object tests/cc/CMakeFiles/usdt_test_lib.dir/usdt_test_lib.c.o
[ 41%] Linking C shared library libusdt_test_lib.so
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 41%] Built target usdt_test_lib
[ 44%] Linking CXX static library libb_frontend.a
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 44%] Built target b_frontend
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
Scanning dependencies of target bcc-shared
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
Scanning dependencies of target bcc-static
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 47%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/link_all.cc.o
[ 50%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/bcc_common.cc.o
[ 50%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/bcc_common.cc.o
[ 50%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/bpf_module.cc.o
[ 50%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/bpf_module.cc.o
[ 50%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/bcc_btf.cc.o
[ 52%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/bcc_btf.cc.o
[ 55%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/exported_files.cc.o
[ 55%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/exported_files.cc.o
[ 55%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/bpf_module_rw_engine.cc.o
[ 58%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/bpf_module_rw_engine.cc.o
[ 58%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/table_storage.cc.o
[ 58%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/table_storage.cc.o
[ 61%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/shared_table.cc.o
[ 61%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/shared_table.cc.o
[ 61%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/bpffs_table.cc.o
[ 63%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/bpffs_table.cc.o
[ 66%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/json_map_decl_visitor.cc.o
[ 66%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/json_map_decl_visitor.cc.o
[ 66%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/common.cc.o
[ 66%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/bcc_syms.cc.o
[ 66%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/usdt/usdt.cc.o
[ 69%] Building C object src/cc/CMakeFiles/bcc-shared.dir/bcc_elf.c.o
[ 69%] Building C object src/cc/CMakeFiles/bcc-shared.dir/bcc_perf_map.c.o
[ 69%] Building C object src/cc/CMakeFiles/bcc-shared.dir/bcc_proc.c.o
[ 72%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/common.cc.o
[ 75%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/usdt/usdt_args.cc.o
[ 75%] Linking CXX shared library libbcc.so
[ 75%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/bcc_syms.cc.o
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 75%] Built target bcc-shared
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
Scanning dependencies of target test_libbcc
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 75%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_libbcc.cc.o
[ 75%] Building C object src/cc/CMakeFiles/bcc-static.dir/bcc_elf.c.o
[ 77%] Building C object src/cc/CMakeFiles/bcc-static.dir/bcc_perf_map.c.o
[ 77%] Building C object src/cc/CMakeFiles/bcc-static.dir/bcc_proc.c.o
[ 77%] Linking CXX static library libbcc.a
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 77%] Built target bcc-static
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
Scanning dependencies of target test_static
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 77%] Building C object tests/cc/CMakeFiles/test_static.dir/test_static.c.o
[ 80%] Linking CXX executable test_static
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 80%] Built target test_static
[ 83%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_c_api.cc.o
[ 83%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_array_table.cc.o
[ 83%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_bpf_table.cc.o
[ 86%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_cg_storage.cc.o
[ 86%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_hash_table.cc.o
[ 88%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_map_in_map.cc.o
[ 88%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_perf_event.cc.o
[ 88%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_pinned_table.cc.o
[ 91%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_prog_table.cc.o
[ 91%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_queuestack_table.cc.o
[ 91%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_shared_table.cc.o
[ 94%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_sk_storage.cc.o
[ 94%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_sock_table.cc.o
[ 94%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_usdt_args.cc.o
[ 97%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_usdt_probes.cc.o
[ 97%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/utils.cc.o
[100%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_parse_tracepoint.cc.o
[100%] Linking CXX executable test_libbcc
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[100%] Built target test_libbcc
make[1]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
make: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/cc'
+ ctest -V -D ExperimentalTest --no-compress-output
UpdateCTestConfiguration  from :/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/DartConfiguration.tcl
Cannot find file: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/DartConfiguration.tcl
   Site: 
   Build name: (empty)
Create new tag: 20200731-0019 - Experimental
UpdateCTestConfiguration  from :/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/DartConfiguration.tcl
Cannot find file: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/DartConfiguration.tcl
Test project /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build
Constructing a list of tests
Checking test dependency graph...
Checking test dependency graph end
test 1
      Start  1: style-check

1: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/scripts/c-style-check.sh
1: Test timeout computed to be: 9.99988e+06
1: diff --git a/libbpf-tools/biosnoop.bpf.c b/libbpf-tools/biosnoop.bpf.c
1: index 3154560..2ab2e3b 100644
1: --- a/libbpf-tools/biosnoop.bpf.c
1: +++ b/libbpf-tools/biosnoop.bpf.c
1: @@ -1,10 +1,10 @@
1:  // SPDX-License-Identifier: GPL-2.0
1:  // Copyright (c) 2020 Wenbo Zhang
1: -#include "vmlinux.h"
1: -#include <bpf/bpf_helpers.h>
1: +#include "biosnoop.h"
1:  #include <bpf/bpf_core_read.h>
1: +#include <bpf/bpf_helpers.h>
1:  #include <bpf/bpf_tracing.h>
1: -#include "biosnoop.h"
1: +#include "vmlinux.h"
1:  
1:  #define MAX_ENTRIES 10240
1:  
1: @@ -12,155 +12,142 @@ const volatile char targ_disk[DISK_NAME_LEN] = {};
1:  const volatile bool targ_queued = false;
1:  
1:  struct piddata {
1: -	char comm[TASK_COMM_LEN];
1: -	u32 pid;
1: +  char comm[TASK_COMM_LEN];
1: +  u32 pid;
1:  };
1:  
1:  struct {
1: -	__uint(type, BPF_MAP_TYPE_HASH);
1: -	__uint(max_entries, MAX_ENTRIES);
1: -	__type(key, struct request *);
1: -	__type(value, struct piddata);
1: -	__uint(map_flags, BPF_F_NO_PREALLOC);
1: +  __uint(type, BPF_MAP_TYPE_HASH);
1: +  __uint(max_entries, MAX_ENTRIES);
1: +  __type(key, struct request *);
1: +  __type(value, struct piddata);
1: +  __uint(map_flags, BPF_F_NO_PREALLOC);
1:  } infobyreq SEC(".maps");
1:  
1:  struct stage {
1: -	u64 insert;
1: -	u64 issue;
1: +  u64 insert;
1: +  u64 issue;
1:  };
1:  
1:  struct {
1: -	__uint(type, BPF_MAP_TYPE_HASH);
1: -	__uint(max_entries, MAX_ENTRIES);
1: -	__type(key, struct request *);
1: -	__type(value, struct stage);
1: +  __uint(type, BPF_MAP_TYPE_HASH);
1: +  __uint(max_entries, MAX_ENTRIES);
1: +  __type(key, struct request *);
1: +  __type(value, struct stage);
1:  } start SEC(".maps");
1:  
1:  struct {
1: -	__uint(type, BPF_MAP_TYPE_PERF_EVENT_ARRAY);
1: -	__uint(key_size, sizeof(u32));
1: -	__uint(value_size, sizeof(u32));
1: +  __uint(type, BPF_MAP_TYPE_PERF_EVENT_ARRAY);
1: +  __uint(key_size, sizeof(u32));
1: +  __uint(value_size, sizeof(u32));
1:  } events SEC(".maps");
1:  
1: -static __always_inline
1: -int trace_pid(struct request *rq)
1: -{
1: -	u64 id = bpf_get_current_pid_tgid();
1: -	struct piddata piddata = {};
1: +static __always_inline int trace_pid(struct request *rq) {
1: +  u64 id = bpf_get_current_pid_tgid();
1: +  struct piddata piddata = {};
1:  
1: -	piddata.pid = id;
1: -	bpf_get_current_comm(&piddata.comm, sizeof(&piddata.comm));
1: -	bpf_map_update_elem(&infobyreq, &rq, &piddata, 0);
1: -	return 0;
1: +  piddata.pid = id;
1: +  bpf_get_current_comm(&piddata.comm, sizeof(&piddata.comm));
1: +  bpf_map_update_elem(&infobyreq, &rq, &piddata, 0);
1: +  return 0;
1:  }
1:  
1:  SEC("fentry/blk_account_io_start")
1: -int BPF_PROG(fentry__blk_account_io_start, struct request *rq)
1: -{
1: -	return trace_pid(rq);
1: +int BPF_PROG(fentry__blk_account_io_start, struct request *rq) {
1: +  return trace_pid(rq);
1:  }
1:  
1:  SEC("kprobe/blk_account_io_merge_bio")
1: -int BPF_KPROBE(kprobe__blk_account_io_merge_bio, struct request *rq)
1: -{
1: -	return trace_pid(rq);
1: +int BPF_KPROBE(kprobe__blk_account_io_merge_bio, struct request *rq) {
1: +  return trace_pid(rq);
1:  }
1:  
1: -static __always_inline bool disk_filtered(const char *disk)
1: -{
1: -	int i;
1: +static __always_inline bool disk_filtered(const char *disk) {
1: +  int i;
1:  
1: -	for (i = 0; targ_disk[i] != '\0' && i < DISK_NAME_LEN; i++) {
1: -		if (disk[i] != targ_disk[i])
1: -			return false;
1: -	}
1: -	return true;
1: +  for (i = 0; targ_disk[i] != '\0' && i < DISK_NAME_LEN; i++) {
1: +    if (disk[i] != targ_disk[i])
1: +      return false;
1: +  }
1: +  return true;
1:  }
1:  
1: -static __always_inline
1: -int trace_rq_start(struct request *rq, bool insert)
1: -{
1: -	struct stage *stagep, stage = {};
1: -	u64 ts = bpf_ktime_get_ns();
1: -	char disk[DISK_NAME_LEN];
1: -
1: -	stagep = bpf_map_lookup_elem(&start, &rq);
1: -	if (!stagep) {
1: -		bpf_probe_read_kernel_str(&disk, sizeof(disk),
1: -					rq->rq_disk->disk_name);
1: -		if (!disk_filtered(disk)) {
1: -			bpf_map_delete_elem(&infobyreq, &rq);
1: -			return 0;
1: -		}
1: -		stagep = &stage;
1: -	}
1: -	if (insert)
1: -		stagep->insert = ts;
1: -	else
1: -		stagep->issue = ts;
1: -	if (stagep == &stage)
1: -		bpf_map_update_elem(&start, &rq, stagep, 0);
1: -	return 0;
1: +static __always_inline int trace_rq_start(struct request *rq, bool insert) {
1: +  struct stage *stagep, stage = {};
1: +  u64 ts = bpf_ktime_get_ns();
1: +  char disk[DISK_NAME_LEN];
1: +
1: +  stagep = bpf_map_lookup_elem(&start, &rq);
1: +  if (!stagep) {
1: +    bpf_probe_read_kernel_str(&disk, sizeof(disk), rq->rq_disk->disk_name);
1: +    if (!disk_filtered(disk)) {
1: +      bpf_map_delete_elem(&infobyreq, &rq);
1: +      return 0;
1: +    }
1: +    stagep = &stage;
1: +  }
1: +  if (insert)
1: +    stagep->insert = ts;
1: +  else
1: +    stagep->issue = ts;
1: +  if (stagep == &stage)
1: +    bpf_map_update_elem(&start, &rq, stagep, 0);
1: +  return 0;
1:  }
1:  
1:  SEC("tp_btf/block_rq_insert")
1:  int BPF_PROG(tp_btf__block_rq_insert, struct request_queue *q,
1: -	     struct request *rq)
1: -{
1: -	return trace_rq_start(rq, true);
1: +             struct request *rq) {
1: +  return trace_rq_start(rq, true);
1:  }
1:  
1:  SEC("tp_btf/block_rq_issue")
1:  int BPF_PROG(tp_btf__block_rq_issue, struct request_queue *q,
1: -	     struct request *rq)
1: -{
1: -	return trace_rq_start(rq, false);
1: +             struct request *rq) {
1: +  return trace_rq_start(rq, false);
1:  }
1:  
1:  SEC("tp_btf/block_rq_complete")
1:  int BPF_PROG(tp_btf__block_rq_complete, struct request *rq, int error,
1: -	     unsigned int nr_bytes)
1: -{
1: -	u64 slot, ts = bpf_ktime_get_ns();
1: -	struct piddata *piddatap;
1: -	struct event event = {};
1: -	struct stage *stagep;
1: -	s64 delta;
1: -
1: -	stagep = bpf_map_lookup_elem(&start, &rq);
1: -	if (!stagep)
1: -		return 0;
1: -	delta = (s64)(ts - stagep->issue);
1: -	if (delta < 0)
1: -		goto cleanup;
1: -	piddatap = bpf_map_lookup_elem(&infobyreq, &rq);
1: -	if (!piddatap) {
1: -		event.comm[0] = '?';
1: -	} else {
1: -		__builtin_memcpy(&event.comm, piddatap->comm,
1: -				sizeof(event.comm));
1: -		event.pid = piddatap->pid;
1: -	}
1: -	event.delta = delta;
1: -	if (targ_queued && BPF_CORE_READ(rq, q, elevator)) {
1: -		if (!stagep->insert)
1: -			event.qdelta = -1; /* missed or don't insert entry */
1: -		else
1: -			event.qdelta = stagep->issue - stagep->insert;
1: -	}
1: -	event.ts = ts;
1: -	event.sector = rq->__sector;
1: -	event.len = rq->__data_len;
1: -	event.cmd_flags = rq->cmd_flags;
1: -	bpf_probe_read_kernel_str(&event.disk, sizeof(event.disk),
1: -				rq->rq_disk->disk_name);
1: -	bpf_perf_event_output(ctx, &events, BPF_F_CURRENT_CPU, &event,
1: -			sizeof(event));
1: +             unsigned int nr_bytes) {
1: +  u64 slot, ts = bpf_ktime_get_ns();
1: +  struct piddata *piddatap;
1: +  struct event event = {};
1: +  struct stage *stagep;
1: +  s64 delta;
1: +
1: +  stagep = bpf_map_lookup_elem(&start, &rq);
1: +  if (!stagep)
1: +    return 0;
1: +  delta = (s64)(ts - stagep->issue);
1: +  if (delta < 0)
1: +    goto cleanup;
1: +  piddatap = bpf_map_lookup_elem(&infobyreq, &rq);
1: +  if (!piddatap) {
1: +    event.comm[0] = '?';
1: +  } else {
1: +    __builtin_memcpy(&event.comm, piddatap->comm, sizeof(event.comm));
1: +    event.pid = piddatap->pid;
1: +  }
1: +  event.delta = delta;
1: +  if (targ_queued && BPF_CORE_READ(rq, q, elevator)) {
1: +    if (!stagep->insert)
1: +      event.qdelta = -1; /* missed or don't insert entry */
1: +    else
1: +      event.qdelta = stagep->issue - stagep->insert;
1: +  }
1: +  event.ts = ts;
1: +  event.sector = rq->__sector;
1: +  event.len = rq->__data_len;
1: +  event.cmd_flags = rq->cmd_flags;
1: +  bpf_probe_read_kernel_str(&event.disk, sizeof(event.disk),
1: +                            rq->rq_disk->disk_name);
1: +  bpf_perf_event_output(ctx, &events, BPF_F_CURRENT_CPU, &event, sizeof(event));
1:  
1:  cleanup:
1: -	bpf_map_delete_elem(&start, &rq);
1: -	bpf_map_delete_elem(&infobyreq, &rq);
1: -	return 0;
1: +  bpf_map_delete_elem(&start, &rq);
1: +  bpf_map_delete_elem(&infobyreq, &rq);
1: +  return 0;
1:  }
1:  
1:  char LICENSE[] SEC("license") = "GPL";
1: diff --git a/libbpf-tools/biosnoop.c b/libbpf-tools/biosnoop.c
1: index 2811c6d..912c157 100644
1: --- a/libbpf-tools/biosnoop.c
1: +++ b/libbpf-tools/biosnoop.c
1: @@ -3,28 +3,28 @@
1:  //
1:  // Based on biosnoop(8) from BCC by Brendan Gregg.
1:  // 29-Jun-2020   Wenbo Zhang   Created this.
1: +#include "biosnoop.h"
1:  #include <argp.h>
1: -#include <stdio.h>
1: -#include <unistd.h>
1: -#include <time.h>
1: +#include <bpf/bpf.h>
1:  #include <bpf/libbpf.h>
1: +#include <stdio.h>
1:  #include <sys/resource.h>
1: -#include <bpf/bpf.h>
1: -#include "blk_types.h"
1: -#include "biosnoop.h"
1: +#include <time.h>
1: +#include <unistd.h>
1:  #include "biosnoop.skel.h"
1: +#include "blk_types.h"
1:  #include "trace_helpers.h"
1:  
1: -#define PERF_BUFFER_PAGES	16
1: -#define PERF_POLL_TIMEOUT_MS	100
1: +#define PERF_BUFFER_PAGES 16
1: +#define PERF_POLL_TIMEOUT_MS 100
1:  
1:  static struct env {
1: -	char *disk;
1: -	int disk_len;
1: -	int duration;
1: -	bool timestamp;
1: -	bool queued;
1: -	bool verbose;
1: +  char *disk;
1: +  int disk_len;
1: +  int duration;
1: +  bool timestamp;
1: +  bool queued;
1: +  bool verbose;
1:  } env = {};
1:  
1:  static volatile __u64 start_ts;
1: @@ -32,264 +32,250 @@ static volatile __u64 start_ts;
1:  const char *argp_program_version = "biosnoop 0.1";
1:  const char *argp_program_bug_address = "<ethercflow@gmail.com>";
1:  const char argp_program_doc[] =
1: -"Summarize block device I/O latency as a histogram.\n"
1: -"\n"
1: -"USAGE: biosnoop [-h] [-T] [-Q]\n"
1: -"\n"
1: -"EXAMPLES:\n"
1: -"    biosnoop              # summarize block I/O latency as a histogram\n"
1: -"    biosnoop -Q           # include OS queued time in I/O time\n"
1: -"    biosnoop 10           # trace for 10 seconds only\n"
1: -"    biosnoop -d sdc       # trace sdc only\n";
1: +    "Summarize block device I/O latency as a histogram.\n"
1: +    "\n"
1: +    "USAGE: biosnoop [-h] [-T] [-Q]\n"
1: +    "\n"
1: +    "EXAMPLES:\n"
1: +    "    biosnoop              # summarize block I/O latency as a histogram\n"
1: +    "    biosnoop -Q           # include OS queued time in I/O time\n"
1: +    "    biosnoop 10           # trace for 10 seconds only\n"
1: +    "    biosnoop -d sdc       # trace sdc only\n";
1:  
1:  static const struct argp_option opts[] = {
1: -	{ NULL, 'h', NULL, OPTION_HIDDEN, "Show the full help" },
1: -	{ "queued", 'Q', NULL, 0, "Include OS queued time in I/O time" },
1: -	{ "disk",  'd', "DISK",  0, "Trace this disk only" },
1: -	{ "verbose", 'v', NULL, 0, "Verbose debug output" },
1: -	{},
1: +    {NULL, 'h', NULL, OPTION_HIDDEN, "Show the full help"},
1: +    {"queued", 'Q', NULL, 0, "Include OS queued time in I/O time"},
1: +    {"disk", 'd', "DISK", 0, "Trace this disk only"},
1: +    {"verbose", 'v', NULL, 0, "Verbose debug output"},
1: +    {},
1:  };
1:  
1: -static error_t parse_arg(int key, char *arg, struct argp_state *state)
1: -{
1: -	static int pos_args;
1: +static error_t parse_arg(int key, char *arg, struct argp_state *state) {
1: +  static int pos_args;
1:  
1: -	switch (key) {
1: -	case 'v':
1: -		env.verbose = true;
1: -		break;
1: -	case 'h':
1: -		argp_usage(state);
1: -		break;
1: -	case 'Q':
1: -		env.queued = true;
1: -		break;
1: -	case 'd':
1: -		env.disk = arg;
1: -		env.disk_len = strlen(arg) + 1;
1: -		if (env.disk_len > DISK_NAME_LEN) {
1: -			fprintf(stderr, "invaild disk name: too long\n");
1: -			argp_usage(state);
1: -		}
1: -		break;
1: -	case ARGP_KEY_ARG:
1: -		if (pos_args++) {
1: -			fprintf(stderr,
1: -				"unrecognized positional argument: %s\n", arg);
1: -			argp_usage(state);
1: -		}
1: -		errno = 0;
1: -		env.duration = strtoll(arg, NULL, 10);
1: -		if (errno || env.duration <= 0) {
1: -			fprintf(stderr, "invalid delay (in us): %s\n", arg);
1: -			argp_usage(state);
1: -		}
1: -	default:
1: -		return ARGP_ERR_UNKNOWN;
1: -	}
1: -	return 0;
1: +  switch (key) {
1: +  case 'v':
1: +    env.verbose = true;
1: +    break;
1: +  case 'h':
1: +    argp_usage(state);
1: +    break;
1: +  case 'Q':
1: +    env.queued = true;
1: +    break;
1: +  case 'd':
1: +    env.disk = arg;
1: +    env.disk_len = strlen(arg) + 1;
1: +    if (env.disk_len > DISK_NAME_LEN) {
1: +      fprintf(stderr, "invaild disk name: too long\n");
1: +      argp_usage(state);
1: +    }
1: +    break;
1: +  case ARGP_KEY_ARG:
1: +    if (pos_args++) {
1: +      fprintf(stderr, "unrecognized positional argument: %s\n", arg);
1: +      argp_usage(state);
1: +    }
1: +    errno = 0;
1: +    env.duration = strtoll(arg, NULL, 10);
1: +    if (errno || env.duration <= 0) {
1: +      fprintf(stderr, "invalid delay (in us): %s\n", arg);
1: +      argp_usage(state);
1: +    }
1: +  default:
1: +    return ARGP_ERR_UNKNOWN;
1: +  }
1: +  return 0;
1:  }
1:  
1: -int libbpf_print_fn(enum libbpf_print_level level,
1: -		const char *format, va_list args)
1: -{
1: -	if (level == LIBBPF_DEBUG && !env.verbose)
1: -		return 0;
1: -	return vfprintf(stderr, format, args);
1: +int libbpf_print_fn(enum libbpf_print_level level, const char *format,
1: +                    va_list args) {
1: +  if (level == LIBBPF_DEBUG && !env.verbose)
1: +    return 0;
1: +  return vfprintf(stderr, format, args);
1:  }
1:  
1: -static void blk_fill_rwbs(char *rwbs, unsigned int op)
1: -{
1: -	int i = 0;
1: +static void blk_fill_rwbs(char *rwbs, unsigned int op) {
1: +  int i = 0;
1:  
1: -	if (op & REQ_PREFLUSH)
1: -		rwbs[i++] = 'F';
1: +  if (op & REQ_PREFLUSH)
1: +    rwbs[i++] = 'F';
1:  
1: -	switch (op & REQ_OP_MASK) {
1: -	case REQ_OP_WRITE:
1: -	case REQ_OP_WRITE_SAME:
1: -		rwbs[i++] = 'W';
1: -		break;
1: -	case REQ_OP_DISCARD:
1: -		rwbs[i++] = 'D';
1: -		break;
1: -	case REQ_OP_SECURE_ERASE:
1: -		rwbs[i++] = 'D';
1: -		rwbs[i++] = 'E';
1: -		break;
1: -	case REQ_OP_FLUSH:
1: -		rwbs[i++] = 'F';
1: -		break;
1: -	case REQ_OP_READ:
1: -		rwbs[i++] = 'R';
1: -		break;
1: -	default:
1: -		rwbs[i++] = 'N';
1: -	}
1: +  switch (op & REQ_OP_MASK) {
1: +  case REQ_OP_WRITE:
1: +  case REQ_OP_WRITE_SAME:
1: +    rwbs[i++] = 'W';
1: +    break;
1: +  case REQ_OP_DISCARD:
1: +    rwbs[i++] = 'D';
1: +    break;
1: +  case REQ_OP_SECURE_ERASE:
1: +    rwbs[i++] = 'D';
1: +    rwbs[i++] = 'E';
1: +    break;
1: +  case REQ_OP_FLUSH:
1: +    rwbs[i++] = 'F';
1: +    break;
1: +  case REQ_OP_READ:
1: +    rwbs[i++] = 'R';
1: +    break;
1: +  default:
1: +    rwbs[i++] = 'N';
1: +  }
1:  
1: -	if (op & REQ_FUA)
1: -		rwbs[i++] = 'F';
1: -	if (op & REQ_RAHEAD)
1: -		rwbs[i++] = 'A';
1: -	if (op & REQ_SYNC)
1: -		rwbs[i++] = 'S';
1: -	if (op & REQ_META)
1: -		rwbs[i++] = 'M';
1: +  if (op & REQ_FUA)
1: +    rwbs[i++] = 'F';
1: +  if (op & REQ_RAHEAD)
1: +    rwbs[i++] = 'A';
1: +  if (op & REQ_SYNC)
1: +    rwbs[i++] = 'S';
1: +  if (op & REQ_META)
1: +    rwbs[i++] = 'M';
1:  
1: -	rwbs[i] = '\0';
1: +  rwbs[i] = '\0';
1:  }
1:  
1: -void handle_event(void *ctx, int cpu, void *data, __u32 data_sz)
1: -{
1: -	const struct event *e = data;
1: -	char rwbs[RWBS_LEN];
1: +void handle_event(void *ctx, int cpu, void *data, __u32 data_sz) {
1: +  const struct event *e = data;
1: +  char rwbs[RWBS_LEN];
1:  
1: -	if (!start_ts)
1: -		start_ts = e->ts;
1: -	blk_fill_rwbs(rwbs, e->cmd_flags);
1: -	printf("%-11.6f %-14.14s %-6d %-7s %-4s %-10lld %-7d ",
1: -		(e->ts - start_ts) / 1000000000.0,
1: -		e->comm, e->pid, e->disk, rwbs, e->sector, e->len);
1: -	if (env.queued)
1: -		printf("%7.3f ", e->qdelta != -1 ?
1: -			e->qdelta / 1000000.0 : -1);
1: -	printf("%7.3f\n", e->delta / 1000000.0);
1: +  if (!start_ts)
1: +    start_ts = e->ts;
1: +  blk_fill_rwbs(rwbs, e->cmd_flags);
1: +  printf("%-11.6f %-14.14s %-6d %-7s %-4s %-10lld %-7d ",
1: +         (e->ts - start_ts) / 1000000000.0, e->comm, e->pid, e->disk, rwbs,
1: +         e->sector, e->len);
1: +  if (env.queued)
1: +    printf("%7.3f ", e->qdelta != -1 ? e->qdelta / 1000000.0 : -1);
1: +  printf("%7.3f\n", e->delta / 1000000.0);
1:  }
1:  
1: -void handle_lost_events(void *ctx, int cpu, __u64 lost_cnt)
1: -{
1: -	fprintf(stderr, "lost %llu events on CPU #%d\n", lost_cnt, cpu);
1: +void handle_lost_events(void *ctx, int cpu, __u64 lost_cnt) {
1: +  fprintf(stderr, "lost %llu events on CPU #%d\n", lost_cnt, cpu);
1:  }
1:  
1: -int main(int argc, char **argv)
1: -{
1: -	static const struct argp argp = {
1: -		.options = opts,
1: -		.parser = parse_arg,
1: -		.doc = argp_program_doc,
1: -	};
1: -	struct perf_buffer_opts pb_opts;
1: -	struct perf_buffer *pb = NULL;
1: -	struct ksyms *ksyms = NULL;
1: -	struct biosnoop_bpf *obj;
1: -	__u64 time_end = 0;
1: -	int err;
1: +int main(int argc, char **argv) {
1: +  static const struct argp argp = {
1: +      .options = opts, .parser = parse_arg, .doc = argp_program_doc,
1: +  };
1: +  struct perf_buffer_opts pb_opts;
1: +  struct perf_buffer *pb = NULL;
1: +  struct ksyms *ksyms = NULL;
1: +  struct biosnoop_bpf *obj;
1: +  __u64 time_end = 0;
1: +  int err;
1:  
1: -	err = argp_parse(&argp, argc, argv, 0, NULL, NULL);
1: -	if (err)
1: -		return err;
1: +  err = argp_parse(&argp, argc, argv, 0, NULL, NULL);
1: +  if (err)
1: +    return err;
1:  
1: -	libbpf_set_print(libbpf_print_fn);
1: +  libbpf_set_print(libbpf_print_fn);
1:  
1: -	err = bump_memlock_rlimit();
1: -	if (err) {
1: -		fprintf(stderr, "failed to increase rlimit: %d\n", err);
1: -		return 1;
1: -	}
1: +  err = bump_memlock_rlimit();
1: +  if (err) {
1: +    fprintf(stderr, "failed to increase rlimit: %d\n", err);
1: +    return 1;
1: +  }
1:  
1: -	obj = biosnoop_bpf__open();
1: -	if (!obj) {
1: -		fprintf(stderr, "failed to open and/or load BPF ojbect\n");
1: -		return 1;
1: -	}
1: +  obj = biosnoop_bpf__open();
1: +  if (!obj) {
1: +    fprintf(stderr, "failed to open and/or load BPF ojbect\n");
1: +    return 1;
1: +  }
1:  
1: -	/* initialize global data (filtering options) */
1: -	if (env.disk)
1: -		strncpy((char*)obj->rodata->targ_disk, env.disk, env.disk_len);
1: -	obj->rodata->targ_queued = env.queued;
1: +  /* initialize global data (filtering options) */
1: +  if (env.disk)
1: +    strncpy((char *)obj->rodata->targ_disk, env.disk, env.disk_len);
1: +  obj->rodata->targ_queued = env.queued;
1:  
1: -	err = biosnoop_bpf__load(obj);
1: -	if (err) {
1: -		fprintf(stderr, "failed to load BPF object: %d\n", err);
1: -		goto cleanup;
1: -	}
1: +  err = biosnoop_bpf__load(obj);
1: +  if (err) {
1: +    fprintf(stderr, "failed to load BPF object: %d\n", err);
1: +    goto cleanup;
1: +  }
1:  
1: -	obj->links.fentry__blk_account_io_start =
1: -		bpf_program__attach(obj->progs.fentry__blk_account_io_start);
1: -	err = libbpf_get_error(obj->links.fentry__blk_account_io_start);
1: -	if (err) {
1: -		fprintf(stderr, "failed to attach blk_account_io_start: %s\n",
1: -			strerror(err));
1: -		goto cleanup;
1: -	}
1: -	ksyms = ksyms__load();
1: -	if (!ksyms) {
1: -		fprintf(stderr, "failed to load kallsyms\n");
1: -		goto cleanup;
1: -	}
1: -	if (ksyms__get_symbol(ksyms, "blk_account_io_merge_bio")) {
1: -		obj->links.kprobe__blk_account_io_merge_bio =
1: -			bpf_program__attach(obj->
1: -					progs.kprobe__blk_account_io_merge_bio);
1: -		err = libbpf_get_error(obj->
1: -				links.kprobe__blk_account_io_merge_bio);
1: -		if (err) {
1: -			fprintf(stderr, "failed to attach "
1: -				"blk_account_io_merge_bio: %s\n",
1: -				strerror(err));
1: -			goto cleanup;
1: -		}
1: -	}
1: -	if (env.queued) {
1: -		obj->links.tp_btf__block_rq_insert =
1: -			bpf_program__attach(obj->progs.tp_btf__block_rq_insert);
1: -		err = libbpf_get_error(obj->links.tp_btf__block_rq_insert);
1: -		if (err) {
1: -			fprintf(stderr, "failed to attach block_rq_insert: %s\n",
1: -				strerror(err));
1: -			goto cleanup;
1: -		}
1: -	}
1: -	obj->links.tp_btf__block_rq_issue =
1: -		bpf_program__attach(obj->progs.tp_btf__block_rq_issue);
1: -	err = libbpf_get_error(obj->links.tp_btf__block_rq_issue);
1: -	if (err) {
1: -		fprintf(stderr, "failed to attach block_rq_issue: %s\n",
1: -			strerror(err));
1: -		goto cleanup;
1: -	}
1: -	obj->links.tp_btf__block_rq_complete =
1: -		bpf_program__attach(obj->progs.tp_btf__block_rq_complete);
1: -	err = libbpf_get_error(obj->links.tp_btf__block_rq_complete);
1: -	if (err) {
1: -		fprintf(stderr, "failed to attach block_rq_complete: %s\n",
1: -			strerror(err));
1: -		goto cleanup;
1: -	}
1: +  obj->links.fentry__blk_account_io_start =
1: +      bpf_program__attach(obj->progs.fentry__blk_account_io_start);
1: +  err = libbpf_get_error(obj->links.fentry__blk_account_io_start);
1: +  if (err) {
1: +    fprintf(stderr, "failed to attach blk_account_io_start: %s\n",
1: +            strerror(err));
1: +    goto cleanup;
1: +  }
1: +  ksyms = ksyms__load();
1: +  if (!ksyms) {
1: +    fprintf(stderr, "failed to load kallsyms\n");
1: +    goto cleanup;
1: +  }
1: +  if (ksyms__get_symbol(ksyms, "blk_account_io_merge_bio")) {
1: +    obj->links.kprobe__blk_account_io_merge_bio =
1: +        bpf_program__attach(obj->progs.kprobe__blk_account_io_merge_bio);
1: +    err = libbpf_get_error(obj->links.kprobe__blk_account_io_merge_bio);
1: +    if (err) {
1: +      fprintf(stderr,
1: +              "failed to attach "
1: +              "blk_account_io_merge_bio: %s\n",
1: +              strerror(err));
1: +      goto cleanup;
1: +    }
1: +  }
1: +  if (env.queued) {
1: +    obj->links.tp_btf__block_rq_insert =
1: +        bpf_program__attach(obj->progs.tp_btf__block_rq_insert);
1: +    err = libbpf_get_error(obj->links.tp_btf__block_rq_insert);
1: +    if (err) {
1: +      fprintf(stderr, "failed to attach block_rq_insert: %s\n", strerror(err));
1: +      goto cleanup;
1: +    }
1: +  }
1: +  obj->links.tp_btf__block_rq_issue =
1: +      bpf_program__attach(obj->progs.tp_btf__block_rq_issue);
1: +  err = libbpf_get_error(obj->links.tp_btf__block_rq_issue);
1: +  if (err) {
1: +    fprintf(stderr, "failed to attach block_rq_issue: %s\n", strerror(err));
1: +    goto cleanup;
1: +  }
1: +  obj->links.tp_btf__block_rq_complete =
1: +      bpf_program__attach(obj->progs.tp_btf__block_rq_complete);
1: +  err = libbpf_get_error(obj->links.tp_btf__block_rq_complete);
1: +  if (err) {
1: +    fprintf(stderr, "failed to attach block_rq_complete: %s\n", strerror(err));
1: +    goto cleanup;
1: +  }
1:  
1: -	pb_opts.sample_cb = handle_event;
1: -	pb_opts.lost_cb = handle_lost_events;
1: -	pb = perf_buffer__new(bpf_map__fd(obj->maps.events), PERF_BUFFER_PAGES,
1: -			&pb_opts);
1: -	err = libbpf_get_error(pb);
1: -	if (err) {
1: -		pb = NULL;
1: -		fprintf(stderr, "failed to open perf buffer: %d\n", err);
1: -		goto cleanup;
1: -	}
1: +  pb_opts.sample_cb = handle_event;
1: +  pb_opts.lost_cb = handle_lost_events;
1: +  pb = perf_buffer__new(bpf_map__fd(obj->maps.events), PERF_BUFFER_PAGES,
1: +                        &pb_opts);
1: +  err = libbpf_get_error(pb);
1: +  if (err) {
1: +    pb = NULL;
1: +    fprintf(stderr, "failed to open perf buffer: %d\n", err);
1: +    goto cleanup;
1: +  }
1:  
1: -	printf("%-11s %-14s %-6s %-7s %-4s %-10s %-7s ",
1: -		"TIME(s)", "COMM", "PID", "DISK", "T", "SECTOR", "BYTES");
1: -	if (env.queued)
1: -		printf("%7s ", "QUE(ms)");
1: -	printf("%7s\n", "LAT(ms)");
1: +  printf("%-11s %-14s %-6s %-7s %-4s %-10s %-7s ", "TIME(s)", "COMM", "PID",
1: +         "DISK", "T", "SECTOR", "BYTES");
1: +  if (env.queued)
1: +    printf("%7s ", "QUE(ms)");
1: +  printf("%7s\n", "LAT(ms)");
1:  
1: -	/* setup duration */
1: -	if (env.duration)
1: -		time_end = get_ktime_ns() + env.duration * NSEC_PER_SEC;
1: +  /* setup duration */
1: +  if (env.duration)
1: +    time_end = get_ktime_ns() + env.duration * NSEC_PER_SEC;
1:  
1: -	/* main: poll */
1: -	while (1) {
1: -		if ((err = perf_buffer__poll(pb, PERF_POLL_TIMEOUT_MS)) < 0)
1: -			break;
1: -		if (env.duration && get_ktime_ns() > time_end)
1: -			goto cleanup;
1: -	}
1: -	printf("error polling perf buffer: %d\n", err);
1: +  /* main: poll */
1: +  while (1) {
1: +    if ((err = perf_buffer__poll(pb, PERF_POLL_TIMEOUT_MS)) < 0)
1: +      break;
1: +    if (env.duration && get_ktime_ns() > time_end)
1: +      goto cleanup;
1: +  }
1: +  printf("error polling perf buffer: %d\n", err);
1:  
1:  cleanup:
1: -	biosnoop_bpf__destroy(obj);
1: -	ksyms__free(ksyms);
1: +  biosnoop_bpf__destroy(obj);
1: +  ksyms__free(ksyms);
1:  
1: -	return err != 0;
1: +  return err != 0;
1:  }
1: diff --git a/libbpf-tools/biosnoop.h b/libbpf-tools/biosnoop.h
1: index b7e5d21..801310c 100644
1: --- a/libbpf-tools/biosnoop.h
1: +++ b/libbpf-tools/biosnoop.h
1: @@ -1,20 +1,20 @@
1:  #ifndef __BIOSNOOP_H
1:  #define __BIOSNOOP_H
1:  
1: -#define DISK_NAME_LEN	32
1: -#define TASK_COMM_LEN	16
1: -#define RWBS_LEN	8
1: +#define DISK_NAME_LEN 32
1: +#define TASK_COMM_LEN 16
1: +#define RWBS_LEN 8
1:  
1:  struct event {
1: -	char comm[TASK_COMM_LEN];
1: -	__u64 delta;
1: -	__u64 qdelta;
1: -	__u64 ts;
1: -	__u64 sector;
1: -	__u32 len;
1: -	__u32 pid;
1: -	__u32 cmd_flags;
1: -	char disk[DISK_NAME_LEN];
1: +  char comm[TASK_COMM_LEN];
1: +  __u64 delta;
1: +  __u64 qdelta;
1: +  __u64 ts;
1: +  __u64 sector;
1: +  __u32 len;
1: +  __u32 pid;
1: +  __u32 cmd_flags;
1: +  char disk[DISK_NAME_LEN];
1:  };
1:  
1:  #endif /* __BIOSNOOP_H */
1: Ignoring changes in the following files (wrong extension):
1:     libbpf-tools/Makefile
1:     libbpf-tools/.gitignore
1: Running clang-format on the following files:
1:     libbpf-tools/biosnoop.c
1:     libbpf-tools/biosnoop.bpf.c
1:     libbpf-tools/biosnoop.h
1: old tree: b8d2a9886d83c2cb8113d22b0dc3621c0218a5e6
1: new tree: 503a2cef8e44862af87a4d811c66087ffe9634bf
 1/44 Test  #1: style-check ......................   Passed    0.83 sec
test 2
      Start  2: c_test_static

2: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "c_test_static" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/cc/test_static"
2: Test timeout computed to be: 9.99988e+06
2: clang -cc1 -triple x86_64-unknown-linux-gnu -emit-llvm-bc -emit-llvm-uselists -disable-free -disable-llvm-verifier -main-file-name main.c -mrelocation-model static -mthread-model posix -fmath-errno -masm-verbose -mconstructor-aliases -fuse-init-array -target-cpu x86-64 -momit-leaf-frame-pointer -dwarf-column-info -debugger-tuning=gdb -coverage-file /usr/src/linux-headers-4.4.0-116-generic/main.c -nostdsysteminc -nobuiltininc -resource-dir ../lib/clang/3.8.0 -isystem /virtual/lib/clang/include -include ./include/linux/kconfig.h -include /virtual/include/bcc/bpf.h -include /virtual/include/bcc/helpers.h -isystem /virtual/include -I /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/cc -D __BPF_TRACING__ -I arch/x86/include/ -I arch/x86/include/generated -I include -I arch/x86/include/uapi -I arch/x86/include/generated/uapi -I include/uapi -I include/generated/uapi -D __KERNEL__ -D __HAVE_BUILTIN_BSWAP16__ -D __HAVE_BUILTIN_BSWAP32__ -D __HAVE_BUILTIN_BSWAP64__ -D KBUILD_MODNAME="bcc" -O2 -Wno-deprecated-declarations -Wno-gnu-variable-sized-type-not-at-end -Wno-pragma-once-outside-header -Wno-address-of-packed-member -Wno-unknown-warning-option -Wno-unused-value -Wno-pointer-sign -fdebug-compilation-dir /usr/src/linux-headers-4.4.0-116-generic -ferror-limit 19 -fmessage-length 0 -fobjc-runtime=gcc -fdiagnostics-show-option -vectorize-loops -vectorize-slp -o main.bc -x c /virtual/main.c
2: #if defined(BPF_LICENSE)
2: #error BPF_LICENSE cannot be specified through cflags
2: #endif
2: #if !defined(CONFIG_CC_STACKPROTECTOR)
2: #if defined(CONFIG_CC_STACKPROTECTOR_AUTO) \
2:     || defined(CONFIG_CC_STACKPROTECTOR_REGULAR) \
2:     || defined(CONFIG_CC_STACKPROTECTOR_STRONG)
2: #define CONFIG_CC_STACKPROTECTOR
2: #endif
2: #endif
2: #define bpf_probe_read_kernel bpf_probe_read
2: #define bpf_probe_read_kernel_str bpf_probe_read_str
2: #define bpf_probe_read_user bpf_probe_read
2: #define bpf_probe_read_user_str bpf_probe_read_str
2: BPF_TABLE("array", int, int, stats, 10);
2: 
2: #include <bcc/footer.h>
2: Running from kernel directory at: /lib/modules/4.4.0-116-generic/build
 2/44 Test  #2: c_test_static ....................   Passed    0.95 sec
test 3
      Start  3: test_libbcc

3: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "c_test_all" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/cc/test_libbcc"
3: Test timeout computed to be: 9.99988e+06
3: prog tag mismatch 57cd311f2e27366b 11
3: WARNING: cannot get prog tag, ignore saving source with program tag
3: Parse error:
3:     4@i%ra+1r
3: -------^
3: prog tag mismatch a04f5eef06a7f555 11
3: WARNING: cannot get prog tag, ignore saving source with program tag
3: prog tag mismatch a04f5eef06a7f555 11
3: WARNING: cannot get prog tag, ignore saving source with program tag
3: prog tag mismatch a04f5eef06a7f555 11
3: WARNING: cannot get prog tag, ignore saving source with program tag
3: unshare: failed to execute ruby: No such file or directory
3: 
3: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
3: test_libbcc is a Catch v1.4.0 host application.
3: Run with -? for options
3: 
3: -------------------------------------------------------------------------------
3: test probing running Ruby process in namespaces
3:   in separate mount namespace
3: -------------------------------------------------------------------------------
3: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/cc/test_usdt_probes.cc:340
3: ...............................................................................
3: 
3: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/cc/test_usdt_probes.cc:355: FAILED:
3:   REQUIRE( res.msg() == "" )
3: with expansion:
3:   "Unable to load USDT ruby:gc__mark__begin from binary  PID 7482 for probe
3:   on_event"
3:   ==
3:   ""
3: 
3: unshare: unrecognized option '--kill-child'
3: 
3: Usage:
3:  unshare [options] <program> [<argument>...]
3: 
3: Run a program with some namespaces unshared from the parent.
3: 
3: Options:
3:  -m, --mount[=<file>]      unshare mounts namespace
3:  -u, --uts[=<file>]        unshare UTS namespace (hostname etc)
3:  -i, --ipc[=<file>]        unshare System V IPC namespace
3:  -n, --net[=<file>]        unshare network namespace
3:  -p, --pid[=<file>]        unshare pid namespace
3:  -U, --user[=<file>]       unshare user namespace
3:  -f, --fork                fork before launching <program>
3:      --mount-proc[=<dir>]  mount proc filesystem first (implies --mount)
3:  -r, --map-root-user       map current user to root (implies --user)
3:      --propagation slave|shared|private|unchanged
3:                            modify mount propagation in mount namespace
3:  -s, --setgroups allow|deny  control the setgroups syscall in user namespaces
3: 
3:  -h, --help     display this help and exit
3:  -V, --version  output version information and exit
3: 
3: For more details see unshare(1).
3: -------------------------------------------------------------------------------
3: test probing running Ruby process in namespaces
3:   in separate mount namespace and separate PID namespace
3: -------------------------------------------------------------------------------
3: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/cc/test_usdt_probes.cc:340
3: ...............................................................................
3: 
3: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/cc/test_usdt_probes.cc:381: FAILED:
3:   REQUIRE( res.msg() == "" )
3: with expansion:
3:   "Unable to load USDT ruby:gc__mark__begin from binary  PID 123080543 for
3:   probe on_event"
3:   ==
3:   ""
3: 
3: ===============================================================================
3: test cases:  34 |  33 passed | 1 failed as expected
3: assertions: 507 | 505 passed | 2 failed as expected
3: 
 3/44 Test  #3: test_libbcc ......................   Passed    9.77 sec
test 4
      Start  4: py_test_stat1_b

4: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_stat1_b" "namespace" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_stat1.py" "test_stat1.b" "proto.b"
4: Test timeout computed to be: 9.99988e+06
4: Actual changes:
4: tx-checksumming: off
4: 	tx-checksum-ip-generic: off
4: tcp-segmentation-offload: off
4: 	tx-tcp-segmentation: off [requested on]
4: 	tx-tcp-ecn-segmentation: off [requested on]
4: 	tx-tcp6-segmentation: off [requested on]
4: udp-fragmentation-offload: off [requested on]
4: .PING 172.16.1.1 (172.16.1.1) 56(84) bytes of data.
4: . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . 
4: --- 172.16.1.1 ping statistics ---
4: 100 packets transmitted, 100 received, 0% packet loss, time 1ms
4: rtt min/avg/max/mdev = 0.005/0.007/0.119/0.011 ms, ipg/ewma 0.016/0.006 ms
4: .
4: ----------------------------------------------------------------------
4: Ran 2 tests in 0.698s
4: 
4: OK
 4/44 Test  #4: py_test_stat1_b ..................   Passed    1.38 sec
test 5
      Start  5: py_test_bpf_log

5: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_bpf_prog" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_bpf_log.py"
5: Test timeout computed to be: 9.99988e+06
 5/44 Test  #5: py_test_bpf_log ..................   Passed    1.52 sec
test 6
      Start  6: py_test_stat1_c

6: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_stat1_c" "namespace" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_stat1.py" "test_stat1.c"
6: Test timeout computed to be: 9.99988e+06
6: Actual changes:
6: tx-checksumming: off
6: 	tx-checksum-ip-generic: off
6: tcp-segmentation-offload: off
6: 	tx-tcp-segmentation: off [requested on]
6: 	tx-tcp-ecn-segmentation: off [requested on]
6: 	tx-tcp6-segmentation: off [requested on]
6: udp-fragmentation-offload: off [requested on]
6: .PING 172.16.1.1 (172.16.1.1) 56(84) bytes of data.
6: . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . 
6: --- 172.16.1.1 ping statistics ---
6: 100 packets transmitted, 100 received, 0% packet loss, time 1ms
6: rtt min/avg/max/mdev = 0.005/0.008/0.123/0.011 ms, ipg/ewma 0.018/0.007 ms
6: .
6: ----------------------------------------------------------------------
6: Ran 2 tests in 0.509s
6: 
6: OK
 6/44 Test  #6: py_test_stat1_c ..................   Passed    0.84 sec
test 7
      Start  7: py_test_xlate1_c

7: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_xlate1_c" "namespace" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_xlate1.py" "test_xlate1.c"
7: Test timeout computed to be: 9.99988e+06
7: Actual changes:
7: tx-checksumming: off
7: 	tx-checksum-ip-generic: off
7: tcp-segmentation-offload: off
7: 	tx-tcp-segmentation: off [requested on]
7: 	tx-tcp-ecn-segmentation: off [requested on]
7: 	tx-tcp6-segmentation: off [requested on]
7: udp-fragmentation-offload: off [requested on]
7: PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
7: 64 bytes from 192.168.1.1: icmp_seq=1 ttl=64 time=0.058 ms
7: 
7: --- 192.168.1.1 ping statistics ---
7: 1 packets transmitted, 1 received, 0% packet loss, time 0ms
7: rtt min/avg/max/mdev = 0.058/0.058/0.058/0.000 ms
7: .
7: ----------------------------------------------------------------------
7: Ran 1 test in 0.319s
7: 
7: OK
 7/44 Test  #7: py_test_xlate1_c .................   Passed    0.69 sec
test 8
      Start  8: py_test_call1

8: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_call1_c" "namespace" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_call1.py" "test_call1.c"
8: Test timeout computed to be: 9.99988e+06
8: Actual changes:
8: tx-checksumming: off
8: 	tx-checksum-ip-generic: off
8: tcp-segmentation-offload: off
8: 	tx-tcp-segmentation: off [requested on]
8: 	tx-tcp-ecn-segmentation: off [requested on]
8: 	tx-tcp6-segmentation: off [requested on]
8: udp-fragmentation-offload: off [requested on]
8: .
8: ----------------------------------------------------------------------
8: Ran 1 test in 0.245s
8: 
8: OK
 8/44 Test  #8: py_test_call1 ....................   Passed    0.64 sec
test 9
      Start  9: py_test_trace1

9: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_trace1" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_trace1.py" "test_trace1.b" "kprobe.b"
9: Test timeout computed to be: 9.99988e+06
9: .
9: ----------------------------------------------------------------------
9: Ran 1 test in 0.360s
9: 
9: OK
9: ('fd 8:', 'stat1 0', 'stat2 1')
9: ('fd ffff8800bacdad80:', 'stat1 2', 'stat2 0')
9: ('fd a:', 'stat1 200', 'stat2 100')
9: ('fd 9:', 'stat1 1', 'stat2 1')
 9/44 Test  #9: py_test_trace1 ...................   Passed    0.51 sec
test 10
      Start 10: py_test_trace2

10: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_trace2" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_trace2.py"
10: Test timeout computed to be: 9.99988e+06
10: .
10: ----------------------------------------------------------------------
10: Ran 1 test in 2.389s
10: 
10: OK
10: ('ptr ffff880215a0c600:', 'stat1 (0 1)')
10: ('ptr ffff880213abf000:', 'stat1 (0 10)')
10: ('ptr ffff880213f4f000:', 'stat1 (0 1)')
10: ('ptr ffff880216b33800:', 'stat1 (0 1)')
10: ('ptr ffff880213a38e00:', 'stat1 (0 100)')
10: ('ptr ffff880213ec3800:', 'stat1 (0 1)')
10: ('ptr ffff880213e90e00:', 'stat1 (0 1)')
10: ('ptr ffff880216b30e00:', 'stat1 (0 61)')
10: ('ptr ffff8800ba64aa00:', 'stat1 (0 1)')
10: ('ptr ffff880216460e00:', 'stat1 (0 1)')
10: ('ptr ffff880213ab3800:', 'stat1 (0 5)')
10: ('ptr ffff880213e94600:', 'stat1 (0 3)')
10: ('ptr ffffffff81e13500:', 'stat1 (0 107)')
10: ('ptr ffff880216b72a00:', 'stat1 (0 1)')
10: ('ptr ffff880215cc8000:', 'stat1 (0 21)')
10: ('ptr ffff8800bb963800:', 'stat1 (0 2)')
10: ('ptr ffff880216ad5400:', 'stat1 (0 6)')
10: ('ptr ffff880213c58e00:', 'stat1 (0 11)')
10: ('ptr ffff880215a09c00:', 'stat1 (0 4)')
10/44 Test #10: py_test_trace2 ...................   Passed    2.47 sec
test 11
      Start 11: py_test_trace3_c

11: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_trace3_c" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_trace3.py" "test_trace3.c"
11: Test timeout computed to be: 9.99988e+06
11: 1024+0 records in
11: 1024+0 records out
11: 4194304 bytes (4.2 MB, 4.0 MiB) copied, 0.00378753 s, 1.1 GB/s
11: 1024+0 records in
11: 1024+0 records out
11: 4194304 bytes (4.2 MB, 4.0 MiB) copied, 0.00458611 s, 915 MB/s
11: ('latency 0:', 'count 0')
11: ('latency 1:', 'count 0')
11: ('latency 2:', 'count 0')
11: ('latency 3:', 'count 0')
11: ('latency 4:', 'count 0')
11: ('latency 5:', 'count 0')
11: ('latency 6:', 'count 0')
11: ('latency 7:', 'count 0')
11: ('latency 8:', 'count 0')
11: ('latency 9:', 'count 0')
11: ('latency 10:', 'count 0')
11: ('latency 11:', 'count 0')
11: ('latency 12:', 'count 0')
11: ('latency 13:', 'count 0')
11: ('latency 14:', 'count 0')
11: ('latency 15:', 'count 0')
11: ('latency 16:', 'count 0')
11: ('latency 17:', 'count 0')
11: ('latency 18:', 'count 0')
11: ('latency 19:', 'count 0')
11: ('latency 20:', 'count 0')
11: ('latency 21:', 'count 0')
11: ('latency 22:', 'count 0')
11: ('latency 23:', 'count 0')
11: ('latency 24:', 'count 0')
11: ('latency 25:', 'count 0')
11: ('latency 26:', 'count 0')
11: ('latency 27:', 'count 0')
11: ('latency 28:', 'count 0')
11: ('latency 29:', 'count 0')
11: ('latency 30:', 'count 0')
11: ('latency 31:', 'count 0')
11: ('latency 32:', 'count 0')
11: ('latency 33:', 'count 0')
11: ('latency 34:', 'count 0')
11: ('latency 35:', 'count 0')
11: ('latency 36:', 'count 0')
11: ('latency 37:', 'count 0')
11: ('latency 38:', 'count 0')
11: ('latency 39:', 'count 0')
11: ('latency 40:', 'count 0')
11: ('latency 41:', 'count 0')
11: ('latency 42:', 'count 0')
11: ('latency 43:', 'count 0')
11: ('latency 44:', 'count 0')
11: ('latency 45:', 'count 1')
11: ('latency 46:', 'count 2')
11: ('latency 47:', 'count 0')
11: ('latency 48:', 'count 1')
11: ('latency 49:', 'count 0')
11: ('latency 50:', 'count 0')
11: ('latency 51:', 'count 0')
11: ('latency 52:', 'count 0')
11: ('latency 53:', 'count 0')
11: ('latency 54:', 'count 0')
11: ('latency 55:', 'count 0')
11: ('latency 56:', 'count 1')
11: ('latency 57:', 'count 15')
11: ('latency 58:', 'count 16')
11: ('latency 59:', 'count 3')
11: ('latency 60:', 'count 9')
11: ('latency 61:', 'count 10')
11: ('latency 62:', 'count 6')
11: ('latency 63:', 'count 35')
11: ('latency 64:', 'count 58')
11: ('latency 65:', 'count 7')
11: ('latency 66:', 'count 14')
11: ('latency 67:', 'count 5')
11: ('latency 68:', 'count 32')
11: ('latency 69:', 'count 0')
11: ('latency 70:', 'count 1')
11: ('latency 71:', 'count 1')
11: ('latency 72:', 'count 0')
11: ('latency 73:', 'count 0')
11: ('latency 74:', 'count 0')
11: ('latency 75:', 'count 0')
11: ('latency 76:', 'count 0')
11: ('latency 77:', 'count 0')
11: ('latency 78:', 'count 0')
11: ('latency 79:', 'count 0')
11: ('latency 80:', 'count 0')
11: ('latency 81:', 'count 0')
11: ('latency 82:', 'count 0')
11: ('latency 83:', 'count 0')
11: ('latency 84:', 'count 0')
11: ('latency 85:', 'count 0')
11: ('latency 86:', 'count 0')
11: ('latency 87:', 'count 0')
11: ('latency 88:', 'count 0')
11: ('latency 89:', 'count 0')
11: ('latency 90:', 'count 0')
11: ('latency 91:', 'count 0')
11: ('latency 92:', 'count 0')
11: ('latency 93:', 'count 0')
11: ('latency 94:', 'count 0')
11: ('latency 95:', 'count 0')
11: ('latency 96:', 'count 0')
11: ('latency 97:', 'count 0')
11: ('latency 98:', 'count 0')
11: ('latency 99:', 'count 0')
11: .
11: ----------------------------------------------------------------------
11: Ran 1 test in 1.340s
11: 
11: OK
11/44 Test #11: py_test_trace3_c .................   Passed    1.53 sec
test 12
      Start 12: py_test_trace4

12: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_trace4" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_trace4.py"
12: Test timeout computed to be: 9.99988e+06
12: ..
12: ----------------------------------------------------------------------
12: Ran 2 tests in 1.463s
12: 
12: OK
12/44 Test #12: py_test_trace4 ...................   Passed    1.66 sec
test 13
      Start 13: py_test_trace_maxactive

13: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_trace_maxactive" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_trace_maxactive.py"
13: Test timeout computed to be: 9.99988e+06
13: .
13: ----------------------------------------------------------------------
13: Ran 1 test in 0.935s
13: 
13: OK
13/44 Test #13: py_test_trace_maxactive ..........   Passed    1.05 sec
test 14
      Start 14: py_test_probe_count

14: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_probe_count" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_probe_count.py"
14: Test timeout computed to be: 9.99988e+06
14: ...cannot attach kprobe, probe entry may not exist
14: ...
14: ----------------------------------------------------------------------
14: Ran 6 tests in 4.315s
14: 
14: OK
14/44 Test #14: py_test_probe_count ..............   Passed    4.36 sec
test 15
      Start 15: py_test_debuginfo

15: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_debuginfo" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_debuginfo.py"
15: Test timeout computed to be: 9.99988e+06
15: ......
15: ----------------------------------------------------------------------
15: Ran 6 tests in 0.518s
15: 
15: OK
15/44 Test #15: py_test_debuginfo ................   Passed    0.61 sec
test 16
      Start 16: py_test_brb

16: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_brb_c" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_brb.py" "test_brb.c"
16: Test timeout computed to be: 9.99988e+06
16: net.ipv4.ip_forward = 1
16: ARPING 100.1.1.254
16: Timeout
16: 
16: --- 100.1.1.254 statistics ---
16: 1 packets transmitted, 0 packets received, 100% unanswered (0 extra)
16: 
16: ARPING 200.1.1.254
16: Timeout
16: 
16: --- 200.1.1.254 statistics ---
16: 1 packets transmitted, 0 packets received, 100% unanswered (0 extra)
16: 
16: PING 200.1.1.1 (200.1.1.1) 56(84) bytes of data.
16: 64 bytes from 200.1.1.1: icmp_seq=1 ttl=63 time=0.128 ms
16: 64 bytes from 200.1.1.1: icmp_seq=2 ttl=63 time=0.061 ms
16: 
16: --- 200.1.1.1 ping statistics ---
16: 2 packets transmitted, 2 received, 0% packet loss, time 1001ms
16: rtt min/avg/max/mdev = 0.061/0.094/0.128/0.034 ms
16: [ ID] Interval       Transfer     Bandwidth
16: [  7]  0.0- 1.0 sec  3.73 GBytes  32.1 Gbits/sec
16: [ ID] Interval       Transfer     Bandwidth
16: [ 10]  0.0- 1.0 sec  3.73 GBytes  31.8 Gbits/sec
16: Starting netserver with host 'IN(6)ADDR_ANY' port '12865' and family AF_UNSPEC
16: MIGRATED TCP STREAM TEST from 0.0.0.0 (0.0.0.0) port 0 AF_INET to 200.1.1.1 () port 0 AF_INET : demo
16: Recv   Send    Send                          
16: Socket Socket  Message  Elapsed              
16: Size   Size    Size     Time     Throughput  
16: bytes  bytes   bytes    secs.    10^6bits/sec  
16: 
16:  87380  16384  65160    1.00     35378.66   
16: MIGRATED TCP REQUEST/RESPONSE TEST from 0.0.0.0 (0.0.0.0) port 0 AF_INET to 200.1.1.1 () port 0 AF_INET : demo : first burst 0
16: Local /Remote
16: Socket Size   Request  Resp.   Elapsed  Trans.
16: Send   Recv   Size     Size    Time     Rate         
16: bytes  Bytes  bytes    bytes   secs.    per sec   
16: 
16: 16384  87380  1        1       1.00     25801.98   
16: 16384  87380 
16: .
16: ----------------------------------------------------------------------
16: Ran 1 test in 7.496s
16: 
16: OK
16/44 Test #16: py_test_brb ......................   Passed    7.61 sec
test 17
      Start 17: py_test_brb2

17: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_brb2_c" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_brb2.py" "test_brb2.c"
17: Test timeout computed to be: 9.99988e+06
17: net.ipv4.ip_forward = 1
17: PING 200.1.1.1 (200.1.1.1) 56(84) bytes of data.
17: 64 bytes from 200.1.1.1: icmp_seq=1 ttl=63 time=0.165 ms
17: 64 bytes from 200.1.1.1: icmp_seq=2 ttl=63 time=0.072 ms
17: 
17: --- 200.1.1.1 ping statistics ---
17: 2 packets transmitted, 2 received, 0% packet loss, time 1001ms
17: rtt min/avg/max/mdev = 0.072/0.118/0.165/0.047 ms
17: [ ID] Interval       Transfer     Bandwidth
17: [  7]  0.0- 1.0 sec  3.35 GBytes  28.8 Gbits/sec
17: [ ID] Interval       Transfer     Bandwidth
17: [ 10]  0.0- 1.0 sec  3.35 GBytes  28.5 Gbits/sec
17: Starting netserver with host 'IN(6)ADDR_ANY' port '12865' and family AF_UNSPEC
17: MIGRATED TCP STREAM TEST from 0.0.0.0 (0.0.0.0) port 0 AF_INET to 200.1.1.1 () port 0 AF_INET : demo
17: Recv   Send    Send                          
17: Socket Socket  Message  Elapsed              
17: Size   Size    Size     Time     Throughput  
17: bytes  bytes   bytes    secs.    10^6bits/sec  
17: 
17:  87380  16384  65160    1.00     29929.76   
17: MIGRATED TCP REQUEST/RESPONSE TEST from 0.0.0.0 (0.0.0.0) port 0 AF_INET to 200.1.1.1 () port 0 AF_INET : demo : first burst 0
17: Local /Remote
17: Socket Size   Request  Resp.   Elapsed  Trans.
17: Send   Recv   Size     Size    Time     Rate         
17: bytes  Bytes  bytes    bytes   secs.    per sec   
17: 
17: 16384  87380  1        1       1.00     23995.66   
17: 16384  87380 
17: .
17: ----------------------------------------------------------------------
17: Ran 1 test in 7.590s
17: 
17: OK
17/44 Test #17: py_test_brb2 .....................   Passed    7.70 sec
test 18
      Start 18: py_test_clang

18: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_clang" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_clang.py"
18: Test timeout computed to be: 9.99988e+06
18: ............s..../virtual/main.c:2:1: error: field has incomplete type 'struct key_t'
18: BPF_HASH(drops, struct key_t);
18: ^
18: /virtual/include/bcc/helpers.h:256:48: note: expanded from macro 'BPF_HASH'
18:   BPF_HASHX(__VA_ARGS__, BPF_HASH4, BPF_HASH3, BPF_HASH2, BPF_HASH1)(__VA_ARGS__)
18:                                                ^
18: /virtual/main.c:2:24: note: forward declaration of 'struct key_t'
18: BPF_HASH(drops, struct key_t);
18:                        ^
18: /virtual/main.c:2:1: error: field has incomplete type 'struct key_t'
18: BPF_HASH(drops, struct key_t);
18: ^
18: /virtual/include/bcc/helpers.h:256:48: note: expanded from macro 'BPF_HASH'
18:   BPF_HASHX(__VA_ARGS__, BPF_HASH4, BPF_HASH3, BPF_HASH2, BPF_HASH1)(__VA_ARGS__)
18:                                                ^
18: /virtual/main.c:2:24: note: forward declaration of 'struct key_t'
18: BPF_HASH(drops, struct key_t);
18:                        ^
18: 2 errors generated.
18: ../virtual/main.c:6:12: error: cannot call non-static helper function
18:     return bar();
18:            ^
18: 1 error generated.
18: ..ss.....s................................/virtual/main.c:7:12: warning: incompatible pointer to integer conversion returning 'u32 *' (aka 'unsigned int *') from a function with result type 'int' [-Wint-conversion]
18:     return *(&skp->sk_daddr);
18:            ^~~~~~~~~~~~~~~~~
18: 1 warning generated.
18: ....ss........../virtual/main.c:1:30: error: expected expression
18: int failure(void *ctx) { if (); return 0; }
18:                              ^
18: 1 error generated.
18: ../virtual/main.c:3:73: error: too many arguments, bcc only supports in-register parameters
18: int many(struct pt_regs *ctx, int a, int b, int c, int d, int e, int f, int g) {
18:                                                                         ^
18: 1 error generated.
18: ....
18: ----------------------------------------------------------------------
18: Ran 83 tests in 55.259s
18: 
18: OK (skipped=6)
18: 0
18/44 Test #18: py_test_clang ....................   Passed   55.57 sec
test 19
      Start 19: py_test_histogram

19: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_histogram" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_histogram.py"
19: Test timeout computed to be: 9.99988e+06
19: 
19: Bucket ptr = 'C2 CompilerThre'
19:      value               : count     distribution
19:          0 -> 1          : 0        |                                        |
19:          2 -> 3          : 0        |                                        |
19:          4 -> 7          : 0        |                                        |
19:          8 -> 15         : 0        |                                        |
19:         16 -> 31         : 0        |                                        |
19:         32 -> 63         : 0        |                                        |
19:         64 -> 127        : 0        |                                        |
19:        128 -> 255        : 0        |                                        |
19:        256 -> 511        : 0        |                                        |
19:        512 -> 1023       : 0        |                                        |
19:       1024 -> 2047       : 0        |                                        |
19:       2048 -> 4095       : 0        |                                        |
19:       4096 -> 8191       : 0        |                                        |
19:       8192 -> 16383      : 0        |                                        |
19:      16384 -> 32767      : 0        |                                        |
19:      32768 -> 65535      : 0        |                                        |
19:      65536 -> 131071     : 0        |                                        |
19:     131072 -> 262143     : 0        |                                        |
19:     262144 -> 524287     : 0        |                                        |
19:     524288 -> 1048575    : 0        |                                        |
19:    1048576 -> 2097151    : 0        |                                        |
19:    2097152 -> 4194303    : 0        |                                        |
19:    4194304 -> 8388607    : 0        |                                        |
19:    8388608 -> 16777215   : 0        |                                        |
19:   16777216 -> 33554431   : 0        |                                        |
19:   33554432 -> 67108863   : 0        |                                        |
19:   67108864 -> 134217727  : 1        |****************************************|
19: 
19: Bucket ptr = 'ntpd'
19:      value               : count     distribution
19:          0 -> 1          : 1        |****************************************|
19: 
19: Bucket ptr = 'python'
19:      value               : count     distribution
19:          0 -> 1          : 86       |****************************************|
19:          2 -> 3          : 0        |                                        |
19:          4 -> 7          : 0        |                                        |
19:          8 -> 15         : 0        |                                        |
19:         16 -> 31         : 0        |                                        |
19:         32 -> 63         : 0        |                                        |
19:         64 -> 127        : 0        |                                        |
19:        128 -> 255        : 0        |                                        |
19:        256 -> 511        : 0        |                                        |
19:        512 -> 1023       : 0        |                                        |
19:       1024 -> 2047       : 0        |                                        |
19:       2048 -> 4095       : 0        |                                        |
19:       4096 -> 8191       : 0        |                                        |
19:       8192 -> 16383      : 0        |                                        |
19:      16384 -> 32767      : 0        |                                        |
19:      32768 -> 65535      : 0        |                                        |
19:      65536 -> 131071     : 0        |                                        |
19:     131072 -> 262143     : 0        |                                        |
19:     262144 -> 524287     : 0        |                                        |
19:     524288 -> 1048575    : 0        |                                        |
19:    1048576 -> 2097151    : 0        |                                        |
19:    2097152 -> 4194303    : 0        |                                        |
19:    4194304 -> 8388607    : 0        |                                        |
19:    8388608 -> 16777215   : 0        |                                        |
19:   16777216 -> 33554431   : 0        |                                        |
19:   33554432 -> 67108863   : 0        |                                        |
19:   67108864 -> 134217727  : 14       |******                                  |
19: 
19: Bucket ptr = 'RemoteInvocatio'
19:      value               : count     distribution
19:          0 -> 1          : 0        |                                        |
19:          2 -> 3          : 0        |                                        |
19:          4 -> 7          : 0        |                                        |
19:          8 -> 15         : 0        |                                        |
19:         16 -> 31         : 0        |                                        |
19:         32 -> 63         : 0        |                                        |
19:         64 -> 127        : 0        |                                        |
19:        128 -> 255        : 0        |                                        |
19:        256 -> 511        : 0        |                                        |
19:        512 -> 1023       : 0        |                                        |
19:       1024 -> 2047       : 0        |                                        |
19:       2048 -> 4095       : 0        |                                        |
19:       4096 -> 8191       : 0        |                                        |
19:       8192 -> 16383      : 0        |                                        |
19:      16384 -> 32767      : 0        |                                        |
19:      32768 -> 65535      : 0        |                                        |
19:      65536 -> 131071     : 0        |                                        |
19:     131072 -> 262143     : 0        |                                        |
19:     262144 -> 524287     : 0        |                                        |
19:     524288 -> 1048575    : 0        |                                        |
19:    1048576 -> 2097151    : 0        |                                        |
19:    2097152 -> 4194303    : 0        |                                        |
19:    4194304 -> 8388607    : 0        |                                        |
19:    8388608 -> 16777215   : 0        |                                        |
19:   16777216 -> 33554431   : 0        |                                        |
19:   33554432 -> 67108863   : 0        |                                        |
19:   67108864 -> 134217727  : 5        |****************************************|
19: 
19: Bucket ptr = 'C1 CompilerThre'
19:      value               : count     distribution
19:          0 -> 1          : 0        |                                        |
19:          2 -> 3          : 0        |                                        |
19:          4 -> 7          : 0        |                                        |
19:          8 -> 15         : 0        |                                        |
19:         16 -> 31         : 0        |                                        |
19:         32 -> 63         : 0        |                                        |
19:         64 -> 127        : 0        |                                        |
19:        128 -> 255        : 0        |                                        |
19:        256 -> 511        : 0        |                                        |
19:        512 -> 1023       : 0        |                                        |
19:       1024 -> 2047       : 0        |                                        |
19:       2048 -> 4095       : 0        |                                        |
19:       4096 -> 8191       : 0        |                                        |
19:       8192 -> 16383      : 0        |                                        |
19:      16384 -> 32767      : 0        |                                        |
19:      32768 -> 65535      : 0        |                                        |
19:      65536 -> 131071     : 0        |                                        |
19:     131072 -> 262143     : 0        |                                        |
19:     262144 -> 524287     : 0        |                                        |
19:     524288 -> 1048575    : 0        |                                        |
19:    1048576 -> 2097151    : 0        |                                        |
19:    2097152 -> 4194303    : 0        |                                        |
19:    4194304 -> 8388607    : 0        |                                        |
19:    8388608 -> 16777215   : 0        |                                        |
19:   16777216 -> 33554431   : 0        |                                        |
19:   33554432 -> 67108863   : 0        |                                        |
19:   67108864 -> 134217727  : 1        |****************************************|
19: 
19: Bucket ptr = 'kworker/u4:1'
19:      value               : count     distribution
19:          0 -> 1          : 0        |                                        |
19:          2 -> 3          : 0        |                                        |
19:          4 -> 7          : 0        |                                        |
19:          8 -> 15         : 0        |                                        |
19:         16 -> 31         : 0        |                                        |
19:         32 -> 63         : 0        |                                        |
19:         64 -> 127        : 0        |                                        |
19:        128 -> 255        : 0        |                                        |
19:        256 -> 511        : 0        |                                        |
19:        512 -> 1023       : 0        |                                        |
19:       1024 -> 2047       : 0        |                                        |
19:       2048 -> 4095       : 0        |                                        |
19:       4096 -> 8191       : 0        |                                        |
19:       8192 -> 16383      : 0        |                                        |
19:      16384 -> 32767      : 0        |                                        |
19:      32768 -> 65535      : 0        |                                        |
19:      65536 -> 131071     : 0        |                                        |
19:     131072 -> 262143     : 0        |                                        |
19:     262144 -> 524287     : 0        |                                        |
19:     524288 -> 1048575    : 0        |                                        |
19:    1048576 -> 2097151    : 0        |                                        |
19:    2097152 -> 4194303    : 0        |                                        |
19:    4194304 -> 8388607    : 0        |                                        |
19:    8388608 -> 16777215   : 0        |                                        |
19:   16777216 -> 33554431   : 0        |                                        |
19:   33554432 -> 67108863   : 0        |                                        |
19:   67108864 -> 134217727  : 1        |****************************************|
19: 
19: Bucket ptr = 'iscsid'
19:      value               : count     distribution
19:          0 -> 1          : 0        |                                        |
19:          2 -> 3          : 0        |                                        |
19:          4 -> 7          : 0        |                                        |
19:          8 -> 15         : 0        |                                        |
19:         16 -> 31         : 0        |                                        |
19:         32 -> 63         : 0        |                                        |
19:         64 -> 127        : 0        |                                        |
19:        128 -> 255        : 0        |                                        |
19:        256 -> 511        : 0        |                                        |
19:        512 -> 1023       : 0        |                                        |
19:       1024 -> 2047       : 0        |                                        |
19:       2048 -> 4095       : 0        |                                        |
19:       4096 -> 8191       : 0        |                                        |
19:       8192 -> 16383      : 0        |                                        |
19:      16384 -> 32767      : 0        |                                        |
19:      32768 -> 65535      : 0        |                                        |
19:      65536 -> 131071     : 0        |                                        |
19:     131072 -> 262143     : 0        |                                        |
19:     262144 -> 524287     : 0        |                                        |
19:     524288 -> 1048575    : 0        |                                        |
19:    1048576 -> 2097151    : 0        |                                        |
19:    2097152 -> 4194303    : 0        |                                        |
19:    4194304 -> 8388607    : 0        |                                        |
19:    8388608 -> 16777215   : 0        |                                        |
19:   16777216 -> 33554431   : 0        |                                        |
19:   33554432 -> 67108863   : 0        |                                        |
19:   67108864 -> 134217727  : 5        |****************************************|
19: 
19: Bucket ptr = 'kworker/0:1'
19:      value               : count     distribution
19:          0 -> 1          : 1        |****************************************|
19: 
19: Bucket ptr = 'sshguard'
19:      value               : count     distribution
19:          0 -> 1          : 27       |****************************************|
19: 
19: Bucket ptr = 'VM Periodic Tas'
19:      value               : count     distribution
19:          0 -> 1          : 0        |                                        |
19:          2 -> 3          : 0        |                                        |
19:          4 -> 7          : 0        |                                        |
19:          8 -> 15         : 0        |                                        |
19:         16 -> 31         : 0        |                                        |
19:         32 -> 63         : 0        |                                        |
19:         64 -> 127        : 0        |                                        |
19:        128 -> 255        : 0        |                                        |
19:        256 -> 511        : 0        |                                        |
19:        512 -> 1023       : 0        |                                        |
19:       1024 -> 2047       : 0        |                                        |
19:       2048 -> 4095       : 0        |                                        |
19:       4096 -> 8191       : 0        |                                        |
19:       8192 -> 16383      : 0        |                                        |
19:      16384 -> 32767      : 0        |                                        |
19:      32768 -> 65535      : 0        |                                        |
19:      65536 -> 131071     : 0        |                                        |
19:     131072 -> 262143     : 0        |                                        |
19:     262144 -> 524287     : 0        |                                        |
19:     524288 -> 1048575    : 0        |                                        |
19:    1048576 -> 2097151    : 0        |                                        |
19:    2097152 -> 4194303    : 0        |                                        |
19:    4194304 -> 8388607    : 0        |                                        |
19:    8388608 -> 16777215   : 0        |                                        |
19:   16777216 -> 33554431   : 0        |                                        |
19:   33554432 -> 67108863   : 0        |                                        |
19:   67108864 -> 134217727  : 20       |****************************************|
19: 
19: Bucket ptr = 'VM Thread'
19:      value               : count     distribution
19:          0 -> 1          : 0        |                                        |
19:          2 -> 3          : 0        |                                        |
19:          4 -> 7          : 0        |                                        |
19:          8 -> 15         : 0        |                                        |
19:         16 -> 31         : 0        |                                        |
19:         32 -> 63         : 0        |                                        |
19:         64 -> 127        : 0        |                                        |
19:        128 -> 255        : 0        |                                        |
19:        256 -> 511        : 0        |                                        |
19:        512 -> 1023       : 0        |                                        |
19:       1024 -> 2047       : 0        |                                        |
19:       2048 -> 4095       : 0        |                                        |
19:       4096 -> 8191       : 0        |                                        |
19:       8192 -> 16383      : 0        |                                        |
19:      16384 -> 32767      : 0        |                                        |
19:      32768 -> 65535      : 0        |                                        |
19:      65536 -> 131071     : 0        |                                        |
19:     131072 -> 262143     : 0        |                                        |
19:     262144 -> 524287     : 0        |                                        |
19:     524288 -> 1048575    : 0        |                                        |
19:    1048576 -> 2097151    : 0        |                                        |
19:    2097152 -> 4194303    : 0        |                                        |
19:    4194304 -> 8388607    : 0        |                                        |
19:    8388608 -> 16777215   : 0        |                                        |
19:   16777216 -> 33554431   : 0        |                                        |
19:   33554432 -> 67108863   : 0        |                                        |
19:   67108864 -> 134217727  : 1        |****************************************|
19: 
19: Bucket ptr = 'swapper/0'
19:                value                         : count     distribution
19:                    0 -> 1                    : 0        |                    |
19:                    2 -> 3                    : 0        |                    |
19:                    4 -> 7                    : 0        |                    |
19:                    8 -> 15                   : 0        |                    |
19:                   16 -> 31                   : 0        |                    |
19:                   32 -> 63                   : 0        |                    |
19:                   64 -> 127                  : 0        |                    |
19:                  128 -> 255                  : 0        |                    |
19:                  256 -> 511                  : 0        |                    |
19:                  512 -> 1023                 : 0        |                    |
19:                 1024 -> 2047                 : 0        |                    |
19:                 2048 -> 4095                 : 0        |                    |
19:                 4096 -> 8191                 : 0        |                    |
19:                 8192 -> 16383                : 0        |                    |
19:                16384 -> 32767                : 0        |                    |
19:                32768 -> 65535                : 0        |                    |
19:                65536 -> 131071               : 0        |                    |
19:               131072 -> 262143               : 0        |                    |
19:               262144 -> 524287               : 0        |                    |
19:               524288 -> 1048575              : 0        |                    |
19:              1048576 -> 2097151              : 0        |                    |
19:              2097152 -> 4194303              : 0        |                    |
19:              4194304 -> 8388607              : 0        |                    |
19:              8388608 -> 16777215             : 0        |                    |
19:             16777216 -> 33554431             : 0        |                    |
19:             33554432 -> 67108863             : 0        |                    |
19:             67108864 -> 134217727            : 2        |                    |
19:            134217728 -> 268435455            : 0        |                    |
19:            268435456 -> 536870911            : 1        |                    |
19:            536870912 -> 1073741823           : 0        |                    |
19:           1073741824 -> 2147483647           : 0        |                    |
19:           2147483648 -> 4294967295           : 0        |                    |
19:           4294967296 -> 8589934591           : 0        |                    |
19:           8589934592 -> 17179869183          : 0        |                    |
19:          17179869184 -> 34359738367          : 0        |                    |
19:          34359738368 -> 68719476735          : 28       |******              |
19:          68719476736 -> 137438953471         : 0        |                    |
19:         137438953472 -> 274877906943         : 0        |                    |
19:         274877906944 -> 549755813887         : 0        |                    |
19:         549755813888 -> 1099511627775        : 85       |********************|
19: 
19: Bucket ptr = 'kworker/1:11'
19:      value               : count     distribution
19:          0 -> 1          : 0        |                                        |
19:          2 -> 3          : 0        |                                        |
19:          4 -> 7          : 0        |                                        |
19:          8 -> 15         : 0        |                                        |
19:         16 -> 31         : 0        |                                        |
19:         32 -> 63         : 0        |                                        |
19:         64 -> 127        : 0        |                                        |
19:        128 -> 255        : 0        |                                        |
19:        256 -> 511        : 0        |                                        |
19:        512 -> 1023       : 0        |                                        |
19:       1024 -> 2047       : 0        |                                        |
19:       2048 -> 4095       : 0        |                                        |
19:       4096 -> 8191       : 0        |                                        |
19:       8192 -> 16383      : 0        |                                        |
19:      16384 -> 32767      : 0        |                                        |
19:      32768 -> 65535      : 0        |                                        |
19:      65536 -> 131071     : 0        |                                        |
19:     131072 -> 262143     : 0        |                                        |
19:     262144 -> 524287     : 0        |                                        |
19:     524288 -> 1048575    : 0        |                                        |
19:    1048576 -> 2097151    : 0        |                                        |
19:    2097152 -> 4194303    : 0        |                                        |
19:    4194304 -> 8388607    : 0        |                                        |
19:    8388608 -> 16777215   : 0        |                                        |
19:   16777216 -> 33554431   : 0        |                                        |
19:   33554432 -> 67108863   : 0        |                                        |
19:   67108864 -> 134217727  : 1        |****************************************|
19: 
19: Bucket ptr = 'swapper/1'
19:                value                         : count     distribution
19:                    0 -> 1                    : 0        |                    |
19:                    2 -> 3                    : 0        |                    |
19:                    4 -> 7                    : 0        |                    |
19:                    8 -> 15                   : 0        |                    |
19:                   16 -> 31                   : 0        |                    |
19:                   32 -> 63                   : 0        |                    |
19:                   64 -> 127                  : 0        |                    |
19:                  128 -> 255                  : 0        |                    |
19:                  256 -> 511                  : 0        |                    |
19:                  512 -> 1023                 : 0        |                    |
19:                 1024 -> 2047                 : 0        |                    |
19:                 2048 -> 4095                 : 0        |                    |
19:                 4096 -> 8191                 : 0        |                    |
19:                 8192 -> 16383                : 0        |                    |
19:                16384 -> 32767                : 0        |                    |
19:                32768 -> 65535                : 0        |                    |
19:                65536 -> 131071               : 0        |                    |
19:               131072 -> 262143               : 0        |                    |
19:               262144 -> 524287               : 0        |                    |
19:               524288 -> 1048575              : 0        |                    |
19:              1048576 -> 2097151              : 0        |                    |
19:              2097152 -> 4194303              : 0        |                    |
19:              4194304 -> 8388607              : 0        |                    |
19:              8388608 -> 16777215             : 0        |                    |
19:             16777216 -> 33554431             : 0        |                    |
19:             33554432 -> 67108863             : 0        |                    |
19:             67108864 -> 134217727            : 0        |                    |
19:            134217728 -> 268435455            : 0        |                    |
19:            268435456 -> 536870911            : 1        |                    |
19:            536870912 -> 1073741823           : 0        |                    |
19:           1073741824 -> 2147483647           : 0        |                    |
19:           2147483648 -> 4294967295           : 0        |                    |
19:           4294967296 -> 8589934591           : 0        |                    |
19:           8589934592 -> 17179869183          : 0        |                    |
19:          17179869184 -> 34359738367          : 0        |                    |
19:          34359738368 -> 68719476735          : 31       |********************|
19:          68719476736 -> 137438953471         : 5        |***                 |
19:         137438953472 -> 274877906943         : 0        |                    |
19:         274877906944 -> 549755813887         : 11       |*******             |
19:         549755813888 -> 1099511627775        : 15       |*********           |
19: 
19: Bucket ptr = 'rcu_sched'
19:      value               : count     distribution
19:          0 -> 1          : 2        |****************************************|
19: 
19: Bucket ptr = 'ctest'
19:      value               : count     distribution
19:          0 -> 1          : 0        |                                        |
19:          2 -> 3          : 0        |                                        |
19:          4 -> 7          : 0        |                                        |
19:          8 -> 15         : 0        |                                        |
19:         16 -> 31         : 0        |                                        |
19:         32 -> 63         : 0        |                                        |
19:         64 -> 127        : 0        |                                        |
19:        128 -> 255        : 0        |                                        |
19:        256 -> 511        : 0        |                                        |
19:        512 -> 1023       : 0        |                                        |
19:       1024 -> 2047       : 0        |                                        |
19:       2048 -> 4095       : 0        |                                        |
19:       4096 -> 8191       : 0        |                                        |
19:       8192 -> 16383      : 0        |                                        |
19:      16384 -> 32767      : 0        |                                        |
19:      32768 -> 65535      : 0        |                                        |
19:      65536 -> 131071     : 0        |                                        |
19:     131072 -> 262143     : 0        |                                        |
19:     262144 -> 524287     : 0        |                                        |
19:     524288 -> 1048575    : 0        |                             ..           |
19:    1048576 -> 2097151    : 0        |                                        |
19:    2097152 -> 4194303    : 0        |                                        |
19:    4194304 -> 8388607    : 0        |                                        |
19:    8388608 -> 16777215   : 0        |                                        |
19:   16777216 -> 33554431   : 0        |                                        |
19:   33554432 -> 67108863   : 0        |                                        |
19:   67108864 -> 134217727  : 10       |****************************************|
19: 
19: Bucket ptr = 'G1 Young RemSet'
19:      value               : count     distribution
19:          0 -> 1          : 0        |                                        |
19:          2 -> 3          : 0        |                                        |
19:          4 -> 7          : 0        |                                        |
19:          8 -> 15         : 0        |                                        |
19:         16 -> 31         : 0        |                                        |
19:         32 -> 63         : 0        |                                        |
19:         64 -> 127        : 0        |                                        |
19:        128 -> 255        : 0        |                                        |
19:        256 -> 511        : 0        |                                        |
19:        512 -> 1023       : 0        |                                        |
19:       1024 -> 2047       : 0        |                                        |
19:       2048 -> 4095       : 0        |                                        |
19:       4096 -> 8191       : 0        |                                        |
19:       8192 -> 16383      : 0        |                                        |
19:      16384 -> 32767      : 0        |                                        |
19:      32768 -> 65535      : 0        |                                        |
19:      65536 -> 131071     : 0        |                                        |
19:     131072 -> 262143     : 0        |                                        |
19:     262144 -> 524287     : 0        |                                        |
19:     524288 -> 1048575    : 0        |                                        |
19:    1048576 -> 2097151    : 0        |                                        |
19:    2097152 -> 4194303    : 0        |                                        |
19:    4194304 -> 8388607    : 0        |                                        |
19:    8388608 -> 16777215   : 0        |                                        |
19:   16777216 -> 33554431   : 0        |                                        |
19:   33554432 -> 67108863   : 0        |                                        |
19:   67108864 -> 134217727  : 3        |****************************************|
19: 
19: k_1 & k_2 = 112 0
19:      size                : count     distribution
19:          0 -> 1          : 12       |****************************************|
19:      value               : count     distribution
19:          0 -> 1          : 7        |****************************            |
19:          2 -> 3          : 2        |********                                |
19:          4 -> 7          : 10       |****************************************|
19:          8 -> 15         : 8        |********************************        |
19:         16 -> 31         : 10       |****************************************|
19:         32 -> 63         : 1        |****                                    |
19:         64 -> 127        : 10       |****************************************|
19:        128 -> 255        : 4        |****************                        |
19:        256 -> 511        : 2        |********                                |
19:        512 -> 1023       : 5        |********************                    |
19:       1024 -> 2047       : 5        |********************                    |
19:       2048 -> 4095       : 7        |****************************            |
19:       4096 -> 8191       : 2        |********                                |
19:       8192 -> 16383      : 1        |****                                    |
19:      16384 -> 32767      : 3        |************                            |
19:      32768 -> 65535      : 6        |************************                |
19:      65536 -> 131071     : 8        |********************************        |
19:     131072 -> 262143     : 1        |****                                    |
19:     262144 -> 524287     : 6        |************************                |
19:     524288 -> 1048575    : 10       |****************************************|
19:    1048576 -> 2097151    : 4        |****************                        |
19:    2097152 -> 4194303    : 4        |****************                        |
19:    4194304 -> 8388607    : 2        |********                                |
19:    8388608 -> 16777215   : 7        |****************************            |
19:   16777216 -> 33554431   : 3        |************                            |
19:   33554432 -> 67108863   : 10       |****************************************|
19:   67108864 -> 134217727  : 1        |****                                    |
19:  134217728 -> 268435455  : 9        |************************************    |
19:  268435456 -> 536870911  : 10       |****************************************|
19:  536870912 -> 1073741823 : 4        |****************                        |
19: 1073741824 -> 2147483647 : 9        |************************************    |
19: 2147483648 -> 4294967295 : 5        |********************                    |
19:                value                         : count     distribution
19:                    0 -> 1                    : 7        |**************      |
19:                    2 -> 3                    : 2        |****                |
19:                    4 -> 7                    : 10       |********************|
19:                    8 -> 15                   : 8        |****************    |
19:                   16 -> 31                   : 10       |********************|
19:                   32 -> 63                   : 1        |**                  |
19:                   64 -> 127                  : 10       |********************|
19:                  128 -> 255                  : 4        |********            |
19:                  256 -> 511                  : 2        |****                |
19:                  512 -> 1023                 : 5        |**********          |
19:                 1024 -> 2047                 : 5        |**********          |
19:                 2048 -> 4095                 : 7        |**************      |
19:                 4096 -> 8191                 : 2        |****                |
19:                 8192 -> 16383                : 1        |**                  |
19:                16384 -> 32767                : 3        |******              |
19:                32768 -> 65535                : 6        |************        |
19:                65536 -> 131071               : 8        |****************    |
19:               131072 -> 262143               : 1        |**                  |
19:               262144 -> 524287               : 6        |************        |
19:               524288 -> 1048575              : 10       |********************|
19:              1048576 -> 2097151              : 4        |********            |
19:              2097152 -> 4194303              : 4        |********            |
19:              4194304 -> 8388607              : 2        |****                |
19:              8388608 -> 16777215             : 7        |**************      |
19:             16777216 -> 33554431             : 3        |******              |
19:             33554432 -> 67108863             : 10       |********************|
19:             67108864 -> 134217727            : 1        |**                  |
19:            134217728 -> 268435455            : 9        |******************  |
19:            268435456 -> 536870911            : 10       |********************|
19:            536870912 -> 1073741823           : 4        |********            |
19:           1073741824 -> 2147483647           : 9        |******************  |
19:           2147483648 -> 4294967295           : 5        |**********          |
19:           4294967296 -> 8589934591           : 1        |**                  |
19:         .  8589934592 -> 17179869183          : 2        |****                |
19:          17179869184 -> 34359738367          : 1        |**                  |
19:          34359738368 -> 68719476735          : 4        |********            |
19:          68719476736 -> 137438953471         : 7        |**************      |
19:         137438953472 -> 274877906943         : 9        |******************  |
19:         274877906944 -> 549755813887         : 9        |******************  |
19:         549755813888 -> 1099511627775        : 3        |******              |
19:        1099511627776 -> 2199023255551        : 10       |********************|
19:        2199023255552 -> 4398046511103        : 2        |****                |
19:        4398046511104 -> 8796093022207        : 1        |**                  |
19:        8796093022208 -> 17592186044415       : 6        |************        |
19:       17592186044416 -> 35184372088831       : 1        |**                  |
19:       35184372088832 -> 70368744177663       : 8        |****************    |
19:       70368744177664 -> 140737488355327      : 5        |**********          |
19:      140737488355328 -> 281474976710655      : 10       |********************|
19:      281474976710656 -> 562949953421311      : 3        |******              |
19:      562949953421312 -> 1125899906842623     : 8        |****************    |
19:     1125899906842624 -> 2251799813685247     : 10       |********************|
19:     2251799813685248 -> 4503599627370495     : 1        |**                  |
19:     4503599627370496 -> 9007199254740991     : 1        |**                  |
19:     9007199254740992 -> 18014398509481983    : 5        |**********          |
19:    18014398509481984 -> 36028797018963967    : 7        |**************      |
19:    36028797018963968 -> 72057594037927935    : 7        |**************      |
19:    72057594037927936 -> 144115188075855871   : 9        |******************  |
19:   144115188075855872 -> 288230376151711743   : 2        |****                |
19:   288230376151711744 -> 576460752303423487   : 1        |**                  |
19:   576460752303423488 -> 1152921504606846975  : 10       |********************|
19:  1152921504606846976 -> 2305843009213693951  : 9        |******************  |
19:  2305843009213693952 -> 4611686018427387903  : 6        |************        |
19:  4611686018427387904 -> 9223372036854775807  : 3        |******              |
19: 
19: Bucket ptr = 18446612141238900480L
19:                value                         : count     distribution
19:                    0 -> 1                    : 1        |**                  |
19:                    2 -> 3                    : 5        |**********          |
19:                    4 -> 7                    : 1        |**                  |
19:                    8 -> 15                   : 4        |********            |
19:                   16 -> 31                   : 6        |************        |
19:                   32 -> 63                   : 2        |****                |
19:                   64 -> 127                  : 6        |************        |
19:                  128 -> 255                  : 2        |****                |
19:                  256 -> 511                  : 10       |********************|
19:                  512 -> 1023                 : 3        |******              |
19:                 1024 -> 2047                 : 5        |**********          |
19:                 2048 -> 4095                 : 5        |**********          |
19:                 4096 -> 8191                 : 3        |******              |
19:                 8192 -> 16383                : 3        |******              |
19:                16384 -> 32767                : 2        |****                |
19:                32768 -> 65535                : 8        |****************    |
19:                65536 -> 131071               : 10       |********************|
19:               131072 -> 262143               : 1        |**                  |
19:               262144 -> 524287               : 9        |******************  |
19:               524288 -> 1048575              : 1        |**                  |
19:              1048576 -> 2097151              : 8        |****************    |
19:              2097152 -> 4194303              : 3        |******              |
19:              4194304 -> 8388607              : 1        |**                  |
19:              8388608 -> 16777215             : 6        |************        |
19:             16777216 -> 33554431             : 9        |******************  |
19:             33554432 -> 67108863             : 1        |**                  |
19:             67108864 -> 134217727            : 3        |******              |
19:            134217728 -> 268435455            : 2        |****                |
19:            268435456 -> 536870911            : 6        |************        |
19:            536870912 -> 1073741823           : 5        |**********          |
19:           1073741824 -> 2147483647           : 9        |******************  |
19:           2147483648 -> 4294967295           : 3        |******              |
19:           4294967296 -> 8589934591           : 4        |********            |
19:           8589934592 -> 17179869183          : 3        |******              |
19:          17179869184 -> 34359738367          : 6        |************        |
19:          34359738368 -> 68719476735          : 8        |****************    |
19:          68719476736 -> 137438953471         : 8        |****************    |
19:         137438953472 -> 274877906943         : 2        |****                |
19:         274877906944 -> 549755813887         : 4        |********            |
19:         549755813888 -> 1099511627775        : 2        |****                |
19:        1099511627776 -> 2199023255551        : 6        |************        |
19:        2199023255552 -> 4398046511103        : 3        |******              |
19:        4398046511104 -> 8796093022207        : 4        |********            |
19:        8796093022208 -> 17592186044415       : 8        |****************    |
19:       17592186044416 -> 35184372088831       : 9        |******************  |
19:       35184372088832 -> 70368744177663       : 2        |****                |
19:       70368744177664 -> 140737488355327      : 3        |******              |
19:      140737488355328 -> 281474976710655      : 3        |******              |
19:      281474976710656 -> 562949953421311      : 2        |****                |
19:      562949953421312 -> 1125899906842623     : 4        |********            |
19:     1125899906842624 -> 2251799813685247     : 9        |******************  |
19:     2251799813685248 -> 4503599627370495     : 7        |**************      |
19:     4503599627370496 -> 9007199254740991     : 3        |******              |
19:     9007199254740992 -> 18014398509481983    : 4        |********            |
19:    18014398509481984 -> 36028797018963967    : 3        |******              |
19:    36028797018963968 -> 72057594037927935    : 4        |********            |
19:    72057594037927936 -> 144115188075855871   : 1        |**                  |
19:   144115188075855872 -> 288230376151711743   : 5        |**********          |
19:   288230376151711744 -> 576460752303423487   : 3        |******              |
19:   576460752303423488 -> 1152921504606846975  : 1        |**                  |
19:  1152921504606846976 -> 2305843009213693951  : 4        |********            |
19:  2305843009213693952 -> 4611686018427387903  : 6        |************        |
19:  4611686018427387904 -> 9223372036854775807  : 4        |********            |
19:  9223372036854775808 -> 18446744073709551615 : 5        |**********          |
19: 
19: Bucket ptr = 18446612141238899840L
19:                value                         : count     distribution
19:                    0 -> 1                    : 1        |**                  |
19:                    2 -> 3                    : 5        |**********          |
19:                    4 -> 7                    : 1        |**                  |
19:                    8 -> 15                   : 4        |********            |
19:                   16 -> 31                   : 6        |************        |
19:                   32 -> 63                   : 2        |****                |
19:                   64 -> 127                  : 6        |************        |
19:                  128 -> 255                  : 2        |****                |
19:                  256 -> 511                  : 10       |********************|
19:                  512 -> 1023                 : 3        |******              |
19:                 1024 -> 2047                 : 5        |**********          |
19:                 2048 -> 4095                 : 5        |**********          |
19:                 4096 -> 8191                 : 3        |******              |
19:                 8192 -> 16383                : 3        |******              |
19:                16384 -> 32767                : 2        |****                |
19:                32768 -> 65535                : 8        |****************    |
19:                65536 -> 131071               : 10       |********************|
19:               131072 -> 262143               : 1        |**                  |
19:               262144 -> 524287               : 9        |******************  |
19:               524288 -> 1048575              : 1        |**                  |
19:              1048576 -> 2097151              : 8        |****************    |
19:              2097152 -> 4194303              : 3        |******              |
19:              4194304 -> 8388607              : 1        |**                  |
19:              8388608 -> 16777215             : 6        |************        |
19:             16777216 -> 33554431             : 9        |******************  |
19:             33554432 -> 67108863             : 1        |**                  |
19:             67108864 -> 134217727            : 3        |******              |
19:            134217728 -> 268435455            : 2        |****                |
19:            268435456 -> 536870911            : 6        |************        |
19:            536870912 -> 1073741823           : 5        |**********          |
19:           1073741824 -> 2147483647           : 9        |******************  |
19:           2147483648 -> 4294967295           : 3        |******              |
19:           4294967296 -> 8589934591           : 4        |********            |
19:           8589934592 -> 17179869183          : 3        |******              |
19:          17179869184 -> 34359738367          : 6        |************        |
19:          34359738368 -> 68719476735          : 8        |****************    |
19:          68719476736 -> 137438953471         : 8        |****************    |
19:         137438953472 -> 274877906943         : 2        |****                |
19:         274877906944 -> 549755813887         : 4        |********            |
19:         549755813888 -> 1099511627775        : 2        |****                |
19:        1099511627776 -> 2199023255551        : 6        |************        |
19:        2199023255552 -> 4398046511103        : 3        |******              |
19:        4398046511104 -> 8796093022207        : 4        |********            |
19:        8796093022208 -> 17592186044415       : 8        |****************    |
19:       17592186044416 -> 35184372088831       : 9        |******************  |
19:       35184372088832 -> 70368744177663       : 2        |****                |
19:       70368744177664 -> 140737488355327      : 3        |******              |
19:      140737488355328 -> 281474976710655      : 3        |******              |
19:      281474976710656 -> 562949953421311      : 2        |****                |
19:      562949953421312 -> 1125899906842623     : 4        |********            |
19:     1125899906842624 -> 2251799813685247     : 9        |******************  |
19:     2251799813685248 -> 4503599627370495     : 7        |**************      |
19:     4503599627370496 -> 9007199254740991     : 3        |******              |
19:     9007199254740992 -> 18014398509481983    : 4        |********            |
19:    18014398509481984 -> 36028797018963967    : 3        |******              |
19:    36028797018963968 -> 72057594037927935    : 4        |********            |
19:    72057594037927936 -> 144115188075855871   : 1        |**                  |
19:   144115188075855872 -> 288230376151711743   : 5        |**********          |
19:   288230376151711744 -> 576460752303423487   : 3        |******             .
19: ----------------------------------------------------------------------
19: Ran 4 tests in 3.485s
19: 
19: OK
19:  |
19:   576460752303423488 -> 1152921504606846975  : 1        |**                  |
19:  1152921504606846976 -> 2305843009213693951  : 4        |********            |
19:  2305843009213693952 -> 4611686018427387903  : 6        |************        |
19:  4611686018427387904 -> 9223372036854775807  : 4        |********            |
19:  9223372036854775808 -> 18446744073709551615 : 5        |**********          |
19/44 Test #19: py_test_histogram ................   Passed    3.53 sec
test 20
      Start 20: py_array

20: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_array" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_array.py"
20: Test timeout computed to be: 9.99988e+06
20: ....
20: ----------------------------------------------------------------------
20: Ran 4 tests in 1.394s
20: 
20: OK
20/44 Test #20: py_array .........................   Passed    1.44 sec
test 21
      Start 21: py_uprobes

21: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_uprobes" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_uprobes.py"
21: Test timeout computed to be: 9.99988e+06
21: .Python 2.7.12
21: .Arena 0:
21: system bytes     =   26279936
21: in use bytes     =    2954448
21: Total (incl. mmap):
21: system bytes     =   27070464
21: in use bytes     =    3744976
21: max mmap regions =          7
21: max mmap bytes   =    9203712
21: .
21: ----------------------------------------------------------------------
21: Ran 3 tests in 6.555s
21: 
21: OK
21/44 Test #21: py_uprobes .......................   Passed    6.60 sec
test 22
      Start 22: py_uprobes_2

22: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_uprobes2" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_uprobes2.py"
22: Test timeout computed to be: 9.99988e+06
22: .
22: ----------------------------------------------------------------------
22: Ran 1 test in 0.279s
22: 
22: OK
22/44 Test #22: py_uprobes_2 .....................   Passed    0.34 sec
test 23
      Start 23: py_test_stackid

23: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_stackid" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_stackid.py"
23: Test timeout computed to be: 9.99988e+06
23: ss
23: ----------------------------------------------------------------------
23: Ran 2 tests in 0.000s
23: 
23: OK (skipped=2)
23/44 Test #23: py_test_stackid ..................   Passed    0.09 sec
test 24
      Start 24: py_test_tracepoint

24: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_tracepoint" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_tracepoint.py"
24: Test timeout computed to be: 9.99988e+06
24: ss
24: ----------------------------------------------------------------------
24: Ran 2 tests in 0.000s
24: 
24: OK (skipped=2)
24/44 Test #24: py_test_tracepoint ...............   Passed    0.04 sec
test 25
      Start 25: py_test_perf_event

25: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_perf_event" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_perf_event.py"
25: Test timeout computed to be: 9.99988e+06
25: perf_event_open: No such file or directory
25: s
25: ----------------------------------------------------------------------
25: Ran 1 test in 0.338s
25: 
25: OK (skipped=1)
25/44 Test #25: py_test_perf_event ...............   Passed    0.45 sec
test 26
      Start 26: py_test_utils

26: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_utils" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_utils.py"
26: Test timeout computed to be: 9.99988e+06
26: ..
26: ----------------------------------------------------------------------
26: Ran 2 tests in 0.000s
26: 
26: OK
26/44 Test #26: py_test_utils ....................   Passed    0.04 sec
test 27
      Start 27: py_test_percpu

27: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_percpu" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_percpu.py"
27: Test timeout computed to be: 9.99988e+06
27: could not open bpf map: stub, error: Invalid argument
27: scould not open bpf map: stub, error: Invalid argument
27: scould not open bpf map: stub, error: Invalid argument
27: scould not open bpf map: stub, error: Invalid argument
27: s
27: ----------------------------------------------------------------------
27: Ran 4 tests in 0.853s
27: 
27: OK (skipped=4)
27/44 Test #27: py_test_percpu ...................   Passed    0.89 sec
test 28
      Start 28: py_test_dump_func

28: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_dump_func" "simple" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_dump_func.py"
28: Test timeout computed to be: 9.99988e+06
28: .
28: ----------------------------------------------------------------------
28: Ran 1 test in 0.114s
28: 
28: OK
28/44 Test #28: py_test_dump_func ................   Passed    0.15 sec
test 29
      Start 29: py_test_disassembler

29: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_disassembler" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_disassembler.py"
29: Test timeout computed to be: 9.99988e+06
29: ..
29: ----------------------------------------------------------------------
29: Ran 2 tests in 0.247s
29: 
29: OK
29/44 Test #29: py_test_disassembler .............   Passed    0.29 sec
test 30
      Start 30: py_test_tools_smoke

30: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_tools_smoke" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_tools_smoke.py"
30: Test timeout computed to be: 9.99988e+06
30: Traceback (most recent call last):
30:   File "../../tools/argdist.py", line 707, in run
30:     self._main_loop()
30:   File "../../tools/argdist.py", line 697, in _main_loop
30:     exit()
30:   File "/usr/lib/python2.7/site.py", line 375, in __call__
30:     raise SystemExit(code)
30: SystemExit: None
30: .....'unknown': I need something more specific.
30: .'unknown': I need something more specific.
30: 'unknown': I need something more specific.
30: 'unknown': I need something more specific.
30: 'unknown': I need something more specific.
30: .......s.s....sss....'unknown': I need something more specific.
30: ......Killed
30: .ss.ss...ssslibbpf: failed to find valid kernel BTF
30: libbpf: vmlinux BTF is not found
30: ..s.ss'unknown': I need something more specific.
30: .ss..s..s...s..'unknown': I need something more specific.
30: ...'unknown': I need something more specific.
30: .s...'unknown': I need something more specific.
30: ...libbpf: failed to find valid kernel BTF
30: libbpf: vmlinux BTF is not found
30: .s....
30: ----------------------------------------------------------------------
30: Ran 83 tests in 203.829s
30: 
30: OK (skipped=22)
30/44 Test #30: py_test_tools_smoke ..............   Passed  203.92 sec
test 31
      Start 31: py_test_tools_memleak

31: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_tools_memleak" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_tools_memleak.py"
31: Test timeout computed to be: 9.99988e+06
31: ssssssss
31: ----------------------------------------------------------------------
31: Ran 8 tests in 0.060s
31: 
31: OK (skipped=8)
31/44 Test #31: py_test_tools_memleak ............   Passed    0.09 sec
test 32
      Start 32: py_test_usdt

32: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_usdt" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_usdt.py"
32: Test timeout computed to be: 9.99988e+06
32: <stdin>: In function ‘main’:
32: <stdin>:23:17: warning: null character(s) preserved in literal
32: <stdin>:25:42: warning: format ‘%d’ expects argument of type ‘int’, but argument 4 has type ‘uint64_t {aka long unsigned int}’ [-Wformat=]
32: <stdin>:25:42: warning: format ‘%d’ expects argument of type ‘int’, but argument 4 has type ‘uint64_t {aka long unsigned int}’ [-Wformat=]
32: <stdin>:28:17: warning: null character(s) preserved in literal
32: <stdin>:30:42: warning: format ‘%d’ expects argument of type ‘int’, but argument 4 has type ‘uint64_t {aka long unsigned int}’ [-Wformat=]
32: <stdin>:30:42: warning: format ‘%d’ expects argument of type ‘int’, but argument 4 has type ‘uint64_t {aka long unsigned int}’ [-Wformat=]
32: clang -cc1 -triple x86_64-unknown-linux-gnu -emit-llvm-bc -emit-llvm-uselists -disable-free -disable-llvm-verifier -main-file-name main.c -mrelocation-model static -mthread-model posix -fmath-errno -masm-verbose -mconstructor-aliases -fuse-init-array -target-cpu x86-64 -momit-leaf-frame-pointer -dwarf-column-info -debugger-tuning=gdb -coverage-file /usr/src/linux-headers-4.4.0-116-generic/main.c -nostdsysteminc -nobuiltininc -resource-dir ../lib/clang/3.8.0 -isystem /virtual/lib/clang/include -include ./include/linux/kconfig.h -include /virtual/include/bcc/bpf.h -include /virtual/include/bcc/helpers.h -isystem /virtual/include -I /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python -D __BPF_TRACING__ -I arch/x86/include/ -I arch/x86/include/generated -I include -I arch/x86/include/uapi -I arch/x86/include/generated/uapi -I include/uapi -I include/generated/uapi -D __KERNEL__ -D __HAVE_BUILTIN_BSWAP16__ -D __HAVE_BUILTIN_BSWAP32__ -D __HAVE_BUILTIN_BSWAP64__ -D KBUILD_MODNAME="bcc" -O2 -Wno-deprecated-declarations -Wno-gnu-variable-sized-type-not-at-end -Wno-pragma-once-outside-header -Wno-address-of-packed-member -Wno-unknown-warning-option -Wno-unused-value -Wno-pointer-sign -fdebug-compilation-dir /usr/src/linux-headers-4.4.0-116-generic -ferror-limit 19 -fmessage-length 0 -fobjc-runtime=gcc -fdiagnostics-show-option -vectorize-loops -vectorize-slp -o main.bc -x c /virtual/main.c
32: #if defined(BPF_LICENSE)
32: #error BPF_LICENSE cannot be specified through cflags
32: #endif
32: #if !defined(CONFIG_CC_STACKPROTECTOR)
32: #if defined(CONFIG_CC_STACKPROTECTOR_AUTO) \
32:     || defined(CONFIG_CC_STACKPROTECTOR_REGULAR) \
32:     || defined(CONFIG_CC_STACKPROTECTOR_STRONG)
32: #define CONFIG_CC_STACKPROTECTOR
32: #endif
32: #endif
32: #define bpf_probe_read_kernel bpf_probe_read
32: #define bpf_probe_read_kernel_str bpf_probe_read_str
32: #define bpf_probe_read_user bpf_probe_read
32: #define bpf_probe_read_user_str bpf_probe_read_str
32: #include <uapi/linux/ptrace.h>
32: __attribute__((always_inline))
32: static __always_inline int _bpf_readarg_do_trace1_1(struct pt_regs *ctx, void *dest, size_t len) {
32:   if (len != sizeof(int8_t)) return -1;
32:   switch(PT_REGS_IP(ctx)) {
32:   case 0x4006e4ULL: *((int8_t *)dest) = ctx->ax; __asm__ __volatile__("": : :"memory"); return 0;
32:   case 0x4006eaULL: *((int8_t *)dest) = ctx->ax; __asm__ __volatile__("": : :"memory"); return 0;
32:   }
32:   return -1;
32: }
32: __attribute__((always_inline))
32: static __always_inline int _bpf_readarg_do_trace1_2(struct pt_regs *ctx, void *dest, size_t len) {
32:   if (len != sizeof(int32_t)) return -1;
32:   switch(PT_REGS_IP(ctx)) {
32:   case 0x4006e4ULL: { u64 __addr = ctx->bp + -204; __asm__ __volatile__("": : :"memory"); int32_t __res = 0x0; bpf_probe_read_user(&__res, sizeof(__res), (void *)__addr); *((int32_t *)dest) = __res; } return 0;
32:   case 0x4006eaULL: { u64 __addr = ctx->bp + -208; __asm__ __volatile__("": : :"memory"); int32_t __res = 0x0; bpf_probe_read_user(&__res, sizeof(__res), (void *)__addr); *((int32_t *)dest) = __res; } return 0;
32:   }
32:   return -1;
32: }
32: __attribute__((always_inline))
32: static __always_inline int _bpf_readarg_do_trace3_1(struct pt_regs *ctx, void *dest, size_t len) {
32:   if (len != sizeof(int32_t)) return -1;
32:   switch(PT_REGS_IP(ctx)) {
32:   case 0x4006e5ULL: { u64 __addr = ctx->bp + -208; __asm__ __volatile__("": : :"memory"); int32_t __res = 0x0; bpf_probe_read_user(&__res, sizeof(__res), (void *)__addr); *((int32_t *)dest) = __res; } return 0;
32:   case 0x4006f8ULL: *((int32_t *)dest) = ctx->ax; __asm__ __volatile__("": : :"memory"); return 0;
32:   }
32:   return -1;
32: }
32: __attribute__((always_inline))
32: static __always_inline int _bpf_readarg_do_trace3_2(struct pt_regs *ctx, void *dest, size_t len) {
32:   if (len != sizeof(int32_t)) return -1;
32:   switch(PT_REGS_IP(ctx)) {
32:   case 0x4006e5ULL: { u64 __addr = ctx->bp + -204; __asm__ __volatile__("": : :"memory"); int32_t __res = 0x0; bpf_probe_read_user(&__res, sizeof(__res), (void *)__addr); *((int32_t *)dest) = __res; } return 0;
32:   case 0x4006f8ULL: *((int32_t *)dest) = ctx->dx; __asm__ __volatile__("": : :"memory"); return 0;
32:   }
32:   return -1;
32: }
32: __attribute__((always_inline))
32: static __always_inline int _bpf_readarg_do_trace2_1(struct pt_regs *ctx, void *dest, size_t len) {
32:   if (len != sizeof(int32_t)) return -1;
32:   *((int32_t *)dest) = 5;
32:   return 0;
32: }
32: __attribute__((always_inline))
32: static __always_inline int _bpf_readarg_do_trace2_2(struct pt_regs *ctx, void *dest, size_t len) {
32:   if (len != sizeof(int8_t)) return -1;
32:   *((int8_t *)dest) = ctx->ax; __asm__ __volatile__("": : :"memory");
32:   return 0;
32: }
32: __attribute__((always_inline))
32: static __always_inline int _bpf_readarg_do_trace4_1(struct pt_regs *ctx, void *dest, size_t len) {
32:   if (len != sizeof(int64_t)) return -1;
32:   *((int64_t *)dest) = ctx->ax; __asm__ __volatile__("": : :"memory");
32:   return 0;
32: }
32: __attribute__((always_inline))
32: static __always_inline int _bpf_readarg_do_trace4_2(struct pt_regs *ctx, void *dest, size_t len) {
32:   if (len != sizeof(int64_t)) return -1;
32:   *((int64_t *)dest) = ctx->dx; __asm__ __volatile__("": : :"memory");
32:   return 0;
32: }
32: __attribute__((always_inline))
32: static __always_inline int _bpf_readarg_do_trace5_1(struct pt_regs *ctx, void *dest, size_t len) {
32:   if (len != sizeof(int64_t)) return -1;
32:   *((int64_t *)dest) = ctx->dx; __asm__ __volatile__("": : :"memory");
32:   return 0;
32: }
32: __attribute__((always_inline))
32: static __always_inline int _bpf_readarg_do_trace5_2(struct pt_regs *ctx, void *dest, size_t len) {
32:   if (len != sizeof(int64_t)) return -1;
32:   *((int64_t *)dest) = ctx->ax; __asm__ __volatile__("": : :"memory");
32:   return 0;
32: }
32: 
32: #include <linux/blkdev.h>
32: #include <uapi/linux/ptrace.h>
32: 
32: struct probe_result_t1 {
32:   char v1;
32:   int  v2;
32: };
32: 
32: struct probe_result_t2 {
32:   int  v1;
32:   char v2;
32: };
32: 
32: struct probe_result_t3 {
32:   int v1;
32:   int v2;
32: };
32: 
32: struct probe_result_t4 {
32:   u64  v1;
32:   char v2[8];
32: };
32: 
32: struct probe_result_t5 {
32:   char v1[8];
32:   u64  v2;
32: };
32: 
32: BPF_PERF_OUTPUT(event1);
32: BPF_PERF_OUTPUT(event2);
32: BPF_PERF_OUTPUT(event3);
32: BPF_PERF_OUTPUT(event4);
32: BPF_PERF_OUTPUT(event5);
32: 
32: __attribute__((section(".bpf.fn.do_trace1")))
32: int do_trace1(struct pt_regs *ctx) {
32: 
32:     struct probe_result_t1 result = {};
32:     _bpf_readarg_do_trace1_1(ctx, &result.v1, sizeof(*(&result.v1)));
32:     _bpf_readarg_do_trace1_2(ctx, &result.v2, sizeof(*(&result.v2)));
32:     bpf_perf_event_output(ctx, bpf_pseudo_fd(1, -1), CUR_CPU_IDENTIFIER, &result, sizeof(result));
32:     return 0;
32: };
32: __attribute__((section(".bpf.fn.do_trace2")))
32: int do_trace2(struct pt_regs *ctx) {
32: 
32:     struct probe_result_t2 result = {};
32:     _bpf_readarg_do_trace2_1(ctx, &result.v1, sizeof(*(&result.v1)));
32:     _bpf_readarg_do_trace2_2(ctx, &result.v2, sizeof(*(&result.v2)));
32:     bpf_perf_event_output(ctx, bpf_pseudo_fd(1, -2), CUR_CPU_IDENTIFIER, &result, sizeof(result));
32:     return 0;
32: }
32: __attribute__((section(".bpf.fn.do_trace3")))
32: int do_trace3(struct pt_regs *ctx) {
32: 
32:     struct probe_result_t3 result = {};
32:     _bpf_readarg_do_trace3_1(ctx, &result.v1, sizeof(*(&result.v1)));
32:     _bpf_readarg_do_trace3_2(ctx, &result.v2, sizeof(*(&result.v2)));
32:     bpf_perf_event_output(ctx, bpf_pseudo_fd(1, -3), CUR_CPU_IDENTIFIER, &result, sizeof(result));
32:     return 0;
32: }
32: __attribute__((section(".bpf.fn.do_trace4")))
32: int do_trace4(struct pt_regs *ctx) {
32: 
32:     struct probe_result_t4 result = {};
32:     _bpf_readarg_do_trace4_1(ctx, &result.v1, sizeof(*(&result.v1)));
32:     ({ u64 __addr = 0x0; _bpf_readarg_do_trace4_2(ctx, &__addr, sizeof(__addr));bpf_probe_read(&result.v2, sizeof(result.v2), (void *)__addr);});
32:     bpf_perf_event_output(ctx, bpf_pseudo_fd(1, -4), CUR_CPU_IDENTIFIER, &result, sizeof(result));
32:     return 0;
32: }
32: __attribute__((section(".bpf.fn.do_trace5")))
32: int do_trace5(struct pt_regs *ctx) {
32: 
32:     struct probe_result_t5 result = {};
32:     ({ u64 __addr = 0x0; _bpf_readarg_do_trace5_1(ctx, &__addr, sizeof(__addr));bpf_probe_read(&result.v1, sizeof(result.v1), (void *)__addr);});
32:     _bpf_readarg_do_trace5_2(ctx, &result.v2, sizeof(*(&result.v2)));
32:     bpf_perf_event_output(ctx, bpf_pseudo_fd(1, -5), CUR_CPU_IDENTIFIER, &result, sizeof(result));
32:     return 0;
32: }
32: 
32: #include <bcc/footer.h>
32: .
32: ----------------------------------------------------------------------
32: Ran 1 test in 3.047s
32: 
32: OK
32: Running from kernel directory at: /lib/modules/4.4.0-116-generic/build
32: str4
32: str5
32: str7
32: str6
32/44 Test #32: py_test_usdt .....................   Passed    3.27 sec
test 33
      Start 33: py_test_usdt2

33: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_usdt2" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_usdt2.py"
33: Test timeout computed to be: 9.99988e+06
33: .
33: ----------------------------------------------------------------------
33: Ran 1 test in 2.043s
33: 
33: OK
33/44 Test #33: py_test_usdt2 ....................   Passed    2.19 sec
test 34
      Start 34: py_test_usdt3

34: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_usdt3" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_usdt3.py"
34: Test timeout computed to be: 9.99988e+06
34: test:probe [sema 0x0]
34:   location #1 /proc/9054/root/tmp/tmpi7sNk1/liba.so 0x657
34:     argument #1 4 signed   bytes @ *(bp - 4)
34:   location #2 /proc/9054/root/tmp/tmpi7sNk1/libb.so 0x657
34:     argument #1 4 signed   bytes @ *(bp - 4)
34:   location #3 /proc/9054/root/tmp/tmpi7sNk1/a.out 0x40072d
34:     argument #1 4 signed   bytes @ *(bp - 4)
34: test_dup_name:probe [sema 0x0]
34:   location #1 /proc/9054/root/tmp/tmpi7sNk1/liba.so 0x658
34:     argument #1 4 signed   bytes @ *(bp - 4)
34:   location #2 /proc/9054/root/tmp/tmpi7sNk1/libb.so 0x658
34:     argument #1 4 signed   bytes @ *(bp - 4)
34:   location #3 /proc/9054/root/tmp/tmpi7sNk1/a.out 0x40072e
34:     argument #1 4 signed   bytes @ *(bp - 4)
34: libc:setjmp [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x351f1
34:     argument #1 8 unsigned bytes @ di
34:     argument #2 4 signed   bytes @ si
34:     argument #3 8 unsigned bytes @ ax
34: libc:longjmp [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x352d3
34:     argument #1 8 unsigned bytes @ di
34:     argument #2 4 signed   bytes @ si
34:     argument #3 8 unsigned bytes @ dx
34:   location #2 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x119063
34:     argument #1 8 unsigned bytes @ di
34:     argument #2 4 signed   bytes @ si
34:     argument #3 8 unsigned bytes @ dx
34: libc:longjmp_target [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x352ef
34:     argument #1 8 unsigned bytes @ di
34:     argument #2 4 signed   bytes @ ax
34:     argument #3 8 unsigned bytes @ dx
34:   location #2 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x11907f
34:     argument #1 8 unsigned bytes @ di
34:     argument #2 4 signed   bytes @ ax
34:     argument #3 8 unsigned bytes @ dx
34: libc:memory_heap_new [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x7d398
34:     argument #1 8 unsigned bytes @ bx
34:     argument #2 8 unsigned bytes @ bp
34: libc:memory_arena_reuse_free_list [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x7d6ec
34:     argument #1 8 unsigned bytes @ dx
34: libc:memory_sbrk_less [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x7d8de
34:     argument #1 8 unsigned bytes @ ax
34:     argument #2 8 signed   bytes @ bx
34: libc:memory_arena_reuse_wait [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x7d9eb
34:     argument #1 8 unsigned bytes @ dx
34:     argument #2 8 unsigned bytes @ dx
34:     argument #3 8 unsigned bytes @ bx
34: libc:memory_arena_reuse [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x7db14
34:     argument #1 8 unsigned bytes @ dx
34:     argument #2 8 unsigned bytes @ bx
34: libc:memory_arena_new [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x7dcbb
34:     argument #1 8 unsigned bytes @ dx
34:     argument #2 8 unsigned bytes @ bp
34: libc:memory_arena_retry [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x7deb3
34:     argument #1 8 unsigned bytes @ si
34:     argument #2 8 unsigned bytes @ di
34: libc:memory_heap_free [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x80265
34:     argument #1 8 unsigned bytes @ di
34:     argument #2 8 unsigned bytes @ ax
34: libc:memory_heap_less [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x8040e
34:     argument #1 8 unsigned bytes @ r15
34:     argument #2 8 unsigned bytes @ r14
34: libc:memory_heap_more [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x81351
34:     argument #1 8 unsigned bytes @ r8
34:     argument #2 8 unsigned bytes @ cx
34: libc:memory_sbrk_more [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x81638
34:     argument #1 8 unsigned bytes @ ax
34:     argument #2 8 signed   bytes @ r15
34: libc:memory_malloc_retry [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x84268
34:     argument #1 8 unsigned bytes @ bp
34:   location #2 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x85978
34:     argument #1 8 unsigned bytes @ bp
34: libc:memory_mallopt_free_dyn_thresholds [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x84581
34:     argument #1 8 unsigned bytes @ ax
34:     argument #2 8 unsigned bytes @ dx
34: libc:memory_realloc_retry [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x84a20
34:     argument #1 8 unsigned bytes @ r13
34:     argument #2 8 unsigned bytes @ bx
34:   location #2 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x85da0
34:     argument #1 8 unsigned bytes @ r13
34:     argument #2 8 unsigned bytes @ bx
34: libc:memory_memalign_retry [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x84c48
34:     argument #1 8 unsigned bytes @ r12
34:     argument #2 8 unsigned bytes @ bx
34:   location #2 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x85fe8
34:     argument #1 8 unsigned bytes @ r12
34:     argument #2 8 unsigned bytes @ bx
34:   location #3 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x867d8
34:     argument #1 8 unsigned bytes @ r12
34:     argument #2 8 unsigned bytes @ bx
34:   location #4 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x86ac8
34:     argument #1 8 unsigned bytes @ r12
34:     argument #2 8 unsigned bytes @ bx
34:   location #5 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x87822
34:     argument #1 8 unsigned bytes @ bx
34:     argument #2 8 unsigned bytes @ r12
34: libc:memory_calloc_retry [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x84f40
34:     argument #1 8 unsigned bytes @ bp
34: libc:memory_mallopt [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x852d4
34:     argument #1 4 signed   bytes @ bx
34:     argument #2 4 signed   bytes @ bp
34: libc:memory_mallopt_mxfast [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x85302
34:     argument #1 4 signed   bytes @ bp
34:     argument #2 8 unsigned bytes @ *(&global_max_fast + 0)
34: libc:memory_mallopt_arena_max [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x8532c
34:     argument #1 4 signed   bytes @ bp
34:     argument #2 8 unsigned bytes @ *(&mp_ + 32)
34: libc:memory_mallopt_arena_test [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x85384
34:     argument #1 4 signed   bytes @ bp
34:     argument #2 8 unsigned bytes @ *(&mp_ + 24)
34: libc:memory_mallopt_mmap_max [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x85398
34:     argument #1 4 signed   bytes @ bp
34:     argument #2 4 signed   bytes @ *(&mp_ + 44)
34:     argument #3 4 signed   bytes @ *(&mp_ + 52)
34: libc:memory_mallopt_mmap_threshold [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x853be
34:     argument #1 4 signed   bytes @ bp
34:     argument #2 8 unsigned bytes @ *(&mp_ + 16)
34:     argument #3 4 signed   bytes @ *(&mp_ + 52)
34: libc:memory_mallopt_top_pad [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x853e0
34:     argument #1 4 signed   bytes @ bp
34:     argument #2 8 unsigned bytes @ *(&mp_ + 8)
34:     argument #3 4 signed   bytes @ *(&mp_ + 52)
34: libc:memory_mallopt_trim_threshold [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x85400
34:     argument #1 4 signed   bytes @ bp
34:     argument #2 8 unsigned bytes @ *(&mp_ + 0)
34:     argument #3 4 signed   bytes @ *(&mp_ + 52)
34: libc:memory_mallopt_perturb [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x85420
34:     argument #1 4 signed   bytes @ bp
34:     argument #2 4 signed   bytes @ *(&perturb_byte + 0)
34: libc:memory_mallopt_check_action [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x85438
34:     argument #1 4 signed   bytes @ bp
34:     argument #2 4 signed   bytes @ *(&check_action + 0)
34: libc:lll_lock_wait_private [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/libc-2.23.so 0x115094
34:     argument #1 8 unsigned bytes @ di
34: rtld:init_start [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/ld-2.23.so 0x396a
34:     argument #1 4 signed   bytes @ 0
34:     argument #2 8 unsigned bytes @ r14
34: rtld:init_complete [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/ld-2.23.so 0x3f3b
34:     argument #1 4 signed   bytes @ 0
34:     argument #2 8 unsigned bytes @ bx
34: rtld:map_failed [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/ld-2.23.so 0x56f6
34:     argument #1 8 signed   bytes @ *(sp + 72)
34:     argument #2 8 unsigned bytes @ bp
34: rtld:map_start [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/ld-2.23.so 0x669a
34:     argument #1 8 signed   bytes @ *(bp + 40)
34:     argument #2 8 unsigned bytes @ *(bp - 216)
34: rtld:map_complete [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/ld-2.23.so 0x15650
34:     argument #1 8 signed   bytes @ *(ax + 40)
34:     argument #2 8 unsigned bytes @ *(bp - 120)
34:     argument #3 8 unsigned bytes @ *(bp - 104)
34: rtld:reloc_start [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/ld-2.23.so 0x1586c
34:     argument #1 8 signed   bytes @ *(r12 + 40)
34:     argument #2 8 unsigned bytes @ *(bp - 120)
34: rtld:reloc_complete [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/ld-2.23.so 0x158c9
34:     argument #1 8 signed   bytes @ *(ax + 40)
34:     argument #2 8 unsigned bytes @ *(bp - 120)
34:     argument #3 8 unsigned bytes @ *(bp - 104)
34: rtld:unmap_start [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/ld-2.23.so 0x165d5
34:     argument #1 8 signed   bytes @ *(bp - 112)
34:     argument #2 8 unsigned bytes @ *(bp - 88)
34: rtld:unmap_complete [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/ld-2.23.so 0x167e6
34:     argument #1 8 signed   bytes @ *(bp - 112)
34:     argument #2 8 unsigned bytes @ *(bp - 88)
34: rtld:setjmp [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/ld-2.23.so 0x1b64d
34:     argument #1 8 unsigned bytes @ di
34:     argument #2 4 signed   bytes @ si
34:     argument #3 8 unsigned bytes @ ax
34: rtld:longjmp [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/ld-2.23.so 0x1b68d
34:     argument #1 8 unsigned bytes @ di
34:     argument #2 4 signed   bytes @ si
34:     argument #3 8 unsigned bytes @ dx
34: rtld:longjmp_target [sema 0x0]
34:   location #1 /proc/9054/root/lib/x86_64-linux-gnu/ld-2.23.so 0x1b6a9
34:     argument #1 8 unsigned bytes @ di
34:     argument #2 4 signed   bytes @ ax
34:     argument #3 8 unsigned bytes @ dx
34: .
34: ----------------------------------------------------------------------
34: Ran 1 test in 1.112s
34: 
34: OK
34: temp directory: /tmp/tmpi7sNk1
34/44 Test #34: py_test_usdt3 ....................   Passed    1.25 sec
test 35
      Start 35: py_test_license

35: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_license" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_license.py"
35: Test timeout computed to be: 9.99988e+06
35: /virtual/main.c:2:2: error: BPF_LICENSE cannot be specified through cflags
35: #error BPF_LICENSE cannot be specified through cflags
35:  ^
35: 1 error generated.
35: x/virtual/main.c:2:2: error: BPF_LICENSE cannot be specified through cflags
35: #error BPF_LICENSE cannot be specified through cflags
35:  ^
35: 1 error generated.
35: x.bpf: Failed to load program: Invalid argument
35: 0: (bf) r6 = r1
35: 1: (85) call 5
35: cannot call GPL only function from proprietary program
35: 
35: x../virtual/main.c:2:2: error: BPF_LICENSE cannot be specified through cflags
35: #error BPF_LICENSE cannot be specified through cflags
35:  ^
35: 1 error generated.
35: xbpf: Failed to load program: Invalid argument
35: 0: (bf) r6 = r1
35: 1: (85) call 5
35: cannot call GPL only function from proprietary program
35: 
35: x..
35: ----------------------------------------------------------------------
35: Ran 10 tests in 2.447s
35: 
35: OK (expected failures=5)
35/44 Test #35: py_test_license ..................   Passed    2.49 sec
test 36
      Start 36: py_test_free_bcc_memory

36: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_free_bcc_memory" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_free_bcc_memory.py"
36: Test timeout computed to be: 9.99988e+06
36: s
36: ----------------------------------------------------------------------
36: Ran 1 test in 0.000s
36: 
36: OK (skipped=1)
36/44 Test #36: py_test_free_bcc_memory ..........   Passed    0.04 sec
test 37
      Start 37: py_test_rlimit

37: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_rlimit" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_rlimit.py"
37: Test timeout computed to be: 9.99988e+06
37: could not open bpf map: unused, error: Operation not permitted
37: .
37: ----------------------------------------------------------------------
37: Ran 1 test in 0.451s
37: 
37: OK
37/44 Test #37: py_test_rlimit ...................   Passed    0.51 sec
test 38
      Start 38: py_test_lpm_trie

38: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_test_lpm_trie" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_lpm_trie.py"
38: Test timeout computed to be: 9.99988e+06
38: ss
38: ----------------------------------------------------------------------
38: Ran 2 tests in 0.000s
38: 
38: OK (skipped=2)
38/44 Test #38: py_test_lpm_trie .................   Passed    0.05 sec
test 39
      Start 39: py_ringbuf

39: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_ringbuf" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_ringbuf.py"
39: Test timeout computed to be: 9.99988e+06
39: ssss
39: ----------------------------------------------------------------------
39: Ran 4 tests in 0.000s
39: 
39: OK (skipped=4)
39/44 Test #39: py_ringbuf .......................   Passed    0.04 sec
test 40
      Start 40: py_queuestack

40: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "py_queuestack" "sudo" "/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/python/test_queuestack.py"
40: Test timeout computed to be: 9.99988e+06
40: ss
40: ----------------------------------------------------------------------
40: Ran 2 tests in 0.000s
40: 
40: OK (skipped=2)
40/44 Test #40: py_queuestack ....................   Passed    0.04 sec
test 41
      Start 41: lua_test_clang

41: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "lua_test_clang" "sudo" "/usr/bin/luajit" "test_clang.lua"
41: Test timeout computed to be: 9.99988e+06
41: /virtual/main.c:1:30: error: expected expression
41: int failure(void *ctx) { if (); return 0; }
41:                              ^
41: 1 error generated.
41: ....................
41: Ran 20 tests in 10.511 seconds, 20 successes, 0 failures
41: OK
41/44 Test #41: lua_test_clang ...................   Passed   10.54 sec
test 42
      Start 42: lua_test_uprobes

42: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "lua_test_uprobes" "sudo" "/usr/bin/luajit" "test_uprobes.lua"
42: Test timeout computed to be: 9.99988e+06
42: Python 2.7.12
42: Arena 0:
42: system bytes     =   23207936
42: in use bytes     =    1009824
42: Total (incl. mmap):
42: system bytes     =   23207936
42: in use bytes     =    1009824
42: max mmap regions =          6
42: max mmap bytes   =    8413184
42: ..
42: Ran 2 tests in 0.464 seconds, 2 successes, 0 failures
42: OK
42/44 Test #42: lua_test_uprobes .................   Passed    0.56 sec
test 43
      Start 43: lua_test_dump

43: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/wrapper.sh "lua_test_dump" "sudo" "/usr/bin/luajit" "test_dump.lua"
43: Test timeout computed to be: 9.99988e+06
43: .
43: Ran 1 tests in 0.233 seconds, 1 successes, 0 failures
43: OK
43/44 Test #43: lua_test_dump ....................   Passed    0.25 sec
test 44
      Start 44: lua_test_standalone

44: Test command: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/lua/test_standalone.sh
44: Test timeout computed to be: 9.99988e+06
44: + cd src/lua
44: + [[ ! -x bcc-lua ]]
44: + echo 'bcc-lua not built --- skipping'
44: bcc-lua not built --- skipping
44: + exit 0
44/44 Test #44: lua_test_standalone ..............   Passed    0.00 sec

100% tests passed, 0 tests failed out of 44

Total Test time (real) = 338.87 sec
+ head -n 1 Testing/TAG
+ cp Testing/20200731-0019/Test.xml ./CTestResults.xml
Taking single-use slave ubuntu1604-slave-c1f offline.
Finished: SUCCESS