Console Output

Skipping 320 KB.. Full Log
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/trace.8.gz
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/ttysnoop.8.gz
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/ucalls.8.gz
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/uflow.8.gz
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/ugc.8.gz
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/uobjnew.8.gz
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/ustat.8.gz
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/uthreads.8.gz
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/vfscount.8.gz
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/vfsstat.8.gz
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/wakeuptime.8.gz
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/xfsdist.8.gz
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/xfsslower.8.gz
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/zfsdist.8.gz
W: bcc-tools: package-contains-timestamped-gzip usr/share/bcc/man/man8/zfsslower.8.gz
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/argdist
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/bashreadline
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/bindsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/biolatency
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/biosnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/biotop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/bitesize
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/bpflist
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/btrfsdist
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/btrfsslower
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/cachestat
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/cachetop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/capable
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/compactsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/cpudist
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/cpuunclaimed
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/criticalstat
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/dbslower
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/dbstat
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/dcsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/dcstat
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/deadlock
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/drsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/execsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/exitsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/ext4dist
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/ext4slower
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/filelife
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/fileslower
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/filetop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/funccount
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/funclatency
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/funcslower
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/gethostlatency
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/hardirqs
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/inject
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/killsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/klockstat
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/lib/ucalls
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/lib/uflow
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/lib/ugc
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/lib/uobjnew
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/lib/ustat
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/lib/uthreads
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/llcstat
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/mdflush
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/memleak
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/mountsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/mysqld_qslower
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/nfsdist
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/nfsslower
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/offcputime
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/offwaketime
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/bashreadline
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/biosnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/compactsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/filelife
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/gethostlatency
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/killsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/memleak
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/offcputime
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/offwaketime
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/oomkill
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/opensnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/profile
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/softirqs
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/stackcount
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/stacksnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/statsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/syncsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/tcpaccept
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/tcpconnect
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/old/wakeuptime
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/oomkill
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/opensnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/pidpersec
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/profile
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/runqlat
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/runqlen
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/runqslower
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/shmsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/slabratetop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/sofdsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/softirqs
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/solisten
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/sslsniff
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/stackcount
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/statsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/syncsnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/syscount
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/tcpaccept
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/tcpconnect
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/tcpconnlat
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/tcpdrop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/tcplife
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/tcpretrans
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/tcpstates
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/tcpsubnet
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/tcptop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/tcptracer
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/tplist
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/trace
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/ttysnoop
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/vfscount
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/vfsstat
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/wakeuptime
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/xfsdist
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/xfsslower
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/zfsdist
E: bcc-tools: python-script-but-no-python-dep usr/share/bcc/tools/zfsslower
Finished running lintian.
+ popd
~/jenkins/workspace/bcc-pr/label/ubuntu1604
+ cp /tmp/debuild.3T6sUW/bcc-lua_0.11.0-187.git.de1c343_all.deb /tmp/debuild.3T6sUW/bcc-tools_0.11.0-187.git.de1c343_all.deb /tmp/debuild.3T6sUW/libbcc_0.11.0-187.git.de1c343_amd64.deb /tmp/debuild.3T6sUW/libbcc-examples_0.11.0-187.git.de1c343_amd64.deb /tmp/debuild.3T6sUW/python3-bcc_0.11.0-187.git.de1c343_all.deb /tmp/debuild.3T6sUW/python-bcc_0.11.0-187.git.de1c343_all.deb .
+ cleanup
+ [[ -d /tmp/debuild.3T6sUW ]]
+ rm -rf /tmp/debuild.3T6sUW
+ sudo dpkg -i bcc-lua_0.11.0-187.git.de1c343_all.deb bcc-tools_0.11.0-187.git.de1c343_all.deb libbcc-examples_0.11.0-187.git.de1c343_amd64.deb libbcc_0.11.0-187.git.de1c343_amd64.deb python-bcc_0.11.0-187.git.de1c343_all.deb python3-bcc_0.11.0-187.git.de1c343_all.deb
Selecting previously unselected package bcc-lua.
(Reading database ... 107639 files and directories currently installed.)
Preparing to unpack bcc-lua_0.11.0-187.git.de1c343_all.deb ...
Unpacking bcc-lua (0.11.0-187.git.de1c343) ...
Selecting previously unselected package bcc-tools.
Preparing to unpack bcc-tools_0.11.0-187.git.de1c343_all.deb ...
Unpacking bcc-tools (0.11.0-187.git.de1c343) ...
Selecting previously unselected package libbcc-examples.
Preparing to unpack libbcc-examples_0.11.0-187.git.de1c343_amd64.deb ...
Unpacking libbcc-examples (0.11.0-187.git.de1c343) ...
Selecting previously unselected package libbcc.
Preparing to unpack libbcc_0.11.0-187.git.de1c343_amd64.deb ...
Unpacking libbcc (0.11.0-187.git.de1c343) ...
Selecting previously unselected package python-bcc.
Preparing to unpack python-bcc_0.11.0-187.git.de1c343_all.deb ...
Unpacking python-bcc (0.11.0-187.git.de1c343) ...
Selecting previously unselected package python3-bcc.
Preparing to unpack python3-bcc_0.11.0-187.git.de1c343_all.deb ...
Unpacking python3-bcc (0.11.0-187.git.de1c343) ...
Setting up libbcc (0.11.0-187.git.de1c343) ...
Setting up python-bcc (0.11.0-187.git.de1c343) ...
Setting up python3-bcc (0.11.0-187.git.de1c343) ...
Setting up bcc-lua (0.11.0-187.git.de1c343) ...
Setting up bcc-tools (0.11.0-187.git.de1c343) ...
Setting up libbcc-examples (0.11.0-187.git.de1c343) ...
Processing triggers for libc-bin (2.23-0ubuntu10) ...
+ mkdir upload
+ mv bcc-lua_0.11.0-187.git.de1c343_all.deb bcc-tools_0.11.0-187.git.de1c343_all.deb libbcc-examples_0.11.0-187.git.de1c343_amd64.deb libbcc_0.11.0-187.git.de1c343_amd64.deb python-bcc_0.11.0-187.git.de1c343_all.deb python3-bcc_0.11.0-187.git.de1c343_all.deb upload
[ubuntu1604] $ /bin/sh -xe /tmp/jenkins1958435554160615463.sh
+ mkdir build
+ cd build
+ [[ ubuntu1604 ubuntu1604-slave-4ed = *fc28* ]]
/tmp/jenkins1958435554160615463.sh: 4: /tmp/jenkins1958435554160615463.sh: [[: not found
+ cmake ..
-- The C compiler identification is GNU 5.4.0
-- The CXX compiler identification is GNU 5.4.0
-- Check for working C compiler: /usr/bin/cc
-- Check for working C compiler: /usr/bin/cc -- works
-- Detecting C compiler ABI info
-- Detecting C compiler ABI info - done
-- Detecting C compile features
-- Detecting C compile features - done
-- Check for working CXX compiler: /usr/bin/c++
-- Check for working CXX compiler: /usr/bin/c++ -- works
-- Detecting CXX compiler ABI info
-- Detecting CXX compiler ABI info - done
-- Detecting CXX compile features
-- Detecting CXX compile features - done
-- Latest recognized Git tag is v0.13.0
-- Git HEAD is de1c343279c6609cf199f63a910fe0dbea3e4bb2
-- Revision is 0.13.0-de1c3432
-- Performing Test HAVE_NO_PIE_FLAG
-- Performing Test HAVE_NO_PIE_FLAG - Success
-- Performing Test HAVE_REALLOCARRAY_SUPPORT
-- Performing Test HAVE_REALLOCARRAY_SUPPORT - Failed
-- Found BISON: /usr/bin/bison (found version "3.0.4") 
-- Found FLEX: /usr/bin/flex (found version "2.6.0") 
-- Found LLVM: /usr/lib/llvm-3.8/include 3.8.0
-- Found LibElf: /usr/lib/x86_64-linux-gnu/libelf.so  
-- Performing Test ELF_GETSHDRSTRNDX
-- Performing Test ELF_GETSHDRSTRNDX - Success
-- Using static-libstdc++
-- Found LuaJIT: /usr/lib/x86_64-linux-gnu/libluajit-5.1.a;/usr/lib/x86_64-linux-gnu/libdl.so;/usr/lib/x86_64-linux-gnu/libm.so  
-- Configuring done
-- Generating done
-- Build files have been written to: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build
+ make -j2 -C tests/cc
make: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build/tests/cc'
make[1]: Entering 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'
Scanning dependencies of target bcc-loader-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'
[  0%] Building CXX object src/cc/CMakeFiles/bcc-loader-static.dir/bcc_syms.cc.o
Scanning dependencies of target clang_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'
[  2%] Building CXX object src/cc/frontends/clang/CMakeFiles/clang_frontend.dir/loader.cc.o
[  5%] Building C object src/cc/CMakeFiles/bcc-loader-static.dir/bcc_elf.c.o
[  5%] Building C object src/cc/CMakeFiles/bcc-loader-static.dir/bcc_perf_map.c.o
[  7%] Building C object src/cc/CMakeFiles/bcc-loader-static.dir/bcc_proc.c.o
[  7%] Building CXX object src/cc/CMakeFiles/bcc-loader-static.dir/common.cc.o
[  7%] Linking CXX static library libbcc-loader-static.a
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[  7%] Built target bcc-loader-static
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
Scanning dependencies of target bpf-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'
[  7%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf.c.o
[ 10%] Building C object src/cc/CMakeFiles/bpf-static.dir/perf_reader.c.o
[ 10%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/libbpf_errno.c.o
[ 13%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/bpf.c.o
[ 13%] 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
[ 15%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/xsk.c.o
[ 15%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/btf_dump.c.o
[ 15%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/bpf_prog_linfo.c.o
[ 18%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/nlattr.c.o
[ 18%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/hashmap.c.o
[ 21%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/libbpf.c.o
[ 21%] Building CXX object src/cc/frontends/clang/CMakeFiles/clang_frontend.dir/b_frontend_action.cc.o
[ 21%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/btf.c.o
[ 21%] Building C object src/cc/CMakeFiles/bpf-static.dir/libbpf/src/str_error.c.o
[ 23%] Linking C static library libbcc_bpf.a
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 23%] 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'
[ 23%] Building CXX object src/cc/api/CMakeFiles/api-static.dir/BPF.cc.o
[ 26%] Building CXX object src/cc/api/CMakeFiles/api-static.dir/BPFTable.cc.o
[ 26%] Linking CXX static library libapi-static.a
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 26%] 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'
[ 26%] Building CXX object src/cc/usdt/CMakeFiles/usdt-static.dir/usdt_args.cc.o
[ 26%] Building CXX object src/cc/usdt/CMakeFiles/usdt-static.dir/usdt.cc.o
[ 28%] Linking CXX static library libusdt-static.a
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 28%] Built target usdt-static
make[2]: Entering directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 31%] [FLEX][Lexer] Building scanner with flex 2.6.0
lexer.ll:110: warning, -s option given but default rule can be matched
[ 31%] [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'
[ 31%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/loader.cc.o
[ 31%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/codegen_llvm.cc.o
[ 34%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/node.cc.o
[ 34%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/parser.cc.o
[ 36%] Building CXX object src/cc/frontends/clang/CMakeFiles/clang_frontend.dir/tp_frontend_action.cc.o
[ 39%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/printer.cc.o
[ 39%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/type_check.cc.o
[ 39%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/parser.yy.cc.o
[ 39%] Building CXX object src/cc/frontends/clang/CMakeFiles/clang_frontend.dir/kbuild_helper.cc.o
[ 42%] Building CXX object src/cc/frontends/b/CMakeFiles/b_frontend.dir/lexer.ll.cc.o
[ 42%] Building CXX object src/cc/frontends/clang/CMakeFiles/clang_frontend.dir/__/__/common.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) {
                                            ^
[ 42%] Linking CXX static library libb_frontend.a
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 42%] Built target b_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'
[ 42%] Building C object tests/cc/CMakeFiles/usdt_test_lib.dir/usdt_test_lib.c.o
[ 44%] Linking CXX static library libclang_frontend.a
[ 47%] Linking C shared library libusdt_test_lib.so
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 47%] Built target clang_frontend
[ 47%] Built target usdt_test_lib
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
[ 47%] 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
[ 52%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/bpf_module.cc.o
[ 52%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/bpf_module.cc.o
[ 52%] 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
[ 52%] 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
[ 57%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/bpf_module_rw_engine.cc.o
[ 57%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/bpf_module_rw_engine.cc.o
[ 57%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/table_storage.cc.o
[ 57%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/table_storage.cc.o
[ 57%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/shared_table.cc.o
[ 60%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/shared_table.cc.o
[ 63%] 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
[ 63%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/json_map_decl_visitor.cc.o
[ 65%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/json_map_decl_visitor.cc.o
[ 65%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/common.cc.o
[ 65%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/bcc_syms.cc.o
[ 68%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/usdt/usdt.cc.o
[ 68%] Building C object src/cc/CMakeFiles/bcc-shared.dir/bcc_elf.c.o
[ 71%] Building C object src/cc/CMakeFiles/bcc-shared.dir/bcc_perf_map.c.o
[ 71%] Building C object src/cc/CMakeFiles/bcc-shared.dir/bcc_proc.c.o
[ 71%] Building CXX object src/cc/CMakeFiles/bcc-shared.dir/common.cc.o
[ 71%] Building CXX object src/cc/CMakeFiles/bcc-static.dir/usdt/usdt_args.cc.o
[ 73%] Linking CXX shared library libbcc.so
[ 76%] 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'
[ 76%] 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'
[ 78%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_libbcc.cc.o
[ 78%] Building C object src/cc/CMakeFiles/bcc-static.dir/bcc_elf.c.o
[ 78%] Building C object src/cc/CMakeFiles/bcc-static.dir/bcc_perf_map.c.o
[ 81%] Building C object src/cc/CMakeFiles/bcc-static.dir/bcc_proc.c.o
[ 81%] Linking CXX static library libbcc.a
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 81%] 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'
[ 81%] Building C object tests/cc/CMakeFiles/test_static.dir/test_static.c.o
[ 84%] Linking CXX executable test_static
make[2]: Leaving directory '/home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/build'
[ 84%] Built target test_static
[ 84%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_c_api.cc.o
[ 84%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_array_table.cc.o
[ 86%] 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
[ 89%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_map_in_map.cc.o
[ 89%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_perf_event.cc.o
[ 92%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_pinned_table.cc.o
[ 92%] Building CXX object tests/cc/CMakeFiles/test_libbcc.dir/test_prog_table.cc.o
[ 92%] 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: 20200320-0522 - 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/xfsslower.bpf.c b/libbpf-tools/xfsslower.bpf.c
1: index a73a2dc..0e66c66 100644
1: --- a/libbpf-tools/xfsslower.bpf.c
1: +++ b/libbpf-tools/xfsslower.bpf.c
1: @@ -1,160 +1,148 @@
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 "xfsslower.h"
1:  #include <bpf/bpf_core_read.h>
1: +#include <bpf/bpf_helpers.h>
1:  #include <bpf/bpf_tracing.h>
1: -#include "xfsslower.h"
1: +#include "vmlinux.h"
1:  
1: -#define NULL    0
1: +#define NULL 0
1:  
1:  const volatile pid_t targ_tgid = 0;
1:  const volatile __u64 min_lat = 0;
1:  
1:  struct piddata {
1: -	u64 ts;
1: -	loff_t start;
1: -	loff_t end;
1: -	struct file *fp;
1: +  u64 ts;
1: +  loff_t start;
1: +  loff_t end;
1: +  struct file *fp;
1:  };
1:  
1:  struct {
1: -	__uint(type, BPF_MAP_TYPE_HASH);
1: -	__uint(max_entries, 8192);
1: -	__type(key, u32);
1: -	__type(value, struct piddata);
1: +  __uint(type, BPF_MAP_TYPE_HASH);
1: +  __uint(max_entries, 8192);
1: +  __type(key, u32);
1: +  __type(value, struct piddata);
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 int
1: -probe_entry(struct file *fp, loff_t s, loff_t e)
1: -{
1: -	u64 id = bpf_get_current_pid_tgid();
1: -	struct piddata piddata;
1: -	u32 tgid = id >> 32;
1: -	u32 pid = id;
1: -
1: -	if (!fp)
1: -		return 0;
1: -	if (targ_tgid && targ_tgid != tgid)
1: -		return 0;
1: -
1: -	piddata.ts = bpf_ktime_get_ns();
1: -	piddata.start = s;
1: -	piddata.end = e;
1: -	piddata.fp = fp;
1: -	bpf_map_update_elem(&start, &pid, &piddata, 0);
1: -	return 0;
1: +static __always_inline int probe_entry(struct file *fp, loff_t s, loff_t e) {
1: +  u64 id = bpf_get_current_pid_tgid();
1: +  struct piddata piddata;
1: +  u32 tgid = id >> 32;
1: +  u32 pid = id;
1: +
1: +  if (!fp)
1: +    return 0;
1: +  if (targ_tgid && targ_tgid != tgid)
1: +    return 0;
1: +
1: +  piddata.ts = bpf_ktime_get_ns();
1: +  piddata.start = s;
1: +  piddata.end = e;
1: +  piddata.fp = fp;
1: +  bpf_map_update_elem(&start, &pid, &piddata, 0);
1: +  return 0;
1:  }
1:  
1:  SEC("kprobe/xfs_file_read_iter")
1: -int BPF_KPROBE(kprobe__xfs_file_read_iter, struct kiocb *iocb)
1: -{
1: -	struct file *fp = BPF_CORE_READ(iocb, ki_filp);
1: -	loff_t start = BPF_CORE_READ(iocb, ki_pos);
1: +int BPF_KPROBE(kprobe__xfs_file_read_iter, struct kiocb *iocb) {
1: +  struct file *fp = BPF_CORE_READ(iocb, ki_filp);
1: +  loff_t start = BPF_CORE_READ(iocb, ki_pos);
1:  
1: -	return probe_entry(fp, start, 0);
1: +  return probe_entry(fp, start, 0);
1:  }
1:  
1:  SEC("kprobe/xfs_file_write_iter")
1: -int BPF_KPROBE(kprobe__xfs_file_write_iter, struct kiocb *iocb)
1: -{
1: -	struct file *fp = BPF_CORE_READ(iocb, ki_filp);
1: -	loff_t start = BPF_CORE_READ(iocb, ki_pos);
1: +int BPF_KPROBE(kprobe__xfs_file_write_iter, struct kiocb *iocb) {
1: +  struct file *fp = BPF_CORE_READ(iocb, ki_filp);
1: +  loff_t start = BPF_CORE_READ(iocb, ki_pos);
1:  
1: -	return probe_entry(fp, start, 0);
1: +  return probe_entry(fp, start, 0);
1:  }
1:  
1:  SEC("kprobe/xfs_file_open")
1: -int BPF_KPROBE(kprobe__xfs_file_open, struct inode *inode, struct file *file)
1: -{
1: -	return probe_entry(file, 0, 0);
1: +int BPF_KPROBE(kprobe__xfs_file_open, struct inode *inode, struct file *file) {
1: +  return probe_entry(file, 0, 0);
1:  }
1:  
1:  SEC("kprobe/xfs_file_fsync")
1:  int BPF_KPROBE(kprobe__xfs_file_fsync, struct file *file, loff_t start,
1: -	       loff_t end)
1: -{
1: -	return probe_entry(file, start, end);
1: +               loff_t end) {
1: +  return probe_entry(file, start, end);
1:  }
1:  
1: -static __always_inline int
1: -probe_exit(struct pt_regs *ctx, char type, ssize_t size)
1: -{
1: -	u64 id = bpf_get_current_pid_tgid();
1: -	u64 end_ns = bpf_ktime_get_ns();
1: -	struct piddata *piddatap;
1: -	struct event event = {};
1: -	struct dentry *dentry;
1: -	const u8 *qs_name_ptr;
1: -	u32 tgid = id >> 32;
1: -	struct file *fp;
1: -	u32 pid = id;
1: -	u64 delta_us;
1: -	u32 qs_len;
1: -
1: -	if (targ_tgid && targ_tgid != tgid)
1: -		return 0;
1: -
1: -	piddatap = bpf_map_lookup_elem(&start, &pid);
1: -	if (!piddatap)
1: -		return 0;    /* missed entry */
1: -
1: -	delta_us = (end_ns - piddatap->ts) / 1000;
1: -	bpf_map_delete_elem(&start, &pid);
1: -
1: -	if ((s64)delta_us < 0 || delta_us <= min_lat * 1000)
1: -		return 0;
1: -
1: -	fp = piddatap->fp;
1: -	dentry = BPF_CORE_READ(fp, f_path.dentry);
1: -	qs_len = BPF_CORE_READ(dentry, d_name.len);
1: -	qs_name_ptr = BPF_CORE_READ(dentry, d_name.name);
1: -	bpf_probe_read_str(&event.file, sizeof(event.file), qs_name_ptr);
1: -	bpf_get_current_comm(&event.task, sizeof(event.task));
1: -	event.delta_us = delta_us;
1: -	event.end_ns = end_ns;
1: -	event.offset = piddatap->start;
1: -	if (type != TRACE_FSYNC)
1: -		event.size = size;
1: -	else
1: -		event.size = piddatap->end - piddatap->start;
1: -	event.type = type;
1: -	event.tgid = tgid;
1: -
1: -	/* output */
1: -	bpf_perf_event_output(ctx, &events, BPF_F_CURRENT_CPU,
1: -			      &event, sizeof(event));
1: -	return 0;
1: +static __always_inline int probe_exit(struct pt_regs *ctx, char type,
1: +                                      ssize_t size) {
1: +  u64 id = bpf_get_current_pid_tgid();
1: +  u64 end_ns = bpf_ktime_get_ns();
1: +  struct piddata *piddatap;
1: +  struct event event = {};
1: +  struct dentry *dentry;
1: +  const u8 *qs_name_ptr;
1: +  u32 tgid = id >> 32;
1: +  struct file *fp;
1: +  u32 pid = id;
1: +  u64 delta_us;
1: +  u32 qs_len;
1: +
1: +  if (targ_tgid && targ_tgid != tgid)
1: +    return 0;
1: +
1: +  piddatap = bpf_map_lookup_elem(&start, &pid);
1: +  if (!piddatap)
1: +    return 0; /* missed entry */
1: +
1: +  delta_us = (end_ns - piddatap->ts) / 1000;
1: +  bpf_map_delete_elem(&start, &pid);
1: +
1: +  if ((s64)delta_us < 0 || delta_us <= min_lat * 1000)
1: +    return 0;
1: +
1: +  fp = piddatap->fp;
1: +  dentry = BPF_CORE_READ(fp, f_path.dentry);
1: +  qs_len = BPF_CORE_READ(dentry, d_name.len);
1: +  qs_name_ptr = BPF_CORE_READ(dentry, d_name.name);
1: +  bpf_probe_read_str(&event.file, sizeof(event.file), qs_name_ptr);
1: +  bpf_get_current_comm(&event.task, sizeof(event.task));
1: +  event.delta_us = delta_us;
1: +  event.end_ns = end_ns;
1: +  event.offset = piddatap->start;
1: +  if (type != TRACE_FSYNC)
1: +    event.size = size;
1: +  else
1: +    event.size = piddatap->end - piddatap->start;
1: +  event.type = type;
1: +  event.tgid = tgid;
1: +
1: +  /* output */
1: +  bpf_perf_event_output(ctx, &events, BPF_F_CURRENT_CPU, &event, sizeof(event));
1: +  return 0;
1:  }
1:  
1:  SEC("kretprobe/xfs_file_read_iter")
1: -int BPF_KRETPROBE(kretprobe__xfs_file_read_iters, ssize_t ret)
1: -{
1: -	return probe_exit(ctx, TRACE_READ, ret);
1: +int BPF_KRETPROBE(kretprobe__xfs_file_read_iters, ssize_t ret) {
1: +  return probe_exit(ctx, TRACE_READ, ret);
1:  }
1:  
1:  SEC("kretprobe/xfs_file_write_iter")
1: -int BPF_KRETPROBE(kretprobe__xfs_file_write_iter, ssize_t ret)
1: -{
1: -	return probe_exit(ctx, TRACE_WRITE, ret);
1: +int BPF_KRETPROBE(kretprobe__xfs_file_write_iter, ssize_t ret) {
1: +  return probe_exit(ctx, TRACE_WRITE, ret);
1:  }
1:  
1:  SEC("kretprobe/xfs_file_open")
1: -int BPF_KRETPROBE(kretprobe__xfs_file_open)
1: -{
1: -	return probe_exit(ctx, TRACE_OPEN, 0);
1: +int BPF_KRETPROBE(kretprobe__xfs_file_open) {
1: +  return probe_exit(ctx, TRACE_OPEN, 0);
1:  }
1:  
1:  SEC("kretprobe/xfs_file_fsync")
1: -int BPF_KRETPROBE(kretprobe__xfs_file_sync)
1: -{
1: -	return probe_exit(ctx, TRACE_FSYNC, 0);
1: +int BPF_KRETPROBE(kretprobe__xfs_file_sync) {
1: +  return probe_exit(ctx, TRACE_FSYNC, 0);
1:  }
1:  
1:  char LICENSE[] SEC("license") = "GPL";
1: diff --git a/libbpf-tools/xfsslower.c b/libbpf-tools/xfsslower.c
1: index b525eff..9416e50 100644
1: --- a/libbpf-tools/xfsslower.c
1: +++ b/libbpf-tools/xfsslower.c
1: @@ -3,260 +3,248 @@
1:  //
1:  // Based on xfsslower(8) from BCC by Brendan Gregg & Dina Goldshtein.
1:  // 9-Mar-2020   Wenbo Zhang   Created this.
1: +#include "xfsslower.h"
1:  #include <argp.h>
1: +#include <bpf/bpf.h>
1: +#include <bpf/libbpf.h>
1:  #include <limits.h>
1:  #include <stdint.h>
1:  #include <stdio.h>
1:  #include <stdlib.h>
1:  #include <string.h>
1:  #include <sys/resource.h>
1: -#include <unistd.h>
1:  #include <time.h>
1: -#include <bpf/libbpf.h>
1: -#include <bpf/bpf.h>
1: -#include "xfsslower.h"
1: -#include "xfsslower.skel.h"
1: +#include <unistd.h>
1:  #include "trace_helpers.h"
1: +#include "xfsslower.skel.h"
1:  
1: -#define PERF_BUFFER_PAGES	64
1: -#define PERF_BUFFER_TIME_MS	10
1: -#define PERF_POLL_TIMEOUT_MS	100
1: +#define PERF_BUFFER_PAGES 64
1: +#define PERF_BUFFER_TIME_MS 10
1: +#define PERF_POLL_TIMEOUT_MS 100
1:  
1: -#define NSEC_PER_SEC		1000000000ULL
1: +#define NSEC_PER_SEC 1000000000ULL
1:  
1:  static struct env {
1: -	pid_t pid;
1: -	time_t duration;
1: -	__u64 min_lat;
1: -	bool csv;
1: -	bool verbose;
1: +  pid_t pid;
1: +  time_t duration;
1: +  __u64 min_lat;
1: +  bool csv;
1: +  bool verbose;
1:  } env = {
1: -	.min_lat = 10000,
1: +    .min_lat = 10000,
1:  };
1:  
1:  const char *argp_program_version = "xfsslower 0.1";
1:  const char *argp_program_bug_address = "<ethercflow@gmail.com>";
1:  const char argp_program_doc[] =
1: -"Trace common XFS file operations slower than a threshold.\n"
1: -"\n"
1: -"Usage: xfslower [--help] [-p PID] [-m MIN] [-d DURATION] [-c]\n"
1: -"\n"
1: -"EXAMPLES:\n"
1: -"    xfsslower          # trace operations slower than 10 ms (default)"
1: -"    xfsslower 0        # trace all operations (warning: verbose)\n"
1: -"    xfsslower -p 123   # trace pid 123\n"
1: -"    xfsslower -c -d 1  # ... 1s, parsable output (csv)";
1: +    "Trace common XFS file operations slower than a threshold.\n"
1: +    "\n"
1: +    "Usage: xfslower [--help] [-p PID] [-m MIN] [-d DURATION] [-c]\n"
1: +    "\n"
1: +    "EXAMPLES:\n"
1: +    "    xfsslower          # trace operations slower than 10 ms (default)"
1: +    "    xfsslower 0        # trace all operations (warning: verbose)\n"
1: +    "    xfsslower -p 123   # trace pid 123\n"
1: +    "    xfsslower -c -d 1  # ... 1s, parsable output (csv)";
1:  
1:  static const struct argp_option opts[] = {
1: -	{ "csv", 'c', NULL, 0, "Output as csv" },
1: -	{ "duration", 'd', "DURATION", 0, "Total duration of trace in seconds" },
1: -	{ NULL, 'h', NULL, OPTION_HIDDEN, "Show the full help"},
1: -	{ "pid", 'p', "PID", 0, "Process PID to trace" },
1: -	{ "min", 'm', "MIN", 0, "Min latency of trace in ms (default 10)" },
1: -	{ "verbose", 'v', NULL, 0, "Verbose debug output" },
1: -	{},
1: +    {"csv", 'c', NULL, 0, "Output as csv"},
1: +    {"duration", 'd', "DURATION", 0, "Total duration of trace in seconds"},
1: +    {NULL, 'h', NULL, OPTION_HIDDEN, "Show the full help"},
1: +    {"pid", 'p', "PID", 0, "Process PID to trace"},
1: +    {"min", 'm', "MIN", 0, "Min latency of trace in ms (default 10)"},
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: -	long long min_lat;
1: -	time_t duration;
1: -	int pid;
1: -
1: -	switch (key) {
1: -	case 'v':
1: -		env.verbose = true;
1: -		break;
1: -	case 'h':
1: -		argp_usage(state);
1: -		break;
1: -	case 'c':
1: -		env.csv = true;
1: -		break;
1: -	case 'd':
1: -		errno = 0;
1: -		duration = strtol(arg, NULL, 10);
1: -		if (errno || duration <= 0) {
1: -			fprintf(stderr, "invalid DURATION: %s\n", arg);
1: -			argp_usage(state);
1: -		}
1: -		env.duration = duration;
1: -		break;
1: -	case 'm':
1: -		errno = 0;
1: -		min_lat = strtoll(arg, NULL, 10);
1: -		if (errno || min_lat < 0) {
1: -			fprintf(stderr, "invalid delay (in ms): %s\n", arg);
1: -		}
1: -		env.min_lat = min_lat;
1: -		break;
1: -	case 'p':
1: -		errno = 0;
1: -		pid = strtol(arg, NULL, 10);
1: -		if (errno || pid <= 0) {
1: -			fprintf(stderr, "invalid PID: %s\n", arg);
1: -			argp_usage(state);
1: -		}
1: -		env.pid = pid;
1: -		break;
1: -	default:
1: -		return ARGP_ERR_UNKNOWN;
1: -	}
1: -	return 0;
1: +static error_t parse_arg(int key, char *arg, struct argp_state *state) {
1: +  long long min_lat;
1: +  time_t duration;
1: +  int pid;
1: +
1: +  switch (key) {
1: +  case 'v':
1: +    env.verbose = true;
1: +    break;
1: +  case 'h':
1: +    argp_usage(state);
1: +    break;
1: +  case 'c':
1: +    env.csv = true;
1: +    break;
1: +  case 'd':
1: +    errno = 0;
1: +    duration = strtol(arg, NULL, 10);
1: +    if (errno || duration <= 0) {
1: +      fprintf(stderr, "invalid DURATION: %s\n", arg);
1: +      argp_usage(state);
1: +    }
1: +    env.duration = duration;
1: +    break;
1: +  case 'm':
1: +    errno = 0;
1: +    min_lat = strtoll(arg, NULL, 10);
1: +    if (errno || min_lat < 0) {
1: +      fprintf(stderr, "invalid delay (in ms): %s\n", arg);
1: +    }
1: +    env.min_lat = min_lat;
1: +    break;
1: +  case 'p':
1: +    errno = 0;
1: +    pid = strtol(arg, NULL, 10);
1: +    if (errno || pid <= 0) {
1: +      fprintf(stderr, "invalid PID: %s\n", arg);
1: +      argp_usage(state);
1: +    }
1: +    env.pid = pid;
1: +    break;
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 int bump_memlock_rlimit(void)
1: -{
1: -	struct rlimit rlim_new = {
1: -		.rlim_cur	= RLIM_INFINITY,
1: -		.rlim_max	= RLIM_INFINITY,
1: -	};
1: +static int bump_memlock_rlimit(void) {
1: +  struct rlimit rlim_new = {
1: +      .rlim_cur = RLIM_INFINITY, .rlim_max = RLIM_INFINITY,
1: +  };
1:  
1: -	return setrlimit(RLIMIT_MEMLOCK, &rlim_new);
1: +  return setrlimit(RLIMIT_MEMLOCK, &rlim_new);
1:  }
1:  
1: -void handle_event(void *ctx, int cpu, void *data, __u32 data_sz)
1: -{
1: -	const struct event *e = data;
1: -	struct tm *tm;
1: -	char ts[32];
1: -	time_t t;
1: -
1: -	time(&t);
1: -	tm = localtime(&t);
1: -	strftime(ts, sizeof(ts), "%H:%M:%S", tm);
1: -
1: -	if (env.csv) {
1: -		printf("%lld,%s,%d,%c,", e->end_ns, e->task, e->tgid, e->type);
1: -		if (e->size == LLONG_MAX)
1: -			printf("LL_MAX,");
1: -		else
1: -			printf("%ld,", e->size);
1: -		printf("%lld,%lld,%s\n", e->offset, e->delta_us, e->file);
1: -	} else {
1: -		printf("%-8s %-14.14s %-6d %c ", ts, e->task, e->tgid, e->type);
1: -		if (e->size == LLONG_MAX)
1: -			printf("%-7s ", "LL_MAX");
1: -		else
1: -			printf("%-7ld ", e->size);
1: -		printf("%-8lld %7.2f %s\n", e->offset / 1024,
1: -		       (double)e->delta_us / 1000, e->file);
1: -	}
1: +void handle_event(void *ctx, int cpu, void *data, __u32 data_sz) {
1: +  const struct event *e = data;
1: +  struct tm *tm;
1: +  char ts[32];
1: +  time_t t;
1: +
1: +  time(&t);
1: +  tm = localtime(&t);
1: +  strftime(ts, sizeof(ts), "%H:%M:%S", tm);
1: +
1: +  if (env.csv) {
1: +    printf("%lld,%s,%d,%c,", e->end_ns, e->task, e->tgid, e->type);
1: +    if (e->size == LLONG_MAX)
1: +      printf("LL_MAX,");
1: +    else
1: +      printf("%ld,", e->size);
1: +    printf("%lld,%lld,%s\n", e->offset, e->delta_us, e->file);
1: +  } else {
1: +    printf("%-8s %-14.14s %-6d %c ", ts, e->task, e->tgid, e->type);
1: +    if (e->size == LLONG_MAX)
1: +      printf("%-7s ", "LL_MAX");
1: +    else
1: +      printf("%-7ld ", e->size);
1: +    printf("%-8lld %7.2f %s\n", e->offset / 1024, (double)e->delta_us / 1000,
1: +           e->file);
1: +  }
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: -uint64_t get_ktime_ns(void)
1: -{
1: -	struct timespec ts;
1: +uint64_t get_ktime_ns(void) {
1: +  struct timespec ts;
1:  
1: -	clock_gettime(CLOCK_MONOTONIC, &ts);
1: -	return ts.tv_sec * NSEC_PER_SEC + ts.tv_nsec;
1: +  clock_gettime(CLOCK_MONOTONIC, &ts);
1: +  return ts.tv_sec * NSEC_PER_SEC + ts.tv_nsec;
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 xfsslower_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: -
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: -
1: -	obj = xfsslower_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: -	obj->rodata->min_lat = env.min_lat;
1: -	obj->rodata->targ_tgid = env.pid;
1: -
1: -	err = xfsslower_bpf__load(obj);
1: -	if (err) {
1: -		fprintf(stderr, "failed to load BPF object: %d\n", err);
1: -		goto cleanup;
1: -	}
1: -
1: -	err = xfsslower_bpf__attach(obj);
1: -	if (err) {
1: -		fprintf(stderr, "failed to attach BPF programs\n");
1: -		goto cleanup;
1: -	}
1: -
1: -	if (env.csv)
1: -		printf("ENDTIME_us,TASK,PID,TYPE,BYTES,OFFSET_b,LATENCY_us,FILE");
1: -	else {
1: -		if (env.min_lat)
1: -			printf("Tracing XFS operations slower than %llu ms",
1: -				env.min_lat);
1: -		else
1: -			printf("Tracing XFS operations");
1: -		if (env.duration)
1: -			printf(" for %ld secs.\n", env.duration);
1: -		else
1: -			printf("... Hit Ctrl-C to end.\n");
1: -		printf("%-8s %-14s %-6s %1s %-7s %-8s %7s %s",
1: -			"TIME", "COMM", "PID", "T", "BYTES", "OFF_KB", "LAT(ms)",
1: -			"FILENAME\n");
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: -
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: -		usleep(PERF_BUFFER_TIME_MS * 1000);
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: -	fprintf(stderr, "failed with polling perf buffer: %d\n", 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 xfsslower_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: +
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: +
1: +  obj = xfsslower_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: +  obj->rodata->min_lat = env.min_lat;
1: +  obj->rodata->targ_tgid = env.pid;
1: +
1: +  err = xfsslower_bpf__load(obj);
1: +  if (err) {
1: +    fprintf(stderr, "failed to load BPF object: %d\n", err);
1: +    goto cleanup;
1: +  }
1: +
1: +  err = xfsslower_bpf__attach(obj);
1: +  if (err) {
1: +    fprintf(stderr, "failed to attach BPF programs\n");
1: +    goto cleanup;
1: +  }
1: +
1: +  if (env.csv)
1: +    printf("ENDTIME_us,TASK,PID,TYPE,BYTES,OFFSET_b,LATENCY_us,FILE");
1: +  else {
1: +    if (env.min_lat)
1: +      printf("Tracing XFS operations slower than %llu ms", env.min_lat);
1: +    else
1: +      printf("Tracing XFS operations");
1: +    if (env.duration)
1: +      printf(" for %ld secs.\n", env.duration);
1: +    else
1: +      printf("... Hit Ctrl-C to end.\n");
1: +    printf("%-8s %-14s %-6s %1s %-7s %-8s %7s %s", "TIME", "COMM", "PID", "T",
1: +           "BYTES", "OFF_KB", "LAT(ms)", "FILENAME\n");
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: +
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: +    usleep(PERF_BUFFER_TIME_MS * 1000);
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: +  fprintf(stderr, "failed with polling perf buffer: %d\n", err);
1:  
1:  cleanup:
1: -	perf_buffer__free(pb);
1: -	xfsslower_bpf__destroy(obj);
1: +  perf_buffer__free(pb);
1: +  xfsslower_bpf__destroy(obj);
1:  
1: -	return err != 0;
1: +  return err != 0;
1:  }
1: diff --git a/libbpf-tools/xfsslower.h b/libbpf-tools/xfsslower.h
1: index 76860fe..6cdf71c 100644
1: --- a/libbpf-tools/xfsslower.h
1: +++ b/libbpf-tools/xfsslower.h
1: @@ -3,22 +3,22 @@
1:  #define __XFSSLOWER_H
1:  
1:  #define DNAME_INLINE_LEN 32
1: -#define TASK_COMM_LEN    16
1: +#define TASK_COMM_LEN 16
1:  
1: -#define TRACE_READ   'R'
1: -#define TRACE_WRITE  'W'
1: -#define TRACE_OPEN   'O'
1: -#define TRACE_FSYNC  'F'
1: +#define TRACE_READ 'R'
1: +#define TRACE_WRITE 'W'
1: +#define TRACE_OPEN 'O'
1: +#define TRACE_FSYNC 'F'
1:  
1:  struct event {
1: -	char file[DNAME_INLINE_LEN];
1: -	char task[TASK_COMM_LEN];
1: -	__u64 delta_us;
1: -	__u64 end_ns;
1: -	__s64 offset;
1: -	ssize_t size;
1: -	pid_t tgid;
1: -	char type;
1: +  char file[DNAME_INLINE_LEN];
1: +  char task[TASK_COMM_LEN];
1: +  __u64 delta_us;
1: +  __u64 end_ns;
1: +  __s64 offset;
1: +  ssize_t size;
1: +  pid_t tgid;
1: +  char type;
1:  };
1:  
1:  #endif /* __DRSNOOP_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/xfsslower.c
1:     libbpf-tools/xfsslower.bpf.c
1:     libbpf-tools/xfsslower.h
1: old tree: e1aa62c1ecffc1e261ba7ab89b10edab09ce7ed7
1: new tree: 7aa8eaa909b88f152b7e0aa23996f8223d444841
 1/40 Test  #1: style-check ......................   Passed    2.17 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/uapi -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__ -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: 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/40 Test  #2: c_test_static ....................   Passed    1.42 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: 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:316
3: ...............................................................................
3: 
3: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/cc/test_usdt_probes.cc:331: FAILED:
3:   REQUIRE( res.msg() == "" )
3: with expansion:
3:   "Unable to load USDT ruby:gc__mark__begin from binary  PID 7417 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:316
3: ...............................................................................
3: 
3: /home/iovisor/jenkins/workspace/bcc-pr/label/ubuntu1604/tests/cc/test_usdt_probes.cc:357: FAILED:
3:   REQUIRE( res.msg() == "" )
3: with expansion:
3:   "Unable to load USDT ruby:gc__mark__begin from binary  PID 32765 for probe
3:   on_event"
3:   ==
3:   ""
3: 
3: ===============================================================================
3: test cases:  32 |  31 passed | 1 failed as expected
3: assertions: 499 | 497 passed | 2 failed as expected
3: 
 3/40 Test  #3: test_libbcc ......................   Passed    9.16 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 2ms
4: rtt min/avg/max/mdev = 0.005/0.008/0.120/0.012 ms, ipg/ewma 0.021/0.006 ms
4: .
4: ----------------------------------------------------------------------
4: Ran 2 tests in 0.328s
4: 
4: OK
 4/40 Test  #4: py_test_stat1_b ..................   Passed    1.39 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/40 Test  #5: py_test_bpf_log ..................   Passed    1.99 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.004/0.006/0.062/0.006 ms, ipg/ewma 0.018/0.006 ms
6: .
6: ----------------------------------------------------------------------
6: Ran 2 tests in 0.290s
6: 
6: OK
 6/40 Test  #6: py_test_stat1_c ..................   Passed    0.63 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.105 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.105/0.105/0.105/0.000 ms
7: .
7: ----------------------------------------------------------------------
7: Ran 1 test in 0.237s
7: 
7: OK
 7/40 Test  #7: py_test_xlate1_c .................   Passed    0.64 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.186s
8: 
8: OK
 8/40 Test  #8: py_test_call1 ....................   Passed    0.60 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.276s
9: 
9: OK
9: ('fd 8:', 'stat1 0', 'stat2 1')
9: ('fd ffff8800ba7ab680:', 'stat1 2', 'stat2 0')
9: ('fd a:', 'stat1 200', 'stat2 100')
9: ('fd 9:', 'stat1 1', 'stat2 1')
 9/40 Test  #9: py_test_trace1 ...................   Passed    0.45 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 4.790s
10: 
10: OK
10: ('ptr ffff880213adb800:', 'stat1 (0 100)')
10: ('ptr ffff880216b34600:', 'stat1 (0 1)')
10: ('ptr ffff880215332a00:', 'stat1 (0 5)')
10: ('ptr ffff8800ba6e2a00:', 'stat1 (0 10)')
10: ('ptr ffff8800375b9c00:', 'stat1 (0 1)')
10: ('ptr ffff880213c8aa00:', 'stat1 (0 1)')
10: ('ptr ffff8800bb514600:', 'stat1 (0 4)')
10: ('ptr ffff8800bab92a00:', 'stat1 (0 20)')
10: ('ptr ffff880216b30e00:', 'stat1 (0 132)')
10: ('ptr ffff880213e84600:', 'stat1 (0 6)')
10: ('ptr ffffffff81e13500:', 'stat1 (0 25)')
10: ('ptr ffff880213958e00:', 'stat1 (0 1)')
10: ('ptr ffff8800bb515400:', 'stat1 (0 1)')
10: ('ptr ffff8802138faa00:', 'stat1 (0 4)')
10: ('ptr ffff880216ad5400:', 'stat1 (0 2)')
10: ('ptr ffff880213e86200:', 'stat1 (0 1)')
10/40 Test #10: py_test_trace2 ...................   Passed    4.88 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.00414326 s, 1.0 GB/s
11: 1024+0 records in
11: 1024+0 records out
11: 4194304 bytes (4.2 MB, 4.0 MiB) copied, 0.00466814 s, 898 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 0')
11: ('latency 46:', 'count 1')
11: ('latency 47:', 'count 1')
11: ('latency 48:', 'count 1')
11: ('latency 49:', 'count 1')
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 0')
11: ('latency 57:', 'count 3')
11: ('latency 58:', 'count 8')
11: ('latency 59:', 'count 1')
11: ('latency 60:', 'count 18')
11: ('latency 61:', 'count 30')
11: ('latency 62:', 'count 0')
11: ('latency 63:', 'count 12')
11: ('latency 64:', 'count 3')
11: ('latency 65:', 'count 5')
11: ('latency 66:', 'count 68')
11: ('latency 67:', 'count 44')
11: ('latency 68:', 'count 18')
11: ('latency 69:', 'count 10')
11: ('latency 70:', 'count 0')
11: ('latency 71:', 'count 0')
11: ('latency 72:', 'count 0')
11: ('latency 73:', 'count 1')
11: ('latency 74:', 'count 1')
11: ('latency 75:', 'count 1')
11: ('latency 76:', 'count 0')
11: ('latency 77:', 'count 1')
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 2.027s
11: 
11: OK
11/40 Test #11: py_test_trace3_c .................   Passed    2.22 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.325s
12: 
12: OK
12/40 Test #12: py_test_trace4 ...................   Passed    1.55 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.866s
13: 
13: OK
13/40 Test #13: py_test_trace_maxactive ..........   Passed    0.99 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 3.732s
14: 
14: OK
14/40 Test #14: py_test_probe_count ..............   Passed    3.78 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.603s
15: 
15: OK
15/40 Test #15: py_test_debuginfo ................   Passed    0.71 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.147 ms
16: 64 bytes from 200.1.1.1: icmp_seq=2 ttl=63 time=0.069 ms
16: 
16: --- 200.1.1.1 ping statistics ---
16: 2 packets transmitted, 2 received, 0% packet loss, time 1000ms
16: rtt min/avg/max/mdev = 0.069/0.108/0.147/0.039 ms
16: [ ID] Interval       Transfer     Bandwidth
16: [  7]  0.0- 1.0 sec  3.75 GBytes  32.2 Gbits/sec
16: [ ID] Interval       Transfer     Bandwidth
16: [ 10]  0.0- 1.0 sec  3.75 GBytes  31.9 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     37235.14   
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     26279.35   
16: 16384  87380 
16: .
16: ----------------------------------------------------------------------
16: Ran 1 test in 7.548s
16: 
16: OK
16/40 Test #16: py_test_brb ......................   Passed    7.68 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.175 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 999ms
17: rtt min/avg/max/mdev = 0.072/0.123/0.175/0.052 ms
17: [ ID] Interval       Transfer     Bandwidth
17: [  7]  0.0- 1.0 sec  3.49 GBytes  29.9 Gbits/sec
17: [ ID] Interval       Transfer     Bandwidth
17: [ 10]  0.0- 1.0 sec  3.49 GBytes  29.7 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     35230.17   
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     24452.06   
17: 16384  87380 
17: .
17: ----------------------------------------------------------------------
17: Ran 1 test in 7.673s
17: 
17: OK
17/40 Test #17: py_test_brb2 .....................   Passed    7.81 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:183: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:183: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: .......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 81 tests in 55.522s
18: 
18: OK (skipped=4)
18: 0
18/40 Test #18: py_test_clang ....................   Passed   55.88 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 = 'khugepaged'
19:      value               : count     distribution
19:          0 -> 1          : 1        |****************************************|
19: 
19: Bucket ptr = 'kworker/0:0'
19:      value               : count     distribution
19:          0 -> 1          : 1        |****************************************|
19: 
19: Bucket ptr = 'python'
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  : 100      |****************************************|
19: 
19: Bucket ptr = 'RemoteInvocatio'
19:      value               : count     distribution
19:          0 -> 1          : 6        |****************************************|
19: 
19: Bucket ptr = 'kworker/u4:2'
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: 
19: Bucket ptr = 'ntpd'
19:      value               : count     distribution
19:          0 -> 1          : 1        |****************************************|
19: 
19: Bucket ptr = 'rcu_sched'
19:      value               : count     distribution
19:          0 -> 1          : 5        |****************************************|
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 = 'gmain'
19:      value               : count     distribution
19:          0 -> 1          : 1        |****************************************|
19: 
19: Bucket ptr = 'sshguard'
19:      value               : count     distribution
19:          0 -> 1          : 16       |****************************************|
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  : 7        |*****************                       |
19: 
19: Bucket ptr = 'VM Periodic Tas'
19:      value               : count     distribution
19:          0 -> 1          : 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 = 'ctest'
19:      value               : count     distribution
19:          0 -> 1          : 10       |****************************************|
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            : 0        |                    |
19:            536870912 -> 1073741823           : 0        |                    |
19:           1073741824 -> 2147483647           : 2        |                    |
19:           2147483648 -> 4294967295           : 0        |                    |
19:           4294967296 -> 8589934591           : 0        |                    |
19:           8589934592 -> 17179869183          : 0        |                    |
19:          17179869184 -> 34359738367          : 0        |                    |
19:          34359738368 -> 68719476735          : 8        |*                   |
19:          68719476736 -> 137438953471         : 0        |                    |
19:         137438953472 -> 274877906943         : 0        |                    |
19:         274877906944 -> 549755813887         : 1        |                    |
19:         549755813888 -> 1099511627775        : 100      |********************|
19: 
19: Bucket ptr = 'kworker/1:14'
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 = 'ksoftirqd/0'
19:      value               : count     distribution
19:          0 -> 1          : 1        |****************************************|
19: 
19: Bucket ptr = 'irqbalance'
19:      value               : count     distribution
19:          0 -> 1          : 1        |****************************************|
19: 
19: Bucket ptr = 'iscsid'
19:      value               : count     distribution
19:          0 -> 1          : 5        |****************************************|
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            : 6        |**                  |
19:            134217728 -> 268435455            : 1        |                    |
19:            268435456 -> 536870911            : 0        |                    |
19:            536870912 -> 10737..41823           : 0        |                    |
19:           1073741824 -> 2147483647           : 0        |                    |
19:           2147483648 -> 4294967295           : 0        |                    |
19:           4294967296 -> 8589934591           : 0        |                    |
19:           8589934592 -> 17179869183          : 0        |                    |
19:          17179869184 -> 34359738367          : 0        |                    |
19:          34359738368 -> 68719476735          : 47       |********************|
19:          68719476736 -> 137438953471         : 6        |**                  |
19:         137438953472 -> 274877906943         : 0        |                    |
19:         274877906944 -> 549755813887         : 11       |****                |
19: 
19: Bucket ptr = 'G1 Young RemSet'
19:      value               : count     distribution
19:          0 -> 1          : 3        |****************************************|
19: 
19: k_1 & k_2 =  96 0
19:      size                : count     distribution
19:          0 -> 1          : 12       |****************************************|
19:      value               : count     distribution
19:          0 -> 1          : 2        |********                                |
19:          2 -> 3          : 10       |****************************************|
19:          4 -> 7          : 7        |****************************            |
19:          8 -> 15         : 5        |********************                    |
19:         16 -> 31         : 4        |****************                        |
19:         32 -> 63         : 4        |****************                        |
19:         64 -> 127        : 6        |************************                |
19:        128 -> 255        : 10       |****************************************|
19:        256 -> 511        : 9        |************************************    |
19:        512 -> 1023       : 10       |****************************************|
19:       1024 -> 2047       : 1        |****                                    |
19:       2048 -> 4095       : 8        |********************************        |
19:       4096 -> 8191       : 6        |************************                |
19:       8192 -> 16383      : 9        |************************************    |
19:      16384 -> 32767      : 10       |****************************************|
19:      32768 -> 65535      : 6        |************************                |
19:      65536 -> 131071     : 6        |************************                |
19:     131072 -> 262143     : 5        |********************                    |
19:     262144 -> 524287     : 9        |************************************    |
19:     524288 -> 1048575    : 4        |****************                        |
19:    1048576 -> 2097151    : 4        |****************                        |
19:    2097152 -> 4194303    : 6        |************************                |
19:    4194304 -> 8388607    : 5        |********************                    |
19:    8388608 -> 16777215   : 7        |****************************            |
19:   16777216 -> 33554431   : 5        |********************                    |
19:   33554432 -> 67108863   : 6        |************************                |
19:   67108864 -> 134217727  : 9        |************************************    |
19:  134217728 -> 268435455  : 5        |********************                    |
19:  268435456 -> 536870911  : 7        |****************************            |
19:  536870912 -> 1073741823 : 7        |****************************            |
19: 1073741824 -> 2147483647 : 1        |****                                    |
19: 2147483648 -> 4294967295 : 9        |************************************    |
19:                value                         : count     distribution
19:                    0 -> 1                    : 2        |****                |
19:                    2 -> 3                    : 10       |********************|
19:                    4 -> 7                    : 7        |**************      |
19:                    8 -> 15                   : 5        |**********          |
19:                   16 -> 31                   : 4        |********            |
19:                   32 -> 63                   : 4        |********            |
19:                   64 -> 127                  : 6        |************        |
19:                  128 -> 255                  : 10       |********************|
19:                  256 -> 511                  : 9        |******************  |
19:                  512 -> 1023                 : 10       |********************|
19:                 1024 -> 2047                 : 1        |**                  |
19:                 2048 -> 4095                 : 8        |****************    |
19:                 4096 -> 8191                 : 6        |************        |
19:                 8192 -> 16383                : 9        |******************  |
19:                16384 -> 32767                : 10       |********************|
19:                32768 -> 65535                : 6        |************        |
19:                65536 -> 131071               : 6        |************        |
19:               131072 -> 262143               : 5        |**********          |
19:               262144 -> 524287               : 9        |******************  |
19:               524288 -> 1048575              : 4        |********            |
19:              1048576 -> 2097151              : 4        |********            |
19:              2097152 -> 4194303              : 6        |************        |
19:              4194304 -> 8388607              : 5        |**********          |
19:              8388608 -> 16777215             : 7        |**************      |
19:             16777216 -> 33554431             : 5        |**********          |
19:             33554432 -> 67108863             : 6        |************        |
19:             67108864 -> 134217727            : 9        |******************  |
19:            134217728 -> 268435455            : 5        |**********          |
19:            268435456 -> 536870911            : 7        |**************      |
19:            536870912 -> 1073741823           : 7        |**************      |
19:           1073741824 -> 2147483647           : 1        |**                  |
19:           2147483648 -> 4294967295           : 9        |******************  |
19:           4294967296 -> 8589934591           : 4        |********            |
19:           8589934592 -> 17179869183          : 4        |********            |
19:          17179869184 -> 34359738367          : 5        |**********          |
19:          34359738368 -> 68719476735          : 9        |******************  |
19:          68719476736 -> 137438953471         : 8        |****************    |
19:         137438953472 -> 274877906943         : 7        |**************      |
19:         274877906944 -> 549755813887         : 10       |********************|
19:         549755813888 -> 1099511627775        : 6        |************        |
19:        1099511627776 -> 2199023255551        : 1        |**                  |
19:        2199023255552 -> 4398046511103        : 1        |**                  |
19:        4398046511104 -> 8796093022207        : 7        |**************      |
19:        8796093022208 -> 17592186044415       : 1        |**                  |
19:       17592186044416 -> 35184372088831       : 10       |********************|
19:       35184372088832 -> 70368744177663       : 9        |******************  |
19:       70368744177664 -> 140737488355327      : 1        |**                  |
19:      140737488355328 -> 281474976710655      : 5        |**********          |
19:      281474976710656 -> 562949953421311      : 6        |************        |
19:      562949953421312 -> 1125899906842623     : 3        |******              |
19:     1125899906842624 -> 2251799813685247     : 5        |**********          |
19:     2251799813685248 -> 4503599627370495     : 10       |********************|
19:     4503599627370496 -> 9007199254740991     : 8        |****************    |
19:     9007199254740992 -> 18014398509481983    : 3        |******              |
19:    18014398509481984 -> 36028797018963967    : 5        |**********          |
19:    36028797018963968 -> 72057594037927935    : 10       |********************|
19:    72057594037927936 -> 144115188075855871   : 5  .      |**********          |
19:   144115188075855872 -> 288230376151711743   : 7        |**************      |
19:   288230376151711744 -> 576460752303423487   : 2        |****                |
19:   576460752303423488 -> 1152921504606846975  : 9        |******************  |
19:  1152921504606846976 -> 2305843009213693951  : 2        |****                |
19:  2305843009213693952 -> 4611686018427387903  : 4        |********            |
19:  4611686018427387904 -> 9223372036854775807  : 2        |****                |
19: 
19: Bucket ptr = 18446612141256624000L
19:                value                         : count     distribution
19:                    0 -> 1                    : 4        |********            |
19:                    2 -> 3                    : 6        |************        |
19:                    4 -> 7                    : 3        |******              |
19:                    8 -> 15                   : 8        |****************    |
19:                   16 -> 31                   : 2        |****                |
19:                   32 -> 63                   : 10       |********************|
19:                   64 -> 127                  : 2        |****                |
19:                  128 -> 255                  : 10       |********************|
19:                  256 -> 511                  : 3        |******              |
19:                  512 -> 1023                 : 10       |********************|
19:                 1024 -> 2047                 : 7        |**************      |
19:                 2048 -> 4095                 : 10       |********************|
19:                 4096 -> 8191                 : 3        |******              |
19:                 8192 -> 16383                : 8        |****************    |
19:                16384 -> 32767                : 4        |********            |
19:                32768 -> 65535                : 1        |**                  |
19:                65536 -> 131071               : 1        |**                  |
19:               131072 -> 262143               : 10       |********************|
19:               262144 -> 524287               : 4        |********            |
19:               524288 -> 1048575              : 8        |****************    |
19:              1048576 -> 2097151              : 7        |**************      |
19:              2097152 -> 4194303              : 4        |********            |
19:              4194304 -> 8388607              : 2        |****                |
19:              8388608 -> 16777215             : 4        |********            |
19:             16777216 -> 33554431             : 9        |******************  |
19:             33554432 -> 67108863             : 8        |****************    |
19:             67108864 -> 134217727            : 2        |****                |
19:            134217728 -> 268435455            : 3        |******              |
19:            268435456 -> 536870911            : 5        |**********          |
19:            536870912 -> 1073741823           : 1        |**                  |
19:           1073741824 -> 2147483647           : 1        |**                  |
19:           2147483648 -> 4294967295           : 9        |******************  |
19:           4294967296 -> 8589934591           : 2        |****                |
19:           8589934592 -> 17179869183          : 7        |**************      |
19:          17179869184 -> 34359738367          : 4        |********            |
19:          34359738368 -> 68719476735          : 8        |****************    |
19:          68719476736 -> 137438953471         : 4        |********            |
19:         137438953472 -> 274877906943         : 8        |****************    |
19:         274877906944 -> 549755813887         : 6        |************        |
19:         549755813888 -> 1099511627775        : 10       |********************|
19:        1099511627776 -> 2199023255551        : 7        |**************      |
19:        2199023255552 -> 4398046511103        : 5        |**********          |
19:        4398046511104 -> 8796093022207        : 3        |******              |
19:        8796093022208 -> 17592186044415       : 3        |******              |
19:       17592186044416 -> 35184372088831       : 8        |****************    |
19:       35184372088832 -> 70368744177663       : 2        |****                |
19:       70368744177664 -> 140737488355327      : 6        |************        |
19:      140737488355328 -> 281474976710655      : 3        |******              |
19:      281474976710656 -> 562949953421311      : 6        |************        |
19:      562949953421312 -> 1125899906842623     : 6        |************        |
19:     1125899906842624 -> 2251799813685247     : 9        |******************  |
19:     2251799813685248 -> 4503599627370495     : 7        |**************      |
19:     4503599627370496 -> 9007199254740991     : 7        |**************      |
19:     9007199254740992 -> 18014398509481983    : 8        |****************    |
19:    18014398509481984 -> 36028797018963967    : 1        |**                  |
19:    36028797018963968 -> 72057594037927935    : 4        |********            |
19:    72057594037927936 -> 144115188075855871   : 2        |****                |
19:   144115188075855872 -> 288230376151711743   : 6        |************        |
19:   288230376151711744 -> 576460752303423487   : 8        |****************    |
19:   576460752303423488 -> 1152921504606846975  : 5        |**********          |
19:  1152921504606846976 -> 2305843009213693951  : 3        |******              |
19:  2305843009213693952 -> 4611686018427387903  : 1        |**                  |
19:  4611686018427387904 -> 9223372036854775807  : 3        |******              |
19:  9223372036854775808 -> 18446744073709551615 : 6        |************        |
19: 
19: Bucket ptr = 18446612141256625920L
19:                value                         : count     distribution
19:                    0 -> 1                    : 4        |********            |
19:                    2 -> 3                    : 6        |************        |
19:                    4 -> 7                    : 3        |******              |
19:                    8 -> 15                   : 8        |****************    |
19:                   16 -> 31                   : 2        |****                |
19:                   32 -> 63                   : 10       |********************|
19:                   64 -> 127                  : 2        |****                |
19:                  128 -> 255                  : 10       |********************|
19:                  256 -> 511                  : 3        |******              |
19:                  512 -> 1023                 : 10       |********************|
19:                 1024 -> 2047                 : 7        |**************      |
19:                 2048 -> 4095                 : 10       |********************|
19:                 4096 -> 8191                 : 3        |******              |
19:                 8192 -> 16383                : 8        |****************    |
19:                16384 -> 32767                : 4        |********            |
19:                32768 -> 65535                : 1        |**                  |
19:                65536 -> 131071               : 1        |**                  |
19:               131072 -> 262143               : 10       |********************|
19:               262144 -> 524287               : 4        |********            |
19:               524288 -> 1048575              : 8        |****************    |
19:              1048576 -> 2097151              : 7        |**************      |
19:              2097152 -> 4194303              : 4        |********            |
19:              4194304 -> 8388607              : 2        |****                |
19:              8388608 -> 16777215             : 4        |********            |
19:             16777216 -> 33554431             : 9        |******************  |
19:             33554432 -> 67108863             : 8        |****************    |
19:             67108864 -> 134217727            : 2        |****                |
19:            134217728 -> 268435455            : 3        |******              |
19:            268435456 -> 536870911            : 5        |**********          |
19:            536870912 -> 1073741823           : 1        |**                  |
19:           1073741824 -> 2147483647           : 1   .
19: ----------------------------------------------------------------------
19: Ran 4 tests in 3.171s
19: 
19: OK
19:      |**                  |
19:           2147483648 -> 4294967295           : 9        |******************  |
19:           4294967296 -> 8589934591           : 2        |****                |
19:           8589934592 -> 17179869183          : 7        |**************      |
19:          17179869184 -> 34359738367          : 4        |********            |
19:          34359738368 -> 68719476735          : 8        |****************    |
19:          68719476736 -> 137438953471         : 4        |********            |
19:         137438953472 -> 274877906943         : 8        |****************    |
19:         274877906944 -> 549755813887         : 6        |************        |
19:         549755813888 -> 1099511627775        : 10       |********************|
19:        1099511627776 -> 2199023255551        : 7        |**************      |
19:        2199023255552 -> 4398046511103        : 5        |**********          |
19:        4398046511104 -> 8796093022207        : 3        |******              |
19:        8796093022208 -> 17592186044415       : 3        |******              |
19:       17592186044416 -> 35184372088831       : 8        |****************    |
19:       35184372088832 -> 70368744177663       : 2        |****                |
19:       70368744177664 -> 140737488355327      : 6        |************        |
19:      140737488355328 -> 281474976710655      : 3        |******              |
19:      281474976710656 -> 562949953421311      : 6        |************        |
19:      562949953421312 -> 1125899906842623     : 6        |************        |
19:     1125899906842624 -> 2251799813685247     : 9        |******************  |
19:     2251799813685248 -> 4503599627370495     : 7        |**************      |
19:     4503599627370496 -> 9007199254740991     : 7        |**************      |
19:     9007199254740992 -> 18014398509481983    : 8        |****************    |
19:    18014398509481984 -> 36028797018963967    : 1        |**                  |
19:    36028797018963968 -> 72057594037927935    : 4        |********            |
19:    72057594037927936 -> 144115188075855871   : 2        |****                |
19:   144115188075855872 -> 288230376151711743   : 6        |************        |
19:   288230376151711744 -> 576460752303423487   : 8        |****************    |
19:   576460752303423488 -> 1152921504606846975  : 5        |**********          |
19:  1152921504606846976 -> 2305843009213693951  : 3        |******              |
19:  2305843009213693952 -> 4611686018427387903  : 1        |**                  |
19:  4611686018427387904 -> 9223372036854775807  : 3        |******              |
19:  9223372036854775808 -> 18446744073709551615 : 6        |************        |
19/40 Test #19: py_test_histogram ................   Passed    3.22 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.027s
20: 
20: OK
20/40 Test #20: py_array .........................   Passed    1.08 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     =   19775488
21: in use bytes     =    2930608
21: Total (incl. mmap):
21: system bytes     =   20566016
21: in use bytes     =    3721136
21: max mmap regions =          7
21: max mmap bytes   =    2781184
21: .
21: ----------------------------------------------------------------------
21: Ran 3 tests in 6.380s
21: 
21: OK
21/40 Test #21: py_uprobes .......................   Passed    6.43 sec
test 22
      Start 22: py_test_stackid

22: 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"
22: Test timeout computed to be: 9.99988e+06
22: ss
22: ----------------------------------------------------------------------
22: Ran 2 tests in 0.000s
22: 
22: OK (skipped=2)
22/40 Test #22: py_test_stackid ..................   Passed    0.11 sec
test 23
      Start 23: py_test_tracepoint

23: 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"
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/40 Test #23: py_test_tracepoint ...............   Passed    0.05 sec
test 24
      Start 24: py_test_perf_event

24: 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"
24: Test timeout computed to be: 9.99988e+06
24: perf_event_open: No such file or directory
24: s
24: ----------------------------------------------------------------------
24: Ran 1 test in 0.240s
24: 
24: OK (skipped=1)
24/40 Test #24: py_test_perf_event ...............   Passed    0.37 sec
test 25
      Start 25: py_test_utils

25: 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"
25: Test timeout computed to be: 9.99988e+06
25: ..
25: ----------------------------------------------------------------------
25: Ran 2 tests in 0.000s
25: 
25: OK
25/40 Test #25: py_test_utils ....................   Passed    0.05 sec
test 26
      Start 26: py_test_percpu

26: 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"
26: Test timeout computed to be: 9.99988e+06
26: could not open bpf map: stub, error: Invalid argument
26: scould not open bpf map: stub, error: Invalid argument
26: scould not open bpf map: stub, error: Invalid argument
26: scould not open bpf map: stub, error: Invalid argument
26: s
26: ----------------------------------------------------------------------
26: Ran 4 tests in 0.474s
26: 
26: OK (skipped=4)
26/40 Test #26: py_test_percpu ...................   Passed    0.52 sec
test 27
      Start 27: py_test_dump_func

27: 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"
27: Test timeout computed to be: 9.99988e+06
27: .
27: ----------------------------------------------------------------------
27: Ran 1 test in 0.125s
27: 
27: OK
27/40 Test #27: py_test_dump_func ................   Passed    0.17 sec
test 28
      Start 28: py_test_disassembler

28: 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"
28: Test timeout computed to be: 9.99988e+06
28: ..
28: ----------------------------------------------------------------------
28: Ran 2 tests in 0.141s
28: 
28: OK
28/40 Test #28: py_test_disassembler .............   Passed    0.19 sec
test 29
      Start 29: py_test_tools_smoke

29: 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"
29: Test timeout computed to be: 9.99988e+06
29: Traceback (most recent call last):
29:   File "../../tools/argdist.py", line 712, in run
29:     self._main_loop()
29:   File "../../tools/argdist.py", line 702, in _main_loop
29:     exit()
29:   File "/usr/lib/python2.7/site.py", line 375, in __call__
29:     raise SystemExit(code)
29: SystemExit: None
29: .....'unknown': I need something more specific.
29: .'unknown': I need something more specific.
29: 'unknown': I need something more specific.
29: 'unknown': I need something more specific.
29: 'unknown': I need something more specific.
29: .......s.s....sss....'unknown': I need something more specific.
29: ......Killed
29: .ss.ss...ssslibbpf: failed to find valid kernel BTF
29: libbpf: vmlinux BTF is not found
29: ..s.ss'unknown': I need something more specific.
29: .ss..s..s...s..'unknown': I need something more specific.
29: ...'unknown': I need something more specific.
29: .s...'unknown': I need something more specific.
29: ...libbpf: failed to find valid kernel BTF
29: libbpf: vmlinux BTF is not found
29: .s....
29: ----------------------------------------------------------------------
29: Ran 83 tests in 203.770s
29: 
29: OK (skipped=22)
29/40 Test #29: py_test_tools_smoke ..............   Passed  203.87 sec
test 30
      Start 30: py_test_tools_memleak

30: 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"
30: Test timeout computed to be: 9.99988e+06
30: ssssssss
30: ----------------------------------------------------------------------
30: Ran 8 tests in 0.072s
30: 
30: OK (skipped=8)
30/40 Test #30: py_test_tools_memleak ............   Passed    0.11 sec
test 31
      Start 31: py_test_usdt

31: 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"
31: Test timeout computed to be: 9.99988e+06
31: <stdin>: In function ‘main’:
31: <stdin>:23:17: warning: null character(s) preserved in literal
31: <stdin>:25:42: warning: format ‘%d’ expects argument of type ‘int’, but argument 4 has type ‘uint64_t {aka long unsigned int}’ [-Wformat=]
31: <stdin>:25:42: warning: format ‘%d’ expects argument of type ‘int’, but argument 4 has type ‘uint64_t {aka long unsigned int}’ [-Wformat=]
31: <stdin>:28:17: warning: null character(s) preserved in literal
31: <stdin>:30:42: warning: format ‘%d’ expects argument of type ‘int’, but argument 4 has type ‘uint64_t {aka long unsigned int}’ [-Wformat=]
31: <stdin>:30:42: warning: format ‘%d’ expects argument of type ‘int’, but argument 4 has type ‘uint64_t {aka long unsigned int}’ [-Wformat=]
31: 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/uapi -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__ -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
31: #if defined(BPF_LICENSE)
31: #error BPF_LICENSE cannot be specified through cflags
31: #endif
31: #if !defined(CONFIG_CC_STACKPROTECTOR)
31: #if defined(CONFIG_CC_STACKPROTECTOR_AUTO) \
31:     || defined(CONFIG_CC_STACKPROTECTOR_REGULAR) \
31:     || defined(CONFIG_CC_STACKPROTECTOR_STRONG)
31: #define CONFIG_CC_STACKPROTECTOR
31: #endif
31: #endif
31: #include <uapi/linux/ptrace.h>
31: __attribute__((always_inline))
31: static __always_inline int _bpf_readarg_do_trace1_1(struct pt_regs *ctx, void *dest, size_t len) {
31:   if (len != sizeof(int8_t)) return -1;
31:   switch(PT_REGS_IP(ctx)) {
31:   case 0x4006e4ULL: *((int8_t *)dest) = ctx->ax; __asm__ __volatile__("": : :"memory"); return 0;
31:   case 0x4006eaULL: *((int8_t *)dest) = ctx->ax; __asm__ __volatile__("": : :"memory"); return 0;
31:   }
31:   return -1;
31: }
31: __attribute__((always_inline))
31: static __always_inline int _bpf_readarg_do_trace1_2(struct pt_regs *ctx, void *dest, size_t len) {
31:   if (len != sizeof(int32_t)) return -1;
31:   switch(PT_REGS_IP(ctx)) {
31:   case 0x4006e4ULL: { u64 __addr = ctx->bp + -204; __asm__ __volatile__("": : :"memory"); int32_t __res = 0x0; bpf_probe_read(&__res, sizeof(__res), (void *)__addr); *((int32_t *)dest) = __res; } return 0;
31:   case 0x4006eaULL: { u64 __addr = ctx->bp + -208; __asm__ __volatile__("": : :"memory"); int32_t __res = 0x0; bpf_probe_read(&__res, sizeof(__res), (void *)__addr); *((int32_t *)dest) = __res; } return 0;
31:   }
31:   return -1;
31: }
31: __attribute__((always_inline))
31: static __always_inline int _bpf_readarg_do_trace3_1(struct pt_regs *ctx, void *dest, size_t len) {
31:   if (len != sizeof(int32_t)) return -1;
31:   switch(PT_REGS_IP(ctx)) {
31:   case 0x4006e5ULL: { u64 __addr = ctx->bp + -208; __asm__ __volatile__("": : :"memory"); int32_t __res = 0x0; bpf_probe_read(&__res, sizeof(__res), (void *)__addr); *((int32_t *)dest) = __res; } return 0;
31:   case 0x4006f8ULL: *((int32_t *)dest) = ctx->ax; __asm__ __volatile__("": : :"memory"); return 0;
31:   }
31:   return -1;
31: }
31: __attribute__((always_inline))
31: static __always_inline int _bpf_readarg_do_trace3_2(struct pt_regs *ctx, void *dest, size_t len) {
31:   if (len != sizeof(int32_t)) return -1;
31:   switch(PT_REGS_IP(ctx)) {
31:   case 0x4006e5ULL: { u64 __addr = ctx->bp + -204; __asm__ __volatile__("": : :"memory"); int32_t __res = 0x0; bpf_probe_read(&__res, sizeof(__res), (void *)__addr); *((int32_t *)dest) = __res; } return 0;
31:   case 0x4006f8ULL: *((int32_t *)dest) = ctx->dx; __asm__ __volatile__("": : :"memory"); return 0;
31:   }
31:   return -1;
31: }
31: __attribute__((always_inline))
31: static __always_inline int _bpf_readarg_do_trace2_1(struct pt_regs *ctx, void *dest, size_t len) {
31:   if (len != sizeof(int32_t)) return -1;
31:   *((int32_t *)dest) = 5;
31:   return 0;
31: }
31: __attribute__((always_inline))
31: static __always_inline int _bpf_readarg_do_trace2_2(struct pt_regs *ctx, void *dest, size_t len) {
31:   if (len != sizeof(int8_t)) return -1;
31:   *((int8_t *)dest) = ctx->ax; __asm__ __volatile__("": : :"memory");
31:   return 0;
31: }
31: __attribute__((always_inline))
31: static __always_inline int _bpf_readarg_do_trace4_1(struct pt_regs *ctx, void *dest, size_t len) {
31:   if (len != sizeof(int64_t)) return -1;
31:   *((int64_t *)dest) = ctx->ax; __asm__ __volatile__("": : :"memory");
31:   return 0;
31: }
31: __attribute__((always_inline))
31: static __always_inline int _bpf_readarg_do_trace4_2(struct pt_regs *ctx, void *dest, size_t len) {
31:   if (len != sizeof(int64_t)) return -1;
31:   *((int64_t *)dest) = ctx->dx; __asm__ __volatile__("": : :"memory");
31:   return 0;
31: }
31: __attribute__((always_inline))
31: static __always_inline int _bpf_readarg_do_trace5_1(struct pt_regs *ctx, void *dest, size_t len) {
31:   if (len != sizeof(int64_t)) return -1;
31:   *((int64_t *)dest) = ctx->dx; __asm__ __volatile__("": : :"memory");
31:   return 0;
31: }
31: __attribute__((always_inline))
31: static __always_inline int _bpf_readarg_do_trace5_2(struct pt_regs *ctx, void *dest, size_t len) {
31:   if (len != sizeof(int64_t)) return -1;
31:   *((int64_t *)dest) = ctx->ax; __asm__ __volatile__("": : :"memory");
31:   return 0;
31: }
31: 
31: #include <linux/blkdev.h>
31: #include <uapi/linux/ptrace.h>
31: 
31: struct probe_result_t1 {
31:   char v1;
31:   int  v2;
31: };
31: 
31: struct probe_result_t2 {
31:   int  v1;
31:   char v2;
31: };
31: 
31: struct probe_result_t3 {
31:   int v1;
31:   int v2;
31: };
31: 
31: struct probe_result_t4 {
31:   u64  v1;
31:   char v2[8];
31: };
31: 
31: struct probe_result_t5 {
31:   char v1[8];
31:   u64  v2;
31: };
31: 
31: BPF_PERF_OUTPUT(event1);
31: BPF_PERF_OUTPUT(event2);
31: BPF_PERF_OUTPUT(event3);
31: BPF_PERF_OUTPUT(event4);
31: BPF_PERF_OUTPUT(event5);
31: 
31: __attribute__((section(".bpf.fn.do_trace1")))
31: int do_trace1(struct pt_regs *ctx) {
31: 
31:     struct probe_result_t1 result = {};
31:     _bpf_readarg_do_trace1_1(ctx, &result.v1, sizeof(*(&result.v1)));
31:     _bpf_readarg_do_trace1_2(ctx, &result.v2, sizeof(*(&result.v2)));
31:     bpf_perf_event_output(ctx, bpf_pseudo_fd(1, -1), CUR_CPU_IDENTIFIER, &result, sizeof(result));
31:     return 0;
31: };
31: __attribute__((section(".bpf.fn.do_trace2")))
31: int do_trace2(struct pt_regs *ctx) {
31: 
31:     struct probe_result_t2 result = {};
31:     _bpf_readarg_do_trace2_1(ctx, &result.v1, sizeof(*(&result.v1)));
31:     _bpf_readarg_do_trace2_2(ctx, &result.v2, sizeof(*(&result.v2)));
31:     bpf_perf_event_output(ctx, bpf_pseudo_fd(1, -2), CUR_CPU_IDENTIFIER, &result, sizeof(result));
31:     return 0;
31: }
31: __attribute__((section(".bpf.fn.do_trace3")))
31: int do_trace3(struct pt_regs *ctx) {
31: 
31:     struct probe_result_t3 result = {};
31:     _bpf_readarg_do_trace3_1(ctx, &result.v1, sizeof(*(&result.v1)));
31:     _bpf_readarg_do_trace3_2(ctx, &result.v2, sizeof(*(&result.v2)));
31:     bpf_perf_event_output(ctx, bpf_pseudo_fd(1, -3), CUR_CPU_IDENTIFIER, &result, sizeof(result));
31:     return 0;
31: }
31: __attribute__((section(".bpf.fn.do_trace4")))
31: int do_trace4(struct pt_regs *ctx) {
31: 
31:     struct probe_result_t4 result = {};
31:     _bpf_readarg_do_trace4_1(ctx, &result.v1, sizeof(*(&result.v1)));
31:     ({ u64 __addr = 0x0; _bpf_readarg_do_trace4_2(ctx, &__addr, sizeof(__addr));bpf_probe_read(&result.v2, sizeof(result.v2), (void *)__addr);});
31:     bpf_perf_event_output(ctx, bpf_pseudo_fd(1, -4), CUR_CPU_IDENTIFIER, &result, sizeof(result));
31:     return 0;
31: }
31: __attribute__((section(".bpf.fn.do_trace5")))
31: int do_trace5(struct pt_regs *ctx) {
31: 
31:     struct probe_result_t5 result = {};
31:     ({ u64 __addr = 0x0; _bpf_readarg_do_trace5_1(ctx, &__addr, sizeof(__addr));bpf_probe_read(&result.v1, sizeof(result.v1), (void *)__addr);});
31:     _bpf_readarg_do_trace5_2(ctx, &result.v2, sizeof(*(&result.v2)));
31:     bpf_perf_event_output(ctx, bpf_pseudo_fd(1, -5), CUR_CPU_IDENTIFIER, &result, sizeof(result));
31:     return 0;
31: }
31: 
31: #include <bcc/footer.h>
31: .
31: ----------------------------------------------------------------------
31: Ran 1 test in 2.054s
31: 
31: OK
31: Running from kernel directory at: /lib/modules/4.4.0-116-generic/build
31: str3
31: str2
31: str5
31: str4
31/40 Test #31: py_test_usdt .....................   Passed    2.29 sec
test 32
      Start 32: py_test_usdt2

32: 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"
32: Test timeout computed to be: 9.99988e+06
32: .
32: ----------------------------------------------------------------------
32: Ran 1 test in 2.047s
32: 
32: OK
32/40 Test #32: py_test_usdt2 ....................   Passed    2.21 sec
test 33
      Start 33: py_test_usdt3

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

34: 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"
34: Test timeout computed to be: 9.99988e+06
34: /virtual/main.c:2:2: error: BPF_LICENSE cannot be specified through cflags
34: #error BPF_LICENSE cannot be specified through cflags
34:  ^
34: 1 error generated.
34: x/virtual/main.c:2:2: error: BPF_LICENSE cannot be specified through cflags
34: #error BPF_LICENSE cannot be specified through cflags
34:  ^
34: 1 error generated.
34: x.bpf: Failed to load program: Invalid argument
34: 0: (bf) r6 = r1
34: 1: (85) call 5
34: cannot call GPL only function from proprietary program
34: 
34: x../virtual/main.c:2:2: error: BPF_LICENSE cannot be specified through cflags
34: #error BPF_LICENSE cannot be specified through cflags
34:  ^
34: 1 error generated.
34: xbpf: Failed to load program: Invalid argument
34: 0: (bf) r6 = r1
34: 1: (85) call 5
34: cannot call GPL only function from proprietary program
34: 
34: x..
34: ----------------------------------------------------------------------
34: Ran 10 tests in 1.563s
34: 
34: OK (expected failures=5)
34/40 Test #34: py_test_license ..................   Passed    1.62 sec
test 35
      Start 35: py_test_free_bcc_memory

35: 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"
35: Test timeout computed to be: 9.99988e+06
35: s
35: ----------------------------------------------------------------------
35: Ran 1 test in 0.000s
35: 
35: OK (skipped=1)
35/40 Test #35: py_test_free_bcc_memory ..........   Passed    0.05 sec
test 36
      Start 36: py_test_rlimit

36: 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"
36: Test timeout computed to be: 9.99988e+06
36: could not open bpf map: unused, error: Operation not permitted
36: .
36: ----------------------------------------------------------------------
36: Ran 1 test in 0.262s
36: 
36: OK
36/40 Test #36: py_test_rlimit ...................   Passed    0.33 sec
test 37
      Start 37: lua_test_clang

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

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

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

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

100% tests passed, 0 tests failed out of 40

Total Test time (real) = 337.90 sec
+ head -n 1 Testing/TAG
+ cp Testing/20200320-0522/Test.xml ./CTestResults.xml
Taking single-use slave ubuntu1604-slave-4ed offline.
Finished: SUCCESS