Build log

The build generated 97,572 lines of output.The output is too long and has been truncated to the last 1,000 lines. Download or view full build log.

09-Dec-2024 12:04:07	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :1
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :1
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :1
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :1
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :2
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :2
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :2
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :2
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :2
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :2
09-Dec-2024 12:04:10	2024-12-09 12:04:08        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.25
09-Dec-2024 12:04:10	2024-12-09 12:04:08        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0.07
09-Dec-2024 12:04:10	2024-12-09 12:04:08        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.14
09-Dec-2024 12:04:10	2024-12-09 12:04:08        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.18
09-Dec-2024 12:04:10	2024-12-09 12:04:08        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.36
09-Dec-2024 12:04:10	2024-12-09 12:04:08        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.18
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [9073.97]
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (7.5566,1.72187) (0,0)
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8815.97]
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (-10.8598,2.46331) (0,0)
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [9073.97]
09-Dec-2024 12:04:10	2024-12-09 12:04:08        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (-7.72009,1.01674) (0,0)
09-Dec-2024 12:04:10	2024-12-09 12:04:09        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.11
09-Dec-2024 12:04:10	2024-12-09 12:04:09        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.04
09-Dec-2024 12:04:10	2024-12-09 12:04:09        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.1
09-Dec-2024 12:04:10	2024-12-09 12:04:09        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0.2
09-Dec-2024 12:04:10	2024-12-09 12:04:09        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.08
09-Dec-2024 12:04:10	2024-12-09 12:04:09        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.08
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [-0.402012]
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (-1.73697,-0.61926) (0,0)
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [0.802125]
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (1.44245,0.451687) (0,0)
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [-0.406142]
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (1.20228,0.290874) (0,0)
09-Dec-2024 12:04:10	2024-12-09 12:04:09        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.01
09-Dec-2024 12:04:10	2024-12-09 12:04:09        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.05
09-Dec-2024 12:04:10	2024-12-09 12:04:09        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.16
09-Dec-2024 12:04:10	2024-12-09 12:04:09        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0.06
09-Dec-2024 12:04:10	2024-12-09 12:04:09        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.06
09-Dec-2024 12:04:10	2024-12-09 12:04:09        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.08
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [241.999]
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (0.314794,0.0235001) (0,0)
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [241.999]
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (0.302587,0.056323) (0,0)
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [235.039]
09-Dec-2024 12:04:10	2024-12-09 12:04:09        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (-0.258059,0.0692248) (0,0)
09-Dec-2024 12:04:10	2024-12-09 12:04:10        INFO        tclean::::casa        ...done
09-Dec-2024 12:04:13	2024-12-09 12:04:10        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
09-Dec-2024 12:04:13	2024-12-09 12:04:11        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Hessian Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:13	2024-12-09 12:04:11        INFO        SIImageStoreMultiTerm::calcSensitivity +        [26963.9, -0.00602937
09-Dec-2024 12:04:13	2024-12-09 12:04:11        INFO        SIImageStoreMultiTerm::calcSensitivity +         -0.00602937, 719.037]
09-Dec-2024 12:04:13	2024-12-09 12:04:11        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Covariance Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:13	2024-12-09 12:04:11        INFO        SIImageStoreMultiTerm::calcSensitivity +        [3.70866e-05, 3.10983e-10
09-Dec-2024 12:04:13	2024-12-09 12:04:11        INFO        SIImageStoreMultiTerm::calcSensitivity +         3.10983e-10, 0.00139075]
09-Dec-2024 12:04:13	2024-12-09 12:04:11        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor0] Theoretical sensitivity (Jy/bm):0.00608988
09-Dec-2024 12:04:13	2024-12-09 12:04:11        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor1] Theoretical sensitivity (Jy/bm):0.0372927
09-Dec-2024 12:04:13	2024-12-09 12:04:11        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 1.04999 GHz -> 1.94998GHz.Fractional Bandwidth : 60.0001 %.Reference Frequency for Taylor Expansion : 1.49998GHz.
09-Dec-2024 12:04:13	2024-12-09 12:04:11        WARN        SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2089)        [Chan0:Pol0] Error Gaussian fit to PSF : Peak of psf is outside the inner quarter of defined image :  Setting restoring beam to largest valid beam.
09-Dec-2024 12:04:13	2024-12-09 12:04:11        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.003187
09-Dec-2024 12:04:13	2024-12-09 12:04:11        INFO        SIImageStore::printBeamSet         Beam : 1e-06 arcsec, 1e-06 arcsec, 0 deg
09-Dec-2024 12:04:13	2024-12-09 12:04:11        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:04:13	2024-12-09 12:04:12        INFO        tclean::::casa        ...done
09-Dec-2024 12:04:13	2024-12-09 12:04:12        INFO        SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n3]
09-Dec-2024 12:04:13	2024-12-09 12:04:12        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt0
09-Dec-2024 12:04:13	2024-12-09 12:04:12        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt1
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt0
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt1
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt0
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt1
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :0
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :0
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :0
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :0
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :0
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :0
09-Dec-2024 12:04:13	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :1
09-Dec-2024 12:04:14	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :1
09-Dec-2024 12:04:14	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :1
09-Dec-2024 12:04:14	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :1
09-Dec-2024 12:04:14	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :1
09-Dec-2024 12:04:14	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :1
09-Dec-2024 12:04:14	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :2
09-Dec-2024 12:04:14	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :2
09-Dec-2024 12:04:14	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :2
09-Dec-2024 12:04:14	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :2
09-Dec-2024 12:04:14	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :2
09-Dec-2024 12:04:14	2024-12-09 12:04:13        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :2
09-Dec-2024 12:04:14	2024-12-09 12:04:14        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.18
09-Dec-2024 12:04:14	2024-12-09 12:04:14        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.03
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [8830.19]
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (7.43783,0.906734) (0,0)
09-Dec-2024 12:04:14	2024-12-09 12:04:14        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.2
09-Dec-2024 12:04:14	2024-12-09 12:04:14        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.05
09-Dec-2024 12:04:14	2024-12-09 12:04:14        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.2
09-Dec-2024 12:04:14	2024-12-09 12:04:14        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0.01
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [8838.74]
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (8.42712,-1.07796) (0,0)
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8583.62]
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (-11.3477,1.13298) (0,0)
09-Dec-2024 12:04:14	2024-12-09 12:04:14        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.16
09-Dec-2024 12:04:14	2024-12-09 12:04:14        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.03
09-Dec-2024 12:04:14	2024-12-09 12:04:14        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.14
09-Dec-2024 12:04:14	2024-12-09 12:04:14        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.01
09-Dec-2024 12:04:14	2024-12-09 12:04:14        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.14
09-Dec-2024 12:04:14	2024-12-09 12:04:14        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0.02
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [-9.40991]
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (2.11689,0.618377) (0,0)
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [-8.47029]
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (1.31295,0.0747608) (0,0)
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [-7.52245]
09-Dec-2024 12:04:14	2024-12-09 12:04:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (1.82303,0.542117) (0,0)
09-Dec-2024 12:04:14	2024-12-09 12:04:15        INFO        tclean::::casa        ...done
09-Dec-2024 12:04:14	2024-12-09 12:04:15        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
09-Dec-2024 12:04:14	2024-12-09 12:04:15        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:14	2024-12-09 12:04:15        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:14	2024-12-09 12:04:15        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:15	2024-12-09 12:04:15        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:15	2024-12-09 12:04:15        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:15	2024-12-09 12:04:15        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:15	2024-12-09 12:04:15        WARN        task_tclean::SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.0651419, over full image : 0.0651419
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing new mask to 1.0 for the full image
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 262144 out of a total of 262144 pixels. [ 100 % ]
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.0651419, over full image : 0.0651419
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 30, 'cyclethreshold': 0.05211351066827774, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        SDAlgorithmBase::deconvolve         [tst] Run mtmfs minor-cycle | CycleThreshold=0.0521135, CycleNiter=30, Gain=0.1
09-Dec-2024 12:04:15	2024-12-09 12:04:15        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:15	2024-12-09 12:04:15        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:15	2024-12-09 12:04:15        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:15	2024-12-09 12:04:15        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        MultiTermMatrixCleaner::verifyScaleSizes()         Scale sizes to be used for deconvolution : [0]
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        MultiTermMatrixCleaner::initialise()         Using a PSF patch of 80 pixels on each side for minor-cycle updates.
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 0.0171854 at [256, 256]
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 1.04999 GHz -> 1.94998GHz.Fractional Bandwidth : 60.0001 %.Reference Frequency for Taylor Expansion : 1.49998GHz.
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        MultiTermMatrixCleaner::computeHessianPeak         Calculating PSF and Scale convolutions
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 0 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.0171854, 5.8489e-05
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        MultiTermMatrixCleaner::computeHessianPeak +         5.8489e-05, 0.000169726]
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 3.79054
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO                [20] Res: 3.16596 Max: 0.409851 Gain: 0.1 Pos: [220, 155] Scale: 0 Coeffs: 2.88868  38.6386
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO                [30] Res: 3.0131 Max: 0.352552 Gain: 0.1 Pos: [277, 343] Scale: 0 Coeffs: 1.07426  43.907
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 5.63416 (in this run)
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 5.63416  [1]: 127.732
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->30 [30], model=0->5.63416, peakres=0.0651419->7.54045, Possible divergence. Peak residual increased by 10% from minimum.
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 30 iterations.
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n3]
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt0
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt1
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt0
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt1
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt0
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt1
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
09-Dec-2024 12:04:15	2024-12-09 12:04:15        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:04:17	2024-12-09 12:04:15        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
09-Dec-2024 12:04:17	2024-12-09 12:04:15        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
09-Dec-2024 12:04:17	2024-12-09 12:04:15        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :0
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :0
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :0
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::loadWtImage::MPIServer-1        Loaded "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n1.weight.tt0"
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::loadWtImage::MPIServer-3        Loaded "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n3.weight.tt0"
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::loadWtImage::MPIServer-2        Loaded "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n2.weight.tt0"
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::loadWtImage::MPIServer-1        Loaded "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n1.weight.tt1"
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::loadWtImage::MPIServer-2        Loaded "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n2.weight.tt1"
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::loadWtImage::MPIServer-3        Loaded "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n3.weight.tt1"
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :0
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :0
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :0
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :1
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :1
09-Dec-2024 12:04:17	2024-12-09 12:04:16        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :1
09-Dec-2024 12:04:17	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :1
09-Dec-2024 12:04:17	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :1
09-Dec-2024 12:04:17	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :1
09-Dec-2024 12:04:17	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :2
09-Dec-2024 12:04:17	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :2
09-Dec-2024 12:04:17	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :2
09-Dec-2024 12:04:17	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :2
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :2
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :2
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-1        Time degrid 0.05
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-1        Time degrid 0.01
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.1
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.03
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [8830.19]
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (-135.357,-43.5039) (0,0)
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-2        Time degrid 0.04
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-2        Time degrid 0.02
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.14
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.02
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [8838.74]
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (-162.577,225.173) (0,0)
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-3        Time degrid 0.05
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-3        Time degrid 0.01
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.1
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0.03
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8583.62]
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (-299.278,71.7258) (0,0)
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.26
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.03
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [-8.47029]
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (27.3993,8.8243) (0,0)
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.17
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.03
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [-9.40991]
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (29.9372,-42.0206) (0,0)
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.13
09-Dec-2024 12:04:18	2024-12-09 12:04:17        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0.02
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [-7.52245]
09-Dec-2024 12:04:18	2024-12-09 12:04:17        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (58.8487,-13.2601) (0,0)
09-Dec-2024 12:04:18	2024-12-09 12:04:18        INFO        tclean::::casa        ...done
09-Dec-2024 12:04:18	2024-12-09 12:04:18        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
09-Dec-2024 12:04:18	2024-12-09 12:04:18        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:18	2024-12-09 12:04:18        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:18	2024-12-09 12:04:18        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:18	2024-12-09 12:04:19        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:18	2024-12-09 12:04:19        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 1.09816, over full image : 1.09816
09-Dec-2024 12:04:18	2024-12-09 12:04:19        INFO        task_tclean::grpcInteractiveCleanManager::cleanComplete         [WARN] Peak residual (within the mask) increased from 0.0651419 to 1.09816
09-Dec-2024 12:04:18	2024-12-09 12:04:19        INFO        tclean::::casa        Reached global stopping criterion : iteration limit
09-Dec-2024 12:04:18	2024-12-09 12:04:19        INFO        tclean::::casa        getSummary call: fullsummary=False
09-Dec-2024 12:04:44	2024-12-09 12:04:19        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:44	2024-12-09 12:04:19        INFO        MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
09-Dec-2024 12:04:44	2024-12-09 12:04:19        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
09-Dec-2024 12:04:44	2024-12-09 12:04:19        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:44	2024-12-09 12:04:19        INFO        SIImageStore::restore         Beam for chan : 0 : 1e-06 arcsec, 1e-06 arcsec, 0 deg
09-Dec-2024 12:04:44	2024-12-09 12:04:19        INFO        SIImageStore::restore         Beam for chan : 0 : 1e-06 arcsec, 1e-06 arcsec, 0 deg
09-Dec-2024 12:04:44	2024-12-09 12:04:19        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Calculating spectral parameters for Intensity > peakresidual/10 = 6.09668 Jy/beam
09-Dec-2024 12:04:44	2024-12-09 12:04:19        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Written Spectral Index Error Image : /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.alpha.error
09-Dec-2024 12:04:44	2024-12-09 12:04:19        INFO        SDAlgorithmMSMFS::restore         Restored images :
09-Dec-2024 12:04:44	2024-12-09 12:04:19        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:04:44	2024-12-09 12:04:19        INFO        SDAlgorithmMSMFS::restore         /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.image.tt0  (model=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.model.tt0)
09-Dec-2024 12:04:44	2024-12-09 12:04:19        INFO        SDAlgorithmMSMFS::restore         /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.image.tt1  (model=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.model.tt1)
09-Dec-2024 12:04:44	2024-12-09 12:04:19        INFO        SDAlgorithmMSMFS::restore
09-Dec-2024 12:04:44	2024-12-09 12:04:19        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-1        Total extra cost of heterogeneous array pointing correction = 0.21sec.  Total VBs processed =  54633. The pointingoffsetsigdev used was = []
09-Dec-2024 12:04:44	2024-12-09 12:04:19        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-2        Total extra cost of heterogeneous array pointing correction = 0.24sec.  Total VBs processed =  54624. The pointingoffsetsigdev used was = []
09-Dec-2024 12:04:44	2024-12-09 12:04:19        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-3        Total extra cost of heterogeneous array pointing correction = 0.22sec.  Total VBs processed =  54579. The pointingoffsetsigdev used was = []
09-Dec-2024 12:04:44	2024-12-09 12:04:37        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:04:44	2024-12-09 12:04:37        INFO        ::casa::MPIServer-1        node 1 has completed its job
09-Dec-2024 12:04:44	2024-12-09 12:04:37        INFO        ::casa::MPIServer-2        node 2 has completed its job
09-Dec-2024 12:04:44	2024-12-09 12:04:37        INFO        ::casa::MPIServer-3        node 3 has completed its job
09-Dec-2024 12:04:44	2024-12-09 12:04:38        INFO        tclean::::casa        ...done
09-Dec-2024 12:04:44	2024-12-09 12:04:38        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
09-Dec-2024 12:04:44	2024-12-09 12:04:38        INFO        tclean::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.image.tt0', 'tst.alpha.error', 'tst.mask', 'tst.image.tt1', 'tst.alpha', 'tst.residual.tt0', 'tst.psf.tt1', 'tst.psf.tt2', 'tst.weight.tt1', 'tst.model.tt0', 'tst.psf.tt0', 'tst.sumwt.tt1', 'tst.weight.tt0', 'tst.pb.tt0', 'tst.residual.tt1', 'tst.sumwt.tt0', 'tst.sumwt.tt2', 'tst.weight.tt2', 'tst.model.tt1']
09-Dec-2024 12:04:44	2024-12-09 12:04:38        INFO        tclean::::casa        Task tclean complete. Start time: 2024-12-09 07:02:25.091015 End time: 2024-12-09 07:04:38.491139
09-Dec-2024 12:04:44	2024-12-09 12:04:38        INFO        tclean::::casa        ##### End Task: tclean               #####
09-Dec-2024 12:04:44	2024-12-09 12:04:38        INFO        tclean::::casa        ##########################################
09-Dec-2024 12:04:44	2024-12-09 12:04:39        INFO        name::        No table opened.
09-Dec-2024 12:04:44	2024-12-09 12:04:39        INFO        showcache::        The Table Cache is empty.
09-Dec-2024 12:04:44	2024-12-09 12:04:39        INFO        tclean::::casa        [ checkall ]
09-Dec-2024 12:04:44	2024-12-09 12:04:39        INFO        tclean::::casa+        [ check_ims ] Image made : ['tst.image.tt0', 'tst.psf.tt0', 'tst.weight.tt0'] =  [True, True, True] ( Pass : should all be True )
09-Dec-2024 12:04:44	2024-12-09 12:04:39        INFO        tclean::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
09-Dec-2024 12:04:44	2024-12-09 12:04:39        INFO        tclean::::casa+        [check_history] All expected history entries found. (Pass)
09-Dec-2024 12:04:44	2024-12-09 12:04:39        INFO        tclean::::casa+        [ check_pix_val ] tst.image.tt0 : Value is -1.198827703774441e-10 at [256, 256, 0, 0] ( Fail : should be 0.96 , Epsilon: 0.05)
09-Dec-2024 12:04:44	2024-12-09 12:04:39        INFO        tclean::::casa+        [ check_pix_val ] tst.weight.tt0 : Value is 0.0008290889672935009 at [256, 256, 0, 0] ( Fail : should be 0.486 , Epsilon: 0.05)
09-Dec-2024 12:04:44	2024-12-09 12:04:39        INFO        tclean::::casa+        [ check_pix_val ] tst.alpha : Value is -0.0 at [256, 256, 0, 0] ( Pass : should be 0.0 , Epsilon: 0.05)
09-Dec-2024 12:04:44	Fs2024-12-09 12:04:39        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
09-Dec-2024 12:04:44	2024-12-09 12:04:39        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
09-Dec-2024 12:04:44	2024-12-09 12:04:39        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
09-Dec-2024 12:04:44	2024-12-09 12:04:40        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
09-Dec-2024 12:04:44	2024-12-09 12:04:43        INFO        tclean::::casa        ##########################################
09-Dec-2024 12:04:44	2024-12-09 12:04:43        INFO        tclean::::casa        ##### Begin Task: tclean             #####
09-Dec-2024 12:04:44	2024-12-09 12:04:43        INFO        tclean::::casa        tclean( vis='refim_point_wterm_vlad.ms', selectdata=True, field='', spw='', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst.awp', imsize=2048, cell='10.0arcsec', phasecenter='', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='awproject', facets=1, psfphasecenter='', wprojplanes=16, vptable='', mosweight=True, aterm=False, psterm=True, wbawp=False, conjbeams=False, cfcache='tst_use_awp.cf', usepointing=False, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=-0.1, normtype='flatnoise', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], pbcor=False, outlierfile='', weighting='uniform', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=0, gain=0.1, threshold=0.0, nsigma=0.0, cycleniter=-1, cyclefactor=1.0, minpsffraction=0.05, maxpsffraction=0.8, interactive=False, nmajor=-1, fullsummary=False, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, minpercentchange=-1.0, verbose=False, fastnoise=True, restart=True, savemodel='none', calcres=True, calcpsf=True, psfcutoff=0.35, parallel=True )
09-Dec-2024 12:04:44	2024-12-09 12:04:43        INFO        tclean::::casa        Verifying Input Parameters
09-Dec-2024 12:04:44	2024-12-09 12:04:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
09-Dec-2024 12:04:49	2024-12-09 12:04:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
09-Dec-2024 12:04:49	2024-12-09 12:04:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        SynthesisUtilMethods::continuumDataPartition
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 10065 15093 [P][M]: 0:2 ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_wterm_vlad.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 5032', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_wterm_vlad.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 5033 && ROWNUMBER() <= 10064', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_wterm_vlad.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        SynthesisImagerVi2::selectData         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        SynthesisImagerVi2::selectData           NRows selected : 15093
09-Dec-2024 12:04:49	mss_p num1 sel  1
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.awp] :
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.awp] :
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        SynthesisImagerVi2::defineImage         Impars: start
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        SynthesisImagerVi2::defineImage         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst.awp] with ftmachine : awprojectft
09-Dec-2024 12:04:49	Oversampling set to 20
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        CFCache2::fillCFSFromDisk        No. of CFS* found in tst_use_awp.cf: 0
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        CFCache2::fillCFSFromDisk        No. of WTCFS* found in tst_use_awp.cf: 0
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        AWProjectFT2::setPAIncrement[R&D]        Setting PA increment to 360 deg
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        SynthesisImagerVi2::createAWPFTMachine         Sending frequency selection information Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        SynthesisImagerVi2::createAWPFTMachine +        [0, 1e+09, 1e+09, 1e+06]
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        SynthesisImagerVi2::createAWPFTMachine +         to AWP FTM.
09-Dec-2024 12:04:49	FTM name awprojectft
09-Dec-2024 12:04:49	SUBCUBE 1 shp  1
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 1.397 GB. Available mem.: 384 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
09-Dec-2024 12:04:49	2024-12-09 12:04:46        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:04:49	2024-12-09 12:04:47        INFO        tclean::::casa        ...done
09-Dec-2024 12:04:49	2024-12-09 12:04:47        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:04:49	2024-12-09 12:04:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
09-Dec-2024 12:04:49	2024-12-09 12:04:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 5033 && ROWNUMBER() <= 10064 |   NRows selected : 5032
09-Dec-2024 12:04:49	mss_p num1 sel  1
09-Dec-2024 12:04:49	2024-12-09 12:04:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
09-Dec-2024 12:04:49	2024-12-09 12:04:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093 |   NRows selected : 5029
09-Dec-2024 12:04:49	mss_p num1 sel  1
09-Dec-2024 12:04:49	2024-12-09 12:04:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
09-Dec-2024 12:04:49	2024-12-09 12:04:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 5032 |   NRows selected : 5032
09-Dec-2024 12:04:49	mss_p num1 sel  1
09-Dec-2024 12:04:49	2024-12-09 12:04:48        INFO        tclean::::casa        ...done
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1] :
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1] :
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1] with ftmachine : awprojectft
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of CFS* found in tst_use_awp.cf: 0
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of WTCFS* found in tst_use_awp.cf: 0
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::setPAIncrement[R&D]::MPIServer-1        Setting PA increment to 360 deg
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-1         Sending frequency selection information Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-1 +        [0, 1e+09, 1e+09, 1e+06]
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-1 +         to AWP FTM.
09-Dec-2024 12:04:49	FTM name awprojectft
09-Dec-2024 12:04:49	SUBCUBE 1 shp  1
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 1.397 GB. Available mem.: 384 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2] :
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2] :
09-Dec-2024 12:04:49	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
09-Dec-2024 12:05:02	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
09-Dec-2024 12:05:02	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2] with ftmachine : awprojectft
09-Dec-2024 12:05:02	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of CFS* found in tst_use_awp.cf: 0
09-Dec-2024 12:05:02	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of WTCFS* found in tst_use_awp.cf: 0
09-Dec-2024 12:05:02	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::setPAIncrement[R&D]::MPIServer-2        Setting PA increment to 360 deg
09-Dec-2024 12:05:02	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-2         Sending frequency selection information Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:05:02	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-2 +        [0, 1e+09, 1e+09, 1e+06]
09-Dec-2024 12:05:02	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-2 +         to AWP FTM.
09-Dec-2024 12:05:02	FTM name awprojectft
09-Dec-2024 12:05:02	SUBCUBE 1 shp  1
09-Dec-2024 12:05:02	2024-12-09 12:04:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 1.397 GB. Available mem.: 384 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
09-Dec-2024 12:05:02	2024-12-09 12:04:49        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3] :
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3] :
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3] with ftmachine : awprojectft
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of CFS* found in tst_use_awp.cf: 0
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of WTCFS* found in tst_use_awp.cf: 0
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::setPAIncrement[R&D]::MPIServer-3        Setting PA increment to 360 deg
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-3         Sending frequency selection information Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-3 +        [0, 1e+09, 1e+09, 1e+06]
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-3 +         to AWP FTM.
09-Dec-2024 12:05:02	FTM name awprojectft
09-Dec-2024 12:05:02	SUBCUBE 1 shp  1
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 1.397 GB. Available mem.: 384 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        tclean::::casa        ...done
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        SynthesisImagerVi2::dryGridding         ---------------------------------------------------- Dry Gridding ---------------------------------------------
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        AWProjectFT2::init[R&D]        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:05:02	2024-12-09 12:04:50        INFO        AWProjectFT2::initializeToSky[R&D]        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:05:02	2024-12-09 12:04:51        WARN        SynthesisImagerVi2::dryGridding (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisImagerVi2.cc, line 3111)        Making a "blank" CFCache (without the A-Term)
09-Dec-2024 12:05:02	2024-12-09 12:04:51        INFO        VB2CFBMap::makeVBRow2CFBMap[R&D]        CFs not cached for -64.1134 deg, dPA = 360 Field ID = 0 Ant1Type, Ant2Type = 0,0
09-Dec-2024 12:05:02	2024-12-09 12:04:51        INFO        AWConvFunc2::makeConvFunction[R&D]        Making a new convolution function for PA=-64.1134deg for field ID 0
09-Dec-2024 12:05:02	2024-12-09 12:04:51        INFO        AWConvFunc2::makeConvFunction[R&D]        Using 16 planes for W-projection
09-Dec-2024 12:05:02	2024-12-09 12:04:51        INFO        AWConvFunc2::makeConvFunction[R&D]        Estimating maximum possible W = 5156.62 (wavelengths)
09-Dec-2024 12:05:02	2024-12-09 12:04:51        INFO        AWConvFunc2::makeConvFunction[R&D]        Scaling in W (at maximum W) = 22.9183 wavelengths per pixel
09-Dec-2024 12:05:02	2024-12-09 12:04:51        INFO        AWConvFunc2::makeConvFunction[R&D]        Making 16 w plane(s).
09-Dec-2024 12:05:02	2024-12-09 12:04:51        INFO        AWConvFunc2::makeConvFunction[R&D]        Making 1 frequency plane(s).
09-Dec-2024 12:05:02	2024-12-09 12:04:51        INFO        AWConvFunc2::makeConvFunction[R&D]        CFB Freq. axis [N, Min, Max, Incr. (GHz)]: 1 0.999981 0.999981 0.1
09-Dec-2024 12:05:02	2024-12-09 12:04:51        INFO        AWConvFunc2::makeConvFunction[R&D]        Making empty CFs for baseline type 0
09-Dec-2024 12:05:02	2024-12-09 12:04:51        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation during gridding to compute sensitivity pattern.
09-Dec-2024 12:05:02	2024-12-09 12:04:51        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
09-Dec-2024 12:05:02	2024-12-09 12:04:51        INFO        CFStore2::makePersistent(pa)[R&D]        Saving CFStore of shape [1, 16, 2] for PA = -64.1134 BaselineType(antType1, antType2) = (0,0)
09-Dec-2024 12:05:02	2024-12-09 12:04:52        INFO        CFStore2::makePersistent(pa)[R&D]        Saving CFStore of shape [1, 16, 2] for PA = -64.1134 BaselineType(antType1, antType2) = (0,0)
09-Dec-2024 12:05:02	2024-12-09 12:04:53        INFO        AWProjectFT2::findConvFunction[R&D]        Convolution function memory footprint:0 KB out of a maximum of 515201 MB
09-Dec-2024 12:05:02	2024-12-09 12:04:53        INFO        SynthesisImagerVi2::fillCFCache         ---------------------------------------------------- fillCFCache ---------------------------------------------
09-Dec-2024 12:05:02	@@@@IN making CFCache
09-Dec-2024 12:05:02	2024-12-09 12:04:53        WARN        SynthesisImagerVi2::fillCFCache (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisImagerVi2.cc, line 3195)        Re-loading the "blank" CFCache for filling
09-Dec-2024 12:05:02	2024-12-09 12:04:53        INFO        CFCache2::fillCFListFromDisk        Loading misc info from CFs
09-Dec-2024 12:05:02	2024-12-09 12:04:53        INFO        CFCache2::fillCFListFromDisk        Loading misc info from CFs
09-Dec-2024 12:05:02	2024-12-09 12:04:53        WARN        crustaceans::makeConvFunction2[R&D]        Filling CFs for baseline type 0, PA slot 0
09-Dec-2024 12:05:02	2024-12-09 12:04:53        INFO        BeamCalc::setBeamCalcGeometries()        Initialisation of geometries for observatory VLA, antenna type STANDARD
09-Dec-2024 12:05:02	distrodata_path=/home/casa/data/master
09-Dec-2024 12:05:02	DATA PATH===/export/cvpost135-4tb/casa/casa-data/casatestdata-large  /home/casa/data/master  /lustre/cv/sw/casa/casaci/data/casatestdata  /lustre/cv/sw/casa/casaci/data/casatestdata-large  /lustre/cv/sw/casa/casaci/pipeline-testdata  /export/cvpost135-4tb/casa/casa-data/casatestdata
09-Dec-2024 12:05:02	Processing request for geometries from observatory VLA, antenna type STANDARD
09-Dec-2024 12:05:02	2024-12-09 12:04:53        INFO        BeamCalc::setBeamCalcGeometries()        Will use default geometries for VLA STANDARD.
09-Dec-2024 12:05:02	Oversampling set to 20
09-Dec-2024 12:05:02	2024-12-09 12:04:58        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:0): CF Support: 4 (6) pixels
09-Dec-2024 12:05:02	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:05:03        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:22.9183): CF Support: 5 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:05:08        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:91.6732): CF Support: 5 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:05:12        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:206.265): CF Support: 5 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:05:17        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:366.693): CF Support: 6 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:05:22        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:572.958): CF Support: 7 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:05:27        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:825.059): CF Support: 9 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:05:32        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:1123): CF Support: 10 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:05:37        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:1466.77): CF Support: 12 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:05:42        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:1856.38): CF Support: 14 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:05:47        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:2291.83): CF Support: 17 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:05:52        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:2773.12): CF Support: 19 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:05:56        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:3300.24): CF Support: 22 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:06:01        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:3873.19): CF Support: 26 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:06:06        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:4491.99): CF Support: 29 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:06:11        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:5156.62): CF Support: 33 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:06:16        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:0): CF Support: 4 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:06:21        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:22.9183): CF Support: 5 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:06:26        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:91.6732): CF Support: 5 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:06:30        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:206.265): CF Support: 5 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:06:35        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:366.693): CF Support: 6 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:06:40        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:572.958): CF Support: 7 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:06:45        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:825.059): CF Support: 9 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:06:50        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:1123): CF Support: 10 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:06:55        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:1466.77): CF Support: 12 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:07:00        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:1856.38): CF Support: 14 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:07:05        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:2291.83): CF Support: 17 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:07:10        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:2773.12): CF Support: 19 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:07:14        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:3300.24): CF Support: 22 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:07:19        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:3873.19): CF Support: 26 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:07:24        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:4491.99): CF Support: 29 (6) pixels
09-Dec-2024 12:07:32	Oversampling set to 20
09-Dec-2024 12:07:32	2024-12-09 12:07:29        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:5156.62): CF Support: 33 (6) pixels
09-Dec-2024 12:07:32	2024-12-09 12:07:29        INFO        CFStore2.2::makePersistent[R&D]        Writing CFStore(0,0) of shape [1, 16, 2]
09-Dec-2024 12:07:32	2024-12-09 12:07:30        INFO        CFStore2.2::makePersistent[R&D]        Writing CFStore(0,0) of shape [1, 16, 2]
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        tclean::::casa        reloadCFCache, CMD = 1 toolsi.reloadcfcache()
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        tclean::::casa        reloadCFCache, CMD = 2 toolsi.reloadcfcache()
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        tclean::::casa        reloadCFCache, CMD = 3 toolsi.reloadcfcache()
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-3         -------------------------------------------- Re-load CFCache ---------------------------------------------
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-3         Lazy fill is On
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of CFS* found in tst_use_awp.cf: 32
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-1         -------------------------------------------- Re-load CFCache ---------------------------------------------
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-1         Lazy fill is On
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of CFS* found in tst_use_awp.cf: 32
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-2         -------------------------------------------- Re-load CFCache ---------------------------------------------
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-2         Lazy fill is On
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of CFS* found in tst_use_awp.cf: 32
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of WTCFS* found in tst_use_awp.cf: 32
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of WTCFS* found in tst_use_awp.cf: 32
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of WTCFS* found in tst_use_awp.cf: 32
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
09-Dec-2024 12:07:32	2024-12-09 12:07:32        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
09-Dec-2024 12:07:32	2024-12-09 12:07:33        INFO        tclean::::casa        ...done
09-Dec-2024 12:07:32	2024-12-09 12:07:33        INFO        tclean::::casa        reloadCFCache, CMD = 1 toolsi.reloadcfcache()
09-Dec-2024 12:07:32	2024-12-09 12:07:33        INFO        tclean::::casa        reloadCFCache, CMD = 2 toolsi.reloadcfcache()
09-Dec-2024 12:07:32	2024-12-09 12:07:33        INFO        tclean::::casa        reloadCFCache, CMD = 3 toolsi.reloadcfcache()
09-Dec-2024 12:07:32	2024-12-09 12:07:33        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-1         -------------------------------------------- Re-load CFCache ---------------------------------------------
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-1         Lazy fill is On
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of CFS* found in tst_use_awp.cf: 32
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-2         -------------------------------------------- Re-load CFCache ---------------------------------------------
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-2         Lazy fill is On
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of CFS* found in tst_use_awp.cf: 32
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-3         -------------------------------------------- Re-load CFCache ---------------------------------------------
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-3         Lazy fill is On
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of CFS* found in tst_use_awp.cf: 32
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of WTCFS* found in tst_use_awp.cf: 32
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of WTCFS* found in tst_use_awp.cf: 32
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of WTCFS* found in tst_use_awp.cf: 32
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
09-Dec-2024 12:07:40	2024-12-09 12:07:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
09-Dec-2024 12:07:40	2024-12-09 12:07:34        INFO        tclean::::casa        ...done
09-Dec-2024 12:07:40	2024-12-09 12:07:34        INFO        VB2CFBMap::~VB2CFMap[R&D]        Total extra cost of heterogeneous array pointing correction = 0sec.  Total VBs processed =  0. The pointingoffsetsigdev used was = []
09-Dec-2024 12:07:40	2024-12-09 12:07:36        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:07:40	2024-12-09 12:07:36        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
09-Dec-2024 12:07:40	2024-12-09 12:07:36        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
09-Dec-2024 12:07:40	2024-12-09 12:07:36        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
09-Dec-2024 12:07:40	2024-12-09 12:07:37        INFO        tclean::::casa        ...done
09-Dec-2024 12:07:40	2024-12-09 12:07:37        INFO        tclean::::casa        Gathering/Merging/Scattering Weight Density for PSF generation
09-Dec-2024 12:07:40	2024-12-09 12:07:37        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:07:40	2024-12-09 12:07:38        INFO        tclean::::casa        ...done
09-Dec-2024 12:07:40	2024-12-09 12:07:38        INFO        tclean::::casa        ******************************************************
09-Dec-2024 12:07:40	2024-12-09 12:07:38        INFO        tclean::::casa         gather and scatter now
09-Dec-2024 12:07:40	2024-12-09 12:07:38        INFO        tclean::::casa        ******************************************************
09-Dec-2024 12:07:40	2024-12-09 12:07:38        INFO        SynthesisNormalizer::gatherImages         Gather  images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3] onto :tst.awp
09-Dec-2024 12:07:40	2024-12-09 12:07:38        INFO        SynthesisNormalizer::scatterWeightDensity         Send the gridded weight from : tst.awp to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3]
09-Dec-2024 12:07:40	2024-12-09 12:07:38        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:07:40	2024-12-09 12:07:39        INFO        tclean::::casa        ...done
09-Dec-2024 12:07:40	2024-12-09 12:07:39        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst.awp] : hogbom
09-Dec-2024 12:07:40	2024-12-09 12:07:39        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:07:40	2024-12-09 12:07:40        INFO        tclean::::casa        ...done
09-Dec-2024 12:07:40	2024-12-09 12:07:40        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:07:40	2024-12-09 12:07:40        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
09-Dec-2024 12:07:40	2024-12-09 12:07:40        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
09-Dec-2024 12:07:40	2024-12-09 12:07:41        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
09-Dec-2024 12:07:40	2024-12-09 12:07:41        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:07:40	2024-12-09 12:07:41        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:07:40	2024-12-09 12:07:41        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:07:40	2024-12-09 12:07:41        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:07:40	2024-12-09 12:07:41        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:07:40	2024-12-09 12:07:41        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:07:40	2024-12-09 12:07:41        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :0
09-Dec-2024 12:07:40	2024-12-09 12:07:41        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :0
09-Dec-2024 12:07:40	2024-12-09 12:07:41        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-3        Setting up for weights accumulation during gridding to compute sensitivity pattern.
09-Dec-2024 12:07:46	2024-12-09 12:07:41        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-3+        Consequently, the first gridding cycle will be slower than the subsequent ones.
09-Dec-2024 12:07:46	2024-12-09 12:07:41        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-1        Setting up for weights accumulation during gridding to compute sensitivity pattern.
09-Dec-2024 12:07:46	2024-12-09 12:07:41        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-1+        Consequently, the first gridding cycle will be slower than the subsequent ones.
09-Dec-2024 12:07:46	2024-12-09 12:07:41        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :0
09-Dec-2024 12:07:46	2024-12-09 12:07:41        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-2        Setting up for weights accumulation during gridding to compute sensitivity pattern.
09-Dec-2024 12:07:46	2024-12-09 12:07:41        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-2+        Consequently, the first gridding cycle will be slower than the subsequent ones.
09-Dec-2024 12:07:46	2024-12-09 12:07:41        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.22
09-Dec-2024 12:07:46	2024-12-09 12:07:41        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.09
09-Dec-2024 12:07:46	2024-12-09 12:07:41        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.18
09-Dec-2024 12:07:46	2024-12-09 12:07:41        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0.12
09-Dec-2024 12:07:46	2024-12-09 12:07:41        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.17
09-Dec-2024 12:07:46	2024-12-09 12:07:41        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.14
09-Dec-2024 12:07:46	2024-12-09 12:07:42        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:07:46	2024-12-09 12:07:42        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8234.25]
09-Dec-2024 12:07:46	2024-12-09 12:07:42        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (1.39154,-7.1218e-08) (0,0)
09-Dec-2024 12:07:46	2024-12-09 12:07:42        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:07:46	2024-12-09 12:07:42        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [8258.9]
09-Dec-2024 12:07:46	2024-12-09 12:07:42        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (1.39034,-2.92298e-08) (0,0)
09-Dec-2024 12:07:46	2024-12-09 12:07:42        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:07:46	2024-12-09 12:07:42        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [8474.84]
09-Dec-2024 12:07:46	2024-12-09 12:07:42        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (0.992538,2.75834e-08) (0,0)
09-Dec-2024 12:07:46	2024-12-09 12:07:43        INFO        tclean::::casa        ...done
09-Dec-2024 12:07:46	2024-12-09 12:07:43        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3] onto :tst.awp
09-Dec-2024 12:07:46	2024-12-09 12:07:44        INFO        SIImageStore::calcSensitivity         [tst.awp] Theoretical sensitivity (Jy/bm):0.00632861
09-Dec-2024 12:07:46	2024-12-09 12:07:44        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.039337
09-Dec-2024 12:07:46	2024-12-09 12:07:44        INFO        SIImageStore::printBeamSet         Beam : 85.3879 arcsec, 71.2449 arcsec, -89.0476 deg
09-Dec-2024 12:07:46	2024-12-09 12:07:44        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:07:46	2024-12-09 12:07:45        INFO        tclean::::casa        ...done
09-Dec-2024 12:07:46	2024-12-09 12:07:45        INFO        SynthesisNormalizer::scatterModel         Send the model from : tst.awp to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3]
09-Dec-2024 12:07:46	2024-12-09 12:07:45        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1.model
09-Dec-2024 12:07:46	2024-12-09 12:07:46        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2.model
09-Dec-2024 12:07:46	2024-12-09 12:07:46        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3.model
09-Dec-2024 12:07:46	2024-12-09 12:07:46        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
09-Dec-2024 12:07:46	2024-12-09 12:07:46        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:07:46	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
09-Dec-2024 12:07:46	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
09-Dec-2024 12:07:46	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
09-Dec-2024 12:07:46	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:07:46	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:07:46	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:07:46	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:07:46	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
09-Dec-2024 12:07:46	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
09-Dec-2024 12:07:46	2024-12-09 12:07:46        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.06
09-Dec-2024 12:07:46	2024-12-09 12:07:46        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.07
09-Dec-2024 12:07:46	2024-12-09 12:07:46        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.06
09-Dec-2024 12:08:01	2024-12-09 12:07:46        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0.1
09-Dec-2024 12:08:01	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:08:01	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [8475.52]
09-Dec-2024 12:08:01	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (-0.4708,-1.04899) (0,0)
09-Dec-2024 12:08:01	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:08:01	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8235.06]
09-Dec-2024 12:08:01	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (-0.996398,0.392974) (0,0)
09-Dec-2024 12:08:01	2024-12-09 12:07:46        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.07
09-Dec-2024 12:08:01	2024-12-09 12:07:46        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.1
09-Dec-2024 12:08:01	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
09-Dec-2024 12:08:01	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [8259.7]
09-Dec-2024 12:08:01	2024-12-09 12:07:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (-1.08331,0.295812) (0,0)
09-Dec-2024 12:08:01	2024-12-09 12:07:48        INFO        tclean::::casa        ...done
09-Dec-2024 12:08:01	2024-12-09 12:07:48        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3] onto :tst.awp
09-Dec-2024 12:08:01	2024-12-09 12:07:48        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7226, 180, 40.7226]) and current imaging run ([299.869, 40.7226, 180, 40.7226]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:08:01	2024-12-09 12:07:48        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7226, 180, 40.7226]) and current imaging run ([299.869, 40.7226, 180, 40.7226]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:08:01	2024-12-09 12:07:48        INFO        task_tclean::SDAlgorithmBase::restore         [tst.awp] : Restoring model image.
09-Dec-2024 12:08:01	2024-12-09 12:07:48        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2298)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
09-Dec-2024 12:08:01	2024-12-09 12:07:48        WARN        task_tclean::SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 583)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7226, 180, 40.7226]) and current imaging run ([299.869, 40.7226, 180, 40.7226]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
09-Dec-2024 12:08:01	2024-12-09 12:07:48        INFO        task_tclean::SIImageStore::restore         Beam for chan : 0 : 85.3879 arcsec, 71.2449 arcsec, -89.0476 deg
09-Dec-2024 12:08:01	2024-12-09 12:07:48        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-3        Total extra cost of heterogeneous array pointing correction = 0.01sec.  Total VBs processed =  15317. The pointingoffsetsigdev used was = []
09-Dec-2024 12:08:01	2024-12-09 12:07:48        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-2        Total extra cost of heterogeneous array pointing correction = 0.07sec.  Total VBs processed =  15562. The pointingoffsetsigdev used was = []
09-Dec-2024 12:08:01	2024-12-09 12:07:48        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-1        Total extra cost of heterogeneous array pointing correction = 0.05sec.  Total VBs processed =  15798. The pointingoffsetsigdev used was = []
09-Dec-2024 12:08:01	2024-12-09 12:07:59        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:08:01	2024-12-09 12:07:59        INFO        ::casa::MPIServer-3        node 3 has completed its job
09-Dec-2024 12:08:01	2024-12-09 12:07:59        INFO        ::casa::MPIServer-2        node 2 has completed its job
09-Dec-2024 12:08:01	2024-12-09 12:07:59        INFO        ::casa::MPIServer-1        node 1 has completed its job
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        tclean::::casa        ...done
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        tclean::::casa        Searching for images with prefix 'tst.awp'... Found these, writing history into them: ['tst.awp.gridwt', 'tst.awp.sumwt', 'tst.awp.model', 'tst.awp.psf', 'tst.awp.weight', 'tst.awp.residual', 'tst.awp.pb', 'tst.awp.image']
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        tclean::::casa        Task tclean complete. Start time: 2024-12-09 07:04:43.366253 End time: 2024-12-09 07:07:59.817179
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        tclean::::casa        ##### End Task: tclean               #####
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        tclean::::casa        ##########################################
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        name::        No table opened.
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        showcache::        The Table Cache is empty.
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        tclean::::casa        [ checkall ]
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        tclean::::casa+        [ check_ims ] Image made : ['tst.awp.image'] =  [True] ( Pass : should all be True )
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        tclean::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        tclean::::casa+        [check_history] All expected history entries found. (Pass)
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        tclean::::casa+        [ check_pix_val ] tst.awp.psf : Value is 1.0 at [1024, 1024, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        tclean::::casa+        [ check_pix_val ] tst.awp.image : Value is 0.9958506226539612 at [1158, 1384, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
09-Dec-2024 12:08:01	.s2024-12-09 12:08:00        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
09-Dec-2024 12:08:01	2024-12-09 12:08:00        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
09-Dec-2024 12:08:01	2024-12-09 12:08:01        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
09-Dec-2024 12:08:01	2024-12-09 12:08:01        INFO        tclean::::casa        ##########################################
09-Dec-2024 12:08:01	2024-12-09 12:08:01        INFO        tclean::::casa        ##### Begin Task: tclean             #####
09-Dec-2024 12:08:07	2024-12-09 12:08:01        INFO        tclean::::casa        tclean( vis='refim_point_wterm_vlad.ms', selectdata=True, field='', spw='', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst.wyes', imsize=2048, cell='10.0arcsec', phasecenter='', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='wproject', facets=1, psfphasecenter='', wprojplanes=16, vptable='', mosweight=True, aterm=True, psterm=False, wbawp=True, conjbeams=False, cfcache='', usepointing=False, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=-0.1, normtype='flatnoise', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], pbcor=False, outlierfile='', weighting='uniform', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=0, gain=0.1, threshold=0.0, nsigma=0.0, cycleniter=-1, cyclefactor=1.0, minpsffraction=0.05, maxpsffraction=0.8, interactive=False, nmajor=-1, fullsummary=False, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, minpercentchange=-1.0, verbose=False, fastnoise=True, restart=True, savemodel='none', calcres=True, calcpsf=True, psfcutoff=0.35, parallel=True )
09-Dec-2024 12:08:07	2024-12-09 12:08:01        INFO        tclean::::casa        Verifying Input Parameters
09-Dec-2024 12:08:07	2024-12-09 12:08:03        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
09-Dec-2024 12:08:07	2024-12-09 12:08:03        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
09-Dec-2024 12:08:07	2024-12-09 12:08:03        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
09-Dec-2024 12:08:07	2024-12-09 12:08:04        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
09-Dec-2024 12:08:07	2024-12-09 12:08:04        INFO        SynthesisUtilMethods::continuumDataPartition
09-Dec-2024 12:08:07	2024-12-09 12:08:04        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 10065 15093 [P][M]: 0:2 ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093
09-Dec-2024 12:08:07	2024-12-09 12:08:04        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_wterm_vlad.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 5032', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_wterm_vlad.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 5033 && ROWNUMBER() <= 10064', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_wterm_vlad.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
09-Dec-2024 12:08:07	2024-12-09 12:08:04        INFO        SynthesisImagerVi2::selectData         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
09-Dec-2024 12:08:07	2024-12-09 12:08:04        INFO        SynthesisImagerVi2::selectData           NRows selected : 15093
09-Dec-2024 12:08:07	mss_p num1 sel  1
09-Dec-2024 12:08:07	2024-12-09 12:08:04        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.wyes] :
09-Dec-2024 12:08:07	2024-12-09 12:08:04        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.wyes] :
09-Dec-2024 12:08:07	2024-12-09 12:08:05        INFO        SynthesisImagerVi2::defineImage         Impars: start
09-Dec-2024 12:08:07	2024-12-09 12:08:05        INFO        SynthesisImagerVi2::defineImage         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
09-Dec-2024 12:08:07	2024-12-09 12:08:05        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst.wyes] with ftmachine : wprojectft
09-Dec-2024 12:08:07	FTM name wprojectft
09-Dec-2024 12:08:07	SUBCUBE 1 shp  1
09-Dec-2024 12:08:07	2024-12-09 12:08:05        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 1.397 GB. Available mem.: 383.9 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
09-Dec-2024 12:08:07	2024-12-09 12:08:05        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:08:07	2024-12-09 12:08:06        INFO        tclean::::casa        ...done
09-Dec-2024 12:08:07	2024-12-09 12:08:06        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:08:07	2024-12-09 12:08:06        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
09-Dec-2024 12:08:07	2024-12-09 12:08:06        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
09-Dec-2024 12:08:07	2024-12-09 12:08:06        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093 |   NRows selected : 5029
09-Dec-2024 12:08:07	2024-12-09 12:08:06        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 5033 && ROWNUMBER() <= 10064 |   NRows selected : 5032
09-Dec-2024 12:08:07	mss_p num1 sel  1
09-Dec-2024 12:08:07	mss_p num1 sel  1
09-Dec-2024 12:08:07	2024-12-09 12:08:06        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
09-Dec-2024 12:08:07	2024-12-09 12:08:06        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 5032 |   NRows selected : 5032
09-Dec-2024 12:08:07	mss_p num1 sel  1
09-Dec-2024 12:08:07	2024-12-09 12:08:07        INFO        tclean::::casa        ...done
09-Dec-2024 12:08:07	2024-12-09 12:08:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1] :
09-Dec-2024 12:08:07	2024-12-09 12:08:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1] :
09-Dec-2024 12:08:07	2024-12-09 12:08:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
09-Dec-2024 12:08:07	2024-12-09 12:08:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
09-Dec-2024 12:08:07	2024-12-09 12:08:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1] with ftmachine : wprojectft
09-Dec-2024 12:08:07	FTM name wprojectft
09-Dec-2024 12:08:07	SUBCUBE 1 shp  1
09-Dec-2024 12:08:07	2024-12-09 12:08:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 1.397 GB. Available mem.: 383.9 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
09-Dec-2024 12:08:07	2024-12-09 12:08:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2] :
09-Dec-2024 12:08:07	2024-12-09 12:08:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2] :
09-Dec-2024 12:08:07	2024-12-09 12:08:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
09-Dec-2024 12:08:07	2024-12-09 12:08:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
09-Dec-2024 12:08:07	2024-12-09 12:08:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2] with ftmachine : wprojectft
09-Dec-2024 12:08:07	FTM name wprojectft
09-Dec-2024 12:08:07	SUBCUBE 1 shp  1
09-Dec-2024 12:08:07	2024-12-09 12:08:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 1.397 GB. Available mem.: 383.9 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
09-Dec-2024 12:08:07	2024-12-09 12:08:08        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:08:07	2024-12-09 12:08:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3] :
09-Dec-2024 12:08:21	2024-12-09 12:08:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3] :
09-Dec-2024 12:08:21	2024-12-09 12:08:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
09-Dec-2024 12:08:21	2024-12-09 12:08:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
09-Dec-2024 12:08:21	2024-12-09 12:08:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3] with ftmachine : wprojectft
09-Dec-2024 12:08:21	FTM name wprojectft
09-Dec-2024 12:08:21	SUBCUBE 1 shp  1
09-Dec-2024 12:08:21	2024-12-09 12:08:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 1.397 GB. Available mem.: 383.9 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
09-Dec-2024 12:08:21	2024-12-09 12:08:09        INFO        tclean::::casa        ...done
09-Dec-2024 12:08:21	2024-12-09 12:08:11        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:08:21	2024-12-09 12:08:11        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
09-Dec-2024 12:08:21	2024-12-09 12:08:11        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
09-Dec-2024 12:08:21	2024-12-09 12:08:11        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
09-Dec-2024 12:08:21	2024-12-09 12:08:12        INFO        tclean::::casa        ...done
09-Dec-2024 12:08:21	2024-12-09 12:08:12        INFO        tclean::::casa        Gathering/Merging/Scattering Weight Density for PSF generation
09-Dec-2024 12:08:21	2024-12-09 12:08:12        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:08:21	2024-12-09 12:08:13        INFO        tclean::::casa        ...done
09-Dec-2024 12:08:21	2024-12-09 12:08:13        INFO        tclean::::casa        ******************************************************
09-Dec-2024 12:08:21	2024-12-09 12:08:13        INFO        tclean::::casa         gather and scatter now
09-Dec-2024 12:08:21	2024-12-09 12:08:13        INFO        tclean::::casa        ******************************************************
09-Dec-2024 12:08:21	2024-12-09 12:08:13        INFO        SynthesisNormalizer::gatherImages         Gather  images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3] onto :tst.wyes
09-Dec-2024 12:08:21	2024-12-09 12:08:13        INFO        SynthesisNormalizer::scatterWeightDensity         Send the gridded weight from : tst.wyes to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3]
09-Dec-2024 12:08:21	2024-12-09 12:08:13        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:08:21	2024-12-09 12:08:14        INFO        tclean::::casa        ...done
09-Dec-2024 12:08:21	2024-12-09 12:08:14        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst.wyes] : hogbom
09-Dec-2024 12:08:21	2024-12-09 12:08:14        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:08:21	2024-12-09 12:08:15        INFO        tclean::::casa        ...done
09-Dec-2024 12:08:21	2024-12-09 12:08:15        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:08:21	2024-12-09 12:08:15        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
09-Dec-2024 12:08:21	2024-12-09 12:08:16        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
09-Dec-2024 12:08:21	2024-12-09 12:08:16        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
09-Dec-2024 12:08:21	2024-12-09 12:08:16        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        W projection using 16 planes
09-Dec-2024 12:08:21	2024-12-09 12:08:16        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        Using maximum possible W = 5156.62 (wavelengths)
09-Dec-2024 12:08:21	2024-12-09 12:08:16        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        Typical wavelength = 0.299798 (m)
09-Dec-2024 12:08:21	2024-12-09 12:08:16        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        Scaling in W (at maximum W) = 22.9183 wavelengths per pixel
09-Dec-2024 12:08:21	2024-12-09 12:08:16        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        W projection using 16 planes
09-Dec-2024 12:08:21	2024-12-09 12:08:16        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        Using maximum possible W = 5156.62 (wavelengths)
09-Dec-2024 12:08:21	2024-12-09 12:08:16        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        Typical wavelength = 0.299798 (m)
09-Dec-2024 12:08:21	2024-12-09 12:08:16        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        Scaling in W (at maximum W) = 22.9183 wavelengths per pixel
09-Dec-2024 12:08:21	2024-12-09 12:08:16        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        W projection using 16 planes
09-Dec-2024 12:08:21	2024-12-09 12:08:16        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        Using maximum possible W = 5156.62 (wavelengths)
09-Dec-2024 12:08:21	2024-12-09 12:08:16        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        Typical wavelength = 0.299798 (m)
09-Dec-2024 12:08:21	2024-12-09 12:08:16        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        Scaling in W (at maximum W) = 22.9183 wavelengths per pixel
09-Dec-2024 12:08:21	2024-12-09 12:08:20        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        Convolution support = [16, 16, 20, 20, 24, 32, 36, 44, 52, 64, 72, 84, 96, 112, 124, 140] pixels in Fourier plane
09-Dec-2024 12:08:21	2024-12-09 12:08:20        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        Memory used in gridding function = 2 MB from maximum 515201 MB
09-Dec-2024 12:08:21	2024-12-09 12:08:20        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        Convolution support = [16, 16, 20, 20, 24, 32, 36, 44, 52, 64, 72, 84, 96, 112, 124, 140] pixels in Fourier plane
09-Dec-2024 12:08:21	2024-12-09 12:08:20        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        Memory used in gridding function = 2 MB from maximum 515201 MB
09-Dec-2024 12:08:21	2024-12-09 12:08:20        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        Convolution support = [16, 16, 20, 20, 24, 32, 36, 44, 52, 64, 72, 84, 96, 112, 124, 140] pixels in Fourier plane
09-Dec-2024 12:08:21	2024-12-09 12:08:20        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        Memory used in gridding function = 2 MB from maximum 515201 MB
09-Dec-2024 12:08:21	2024-12-09 12:08:21        INFO        tclean::::casa        ...done
09-Dec-2024 12:08:34	2024-12-09 12:08:21        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3] onto :tst.wyes
09-Dec-2024 12:08:34	2024-12-09 12:08:22        INFO        SIImageStore::calcSensitivity         [tst.wyes] Theoretical sensitivity (Jy/bm):0.00632763
09-Dec-2024 12:08:34	2024-12-09 12:08:22        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.039257
09-Dec-2024 12:08:34	2024-12-09 12:08:22        INFO        SIImageStore::printBeamSet         Beam : 85.3971 arcsec, 71.2504 arcsec, -89.0441 deg
09-Dec-2024 12:08:34	2024-12-09 12:08:22        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:08:34	2024-12-09 12:08:22        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::makePrimaryBeam::MPIServer-1         vi2 : Evaluating Primary Beam model onto image grid(s)
09-Dec-2024 12:08:34	2024-12-09 12:08:23        INFO        tclean::::casa        ...done
09-Dec-2024 12:08:34	2024-12-09 12:08:23        INFO        SynthesisNormalizer::scatterModel         Send the model from : tst.wyes to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3]
09-Dec-2024 12:08:34	2024-12-09 12:08:23        INFO        SIImageStore::setModelImageOne         Starting model image tst.wyes.model does not exist. No initial prediction will be done
09-Dec-2024 12:08:34	2024-12-09 12:08:23        INFO        SIImageStore::setModelImageOne         Starting model image tst.wyes.model does not exist. No initial prediction will be done
09-Dec-2024 12:08:34	2024-12-09 12:08:23        INFO        SIImageStore::setModelImageOne         Starting model image tst.wyes.model does not exist. No initial prediction will be done
09-Dec-2024 12:08:34	2024-12-09 12:08:23        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
09-Dec-2024 12:08:34	2024-12-09 12:08:23        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:08:34	2024-12-09 12:08:23        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
09-Dec-2024 12:08:34	2024-12-09 12:08:23        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
09-Dec-2024 12:08:34	2024-12-09 12:08:23        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
09-Dec-2024 12:08:34	2024-12-09 12:08:25        INFO        tclean::::casa        ...done
09-Dec-2024 12:08:34	2024-12-09 12:08:25        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3] onto :tst.wyes
09-Dec-2024 12:08:34	2024-12-09 12:08:25        INFO        task_tclean::SDAlgorithmBase::restore         [tst.wyes] : Restoring model image.
09-Dec-2024 12:08:34	2024-12-09 12:08:25        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2298)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
09-Dec-2024 12:08:34	2024-12-09 12:08:25        INFO        task_tclean::SIImageStore::restore         Beam for chan : 0 : 85.3971 arcsec, 71.2504 arcsec, -89.0441 deg
09-Dec-2024 12:08:34	2024-12-09 12:08:33        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
09-Dec-2024 12:08:34	2024-12-09 12:08:33        INFO        ::casa::MPIServer-2        node 2 has completed its job
09-Dec-2024 12:08:34	2024-12-09 12:08:33        INFO        ::casa::MPIServer-3        node 3 has completed its job
09-Dec-2024 12:08:34	2024-12-09 12:08:33        INFO        ::casa::MPIServer-1        node 1 has completed its job
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        tclean::::casa        ...done
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        tclean::::casa        Searching for images with prefix 'tst.wyes'... Found these, writing history into them: ['tst.wyes.psf', 'tst.wyes.gridwt', 'tst.wyes.residual', 'tst.wyes.sumwt', 'tst.wyes.model', 'tst.wyes.pb', 'tst.wyes.image']
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        tclean::::casa        Task tclean complete. Start time: 2024-12-09 07:08:01.487537 End time: 2024-12-09 07:08:34.464144
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        tclean::::casa        ##### End Task: tclean               #####
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        tclean::::casa        ##########################################
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        name::        No table opened.
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        showcache::        The Table Cache is empty.
09-Dec-2024 12:08:34	[check_tfmask]  T/F mask name for tst.wyes.image is : ['T'] (Pass : should be ['T'])
09-Dec-2024 12:08:34
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        tclean::::casa        [ checkall ]
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        tclean::::casa+        [ check_ims ] Image made : ['tst.wyes.image'] =  [True] ( Pass : should all be True )
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        tclean::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        tclean::::casa+        [check_history] All expected history entries found. (Pass)
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        tclean::::casa+        [ check_pix_val ] tst.wyes.psf : Value is 1.0 at [1024, 1024, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        tclean::::casa+        [ check_pix_val ] tst.wyes.image : Value is 0.9963294267654419 at [1158, 1384, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
09-Dec-2024 12:08:34	2024-12-09 12:08:34        INFO        tclean::::casa+        [check_tfmask]  T/F mask name for tst.wyes.image is : ['T'] (Pass : should be ['T'])
09-Dec-2024 12:08:34	.s
09-Dec-2024 12:08:34	======================================================================
09-Dec-2024 12:08:34	ERROR: test_widefield_awp2_mfs (__main__.test_widefield)
09-Dec-2024 12:08:34	[widefield] Test_Widefield_awp2 : MFS with narrowband AWProjection (1spw)  stokes I
09-Dec-2024 12:08:34	----------------------------------------------------------------------
09-Dec-2024 12:08:34	Traceback (most recent call last):
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/casasources//casa6/casatasks/tests/tasks/test_task_tclean.py", line 3864, in test_widefield_awp2_mfs
09-Dec-2024 12:08:34	ret = tclean(vis=self.msfile,spw='1',field='*',imagename=self.img,imsize=512,cell='10.0arcsec',phasecenter="J2000 19:59:28.500 +40.44.01.50",
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/pkg/casa-CAS-13581-7-py3.10.el8/lib/py/lib/python3.10/site-packages/casatasks/tclean.py", line 1838, in __call__
09-Dec-2024 12:08:34	task_result = _tclean_t( _pc.document['vis'], _pc.document['selectdata'], _pc.document['field'], _pc.document['spw'], _pc.document['timerange'], _pc.document['uvrange'], _pc.document['antenna'], _pc.document['scan'], _pc.document['observation'], _pc.document['intent'], _pc.document['datacolumn'], _pc.document['imagename'], _pc.document['imsize'], _pc.document['cell'], _pc.document['phasecenter'], _pc.document['stokes'], _pc.document['projection'], _pc.document['startmodel'], _pc.document['specmode'], _pc.document['reffreq'], _pc.document['nchan'], _pc.document['start'], _pc.document['width'], _pc.document['outframe'], _pc.document['veltype'], _pc.document['restfreq'], _pc.document['interpolation'], _pc.document['perchanweightdensity'], _pc.document['gridder'], _pc.document['facets'], _pc.document['psfphasecenter'], _pc.document['wprojplanes'], _pc.document['vptable'], _pc.document['mosweight'], _pc.document['aterm'], _pc.document['psterm'], _pc.document['wbawp'], _pc.document['conjbeams'], _pc.document['cfcache'], _pc.document['usepointing'], _pc.document['computepastep'], _pc.document['rotatepastep'], _pc.document['pointingoffsetsigdev'], _pc.document['pblimit'], _pc.document['normtype'], _pc.document['deconvolver'], _pc.document['scales'], _pc.document['nterms'], _pc.document['smallscalebias'], _pc.document['fusedthreshold'], _pc.document['largestscale'], _pc.document['restoration'], _pc.document['restoringbeam'], _pc.document['pbcor'], _pc.document['outlierfile'], _pc.document['weighting'], _pc.document['robust'], _pc.document['noise'], _pc.document['npixels'], _pc.document['uvtaper'], _pc.document['niter'], _pc.document['gain'], _pc.document['threshold'], _pc.document['nsigma'], _pc.document['cycleniter'], _pc.document['cyclefactor'], _pc.document['minpsffraction'], _pc.document['maxpsffraction'], _pc.document['interactive'], _pc.document['nmajor'], _pc.document['fullsummary'], _pc.document['usemask'], _pc.document['mask'], _pc.document['pbmask'], _pc.document['sidelobethreshold'], _pc.document['noisethreshold'], _pc.document['lownoisethreshold'], _pc.document['negativethreshold'], _pc.document['smoothfactor'], _pc.document['minbeamfrac'], _pc.document['cutthreshold'], _pc.document['growiterations'], _pc.document['dogrowprune'], _pc.document['minpercentchange'], _pc.document['verbose'], _pc.document['fastnoise'], _pc.document['restart'], _pc.document['savemodel'], _pc.document['calcres'], _pc.document['calcpsf'], _pc.document['psfcutoff'], _pc.document['parallel'] )
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/pkg/casa-CAS-13581-7-py3.10.el8/lib/py/lib/python3.10/site-packages/casatasks/private/task_tclean.py", line 448, in tclean
09-Dec-2024 12:08:34	imager.initializeImagers()
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/pkg/casa-CAS-13581-7-py3.10.el8/lib/py/lib/python3.10/site-packages/casatasks/private/imagerhelpers/imager_parallel_continuum.py", line 221, in initializeImagers
09-Dec-2024 12:08:34	super().makeCFCache(False)
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/pkg/casa-CAS-13581-7-py3.10.el8/lib/py/lib/python3.10/site-packages/casatasks/private/imagerhelpers/imager_base.py", line 91, in makeCFCache
09-Dec-2024 12:08:34	self.fillCFCache();
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/pkg/casa-CAS-13581-7-py3.10.el8/lib/py/lib/python3.10/site-packages/casatasks/private/imagerhelpers/imager_base.py", line 514, in fillCFCache
09-Dec-2024 12:08:34	self.SItool.fillcfcache(
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/pkg/casa-CAS-13581-7-py3.10.el8/lib/py/lib/python3.10/site-packages/casatools/synthesisimager.py", line 82, in fillcfcache
09-Dec-2024 12:08:34	return self._swigobj.fillcfcache(cflist, ftmname, cfcpath, pstermon, atermon, conjbeams)
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/pkg/casa-CAS-13581-7-py3.10.el8/lib/py/lib/python3.10/site-packages/casatools/__casac__/synthesisimager.py", line 403, in fillcfcache
09-Dec-2024 12:08:34	return _synthesisimager.synthesisimager_fillcfcache(self, *args, **kwargs)
09-Dec-2024 12:08:34	RuntimeError: Error while initializing CF disk cache: Table file /table.dat does not exist
09-Dec-2024 12:08:34
09-Dec-2024 12:08:34	======================================================================
09-Dec-2024 12:08:34	ERROR: test_widefield_awp2_mtmfs (__main__.test_widefield)
09-Dec-2024 12:08:34	[widefield] Test_Widefield_wbaproj_mtmfs : MFS with wideband AWProjection (wbawp=T,conjbeams=T, allspw) and nt=2 stokes I
09-Dec-2024 12:08:34	----------------------------------------------------------------------
09-Dec-2024 12:08:34	Traceback (most recent call last):
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/casasources//casa6/casatasks/tests/tasks/test_task_tclean.py", line 3997, in test_widefield_awp2_mtmfs
09-Dec-2024 12:08:34	ret = tclean(vis=self.msfile,field='*',imagename=self.img,imsize=512,cell='10.0arcsec',phasecenter="J2000 19:59:28.500 +40.44.01.50",
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/pkg/casa-CAS-13581-7-py3.10.el8/lib/py/lib/python3.10/site-packages/casatasks/tclean.py", line 1838, in __call__
09-Dec-2024 12:08:34	task_result = _tclean_t( _pc.document['vis'], _pc.document['selectdata'], _pc.document['field'], _pc.document['spw'], _pc.document['timerange'], _pc.document['uvrange'], _pc.document['antenna'], _pc.document['scan'], _pc.document['observation'], _pc.document['intent'], _pc.document['datacolumn'], _pc.document['imagename'], _pc.document['imsize'], _pc.document['cell'], _pc.document['phasecenter'], _pc.document['stokes'], _pc.document['projection'], _pc.document['startmodel'], _pc.document['specmode'], _pc.document['reffreq'], _pc.document['nchan'], _pc.document['start'], _pc.document['width'], _pc.document['outframe'], _pc.document['veltype'], _pc.document['restfreq'], _pc.document['interpolation'], _pc.document['perchanweightdensity'], _pc.document['gridder'], _pc.document['facets'], _pc.document['psfphasecenter'], _pc.document['wprojplanes'], _pc.document['vptable'], _pc.document['mosweight'], _pc.document['aterm'], _pc.document['psterm'], _pc.document['wbawp'], _pc.document['conjbeams'], _pc.document['cfcache'], _pc.document['usepointing'], _pc.document['computepastep'], _pc.document['rotatepastep'], _pc.document['pointingoffsetsigdev'], _pc.document['pblimit'], _pc.document['normtype'], _pc.document['deconvolver'], _pc.document['scales'], _pc.document['nterms'], _pc.document['smallscalebias'], _pc.document['fusedthreshold'], _pc.document['largestscale'], _pc.document['restoration'], _pc.document['restoringbeam'], _pc.document['pbcor'], _pc.document['outlierfile'], _pc.document['weighting'], _pc.document['robust'], _pc.document['noise'], _pc.document['npixels'], _pc.document['uvtaper'], _pc.document['niter'], _pc.document['gain'], _pc.document['threshold'], _pc.document['nsigma'], _pc.document['cycleniter'], _pc.document['cyclefactor'], _pc.document['minpsffraction'], _pc.document['maxpsffraction'], _pc.document['interactive'], _pc.document['nmajor'], _pc.document['fullsummary'], _pc.document['usemask'], _pc.document['mask'], _pc.document['pbmask'], _pc.document['sidelobethreshold'], _pc.document['noisethreshold'], _pc.document['lownoisethreshold'], _pc.document['negativethreshold'], _pc.document['smoothfactor'], _pc.document['minbeamfrac'], _pc.document['cutthreshold'], _pc.document['growiterations'], _pc.document['dogrowprune'], _pc.document['minpercentchange'], _pc.document['verbose'], _pc.document['fastnoise'], _pc.document['restart'], _pc.document['savemodel'], _pc.document['calcres'], _pc.document['calcpsf'], _pc.document['psfcutoff'], _pc.document['parallel'] )
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/pkg/casa-CAS-13581-7-py3.10.el8/lib/py/lib/python3.10/site-packages/casatasks/private/task_tclean.py", line 448, in tclean
09-Dec-2024 12:08:34	imager.initializeImagers()
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/pkg/casa-CAS-13581-7-py3.10.el8/lib/py/lib/python3.10/site-packages/casatasks/private/imagerhelpers/imager_parallel_continuum.py", line 221, in initializeImagers
09-Dec-2024 12:08:34	super().makeCFCache(False)
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/pkg/casa-CAS-13581-7-py3.10.el8/lib/py/lib/python3.10/site-packages/casatasks/private/imagerhelpers/imager_base.py", line 91, in makeCFCache
09-Dec-2024 12:08:34	self.fillCFCache();
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/pkg/casa-CAS-13581-7-py3.10.el8/lib/py/lib/python3.10/site-packages/casatasks/private/imagerhelpers/imager_base.py", line 514, in fillCFCache
09-Dec-2024 12:08:34	self.SItool.fillcfcache(
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/pkg/casa-CAS-13581-7-py3.10.el8/lib/py/lib/python3.10/site-packages/casatools/synthesisimager.py", line 82, in fillcfcache
09-Dec-2024 12:08:34	return self._swigobj.fillcfcache(cflist, ftmname, cfcpath, pstermon, atermon, conjbeams)
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/pkg/casa-CAS-13581-7-py3.10.el8/lib/py/lib/python3.10/site-packages/casatools/__casac__/synthesisimager.py", line 403, in fillcfcache
09-Dec-2024 12:08:34	return _synthesisimager.synthesisimager_fillcfcache(self, *args, **kwargs)
09-Dec-2024 12:08:34	RuntimeError: Error while initializing CF disk cache: Table file /table.dat does not exist
09-Dec-2024 12:08:34
09-Dec-2024 12:08:34	======================================================================
09-Dec-2024 12:08:34	FAIL: test_mtmfs_awproject_cbFalse_twofield (__main__.test_mosaic_mtmfs)
09-Dec-2024 12:08:34	----------------------------------------------------------------------
09-Dec-2024 12:08:34	Traceback (most recent call last):
09-Dec-2024 12:08:34	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/casasources//casa6/casatasks/tests/tasks/test_task_tclean.py", line 6036, in test_mtmfs_awproject_cbFalse_twofield
09-Dec-2024 12:08:34	self.assertTrue(self.check_final(report1 + report2 + '\n Warning: values must be theoretically validated'))
09-Dec-2024 12:08:34	AssertionError: False is not true
09-Dec-2024 12:08:34
09-Dec-2024 12:08:34	======================================================================
09-Dec-2024 12:08:34	FAIL: test_mtmfs_awproject_cbFalse_twofield_upTrue (__main__.test_mosaic_mtmfs)
09-Dec-2024 12:08:34	----------------------------------------------------------------------
09-Dec-2024 12:08:34	Traceback (most recent call last):
09-Dec-2024 12:08:48	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/casasources//casa6/casatasks/tests/tasks/test_task_tclean.py", line 6164, in test_mtmfs_awproject_cbFalse_twofield_upTrue
09-Dec-2024 12:08:48	self.assertTrue(self.check_final(report1 + report2 + '\n Warning: values must be theoretically validated'))
09-Dec-2024 12:08:48	AssertionError: False is not true
09-Dec-2024 12:08:48
09-Dec-2024 12:08:48	======================================================================
09-Dec-2024 12:08:48	FAIL: test_mtmfs_awproject_cbTrue_twofield (__main__.test_mosaic_mtmfs)
09-Dec-2024 12:08:48	----------------------------------------------------------------------
09-Dec-2024 12:08:48	Traceback (most recent call last):
09-Dec-2024 12:08:48	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/casasources//casa6/casatasks/tests/tasks/test_task_tclean.py", line 6062, in test_mtmfs_awproject_cbTrue_twofield
09-Dec-2024 12:08:48	self.assertTrue(self.check_final(report1+report2))
09-Dec-2024 12:08:48	AssertionError: False is not true
09-Dec-2024 12:08:48
09-Dec-2024 12:08:48	======================================================================
09-Dec-2024 12:08:48	FAIL: test_mtmfs_awproject_cbTrue_twofield_use_standard_psf (__main__.test_mosaic_mtmfs)
09-Dec-2024 12:08:48	----------------------------------------------------------------------
09-Dec-2024 12:08:48	Traceback (most recent call last):
09-Dec-2024 12:08:48	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/casasources//casa6/casatasks/tests/tasks/test_task_tclean.py", line 6111, in test_mtmfs_awproject_cbTrue_twofield_use_standard_psf
09-Dec-2024 12:08:48	self.assertTrue(self.check_final(report))
09-Dec-2024 12:08:48	AssertionError: False is not true
09-Dec-2024 12:08:48
09-Dec-2024 12:08:48	======================================================================
09-Dec-2024 12:08:48	FAIL: test_mtmfsviacube_awproject (__main__.test_mtmfsviacube)
09-Dec-2024 12:08:48	[mtmfsviacube] test_mosaic_mtmfs_cube: test mosaic with mtmfs via cube
09-Dec-2024 12:08:48	----------------------------------------------------------------------
09-Dec-2024 12:08:48	Traceback (most recent call last):
09-Dec-2024 12:08:48	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/casasources//casa6/casatasks/tests/tasks/test_task_tclean.py", line 5743, in test_mtmfsviacube_awproject
09-Dec-2024 12:08:48	self.assertTrue(self.check_final(pstr=report))
09-Dec-2024 12:08:48	AssertionError: False is not true
09-Dec-2024 12:08:48
09-Dec-2024 12:08:48	======================================================================
09-Dec-2024 12:08:48	FAIL: test_widefield_aproj_cube (__main__.test_widefield)
09-Dec-2024 12:08:48	[widefield] Test_Widefield_aproj_cube_aproj : Cube with AW-Projection  and rotation off
09-Dec-2024 12:08:48	----------------------------------------------------------------------
09-Dec-2024 12:08:48	Traceback (most recent call last):
09-Dec-2024 12:08:48	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/casasources//casa6/casatasks/tests/tasks/test_task_tclean.py", line 3900, in test_widefield_aproj_cube
09-Dec-2024 12:08:48	self.assertTrue(self.check_final(report))
09-Dec-2024 12:08:48	AssertionError: False is not true
09-Dec-2024 12:08:48
09-Dec-2024 12:08:48	======================================================================
09-Dec-2024 12:08:48	FAIL: test_widefield_aproj_mfs (__main__.test_widefield)
09-Dec-2024 12:08:48	[widefield] Test_Widefield_aproj : MFS with narrowband AWProjection (wbawp=F, 1spw)  stokes I
09-Dec-2024 12:08:48	----------------------------------------------------------------------
09-Dec-2024 12:08:48	Traceback (most recent call last):
09-Dec-2024 12:08:48	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/casasources//casa6/casatasks/tests/tasks/test_task_tclean.py", line 3855, in test_widefield_aproj_mfs
09-Dec-2024 12:08:48	self.assertTrue(self.check_final(report))
09-Dec-2024 12:08:48	AssertionError: False is not true
09-Dec-2024 12:08:48
09-Dec-2024 12:08:48	======================================================================
09-Dec-2024 12:08:48	FAIL: test_widefield_aproj_mtmfs (__main__.test_widefield)
09-Dec-2024 12:08:48	[widefield] Test_Widefield_aproj_mtmfs : MFS with AWProjection (wbawp=T,conjbeams=F, allspw) and nt=2 stokes I
09-Dec-2024 12:08:48	----------------------------------------------------------------------
09-Dec-2024 12:08:48	Traceback (most recent call last):
09-Dec-2024 12:08:48	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/casasources//casa6/casatasks/tests/tasks/test_task_tclean.py", line 3967, in test_widefield_aproj_mtmfs
09-Dec-2024 12:08:48	self.assertTrue(self.check_final(report))
09-Dec-2024 12:08:48	AssertionError: False is not true
09-Dec-2024 12:08:48
09-Dec-2024 12:08:48	======================================================================
09-Dec-2024 12:08:48	FAIL: test_widefield_wbaproj_mfs (__main__.test_widefield)
09-Dec-2024 12:08:48	[widefield] Test_Widefield_wbaproj_mfs : MFS with wideband AWProjection (wbawp=T, allspw) and nt=1 stokes I
09-Dec-2024 12:08:48	----------------------------------------------------------------------
09-Dec-2024 12:08:48	Traceback (most recent call last):
09-Dec-2024 12:08:48	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/casasources//casa6/casatasks/tests/tasks/test_task_tclean.py", line 3947, in test_widefield_wbaproj_mfs
09-Dec-2024 12:08:48	self.assertTrue(self.check_final(report))
09-Dec-2024 12:08:48	AssertionError: False is not true
09-Dec-2024 12:08:48
09-Dec-2024 12:08:48	======================================================================
09-Dec-2024 12:08:48	FAIL: test_widefield_wbaproj_mtmfs (__main__.test_widefield)
09-Dec-2024 12:08:48	[widefield] Test_Widefield_wbaproj_mtmfs : MFS with wideband AWProjection (wbawp=T,conjbeams=T, allspw) and nt=2 stokes I
09-Dec-2024 12:08:48	----------------------------------------------------------------------
09-Dec-2024 12:08:48	Traceback (most recent call last):
09-Dec-2024 12:08:48	File "/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228/work/casasources//casa6/casatasks/tests/tasks/test_task_tclean.py", line 3988, in test_widefield_wbaproj_mtmfs
09-Dec-2024 12:08:48	self.assertTrue(self.check_final(report))
09-Dec-2024 12:08:48	AssertionError: False is not true
09-Dec-2024 12:08:48
09-Dec-2024 12:08:48	----------------------------------------------------------------------
09-Dec-2024 12:08:48	Ran 318 tests in 12362.310s
09-Dec-2024 12:08:48
09-Dec-2024 12:08:48	FAILED (failures=10, errors=2, skipped=33)
09-Dec-2024 12:08:48	CASA exits with a non-zero status : system exit called
09-Dec-2024 12:08:48	2024-12-09 12:08:39        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal self.signal_stop_virtual_frame_buffer() successfully handled by server 3
09-Dec-2024 12:08:48	2024-12-09 12:08:39        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal self.signal_stop_virtual_frame_buffer() successfully handled by server 2
09-Dec-2024 12:08:48	2024-12-09 12:08:39        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal self.signal_stop_virtual_frame_buffer() successfully handled by server 1
09-Dec-2024 12:08:48	2024-12-09 12:08:42        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal self.signal_stop_service_request() successfully handled by server 3
09-Dec-2024 12:08:48	2024-12-09 12:08:42        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal self.signal_stop_service_request() successfully handled by server 2
09-Dec-2024 12:08:48	2024-12-09 12:08:42        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal self.signal_stop_service_request() successfully handled by server 1
09-Dec-2024 12:08:48	2024-12-09 12:08:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        stop service signal received, stopping services...
09-Dec-2024 12:08:48	2024-12-09 12:08:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        stop service signal received, stopping services...
09-Dec-2024 12:08:48	2024-12-09 12:08:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        stop service signal received, stopping services...
09-Dec-2024 12:08:48	2024-12-09 12:08:48        INFO        MPICommandServer::stop_command_request_handler_service::MPICommandServer::stop_command_request_handler_service::casa::MPIServer-1        MPI command request handler service stopped
09-Dec-2024 12:08:48	2024-12-09 12:08:48        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Going to finalize MPI environment
09-Dec-2024 12:08:48	2024-12-09 12:08:48        INFO        MPICommandServer::stop_command_request_handler_service::MPICommandServer::stop_command_request_handler_service::casa::MPIServer-3        MPI command request handler service stopped
09-Dec-2024 12:08:48	2024-12-09 12:08:48        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Going to finalize MPI environment
09-Dec-2024 12:08:48	2024-12-09 12:08:48        INFO        MPICommandServer::stop_command_request_handler_service::MPICommandServer::stop_command_request_handler_service::casa::MPIServer-2        MPI command request handler service stopped
09-Dec-2024 12:08:48	2024-12-09 12:08:48        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Going to finalize MPI environment
09-Dec-2024 12:08:48	2024-12-09 12:08:48        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Exiting
09-Dec-2024 12:08:48	2024-12-09 12:08:48        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Exiting
09-Dec-2024 12:08:48	2024-12-09 12:08:48        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Exiting
09-Dec-2024 12:08:48	Executor result: 1
09-Dec-2024 12:08:48	Failed tests:['test_mtmfs_awproject_cbFalse_twofield', 'test_mtmfs_awproject_cbFalse_twofield_upTrue', 'test_mtmfs_awproject_cbTrue_twofield', 'test_mtmfs_awproject_cbTrue_twofield_use_standard_psf', 'test_mtmfsviacube_awproject', 'test_widefield_aproj_cube', 'test_widefield_aproj_mfs', 'test_widefield_aproj_mtmfs', 'test_widefield_wbaproj_mfs', 'test_widefield_wbaproj_mtmfs', 'Executor returned a non-zero exit code']
09-Dec-2024 12:08:48
09-Dec-2024 12:08:48	Results: [{'testname': 'test_task_tclean', 'runtime': '3:26:18.438318', 'returncode': 11, 'testerr': ['test_mtmfs_awproject_cbFalse_twofield', 'test_mtmfs_awproject_cbFalse_twofield_upTrue', 'test_mtmfs_awproject_cbTrue_twofield', 'test_mtmfs_awproject_cbTrue_twofield_use_standard_psf', 'test_mtmfsviacube_awproject', 'test_widefield_aproj_cube', 'test_widefield_aproj_mfs', 'test_widefield_aproj_mtmfs', 'test_widefield_wbaproj_mfs', 'test_widefield_wbaproj_mtmfs', 'Executor returned a non-zero exit code']}]
09-Dec-2024 12:08:48	Stopping Xvfb.
09-Dec-2024 12:08:48	Stopping framebuffer <subprocess.Popen object at 0x7f11dd5b1c88>
09-Dec-2024 12:08:48	Virtual frame buffer deployed at :3714756 with pid 3714808 successfully shutdown
09-Dec-2024 12:08:48	Xvfb stopped.
09-Dec-2024 12:08:48	Finished task 'Test' with result: Success
09-Dec-2024 12:08:48	Starting task 'Parse test results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
09-Dec-2024 12:08:48	Parsing test results under /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY3102-CCM228...
09-Dec-2024 12:08:48	Failing task since 1 failing test cases were found.
09-Dec-2024 12:08:48	Finished task 'Parse test results' with result: Failed
09-Dec-2024 12:08:48	Running post build plugin 'Docker Container Cleanup'
09-Dec-2024 12:08:48	Running post build plugin 'NCover Results Collector'
09-Dec-2024 12:08:48	Running post build plugin 'Build Results Label Collector'
09-Dec-2024 12:08:48	Running post build plugin 'Clover Results Collector'
09-Dec-2024 12:08:48	Running post build plugin 'npm Cache Cleanup'
09-Dec-2024 12:08:48	Running post build plugin 'Artifact Copier'
09-Dec-2024 12:08:48	Finalising the build...
09-Dec-2024 12:08:48	Stopping timer.
09-Dec-2024 12:08:48	Build CASASPECS-DEVVERCASALITHPY3102-CCM228-1 completed.
09-Dec-2024 07:08:48	Running on server: post build plugin 'Build Hanging Detection Configuration'
09-Dec-2024 07:08:48	Running on server: post build plugin 'NCover Results Collector'
09-Dec-2024 07:08:48	Running on server: post build plugin 'Build Labeller'
09-Dec-2024 07:08:48	Running on server: post build plugin 'Clover Delta Calculator'
09-Dec-2024 07:08:48	Running on server: post build plugin 'Maven Dependencies Postprocessor'
09-Dec-2024 07:08:48	All post build plugins have finished
09-Dec-2024 07:08:48	Generating build results summary...
09-Dec-2024 07:08:48	Saving build results to disk...
09-Dec-2024 07:08:49	Store variable context...
09-Dec-2024 07:08:49	Finished building CASASPECS-DEVVERCASALITHPY3102-CCM228-1.