CASA Testing Log S. T. Myers (AOC) 2007-07-25 ============================================================ General logger comments (in rough priority order): 1. The "Copy" button seems to work haphazardly. It often just puts a bunch of blank lines (or with ":") instead of what you want. You currently pretty much have to go into the casapy.log file. If it cannot be made to work, remove it. It would also be good to have better copy capability, ie. to highlight parts of a message and only copy that bit. Or to grab only the message (and not the Time/Origin/Priority fields). I dont see an easy way to do that however if using a qt display, so probably have to leave that to going into the casapy.log file. 2. The "Time" column is in a poor order, currently Tue Jul 24 15:55:17 2007 Should be broken into "Time" and "Date", with e.g. Time Date 15:55:17 24-Jul-2007 and we dont need to know the day of week but if you insist then tack it on at the end. Consideration should be taken on how to specify time ranges in filtering. Ideally it would be the same way we do in MS selection or similar? 3. The "Origin" info is confusing and generally unhelpful, e.g. Tue Jul 24 16:11:55 2007 NORMAL Imager::open() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 398): This should be first and foremost the Task and/or Tool that the user called. Should split this into more columns, such as TASK and TOOL, TASK TOOL clean none if generated at the task level, or clean im.open if a tool call in a task, or none im.open if from toolkit directly. This would allow unnecessary columns to be be hidden by the user. Underneath, this could be as an origin string like clean::im.open Should probably ditch the file and line number info, leave that for crash info. At worst, put it as the message text in "debug" priority message. 4. Some messages go to the terminal window also. Many of these are debugging messages (e.g. in clean the line "phase center field index is 0"). If logger is running then I would say that only progress meters stuff and WARNING/SEVERE errors go to terminal. 5. There are lots of debugging-style lines in the logger. It would be good to get rid of unnecessary ones or if the CASA crew wants them badly, then tag them to allow filtering. I think there needs to be a level other than NORMAL for these, maybe something like DEBUG or INFO so they can be filtered out. NOTE: Joe and Boyd say that we will likely move to a numeric priority scale, 1-10. I would suggest 0-9 (keep to a single digit to make matching better) with well-defined criteria for the assignments. I suggest: 0 = SEVERE errors that cause the operation to fail 1 = WARNING it ran, but there are issues 2 = returninfo information that the user requested by running the task/tool (e.g. imstat) 3 = info diagnostic information helpful to the user to see what happened (e.g. total cleaned flux) 4 = progress information to indicate what is going on, progress through task (e.g. for timing) 5 = note a user supplied note (e.g. entered in logger, or via a "addnote" task) 6 = debug lowest priority, any debugging info the developers feel they have to include, but are limited utility for users (most of current messages) These levels seem sufficient to me, and should be fairly transparent for developers to assign messages to. The default display level in the logger would be "<6". Note that the above levels could be used with out numbers. The names were chosen to start with unique letters. Thus Priority=S would be same as 0 or SEVERE. I like the numbers. Note that the interaction with filtering should be considered, you'd want to be able to specify stuff like: "0" = only pri 0 "0,5" = pri 0 and 5 "<3" = pri < 3 (0,1,2) ">5" = pri > 5 "1-3" = pri 1,2,3 With non-numerical priorities would have to just list them. 6. Filtering seems to be minmatch anywhere in string (e.g. "N" gets NORMAL, as does any of its letters like "L"). I'll need to document this better. How to get NORMAL and SEVERE? ("R" will work but "R" is in WARNING also). Or WARNING and SEVERE? Or everything but NORMAL? This seems inadequate - numbers would help. Maybe an enumerated menu (that you check off) is better? Or regexp? Or SQL? Maybe messageql="priority==0 && task==clean" Similar concerns for Searching. 7. The "Open" menu/button is disabled. I assume this is to allow opening of different casapy.log* files. Will this be enabled at some point? 8. Currently, if you run "casapy --nolog" the logger data only goes to the casapy.log file. This is right, "--nolog" should mean NO LOG, but there should also be a "casapy --termlog" option to send the logger messages to the term window. 9. It is desirable that the user be able to specify (like in environment variables) what the logger should show and in what order. The View menu where you can enable/disable columns is good enough, but maybe more flexibility would be possible. 10. It might be helpful to include the parameters of the task or tool call in the logger when a task/tool execution is started. You could add a new priority level for this, say make 7=debug and 6=execute. This could be the same line as in the .last file, e.g. clean(vis="foo.ms",imagename="jupiter6cm.usecase.clean1",mode="mfs",alg="clark",niter=10000,gain=0.1,threshold=0.05,mask="",cleanbo x="interactive",nchan=-1,start=0,width=1,step=1,imsize=[288, 288],cell=[4.0, 4.0],stokes="I",field="*",spw=0,weighting="briggs",rmod e="norm",robust=0.5,uvfilter=False,uvfilterbmaj=1.0,uvfilterbmin=1.0,uvfilterbpa=0.0,selecttime="1960/01/01/00:00:00~2020/12/31/23:5 9:59",restfreq="",noise="0.0Jy",npixels=0,scales=[0, 3, 10],npercycle=100,async=False) ============================================================ Test1 - continuum imaging Jupiter VLA 6cm dataset The following is the logger output from an interactive clean session: ------------------------------------------------------------ Tue Jul 24 15:55:17 2007 NORMAL ::casa::logger: CASA Version 2.0 Rev 2834 Tue Jul 24 15:55:17 2007 NORMAL ::casa::logger: Built on: Mon 2007/07/23 01:15:05 UTC Tue Jul 24 15:55:20 2007 NORMAL "created message log." Tue Jul 24 16:11:55 2007 NORMAL Imager::open() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 398): Opening MeasurementSet /home/sandrock2/smyers/Testing2/Jul07/jupiter6cm.usecase.split.ms Tue Jul 24 16:11:55 2007 NORMAL imager::data selection "Selecting data" Tue Jul 24 16:11:55 2007 NORMAL imager::data selection: Performing selection on MeasurementSet Tue Jul 24 16:11:55 2007 NORMAL imager::data selection: Selecting on field names * Tue Jul 24 16:11:55 2007 NORMAL imager::data selection: Selecting on spectral windows expression 0 Tue Jul 24 16:11:56 2007 NORMAL imager::data selection: By selection 114750 rows are reduced to 57375 Tue Jul 24 16:11:56 2007 NORMAL Imager::selectDataChannel() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 7402): Selecting 1 channels, starting at visibility channel 0 stepped by 1 for spw 0 Tue Jul 24 16:11:56 2007 NORMAL imager::defineimage(): Defining image properties Tue Jul 24 16:11:56 2007 NORMAL imager::weight(): Weighting MS: IMAGING_WEIGHT column will be changed Tue Jul 24 16:11:56 2007 NORMAL imager::weight(): Briggs weighting: sidelobes will be suppressed over full image : using 288 pixels in the uv plane Tue Jul 24 16:11:56 2007 NORMAL VisSetUtil::WeightUniform() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/msvis/implement/MSVis/VisSetUtil.cc, line 121): Normal robustness, robust = 0.5 Tue Jul 24 16:11:56 2007 NORMAL imager::weight(): Sum of weights = 6067.17 Tue Jul 24 16:11:56 2007 NORMAL imager::clean() "Cleaning images" Tue Jul 24 16:11:56 2007 NORMAL imager::clean(): Found 1 specified model images Tue Jul 24 16:11:56 2007 NORMAL imager::make() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 5500): Making empty image: jupiter6cm.usecase.clean1.model Tue Jul 24 16:11:56 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Frequency = 4.8851 GHz, synthesized continuum bandwidth = 0.1 GHz Tue Jul 24 16:11:57 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Preferred polarization representation is circular Tue Jul 24 16:11:57 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Image polarization = Stokes I Tue Jul 24 16:11:57 2007 NORMAL imager::createFTMachine() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 6443): Performing interferometric gridding with convolution function SF Tue Jul 24 16:11:57 2007 NORMAL imager::createFTMachine() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 6443): Single facet Fourier transforms will use image center as tangent points Tue Jul 24 16:11:57 2007 NORMAL imager::createSkyEquation() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 6803): Processing single field with simple sky equation Tue Jul 24 16:11:57 2007 NORMAL imager::clean() "Created Sky Equation" Tue Jul 24 16:11:57 2007 NORMAL imager::clean(): Clean gain = 0.1, Niter = 1, Threshold = 0.05 mJy, Algorithm = clark Tue Jul 24 16:11:57 2007 NORMAL imager::clean(): Starting deconvolution Tue Jul 24 16:11:59 2007 NORMAL ClarkCleanLatModel::solve: Initial maximum residual: 1.04284 Tue Jul 24 16:11:59 2007 NORMAL ClarkCleanLatModel::solve: Iteration: 1, Maximum residual=0.941964 Tue Jul 24 16:11:59 2007 NORMAL ClarkCleanImageSkyModel::solve (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementComponents/ClarkCleanImageSkyModel.cc, line 64): Clean used 1 iterations to get to a max residual of 0.941964 Tue Jul 24 16:11:59 2007 NORMAL imager::clean(): Clean did not reach threshold Tue Jul 24 16:11:59 2007 NORMAL imager::clean(): Fitted beam used in restoration: 16.7434 by 13.5432 (arcsec) at pa -38.0995 (deg) Tue Jul 24 16:12:33 2007 NORMAL imager::clean() "Cleaning images" Tue Jul 24 16:12:33 2007 NORMAL imager::clean(): Found 1 specified model images Tue Jul 24 16:12:33 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Frequency = 4.8851 GHz, synthesized continuum bandwidth = 0.1 GHz Tue Jul 24 16:12:33 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Preferred polarization representation is circular Tue Jul 24 16:12:33 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Image polarization = Stokes I Tue Jul 24 16:12:33 2007 NORMAL imager::clean(): Clean gain = 0.1, Niter = 100, Threshold = 0.05 mJy, Algorithm = clark Tue Jul 24 16:12:33 2007 NORMAL imager::clean(): Starting deconvolution Tue Jul 24 16:12:33 2007 NORMAL ClarkCleanLatModel::solve: Initial maximum residual: 0.941964 Tue Jul 24 16:12:33 2007 NORMAL ClarkCleanLatModel::solve: Iteration: 45, Maximum residual=0.24661 Tue Jul 24 16:12:33 2007 NORMAL ClarkCleanLatModel::solve: Iteration: 100, Maximum residual=0.100538 Tue Jul 24 16:12:33 2007 NORMAL ClarkCleanImageSkyModel::solve (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementComponents/ClarkCleanImageSkyModel.cc, line 64): Clean used 100 iterations to get to a max residual of 0.100538 Tue Jul 24 16:12:33 2007 NORMAL imager::clean(): Clean did not reach threshold Tue Jul 24 16:12:33 2007 NORMAL imager::clean(): Beam used in restoration: 16.7434 by 13.5432 (arcsec) at pa -38.0995 (deg) Tue Jul 24 16:13:05 2007 NORMAL imager::clean() "Cleaning images" Tue Jul 24 16:13:05 2007 NORMAL imager::clean(): Found 1 specified model images Tue Jul 24 16:13:05 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Frequency = 4.8851 GHz, synthesized continuum bandwidth = 0.1 GHz Tue Jul 24 16:13:05 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Preferred polarization representation is circular Tue Jul 24 16:13:05 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Image polarization = Stokes I Tue Jul 24 16:13:05 2007 NORMAL imager::clean(): Clean gain = 0.1, Niter = 100, Threshold = 0.05 mJy, Algorithm = clark Tue Jul 24 16:13:05 2007 NORMAL imager::clean(): Starting deconvolution Tue Jul 24 16:13:06 2007 NORMAL ClarkCleanLatModel::solve: Initial maximum residual: 0.100538 Tue Jul 24 16:13:06 2007 NORMAL ClarkCleanLatModel::solve: Iteration: 100, Maximum residual=0.0380255 Tue Jul 24 16:13:06 2007 NORMAL ClarkCleanImageSkyModel::solve (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementComponents/ClarkCleanImageSkyModel.cc, line 64): Clean used 100 iterations to get to a max residual of 0.0380255 Tue Jul 24 16:13:06 2007 NORMAL imager::clean(): Clean did not reach threshold Tue Jul 24 16:13:06 2007 NORMAL imager::clean(): Beam used in restoration: 16.7434 by 13.5432 (arcsec) at pa -38.0995 (deg) Tue Jul 24 16:13:45 2007 NORMAL imager::clean() "Cleaning images" Tue Jul 24 16:13:45 2007 NORMAL imager::clean(): Found 1 specified model images Tue Jul 24 16:13:45 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Frequency = 4.8851 GHz, synthesized continuum bandwidth = 0.1 GHz Tue Jul 24 16:13:45 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Preferred polarization representation is circular Tue Jul 24 16:13:45 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Image polarization = Stokes I Tue Jul 24 16:13:45 2007 NORMAL imager::clean(): Clean gain = 0.1, Niter = 100, Threshold = 0.05 mJy, Algorithm = clark Tue Jul 24 16:13:45 2007 NORMAL imager::clean(): Starting deconvolution Tue Jul 24 16:13:45 2007 NORMAL ClarkCleanLatModel::solve: Initial maximum residual: 0.0382163 Tue Jul 24 16:13:45 2007 NORMAL ClarkCleanLatModel::solve: Iteration: 100, Maximum residual=0.0209061 Tue Jul 24 16:13:45 2007 NORMAL ClarkCleanImageSkyModel::solve (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementComponents/ClarkCleanImageSkyModel.cc, line 64): Clean used 100 iterations to get to a max residual of 0.0209061 Tue Jul 24 16:13:45 2007 NORMAL imager::clean(): Clean did not reach threshold Tue Jul 24 16:13:45 2007 NORMAL imager::clean(): Beam used in restoration: 16.7434 by 13.5432 (arcsec) at pa -38.0995 (deg) Tue Jul 24 16:14:16 2007 NORMAL imager::clean() "Cleaning images" Tue Jul 24 16:14:17 2007 NORMAL imager::clean(): Found 1 specified model images Tue Jul 24 16:14:17 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Frequency = 4.8851 GHz, synthesized continuum bandwidth = 0.1 GHz Tue Jul 24 16:14:17 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Preferred polarization representation is circular Tue Jul 24 16:14:17 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Image polarization = Stokes I Tue Jul 24 16:14:17 2007 NORMAL imager::clean(): Clean gain = 0.1, Niter = 100, Threshold = 0.05 mJy, Algorithm = clark Tue Jul 24 16:14:17 2007 NORMAL imager::clean(): Starting deconvolution Tue Jul 24 16:14:17 2007 NORMAL ClarkCleanLatModel::solve: Initial maximum residual: 0.0209061 Tue Jul 24 16:14:17 2007 NORMAL ClarkCleanLatModel::solve: Iteration: 100, Maximum residual=0.0125395 Tue Jul 24 16:14:17 2007 NORMAL ClarkCleanImageSkyModel::solve (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementComponents/ClarkCleanImageSkyModel.cc, line 64): Clean used 100 iterations to get to a max residual of 0.0125395 Tue Jul 24 16:14:17 2007 NORMAL imager::clean(): Clean did not reach threshold Tue Jul 24 16:14:17 2007 NORMAL imager::clean(): Beam used in restoration: 16.7434 by 13.5432 (arcsec) at pa -38.0995 (deg) Tue Jul 24 16:14:47 2007 NORMAL imager::clean() "Cleaning images" Tue Jul 24 16:14:47 2007 NORMAL imager::clean(): Found 1 specified model images Tue Jul 24 16:14:47 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Frequency = 4.8851 GHz, synthesized continuum bandwidth = 0.1 GHz Tue Jul 24 16:14:47 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Preferred polarization representation is circular Tue Jul 24 16:14:47 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Image polarization = Stokes I Tue Jul 24 16:14:47 2007 NORMAL imager::clean(): Clean gain = 0.1, Niter = 100, Threshold = 0.05 mJy, Algorithm = clark Tue Jul 24 16:14:47 2007 NORMAL imager::clean(): Starting deconvolution Tue Jul 24 16:14:47 2007 NORMAL ClarkCleanLatModel::solve: Initial maximum residual: 0.0125394 Tue Jul 24 16:14:47 2007 NORMAL ClarkCleanLatModel::solve: Iteration: 100, Maximum residual=0.00812454 Tue Jul 24 16:14:47 2007 NORMAL ClarkCleanImageSkyModel::solve (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementComponents/ClarkCleanImageSkyModel.cc, line 64): Clean used 100 iterations to get to a max residual of 0.00812454 Tue Jul 24 16:14:47 2007 NORMAL imager::clean(): Clean did not reach threshold Tue Jul 24 16:14:47 2007 NORMAL imager::clean(): Beam used in restoration: 16.7434 by 13.5432 (arcsec) at pa -38.0995 (deg) Tue Jul 24 16:14:56 2007 NORMAL imager::clean() "Cleaning images" Tue Jul 24 16:14:56 2007 NORMAL imager::clean(): Found 1 specified model images Tue Jul 24 16:14:56 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Frequency = 4.8851 GHz, synthesized continuum bandwidth = 0.1 GHz Tue Jul 24 16:14:56 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Preferred polarization representation is circular Tue Jul 24 16:14:56 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Image polarization = Stokes I Tue Jul 24 16:14:56 2007 NORMAL imager::clean(): Clean gain = 0.1, Niter = 100, Threshold = 0.05 mJy, Algorithm = clark Tue Jul 24 16:14:56 2007 NORMAL imager::clean(): Starting deconvolution Tue Jul 24 16:14:56 2007 NORMAL ClarkCleanLatModel::solve: Initial maximum residual: 0.00812454 Tue Jul 24 16:14:56 2007 NORMAL ClarkCleanLatModel::solve: Iteration: 100, Maximum residual=0.00551873 Tue Jul 24 16:14:56 2007 NORMAL ClarkCleanImageSkyModel::solve (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementComponents/ClarkCleanImageSkyModel.cc, line 64): Clean used 100 iterations to get to a max residual of 0.00551873 Tue Jul 24 16:14:56 2007 NORMAL imager::clean(): Clean did not reach threshold Tue Jul 24 16:14:56 2007 NORMAL imager::clean(): Beam used in restoration: 16.7434 by 13.5432 (arcsec) at pa -38.0995 (deg) Tue Jul 24 16:15:20 2007 NORMAL imager::clean() "Cleaning images" Tue Jul 24 16:15:20 2007 NORMAL imager::clean(): Found 1 specified model images Tue Jul 24 16:15:20 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Frequency = 4.8851 GHz, synthesized continuum bandwidth = 0.1 GHz Tue Jul 24 16:15:20 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Preferred polarization representation is circular Tue Jul 24 16:15:20 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Image polarization = Stokes I Tue Jul 24 16:15:20 2007 NORMAL imager::clean(): Clean gain = 0.1, Niter = 100, Threshold = 0.05 mJy, Algorithm = clark Tue Jul 24 16:15:20 2007 NORMAL imager::clean(): Starting deconvolution Tue Jul 24 16:15:20 2007 NORMAL ClarkCleanLatModel::solve: Initial maximum residual: 0.00570722 Tue Jul 24 16:15:20 2007 NORMAL ClarkCleanLatModel::solve: Iteration: 100, Maximum residual=0.00391872 Tue Jul 24 16:15:20 2007 NORMAL ClarkCleanImageSkyModel::solve (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementComponents/ClarkCleanImageSkyModel.cc, line 64): Clean used 100 iterations to get to a max residual of 0.00391872 Tue Jul 24 16:15:20 2007 NORMAL imager::clean(): Clean did not reach threshold Tue Jul 24 16:15:20 2007 NORMAL imager::clean(): Beam used in restoration: 16.7434 by 13.5432 (arcsec) at pa -38.0995 (deg) Tue Jul 24 16:15:36 2007 NORMAL imager::clean() "Cleaning images" Tue Jul 24 16:15:36 2007 NORMAL imager::clean(): Found 1 specified model images Tue Jul 24 16:15:36 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Frequency = 4.8851 GHz, synthesized continuum bandwidth = 0.1 GHz Tue Jul 24 16:15:36 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Preferred polarization representation is circular Tue Jul 24 16:15:36 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Image polarization = Stokes I Tue Jul 24 16:15:36 2007 NORMAL imager::clean(): Clean gain = 0.1, Niter = 100, Threshold = 0.05 mJy, Algorithm = clark Tue Jul 24 16:15:36 2007 NORMAL imager::clean(): Starting deconvolution Tue Jul 24 16:15:36 2007 NORMAL ClarkCleanLatModel::solve: Initial maximum residual: 0.00391871 Tue Jul 24 16:15:36 2007 NORMAL ClarkCleanLatModel::solve: Iteration: 100, Maximum residual=0.00280958 Tue Jul 24 16:15:36 2007 NORMAL ClarkCleanImageSkyModel::solve (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementComponents/ClarkCleanImageSkyModel.cc, line 64): Clean used 100 iterations to get to a max residual of 0.00280958 Tue Jul 24 16:15:36 2007 NORMAL imager::clean(): Clean did not reach threshold Tue Jul 24 16:15:36 2007 NORMAL imager::clean(): Beam used in restoration: 16.7434 by 13.5432 (arcsec) at pa -38.0995 (deg) Tue Jul 24 16:15:52 2007 NORMAL imager::clean() "Cleaning images" Tue Jul 24 16:15:52 2007 NORMAL imager::clean(): Found 1 specified model images Tue Jul 24 16:15:52 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Frequency = 4.8851 GHz, synthesized continuum bandwidth = 0.1 GHz Tue Jul 24 16:15:52 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Preferred polarization representation is circular Tue Jul 24 16:15:52 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Image polarization = Stokes I Tue Jul 24 16:15:52 2007 NORMAL imager::clean(): Clean gain = 0.1, Niter = 100, Threshold = 0.05 mJy, Algorithm = clark Tue Jul 24 16:15:52 2007 NORMAL imager::clean(): Starting deconvolution Tue Jul 24 16:15:52 2007 NORMAL ClarkCleanLatModel::solve: Initial maximum residual: 0.00282178 Tue Jul 24 16:15:52 2007 NORMAL ClarkCleanLatModel::solve: Iteration: 100, Maximum residual=0.00204491 Tue Jul 24 16:15:52 2007 NORMAL ClarkCleanImageSkyModel::solve (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementComponents/ClarkCleanImageSkyModel.cc, line 64): Clean used 100 iterations to get to a max residual of 0.00204491 Tue Jul 24 16:15:53 2007 NORMAL imager::clean(): Clean did not reach threshold Tue Jul 24 16:15:53 2007 NORMAL imager::clean(): Beam used in restoration: 16.7434 by 13.5432 (arcsec) at pa -38.0995 (deg) ============================================================ Now here are the same lines with my comments: = remove this line = need to add a line here = this line is debug priority (see above) you may or may not want to keep these = this line is progress priority (see above) = this line is diagnostic information priority (see above) = this line is return information priority (see above) = this is my comment on the previous line in particular ------------------------------------------------------------ Tue Jul 24 16:11:55 2007 NORMAL Imager::open() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 398): Opening MeasurementSet /home/sandrock2/smyers/Testing2/Jul07/jupiter6cm.usecase.split.ms get rid of the extraneous stuff like file and line number or move into separate message text Tue Jul 24 16:11:55 2007 NORMAL imager::data selection "Selecting data" this should have an origin in a tool, not just "data selection" Tue Jul 24 16:11:55 2007 NORMAL imager::data selection: Performing selection on MeasurementSet Tue Jul 24 16:11:55 2007 NORMAL imager::data selection: Selecting on field names * Tue Jul 24 16:11:55 2007 NORMAL imager::data selection: Selecting on spectral windows expression 0 Tue Jul 24 16:11:56 2007 NORMAL imager::data selection: By selection 114750 rows are reduced to 57375 rows do not mean anything to average user, could this reported in visibilities? Tue Jul 24 16:11:56 2007 NORMAL Imager::selectDataChannel() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 7402): Selecting 1 channels, starting at visibility channel 0 stepped by 1 for spw 0 get rid of the extraneous stuff like file and line number or move into separate message text Tue Jul 24 16:11:56 2007 NORMAL imager::defineimage(): Defining image properties Tue Jul 24 16:11:56 2007 NORMAL imager::weight(): Weighting MS: IMAGING_WEIGHT column will be changed Tue Jul 24 16:11:56 2007 NORMAL imager::weight(): Briggs weighting: sidelobes will be suppressed over full image : using 288 pixels in the uv plane Is there something more helpful you can say (im not sure what 288 pixels in uv-plane implies) Tue Jul 24 16:11:56 2007 NORMAL VisSetUtil::WeightUniform() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/msvis/implement/MSVis/VisSetUtil.cc, line 121): Normal robustness, robust = 0.5 The origin of this message is way too deep in code, its more like a debug statement now. Keep to clean::im.weight and put other stuff in text if you still want this. Tue Jul 24 16:11:56 2007 NORMAL imager::weight(): Sum of weights = 6067.17 Tue Jul 24 16:11:56 2007 NORMAL imager::clean() "Cleaning images" Tue Jul 24 16:11:56 2007 NORMAL imager::clean(): Found 1 specified model images Tue Jul 24 16:11:56 2007 NORMAL imager::make() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 5500): Making empty image: jupiter6cm.usecase.clean1.model Tue Jul 24 16:11:56 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Frequency = 4.8851 GHz, synthesized continuum bandwidth = 0.1 GHz is that a center frequency? Tue Jul 24 16:11:57 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Preferred polarization representation is circular Tue Jul 24 16:11:57 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Image polarization = Stokes I Is this something to say once at beginning or is it telling about progress through polarizations (e.g. for IQUV)? Tue Jul 24 16:11:57 2007 NORMAL imager::createFTMachine() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 6443): Performing interferometric gridding with convolution function SF What does this mean? Spheroidal? Tue Jul 24 16:11:57 2007 NORMAL imager::createFTMachine() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 6443): Single facet Fourier transforms will use image center as tangent points Tue Jul 24 16:11:57 2007 NORMAL imager::createSkyEquation() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 6803): Processing single field with simple sky equation Tue Jul 24 16:11:57 2007 NORMAL imager::clean() "Created Sky Equation" Tue Jul 24 16:11:57 2007 NORMAL imager::clean(): Clean gain = 0.1, Niter = 1, Threshold = 0.05 mJy, Algorithm = clark Tue Jul 24 16:11:57 2007 NORMAL imager::clean(): Starting deconvolution Tue Jul 24 16:11:59 2007 NORMAL ClarkCleanLatModel::solve: Initial maximum residual: 1.04284 Tue Jul 24 16:11:59 2007 NORMAL ClarkCleanLatModel::solve: Iteration: 1, Maximum residual=0.941964 This seems redundant with next line Tue Jul 24 16:11:59 2007 NORMAL ClarkCleanImageSkyModel::solve (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementComponents/ClarkCleanImageSkyModel.cc, line 64): Clean used 1 iterations to get to a max residual of 0.941964 Note that I am not enthused about doing a 1 iteration clean just to get the starting image for interactive clean. You should make a true dirty image for this, or at least set the gain=0.001 for this spurious iteration. Tue Jul 24 16:11:59 2007 NORMAL imager::clean(): Clean did not reach threshold Just say when it DOES reach threshold, or at end if it doesnt. Or do you mean some major cycle cutoff? Tue Jul 24 16:11:59 2007 NORMAL imager::clean(): Fitted beam used in restoration: 16.7434 by 13.5432 (arcsec) at pa -38.0995 (deg) This can be presented once during the whole clean since it will not change - report this earlier on? Shouldnt it be computed once at the beginning rather than each maj cycle? Tue Jul 24 16:12:33 2007 NORMAL imager::clean() "Cleaning images" Maybe more info, since this is restarting after interactive setting. Maybe mention new regions set or npercycle changed? Tue Jul 24 16:12:33 2007 NORMAL imager::clean(): Found 1 specified model images Since this doesnt change during clean, why keep repeating it? Should put these sort of messages higher up rather than in low-level routines then. There are lots of these... Tue Jul 24 16:12:33 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Frequency = 4.8851 GHz, synthesized continuum bandwidth = 0.1 GHz Since this doesnt change during clean, why keep repeating it? Tue Jul 24 16:12:33 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Preferred polarization representation is circular Since this doesnt change during clean, why keep repeating it? Tue Jul 24 16:12:33 2007 NORMAL Imager::imagecoordinates() (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementEquations/Imager.cc, line 550): Image polarization = Stokes I Since this doesnt change during clean, why keep repeating it? Tue Jul 24 16:12:33 2007 NORMAL imager::clean(): Clean gain = 0.1, Niter = 100, Threshold = 0.05 mJy, Algorithm = clark this Niter is really an npercycle? Tue Jul 24 16:12:33 2007 NORMAL imager::clean(): Starting deconvolution Tue Jul 24 16:12:33 2007 NORMAL ClarkCleanLatModel::solve: Initial maximum residual: 0.941964 Tue Jul 24 16:12:33 2007 NORMAL ClarkCleanLatModel::solve: Iteration: 45, Maximum residual=0.24661 I assume this is a major cycle break? Tue Jul 24 16:12:33 2007 NORMAL ClarkCleanLatModel::solve: Iteration: 100, Maximum residual=0.100538 Tue Jul 24 16:12:33 2007 NORMAL ClarkCleanImageSkyModel::solve (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementComponents/ClarkCleanImageSkyModel.cc, line 64): Clean used 100 iterations to get to a max residual of 0.100538 Should include total clean flux, and the iteration count should be from zero, not just in this interactive cycle. Tue Jul 24 16:12:33 2007 NORMAL imager::clean(): Clean did not reach threshold Tue Jul 24 16:12:33 2007 NORMAL imager::clean(): Beam used in restoration: 16.7434 by 13.5432 (arcsec) at pa -38.0995 (deg) This was the same as before. ... a bunch of the same stuff for each interactive cycle ... Tue Jul 24 16:15:52 2007 NORMAL ClarkCleanImageSkyModel::solve (file /usr/src/redhat/BUILD/casapy-svn-20.0.2763/code/synthesis/implement/MeasurementComponents/ClarkCleanImageSkyModel.cc, line 64): Clean used 100 iterations to get to a max residual of 0.00204491 Should include total clean flux, and the iteration count should be from zero, not just in this interactive cycle. Tue Jul 24 16:15:53 2007 NORMAL imager::clean(): Clean did not reach threshold This was the final iteration so it is appropriate to put this here. Tue Jul 24 16:15:53 2007 NORMAL imager::clean(): Beam used in restoration: 16.7434 by 13.5432 (arcsec) at pa -38.0995 (deg) There should be a task completion line for each task, so you can figure out when a task ended going back through the log. This would be progress level () ------------------------------------------------------------