[GE users] high CPU load for sge_qmaster

Stephan Grell - Sun Germany - SSG - Software Engineer stephan.grell at sun.com
Fri Apr 29 16:52:42 BST 2005


    [ The following text is in the "ISO-8859-1" character set. ]
    [ Your display is set for the "ISO-8859-10" character set.  ]
    [ Some special characters may be displayed incorrectly. ]

Sean Dilda wrote:

> Stephan Grell - Sun Germany - SSG - Software Engineer wrote:
>
>> Thanks for the information.
>>
>> Looking at your scheduler output, it seems that you have constantly 
>> finished jobs and starting jobs. This
>> results in a scheduler, which is scheduling all the time, if you have 
>> the flush parameters set and I think
>> you do.
>> The qping output shows, that there are 11 messages waiting to be 
>> processed. These are most likely
>> finished jobs or new ones. In other words, there is something to do 
>> for the qmaster.
>>
>>  From the data you send me, I would assume, that you have a busy 
>> system. Please monitor the
>> peneding messages. You could also change the scheduler configuration 
>> to slow down the scheduling
>> process.
>> If you want to know more about the origion of the pending messages, 
>> use qping -dump.
>>
>> I do not see a problem sofar.
>
>
> I've also experienced the high sge_qmaster load in 6.0u3.  Usually 
> when sge_qmaster starts its not a problem, but a few hours later, one 
> thread will shoot up to 100% cpu usage and it will never go down.  It 
> even stays up there when there aren't jobs pending and none are 
> finishing.
>
> I first noticed the problem about the time that sge_schedd was 
> crashing with parallel jobs were exiting.  I applied your patch and 
> that problem went away, but the CPU load didn't, and I kept forgetting 
> to report it.  When I first noticed it, it seemed that the load would 
> shoot up at the same time sge_schedd died, but I never truly verified 
> that.
>
> Here's my current information:
>
> [sean at head4 sean]$ qping -info head4 535 qmaster 1
> 04/29/2005 10:45:33:
> SIRM version:             0.1
> SIRM message id:          1
> start time:               04/06/2005 16:09:06 (1112818146)
> run time [s]:             1967787
> messages in read buffer:  0
> messages in write buffer: 0
> nr. of connected clients: 284
> status:                   0
> info:                     EDT: R (0.42) | TET: R (0.84) | MT: R (0.04) 
> | SIGT: R (1967763.74) | ok

You are right, there are no jobs in the system. Could you monitor the 
qping output? Is the MT: allways that low?
If there is nothing to do, I would except higher times than 0.4.
When the system is idel, as yours are, the number should be similar to;:

EDT:R(x) ~0.9
TET:R(x) > 1
MT:R(x) > 1

Do you know what triggers this behavior?
What operating system are you using?

Stephan

>
>
> As far as I can tell, there were no jobs submitted or pending during 
> the time the profiling was run.
>
>------------------------------------------------------------------------
>
>04/29/2005 10:41:07|schedd|head4|I|using param: "PROFILE=1"
>04/29/2005 10:41:08|schedd|head4|I|PROF: static urgency took 0.000 s
>04/29/2005 10:41:08|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.010, calc: 0.000 s
>04/29/2005 10:41:08|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/29/2005 10:41:08|schedd|head4|I|PROF: normalizing job tickets took 0.000 s
>04/29/2005 10:41:08|schedd|head4|I|PROF: create active job orders: 0.050 s
>04/29/2005 10:41:08|schedd|head4|I|PROF: job-order calculation took 0.130 s
>04/29/2005 10:41:08|schedd|head4|I|PROF: create pending job orders: 0.000 s
>04/29/2005 10:41:08|schedd|head4|I|PROF: scheduled in 0.210 (u 0.050 + s 0.000 = 0.050): 0 sequential, 0 parallel, 52 orders, 309 H, 336 Q, 504 QA, 0 J(qw), 49 J(r), 0 J(s), 0 J(h), 0 J(e), 0 J(x), 49 J(all), 59 C, 7 ACL, 4 PE, 48 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 168 gMes, 0 jMes
>04/29/2005 10:41:08|schedd|head4|I|PROF: send orders and cleanup took: 0.070 (u 0.010,s 0.000) s
>04/29/2005 10:41:08|schedd|head4|I|PROF: schedd run took: 0.390 s (init: 0.000 s, copy: 0.050 s, run:0.320, free: 0.020 s, jobs: 49, categories: 9)
>04/29/2005 10:41:09|schedd|head4|I|PROF: profiling summary:
>04/29/2005 10:41:09|schedd|head4|I|PROF: other          : wc =      0.030s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:41:09|schedd|head4|I|PROF: packing        : wc =      0.000s, utime =      0.010s, stime =      0.000s, utilization =   0%
>04/29/2005 10:41:09|schedd|head4|I|PROF: eventclient    : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:41:09|schedd|head4|I|PROF: mirror         : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:41:09|schedd|head4|I|PROF: gdi            : wc =      0.060s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:41:09|schedd|head4|I|PROF: ht-resize      : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:41:09|schedd|head4|I|PROF: scheduler      : wc =      0.080s, utime =      0.030s, stime =      0.000s, utilization =  38%
>04/29/2005 10:41:09|schedd|head4|I|PROF: pending ticket : wc =      0.120s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:41:09|schedd|head4|I|PROF: job sorting    : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:41:09|schedd|head4|I|PROF: job dispatching: wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:41:09|schedd|head4|I|PROF: send orders    : wc =      0.010s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:41:09|schedd|head4|I|PROF: scheduler event: wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:41:09|schedd|head4|I|PROF: copy lists     : wc =      0.110s, utime =      0.070s, stime =      0.000s, utilization =  64%
>04/29/2005 10:41:09|schedd|head4|I|PROF: total          : wc =      0.520s, utime =      0.130s, stime =      0.000s, utilization =  25%
>04/29/2005 10:41:10|schedd|head4|I|PROF: sge_mirror processed 154 events in 0.000 s
>04/29/2005 10:41:12|schedd|head4|I|PROF: static urgency took 0.000 s
>04/29/2005 10:41:12|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.010 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/29/2005 10:41:12|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/29/2005 10:41:12|schedd|head4|I|PROF: normalizing job tickets took 0.000 s
>04/29/2005 10:41:12|schedd|head4|I|PROF: create active job orders: 0.080 s
>04/29/2005 10:41:12|schedd|head4|I|PROF: job-order calculation took 0.190 s
>04/29/2005 10:41:12|schedd|head4|I|PROF: create pending job orders: 0.000 s
>04/29/2005 10:41:12|schedd|head4|I|PROF: scheduled in 0.310 (u 0.050 + s 0.000 = 0.050): 0 sequential, 0 parallel, 52 orders, 309 H, 336 Q, 504 QA, 0 J(qw), 49 J(r), 0 J(s), 0 J(h), 0 J(e), 0 J(x), 49 J(all), 59 C, 7 ACL, 4 PE, 48 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 168 gMes, 0 jMes
>04/29/2005 10:41:12|schedd|head4|I|PROF: send orders and cleanup took: 0.100 (u 0.020,s 0.000) s
>04/29/2005 10:41:12|schedd|head4|I|PROF: schedd run took: 1.950 s (init: 1.390 s, copy: 0.090 s, run:0.450, free: 0.020 s, jobs: 49, categories: 9)
>04/29/2005 10:41:49|schedd|head4|I|PROF: sge_mirror processed 520 events in 0.010 s
>04/29/2005 10:41:49|schedd|head4|I|PROF: static urgency took 0.000 s
>04/29/2005 10:41:49|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/29/2005 10:41:49|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/29/2005 10:41:49|schedd|head4|I|PROF: normalizing job tickets took 0.000 s
>04/29/2005 10:41:49|schedd|head4|I|PROF: create active job orders: 0.050 s
>04/29/2005 10:41:49|schedd|head4|I|PROF: job-order calculation took 0.120 s
>04/29/2005 10:41:49|schedd|head4|I|PROF: create pending job orders: 0.000 s
>04/29/2005 10:41:49|schedd|head4|I|PROF: scheduled in 0.200 (u 0.020 + s 0.000 = 0.020): 0 sequential, 0 parallel, 52 orders, 309 H, 336 Q, 504 QA, 0 J(qw), 49 J(r), 0 J(s), 0 J(h), 0 J(e), 0 J(x), 49 J(all), 59 C, 7 ACL, 4 PE, 48 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 168 gMes, 0 jMes
>04/29/2005 10:41:49|schedd|head4|I|PROF: send orders and cleanup took: 0.100 (u 0.010,s 0.000) s
>04/29/2005 10:41:49|schedd|head4|I|PROF: schedd run took: 0.440 s (init: 0.000 s, copy: 0.070 s, run:0.360, free: 0.010 s, jobs: 49, categories: 9)
>04/29/2005 10:42:29|schedd|head4|I|PROF: sge_mirror processed 536 events in 0.010 s
>04/29/2005 10:42:29|schedd|head4|I|PROF: static urgency took 0.000 s
>04/29/2005 10:42:29|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/29/2005 10:42:29|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/29/2005 10:42:29|schedd|head4|I|PROF: normalizing job tickets took 0.000 s
>04/29/2005 10:42:29|schedd|head4|I|PROF: create active job orders: 0.050 s
>04/29/2005 10:42:29|schedd|head4|I|PROF: job-order calculation took 0.120 s
>04/29/2005 10:42:29|schedd|head4|I|PROF: create pending job orders: 0.000 s
>04/29/2005 10:42:29|schedd|head4|I|PROF: scheduled in 0.210 (u 0.040 + s 0.000 = 0.040): 0 sequential, 0 parallel, 52 orders, 309 H, 336 Q, 504 QA, 0 J(qw), 49 J(r), 0 J(s), 0 J(h), 0 J(e), 0 J(x), 49 J(all), 59 C, 7 ACL, 4 PE, 48 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 168 gMes, 0 jMes
>04/29/2005 10:42:30|schedd|head4|I|PROF: send orders and cleanup took: 0.830 (u 0.010,s 0.000) s
>04/29/2005 10:42:30|schedd|head4|I|PROF: schedd run took: 1.200 s (init: 0.000 s, copy: 0.060 s, run:1.120, free: 0.020 s, jobs: 49, categories: 9)
>04/29/2005 10:42:30|schedd|head4|I|PROF: profiling summary:
>04/29/2005 10:42:30|schedd|head4|I|PROF: other          : wc =      0.680s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:42:30|schedd|head4|I|PROF: packing        : wc =      0.110s, utime =      0.120s, stime =      0.000s, utilization = 109%
>04/29/2005 10:42:30|schedd|head4|I|PROF: eventclient    : wc =      0.010s, utime =      0.010s, stime =      0.000s, utilization = 100%
>04/29/2005 10:42:30|schedd|head4|I|PROF: mirror         : wc =      0.020s, utime =      0.020s, stime =      0.000s, utilization = 100%
>04/29/2005 10:42:30|schedd|head4|I|PROF: gdi            : wc =     78.160s, utime =      0.090s, stime =      0.000s, utilization =   0%
>04/29/2005 10:42:30|schedd|head4|I|PROF: ht-resize      : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:42:30|schedd|head4|I|PROF: scheduler      : wc =      0.370s, utime =      0.120s, stime =      0.000s, utilization =  32%
>04/29/2005 10:42:30|schedd|head4|I|PROF: pending ticket : wc =      0.540s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:42:30|schedd|head4|I|PROF: job sorting    : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:42:30|schedd|head4|I|PROF: job dispatching: wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:42:30|schedd|head4|I|PROF: send orders    : wc =      0.010s, utime =      0.010s, stime =      0.000s, utilization = 100%
>04/29/2005 10:42:30|schedd|head4|I|PROF: scheduler event: wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:42:30|schedd|head4|I|PROF: copy lists     : wc =      1.930s, utime =      0.370s, stime =      0.000s, utilization =  19%
>04/29/2005 10:42:30|schedd|head4|I|PROF: total          : wc =     82.310s, utime =      0.780s, stime =      0.000s, utilization =   1%
>04/29/2005 10:43:09|schedd|head4|I|PROF: sge_mirror processed 536 events in 0.010 s
>04/29/2005 10:43:09|schedd|head4|I|PROF: static urgency took 0.000 s
>04/29/2005 10:43:09|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/29/2005 10:43:09|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/29/2005 10:43:09|schedd|head4|I|PROF: normalizing job tickets took 0.000 s
>04/29/2005 10:43:09|schedd|head4|I|PROF: create active job orders: 0.060 s
>04/29/2005 10:43:09|schedd|head4|I|PROF: job-order calculation took 0.150 s
>04/29/2005 10:43:09|schedd|head4|I|PROF: create pending job orders: 0.000 s
>04/29/2005 10:43:09|schedd|head4|I|PROF: scheduled in 0.250 (u 0.050 + s 0.000 = 0.050): 0 sequential, 0 parallel, 52 orders, 309 H, 336 Q, 504 QA, 0 J(qw), 49 J(r), 0 J(s), 0 J(h), 0 J(e), 0 J(x), 49 J(all), 59 C, 7 ACL, 4 PE, 48 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 168 gMes, 0 jMes
>04/29/2005 10:43:09|schedd|head4|I|PROF: send orders and cleanup took: 0.070 (u 0.020,s 0.000) s
>04/29/2005 10:43:09|schedd|head4|I|PROF: schedd run took: 0.480 s (init: 0.000 s, copy: 0.090 s, run:0.370, free: 0.020 s, jobs: 49, categories: 9)
>04/29/2005 10:43:49|schedd|head4|I|PROF: sge_mirror processed 536 events in 0.010 s
>04/29/2005 10:43:49|schedd|head4|I|PROF: static urgency took 0.000 s
>04/29/2005 10:43:49|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.010 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/29/2005 10:43:49|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.010, calc: 0.000 s
>04/29/2005 10:43:49|schedd|head4|I|PROF: normalizing job tickets took 0.000 s
>04/29/2005 10:43:49|schedd|head4|I|PROF: create active job orders: 0.050 s
>04/29/2005 10:43:49|schedd|head4|I|PROF: job-order calculation took 0.140 s
>04/29/2005 10:43:49|schedd|head4|I|PROF: create pending job orders: 0.000 s
>04/29/2005 10:43:49|schedd|head4|I|PROF: scheduled in 0.240 (u 0.070 + s 0.000 = 0.070): 0 sequential, 0 parallel, 52 orders, 309 H, 336 Q, 504 QA, 0 J(qw), 49 J(r), 0 J(s), 0 J(h), 0 J(e), 0 J(x), 49 J(all), 59 C, 7 ACL, 4 PE, 48 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 168 gMes, 0 jMes
>04/29/2005 10:43:49|schedd|head4|I|PROF: send orders and cleanup took: 0.060 (u 0.020,s 0.000) s
>04/29/2005 10:43:49|schedd|head4|I|PROF: schedd run took: 0.480 s (init: 0.000 s, copy: 0.100 s, run:0.360, free: 0.020 s, jobs: 49, categories: 9)
>04/29/2005 10:43:49|schedd|head4|I|PROF: profiling summary:
>04/29/2005 10:43:49|schedd|head4|I|PROF: other          : wc =      1.190s, utime =      0.000s, stime =      0.020s, utilization =   2%
>04/29/2005 10:43:49|schedd|head4|I|PROF: packing        : wc =      0.220s, utime =      0.220s, stime =      0.000s, utilization = 100%
>04/29/2005 10:43:49|schedd|head4|I|PROF: eventclient    : wc =      0.020s, utime =      0.040s, stime =      0.000s, utilization = 200%
>04/29/2005 10:43:49|schedd|head4|I|PROF: mirror         : wc =      0.040s, utime =      0.040s, stime =      0.000s, utilization = 100%
>04/29/2005 10:43:49|schedd|head4|I|PROF: gdi            : wc =    155.900s, utime =      0.170s, stime =      0.020s, utilization =   0%
>04/29/2005 10:43:49|schedd|head4|I|PROF: ht-resize      : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:43:49|schedd|head4|I|PROF: scheduler      : wc =      0.570s, utime =      0.220s, stime =      0.000s, utilization =  39%
>04/29/2005 10:43:49|schedd|head4|I|PROF: pending ticket : wc =      0.810s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:43:49|schedd|head4|I|PROF: job sorting    : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:43:49|schedd|head4|I|PROF: job dispatching: wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:43:49|schedd|head4|I|PROF: send orders    : wc =      0.010s, utime =      0.010s, stime =      0.000s, utilization = 100%
>04/29/2005 10:43:50|schedd|head4|I|PROF: scheduler event: wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:43:50|schedd|head4|I|PROF: copy lists     : wc =      2.270s, utime =      0.590s, stime =      0.000s, utilization =  26%
>04/29/2005 10:43:50|schedd|head4|I|PROF: total          : wc =    161.750s, utime =      1.350s, stime =      0.040s, utilization =   1%
>04/29/2005 10:44:29|schedd|head4|I|PROF: sge_mirror processed 536 events in 0.010 s
>04/29/2005 10:44:29|schedd|head4|I|PROF: static urgency took 0.000 s
>04/29/2005 10:44:29|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.010, calc: 0.000 s
>04/29/2005 10:44:29|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/29/2005 10:44:29|schedd|head4|I|PROF: normalizing job tickets took 0.000 s
>04/29/2005 10:44:29|schedd|head4|I|PROF: create active job orders: 0.060 s
>04/29/2005 10:44:29|schedd|head4|I|PROF: job-order calculation took 0.160 s
>04/29/2005 10:44:29|schedd|head4|I|PROF: create pending job orders: 0.000 s
>04/29/2005 10:44:29|schedd|head4|I|PROF: scheduled in 0.250 (u 0.050 + s 0.000 = 0.050): 0 sequential, 0 parallel, 52 orders, 309 H, 336 Q, 504 QA, 0 J(qw), 49 J(r), 0 J(s), 0 J(h), 0 J(e), 0 J(x), 49 J(all), 59 C, 7 ACL, 4 PE, 48 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 168 gMes, 0 jMes
>04/29/2005 10:44:29|schedd|head4|I|PROF: send orders and cleanup took: 0.060 (u 0.020,s 0.000) s
>04/29/2005 10:44:29|schedd|head4|I|PROF: schedd run took: 0.490 s (init: 0.000 s, copy: 0.100 s, run:0.360, free: 0.030 s, jobs: 49, categories: 9)
>04/29/2005 10:45:09|schedd|head4|I|PROF: sge_mirror processed 536 events in 0.010 s
>04/29/2005 10:45:09|schedd|head4|I|PROF: static urgency took 0.000 s
>04/29/2005 10:45:09|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.010 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/29/2005 10:45:09|schedd|head4|I|PROF: job ticket calculation: init: 0.000 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.010, calc: 0.000 s
>04/29/2005 10:45:09|schedd|head4|I|PROF: normalizing job tickets took 0.000 s
>04/29/2005 10:45:09|schedd|head4|I|PROF: create active job orders: 0.050 s
>04/29/2005 10:45:09|schedd|head4|I|PROF: job-order calculation took 0.140 s
>04/29/2005 10:45:09|schedd|head4|I|PROF: create pending job orders: 0.000 s
>04/29/2005 10:45:09|schedd|head4|I|PROF: scheduled in 0.230 (u 0.040 + s 0.000 = 0.040): 0 sequential, 0 parallel, 52 orders, 309 H, 336 Q, 504 QA, 0 J(qw), 49 J(r), 0 J(s), 0 J(h), 0 J(e), 0 J(x), 49 J(all), 59 C, 7 ACL, 4 PE, 48 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 168 gMes, 0 jMes
>04/29/2005 10:45:09|schedd|head4|I|PROF: send orders and cleanup took: 0.050 (u 0.010,s 0.000) s
>04/29/2005 10:45:09|schedd|head4|I|PROF: schedd run took: 0.420 s (init: 0.000 s, copy: 0.070 s, run:0.340, free: 0.010 s, jobs: 49, categories: 9)
>04/29/2005 10:45:09|schedd|head4|I|PROF: profiling summary:
>04/29/2005 10:45:09|schedd|head4|I|PROF: other          : wc =      1.660s, utime =      0.000s, stime =      0.020s, utilization =   1%
>04/29/2005 10:45:09|schedd|head4|I|PROF: packing        : wc =      0.330s, utime =      0.340s, stime =      0.000s, utilization = 103%
>04/29/2005 10:45:09|schedd|head4|I|PROF: eventclient    : wc =      0.030s, utime =      0.040s, stime =      0.000s, utilization = 133%
>04/29/2005 10:45:09|schedd|head4|I|PROF: mirror         : wc =      0.060s, utime =      0.060s, stime =      0.000s, utilization = 100%
>04/29/2005 10:45:09|schedd|head4|I|PROF: gdi            : wc =    234.420s, utime =      0.230s, stime =      0.030s, utilization =   0%
>04/29/2005 10:45:09|schedd|head4|I|PROF: ht-resize      : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:45:09|schedd|head4|I|PROF: scheduler      : wc =      0.750s, utime =      0.290s, stime =      0.000s, utilization =  39%
>04/29/2005 10:45:09|schedd|head4|I|PROF: pending ticket : wc =      1.080s, utime =      0.010s, stime =      0.000s, utilization =   1%
>04/29/2005 10:45:09|schedd|head4|I|PROF: job sorting    : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:45:09|schedd|head4|I|PROF: job dispatching: wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:45:10|schedd|head4|I|PROF: send orders    : wc =      0.010s, utime =      0.020s, stime =      0.000s, utilization = 200%
>04/29/2005 10:45:10|schedd|head4|I|PROF: scheduler event: wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/29/2005 10:45:10|schedd|head4|I|PROF: copy lists     : wc =      2.590s, utime =      0.790s, stime =      0.000s, utilization =  31%
>04/29/2005 10:45:10|schedd|head4|I|PROF: total          : wc =    241.900s, utime =      1.860s, stime =      0.050s, utilization =   1%
>04/29/2005 10:45:31|schedd|head4|I|PROF: sge_mirror processed 150 events in 1.110 s
>
>  
>
>------------------------------------------------------------------------
>
>---------------------------------------------------------------------
>To unsubscribe, e-mail: users-unsubscribe at gridengine.sunsource.net
>For additional commands, e-mail: users-help at gridengine.sunsource.net
>  
>


---------------------------------------------------------------------
To unsubscribe, e-mail: users-unsubscribe at gridengine.sunsource.net
For additional commands, e-mail: users-help at gridengine.sunsource.net




More information about the gridengine-users mailing list