[GE users] high CPU load for sge_qmaster

Stephan Grell - Sun Germany - SSG - Software Engineer stephan.grell at sun.com
Fri Apr 29 07:50:07 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. ]

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.

Cheers,
Stephan

Göran Uddeborg wrote:

>Stephan Grell - Sun Germany - SSG - Software Engineer writes:
>  
>
>>can you take a look at the qmaster using qping when it is really
>>busy? Would be nice to know, if there is a lot of communication
>>going on.
>>    
>>
>
>I guess you asked Kees, but since we see the same problem:
>
>I restarted our sge_master earlier today, but this time it quickly
>entered into the "busy state".  What invocation (flags) is it you
>want with qping?  Like this?
>
>    morgantown> qping -info wake 536 qmaster 1
>    04/28/2005 16:12:34:
>    SIRM version:             0.1
>    SIRM message id:          1
>    start time:               04/28/2005 14:52:09 (1114692729)
>    run time [s]:             4825
>    messages in read buffer:  11
>    messages in write buffer: 0
>    nr. of connected clients: 120
>    status:                   0
>    info:                     EDT: R (0.25) | TET: R (0.38) | MT: R (0.01) | SIGT: R (4819.42) | ok
>
>  
>
>>A output of the scheduler profiling would also be very very helpfull.
>>It would be enough to have 2 or 3 scheduling runs in the profiling
>>log.
>>    
>>
>
>I did "qconf -msconf", set "params" to "PROFILE=1", and picked a
>number of lines from $SGE_ROOT/$SGE_CELL/qmaster/schedd/messages.  Was
>that what you meant?  I attach the messages generated.
>
>  
>
>------------------------------------------------------------------------
>
>04/28/2005 16:17:03|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:17:03|schedd|wake|I|PROF: job ticket calculation: init: 0.010 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/28/2005 16:17:03|schedd|wake|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/28/2005 16:17:03|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:17:03|schedd|wake|I|PROF: create active job orders: 0.030 s
>04/28/2005 16:17:03|schedd|wake|I|PROF: job-order calculation took 0.060 s
>04/28/2005 16:17:03|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:17:03|schedd|wake|I|PROF: job dispatching took 0.270 s
>04/28/2005 16:17:04|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:17:04|schedd|wake|I|PROF: scheduled in 1.400 (u 0.320 + s 0.000 = 0.320): 1 sequential, 0 parallel, 61 orders, 128 H, 458 Q, 626 QA, 30 J(qw), 24 J(r), 9 J(s), 0 J(h), 0 J(e), 0 J(x), 68 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 186 gMes, 1091 jMes
>04/28/2005 16:17:06|schedd|wake|I|PROF: send orders and cleanup took: 1.250 (u 0.020,s 0.010) s
>04/28/2005 16:17:06|schedd|wake|I|PROF: schedd run took: 5.040 s (init: 2.240 s, copy: 0.090 s, run:2.680, free: 0.030 s, jobs: 68, categories: 8)
>04/28/2005 16:17:06|schedd|wake|I|PROF: profiling summary:
>04/28/2005 16:17:06|schedd|wake|I|PROF: other          : wc =      0.080s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:17:06|schedd|wake|I|PROF: packing        : wc =      0.020s, utime =      0.010s, stime =      0.000s, utilization =  50%
>04/28/2005 16:17:06|schedd|wake|I|PROF: eventclient    : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:17:06|schedd|wake|I|PROF: mirror         : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:17:06|schedd|wake|I|PROF: gdi            : wc =      4.460s, utime =      0.010s, stime =      0.010s, utilization =   0%
>04/28/2005 16:17:06|schedd|wake|I|PROF: ht-resize      : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:17:06|schedd|wake|I|PROF: scheduler      : wc =      0.080s, utime =      0.030s, stime =      0.000s, utilization =  38%
>04/28/2005 16:17:06|schedd|wake|I|PROF: pending ticket : wc =      0.050s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:17:06|schedd|wake|I|PROF: job sorting    : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:17:06|schedd|wake|I|PROF: job dispatching: wc =      0.270s, utime =      0.280s, stime =      0.000s, utilization = 104%
>04/28/2005 16:17:06|schedd|wake|I|PROF: send orders    : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:17:06|schedd|wake|I|PROF: scheduler event: wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:17:06|schedd|wake|I|PROF: copy lists     : wc =      0.150s, utime =      0.110s, stime =      0.000s, utilization =  73%
>04/28/2005 16:17:06|schedd|wake|I|PROF: total          : wc =      5.180s, utime =      0.450s, stime =      0.010s, utilization =   9%
>04/28/2005 16:17:08|schedd|wake|I|PROF: sge_mirror processed 21 events in 0.000 s
>04/28/2005 16:17:08|schedd|wake|I|PROF: static urgency took 0.010 s
>04/28/2005 16:17:08|schedd|wake|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/28/2005 16:17:08|schedd|wake|I|PROF: job ticket calculation: init: 0.010 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/28/2005 16:17:08|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:17:08|schedd|wake|I|PROF: create active job orders: 0.050 s
>04/28/2005 16:17:08|schedd|wake|I|PROF: job-order calculation took 0.130 s
>04/28/2005 16:17:08|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:17:08|schedd|wake|I|PROF: job dispatching took 0.270 s
>04/28/2005 16:17:08|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:17:08|schedd|wake|I|PROF: scheduled in 0.520 (u 0.330 + s 0.000 = 0.330): 0 sequential, 0 parallel, 62 orders, 128 H, 459 Q, 626 QA, 30 J(qw), 22 J(r), 9 J(s), 0 J(h), 0 J(e), 2 J(x), 68 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 167 gMes, 1091 jMes
>04/28/2005 16:17:09|schedd|wake|I|PROF: send orders and cleanup took: 1.130 (u 0.020,s 0.000) s
>04/28/2005 16:17:09|schedd|wake|I|PROF: schedd run took: 1.790 s (init: 0.010 s, copy: 0.080 s, run:1.670, free: 0.030 s, jobs: 68, categories: 8)
>04/28/2005 16:17:12|schedd|wake|I|PROF: sge_mirror processed 20 events in 0.000 s
>04/28/2005 16:17:12|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:17:12|schedd|wake|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/28/2005 16:17:12|schedd|wake|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/28/2005 16:17:12|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:17:12|schedd|wake|I|PROF: create active job orders: 0.010 s
>04/28/2005 16:17:12|schedd|wake|I|PROF: job-order calculation took 0.030 s
>04/28/2005 16:17:12|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:17:12|schedd|wake|I|PROF: job dispatching took 0.260 s
>04/28/2005 16:17:12|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:17:12|schedd|wake|I|PROF: scheduled in 0.350 (u 0.320 + s 0.000 = 0.320): 0 sequential, 0 parallel, 60 orders, 128 H, 459 Q, 626 QA, 30 J(qw), 22 J(r), 9 J(s), 0 J(h), 0 J(e), 0 J(x), 66 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 167 gMes, 1091 jMes
>04/28/2005 16:17:14|schedd|wake|I|PROF: send orders and cleanup took: 1.580 (u 0.010,s 0.000) s
>04/28/2005 16:17:14|schedd|wake|I|PROF: schedd run took: 2.080 s (init: 0.000 s, copy: 0.100 s, run:1.950, free: 0.030 s, jobs: 66, categories: 8)
>04/28/2005 16:17:17|schedd|wake|I|PROF: sge_mirror processed 32 events in 0.000 s
>04/28/2005 16:17:17|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:17:17|schedd|wake|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/28/2005 16:17:17|schedd|wake|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/28/2005 16:17:17|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:17:17|schedd|wake|I|PROF: create active job orders: 0.070 s
>04/28/2005 16:17:17|schedd|wake|I|PROF: job-order calculation took 0.210 s
>04/28/2005 16:17:17|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:17:17|schedd|wake|I|PROF: job dispatching took 0.400 s
>04/28/2005 16:17:17|schedd|wake|I|PROF: create pending job orders: 0.000 s
>04/28/2005 16:17:17|schedd|wake|I|PROF: scheduled in 0.750 (u 0.440 + s 0.000 = 0.440): 0 sequential, 0 parallel, 60 orders, 128 H, 460 Q, 626 QA, 30 J(qw), 21 J(r), 9 J(s), 0 J(h), 0 J(e), 1 J(x), 66 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 166 gMes, 1093 jMes
>04/28/2005 16:17:18|schedd|wake|I|PROF: send orders and cleanup took: 0.670 (u 0.020,s 0.000) s
>04/28/2005 16:17:18|schedd|wake|I|PROF: schedd run took: 1.550 s (init: 0.000 s, copy: 0.090 s, run:1.440, free: 0.020 s, jobs: 66, categories: 8)
>04/28/2005 16:17:22|schedd|wake|I|PROF: sge_mirror processed 29 events in 0.000 s
>04/28/2005 16:17:22|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:17:22|schedd|wake|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/28/2005 16:17:22|schedd|wake|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/28/2005 16:17:22|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:17:22|schedd|wake|I|PROF: create active job orders: 0.010 s
>04/28/2005 16:17:22|schedd|wake|I|PROF: job-order calculation took 0.030 s
>04/28/2005 16:17:22|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:17:22|schedd|wake|I|PROF: job dispatching took 0.260 s
>04/28/2005 16:17:22|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:17:22|schedd|wake|I|PROF: scheduled in 0.350 (u 0.300 + s 0.000 = 0.300): 0 sequential, 0 parallel, 59 orders, 128 H, 463 Q, 626 QA, 30 J(qw), 19 J(r), 9 J(s), 0 J(h), 0 J(e), 2 J(x), 65 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 163 gMes, 1099 jMes
>04/28/2005 16:17:24|schedd|wake|I|PROF: send orders and cleanup took: 1.520 (u 0.020,s 0.000) s
>04/28/2005 16:17:24|schedd|wake|I|PROF: schedd run took: 2.020 s (init: 0.000 s, copy: 0.100 s, run:1.900, free: 0.020 s, jobs: 65, categories: 8)
>04/28/2005 16:17:27|schedd|wake|I|PROF: sge_mirror processed 33 events in 0.000 s
>04/28/2005 16:17:27|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:17:27|schedd|wake|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/28/2005 16:17:27|schedd|wake|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/28/2005 16:17:27|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:17:27|schedd|wake|I|PROF: create active job orders: 0.010 s
>04/28/2005 16:17:27|schedd|wake|I|PROF: job-order calculation took 0.030 s
>04/28/2005 16:17:27|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:17:27|schedd|wake|I|PROF: job dispatching took 0.260 s
>04/28/2005 16:17:28|schedd|wake|I|PROF: create pending job orders: 0.000 s
>04/28/2005 16:17:28|schedd|wake|I|PROF: scheduled in 1.640 (u 0.300 + s 0.000 = 0.300): 1 sequential, 0 parallel, 57 orders, 128 H, 463 Q, 626 QA, 30 J(qw), 19 J(r), 9 J(s), 0 J(h), 0 J(e), 1 J(x), 64 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 171 gMes, 1101 jMes
>04/28/2005 16:17:30|schedd|wake|I|PROF: send orders and cleanup took: 1.610 (u 0.020,s 0.000) s
>04/28/2005 16:17:30|schedd|wake|I|PROF: schedd run took: 3.380 s (init: 0.000 s, copy: 0.100 s, run:3.250, free: 0.030 s, jobs: 64, categories: 8)
>04/28/2005 16:17:32|schedd|wake|I|PROF: sge_mirror processed 24 events in 0.000 s
>04/28/2005 16:17:32|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:17:32|schedd|wake|I|PROF: job ticket calculation: init: 0.010 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/28/2005 16:17:32|schedd|wake|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/28/2005 16:17:32|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:17:32|schedd|wake|I|PROF: create active job orders: 0.010 s
>04/28/2005 16:17:32|schedd|wake|I|PROF: job-order calculation took 0.030 s
>04/28/2005 16:17:32|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:17:32|schedd|wake|I|PROF: job dispatching took 0.280 s
>04/28/2005 16:17:34|schedd|wake|I|PROF: create pending job orders: 0.000 s
>04/28/2005 16:17:34|schedd|wake|I|PROF: scheduled in 2.600 (u 0.320 + s 0.000 = 0.320): 3 sequential, 0 parallel, 57 orders, 128 H, 460 Q, 626 QA, 30 J(qw), 22 J(r), 9 J(s), 0 J(h), 0 J(e), 0 J(x), 66 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 202 gMes, 1099 jMes
>04/28/2005 16:17:36|schedd|wake|I|PROF: send orders and cleanup took: 1.800 (u 0.010,s 0.010) s
>04/28/2005 16:17:36|schedd|wake|I|PROF: schedd run took: 4.540 s (init: 0.000 s, copy: 0.100 s, run:4.420, free: 0.020 s, jobs: 66, categories: 8)
>04/28/2005 16:17:37|schedd|wake|I|PROF: sge_mirror processed 59 events in 0.000 s
>04/28/2005 16:17:37|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:17:37|schedd|wake|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/28/2005 16:17:37|schedd|wake|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/28/2005 16:17:38|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:17:38|schedd|wake|I|PROF: create active job orders: 0.080 s
>04/28/2005 16:17:38|schedd|wake|I|PROF: job-order calculation took 0.220 s
>04/28/2005 16:17:38|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:17:38|schedd|wake|I|PROF: job dispatching took 0.410 s
>04/28/2005 16:17:40|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:17:40|schedd|wake|I|PROF: scheduled in 2.280 (u 0.470 + s 0.000 = 0.470): 1 sequential, 0 parallel, 60 orders, 128 H, 460 Q, 626 QA, 30 J(qw), 22 J(r), 9 J(s), 0 J(h), 0 J(e), 1 J(x), 67 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 180 gMes, 1095 jMes
>04/28/2005 16:17:41|schedd|wake|I|PROF: send orders and cleanup took: 1.490 (u 0.020,s 0.000) s
>04/28/2005 16:17:41|schedd|wake|I|PROF: schedd run took: 3.910 s (init: 0.000 s, copy: 0.090 s, run:3.800, free: 0.020 s, jobs: 67, categories: 8)
>04/28/2005 16:17:43|schedd|wake|I|PROF: sge_mirror processed 16 events in 0.000 s
>04/28/2005 16:17:43|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:17:43|schedd|wake|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/28/2005 16:17:43|schedd|wake|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/28/2005 16:17:43|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:17:43|schedd|wake|I|PROF: create active job orders: 0.020 s
>04/28/2005 16:17:43|schedd|wake|I|PROF: job-order calculation took 0.070 s
>04/28/2005 16:17:43|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:17:44|schedd|wake|I|PROF: job dispatching took 0.260 s
>04/28/2005 16:17:44|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:17:44|schedd|wake|I|PROF: scheduled in 0.410 (u 0.320 + s 0.000 = 0.320): 0 sequential, 0 parallel, 60 orders, 128 H, 461 Q, 626 QA, 30 J(qw), 21 J(r), 9 J(s), 0 J(h), 0 J(e), 1 J(x), 66 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 165 gMes, 1095 jMes
>04/28/2005 16:17:45|schedd|wake|I|PROF: send orders and cleanup took: 1.560 (u 0.030,s 0.000) s
>04/28/2005 16:17:45|schedd|wake|I|PROF: schedd run took: 2.130 s (init: 0.000 s, copy: 0.100 s, run:2.000, free: 0.030 s, jobs: 66, categories: 8)
>04/28/2005 16:17:47|schedd|wake|I|PROF: sge_mirror processed 15 events in 0.000 s
>04/28/2005 16:17:47|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:17:47|schedd|wake|I|PROF: job ticket calculation: init: 0.010 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/28/2005 16:17:47|schedd|wake|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/28/2005 16:17:47|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:17:47|schedd|wake|I|PROF: create active job orders: 0.020 s
>04/28/2005 16:17:47|schedd|wake|I|PROF: job-order calculation took 0.050 s
>04/28/2005 16:17:47|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:17:47|schedd|wake|I|PROF: job dispatching took 0.410 s
>04/28/2005 16:17:47|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:17:47|schedd|wake|I|PROF: scheduled in 0.540 (u 0.490 + s 0.000 = 0.490): 0 sequential, 0 parallel, 59 orders, 128 H, 463 Q, 626 QA, 30 J(qw), 19 J(r), 9 J(s), 0 J(h), 0 J(e), 2 J(x), 65 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 163 gMes, 1099 jMes
>04/28/2005 16:17:50|schedd|wake|I|PROF: send orders and cleanup took: 2.750 (u 0.030,s 0.000) s
>04/28/2005 16:17:50|schedd|wake|I|PROF: schedd run took: 3.470 s (init: 0.000 s, copy: 0.130 s, run:3.310, free: 0.030 s, jobs: 65, categories: 8)
>04/28/2005 16:17:52|schedd|wake|I|PROF: sge_mirror processed 30 events in 0.000 s
>04/28/2005 16:17:52|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:17:52|schedd|wake|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/28/2005 16:17:52|schedd|wake|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/28/2005 16:17:52|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:17:52|schedd|wake|I|PROF: create active job orders: 0.010 s
>04/28/2005 16:17:52|schedd|wake|I|PROF: job-order calculation took 0.030 s
>04/28/2005 16:17:52|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:17:52|schedd|wake|I|PROF: job dispatching took 0.260 s
>04/28/2005 16:17:52|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:17:52|schedd|wake|I|PROF: scheduled in 0.360 (u 0.320 + s 0.000 = 0.320): 0 sequential, 0 parallel, 58 orders, 128 H, 466 Q, 626 QA, 30 J(qw), 17 J(r), 8 J(s), 0 J(h), 0 J(e), 3 J(x), 63 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 160 gMes, 1105 jMes
>04/28/2005 16:17:55|schedd|wake|I|PROF: send orders and cleanup took: 2.440 (u 0.010,s 0.000) s
>04/28/2005 16:17:55|schedd|wake|I|PROF: schedd run took: 2.970 s (init: 0.000 s, copy: 0.140 s, run:2.810, free: 0.020 s, jobs: 63, categories: 8)
>04/28/2005 16:17:57|schedd|wake|I|PROF: sge_mirror processed 44 events in 0.000 s
>04/28/2005 16:17:57|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:17:57|schedd|wake|I|PROF: job ticket calculation: init: 0.010 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/28/2005 16:17:57|schedd|wake|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/28/2005 16:17:57|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:17:57|schedd|wake|I|PROF: create active job orders: 0.020 s
>04/28/2005 16:17:57|schedd|wake|I|PROF: job-order calculation took 0.050 s
>04/28/2005 16:17:57|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:17:57|schedd|wake|I|PROF: job dispatching took 0.320 s
>04/28/2005 16:17:59|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:17:59|schedd|wake|I|PROF: scheduled in 2.010 (u 0.350 + s 0.000 = 0.350): 1 sequential, 0 parallel, 55 orders, 128 H, 469 Q, 626 QA, 30 J(qw), 15 J(r), 8 J(s), 0 J(h), 0 J(e), 3 J(x), 61 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 159 gMes, 1111 jMes
>04/28/2005 16:18:01|schedd|wake|I|PROF: send orders and cleanup took: 1.880 (u 0.020,s 0.000) s
>04/28/2005 16:18:01|schedd|wake|I|PROF: schedd run took: 4.030 s (init: 0.000 s, copy: 0.100 s, run:3.910, free: 0.020 s, jobs: 61, categories: 9)
>04/28/2005 16:18:03|schedd|wake|I|PROF: sge_mirror processed 33 events in 0.000 s
>04/28/2005 16:18:03|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:18:03|schedd|wake|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/28/2005 16:18:03|schedd|wake|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/28/2005 16:18:03|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:18:03|schedd|wake|I|PROF: create active job orders: 0.010 s
>04/28/2005 16:18:03|schedd|wake|I|PROF: job-order calculation took 0.050 s
>04/28/2005 16:18:03|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:18:03|schedd|wake|I|PROF: job dispatching took 0.280 s
>04/28/2005 16:18:05|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:18:05|schedd|wake|I|PROF: scheduled in 1.850 (u 0.330 + s 0.000 = 0.330): 2 sequential, 0 parallel, 54 orders, 128 H, 468 Q, 626 QA, 30 J(qw), 17 J(r), 7 J(s), 0 J(h), 0 J(e), 1 J(x), 60 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 162 gMes, 1111 jMes
>04/28/2005 16:18:06|schedd|wake|I|PROF: send orders and cleanup took: 1.460 (u 0.020,s 0.000) s
>04/28/2005 16:18:06|schedd|wake|I|PROF: schedd run took: 3.450 s (init: 0.000 s, copy: 0.100 s, run:3.320, free: 0.030 s, jobs: 60, categories: 9)
>04/28/2005 16:18:07|schedd|wake|I|PROF: sge_mirror processed 49 events in 0.000 s
>04/28/2005 16:18:07|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:18:07|schedd|wake|I|PROF: job ticket calculation: init: 0.010 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/28/2005 16:18:07|schedd|wake|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/28/2005 16:18:07|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:18:07|schedd|wake|I|PROF: create active job orders: 0.010 s
>04/28/2005 16:18:07|schedd|wake|I|PROF: job-order calculation took 0.030 s
>04/28/2005 16:18:07|schedd|wake|I|PROF: job sorting took 0.010 s
>04/28/2005 16:18:08|schedd|wake|I|PROF: job dispatching took 0.300 s
>04/28/2005 16:18:09|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:18:09|schedd|wake|I|PROF: scheduled in 1.560 (u 0.340 + s 0.000 = 0.340): 3 sequential, 0 parallel, 54 orders, 128 H, 465 Q, 626 QA, 30 J(qw), 18 J(r), 8 J(s), 0 J(h), 0 J(e), 1 J(x), 62 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 173 gMes, 1109 jMes
>04/28/2005 16:18:10|schedd|wake|I|PROF: send orders and cleanup took: 0.920 (u 0.020,s 0.000) s
>04/28/2005 16:18:10|schedd|wake|I|PROF: schedd run took: 2.710 s (init: 0.000 s, copy: 0.100 s, run:2.590, free: 0.020 s, jobs: 62, categories: 9)
>04/28/2005 16:18:10|schedd|wake|I|PROF: profiling summary:
>04/28/2005 16:18:10|schedd|wake|I|PROF: other          : wc =      1.170s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:18:10|schedd|wake|I|PROF: packing        : wc =      0.340s, utime =      0.370s, stime =      0.000s, utilization = 109%
>04/28/2005 16:18:10|schedd|wake|I|PROF: eventclient    : wc =      0.000s, utime =      0.010s, stime =      0.000s, utilization =   0%
>04/28/2005 16:18:10|schedd|wake|I|PROF: mirror         : wc =      0.000s, utime =      0.040s, stime =      0.000s, utilization =   0%
>04/28/2005 16:18:10|schedd|wake|I|PROF: gdi            : wc =     58.890s, utime =      0.430s, stime =      0.020s, utilization =   1%
>04/28/2005 16:18:10|schedd|wake|I|PROF: ht-resize      : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:18:10|schedd|wake|I|PROF: scheduler      : wc =      1.410s, utime =      0.570s, stime =      0.000s, utilization =  40%
>04/28/2005 16:18:10|schedd|wake|I|PROF: pending ticket : wc =      0.930s, utime =      0.030s, stime =      0.000s, utilization =   3%
>04/28/2005 16:18:10|schedd|wake|I|PROF: job sorting    : wc =      0.020s, utime =      0.010s, stime =      0.000s, utilization =  50%
>04/28/2005 16:18:10|schedd|wake|I|PROF: job dispatching: wc =      4.240s, utime =      4.150s, stime =      0.000s, utilization =  98%
>04/28/2005 16:18:10|schedd|wake|I|PROF: send orders    : wc =      0.020s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:18:10|schedd|wake|I|PROF: scheduler event: wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:18:10|schedd|wake|I|PROF: copy lists     : wc =      2.150s, utime =      1.680s, stime =      0.010s, utilization =  79%
>04/28/2005 16:18:10|schedd|wake|I|PROF: total          : wc =     69.990s, utime =      7.480s, stime =      0.030s, utilization =  11%
>04/28/2005 16:18:12|schedd|wake|I|PROF: sge_mirror processed 29 events in 0.000 s
>04/28/2005 16:18:12|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:18:12|schedd|wake|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/28/2005 16:18:12|schedd|wake|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/28/2005 16:18:12|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:18:12|schedd|wake|I|PROF: create active job orders: 0.010 s
>04/28/2005 16:18:12|schedd|wake|I|PROF: job-order calculation took 0.050 s
>04/28/2005 16:18:12|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:18:12|schedd|wake|I|PROF: job dispatching took 0.270 s
>04/28/2005 16:18:12|schedd|wake|I|PROF: create pending job orders: 0.000 s
>04/28/2005 16:18:12|schedd|wake|I|PROF: scheduled in 0.370 (u 0.280 + s 0.000 = 0.280): 0 sequential, 0 parallel, 56 orders, 128 H, 466 Q, 626 QA, 30 J(qw), 17 J(r), 8 J(s), 0 J(h), 0 J(e), 1 J(x), 61 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 160 gMes, 1105 jMes
>04/28/2005 16:18:12|schedd|wake|I|PROF: send orders and cleanup took: 0.390 (u 0.020,s 0.000) s
>04/28/2005 16:18:12|schedd|wake|I|PROF: schedd run took: 0.910 s (init: 0.000 s, copy: 0.100 s, run:0.790, free: 0.020 s, jobs: 61, categories: 9)
>04/28/2005 16:18:17|schedd|wake|I|PROF: sge_mirror processed 42 events in 0.000 s
>04/28/2005 16:18:17|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:18:17|schedd|wake|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/28/2005 16:18:17|schedd|wake|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/28/2005 16:18:17|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:18:17|schedd|wake|I|PROF: create active job orders: 0.020 s
>04/28/2005 16:18:17|schedd|wake|I|PROF: job-order calculation took 0.050 s
>04/28/2005 16:18:17|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:18:17|schedd|wake|I|PROF: job dispatching took 0.260 s
>04/28/2005 16:18:17|schedd|wake|I|PROF: create pending job orders: 0.000 s
>04/28/2005 16:18:17|schedd|wake|I|PROF: scheduled in 0.420 (u 0.300 + s 0.000 = 0.300): 0 sequential, 0 parallel, 55 orders, 128 H, 466 Q, 626 QA, 30 J(qw), 17 J(r), 8 J(s), 0 J(h), 0 J(e), 0 J(x), 60 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 160 gMes, 1105 jMes
>04/28/2005 16:18:18|schedd|wake|I|PROF: send orders and cleanup took: 0.690 (u 0.020,s 0.000) s
>04/28/2005 16:18:18|schedd|wake|I|PROF: schedd run took: 1.270 s (init: 0.000 s, copy: 0.110 s, run:1.130, free: 0.030 s, jobs: 60, categories: 8)
>04/28/2005 16:18:22|schedd|wake|I|PROF: sge_mirror processed 14 events in 0.000 s
>04/28/2005 16:18:22|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:18:22|schedd|wake|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/28/2005 16:18:22|schedd|wake|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/28/2005 16:18:22|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:18:22|schedd|wake|I|PROF: create active job orders: 0.010 s
>04/28/2005 16:18:22|schedd|wake|I|PROF: job-order calculation took 0.050 s
>04/28/2005 16:18:22|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:18:22|schedd|wake|I|PROF: job dispatching took 0.260 s
>04/28/2005 16:18:22|schedd|wake|I|PROF: create pending job orders: 0.000 s
>04/28/2005 16:18:22|schedd|wake|I|PROF: scheduled in 0.400 (u 0.300 + s 0.000 = 0.300): 0 sequential, 0 parallel, 55 orders, 128 H, 467 Q, 626 QA, 30 J(qw), 16 J(r), 8 J(s), 0 J(h), 0 J(e), 1 J(x), 60 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 159 gMes, 1107 jMes
>04/28/2005 16:18:23|schedd|wake|I|PROF: send orders and cleanup took: 1.110 (u 0.020,s 0.000) s
>04/28/2005 16:18:23|schedd|wake|I|PROF: schedd run took: 1.710 s (init: 0.000 s, copy: 0.110 s, run:1.570, free: 0.030 s, jobs: 60, categories: 8)
>04/28/2005 16:18:27|schedd|wake|I|PROF: sge_mirror processed 23 events in 0.000 s
>04/28/2005 16:18:27|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:18:27|schedd|wake|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/28/2005 16:18:27|schedd|wake|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/28/2005 16:18:27|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:18:27|schedd|wake|I|PROF: create active job orders: 0.080 s
>04/28/2005 16:18:27|schedd|wake|I|PROF: job-order calculation took 0.150 s
>04/28/2005 16:18:27|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:18:27|schedd|wake|I|PROF: job dispatching took 0.270 s
>04/28/2005 16:18:27|schedd|wake|I|PROF: create pending job orders: 0.000 s
>04/28/2005 16:18:27|schedd|wake|I|PROF: scheduled in 0.580 (u 0.310 + s 0.000 = 0.310): 0 sequential, 0 parallel, 56 orders, 128 H, 471 Q, 626 QA, 30 J(qw), 14 J(r), 6 J(s), 0 J(h), 0 J(e), 4 J(x), 59 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 155 gMes, 1115 jMes
>04/28/2005 16:18:27|schedd|wake|I|PROF: send orders and cleanup took: 0.210 (u 0.010,s 0.010) s
>04/28/2005 16:18:28|schedd|wake|I|PROF: schedd run took: 0.960 s (init: 0.000 s, copy: 0.100 s, run:0.840, free: 0.020 s, jobs: 59, categories: 8)
>04/28/2005 16:18:32|schedd|wake|I|PROF: sge_mirror processed 29 events in 0.000 s
>04/28/2005 16:18:32|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:18:32|schedd|wake|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/28/2005 16:18:32|schedd|wake|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/28/2005 16:18:32|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:18:32|schedd|wake|I|PROF: create active job orders: 0.010 s
>04/28/2005 16:18:32|schedd|wake|I|PROF: job-order calculation took 0.060 s
>04/28/2005 16:18:32|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:18:32|schedd|wake|I|PROF: job dispatching took 0.260 s
>04/28/2005 16:18:32|schedd|wake|I|PROF: create pending job orders: 0.000 s
>04/28/2005 16:18:32|schedd|wake|I|PROF: scheduled in 0.390 (u 0.320 + s 0.020 = 0.340): 0 sequential, 0 parallel, 53 orders, 128 H, 473 Q, 626 QA, 30 J(qw), 13 J(r), 5 J(s), 0 J(h), 0 J(e), 2 J(x), 55 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 153 gMes, 1119 jMes
>04/28/2005 16:18:33|schedd|wake|I|PROF: send orders and cleanup took: 0.730 (u 0.020,s 0.000) s
>04/28/2005 16:18:33|schedd|wake|I|PROF: schedd run took: 1.250 s (init: 0.000 s, copy: 0.100 s, run:1.120, free: 0.030 s, jobs: 55, categories: 8)
>04/28/2005 16:18:37|schedd|wake|I|PROF: sge_mirror processed 35 events in 0.000 s
>04/28/2005 16:18:37|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:18:37|schedd|wake|I|PROF: job ticket calculation: init: 0.010 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/28/2005 16:18:37|schedd|wake|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/28/2005 16:18:37|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:18:37|schedd|wake|I|PROF: create active job orders: 0.010 s
>04/28/2005 16:18:37|schedd|wake|I|PROF: job-order calculation took 0.030 s
>04/28/2005 16:18:37|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:18:37|schedd|wake|I|PROF: job dispatching took 0.290 s
>04/28/2005 16:18:38|schedd|wake|I|PROF: create pending job orders: 0.000 s
>04/28/2005 16:18:38|schedd|wake|I|PROF: scheduled in 0.910 (u 0.350 + s 0.000 = 0.350): 5 sequential, 0 parallel, 51 orders, 128 H, 470 Q, 626 QA, 30 J(qw), 18 J(r), 5 J(s), 0 J(h), 0 J(e), 0 J(x), 58 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 162 gMes, 1119 jMes
>04/28/2005 16:18:38|schedd|wake|I|PROF: send orders and cleanup took: 0.770 (u 0.020,s 0.000) s
>04/28/2005 16:18:38|schedd|wake|I|PROF: schedd run took: 1.840 s (init: 0.000 s, copy: 0.090 s, run:1.720, free: 0.030 s, jobs: 58, categories: 8)
>04/28/2005 16:18:42|schedd|wake|I|PROF: sge_mirror processed 49 events in 0.000 s
>04/28/2005 16:18:42|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:18:42|schedd|wake|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/28/2005 16:18:42|schedd|wake|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/28/2005 16:18:42|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:18:42|schedd|wake|I|PROF: create active job orders: 0.110 s
>04/28/2005 16:18:42|schedd|wake|I|PROF: job-order calculation took 0.150 s
>04/28/2005 16:18:42|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:18:42|schedd|wake|I|PROF: job dispatching took 0.280 s
>04/28/2005 16:18:42|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:18:42|schedd|wake|I|PROF: scheduled in 0.610 (u 0.320 + s 0.000 = 0.320): 1 sequential, 0 parallel, 51 orders, 128 H, 468 Q, 626 QA, 27 J(qw), 16 J(r), 7 J(s), 0 J(h), 0 J(e), 1 J(x), 56 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 164 gMes, 1110 jMes
>04/28/2005 16:18:43|schedd|wake|I|PROF: send orders and cleanup took: 0.450 (u 0.020,s 0.000) s
>04/28/2005 16:18:43|schedd|wake|I|PROF: schedd run took: 1.220 s (init: 0.000 s, copy: 0.110 s, run:1.090, free: 0.020 s, jobs: 56, categories: 8)
>04/28/2005 16:18:47|schedd|wake|I|PROF: sge_mirror processed 36 events in 0.000 s
>04/28/2005 16:18:47|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:18:47|schedd|wake|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/28/2005 16:18:47|schedd|wake|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/28/2005 16:18:47|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:18:47|schedd|wake|I|PROF: create active job orders: 0.020 s
>04/28/2005 16:18:47|schedd|wake|I|PROF: job-order calculation took 0.040 s
>04/28/2005 16:18:47|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:18:47|schedd|wake|I|PROF: job dispatching took 0.270 s
>04/28/2005 16:18:48|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:18:48|schedd|wake|I|PROF: scheduled in 1.090 (u 0.320 + s 0.000 = 0.320): 1 sequential, 0 parallel, 48 orders, 128 H, 469 Q, 626 QA, 24 J(qw), 16 J(r), 6 J(s), 0 J(h), 0 J(e), 1 J(x), 52 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 163 gMes, 1114 jMes
>04/28/2005 16:18:48|schedd|wake|I|PROF: send orders and cleanup took: 0.470 (u 0.030,s 0.000) s
>04/28/2005 16:18:48|schedd|wake|I|PROF: schedd run took: 1.780 s (init: 0.000 s, copy: 0.130 s, run:1.620, free: 0.030 s, jobs: 52, categories: 8)
>04/28/2005 16:18:52|schedd|wake|I|PROF: sge_mirror processed 33 events in 0.000 s
>04/28/2005 16:18:52|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:18:52|schedd|wake|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/28/2005 16:18:52|schedd|wake|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/28/2005 16:18:52|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:18:52|schedd|wake|I|PROF: create active job orders: 0.020 s
>04/28/2005 16:18:52|schedd|wake|I|PROF: job-order calculation took 0.040 s
>04/28/2005 16:18:52|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:18:52|schedd|wake|I|PROF: job dispatching took 0.260 s
>04/28/2005 16:18:52|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:18:52|schedd|wake|I|PROF: scheduled in 0.370 (u 0.340 + s 0.010 = 0.350): 0 sequential, 0 parallel, 49 orders, 128 H, 471 Q, 626 QA, 24 J(qw), 14 J(r), 5 J(s), 0 J(h), 0 J(e), 3 J(x), 51 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 155 gMes, 1115 jMes
>04/28/2005 16:18:52|schedd|wake|I|PROF: send orders and cleanup took: 0.280 (u 0.010,s 0.000) s
>04/28/2005 16:18:52|schedd|wake|I|PROF: schedd run took: 0.810 s (init: 0.000 s, copy: 0.100 s, run:0.680, free: 0.030 s, jobs: 51, categories: 8)
>04/28/2005 16:18:57|schedd|wake|I|PROF: sge_mirror processed 48 events in 0.000 s
>04/28/2005 16:18:57|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:18:57|schedd|wake|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/28/2005 16:18:57|schedd|wake|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/28/2005 16:18:57|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:18:57|schedd|wake|I|PROF: create active job orders: 0.010 s
>04/28/2005 16:18:57|schedd|wake|I|PROF: job-order calculation took 0.040 s
>04/28/2005 16:18:57|schedd|wake|I|PROF: job sorting took 0.010 s
>04/28/2005 16:18:57|schedd|wake|I|PROF: job dispatching took 0.280 s
>04/28/2005 16:18:58|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:18:58|schedd|wake|I|PROF: scheduled in 0.920 (u 0.340 + s 0.000 = 0.340): 4 sequential, 0 parallel, 47 orders, 128 H, 470 Q, 626 QA, 24 J(qw), 18 J(r), 4 J(s), 0 J(h), 0 J(e), 1 J(x), 52 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 166 gMes, 1117 jMes
>04/28/2005 16:18:58|schedd|wake|I|PROF: send orders and cleanup took: 0.830 (u 0.030,s 0.000) s
>04/28/2005 16:18:59|schedd|wake|I|PROF: schedd run took: 1.920 s (init: 0.000 s, copy: 0.100 s, run:1.790, free: 0.030 s, jobs: 52, categories: 8)
>04/28/2005 16:19:02|schedd|wake|I|PROF: sge_mirror processed 33 events in 0.000 s
>04/28/2005 16:19:02|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:19:02|schedd|wake|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/28/2005 16:19:02|schedd|wake|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/28/2005 16:19:02|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:19:02|schedd|wake|I|PROF: create active job orders: 0.050 s
>04/28/2005 16:19:02|schedd|wake|I|PROF: job-order calculation took 0.150 s
>04/28/2005 16:19:02|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:19:02|schedd|wake|I|PROF: job dispatching took 0.260 s
>04/28/2005 16:19:03|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:19:03|schedd|wake|I|PROF: scheduled in 0.890 (u 0.320 + s 0.000 = 0.320): 1 sequential, 0 parallel, 48 orders, 128 H, 469 Q, 626 QA, 24 J(qw), 16 J(r), 6 J(s), 0 J(h), 0 J(e), 1 J(x), 52 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 161 gMes, 1111 jMes
>04/28/2005 16:19:03|schedd|wake|I|PROF: send orders and cleanup took: 0.220 (u 0.010,s 0.000) s
>04/28/2005 16:19:03|schedd|wake|I|PROF: schedd run took: 1.300 s (init: 0.000 s, copy: 0.100 s, run:1.170, free: 0.030 s, jobs: 52, categories: 8)
>04/28/2005 16:19:07|schedd|wake|I|PROF: sge_mirror processed 17 events in 0.000 s
>04/28/2005 16:19:07|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:19:07|schedd|wake|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/28/2005 16:19:07|schedd|wake|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/28/2005 16:19:07|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:19:07|schedd|wake|I|PROF: create active job orders: 0.010 s
>04/28/2005 16:19:07|schedd|wake|I|PROF: job-order calculation took 0.040 s
>04/28/2005 16:19:07|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:19:07|schedd|wake|I|PROF: job dispatching took 0.400 s
>04/28/2005 16:19:07|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:19:07|schedd|wake|I|PROF: scheduled in 0.520 (u 0.470 + s 0.000 = 0.470): 0 sequential, 0 parallel, 48 orders, 128 H, 469 Q, 626 QA, 24 J(qw), 15 J(r), 6 J(s), 0 J(h), 0 J(e), 1 J(x), 51 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 157 gMes, 1111 jMes
>04/28/2005 16:19:07|schedd|wake|I|PROF: send orders and cleanup took: 0.140 (u 0.030,s 0.000) s
>04/28/2005 16:19:07|schedd|wake|I|PROF: schedd run took: 0.840 s (init: 0.000 s, copy: 0.130 s, run:0.680, free: 0.030 s, jobs: 51, categories: 8)
>04/28/2005 16:19:12|schedd|wake|I|PROF: sge_mirror processed 19 events in 0.000 s
>04/28/2005 16:19:12|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:19:12|schedd|wake|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/28/2005 16:19:12|schedd|wake|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/28/2005 16:19:12|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:19:12|schedd|wake|I|PROF: create active job orders: 0.020 s
>04/28/2005 16:19:12|schedd|wake|I|PROF: job-order calculation took 0.050 s
>04/28/2005 16:19:12|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:19:12|schedd|wake|I|PROF: job dispatching took 0.260 s
>04/28/2005 16:19:12|schedd|wake|I|PROF: create pending job orders: 0.000 s
>04/28/2005 16:19:12|schedd|wake|I|PROF: scheduled in 0.380 (u 0.310 + s 0.000 = 0.310): 0 sequential, 0 parallel, 47 orders, 128 H, 469 Q, 626 QA, 24 J(qw), 15 J(r), 6 J(s), 0 J(h), 0 J(e), 0 J(x), 50 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 157 gMes, 1111 jMes
>04/28/2005 16:19:12|schedd|wake|I|PROF: send orders and cleanup took: 0.070 (u 0.020,s 0.000) s
>04/28/2005 16:19:12|schedd|wake|I|PROF: schedd run took: 0.590 s (init: 0.000 s, copy: 0.100 s, run:0.470, free: 0.020 s, jobs: 50, categories: 8)
>04/28/2005 16:19:12|schedd|wake|I|PROF: profiling summary:
>04/28/2005 16:19:12|schedd|wake|I|PROF: other          : wc =      1.880s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:19:12|schedd|wake|I|PROF: packing        : wc =      0.570s, utime =      0.620s, stime =      0.000s, utilization = 109%
>04/28/2005 16:19:12|schedd|wake|I|PROF: eventclient    : wc =      0.030s, utime =      0.030s, stime =      0.000s, utilization = 100%
>04/28/2005 16:19:12|schedd|wake|I|PROF: mirror         : wc =      0.000s, utime =      0.100s, stime =      0.000s, utilization =   0%
>04/28/2005 16:19:12|schedd|wake|I|PROF: gdi            : wc =    112.030s, utime =      0.920s, stime =      0.050s, utilization =   1%
>04/28/2005 16:19:12|schedd|wake|I|PROF: ht-resize      : wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:19:12|schedd|wake|I|PROF: scheduler      : wc =      2.650s, utime =      1.110s, stime =      0.020s, utilization =  43%
>04/28/2005 16:19:12|schedd|wake|I|PROF: pending ticket : wc =      1.810s, utime =      0.040s, stime =      0.010s, utilization =   3%
>04/28/2005 16:19:12|schedd|wake|I|PROF: job sorting    : wc =      0.030s, utime =      0.020s, stime =      0.000s, utilization =  67%
>04/28/2005 16:19:12|schedd|wake|I|PROF: job dispatching: wc =      7.860s, utime =      7.740s, stime =      0.000s, utilization =  98%
>04/28/2005 16:19:12|schedd|wake|I|PROF: send orders    : wc =      0.030s, utime =      0.020s, stime =      0.000s, utilization =  67%
>04/28/2005 16:19:12|schedd|wake|I|PROF: scheduler event: wc =      0.000s, utime =      0.000s, stime =      0.000s, utilization =   0%
>04/28/2005 16:19:12|schedd|wake|I|PROF: copy lists     : wc =      4.340s, utime =      3.330s, stime =      0.010s, utilization =  77%
>04/28/2005 16:19:12|schedd|wake|I|PROF: total          : wc =    132.920s, utime =     14.260s, stime =      0.090s, utilization =  11%
>04/28/2005 16:19:17|schedd|wake|I|PROF: sge_mirror processed 31 events in 0.000 s
>04/28/2005 16:19:17|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:19:17|schedd|wake|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/28/2005 16:19:17|schedd|wake|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/28/2005 16:19:17|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:19:17|schedd|wake|I|PROF: create active job orders: 0.020 s
>04/28/2005 16:19:17|schedd|wake|I|PROF: job-order calculation took 0.050 s
>04/28/2005 16:19:17|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:19:17|schedd|wake|I|PROF: job dispatching took 0.280 s
>04/28/2005 16:19:17|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:19:17|schedd|wake|I|PROF: scheduled in 0.580 (u 0.320 + s 0.010 = 0.330): 4 sequential, 0 parallel, 47 orders, 128 H, 466 Q, 626 QA, 24 J(qw), 19 J(r), 6 J(s), 0 J(h), 0 J(e), 0 J(x), 54 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 182 gMes, 1111 jMes
>04/28/2005 16:19:18|schedd|wake|I|PROF: send orders and cleanup took: 0.560 (u 0.020,s 0.000) s
>04/28/2005 16:19:18|schedd|wake|I|PROF: schedd run took: 1.310 s (init: 0.000 s, copy: 0.100 s, run:1.190, free: 0.020 s, jobs: 54, categories: 8)
>04/28/2005 16:19:22|schedd|wake|I|PROF: sge_mirror processed 42 events in 0.000 s
>04/28/2005 16:19:22|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:19:22|schedd|wake|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/28/2005 16:19:22|schedd|wake|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/28/2005 16:19:22|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:19:22|schedd|wake|I|PROF: create active job orders: 0.030 s
>04/28/2005 16:19:22|schedd|wake|I|PROF: job-order calculation took 0.120 s
>04/28/2005 16:19:22|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:19:22|schedd|wake|I|PROF: job dispatching took 0.280 s
>04/28/2005 16:19:22|schedd|wake|I|PROF: create pending job orders: 0.000 s
>04/28/2005 16:19:22|schedd|wake|I|PROF: scheduled in 0.530 (u 0.300 + s 0.000 = 0.300): 1 sequential, 0 parallel, 50 orders, 128 H, 466 Q, 626 QA, 24 J(qw), 17 J(r), 7 J(s), 0 J(h), 0 J(e), 2 J(x), 55 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 166 gMes, 1107 jMes
>04/28/2005 16:19:23|schedd|wake|I|PROF: send orders and cleanup took: 0.580 (u 0.020,s 0.000) s
>04/28/2005 16:19:23|schedd|wake|I|PROF: schedd run took: 1.260 s (init: 0.000 s, copy: 0.110 s, run:1.130, free: 0.020 s, jobs: 55, categories: 8)
>04/28/2005 16:19:27|schedd|wake|I|PROF: sge_mirror processed 30 events in 0.000 s
>04/28/2005 16:19:27|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:19:27|schedd|wake|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/28/2005 16:19:27|schedd|wake|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/28/2005 16:19:27|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:19:27|schedd|wake|I|PROF: create active job orders: 0.020 s
>04/28/2005 16:19:27|schedd|wake|I|PROF: job-order calculation took 0.040 s
>04/28/2005 16:19:27|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:19:27|schedd|wake|I|PROF: job dispatching took 0.260 s
>04/28/2005 16:19:27|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:19:27|schedd|wake|I|PROF: scheduled in 0.410 (u 0.310 + s 0.000 = 0.310): 0 sequential, 0 parallel, 49 orders, 128 H, 466 Q, 626 QA, 24 J(qw), 17 J(r), 7 J(s), 0 J(h), 0 J(e), 0 J(x), 53 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 160 gMes, 1105 jMes
>04/28/2005 16:19:27|schedd|wake|I|PROF: send orders and cleanup took: 0.350 (u 0.010,s 0.000) s
>04/28/2005 16:19:28|schedd|wake|I|PROF: schedd run took: 0.930 s (init: 0.000 s, copy: 0.100 s, run:0.800, free: 0.030 s, jobs: 53, categories: 8)
>04/28/2005 16:19:32|schedd|wake|I|PROF: sge_mirror processed 24 events in 0.000 s
>04/28/2005 16:19:32|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:19:32|schedd|wake|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/28/2005 16:19:32|schedd|wake|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/28/2005 16:19:32|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:19:32|schedd|wake|I|PROF: create active job orders: 0.020 s
>04/28/2005 16:19:32|schedd|wake|I|PROF: job-order calculation took 0.050 s
>04/28/2005 16:19:32|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:19:32|schedd|wake|I|PROF: job dispatching took 0.290 s
>04/28/2005 16:19:32|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:19:32|schedd|wake|I|PROF: scheduled in 0.720 (u 0.330 + s 0.000 = 0.330): 5 sequential, 0 parallel, 49 orders, 128 H, 463 Q, 626 QA, 24 J(qw), 21 J(r), 7 J(s), 0 J(h), 0 J(e), 1 J(x), 58 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 213 gMes, 1107 jMes
>04/28/2005 16:19:33|schedd|wake|I|PROF: send orders and cleanup took: 0.510 (u 0.040,s 0.000) s
>04/28/2005 16:19:33|schedd|wake|I|PROF: schedd run took: 1.430 s (init: 0.000 s, copy: 0.100 s, run:1.300, free: 0.030 s, jobs: 58, categories: 8)
>04/28/2005 16:19:37|schedd|wake|I|PROF: sge_mirror processed 64 events in 0.000 s
>04/28/2005 16:19:37|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:19:37|schedd|wake|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/28/2005 16:19:37|schedd|wake|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/28/2005 16:19:37|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:19:37|schedd|wake|I|PROF: create active job orders: 0.010 s
>04/28/2005 16:19:37|schedd|wake|I|PROF: job-order calculation took 0.040 s
>04/28/2005 16:19:37|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:19:37|schedd|wake|I|PROF: job dispatching took 0.260 s
>04/28/2005 16:19:37|schedd|wake|I|PROF: create pending job orders: 0.000 s
>04/28/2005 16:19:37|schedd|wake|I|PROF: scheduled in 0.370 (u 0.300 + s 0.000 = 0.300): 0 sequential, 0 parallel, 53 orders, 128 H, 462 Q, 626 QA, 24 J(qw), 20 J(r), 7 J(s), 0 J(h), 0 J(e), 1 J(x), 57 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 164 gMes, 1097 jMes
>04/28/2005 16:19:37|schedd|wake|I|PROF: send orders and cleanup took: 0.210 (u 0.010,s 0.000) s
>04/28/2005 16:19:37|schedd|wake|I|PROF: schedd run took: 0.720 s (init: 0.010 s, copy: 0.090 s, run:0.600, free: 0.020 s, jobs: 57, categories: 8)
>04/28/2005 16:19:42|schedd|wake|I|PROF: sge_mirror processed 18 events in 0.000 s
>04/28/2005 16:19:42|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:19:42|schedd|wake|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/28/2005 16:19:42|schedd|wake|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/28/2005 16:19:42|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:19:42|schedd|wake|I|PROF: create active job orders: 0.020 s
>04/28/2005 16:19:42|schedd|wake|I|PROF: job-order calculation took 0.060 s
>04/28/2005 16:19:42|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:19:42|schedd|wake|I|PROF: job dispatching took 0.280 s
>04/28/2005 16:19:43|schedd|wake|I|PROF: create pending job orders: 0.000 s
>04/28/2005 16:19:43|schedd|wake|I|PROF: scheduled in 1.470 (u 0.310 + s 0.000 = 0.310): 1 sequential, 0 parallel, 52 orders, 128 H, 463 Q, 626 QA, 24 J(qw), 19 J(r), 7 J(s), 0 J(h), 0 J(e), 2 J(x), 57 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 171 gMes, 1101 jMes
>04/28/2005 16:19:44|schedd|wake|I|PROF: send orders and cleanup took: 0.740 (u 0.010,s 0.000) s
>04/28/2005 16:19:44|schedd|wake|I|PROF: schedd run took: 2.390 s (init: 0.000 s, copy: 0.090 s, run:2.280, free: 0.020 s, jobs: 57, categories: 8)
>04/28/2005 16:19:47|schedd|wake|I|PROF: sge_mirror processed 31 events in 0.000 s
>04/28/2005 16:19:47|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:19:47|schedd|wake|I|PROF: job ticket calculation: init: 0.010 s, pass 0: 0.000 s, pass 1: 0.000, pass2: 0.000, calc: 0.000 s
>04/28/2005 16:19:47|schedd|wake|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/28/2005 16:19:47|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:19:47|schedd|wake|I|PROF: create active job orders: 0.020 s
>04/28/2005 16:19:47|schedd|wake|I|PROF: job-order calculation took 0.060 s
>04/28/2005 16:19:47|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:19:47|schedd|wake|I|PROF: job dispatching took 0.280 s
>04/28/2005 16:19:47|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:19:47|schedd|wake|I|PROF: scheduled in 0.580 (u 0.340 + s 0.010 = 0.350): 4 sequential, 0 parallel, 52 orders, 128 H, 464 Q, 626 QA, 24 J(qw), 20 J(r), 6 J(s), 0 J(h), 0 J(e), 4 J(x), 59 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 186 gMes, 1107 jMes
>04/28/2005 16:19:48|schedd|wake|I|PROF: send orders and cleanup took: 0.270 (u 0.020,s 0.000) s
>04/28/2005 16:19:48|schedd|wake|I|PROF: schedd run took: 1.060 s (init: 0.000 s, copy: 0.090 s, run:0.940, free: 0.030 s, jobs: 59, categories: 8)
>04/28/2005 16:19:52|schedd|wake|I|PROF: sge_mirror processed 43 events in 0.000 s
>04/28/2005 16:19:52|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:19:52|schedd|wake|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/28/2005 16:19:52|schedd|wake|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/28/2005 16:19:52|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:19:52|schedd|wake|I|PROF: create active job orders: 0.020 s
>04/28/2005 16:19:52|schedd|wake|I|PROF: job-order calculation took 0.040 s
>04/28/2005 16:19:52|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:19:52|schedd|wake|I|PROF: job dispatching took 0.270 s
>04/28/2005 16:19:52|schedd|wake|I|PROF: create pending job orders: 0.000 s
>04/28/2005 16:19:52|schedd|wake|I|PROF: scheduled in 0.420 (u 0.320 + s 0.000 = 0.320): 0 sequential, 0 parallel, 52 orders, 128 H, 464 Q, 626 QA, 24 J(qw), 19 J(r), 6 J(s), 0 J(h), 0 J(e), 1 J(x), 55 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 162 gMes, 1101 jMes
>04/28/2005 16:19:52|schedd|wake|I|PROF: send orders and cleanup took: 0.180 (u 0.030,s 0.000) s
>04/28/2005 16:19:52|schedd|wake|I|PROF: schedd run took: 0.740 s (init: 0.000 s, copy: 0.100 s, run:0.620, free: 0.020 s, jobs: 55, categories: 8)
>04/28/2005 16:19:57|schedd|wake|I|PROF: sge_mirror processed 29 events in 0.000 s
>04/28/2005 16:19:57|schedd|wake|I|PROF: static urgency took 0.000 s
>04/28/2005 16:19:57|schedd|wake|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/28/2005 16:19:57|schedd|wake|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/28/2005 16:19:57|schedd|wake|I|PROF: normalizing job tickets took 0.000 s
>04/28/2005 16:19:57|schedd|wake|I|PROF: create active job orders: 0.080 s
>04/28/2005 16:19:57|schedd|wake|I|PROF: job-order calculation took 0.150 s
>04/28/2005 16:19:57|schedd|wake|I|PROF: job sorting took 0.000 s
>04/28/2005 16:19:57|schedd|wake|I|PROF: job dispatching took 0.260 s
>04/28/2005 16:19:57|schedd|wake|I|PROF: create pending job orders: 0.010 s
>04/28/2005 16:19:57|schedd|wake|I|PROF: scheduled in 0.570 (u 0.300 + s 0.000 = 0.300): 0 sequential, 0 parallel, 51 orders, 128 H, 465 Q, 626 QA, 24 J(qw), 19 J(r), 6 J(s), 0 J(h), 0 J(e), 0 J(x), 54 J(all), 82 C, 6 ACL, 3 PE, 5 U, 1 D, 0 PRJ, 0 ST, 0 CKPT, 0 RU, 161 gMes, 1103 jMes
>04/28/2005 16:19:58|schedd|wake|I|PROF: send orders and cleanup took: 0.640 (u 0.020,s 0.000) s
>04/28/2005 16:19:58|schedd|wake|I|PROF: schedd run took: 1.350 s (init: 0.000 s, copy: 0.090 s, run:1.230, free: 0.030 s, jobs: 54, categories: 8)
>04/28/2005 16:20:00|schedd|wake|I|PROF: sge_mirror processed 34 events in 0.000 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