New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Improve usability of performance counter timestamps #337

Closed
brycelelbach opened this Issue Jul 10, 2012 · 2 comments

Comments

Projects
None yet
1 participant
@brycelelbach
Member

brycelelbach commented Jul 10, 2012

[reported by maciek] [Trac time Mon Feb 27 15:17:52 2012] Timestamps printed along with the values of performance counters should:

  • be human readable (i.e. be explicitly expressed in units with sensible physical interpretation, such as seconds),
  • represent timing values that are of relevance to the programmer, for example, since the approximate start of the application.
@brycelelbach

This comment has been minimized.

Show comment
Hide comment
@brycelelbach

brycelelbach Jul 10, 2012

Member

[comment by blelbach] [Trac time Tue Feb 28 15:27:26 2012] ```
[15:24:24]:wash@vega:/home/wash/hpx/gcc-4.6.2-release$ bin/sheneos_test --file /shared/hdf5/sheneos_220r_180t_50y_extT_analmu_20100322_SVNr28.h5 -0 -l2 --hpx:print-counter '/parcels{locality#0/total}/count/sent' --hpx:print-counter-interval 100
/counters/parcels{locality#0/total}/count/sent,339185.000892[s],19
Seed: 1330442756
/counters/parcels{locality#0/total}/count/sent,0.100122[s],40
/counters/parcels{locality#0/total}/count/sent,0.200288[s],56
/counters/parcels{locality#0/total}/count/sent,0.351689[s],67
Created interpolator: 1.16246 [s]
/counters/parcels{locality#0/total}/count/sent,1.670500[s],7696
/counters/parcels{locality#0/total}/count/sent,1.800293[s],7696
/counters/parcels{locality#0/total}/count/sent,1.900875[s],7713
/counters/parcels{locality#0/total}/count/sent,2.007101[s],7746
/counters/parcels{locality#0/total}/count/sent,2.107438[s],7786
/counters/parcels{locality#0/total}/count/sent,2.231168[s],7790
/counters/parcels{locality#0/total}/count/sent,2.342981[s],7795
/counters/parcels{locality#0/total}/count/sent,2.453169[s],7800
/counters/parcels{locality#0/total}/count/sent,2.568018[s],7805
/counters/parcels{locality#0/total}/count/sent,2.680491[s],7810
/counters/parcels{locality#0/total}/count/sent,2.803128[s],7814
/counters/parcels{locality#0/total}/count/sent,2.911239[s],7817
/counters/parcels{locality#0/total}/count/sent,3.039357[s],7822
Finished task 0: 1.84883 [s]
/counters/parcels{locality#0/total}/count/sent,3.146645[s],7864
/counters/parcels{locality#0/total}/count/sent,3.287189[s],8055
/counters/parcels{locality#0/total}/count/sent,3.393165[s],8227
Finished task 1: 2.28737 [s]
Completed tests: 2.28743 [s]
/counters/parcels{locality#0/total}/count/sent,3.493528[s],8228


So this works, but the first time value is drastically off.
Member

brycelelbach commented Jul 10, 2012

[comment by blelbach] [Trac time Tue Feb 28 15:27:26 2012] ```
[15:24:24]:wash@vega:/home/wash/hpx/gcc-4.6.2-release$ bin/sheneos_test --file /shared/hdf5/sheneos_220r_180t_50y_extT_analmu_20100322_SVNr28.h5 -0 -l2 --hpx:print-counter '/parcels{locality#0/total}/count/sent' --hpx:print-counter-interval 100
/counters/parcels{locality#0/total}/count/sent,339185.000892[s],19
Seed: 1330442756
/counters/parcels{locality#0/total}/count/sent,0.100122[s],40
/counters/parcels{locality#0/total}/count/sent,0.200288[s],56
/counters/parcels{locality#0/total}/count/sent,0.351689[s],67
Created interpolator: 1.16246 [s]
/counters/parcels{locality#0/total}/count/sent,1.670500[s],7696
/counters/parcels{locality#0/total}/count/sent,1.800293[s],7696
/counters/parcels{locality#0/total}/count/sent,1.900875[s],7713
/counters/parcels{locality#0/total}/count/sent,2.007101[s],7746
/counters/parcels{locality#0/total}/count/sent,2.107438[s],7786
/counters/parcels{locality#0/total}/count/sent,2.231168[s],7790
/counters/parcels{locality#0/total}/count/sent,2.342981[s],7795
/counters/parcels{locality#0/total}/count/sent,2.453169[s],7800
/counters/parcels{locality#0/total}/count/sent,2.568018[s],7805
/counters/parcels{locality#0/total}/count/sent,2.680491[s],7810
/counters/parcels{locality#0/total}/count/sent,2.803128[s],7814
/counters/parcels{locality#0/total}/count/sent,2.911239[s],7817
/counters/parcels{locality#0/total}/count/sent,3.039357[s],7822
Finished task 0: 1.84883 [s]
/counters/parcels{locality#0/total}/count/sent,3.146645[s],7864
/counters/parcels{locality#0/total}/count/sent,3.287189[s],8055
/counters/parcels{locality#0/total}/count/sent,3.393165[s],8227
Finished task 1: 2.28737 [s]
Completed tests: 2.28743 [s]
/counters/parcels{locality#0/total}/count/sent,3.493528[s],8228


So this works, but the first time value is drastically off.
@brycelelbach

This comment has been minimized.

Show comment
Hide comment
@brycelelbach

brycelelbach Jul 10, 2012

Member

[comment by blelbach] [Trac time Tue Feb 28 21:44:13 2012] r7212

Member

brycelelbach commented Jul 10, 2012

[comment by blelbach] [Trac time Tue Feb 28 21:44:13 2012] r7212

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment