From: Ryszard J. <rys...@ce...> - 2006-03-17 11:30:24
|
Hi, I am using PerfSuite 0.6.1with perfctr-2.6.19 and with papi-3.2.1. I am = trying to profiling the Atlas simulation at CERN. It seems to work, but = I got stuck in one point. I run the profiling job by the command: export LD_PRELOAD=3Dsome libraries psrun -o output_file -e -p -c papi_cycles.xml python simulation.py = joboptions.py export LD_PRELOAD=3D"" In the output file from psprocess I can find most of libraries apart = from one (geant4 G4) which I expected to find. This library is not = inluded in the LD_PRELOAD variable. Functions from this library are very = frequently and hard used. I guess, that there are questions marks instead of the missed library in = the report. I have attached a small part from my raport. Profile Information =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D Class : PAPI Event : PAPI_TOT_CYC (Total cycles) Period : 50000 Samples : 18835979 Domain : user Run Time : 3759.06 (seconds) Min Self % : (all) Module Summary -------------------------------------------------------------------------= ------- Samples Self % Total % Module 4338437 23.03% 23.03% /usr/lib/libstdc++.so.5.0.3 4118852 21.87% 44.90% /lib/tls/libc-2.3.2.so 3633690 19.29% 64.19% /lib/tls/libm-2.3.2.so 1575474 8.36% 72.56% = /afs/cern.ch/sw/lcg/external/clhep/1.9.2.1/slc3_ia32_gcc323/lib/libCLHEP-= Random-1.9.2.1.so ..... =20 File Summary -------------------------------------------------------------------------= ------- Samples Self % Total % File 9508883 50.48% 50.48% ? 4893321 25.98% 76.46% ?? 1212717 6.44% 82.90% ../../../CLHEP/Random/src/JamesRandom.cc ..... Function Summary -------------------------------------------------------------------------= ------- Samples Self % Total % Function 4893321 25.98% 25.98% ?? 1212677 6.44% 32.42% CLHEP::HepJamesRandom::flat()=20 .... Function:File:Line Summary -------------------------------------------------------------------------= ------- Samples Self % Total % Function:File:Line 4893321 25.98% 25.98% ??:??:0 651638 3.46% 29.44% _int_malloc:?:0 592882 3.15% 32.59% atan2:?:0 462413 2.45% 35.04% __ieee754_log:?:0=20 As you can see at module summary 65% is reported as libstdc++, libc, = libm and in function summary. At the file summary 75% is unknown and at = function summary ~26% is unknown.=20 Do you have any ideas where the problem could be? Maybe this information could help. During profiling other small = programs, I saw the different behaviour between the application which is = dynamically linked with share libraries, and between one which uses the = dynamic loading of shared libraries. It is worth to mention, that both = applications run the same time. During running the application with the = dymamic loading the only 11 samples are collected, compared to 381998 = for the dynamically linked application. Below there are two small parts = of reports from profiling two applications which take advantage of the = same library: 1. Dynamically linked application Profile Information =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D Class : PAPI Event : PAPI_TOT_CYC (Total cycles) Period : 50000 Samples : 381998 Domain : user Run Time : 22.02 (seconds) Min Self % : (all) Module Summary -------------------------------------------------------------------------= ------- Samples Self % Total % Module 272392 71.31% 71.31% = /afs/cern.ch/user/o/oplaatl3/testdll/libhello2.so.1 109600 28.69% 100.00% = /afs/cern.ch/user/o/oplaatl3/testdll/libhello1.so.1 2 0.00% 100.00% /lib/tls/libc-2.3.2.so 2 0.00% 100.00% /lib/ld-2.3.2.so 1 0.00% 100.00% /usr/local/papi/lib/libpapi.so 1 0.00% 100.00% /usr/local/perfsuite/lib/libpsrun.so.0.0.0 File Summary -------------------------------------------------------------------------= ------- Samples Self % Total % File 360739 94.43% 94.43% ? 21259 5.57% 100.00% ?? Function Summary -------------------------------------------------------------------------= ------- Samples Self % Total % Function 194343 50.88% 50.88% sum 97454 25.51% 76.39% hello 68937 18.05% 94.43% count 21259 5.57% 100.00% ?? 1 0.00% 100.00% _IO_default_xsputn 1 0.00% 100.00% strlen 1 0.00% 100.00% _fini 1 0.00% 100.00% strcmp 1 0.00% 100.00% _dl_lookup_versioned_symbol =20 2. Application with the dynamic loading Profile Information =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D Class : PAPI Event : PAPI_TOT_CYC (Total cycles) Period : 50000 Samples : 11 Domain : user Run Time : 21.93 (seconds) Min Self % : (all) Module Summary -------------------------------------------------------------------------= ------- Samples Self % Total % Module 6 54.55% 54.55% /lib/ld-2.3.2.so 4 36.36% 90.91% /lib/tls/libc-2.3.2.so 1 9.09% 100.00% /lib/libdl-2.3.2.so File Summary -------------------------------------------------------------------------= ------- Samples Self % Total % File 10 90.91% 90.91% ? 1 9.09% 100.00% ?? Function Summary -------------------------------------------------------------------------= ------- Samples Self % Total % Function 2 18.18% 18.18% strcmp 1 9.09% 27.27% strlen 1 9.09% 36.36% _dl_close 1 9.09% 45.45% do_lookup 1 9.09% 54.55% fixup 1 9.09% 63.64% rtld_lock_default_lock_recursive 1 9.09% 72.73% _setjmp 1 9.09% 81.82% ?? 1 9.09% 90.91% dl_open_worker 1 9.09% 100.00% __write_nocancel=20 Thank you in advance for any help and comments. Best regards, Ryszard. |