* [Bug testsuite/15964] New: poll_map.exp testcase timeout on ARM
@ 2013-09-17 8:56 naresh.kamboju at linaro dot org
2013-09-17 9:11 ` [Bug testsuite/15964] " mjw at redhat dot com
` (8 more replies)
0 siblings, 9 replies; 10+ messages in thread
From: naresh.kamboju at linaro dot org @ 2013-09-17 8:56 UTC (permalink / raw)
To: systemtap
https://sourceware.org/bugzilla/show_bug.cgi?id=15964
Bug ID: 15964
Summary: poll_map.exp testcase timeout on ARM
Product: systemtap
Version: unspecified
Status: NEW
Severity: normal
Priority: P2
Component: testsuite
Assignee: systemtap at sourceware dot org
Reporter: naresh.kamboju at linaro dot org
Hi
While running "make installcheck" on ARM snowball board, I have noticed
poll_map testcase reported FAIL timeout. Due to this reason " make
installcheck" stopped and results are not available.
1. When test suite detects a failure it should report the failure case and test
execution should continues. It should not struck at a single failure.
2. Please investigate this poll_map testcase failure.
If you need more information please me know.
#make installcheck
Running ./systemtap.base/poll_map.exp ...
FAIL: poll_map (timeout)
poll_map.exp_hash.log
-----------------------
http://pastebin.com/zCH9WUur
poll_map_stap-report-log
-----------------------
http://pastebin.com/JtyENt0j
poll_map.exp_c_prog
-----------------------
http://pastebin.com/CWv0B7v7
--------------------------------------------------------------
root@linaro-developer:~/systemtap-2.2.1/testsuite# cat /var/log/kern.log
Sep 17 07:09:38 linaro-developer kernel:
stap_83df0379e451bb1ec824cc2edc7d8b76_9733: systemtap: 2.2.1/0.155, base:
bf1c2000, memory: 1449data/50text/4ctx/2058net/1105alloc kb, probes: 43
---------------------------------------------------------------------
root@linaro-developer:~/systemtap-2.2.1/testsuite# stap-report
== id ==
uid=0(root) gid=0(root) groups=0(root)
== stap -V ==
Systemtap translator/driver (version 2.2.1/0.155, non-git sources)
Copyright (C) 2005-2013 Red Hat, Inc. and others
This is free software; see the source for copying conditions.
enabled features: TR1_UNORDERED_MAP NLS
== which stap ==
/usr/local/bin/stap
== locate --regex '/stap(run|io|dyn)?$' | xargs ls -ald ==
sh: 1: locate: not found
drwxrwxr-x 37 root root 4096 Sep 17 07:08 .
== printenv | egrep '^PATH=|^LD_LIBRARY_PATH=|^SYSTEMTAP_.*=|^XDG_DATA.*=' ==
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/bin:/sbin:/usr/bin:/usr/sbin
== stap -vv --poison-cache -p4 -e 'probe begin {exit()}' ==
Systemtap translator/driver (version 2.2.1/0.155, non-git sources)
Copyright (C) 2005-2013 Red Hat, Inc. and others
This is free software; see the source for copying conditions.
enabled features: TR1_UNORDERED_MAP NLS
Created temporary directory "/tmp/stap8WlBZG"
Session arch: arm release: 3.11.0-rc5
Searched for library macro files:
"/usr/local/share/systemtap/tapset/linux/*.stpm", found: 1, processed: 1
Searched for library macro files: "/usr/local/share/systemtap/tapset/*.stpm",
found: 1, processed: 1
Searched: "/usr/local/share/systemtap/tapset/linux/arm/*.stp", found: 1,
processed: 1
Searched: "/usr/local/share/systemtap/tapset/linux/*.stp", found: 64,
processed: 64
Searched: "/usr/local/share/systemtap/tapset/arm/*.stp", found: 1, processed: 1
Searched: "/usr/local/share/systemtap/tapset/*.stp", found: 21, processed: 21
Pass 1: parsed user script and 89 library script(s) using
18440virt/13892res/1560shr/12936data kb, in 840usr/40sys/894real ms.
Pass 2: analyzed script: 1 probe(s), 1 function(s), 0 embed(s), 0 global(s)
using 18704virt/14348res/1748shr/13200data kb, in 20usr/0sys/20real ms.
ignoring extra parts of compat version: 2.2.1
function recursion-analysis: max-nesting 0 non-recursive
Pass 3: translated to C into
"/tmp/stap8WlBZG/stap_b00d6e09dd729ff1650d3b351fbb314e_803_src.c" using
18704virt/14540res/1916shr/13200data kb, in 0usr/0sys/5real ms.
Running env -uARCH -uKBUILD_EXTMOD -uCROSS_COMPILE -uKBUILD_IMAGE
-uKCONFIG_CONFIG -uINSTALL_PATH
PATH=/usr/bin:/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/bin:/sbin:/usr/bin:/usr/sbin
make -C /lib/modules/3.11.0-rc5/build M=/tmp/stap8WlBZG modules ARCH=arm
CONFIG_DEBUG_INFO= --no-print-directory -j3
CC [M] /tmp/stap8WlBZG/stap_b00d6e09dd729ff1650d3b351fbb314e_803_src.o
LD [M] /tmp/stap8WlBZG/stap_b00d6e09dd729ff1650d3b351fbb314e_803.o
Building modules, stage 2.
MODPOST 1 modules
CC /tmp/stap8WlBZG/stap_b00d6e09dd729ff1650d3b351fbb314e_803.mod.o
LD [M] /tmp/stap8WlBZG/stap_b00d6e09dd729ff1650d3b351fbb314e_803.ko
Spawn waitpid result (0x0): 0
/root/.systemtap/cache/b0/stap_b00d6e09dd729ff1650d3b351fbb314e_803.ko
Pass 4: compiled C into "stap_b00d6e09dd729ff1650d3b351fbb314e_803.ko" in
25820usr/2630sys/30488real ms.
Cleaning cache, interval reached 2454 s > 300 s.
Copying /tmp/stap8WlBZG/stap_b00d6e09dd729ff1650d3b351fbb314e_803.ko to
/root/.systemtap/cache/b0/stap_b00d6e09dd729ff1650d3b351fbb314e_803.ko
Copying /tmp/stap8WlBZG/stap_b00d6e09dd729ff1650d3b351fbb314e_803_src.c to
/root/.systemtap/cache/b0/stap_b00d6e09dd729ff1650d3b351fbb314e_803.c
Copying /tmp/stap8WlBZG/stapconf_55d6411c6f9226322c4f3d007bff23d7_617.h to
/root/.systemtap/cache/55/stapconf_55d6411c6f9226322c4f3d007bff23d7_617.h
Running rm -rf /tmp/stap8WlBZG
Spawn waitpid result (0x0): 0
Removed temporary directory "/tmp/stap8WlBZG"
== gcc -v ==
Using built-in specs.
COLLECT_GCC=gcc
COLLECT_LTO_WRAPPER=/usr/lib/gcc/arm-linux-gnueabihf/4.7/lto-wrapper
Target: arm-linux-gnueabihf
Configured with: ../src/configure -v --with-pkgversion='Ubuntu/Linaro
4.7.3-1ubuntu1' --with-bugurl=file:///usr/share/doc/gcc-4.7/README.Bugs
--enable-languages=c,c++,go,fortran,objc,obj-c++ --prefix=/usr
--program-suffix=-4.7 --enable-shared --enable-linker-build-id
--libexecdir=/usr/lib --without-included-gettext --enable-threads=posix
--with-gxx-include-dir=/usr/include/c++/4.7 --libdir=/usr/lib --enable-nls
--with-sysroot=/ --enable-clocale=gnu --enable-libstdcxx-debug
--enable-libstdcxx-time=yes --enable-gnu-unique-object --disable-libitm
--enable-plugin --with-system-zlib --enable-objc-gc --with-cloog
--enable-cloog-backend=ppl --disable-cloog-version-check
--disable-ppl-version-check --enable-multiarch --enable-multilib
--disable-sjlj-exceptions --with-arch=armv7-a --with-fpu=vfpv3-d16
--with-float=hard --with-mode=thumb --disable-werror --enable-checking=release
--build=arm-linux-gnueabihf --host=arm-linux-gnueabihf
--target=arm-linux-gnueabihf
Thread model: posix
gcc version 4.7.3 (Ubuntu/Linaro 4.7.3-1ubuntu1)
== uname -a ==
Linux linaro-developer 3.11.0-rc5 #2 SMP PREEMPT Fri Sep 6 09:39:29 UTC 2013
armv7l armv7l armv7l GNU/Linux
== dmesg | egrep 'stap|systemtap' | tail -n 10 ==
== cat /proc/cpuinfo | egrep 'processor|vendor_id|model name' ==
processor : 0
model name : ARMv7 Processor rev 1 (v7l)
processor : 1
model name : ARMv7 Processor rev 1 (v7l)
== dpkg --list | egrep
'systemtap|elfutils|kernel|linux|gcc|dyninst|avahi|nss|nspr|dejagnu' | awk
'{print $2,$3}' | sort ==
cpufrequtils 008-1
dejagnu 1.5-3
elfutils 0.153-2ubuntu1
flash-kernel 3.0~rc.4ubuntu33+linaro4
gcc 4:4.7.3-1ubuntu10
gcc-4.7 4.7.3-1ubuntu1
gcc-4.7-base:armhf 4.7.3-1ubuntu1
input-utils 1.0-1
insserv 1.14.0-5ubuntu1
kmod 9-3ubuntu1
libaio1:armhf 0.3.109-3
libavahi-client3:armhf 0.6.31-1ubuntu3
libavahi-common-data:armhf 0.6.31-1ubuntu3
libavahi-common3:armhf 0.6.31-1ubuntu3
libcpufreq0 008-1
libdrm2:armhf 2.4.43-0ubuntu1
libgcc-4.7-dev:armhf 4.7.3-1ubuntu1
libgcc1:armhf 1:4.7.3-1ubuntu1
libnspr4:armhf 2:4.9.5-1ubuntu1
libnss3:armhf 2:3.14.3-0ubuntu1
libselinux1:armhf 2.1.9-5ubuntu1
linux-base 3.5ubuntu4
linux-headers-3.10.0-1-linaro-u8500 3.10.0-1.1ubuntu1~ci+130621194855
linux-headers-linaro-u8500 3.10.0.1.1ubuntu1
linux-image-3.11.0-rc7-linaro-u8500 3.11.0-rc7-linaro-u8500-2
linux-libc-dev:armhf 3.8.0-19.29
linux-sound-base 1.0.25+dfsg-0ubuntu4
linux-tools 3.8.0.19.35
openssh-client 1:6.1p1-4
openssh-server 1:6.1p1-4
openssl 1.0.1c-4ubuntu9~linaro4
rsyslog 5.8.11-2ubuntu2
systemtap 2.1-1~experimental1
systemtap-common 2.1-1~experimental1
systemtap-runtime 2.1-1~experimental1
uboot-mkimage 2012.04.01-2
udev 175-0ubuntu26
util-linux 2.20.1-5.1ubuntu8
== egrep 'PROBE|TRACE|MARKER|_DEBUG_|MODULE|FENTRY|_SIG'
/lib/modules/3.11.0-rc5/build/.config | grep -v not.set | sort | fmt -w 80 ==
CONFIG_ARM_KPROBES_TEST=m CONFIG_CONTEXT_SWITCH_TRACER=y
CONFIG_DEBUG_BUGVERBOSE=y CONFIG_DEBUG_FS=y CONFIG_DEBUG_INFO=y
CONFIG_DEBUG_KERNEL=y CONFIG_DEBUG_LL_INCLUDE="mach/debug-macro.S"
CONFIG_DEBUG_MEMORY_INIT=y CONFIG_DEBUG_PERF_USE_VMALLOC=y
CONFIG_DEBUG_PREEMPT=y CONFIG_DEBUG_USER=y CONFIG_DEBUG_WRITECOUNT=y
CONFIG_DYNAMIC_FTRACE=y CONFIG_FTRACE=y CONFIG_FTRACE_MCOUNT_RECORD=y
CONFIG_FTRACE_SYSCALLS=y CONFIG_FUNCTION_GRAPH_TRACER=y
CONFIG_FUNCTION_TRACER=y CONFIG_GENERIC_IRQ_PROBE=y
CONFIG_GENERIC_TRACER=y CONFIG_HAVE_ARCH_TRACEHOOK=y
CONFIG_HAVE_DEBUG_KMEMLEAK=y CONFIG_HAVE_DYNAMIC_FTRACE=y
CONFIG_HAVE_FTRACE_MCOUNT_RECORD=y CONFIG_HAVE_FUNCTION_GRAPH_TRACER=y
CONFIG_HAVE_FUNCTION_TRACER=y CONFIG_HAVE_KPROBES=y CONFIG_HAVE_KRETPROBES=y
CONFIG_HAVE_SYSCALL_TRACEPOINTS=y CONFIG_KPROBES=y CONFIG_KPROBE_EVENT=y
CONFIG_KRETPROBES=y CONFIG_MODULES=y CONFIG_MODULES_USE_ELF_REL=y
CONFIG_MODULE_UNLOAD=y CONFIG_NOP_TRACER=y CONFIG_OLD_SIGACTION=y
CONFIG_OLD_SIGSUSPEND3=y CONFIG_PROBE_EVENTS=y CONFIG_SIGNALFD=y
CONFIG_STACKTRACE=y CONFIG_STACKTRACE_SUPPORT=y CONFIG_STACK_TRACER=y
CONFIG_TRACEPOINTS=y CONFIG_TRACE_CLOCK=y CONFIG_TRACE_IRQFLAGS_SUPPORT=y
CONFIG_UX500_DEBUG_UART=2
== find /debugfs /proc /sys /dev /sys/kernel/debug -path '*kprobe*' -o -path
'*yama*' -type f 2>/dev/null | xargs grep -H . ==
grep: /sys/kernel/debug/kprobes: Is a directory
/sys/kernel/debug/kprobes/enabled:1
/sys/kernel/debug/kprobes/list:c012791c k vfs_fsync_range+0x0
/sys/kernel/debug/kprobes/list:c00fcb6c k vfs_writev+0x0
/sys/kernel/debug/kprobes/list:c011dad0 k vfs_setxattr+0x0
/sys/kernel/debug/kprobes/list:c0100950 k vfs_fstatat+0x0
/sys/kernel/debug/kprobes/list:c0108638 k vfs_unlink+0x0
/sys/kernel/debug/kprobes/list:c0108b40 k vfs_rename+0x0
/sys/kernel/debug/kprobes/list:c00fcbec k vfs_readv+0x0
/sys/kernel/debug/kprobes/list:c014418c k vfs_setlease+0x0
/sys/kernel/debug/kprobes/list:c00fa4ac k vfs_truncate+0x0
/sys/kernel/debug/kprobes/list:c011d130 k vfs_listxattr+0x0
/sys/kernel/debug/kprobes/list:c01008ec k vfs_fstat+0x0
/sys/kernel/debug/kprobes/list:c00fc1c4 k vfs_write+0x0
/sys/kernel/debug/kprobes/list:c0106988 k vfs_symlink+0x0
/sys/kernel/debug/kprobes/list:c0129148 k vfs_ustat+0x0
/sys/kernel/debug/kprobes/list:c0105e04 k vfs_readlink+0x0
/sys/kernel/debug/kprobes/list:c01072bc k vfs_follow_link+0x0
/sys/kernel/debug/kprobes/list:c011de00 k vfs_xattr_cmp+0x0
/sys/kernel/debug/kprobes/list:c01088d8 k vfs_rmdir+0x0
/sys/kernel/debug/kprobes/list:c0107390 k vfs_link+0x0
/sys/kernel/debug/kprobes/list:c01009f4 k vfs_lstat+0x0
/sys/kernel/debug/kprobes/list:c011d6b0 k vfs_getxattr+0x0
/sys/kernel/debug/kprobes/list:c0107538 k vfs_mknod+0x0
/sys/kernel/debug/kprobes/list:c00fc35c k vfs_read+0x0
/sys/kernel/debug/kprobes/list:c014311c k vfs_test_lock+0x0
/sys/kernel/debug/kprobes/list:c00fb508 k vfs_setpos+0x0
/sys/kernel/debug/kprobes/list:c01421b0 k vfs_cancel_lock+0x0
/sys/kernel/debug/kprobes/list:c011d894 k vfs_removexattr+0x0
/sys/kernel/debug/kprobes/list:c011851c k vfs_kern_mount+0x0
/sys/kernel/debug/kprobes/list:c011dd0c k vfs_getxattr_alloc+0x0
/sys/kernel/debug/kprobes/list:c0100a20 k vfs_stat+0x0
/sys/kernel/debug/kprobes/list:c0143b1c k vfs_lock_file+0x0
/sys/kernel/debug/kprobes/list:c01067e4 k vfs_create+0x0
/sys/kernel/debug/kprobes/list:c0450620 k vfs_path_lookup.part.33+0x0
/sys/kernel/debug/kprobes/list:c010089c k vfs_getattr+0x0
/sys/kernel/debug/kprobes/list:c00fb5b4 k vfs_llseek+0x0
/sys/kernel/debug/kprobes/list:c01068a8 k vfs_mkdir+0x0
/sys/kernel/debug/kprobes/list:c0127970 k vfs_fsync+0x0
/sys/kernel/debug/kprobes/list:c010845c k vfs_path_lookup+0x0
/sys/kernel/debug/kprobes/list:c0128eb8 k vfs_statfs+0x0
grep: /sys/kernel/debug/kprobes: Is a directory
/sys/kernel/debug/kprobes/enabled:1
/sys/kernel/debug/kprobes/list:c012791c k vfs_fsync_range+0x0
/sys/kernel/debug/kprobes/list:c00fcb6c k vfs_writev+0x0
/sys/kernel/debug/kprobes/list:c011dad0 k vfs_setxattr+0x0
/sys/kernel/debug/kprobes/list:c0100950 k vfs_fstatat+0x0
/sys/kernel/debug/kprobes/list:c0108638 k vfs_unlink+0x0
/sys/kernel/debug/kprobes/list:c0108b40 k vfs_rename+0x0
/sys/kernel/debug/kprobes/list:c00fcbec k vfs_readv+0x0
/sys/kernel/debug/kprobes/list:c014418c k vfs_setlease+0x0
/sys/kernel/debug/kprobes/list:c00fa4ac k vfs_truncate+0x0
/sys/kernel/debug/kprobes/list:c011d130 k vfs_listxattr+0x0
/sys/kernel/debug/kprobes/list:c01008ec k vfs_fstat+0x0
/sys/kernel/debug/kprobes/list:c00fc1c4 k vfs_write+0x0
/sys/kernel/debug/kprobes/list:c0106988 k vfs_symlink+0x0
/sys/kernel/debug/kprobes/list:c0129148 k vfs_ustat+0x0
/sys/kernel/debug/kprobes/list:c0105e04 k vfs_readlink+0x0
/sys/kernel/debug/kprobes/list:c01072bc k vfs_follow_link+0x0
/sys/kernel/debug/kprobes/list:c011de00 k vfs_xattr_cmp+0x0
/sys/kernel/debug/kprobes/list:c01088d8 k vfs_rmdir+0x0
/sys/kernel/debug/kprobes/list:c0107390 k vfs_link+0x0
/sys/kernel/debug/kprobes/list:c01009f4 k vfs_lstat+0x0
/sys/kernel/debug/kprobes/list:c011d6b0 k vfs_getxattr+0x0
/sys/kernel/debug/kprobes/list:c0107538 k vfs_mknod+0x0
/sys/kernel/debug/kprobes/list:c00fc35c k vfs_read+0x0
/sys/kernel/debug/kprobes/list:c014311c k vfs_test_lock+0x0
/sys/kernel/debug/kprobes/list:c00fb508 k vfs_setpos+0x0
/sys/kernel/debug/kprobes/list:c01421b0 k vfs_cancel_lock+0x0
/sys/kernel/debug/kprobes/list:c011d894 k vfs_removexattr+0x0
/sys/kernel/debug/kprobes/list:c011851c k vfs_kern_mount+0x0
/sys/kernel/debug/kprobes/list:c011dd0c k vfs_getxattr_alloc+0x0
/sys/kernel/debug/kprobes/list:c0100a20 k vfs_stat+0x0
/sys/kernel/debug/kprobes/list:c0143b1c k vfs_lock_file+0x0
/sys/kernel/debug/kprobes/list:c01067e4 k vfs_create+0x0
/sys/kernel/debug/kprobes/list:c0450620 k vfs_path_lookup.part.33+0x0
/sys/kernel/debug/kprobes/list:c010089c k vfs_getattr+0x0
/sys/kernel/debug/kprobes/list:c00fb5b4 k vfs_llseek+0x0
/sys/kernel/debug/kprobes/list:c01068a8 k vfs_mkdir+0x0
/sys/kernel/debug/kprobes/list:c0127970 k vfs_fsync+0x0
/sys/kernel/debug/kprobes/list:c010845c k vfs_path_lookup+0x0
/sys/kernel/debug/kprobes/list:c0128eb8 k vfs_statfs+0x0
== lsmod ==
Module Size Used by
stap_83df0379e451bb1ec824cc2edc7d8b76_9733 1536184 2
ipv6 294504 14
== avahi-browse -r -t _stap._tcp ==
sh: 1: avahi-browse: not found
== ifconfig -a ==
eth0 Link encap:Ethernet HWaddr 8e:87:6d:7a:ec:fd
inet addr:192.168.1.67 Bcast:192.168.1.255 Mask:255.255.255.0
inet6 addr: fe80::8c87:6dff:fe7a:ecfd/64 Scope:Link
UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
RX packets:24771 errors:0 dropped:0 overruns:0 frame:0
TX packets:12107 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:28416584 (28.4 MB) TX bytes:2191674 (2.1 MB)
Interrupt:50
lo Link encap:Local Loopback
inet addr:127.0.0.1 Mask:255.0.0.0
inet6 addr: ::1/128 Scope:Host
UP LOOPBACK RUNNING MTU:65536 Metric:1
RX packets:22 errors:0 dropped:0 overruns:0 frame:0
TX packets:22 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:0
RX bytes:1524 (1.5 KB) TX bytes:1524 (1.5 KB)
== eu-readelf -n /boot/vmlinuz-3.11.0-rc5 | grep Build ==
eu-readelf: failed reading '/boot/vmlinuz-3.11.0-rc5': not a valid ELF file
== eu-readelf -n /lib/modules/3.11.0-rc5/build/vmlinux | grep Build ==
Build ID: edf55984c0061126c3fd1fe628eb5ea2cc049314
== hexdump -C /sys/kernel/notes ==
00000000 04 00 00 00 14 00 00 00 03 00 00 00 47 4e 55 00 |............GNU.|
00000010 ed f5 59 84 c0 06 11 26 c3 fd 1f e6 28 eb 5e a2 |..Y....&....(.^.|
00000020 cc 04 93 14 |....|
00000024
== hexdump -C /sys/module/ipv6/notes/.note.gnu.build-id ==
00000000 04 00 00 00 14 00 00 00 03 00 00 00 47 4e 55 00 |............GNU.|
00000010 66 6b 24 3b 34 ba 4a 6d d6 d0 73 e1 f1 5d e1 4f |fk$;4.Jm..s..].O|
00000020 16 28 95 f4 |.(..|
00000024
== hexdump -C
/sys/module/stap_83df0379e451bb1ec824cc2edc7d8b76_9733/notes/.note.gnu.build-id
==
00000000 04 00 00 00 14 00 00 00 03 00 00 00 47 4e 55 00 |............GNU.|
00000010 2d ff ad 80 62 df 33 a6 7f 91 31 16 ea f7 c6 28 |-...b.3...1....(|
00000020 f5 3d 44 5f |.=D_|
00000024
--
You are receiving this mail because:
You are the assignee for the bug.
^ permalink raw reply [flat|nested] 10+ messages in thread
* [Bug testsuite/15964] poll_map.exp testcase timeout on ARM
2013-09-17 8:56 [Bug testsuite/15964] New: poll_map.exp testcase timeout on ARM naresh.kamboju at linaro dot org
@ 2013-09-17 9:11 ` mjw at redhat dot com
2013-09-17 10:19 ` wcohen at redhat dot com
` (7 subsequent siblings)
8 siblings, 0 replies; 10+ messages in thread
From: mjw at redhat dot com @ 2013-09-17 9:11 UTC (permalink / raw)
To: systemtap
https://sourceware.org/bugzilla/show_bug.cgi?id=15964
Mark Wielaard <mjw at redhat dot com> changed:
What |Removed |Added
----------------------------------------------------------------------------
CC| |mjw at redhat dot com
--- Comment #1 from Mark Wielaard <mjw at redhat dot com> ---
I do notice a recent commit that might help:
commit b313f94ed30b4342eb02def7d8e895f5d2af8008
Author: David Smith <dsmith@redhat.com>
Date: Thu Aug 20 08:41:03 2009 -0500
Two minor testcase fixes.
* testsuite/systemtap.base/poll_map.exp: Improved error handling.
* testsuite/systemtap.base/postgres.exp: Fixed typo.
diff --git a/testsuite/systemtap.base/poll_map.exp
b/testsuite/systemtap.base/poll_map.exp
index 5ade48e..85687b6 100644
--- a/testsuite/systemtap.base/poll_map.exp
+++ b/testsuite/systemtap.base/poll_map.exp
@@ -9,6 +9,6 @@ expect {
timeout { fail "$test (timeout)" }
eof { }
}
-close
+catch { close }
wait
if {$ok == 1} { pass "$test ($ok)" } { fail "$test ($ok)" }
--
You are receiving this mail because:
You are the assignee for the bug.
^ permalink raw reply [flat|nested] 10+ messages in thread
* [Bug testsuite/15964] poll_map.exp testcase timeout on ARM
2013-09-17 8:56 [Bug testsuite/15964] New: poll_map.exp testcase timeout on ARM naresh.kamboju at linaro dot org
2013-09-17 9:11 ` [Bug testsuite/15964] " mjw at redhat dot com
@ 2013-09-17 10:19 ` wcohen at redhat dot com
2013-09-17 13:29 ` dsmith at redhat dot com
` (6 subsequent siblings)
8 siblings, 0 replies; 10+ messages in thread
From: wcohen at redhat dot com @ 2013-09-17 10:19 UTC (permalink / raw)
To: systemtap
https://sourceware.org/bugzilla/show_bug.cgi?id=15964
William Cohen <wcohen at redhat dot com> changed:
What |Removed |Added
----------------------------------------------------------------------------
CC| |wcohen at redhat dot com
--
You are receiving this mail because:
You are the assignee for the bug.
^ permalink raw reply [flat|nested] 10+ messages in thread
* [Bug testsuite/15964] poll_map.exp testcase timeout on ARM
2013-09-17 8:56 [Bug testsuite/15964] New: poll_map.exp testcase timeout on ARM naresh.kamboju at linaro dot org
2013-09-17 9:11 ` [Bug testsuite/15964] " mjw at redhat dot com
2013-09-17 10:19 ` wcohen at redhat dot com
@ 2013-09-17 13:29 ` dsmith at redhat dot com
2013-09-18 6:58 ` naresh.kamboju at linaro dot org
` (5 subsequent siblings)
8 siblings, 0 replies; 10+ messages in thread
From: dsmith at redhat dot com @ 2013-09-17 13:29 UTC (permalink / raw)
To: systemtap
https://sourceware.org/bugzilla/show_bug.cgi?id=15964
David Smith <dsmith at redhat dot com> changed:
What |Removed |Added
----------------------------------------------------------------------------
CC| |dsmith at redhat dot com
--- Comment #2 from David Smith <dsmith at redhat dot com> ---
Hmm, it is possible we need to kill the running systemtap when we get a
timeout. Can you try the following (untested) patch?
diff --git a/testsuite/systemtap.base/poll_map.exp
b/testsuite/systemtap.base/poll_map.exp
index 85687b6..228cc9d 100644
--- a/testsuite/systemtap.base/poll_map.exp
+++ b/testsuite/systemtap.base/poll_map.exp
@@ -6,7 +6,10 @@ set ok 0
expect {
-timeout 400
-ex "SUCCESS" { incr ok }
- timeout { fail "$test (timeout)" }
+ timeout {
+ fail "$test (timeout)"
+ exec kill -INT -[exp_pid]
+ }
eof { }
}
catch { close }
--
You are receiving this mail because:
You are the assignee for the bug.
^ permalink raw reply [flat|nested] 10+ messages in thread
* [Bug testsuite/15964] poll_map.exp testcase timeout on ARM
2013-09-17 8:56 [Bug testsuite/15964] New: poll_map.exp testcase timeout on ARM naresh.kamboju at linaro dot org
` (2 preceding siblings ...)
2013-09-17 13:29 ` dsmith at redhat dot com
@ 2013-09-18 6:58 ` naresh.kamboju at linaro dot org
2017-10-11 10:44 ` mark at klomp dot org
` (4 subsequent siblings)
8 siblings, 0 replies; 10+ messages in thread
From: naresh.kamboju at linaro dot org @ 2013-09-18 6:58 UTC (permalink / raw)
To: systemtap
https://sourceware.org/bugzilla/show_bug.cgi?id=15964
--- Comment #3 from Naresh Kamboju <naresh.kamboju at linaro dot org> ---
Running ./systemtap.base/poll_map.exp ...
FAIL: poll_map (timeout)(In reply to David Smith from comment #2)
> Hmm, it is possible we need to kill the running systemtap when we get a
> timeout. Can you try the following (untested) patch?
>
> diff --git a/testsuite/systemtap.base/poll_map.exp
> b/testsuite/systemtap.base/poll_map.exp
> index 85687b6..228cc9d 100644
> --- a/testsuite/systemtap.base/poll_map.exp
> +++ b/testsuite/systemtap.base/poll_map.exp
> @@ -6,7 +6,10 @@ set ok 0
> expect {
> -timeout 400
> -ex "SUCCESS" { incr ok }
> - timeout { fail "$test (timeout)" }
> + timeout {
> + fail "$test (timeout)"
> + exec kill -INT -[exp_pid]
> + }
> eof { }
> }
> catch { close }
After applying above patch no difference in results.
Running ./systemtap.base/poll_map.exp ...
FAIL: poll_map (timeout)
--
You are receiving this mail because:
You are the assignee for the bug.
^ permalink raw reply [flat|nested] 10+ messages in thread
* [Bug testsuite/15964] poll_map.exp testcase timeout on ARM
2013-09-17 8:56 [Bug testsuite/15964] New: poll_map.exp testcase timeout on ARM naresh.kamboju at linaro dot org
` (3 preceding siblings ...)
2013-09-18 6:58 ` naresh.kamboju at linaro dot org
@ 2017-10-11 10:44 ` mark at klomp dot org
2017-10-11 10:49 ` mjw at fedoraproject dot org
` (3 subsequent siblings)
8 siblings, 0 replies; 10+ messages in thread
From: mark at klomp dot org @ 2017-10-11 10:44 UTC (permalink / raw)
To: systemtap
https://sourceware.org/bugzilla/show_bug.cgi?id=15964
Mark Wielaard <mark at klomp dot org> changed:
What |Removed |Added
----------------------------------------------------------------------------
CC| |mark at klomp dot org
--
You are receiving this mail because:
You are the assignee for the bug.
^ permalink raw reply [flat|nested] 10+ messages in thread
* [Bug testsuite/15964] poll_map.exp testcase timeout on ARM
2013-09-17 8:56 [Bug testsuite/15964] New: poll_map.exp testcase timeout on ARM naresh.kamboju at linaro dot org
` (4 preceding siblings ...)
2017-10-11 10:44 ` mark at klomp dot org
@ 2017-10-11 10:49 ` mjw at fedoraproject dot org
2024-02-13 18:59 ` wcohen at redhat dot com
` (2 subsequent siblings)
8 siblings, 0 replies; 10+ messages in thread
From: mjw at fedoraproject dot org @ 2017-10-11 10:49 UTC (permalink / raw)
To: systemtap
https://sourceware.org/bugzilla/show_bug.cgi?id=15964
Mark Wielaard <mjw at fedoraproject dot org> changed:
What |Removed |Added
----------------------------------------------------------------------------
CC|mjw at fedoraproject dot org |
--
You are receiving this mail because:
You are the assignee for the bug.
^ permalink raw reply [flat|nested] 10+ messages in thread
* [Bug testsuite/15964] poll_map.exp testcase timeout on ARM
2013-09-17 8:56 [Bug testsuite/15964] New: poll_map.exp testcase timeout on ARM naresh.kamboju at linaro dot org
` (5 preceding siblings ...)
2017-10-11 10:49 ` mjw at fedoraproject dot org
@ 2024-02-13 18:59 ` wcohen at redhat dot com
2024-02-13 19:46 ` fche at redhat dot com
2024-02-13 19:57 ` wcohen at redhat dot com
8 siblings, 0 replies; 10+ messages in thread
From: wcohen at redhat dot com @ 2024-02-13 18:59 UTC (permalink / raw)
To: systemtap
https://sourceware.org/bugzilla/show_bug.cgi?id=15964
--- Comment #4 from William Cohen <wcohen at redhat dot com> ---
More recently an x86_64 fedora39 machine I have running tests seems to hang on
poll_map.exp test also. It is a Lenovo Thinkpad P51 with 8 logical processors
(4 cores, each hyperthreaded). Compared to the ARM snowball system used to
originally file the report is pretty different, but both are multiprocessor
machines. The machine is running a current f39 kernel:
Linux cervelo 6.7.4-200.fc39.x86_64 #1 SMP PREEMPT_DYNAMIC Mon Feb 5 22:21:14
UTC 2024 x86_64 GNU/Linux
When trying to run the poll_map.stp script from the commandline the process
gets hung when cntl-c is hit. It doesn't exit and print "FAIL" or "SUCCESS":
$ stap -v -g /usr/share/systemtap/testsuite/systemtap.base/poll_map.stp
Pass 1: parsed user script and 731 library scripts using
2991856virt/2743848res/16000shr/2729288data kb, in 7430usr/900sys/8344real ms.
Pass 2: analyzed script: 79 probes, 8 functions, 0 embeds, 2 globals using
3236600virt/2988640res/17424shr/2974032data kb, in 2520usr/180sys/2712real ms.
Pass 3: translated to C into
"/tmp/stapJHWiaf/stap_472aafea38c440a3561a5017fd0961a9_20592_src.c" using
3236600virt/2988768res/17552shr/2974032data kb, in 210usr/100sys/307real ms.
Pass 4: compiled C into "stap_472aafea38c440a3561a5017fd0961a9_20592.ko" in
41040usr/4340sys/9567real ms.
Pass 5: starting run.
^C
Pressing cntl-c multiple times allow getting back to the command prompt:
[wcohen@cervelo ~]$ stap -v -g
/usr/share/systemtap/testsuite/systemtap.base/poll_map.stp
Pass 1: parsed user script and 731 library scripts using
2991856virt/2743668res/15872shr/2729288data kb, in 7490usr/870sys/8374real ms.
Pass 2: analyzed script: 79 probes, 8 functions, 0 embeds, 2 globals using
3236600virt/2988316res/17236shr/2974032data kb, in 2640usr/160sys/2816real ms.
Pass 3: using cached
/home/wcohen/.systemtap/cache/a4/stap_a41f3720dfed8a00a2cb71db2efb5832_20592.c
Pass 4: using cached
/home/wcohen/.systemtap/cache/a4/stap_a41f3720dfed8a00a2cb71db2efb5832_20592.ko
Pass 5: starting run.
^C^C^CToo many interrupts received, exiting.
However noticed in the lsmod that the stap module was still loaded.
Booted with "maxcpus=1" to limit the machine to one processor and the machine
still hung without output.
Tried similar experiment on a x86_64 qemu f39 image on another x86_64 laptop
and output was printed and the test exited. That was successful.
Looks like something specific to lenovo thinkpad p51. Did a fresh install of
x86_64 f39 on a vm with 4GB memory and 4 CPUs. it had the same behavior.
--
You are receiving this mail because:
You are the assignee for the bug.
^ permalink raw reply [flat|nested] 10+ messages in thread
* [Bug testsuite/15964] poll_map.exp testcase timeout on ARM
2013-09-17 8:56 [Bug testsuite/15964] New: poll_map.exp testcase timeout on ARM naresh.kamboju at linaro dot org
` (6 preceding siblings ...)
2024-02-13 18:59 ` wcohen at redhat dot com
@ 2024-02-13 19:46 ` fche at redhat dot com
2024-02-13 19:57 ` wcohen at redhat dot com
8 siblings, 0 replies; 10+ messages in thread
From: fche at redhat dot com @ 2024-02-13 19:46 UTC (permalink / raw)
To: systemtap
https://sourceware.org/bugzilla/show_bug.cgi?id=15964
Frank Ch. Eigler <fche at redhat dot com> changed:
What |Removed |Added
----------------------------------------------------------------------------
CC| |fche at redhat dot com
--- Comment #5 from Frank Ch. Eigler <fche at redhat dot com> ---
any sysrq-t type stack traces at the point of apparent hang?
--
You are receiving this mail because:
You are the assignee for the bug.
^ permalink raw reply [flat|nested] 10+ messages in thread
* [Bug testsuite/15964] poll_map.exp testcase timeout on ARM
2013-09-17 8:56 [Bug testsuite/15964] New: poll_map.exp testcase timeout on ARM naresh.kamboju at linaro dot org
` (7 preceding siblings ...)
2024-02-13 19:46 ` fche at redhat dot com
@ 2024-02-13 19:57 ` wcohen at redhat dot com
8 siblings, 0 replies; 10+ messages in thread
From: wcohen at redhat dot com @ 2024-02-13 19:57 UTC (permalink / raw)
To: systemtap
https://sourceware.org/bugzilla/show_bug.cgi?id=15964
--- Comment #6 from William Cohen <wcohen at redhat dot com> ---
Looking through the dmesg output there does seem a BUG reported:
[ 915.602397] goopoll: loading out-of-tree module taints kernel.
[ 915.602402] goopoll: module verification failed: signature and/or required
key missing - tainting kernel
[ 915.674121] goopoll (poll_map.stp): systemtap: 5.1/0.190, base:
ffffffffc0a48000, memory: 3712data/72text/15ctx/16486net/2246alloc kb, probes:
79
[ 915.766512] BUG: unable to handle page fault for address: ffffffff83d48260
[ 915.766518] #PF: supervisor read access in kernel mode
[ 915.766520] #PF: error_code(0x0000) - not-present page
[ 915.766522] PGD b427067 P4D b427067 PUD b428063 PMD 106470063 PTE
800ffffff42b7062
[ 915.766526] Oops: 0000 [#1] PREEMPT SMP PTI
[ 915.766528] CPU: 0 PID: 7447 Comm: stapio Tainted: G OE
6.7.4-200.fc39.x86_64 #1
[ 915.766531] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS
1.16.3-1.fc39 04/01/2014
[ 915.766532] RIP: 0010:arch_adjust_kprobe_addr+0x9/0x60
[ 915.766536] Code: cc cc cc 48 89 de 48 89 ef 5b 5d e9 f1 f8 ff ff 90 90 90
90 90 90 90 90 90 90 90 90 90 90 90 90 90 66 0f 1f 00 0f 1f 44 00 00 <8b> 07 3d
66 0f 1f 00 74 24 25 ff ff ff fe 41 b8 0c f0 e1 05 41 f7
[ 915.766538] RSP: 0018:ffffaf400558fd10 EFLAGS: 00010286
[ 915.766539] RAX: 0000000000000001 RBX: ffffffff83d48260 RCX:
ffffffff83d48260
[ 915.766541] RDX: ffffaf400558fd47 RSI: 0000000000000000 RDI:
ffffffff83d48260
[ 915.766542] RBP: ffffaf400558fd47 R08: fffffffffd2b7d9f R09:
ffffaf400558fd18
[ 915.766543] R10: 0000000000000000 R11: 0000000000033a77 R12:
0000000000000000
[ 915.766544] R13: 0000000000000000 R14: 0000000000000000 R15:
ffffffffc0a5b610
[ 915.766546] FS: 00007f8fb1182140(0000) GS:ffff8de9bbc00000(0000)
knlGS:0000000000000000
[ 915.766547] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 915.766549] CR2: ffffffff83d48260 CR3: 00000000629b4002 CR4:
0000000000370ef0
[ 915.766552] DR0: 0000000000000000 DR1: 0000000000000000 DR2:
0000000000000000
[ 915.766553] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7:
0000000000000400
[ 915.766554] Call Trace:
[ 915.766555] <TASK>
[ 915.766557] ? __die+0x23/0x70
[ 915.766561] ? __pfx_vfs_caches_init+0x10/0x10
[ 915.766564] ? page_fault_oops+0x171/0x4e0
[ 915.766572] ? __pfx_vfs_caches_init+0x10/0x10
[ 915.766575] ? exc_page_fault+0x175/0x180
[ 915.766597] ? asm_exc_page_fault+0x26/0x30
[ 915.766601] ? __pfx_vfs_caches_init+0x10/0x10
[ 915.766604] ? __pfx_vfs_caches_init+0x10/0x10
[ 915.766607] ? __pfx_vfs_caches_init+0x10/0x10
[ 915.766609] ? arch_adjust_kprobe_addr+0x9/0x60
[ 915.766611] _kprobe_addr+0x66/0x90
[ 915.766615] register_kprobe+0x44/0x690
[ 915.766618] stapkp_register_probe+0x124/0x180 [goopoll]
[ 915.766640] _stp_ctl_write_cmd+0x1066/0x2140 [goopoll]
[ 915.766648] ? __do_sys_clone3+0xe0/0x120
[ 915.766651] ? inode_security+0x22/0x60
[ 915.766654] proc_reg_write+0x5a/0xa0
[ 915.766657] vfs_write+0xef/0x400
[ 915.766660] ? switch_fpu_return+0x50/0xe0
[ 915.766662] ? __fget_light+0x85/0x100
[ 915.766665] ksys_write+0x6f/0xf0
[ 915.766667] do_syscall_64+0x61/0xe0
[ 915.766670] ? do_syscall_64+0x70/0xe0
[ 915.766671] ? exc_page_fault+0x7f/0x180
[ 915.766673] entry_SYSCALL_64_after_hwframe+0x6e/0x76
[ 915.766675] RIP: 0033:0x7f8fb128bccd
[ 915.766688] Code: e5 48 83 ec 20 48 89 55 e8 48 89 75 f0 89 7d f8 e8 58 1d
f8 ff 48 8b 55 e8 48 8b 75 f0 41 89 c0 8b 7d f8 b8 01 00 00 00 0f 05 <48> 3d 00
f0 ff ff 77 33 44 89 c7 48 89 45 f8 e8 af 1d f8 ff 48 8b
[ 915.766689] RSP: 002b:00007fffe5b7a5b0 EFLAGS: 00000293 ORIG_RAX:
0000000000000001
[ 915.766691] RAX: ffffffffffffffda RBX: 0000000000000008 RCX:
00007f8fb128bccd
[ 915.766692] RDX: 000000000000000c RSI: 00007fffe5b7a5e0 RDI:
0000000000000004
[ 915.766694] RBP: 00007fffe5b7a5d0 R08: 0000000000000000 R09:
00007fffe5b797a7
[ 915.766695] R10: 0000000000000008 R11: 0000000000000293 R12:
00007fffe5b7aa60
[ 915.766696] R13: 0000000000000000 R14: 0000000000000001 R15:
00007fffe5b7aae4
[ 915.766698] </TASK>
[ 915.766699] Modules linked in: goopoll(OE) uinput snd_seq_dummy snd_hrtimer
nf_conntrack_netbios_ns nf_conntrack_broadcast nft_fib_inet nft_fib_ipv4
nft_fib_ipv6 nft_fib nft_reject_inet nf_reject_ipv4 nf_reject_ipv6 nft_reject
nft_ct nft_chain_nat nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 rfkill
ip_set nf_tables nfnetlink qrtr intel_rapl_msr intel_rapl_common kvm_intel
snd_hda_codec_generic ledtrig_audio snd_hda_intel kvm snd_intel_dspcfg
snd_intel_sdw_acpi snd_hda_codec snd_hda_core snd_hwdep snd_seq snd_seq_device
snd_pcm irqbypass rapl iTCO_wdt intel_pmc_bxt iTCO_vendor_support snd_timer
sunrpc snd i2c_i801 pcspkr i2c_smbus lpc_ich soundcore pktcdvd virtio_balloon
binfmt_misc joydev loop zram crct10dif_pclmul crc32_pclmul crc32c_intel
polyval_clmulni polyval_generic ghash_clmulni_intel sha512_ssse3 sha256_ssse3
virtio_net virtio_gpu sha1_ssse3 virtio_console virtio_blk net_failover
failover virtio_dma_buf serio_raw ip6_tables ip_tables fuse qemu_fw_cfg
[ 915.766743] CR2: ffffffff83d48260
[ 915.766747] ---[ end trace 0000000000000000 ]---
[ 915.766748] RIP: 0010:arch_adjust_kprobe_addr+0x9/0x60
[ 915.766750] Code: cc cc cc 48 89 de 48 89 ef 5b 5d e9 f1 f8 ff ff 90 90 90
90 90 90 90 90 90 90 90 90 90 90 90 90 90 66 0f 1f 00 0f 1f 44 00 00 <8b> 07 3d
66 0f 1f 00 74 24 25 ff ff ff fe 41 b8 0c f0 e1 05 41 f7
[ 915.766751] RSP: 0018:ffffaf400558fd10 EFLAGS: 00010286
[ 915.766753] RAX: 0000000000000001 RBX: ffffffff83d48260 RCX:
ffffffff83d48260
[ 915.766754] RDX: ffffaf400558fd47 RSI: 0000000000000000 RDI:
ffffffff83d48260
[ 915.766755] RBP: ffffaf400558fd47 R08: fffffffffd2b7d9f R09:
ffffaf400558fd18
[ 915.766756] R10: 0000000000000000 R11: 0000000000033a77 R12:
0000000000000000
[ 915.766757] R13: 0000000000000000 R14: 0000000000000000 R15:
ffffffffc0a5b610
[ 915.766758] FS: 00007f8fb1182140(0000) GS:ffff8de9bbc00000(0000)
knlGS:0000000000000000
[ 915.766760] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 915.766761] CR2: ffffffff83d48260 CR3: 00000000629b4002 CR4:
0000000000370ef0
[ 915.766763] DR0: 0000000000000000 DR1: 0000000000000000 DR2:
0000000000000000
[ 915.766764] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7:
0000000000000400
[ 915.766766] note: stapio[7447] exited with irqs disabled
--
You are receiving this mail because:
You are the assignee for the bug.
^ permalink raw reply [flat|nested] 10+ messages in thread
end of thread, other threads:[~2024-02-13 19:57 UTC | newest]
Thread overview: 10+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2013-09-17 8:56 [Bug testsuite/15964] New: poll_map.exp testcase timeout on ARM naresh.kamboju at linaro dot org
2013-09-17 9:11 ` [Bug testsuite/15964] " mjw at redhat dot com
2013-09-17 10:19 ` wcohen at redhat dot com
2013-09-17 13:29 ` dsmith at redhat dot com
2013-09-18 6:58 ` naresh.kamboju at linaro dot org
2017-10-11 10:44 ` mark at klomp dot org
2017-10-11 10:49 ` mjw at fedoraproject dot org
2024-02-13 18:59 ` wcohen at redhat dot com
2024-02-13 19:46 ` fche at redhat dot com
2024-02-13 19:57 ` wcohen at redhat dot com
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for read-only IMAP folder(s) and NNTP newsgroup(s).