From: <ree...@am...> - 2004-03-22 21:37:42
Attachments:
oprofiled.log.tar.gz
|
Hi, I am using oprofile-0.7.1 on linux-2.4.21. when I run oprofile I find lots of samples missing due to no map information. Please see the my log below. Checking oprofiled.log almost all of these samples have the pid of XFree86. When I compared the missed EIP value with the maps of XFree86, all of them are from the XFree86 map 0000000040000000-000000004043e000 rwxp 0000000000000000 00:00 0 Please see the attached oprofile.log for comparison. Since this map is not associated with an image file, it is not taken care of in oprofile. How is it possible for EIP has a value that is not associated with an image? Is this a bug? Any help appreciated. Thanks, Reeja --------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------- cmddhcp43:~ # opcontrol --reset ;opcontrol -V --event=CPU_CLK_UNHALTED:50000:0:1:1 --vmlinux=/home/backup/vmlinux-2.4.21-102-default --start; sleep 20s;opcontrol -V --shutdown;opreport KERNEL_RANGE ffffffff80100000,ffffffff802bbca5 Removing /var/lib/oprofile/opdev Removing /var/lib/oprofile/opnotedev Removing /var/lib/oprofile/ophashmapdev Doing mknod /var/lib/oprofile/opdev Doing mknod /var/lib/oprofile/opnotedev Doing mknod /var/lib/oprofile/ophashmapdev Parameters used: CPUTYPE 10 BUF_SIZE default value SEPARATE_LIB 0 SEPARATE_KERNEL 0 SEPARATE_THREAD 0 SEPARATE_CPU 0 VMLINUX /home/backup/vmlinux-2.4.21-102-default executing oprofiled --separate-lib=0 --separate-kernel=0 --separate-thread=0 --separate-cpu=0 --events=CPU_CLK_UNHALTED:118:0:50000:0:1:1, --vmlinux=/home/backup/vmlinux-2.4.21-102-default --kernel-range=ffffffff80100000,ffffffff802bbca5 --verbose Using 2.4 OProfile kernel interface. Events: CPU_CLK_UNHALTED:118:0:50000:0:1:1, OPD_PARSE_KERNEL_RANGE: kernel_start = ffffffff80100000, kernel_end = ffffffff802bbca5 Creating image: /home/backup/vmlinux-2.4.21-102-default (null), kernel 1, tid 0, tgid 0 Using log file /var/lib/oprofile/oprofiled.log Daemon started. Profiler running. Killing daemon. CPU: Hammer, speed 1595.63 MHz (estimated) Counted CPU_CLK_UNHALTED events (Cycles outside of halt state) with a unit mask of 0x00 (No unit mask) count 50000 CPU_CLK_UNHALT...| samples| %| ------------------ 10391 54.3804 vmlinux-2.4.21-102-default 5716 29.9142 libc.so.6 1296 6.7825 oprofiled 694 3.6320 libqt-mt.so.3.2.1 232 1.2142 ld-2.3.2.so 160 0.8373 bash 155 0.8112 insmod.old 123 0.6437 XFree86 69 0.3611 ISO8859-1.so 41 0.2146 libkdecore.so.4.1.0 39 0.2041 libstdc++.so.5.0.5 cmddhcp43:~ # cat /proc/1327/maps 00000000000a0000-00000000000c0000 rwxs 00000000000a0000 03:03 33462 /dev/mem 00000000000f0000-0000000000100000 rwxs 00000000000f0000 03:03 33462 /dev/mem 0000000000400000-000000000058b000 r-xp 0000000000000000 03:03 2064 /usr/X11R6/bin/XFree86 000000000068b000-00000000006eb000 rw-p 000000000018b000 03:03 2064 /usr/X11R6/bin/XFree86 00000000006eb000-0000000000e72000 rwxp 0000000000000000 00:00 0 0000000040000000-000000004043e000 rwxp 0000000000000000 00:00 0 0000002a95556000-0000002a9556b000 r-xp 0000000000000000 03:03 6821 /lib64/ld-2.3.2.so 0000002a9556b000-0000002a9556c000 rw-p 0000000000000000 00:00 0 0000002a9556c000-0000002a95570000 rw-s 00000000da000000 03:03 33462 /dev/mem 0000002a95570000-0000002a95580000 rw-s 00000000000a0000 03:03 33462 /dev/mem 0000002a9558c000-0000002a9564a000 rw-p 0000000000000000 00:00 0 0000002a9566b000-0000002a9566d000 rw-p 0000000000015000 03:03 6821 /lib64/ld-2.3.2.so 0000002a9566d000-0000002a9567b000 r-xp 0000000000000000 03:03 6970 /lib64/libz.so.1.1.4 0000002a9567b000-0000002a9576d000 ---p 000000000000e000 03:03 6970 /lib64/libz.so.1.1.4 0000002a9576d000-0000002a9577d000 rw-p 0000000000000000 03:03 6970 /lib64/libz.so.1.1.4 0000002a9577d000-0000002a957d3000 r-xp 0000000000000000 03:03 6830 /lib64/libm.so.6 0000002a957d3000-0000002a9587d000 ---p 0000000000056000 03:03 6830 /lib64/libm.so.6 0000002a9587d000-0000002a958d7000 rw-p 0000000000000000 03:03 6830 /lib64/libm.so.6 0000002a958d7000-0000002a958da000 r-xp 0000000000000000 03:03 6829 /lib64/libdl.so.2 0000002a958da000-0000002a959d7000 ---p 0000000000003000 03:03 6829 /lib64/libdl.so.2 0000002a959d7000-0000002a959da000 rw-p 0000000000000000 03:03 6829 /lib64/libdl.so.2 0000002a959da000-0000002a95afe000 r-xp 0000000000000000 03:03 6826 /lib64/libc.so.6 0000002a95afe000-0000002a95bda000 ---p 0000000000124000 03:03 6826 /lib64/libc.so.6 0000002a95bda000-0000002a95c18000 rw-p 0000000000100000 03:03 6826 /lib64/libc.so.6 0000002a95c18000-0000002a95c1c000 rw-p 0000000000000000 00:00 0 0000002a95c1c000-0000002a95c23000 r-xp 0000000000000000 03:03 2150 /usr/X11R6/lib64/modules/fonts/libfreetype.so 0000002a95c23000-0000002a95d1c000 ---p 0000000000007000 03:03 2150 /usr/X11R6/lib64/modules/fonts/libfreetype.so 0000002a95d1c000-0000002a95d24000 rw-p 0000000000000000 03:03 2150 /usr/X11R6/lib64/modules/fonts/libfreetype.so 0000002a95d24000-0000002a95d7b000 r-xp 0000000000000000 03:03 7240 /usr/lib64/libfreetype.so.6.3.3 0000002a95d7b000-0000002a95e24000 ---p 0000000000057000 03:03 7240 /usr/lib64/libfreetype.so.6.3.3 0000002a95e24000-0000002a95e89000 rw-p 0000000000000000 03:03 7240 /usr/lib64/libfreetype.so.6.3.3 0000002a95e89000-0000002a95e8a000 rw-p 0000000000000000 00:00 0 0000002a95e8a000-0000002a97e8a000 rw-s 00000000d8000000 03:03 33462 /dev/mem 0000002a97e8a000-0000002a9868a000 rw-s 00000000db000000 03:03 33462 /dev/mem 0000002a9868a000-0000002a9890d000 rw-p 0000000000000000 00:00 0 0000007fbffee000-0000007fc0000000 rwxp fffffffffffef000 00:00 0 <<oprofiled.log.tar.gz>> Reeja John Software Research and Development,AMD (408) 749 - 3289 |
From: Philippe E. <ph...@wa...> - 2004-03-22 22:54:26
|
On Mon, 22 Mar 2004 at 13:37 +0000, ree...@am... wrote: > Hi, > I am using oprofile-0.7.1 on linux-2.4.21. when I run oprofile I find lots of samples missing due to no map information. Please see the my log below. Checking oprofiled.log almost all of these samples have the pid of XFree86. When I compared the missed EIP value with the maps of XFree86, all of them are from the XFree86 map > > 0000000040000000-000000004043e000 rwxp 0000000000000000 00:00 0 > > Please see the attached oprofile.log for comparison. > Since this map is not associated with an image file, it is not taken care of in oprofile. > How is it possible for EIP has a value that is not associated with an image? Is this a bug? Known problem, I dunno what exactly do xfree, probably something like mmap an anon region then copy the right driver in this region so we can't bind them to a file and we drop these samples. It's not arch or kernel version dependant but xfree side. In the same way we lost sample coming from trampoline code, valgring or java dynamically generated code etc. regards, Phil |