thank you for information! I attempt to check thing out myself, but not so easy!<br><br>Something is not as you say it should be, but perhaps I make a wrong move when I code my reader, so I really want to get this resolved, because as it stands pipeline execute takes 80% while read if 1/2Gig only takes 20%. That can't be correct!<br>
<br>I step in gdb and I see the stack has mixture of CompositeDataPipeline, StreamingDemandDrivenPipeline, DemandDrivenPipeline, and Executive. In addition to timing RequestData in the reader I time in ProcessRequest in each of these classes to see where the hang up is. To my surprise I see that many,many,many! things are going on after the reader finished RequestData. Take a look at the io from the 2 process run that follws my text. I put notes in with '===>' so you can see timing from RequestData easy. This io is from a single pipeline update! For this one pipeline update I see timer log report:<br>
<br><meta http-equiv="Content-Type" content="text/html; charset=utf-8"><style type="text/css">
p, li { white-space: pre-wrap; }
</style>
<p style="margin: 0px; text-indent: 0px;"><span style="font-family: 'Courier New,courier';">Execute vtkBAMReader id: 7750, 5.56547 seconds</span></p><p style="margin: 0px; text-indent: 0px;"><br><span style="font-family: 'Courier New,courier';"></span></p>
<p style="margin: 0px; text-indent: 0px;"><span style="font-family: 'Courier New,courier';">lost + do not know what causes this! </span><span style="font-family: 'Courier New,courier';">Plz HLP, <br></span></p>
<p style="margin: 0px; text-indent: 0px;"><span style="font-family: 'Courier New,courier';">thx Bam.</span></p><p style="margin: 0px; text-indent: 0px;"><br><span style="font-family: 'Courier New,courier';"></span></p>
<p style="margin: 0px; text-indent: 0px;"><span style="font-family: 'Courier New,courier';"><br></span></p>n019:~/PV3-3.7-Debug/bin$mpiexec -np 2 ./pvserver --use-offscreen-rendering --server-port=12345<br>Listen on port: 12345 <br>
Waiting for client... <br>Client connected. <br>
DDP:ProcessRequest:RequestDataObject:0:0DDP:ProcessRequest:RequestDataObject: <br>1:0.01 <br>
CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>
DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
DDP:ProcessRequest:RequestInformation:1:0.01 <br>CDP:ProcessRequest:RequestInformation:1:0.01 <br>
CDP:ProcessRequest:RequestDataObject:0:CDP:ProcessRequest:RequestDataObject:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>1:0 <br>
DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:CDP:ProcessRequest:RequestInformation:0:0 <br>1:0 <br>
CDP:ProcessRequest:RequestDataObject:1:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>
DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:CDP:ProcessRequest:RequestInformation:0:0 <br>1:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:1:0<br>=================================================>>Read happens from here <br>
RequestData n019 0 135796744 <br>RequestData n019 0 0 513 0 513 0 256 <br>
RequestData n012 1 135796744 <br>RequestData n012 1 0 513 0 513 256 513 <br>
RequestData n019 0 RequestData n012 1 1.01 <br>1 <br>
DDP:ProcessRequest:RequestData:0:1.01 <br>SDDP:ProcessRequest:RequestData+Crop:0:1.01 <br>
DDP:ProcessRequest:RequestData:1:1 <br>SDDP:ProcessRequest:RequestData+Crop:1:1<br>================================================>> to here! takes 1.01 sec. What follow take ~ 5 sec <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:1CDP:ProcessRequest:RequestInformation:0:0 <br>
:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:1:0 <br>
DDP:ProcessRequest:RequestDataObject:0:0 <br>DDP:ProcessRequest:RequestDataObject:1:0 <br>
DDP:ProcessRequest:RequestDataObject:0:0 <br>DDP:ProcessRequest:RequestDataObject:1:0 <br>
DDP:ProcessRequest:RequestDataObject:0:0 <br>DDP:ProcessRequest:RequestDataObject:1:0 <br>
DDP:ProcessRequest:RequestDataObject:0:0 <br>DDP:ProcessRequest:RequestDataObject:1:0 <br>
DDP:ProcessRequest:RequestDataObject:0:0 <br>DDP:ProcessRequest:RequestDataObject:1DDP:ProcessRequest:RequestDataObject:0:0 <br>
:0 <br>DDP:ProcessRequest:RequestDataObject:0:0 <br>
DDP:ProcessRequest:RequestDataObject:1:0 <br>DDP:ProcessRequest:RequestDataObject:0:0 <br>
DDP:ProcessRequest:RequestDataObject:1:0 <br>DDP:ProcessRequest:RequestDataObject:0:0 <br>
DDP:ProcessRequest:RequestDataObject:1:0 <br>DDP:ProcessRequest:RequestDataObject:0:0 <br>
DDP:ProcessRequest:RequestDataObject:1:0 <br>DDP:ProcessRequest:RequestDataObject:0:0 <br>
DDP:ProcessRequest:RequestDataObject:1:0 <br>DDP:ProcessRequest:RequestDataObject:0:0 <br>
DDP:ProcessRequest:RequestDataObject:1:0 <br>DDP:ProcessRequest:RequestDataObject:1:0 <br>
<br>CDP:ProcessRequest:RequestDataObject:0:0<br>CDP:ProcessRequest:RequestDataObject:0:0<br>CDP:ProcessRequest:RequestDataObject:0:0<br>CDP:ProcessRequest:RequestDataObject:1:0<br>CDP:ProcessRequest:RequestDataObject:0:0<br>
CDP:ProcessRequest:RequestDataObject:1:0<br>CDP:ProcessRequest:RequestDataObject:1:0<br>CDP:ProcessRequest:RequestDataObject:1:0<br>CDP:ProcessRequest:RequestInformation:0:0<br>CDP:ProcessRequest:RequestInformation:1:0<br>
DDP:ProcessRequest:RequestInformation:1:0<br>CDP:ProcessRequest:RequestInformation:1:0<br>DDP:ProcessRequest:RequestInformation:1:0<br>CDP:ProcessRequest:RequestInformation:1:0<br>DDP:ProcessRequest:RequestInformation:1:0<br>
CDP:ProcessRequest:RequestInformation:1:0<br>DDP:ProcessRequest:RequestInformation:0:0<br>CDP:ProcessRequest:RequestInformation:0:0<br>DDP:ProcessRequest:RequestInformation:1:0<br>CDP:ProcessRequest:RequestInformation:1:DDP:ProcessRequest:RequestInformation:0:0<br>
CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestDataObject:0:CDP:ProcessRequest:RequestDataObject:1:0 <br>0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>
DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestDataObject:0CDP:ProcessRequest:RequestDataObject:1:0 <br>
:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1DDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
:0 <br>CDP:ProcessRequest:RequestDataObject:0:CDP:ProcessRequest:RequestDataObject:1:0 <br>0 <br>
CDP:ProcessRequest:RequestDataObject:1:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestDataObject:1:CDP:ProcessRequest:RequestDataObject:0:0 <br>
0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:1DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestDataObject:0:CDP:ProcessRequest:RequestDataObject:1:0 <br>0 <br>CDP:ProcessRequest:RequestInformation:0:CDP:ProcessRequest:RequestInformation:1:0<br>
0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestDataObject:0CDP:ProcessRequest:RequestDataObject:1:0 <br>
:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1DDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
:0 <br>CDP:ProcessRequest:RequestDataObject:0:CDP:ProcessRequest:RequestDataObject:1:0 <br>0 <br>
CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>
CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>
CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>
CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>
DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:DDP:ProcessRequest:RequestInformation:0:0<br>
CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>
DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestDataObject:0:CDP:ProcessRequest:RequestDataObject:1:0 <br>0 <br>
CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>
CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:DDP:ProcessRequest:RequestInformation:0:0<br>
CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>
DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestDataObject:0:CDP:ProcessRequest:RequestDataObject:1:0 <br>0.01 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>
CDP:ProcessRequest:RequestDataObject:1:0.01 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestDataObject:1:0.01 <br>
CDP:ProcessRequest:RequestDataObject:1:0.01 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>
DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>
DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>
CDP:ProcessRequest:RequestDataObject:1:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>
CDP:ProcessRequest:RequestDataObject:1:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>
CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:1:DDP:ProcessRequest:RequestInformation:0:0<br>CDP:ProcessRequest:RequestInformation:0:0 <br>0 <br>
DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>:0 <br>
DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestDataObject:0:CDP:ProcessRequest:RequestDataObject:1:0 <br>0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestDataObject:0CDP:ProcessRequest:RequestDataObject:1:0 <br>:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>
CDP:ProcessRequest:RequestDataObject:0:0.01 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>
DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:1DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>
CDP:ProcessRequest:RequestDataObject:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>
CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:1:DDP:ProcessRequest:RequestInformation:0:0<br>CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:1:0 <br>
SDDP:ProcessRequest:RequestUpdateExtent:1:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:1:0 <br>DDP:ProcessRequest:RequestData:1:0 <br>
SDDP:ProcessRequest:RequestData+Crop:1:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>
SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>DDP:ProcessRequest:RequestData:0:0 <br>
SDDP:ProcessRequest:RequestData+Crop:0:0 <br>DDP:ProcessRequest:RequestDataObject:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>
SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>DDP:ProcessRequest:RequestData:0:0 <br>SDDP:ProcessRequest:RequestData+Crop:0:0 <br>
DDP:ProcessRequest:RequestData:1:0 <br>SDDP:ProcessRequest:RequestData+Crop:1:0 <br>DDP:ProcessRequest:RequestData:0:0 <br>
SDDP:ProcessRequest:RequestData+Crop:0:0 <br>DDP:ProcessRequest:RequestData:1:0 <br>SDDP:ProcessRequest:RequestData+Crop:1:0 <br>
DDP:ProcessRequest:RequestData:0:0 <br>SDDP:ProcessRequest:RequestData+Crop:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:CDP:ProcessRequest:RequestInformation:1:0<br>
0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>
CDP:ProcessRequest:RequestDataObject:1:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>
CDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestDataObject:1:0 <br>
CDP:ProcessRequest:RequestInformation:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>CDP:ProcessRequest:RequestInformation:1:0 <br>
SDDP:ProcessRequest:RequestUpdateExtent:1:0 <br>CDP:ProcessRequest:RequestDataObject:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>
DDP:ProcessRequest:RequestInformation:0:0 <br>CDP:ProcessRequest:RequestInformation:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>
DDP:ProcessRequest:RequestDataObject:1:0 <br>DDP:ProcessRequest:RequestDataObject:1:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>
DDP:ProcessRequest:RequestInformation:1:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:1:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:1:0 <br>
DDP:ProcessRequest:RequestData:1:0 <br>SDDP:ProcessRequest:RequestData+Crop:1:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:1:0 <br>
DDP:ProcessRequest:RequestDataObject:0:0 <br>DDP:ProcessRequest:RequestDataObject:1:0 <br>DDP:ProcessRequest:RequestDataObject:0:0 <br>
DDP:ProcessRequest:RequestInformation:0:0 <br>DDP:ProcessRequest:RequestInformation:1:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:1:0 <br>
SDDP:ProcessRequest:RequestUpdateExtent:1:0 <br>DDP:ProcessRequest:RequestData:1:0 <br>SDDP:ProcessRequest:RequestData+Crop:1:0 <br>
SDDP:ProcessRequest:RequestUpdateExtent:1:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:1:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>
SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>DDP:ProcessRequest:RequestData:0:0 <br>
SDDP:ProcessRequest:RequestData+Crop:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>DDP:ProcessRequest:RequestDataObject:0:0 <br>
DDP:ProcessRequest:RequestData:1:0.01 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>
SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>DDP:ProcessRequest:RequestData:0:0 <br>SDDP:ProcessRequest:RequestData+Crop:0:0 <br>
SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>SDDP:ProcessRequest:RequestData+Crop:1:0.01 <br>
DDP:ProcessRequest:RequestData:1:0.01 <br>SDDP:ProcessRequest:RequestData+Crop:1:0.01 <br>DDP:ProcessRequest:RequestData:0:0 <br>
SDDP:ProcessRequest:RequestData+Crop:0:0 <br>DDP:ProcessRequest:RequestData:0:0 <br>SDDP:ProcessRequest:RequestData+Crop:0:0 <br>
DDP:ProcessRequest:RequestData:1:0.01 <br>SDDP:ProcessRequest:RequestData+Crop:1:0.01 <br>DDP:ProcessRequest:RequestDataObject:0:0 <br>
DDP:ProcessRequest:RequestInformation:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>DDP:ProcessRequest:RequestDataObject:0:0 <br>
DDP:ProcessRequest:RequestInformation:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>DDP:ProcessRequest:RequestData:0:0 <br>
SDDP:ProcessRequest:RequestData+Crop:0:0 <br>DDP:ProcessRequest:RequestData:0:0 <br>SDDP:ProcessRequest:RequestData+Crop:0:0 <br>
DDP:ProcessRequest:RequestDataObject:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>
DDP:ProcessRequest:RequestDataObject:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>
DDP:ProcessRequest:RequestData:0:0 <br>SDDP:ProcessRequest:RequestData+Crop:0:0 <br>DDP:ProcessRequest:RequestData:0:0 <br>
SDDP:ProcessRequest:RequestData+Crop:0:0 <br>DDP:ProcessRequest:RequestDataObject:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>
SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>
DDP:ProcessRequest:RequestData:0:0 <br>SDDP:ProcessRequest:RequestData+Crop:0:0 <br>DDP:ProcessRequest:RequestData:0:0 <br>
SDDP:ProcessRequest:RequestData+Crop:0:0 <br>DDP:ProcessRequest:RequestData:0:0.01 <br>SDDP:ProcessRequest:RequestData+Crop:0:0.01 <br>
DDP:ProcessRequest:RequestDataObject:0:0 <br>DDP:ProcessRequest:RequestDataObject:0:0 <br>DDP:ProcessRequest:RequestInformation:0:0 <br>
DDP:ProcessRequest:RequestInformation:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>
DDP:ProcessRequest:RequestData:0:0 <br>SDDP:ProcessRequest:RequestData+Crop:0:0 <br>SDDP:ProcessRequest:RequestUpdateExtent:0:0 <br>
DDP:ProcessRequest:RequestDataObject:0:0<br>DDP:ProcessRequest:RequestInformation:0:0<br>SDDP:ProcessRequest:RequestUpdateExtent:0:0<br>SDDP:ProcessRequest:RequestUpdateExtent:0:0<br>DDP:ProcessRequest:RequestData:0:0<br>
SDDP:ProcessRequest:RequestData+Crop:0:0<br>SDDP:ProcessRequest:RequestUpdateExtent:0:0<br>SDDP:ProcessRequest:RequestUpdateExtent:0:0<br>DDP:ProcessRequest:RequestData:0:0<br>SDDP:ProcessRequest:RequestData+Crop:0:0<br>DDP:ProcessRequest:RequestData:0:0<br>
SDDP:ProcessRequest:RequestData+Crop:0:0<br>DDP:ProcessRequest:RequestData:0:0.01<br>SDDP:ProcessRequest:RequestData+Crop:0:0.01<br>CDP:ProcessRequest:RequestDataObject:0:0<br>CDP:ProcessRequest:RequestInformation:0:0<br>
CDP:ProcessRequest:RequestDataObject:1:0<br>CDP:ProcessRequest:RequestInformation:1:0<br>DDP:ProcessRequest:RequestInformation:0:0<br>CDP:ProcessRequest:RequestInformation:0:0<br>DDP:ProcessRequest:RequestInformation:1:0<br>
CDP:ProcessRequest:RequestInformation:1:0<br>CDP:ProcessRequest:RequestInformation:0:0<br>CDP:ProcessRequest:RequestInformation:1:0<br>CDP:ProcessRequest:RequestDataObject:0:CDP:ProcessRequest:RequestDataObject:1:0<br>0<br>
CDP:ProcessRequest:RequestDataObject:1:0<br>CDP:ProcessRequest:RequestDataObject:0:0<br>CDP:ProcessRequest:RequestInformation:0:0<br>CDP:ProcessRequest:RequestInformation:1:0<br>DDP:ProcessRequest:RequestInformation:0:0<br>
CDP:ProcessRequest:RequestInformation:0:DDP:ProcessRequest:RequestInformation:1:0<br>CDP:ProcessRequest:RequestInformation:1:0<br>0<br>DDP:ProcessRequest:RequestInformation:0:0<br>CDP:ProcessRequest:RequestInformation:0:0<br>
DDP:ProcessRequest:RequestInformation:1:0<br>CDP:ProcessRequest:RequestInformation:1:0<br>CDP:ProcessRequest:RequestDataObject:1:0<br>CDP:ProcessRequest:RequestInformation:1:0<br>CDP:ProcessRequest:RequestDataObject:0:0<br>
CDP:ProcessRequest:RequestInformation:0:0<br>DDP:ProcessRequest:RequestInformation:1:0<br>CDP:ProcessRequest:RequestInformation:1:DDP:ProcessRequest:RequestInformation:0:0<br>CDP:ProcessRequest:RequestInformation:0:0<br>SDDP:ProcessRequest:RequestUpdateExtent:0:0<br>
0<br>SDDP:ProcessRequest:RequestUpdateExtent:1:0<br>DDP:ProcessRequest:RequestData:1:0<br>SDDP:ProcessRequest:RequestData+Crop:1:0<br>DDP:ProcessRequest:RequestData:0:0<br>SDDP:ProcessRequest:RequestData+Crop:0:0<br>CDP:ProcessRequest:RequestDataObject:0:0<br>
CDP:ProcessRequest:RequestDataObject:1:0<br>CDP:ProcessRequest:RequestInformation:0:0<br>DDP:ProcessRequest:RequestInformation:0:0<br>CDP:ProcessRequest:RequestInformation:0:CDP:ProcessRequest:RequestInformation:1:0<br>DDP:ProcessRequest:RequestInformation:1:0<br>
CDP:ProcessRequest:RequestInformation:1:00<br><br>CDP:ProcessRequest:RequestInformation:0:0<br>CDP:ProcessRequest:RequestInformation:1:0<br><br><br><br><br><br><br><br><div class="gmail_quote">On Thu, Jul 9, 2009 at 6:41 AM, Berk Geveci <span dir="ltr"><<a href="mailto:berk.geveci@kitware.com">berk.geveci@kitware.com</a>></span> wrote:<br>
<blockquote class="gmail_quote" style="border-left: 1px solid rgb(204, 204, 204); margin: 0pt 0pt 0pt 0.8ex; padding-left: 1ex;">If you are look at a line like:<br>
<br>
Execute vtkSphereSource id: 115, 0.256536 seconds<br>
<br>
where vtkSphereSource should be replaced with your reader, it should<br>
be only the timing for your reader from right before its execution to<br>
right after. If you are looking at the render time, it would include<br>
other things. In the rest of the message, I am assuming that you are<br>
looking at a line like the one above. Even if not, some of my answers<br>
should shed some light on how timing works.</blockquote><div>In fact<br> </div><blockquote class="gmail_quote" style="border-left: 1px solid rgb(204, 204, 204); margin: 0pt 0pt 0pt 0.8ex; padding-left: 1ex;"><br>
<br>
During the RequestData pass, the executive does the following:<br>
<br>
- vtkDemandDrivePipeline::ExecuteDataStart() -> calls<br>
this->Algorithm->UpdateProgress(0.0) at the end<br>
- calls your algorithm<br>
- vtkDemandDrivePipeline::ExecuteDataEnd() -> calls<br>
this->Algorithm->UpdateProgress(1.0) at the beginning<br>
<br>
The timer log reports the time that passed between progress 0 and 1.<br>
There seems to be a big discrepancy between this number and what your<br>
timing show. So I'd guess that there is a bug somewhere. If you are<br>
calling UpdateProgress(), can you verify that it is doing the right<br>
thing? Also, you can insert your own timing code in<br>
vtkDemandDrivenPipeline to look for a bug in ParaView.<br>
<div class="im"><br>
> The more important question: What is going on behind the scenes in those 5<br>
> seconds after my reader executes?<br>
<br>
</div>I am going to say nothing - barring any bugs.<br>
<div class="im"><br>
> Can we safely say it's client server communication?<br>
<br>
</div>Nope. The timing should not include any client-server communication.<br>
<div class="im"><br>
> ParaView wont try to load ballance behind my back will it?<br>
<br>
</div>It depends. If this reader produces polydata or unstructured grid, are<br>
you setting MAXIMUM_NUMBER_OF_PIECES to -1 in RequestInformation? If<br>
this reader produces one of the structured datasets, are you producing<br>
exactly the UPDATE_EXTENT? Either way, the timing should not include<br>
any load balancing ParaView does. That should be reported separately.<br>
<div><div></div><div class="h5"><br>
On Tue, Jul 7, 2009 at 8:49 PM, Bam Ting<<a href="mailto:bampingting@gmail.com">bampingting@gmail.com</a>> wrote:<br>
> testing my reader plugin, I thought timer log would be a good way to go,<br>
> however not so sure what it's timing. running in client server mode, 2 , 4<br>
> and 8 processes so far.<br>
><br>
> I put some time computation in my request data method that show the read<br>
> takes less than 1 second on all processes and decreases as process are<br>
> added, however timer log reports nearly 6 seconds on all runs.<br>
><br>
> The more important question: What is going on behind the scenes in those 5<br>
> seconds after my reader executes?<br>
><br>
> Can we safely say it's client server communication?<br>
><br>
> ParaView wont try to load ballance behind my back will it?<br>
><br>
><br>
</div></div>> _______________________________________________<br>
> Powered by <a href="http://www.kitware.com" target="_blank">www.kitware.com</a><br>
><br>
> Visit other Kitware open-source projects at<br>
> <a href="http://www.kitware.com/opensource/opensource.html" target="_blank">http://www.kitware.com/opensource/opensource.html</a><br>
><br>
> Please keep messages on-topic and check the ParaView Wiki at:<br>
> <a href="http://paraview.org/Wiki/ParaView" target="_blank">http://paraview.org/Wiki/ParaView</a><br>
><br>
> Follow this link to subscribe/unsubscribe:<br>
> <a href="http://www.paraview.org/mailman/listinfo/paraview" target="_blank">http://www.paraview.org/mailman/listinfo/paraview</a><br>
><br>
><br>
</blockquote></div><br>