Build: #2 did not complete

Job: Test Tasks Many Linux 2014 did not complete

Build log

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

31-Oct-2023 14:50:29 2023-10-31 18:50:30        WARN        ImageFitter::_finishConstruction        Unrecognized intensity unit . Will assume Jy/pixel
31-Oct-2023 14:50:29 2023-10-31 18:50:30        WARN        ImageFitter::clearNoiseFWHM        noiseFWHM not specified and image has no beam, using uncorrelated noise expressions to calculate uncertainties
31-Oct-2023 14:50:29 2023-10-31 18:50:30        WARN        SkyCompRep::convertToJy        Cannot convert units of brightness to Jy - will assume Jy
31-Oct-2023 14:50:29 2023-10-31 18:50:30        WARN        SkyCompRep::peakToIntegralFlux()        There are no image brightness units, assuming Jy/pixel
31-Oct-2023 14:50:29 2023-10-31 18:50:30        WARN        SkyCompRep::integralToPeakFlux()        There are no image brightness units, assuming Jy/pixel
31-Oct-2023 14:50:30 2023-10-31 18:50:30        WARN        ImageFitter::_finishConstruction        Unrecognized intensity unit . Will assume Jy/pixel
31-Oct-2023 14:50:30 2023-10-31 18:50:30        WARN        ImageFitter::clearNoiseFWHM        noiseFWHM not specified and image has no beam, using uncorrelated noise expressions to calculate uncertainties
31-Oct-2023 14:50:30 2023-10-31 18:50:30        WARN        SkyCompRep::convertToJy        Cannot convert units of brightness to Jy - will assume Jy
31-Oct-2023 14:50:30 2023-10-31 18:50:30        WARN        SkyCompRep::peakToIntegralFlux()        There are no image brightness units, assuming Jy/pixel
31-Oct-2023 14:50:30 2023-10-31 18:50:30        WARN        SkyCompRep::integralToPeakFlux()        There are no image brightness units, assuming Jy/pixel
31-Oct-2023 14:50:30 2023-10-31 18:50:30        WARN        ImageFitter::_finishConstruction        Unrecognized intensity unit . Will assume Jy/pixel
31-Oct-2023 14:50:30 2023-10-31 18:50:30        WARN        ImageFitter::clearNoiseFWHM        noiseFWHM not specified and image has no beam, using uncorrelated noise expressions to calculate uncertainties
31-Oct-2023 14:50:30 2023-10-31 18:50:30        WARN        SkyCompRep::convertToJy        Cannot convert units of brightness to Jy - will assume Jy
31-Oct-2023 14:50:30 2023-10-31 18:50:30        WARN        SkyCompRep::peakToIntegralFlux()        There are no image brightness units, assuming Jy/pixel
31-Oct-2023 14:50:31 2023-10-31 18:50:31        WARN        SkyCompRep::integralToPeakFlux()        There are no image brightness units, assuming Jy/pixel
31-Oct-2023 14:50:31 2023-10-31 18:50:31        WARN        ImageFitter::_finishConstruction        Unrecognized intensity unit . Will assume Jy/pixel
31-Oct-2023 14:50:31 2023-10-31 18:50:31        WARN        ImageFitter::clearNoiseFWHM        noiseFWHM not specified and image has no beam, using uncorrelated noise expressions to calculate uncertainties
31-Oct-2023 14:50:31 2023-10-31 18:50:32        WARN        SkyCompRep::convertToJy        Cannot convert units of brightness to Jy - will assume Jy
31-Oct-2023 14:50:31 2023-10-31 18:50:32        WARN        SkyCompRep::peakToIntegralFlux()        There are no image brightness units, assuming Jy/pixel
31-Oct-2023 14:50:31 2023-10-31 18:50:32        WARN        SkyCompRep::integralToPeakFlux()        There are no image brightness units, assuming Jy/pixel
31-Oct-2023 14:50:31
31-Oct-2023 14:50:31 Test that regridding to new refcode preserves source positions
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 diff [0.]
31-Oct-2023 14:50:31 PASSED
31-Oct-2023 14:50:31
31-Oct-2023 14:50:32 test_task_imregrid.py::imregrid_test::test_template_stokes_length_and_input_stokes_length_gt_1 2023-10-31 18:50:32        WARN        imregrid::ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
31-Oct-2023 14:50:32
31-Oct-2023 14:50:32 PASSED
31-Oct-2023 14:50:32
31-Oct-2023 14:50:32 test_task_imregrid.py::imregrid_test::test_template_stokes_length_gt_1_and_input_stokes_length_gt_0 2023-10-31 18:50:32        SEVERE        imregrid::image::regrid        Exception Reported: Exception: Input image and template coordinate system have no common stokes..
31-Oct-2023 14:50:32 2023-10-31 18:50:32        SEVERE        imregrid::image::regrid+        ... thrown by void casa::ImageRegridderBase<T>::_finishConstruction() [with T = float] at File: /source/casainstall/include/casacpp/imageanalysis/ImageAnalysis/ImageRegridderBase.tcc, line: 138
31-Oct-2023 14:50:32 2023-10-31 18:50:32        SEVERE        imregrid::::casa        Task imregrid raised an exception of class RuntimeError with the following message: Exception: Input image and template coordinate system have no common stokes..
31-Oct-2023 14:50:32 2023-10-31 18:50:32        SEVERE        imregrid::::casa+        ... thrown by void casa::ImageRegridderBase<T>::_finishConstruction() [with T = float] at File: /source/casainstall/include/casacpp/imageanalysis/ImageAnalysis/ImageRegridderBase.tcc, line: 138
31-Oct-2023 14:50:32 2023-10-31 18:50:32        SEVERE        imregrid::image::regrid        Exception Reported: Exception: Specified output stokes axis length (3) does not match the number of common stokes (1) in the input image and template coordinate system..
31-Oct-2023 14:50:32 2023-10-31 18:50:32        SEVERE        imregrid::image::regrid+        ... thrown by void casa::ImageRegridderBase<T>::_finishConstruction() [with T = float] at File: /source/casainstall/include/casacpp/imageanalysis/ImageAnalysis/ImageRegridderBase.tcc, line: 143
31-Oct-2023 14:50:32 2023-10-31 18:50:32        SEVERE        imregrid::::casa        Task imregrid raised an exception of class RuntimeError with the following message: Exception: Specified output stokes axis length (3) does not match the number of common stokes (1) in the input image and template coordinate system..
31-Oct-2023 14:50:32 2023-10-31 18:50:32        SEVERE        imregrid::::casa+        ... thrown by void casa::ImageRegridderBase<T>::_finishConstruction() [with T = float] at File: /source/casainstall/include/casacpp/imageanalysis/ImageAnalysis/ImageRegridderBase.tcc, line: 143
31-Oct-2023 14:50:32
31-Oct-2023 14:50:32 Verify correct behavior for the template image having a stokes axis of length > 1
31-Oct-2023 14:50:32 PASSED
31-Oct-2023 14:50:32
31-Oct-2023 14:50:32 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_task_imregrid/nose.xml -
31-Oct-2023 14:50:32 ================== 20 passed, 2 warnings in 114.72s (0:01:54) ==================
31-Oct-2023 14:50:33 ============================= test session starts ==============================
31-Oct-2023 14:50:33 platform linux -- Python 3.8.7, pytest-7.4.3, pluggy-1.3.0 -- /home/casatest/venv/bin/python3.8
31-Oct-2023 14:50:33 cachedir: .pytest_cache
31-Oct-2023 14:50:33 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_task_virtualconcat
31-Oct-2023 14:50:33 configfile: pytest.ini
31-Oct-2023 14:50:34 collecting ... collected 13 items
31-Oct-2023 14:50:34
31-Oct-2023 14:50:34
31-Oct-2023 14:50:36 test_task_virtualconcat.py::test_virtualconcat::test1 2023-10-31 18:50:36        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:36 2023-10-31 18:50:36        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:36 2023-10-31 18:50:36        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:36 2023-10-31 18:50:36        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:37
31-Oct-2023 14:50:37 Virtualconcat 1: 4 parts, same sources but different spws
31-Oct-2023 14:50:37 Copying  A2256LC2_4.5s-1.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/A2256LC2_4.5s-1.ms
31-Oct-2023 14:50:37 Copying  A2256LC2_4.5s-2.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/A2256LC2_4.5s-2.ms
31-Oct-2023 14:50:37 Copying  part2-mod2.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/part2-mod2.ms
31-Oct-2023 14:50:37 Copying  part2.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/part2.ms
31-Oct-2023 14:50:37 Copying  part4.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/part4.ms
31-Oct-2023 14:50:37 Copying  shortpart2.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/shortpart2.ms
31-Oct-2023 14:50:37 Copying  shortpart4.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/shortpart4.ms
31-Oct-2023 14:50:37 Copying  sim7.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/sim7.ms
31-Oct-2023 14:50:37 Copying  X39a.pm03.scan3.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/X39a.pm03.scan3.ms
31-Oct-2023 14:50:37 Copying  X425.pm04.scan4.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/X425.pm04.scan4.ms
31-Oct-2023 14:50:37 Copying  A2256LC2_4.5s-2b.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/A2256LC2_4.5s-2b.ms
31-Oct-2023 14:50:37 Copying  part1.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/part1.ms
31-Oct-2023 14:50:37 Copying  part2-mod.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/part2-mod.ms
31-Oct-2023 14:50:37 Copying  part3.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/part3.ms
31-Oct-2023 14:50:37 Copying  shortpart1.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/shortpart1.ms
31-Oct-2023 14:50:37 Copying  shortpart3.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/shortpart3.ms
31-Oct-2023 14:50:37 Copying  shortpart5.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/shortpart5.ms
31-Oct-2023 14:50:37 Copying  sim8.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/sim8.ms
31-Oct-2023 14:50:37 Copying  X425.pm03.scan4.ms
31-Oct-2023 14:50:37 /opt/casa/data/casatestdata/unittest/virtualconcat/X425.pm03.scan4.ms
31-Oct-2023 14:50:37 test_task_virtualconcat : Now checking output MS  concatenated.ms
31-Oct-2023 14:50:37 test_task_virtualconcat : OK. Checking tables in detail ...
31-Oct-2023 14:50:37 test_task_virtualconcat : comparing  ['SOURCE_ID', 55, 13, 0]
31-Oct-2023 14:50:37 test_task_virtualconcat : comparing  ['SPECTRAL_WINDOW_ID', 55, 3, 0]
31-Oct-2023 14:50:37 test_task_virtualconcat : table  SOURCE  as expected.
31-Oct-2023 14:50:37 test_task_virtualconcat : comparing  ['NUM_CHAN', 3, 128, 0]
31-Oct-2023 14:50:37 test_task_virtualconcat : table  SPECTRAL_WINDOW  as expected.
31-Oct-2023 14:50:37 PASSED
31-Oct-2023 14:50:37
31-Oct-2023 14:50:38 test_task_virtualconcat.py::test_virtualconcat::test10 2023-10-31 18:50:38        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:38 2023-10-31 18:50:38        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:38 2023-10-31 18:50:38        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:38 2023-10-31 18:50:38        WARN        virtualconcat::::casa        The setup of the input MSs is not fully consistent. The concatenation may fail
31-Oct-2023 14:50:38 2023-10-31 18:50:38        WARN        virtualconcat::::casa        and/or the affected columns may contain partially only default data.
31-Oct-2023 14:50:38 2023-10-31 18:50:38        WARN        virtualconcat::::casa        {'part2-mod2.ms': {'Main': {'present_a': True, 'present_b': True, 'missingcol_a': [], 'missingcol_b': ['CORRECTED_DATA', 'MODEL_DATA']}}, 'part3.ms': {'Main': {'present_a': True, 'present_b': True, 'missingcol_a': [], 'missingcol_b': ['CORRECTED_DATA', 'MODEL_DATA']}}}
31-Oct-2023 14:50:40
31-Oct-2023 14:50:40 Virtualconcat 10: 3 parts, different sources, same spws, different scratch columns: yes, no, no
31-Oct-2023 14:50:40 Copying  part2.ms
31-Oct-2023 14:50:40 /opt/casa/data/casatestdata/unittest/virtualconcat/part2.ms
31-Oct-2023 14:50:40 Copying  part4.ms
31-Oct-2023 14:50:40 /opt/casa/data/casatestdata/unittest/virtualconcat/part4.ms
31-Oct-2023 14:50:40 Copying  part1.ms
31-Oct-2023 14:50:40 /opt/casa/data/casatestdata/unittest/virtualconcat/part1.ms
31-Oct-2023 14:50:40 Copying  part3.ms
31-Oct-2023 14:50:40 /opt/casa/data/casatestdata/unittest/virtualconcat/part3.ms
31-Oct-2023 14:50:40 creating scratch columns in part1-wscratch.ms
31-Oct-2023 14:50:40 test_task_virtualconcat : Now checking output ...
31-Oct-2023 14:50:40 test_task_virtualconcat : OK. Checking tables in detail ...
31-Oct-2023 14:50:40 test_task_virtualconcat : comparing  ['SOURCE_ID', 28, 13, 0]
31-Oct-2023 14:50:40 test_task_virtualconcat : comparing  ['SPECTRAL_WINDOW_ID', 28, 1, 0]
31-Oct-2023 14:50:40 test_task_virtualconcat : table  SOURCE  as expected.
31-Oct-2023 14:50:40 test_task_virtualconcat : comparing  ['NUM_CHAN', 1, 128, 0]
31-Oct-2023 14:50:40 test_task_virtualconcat : table  SPECTRAL_WINDOW  as expected.
31-Oct-2023 14:50:40 PASSED
31-Oct-2023 14:50:40
31-Oct-2023 14:50:40 test_task_virtualconcat.py::test_virtualconcat::test11 2023-10-31 18:50:41        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:40 2023-10-31 18:50:41        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:40 2023-10-31 18:50:41        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:40 2023-10-31 18:50:41        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:43 2023-10-31 18:50:43        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:43 2023-10-31 18:50:43        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:43 2023-10-31 18:50:43        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:43 2023-10-31 18:50:43        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:44
31-Oct-2023 14:50:44 Virtualconcat 11: comparison to concat
31-Oct-2023 14:50:44 Copying  part2-mod2.ms
31-Oct-2023 14:50:44 /opt/casa/data/casatestdata/unittest/virtualconcat/part2-mod2.ms
31-Oct-2023 14:50:44 Copying  part3.ms
31-Oct-2023 14:50:44 /opt/casa/data/casatestdata/unittest/virtualconcat/part3.ms
31-Oct-2023 14:50:44 duration using concat (s) =  2.3431289196014404
31-Oct-2023 14:50:44 duration using virtualconcat (s) = 0.7841787338256836
31-Oct-2023 14:50:44 PASSED
31-Oct-2023 14:50:44
31-Oct-2023 14:50:44 test_task_virtualconcat.py::test_virtualconcat::test12 2023-10-31 18:50:45        WARN        MSConcat::virtualconcat (file /source/casa6/casatools/casacore/ms/MSOper/MSConcat.cc, line 149)        Zero or negative scan numbers in MS. May lead to duplicate scan numbers in concatenated MS.
31-Oct-2023 14:50:45
31-Oct-2023 14:50:45 Virtualconcat 12: two MSs with different antenna tables, copypointing=True (default)
31-Oct-2023 14:50:45 Copying  part2.ms
31-Oct-2023 14:50:45 /opt/casa/data/casatestdata/unittest/virtualconcat/part2.ms
31-Oct-2023 14:50:45 Copying  part4.ms
31-Oct-2023 14:50:45 /opt/casa/data/casatestdata/unittest/virtualconcat/part4.ms
31-Oct-2023 14:50:45 Copying  part1.ms
31-Oct-2023 14:50:45 /opt/casa/data/casatestdata/unittest/virtualconcat/part1.ms
31-Oct-2023 14:50:45 Copying  part3.ms
31-Oct-2023 14:50:45 /opt/casa/data/casatestdata/unittest/virtualconcat/part3.ms
31-Oct-2023 14:50:45 test_task_virtualconcat : Now checking output ...
31-Oct-2023 14:50:45 test_task_virtualconcat : OK. Checking tables in detail ...
31-Oct-2023 14:50:45 PASSED
31-Oct-2023 14:50:45
31-Oct-2023 14:50:45 test_task_virtualconcat.py::test_virtualconcat::test13
31-Oct-2023 14:50:45 Virtualconcat 13: 3 parts, SD data, one non-concurrent, two concurrent (CAS-5316)
31-Oct-2023 14:50:45 Copying  sim7.ms
31-Oct-2023 14:50:45 /opt/casa/data/casatestdata/unittest/virtualconcat/sim7.ms
31-Oct-2023 14:50:45 Copying  sim8.ms
31-Oct-2023 14:50:45 /opt/casa/data/casatestdata/unittest/virtualconcat/sim8.ms
31-Oct-2023 14:50:45 test_task_virtualconcat : Now checking output ...
31-Oct-2023 14:50:45 test_task_virtualconcat : OK. Checking tables in detail ...
31-Oct-2023 14:50:45 PASSED
31-Oct-2023 14:50:45
31-Oct-2023 14:50:46 test_task_virtualconcat.py::test_virtualconcat::test2 2023-10-31 18:50:46        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:46 2023-10-31 18:50:46        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:46 2023-10-31 18:50:46        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:48
31-Oct-2023 14:50:48 Virtualconcat 2: 3 parts, different sources, different spws, visweightscale=[3.,2.,1.], keepcopy=True
31-Oct-2023 14:50:48 Copying  X39a.pm03.scan3.ms
31-Oct-2023 14:50:48 /opt/casa/data/casatestdata/unittest/virtualconcat/X39a.pm03.scan3.ms
31-Oct-2023 14:50:48 Copying  X425.pm04.scan4.ms
31-Oct-2023 14:50:48 /opt/casa/data/casatestdata/unittest/virtualconcat/X425.pm04.scan4.ms
31-Oct-2023 14:50:48 Copying  X425.pm03.scan4.ms
31-Oct-2023 14:50:48 /opt/casa/data/casatestdata/unittest/virtualconcat/X425.pm03.scan4.ms
31-Oct-2023 14:50:48 test_task_virtualconcat : Now checking output MS  concatenated.ms
31-Oct-2023 14:50:48 test_task_virtualconcat : OK. Checking tables in detail ...
31-Oct-2023 14:50:48 test_task_virtualconcat : comparing  ['SOURCE_ID', 41, 13, 0]
31-Oct-2023 14:50:48 test_task_virtualconcat : comparing  ['SPECTRAL_WINDOW_ID', 41, 2, 0]
31-Oct-2023 14:50:48 test_task_virtualconcat : table  SOURCE  as expected.
31-Oct-2023 14:50:48 test_task_virtualconcat : comparing  ['NUM_CHAN', 2, 128, 0]
31-Oct-2023 14:50:48 test_task_virtualconcat : table  SPECTRAL_WINDOW  as expected.
31-Oct-2023 14:50:48 test_task_virtualconcat : comparing  ['WEIGHT', 0, array([11.86871839]), 1e-06]
31-Oct-2023 14:50:48 test_task_virtualconcat : comparing  ['WEIGHT', 23813, array([19.3635335]), 1e-06]
31-Oct-2023 14:50:48 test_task_virtualconcat : comparing  ['WEIGHT', 23814, array([7.91247892]), 1e-06]
31-Oct-2023 14:50:48 test_task_virtualconcat : comparing  ['WEIGHT', 47627, array([12.90902233]), 1e-06]
31-Oct-2023 14:50:48 test_task_virtualconcat : comparing  ['WEIGHT', 47628, array([3.95623946]), 1e-06]
31-Oct-2023 14:50:48 test_task_virtualconcat : comparing  ['WEIGHT', 71441, array([6.45451117]), 1e-06]
31-Oct-2023 14:50:48 test_task_virtualconcat : table  MAIN  as expected.
31-Oct-2023 14:50:48 PASSED
31-Oct-2023 14:50:48
31-Oct-2023 14:50:48 test_task_virtualconcat.py::test_virtualconcat::test3 2023-10-31 18:50:49        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:48 2023-10-31 18:50:49        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:48 2023-10-31 18:50:49        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:49
31-Oct-2023 14:50:49 Virtualconcat 3: 3 parts, different sources, same spws
31-Oct-2023 14:50:49 test_task_virtualconcat : Now checking output MS  concatenated.ms
31-Oct-2023 14:50:49 test_task_virtualconcat : OK. Checking tables in detail ...
31-Oct-2023 14:50:49 test_task_virtualconcat : comparing  ['SOURCE_ID', 28, 13, 0]
31-Oct-2023 14:50:49 test_task_virtualconcat : comparing  ['SPECTRAL_WINDOW_ID', 28, 1, 0]
31-Oct-2023 14:50:49 test_task_virtualconcat : table  SOURCE  as expected.
31-Oct-2023 14:50:49 test_task_virtualconcat : comparing  ['NUM_CHAN', 1, 128, 0]
31-Oct-2023 14:50:49 test_task_virtualconcat : table  SPECTRAL_WINDOW  as expected.
31-Oct-2023 14:50:49 PASSED
31-Oct-2023 14:50:49
31-Oct-2023 14:50:49 test_task_virtualconcat.py::test_virtualconcat::test4 2023-10-31 18:50:50        SEVERE        getcell::WEIGHT_SPECTRUM        Exception Reported: Table DataManager error: Invalid operation: TSM: no array in row 0 of column WEIGHT_SPECTRUM in /home/casatest/casa6/casatestutils/nosedir/test_task_virtualconcat/shortpart1.ms/table.f20
31-Oct-2023 14:50:49 2023-10-31 18:50:50        WARN        virtualconcat::::casa        Column WEIGHT_SPECTRUM in table shortpart1.ms has no data. Accessing it will cause errors.
31-Oct-2023 14:50:49 2023-10-31 18:50:50        SEVERE        getcell::WEIGHT_SPECTRUM        Exception Reported: Table DataManager error: Invalid operation: TSM: no array in row 0 of column WEIGHT_SPECTRUM in /home/casatest/casa6/casatestutils/nosedir/test_task_virtualconcat/shortpart2.ms/table.f20
31-Oct-2023 14:50:49 2023-10-31 18:50:50        WARN        virtualconcat::::casa        Column WEIGHT_SPECTRUM in table shortpart2.ms has no data. Accessing it will cause errors.
31-Oct-2023 14:50:49 2023-10-31 18:50:50        SEVERE        getcell::WEIGHT_SPECTRUM        Exception Reported: Table DataManager error: Invalid operation: TSM: no array in row 0 of column WEIGHT_SPECTRUM in /home/casatest/casa6/casatestutils/nosedir/test_task_virtualconcat/shortpart3.ms/table.f20
31-Oct-2023 14:50:49 2023-10-31 18:50:50        WARN        virtualconcat::::casa        Column WEIGHT_SPECTRUM in table shortpart3.ms has no data. Accessing it will cause errors.
31-Oct-2023 14:50:49 2023-10-31 18:50:50        SEVERE        getcell::WEIGHT_SPECTRUM        Exception Reported: Table DataManager error: Invalid operation: TSM: no array in row 0 of column WEIGHT_SPECTRUM in /home/casatest/casa6/casatestutils/nosedir/test_task_virtualconcat/shortpart4.ms/table.f20
31-Oct-2023 14:50:49 2023-10-31 18:50:50        WARN        virtualconcat::::casa        Column WEIGHT_SPECTRUM in table shortpart4.ms has no data. Accessing it will cause errors.
31-Oct-2023 14:50:49 2023-10-31 18:50:50        SEVERE        getcell::WEIGHT_SPECTRUM        Exception Reported: Table DataManager error: Invalid operation: TSM: no array in row 0 of column WEIGHT_SPECTRUM in /home/casatest/casa6/casatestutils/nosedir/test_task_virtualconcat/shortpart5.ms/table.f20
31-Oct-2023 14:50:49 2023-10-31 18:50:50        WARN        virtualconcat::::casa        Column WEIGHT_SPECTRUM in table shortpart5.ms has no data. Accessing it will cause errors.
31-Oct-2023 14:50:50 2023-10-31 18:50:51        SEVERE        getcell::SOURCE_ID        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:50
31-Oct-2023 14:50:50 Virtualconcat 4: five MSs with identical sources but different time/intervals on them (CSV-268)
31-Oct-2023 14:50:50 Copying  part2-mod2.ms
31-Oct-2023 14:50:50 /opt/casa/data/casatestdata/unittest/virtualconcat/part2-mod2.ms
31-Oct-2023 14:50:50 Copying  part1.ms
31-Oct-2023 14:50:50 /opt/casa/data/casatestdata/unittest/virtualconcat/part1.ms
31-Oct-2023 14:50:50 Copying  part3.ms
31-Oct-2023 14:50:50 /opt/casa/data/casatestdata/unittest/virtualconcat/part3.ms
31-Oct-2023 14:50:50 test_task_virtualconcat : Now checking output ...
31-Oct-2023 14:50:50 test_task_virtualconcat : OK. Checking tables in detail ...
31-Oct-2023 14:50:50 test_task_virtualconcat : comparing  ['SOURCE_ID', 0, 0, 0]
31-Oct-2023 14:50:50 test_task_virtualconcat : comparing  ['SPECTRAL_WINDOW_ID', 0, 0, 0]
31-Oct-2023 14:50:50 test_task_virtualconcat : table  SOURCE  as expected.
31-Oct-2023 14:50:50 test_task_virtualconcat : comparing  ['SOURCE_ID', 7, 0, 0]
31-Oct-2023 14:50:50 test_task_virtualconcat : comparing  ['SPECTRAL_WINDOW_ID', 7, 7, 0]
31-Oct-2023 14:50:50 test_task_virtualconcat : table  SOURCE  as expected.
31-Oct-2023 14:50:50 test_task_virtualconcat : comparing  ['SOURCE_ID', 8, 1, 0]
31-Oct-2023 14:50:50 test_task_virtualconcat : comparing  ['SPECTRAL_WINDOW_ID', 8, 0, 0]
31-Oct-2023 14:50:50 test_task_virtualconcat : table  SOURCE  as expected.
31-Oct-2023 14:50:50 test_task_virtualconcat : comparing  ['SOURCE_ID', 15, 1, 0]
31-Oct-2023 14:50:50 test_task_virtualconcat : comparing  ['SPECTRAL_WINDOW_ID', 15, 7, 0]
31-Oct-2023 14:50:50 test_task_virtualconcat : table  SOURCE  as expected.
31-Oct-2023 14:50:50 The following should fail: SOURCE row 16 should not exist
31-Oct-2023 14:50:50 test_task_virtualconcat : comparing  ['SOURCE_ID', 16, 0, 100000]
31-Oct-2023 14:50:50 Expected error.
31-Oct-2023 14:50:50 test_task_virtualconcat : comparing  ['NUM_CHAN', 8, 4, 0]
31-Oct-2023 14:50:50 test_task_virtualconcat : table  SPECTRAL_WINDOW  as expected.
31-Oct-2023 14:50:50 PASSED
31-Oct-2023 14:50:50
31-Oct-2023 14:50:51 test_task_virtualconcat.py::test_virtualconcat::test5 2023-10-31 18:50:51        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:51 2023-10-31 18:50:51        SEVERE        getcell::WEIGHT_SPECTRUM        Exception Reported: Table DataManager error: Invalid operation: TSM: no array in row 0 of column WEIGHT_SPECTRUM in /home/casatest/casa6/casatestutils/nosedir/test_task_virtualconcat/A2256LC2_4.5s-1.ms/table.f20
31-Oct-2023 14:50:51 2023-10-31 18:50:51        WARN        virtualconcat::::casa        Column WEIGHT_SPECTRUM in table A2256LC2_4.5s-1.ms has no data. Accessing it will cause errors.
31-Oct-2023 14:50:51 2023-10-31 18:50:51        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:51 2023-10-31 18:50:51        SEVERE        getcell::WEIGHT_SPECTRUM        Exception Reported: Table DataManager error: Invalid operation: TSM: no array in row 0 of column WEIGHT_SPECTRUM in /home/casatest/casa6/casatestutils/nosedir/test_task_virtualconcat/A2256LC2_4.5s-2.ms/table.f20
31-Oct-2023 14:50:51 2023-10-31 18:50:51        WARN        virtualconcat::::casa        Column WEIGHT_SPECTRUM in table A2256LC2_4.5s-2.ms has no data. Accessing it will cause errors.
31-Oct-2023 14:50:51
31-Oct-2023 14:50:51 Virtualconcat 5: two MSs with different state table (CAS-2601)
31-Oct-2023 14:50:51 Copying  shortpart2.ms
31-Oct-2023 14:50:51 /opt/casa/data/casatestdata/unittest/virtualconcat/shortpart2.ms
31-Oct-2023 14:50:51 Copying  shortpart4.ms
31-Oct-2023 14:50:51 /opt/casa/data/casatestdata/unittest/virtualconcat/shortpart4.ms
31-Oct-2023 14:50:51 Copying  shortpart1.ms
31-Oct-2023 14:50:51 /opt/casa/data/casatestdata/unittest/virtualconcat/shortpart1.ms
31-Oct-2023 14:50:51 Copying  shortpart3.ms
31-Oct-2023 14:50:51 /opt/casa/data/casatestdata/unittest/virtualconcat/shortpart3.ms
31-Oct-2023 14:50:51 Copying  shortpart5.ms
31-Oct-2023 14:50:51 /opt/casa/data/casatestdata/unittest/virtualconcat/shortpart5.ms
31-Oct-2023 14:50:51 test_task_virtualconcat : Now checking output ...
31-Oct-2023 14:50:51 test_task_virtualconcat : OK. Checking tables in detail ...
31-Oct-2023 14:50:51 test_task_virtualconcat : comparing  ['CAL', 0, 0, 0]
31-Oct-2023 14:50:51 test_task_virtualconcat : comparing  ['SIG', 0, 1, 0]
31-Oct-2023 14:50:51 test_task_virtualconcat : comparing  ['SUB_SCAN', 2, 1, 0]
31-Oct-2023 14:50:51 test_task_virtualconcat : table  STATE  as expected.
31-Oct-2023 14:50:51 PASSED
31-Oct-2023 14:50:51
31-Oct-2023 14:50:51 test_task_virtualconcat.py::test_virtualconcat::test6 2023-10-31 18:50:52        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:51 2023-10-31 18:50:52        SEVERE        getcell::WEIGHT_SPECTRUM        Exception Reported: Table DataManager error: Invalid operation: TSM: no array in row 0 of column WEIGHT_SPECTRUM in /home/casatest/casa6/casatestutils/nosedir/test_task_virtualconcat/A2256LC2_4.5s-1.ms/table.f20
31-Oct-2023 14:50:51 2023-10-31 18:50:52        WARN        virtualconcat::::casa        Column WEIGHT_SPECTRUM in table A2256LC2_4.5s-1.ms has no data. Accessing it will cause errors.
31-Oct-2023 14:50:51 2023-10-31 18:50:52        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:51 2023-10-31 18:50:52        SEVERE        getcell::WEIGHT_SPECTRUM        Exception Reported: Table DataManager error: Invalid operation: TSM: no array in row 0 of column WEIGHT_SPECTRUM in /home/casatest/casa6/casatestutils/nosedir/test_task_virtualconcat/A2256LC2_4.5s-2b.ms/table.f20
31-Oct-2023 14:50:51 2023-10-31 18:50:52        WARN        virtualconcat::::casa        Column WEIGHT_SPECTRUM in table A2256LC2_4.5s-2b.ms has no data. Accessing it will cause errors.
31-Oct-2023 14:50:52
31-Oct-2023 14:50:52 Virtualconcat 6: two MSs with different state table and feed table
31-Oct-2023 14:50:52 Copying  A2256LC2_4.5s-1.ms
31-Oct-2023 14:50:52 /opt/casa/data/casatestdata/unittest/virtualconcat/A2256LC2_4.5s-1.ms
31-Oct-2023 14:50:52 Copying  A2256LC2_4.5s-2.ms
31-Oct-2023 14:50:52 /opt/casa/data/casatestdata/unittest/virtualconcat/A2256LC2_4.5s-2.ms
31-Oct-2023 14:50:52 test_task_virtualconcat : Now checking output ...
31-Oct-2023 14:50:52 test_task_virtualconcat : OK. Checking tables in detail ...
31-Oct-2023 14:50:52 test_task_virtualconcat : comparing  ['SPECTRAL_WINDOW_ID', 53, 1, 0]
31-Oct-2023 14:50:52 test_task_virtualconcat : comparing  ['SPECTRAL_WINDOW_ID', 54, 2, 0]
31-Oct-2023 14:50:52 test_task_virtualconcat : comparing  ['SPECTRAL_WINDOW_ID', 107, 3, 0]
31-Oct-2023 14:50:52 test_task_virtualconcat : comparing  ['RECEPTOR_ANGLE', 54, [-1, 0], 0]
31-Oct-2023 14:50:52 test_task_virtualconcat : table  FEED  as expected.
31-Oct-2023 14:50:52 PASSED
31-Oct-2023 14:50:52
31-Oct-2023 14:50:52 test_task_virtualconcat.py::test_virtualconcat::test7 2023-10-31 18:50:53        WARN        MSConcat::virtualconcat (file /source/casa6/casatools/casacore/ms/MSOper/MSConcat.cc, line 149)        Zero or negative scan numbers in MS. May lead to duplicate scan numbers in concatenated MS.
31-Oct-2023 14:50:53
31-Oct-2023 14:50:53 Virtualconcat 7: two MSs with different antenna table such that baseline label reversal becomes necessary
31-Oct-2023 14:50:53 Copying  A2256LC2_4.5s-1.ms
31-Oct-2023 14:50:53 /opt/casa/data/casatestdata/unittest/virtualconcat/A2256LC2_4.5s-1.ms
31-Oct-2023 14:50:53 Copying  A2256LC2_4.5s-2b.ms
31-Oct-2023 14:50:53 /opt/casa/data/casatestdata/unittest/virtualconcat/A2256LC2_4.5s-2b.ms
31-Oct-2023 14:50:53 test_task_virtualconcat : Now checking output ...
31-Oct-2023 14:50:53 test_task_virtualconcat : OK. Checking tables in detail ...
31-Oct-2023 14:50:53 test_task_virtualconcat : OK. Checking baseline labels ...
31-Oct-2023 14:50:53 PASSED
31-Oct-2023 14:50:53
31-Oct-2023 14:50:53 test_task_virtualconcat.py::test_virtualconcat::test8 2023-10-31 18:50:53        WARN        MSConcat::concatenate (file /source/casa6/casatools/casacore/ms/MSOper/MSConcat.cc, line 997)        Zero or negative scan numbers in MS. May lead to duplicate scan numbers in concatenated MS.
31-Oct-2023 14:50:53 2023-10-31 18:50:54        WARN        MSConcat::virtualconcat (file /source/casa6/casatools/casacore/ms/MSOper/MSConcat.cc, line 149)        Zero or negative scan numbers in MS. May lead to duplicate scan numbers in concatenated MS.
31-Oct-2023 14:50:54
31-Oct-2023 14:50:54 Virtualconcat 8: two MSs with different antenna tables, copypointing=False
31-Oct-2023 14:50:54 Copying  sim7.ms
31-Oct-2023 14:50:54 /opt/casa/data/casatestdata/unittest/virtualconcat/sim7.ms
31-Oct-2023 14:50:54 Copying  sim8.ms
31-Oct-2023 14:50:54 /opt/casa/data/casatestdata/unittest/virtualconcat/sim8.ms
31-Oct-2023 14:50:54 test_task_virtualconcat : Now checking output ...
31-Oct-2023 14:50:54 test_task_virtualconcat : OK. Checking tables in detail ...
31-Oct-2023 14:50:54 test_task_virtualconcat : OK. Checking baseline labels ...
31-Oct-2023 14:50:54 test_task_virtualconcat : OK. Checking pointing table ...
31-Oct-2023 14:50:54 PASSED
31-Oct-2023 14:50:54
31-Oct-2023 14:50:54 test_task_virtualconcat.py::test_virtualconcat::test9 2023-10-31 18:50:55        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:54 2023-10-31 18:50:55        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:54 2023-10-31 18:50:55        SEVERE        getcell::TIME        Exception Reported: TableProxy::getCell: no such row
31-Oct-2023 14:50:54 2023-10-31 18:50:55        WARN        virtualconcat::::casa        The setup of the input MSs is not fully consistent. The concatenation may fail
31-Oct-2023 14:50:54 2023-10-31 18:50:55        WARN        virtualconcat::::casa        and/or the affected columns may contain partially only default data.
31-Oct-2023 14:50:54 2023-10-31 18:50:55        WARN        virtualconcat::::casa        {'part2-mod2-wscratch.ms': {'Main': {'present_a': True, 'present_b': True, 'missingcol_a': ['CORRECTED_DATA', 'MODEL_DATA'], 'missingcol_b': []}}}
31-Oct-2023 14:50:56
31-Oct-2023 14:50:56 Virtualconcat 9: 3 parts, different sources, same spws, different scratch columns: no, yes, no
31-Oct-2023 14:50:56 Copying  sim7.ms
31-Oct-2023 14:50:56 /opt/casa/data/casatestdata/unittest/virtualconcat/sim7.ms
31-Oct-2023 14:50:56 Copying  sim8.ms
31-Oct-2023 14:50:56 /opt/casa/data/casatestdata/unittest/virtualconcat/sim8.ms
31-Oct-2023 14:50:56 creating scratch columns in part2-mod2-wscratch.ms
31-Oct-2023 14:50:56 test_task_virtualconcat : Now checking output ...
31-Oct-2023 14:50:56 test_task_virtualconcat : OK. Checking tables in detail ...
31-Oct-2023 14:50:56 test_task_virtualconcat : comparing  ['SOURCE_ID', 28, 13, 0]
31-Oct-2023 14:50:56 test_task_virtualconcat : comparing  ['SPECTRAL_WINDOW_ID', 28, 1, 0]
31-Oct-2023 14:50:56 test_task_virtualconcat : table  SOURCE  as expected.
31-Oct-2023 14:50:56 test_task_virtualconcat : comparing  ['NUM_CHAN', 1, 128, 0]
31-Oct-2023 14:50:56 test_task_virtualconcat : table  SPECTRAL_WINDOW  as expected.
31-Oct-2023 14:50:56 PASSED
31-Oct-2023 14:50:56
31-Oct-2023 14:50:56 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_task_virtualconcat/nose.xml -
31-Oct-2023 14:50:56 ======================= 13 passed, 2 warnings in 23.74s ========================
31-Oct-2023 14:50:57 ============================= test session starts ==============================
31-Oct-2023 14:50:57 platform linux -- Python 3.8.7, pytest-7.4.3, pluggy-1.3.0 -- /home/casatest/venv/bin/python3.8
31-Oct-2023 14:50:57 cachedir: .pytest_cache
31-Oct-2023 14:50:57 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_task_imreframe
31-Oct-2023 14:50:57 configfile: pytest.ini
31-Oct-2023 14:50:58 collecting ... collected 6 items
31-Oct-2023 14:50:58
31-Oct-2023 14:50:58
31-Oct-2023 14:50:59 test_task_imreframe.py::imreframe_test::test_epoch 2023-10-31 18:50:59        WARN        imreframe::SpectralCoordinate::setFrequencySystem        Resetting the conversion frequency system GEO
31-Oct-2023 14:50:59 2023-10-31 18:50:59        WARN        imreframe::SpectralCoordinate::setFrequencySystem+        to the new native frequency system GEO
31-Oct-2023 14:50:59 2023-10-31 18:50:59        WARN        imreframe::SpectralCoordinate::setFrequencySystem+        You must explicitly reset the conversion frequency system if desired
31-Oct-2023 14:50:59 2023-10-31 18:50:59        WARN        imreframe::ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
31-Oct-2023 14:51:00 2023-10-31 18:51:00        WARN        imreframe::SpectralCoordinate::setFrequencySystem        Resetting the conversion frequency system TOPO
31-Oct-2023 14:51:00 2023-10-31 18:51:00        WARN        imreframe::SpectralCoordinate::setFrequencySystem+        to the new native frequency system TOPO
31-Oct-2023 14:51:00 2023-10-31 18:51:00        WARN        imreframe::SpectralCoordinate::setFrequencySystem+        You must explicitly reset the conversion frequency system if desired
31-Oct-2023 14:51:00 2023-10-31 18:51:00        WARN        imreframe::ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
31-Oct-2023 14:51:01
31-Oct-2023 14:51:01 test_epoch
31-Oct-2023 14:51:01 -------------
31-Oct-2023 14:51:01
31-Oct-2023 14:51:01 Test that the epoch paramter gives the epoch to be associated with the final image only works for outframe = geo and topo
31-Oct-2023 14:51:01 PASSED
31-Oct-2023 14:51:01
31-Oct-2023 14:51:01 test_task_imreframe.py::imreframe_test::test_makesfile
31-Oct-2023 14:51:01 test_makesfile
31-Oct-2023 14:51:01 ----------------
31-Oct-2023 14:51:01
31-Oct-2023 14:51:01 Test to check that the input image is accepted and an output is generated
31-Oct-2023 14:51:01 PASSED
31-Oct-2023 14:51:01
31-Oct-2023 14:51:01 test_task_imreframe.py::imreframe_test::test_modifyim
31-Oct-2023 14:51:01 test_modifyim
31-Oct-2023 14:51:01 ----------------
31-Oct-2023 14:51:01
31-Oct-2023 14:51:01 Test that when no outout is given the original file is modified instead
31-Oct-2023 14:51:01 PASSED
31-Oct-2023 14:51:01
31-Oct-2023 14:51:01 test_task_imreframe.py::imreframe_test::test_outframe
31-Oct-2023 14:51:01 test_outframe
31-Oct-2023 14:51:01 ---------------
31-Oct-2023 14:51:01
31-Oct-2023 14:51:01 Test that the new parameters are used and provide different images than the unmodified one
31-Oct-2023 14:51:01
31-Oct-2023 14:51:01 Check the log file for the change in spectral reference
31-Oct-2023 14:51:01 PASSED
31-Oct-2023 14:51:01
31-Oct-2023 14:51:02 test_task_imreframe.py::imreframe_test::test_outframe_lowercase
31-Oct-2023 14:51:02 test_outframe_lowercase
31-Oct-2023 14:51:02 ----------------------
31-Oct-2023 14:51:02
31-Oct-2023 14:51:02 Test that the new parameters are used and provide different images than the unmodified one
31-Oct-2023 14:51:02
31-Oct-2023 14:51:02 Check the log file for the change in spectral reference
31-Oct-2023 14:51:02 PASSED
31-Oct-2023 14:51:02
31-Oct-2023 14:51:02 test_task_imreframe.py::imreframe_test::test_restfreq
31-Oct-2023 14:51:02 test_restfreq
31-Oct-2023 14:51:02 ---------------
31-Oct-2023 14:51:02
31-Oct-2023 14:51:02 Test that the rest frequency sets the rest frequency to use for the velocity value
31-Oct-2023 14:51:02 PASSED
31-Oct-2023 14:51:02
31-Oct-2023 14:51:02 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_task_imreframe/nose.xml -
31-Oct-2023 14:51:02 ======================== 6 passed, 2 warnings in 4.80s =========================
31-Oct-2023 14:51:02 ============================= test session starts ==============================
31-Oct-2023 14:51:02 platform linux -- Python 3.8.7, pytest-7.4.3, pluggy-1.3.0 -- /home/casatest/venv/bin/python3.8
31-Oct-2023 14:51:02 cachedir: .pytest_cache
31-Oct-2023 14:51:02 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_task_imstat
31-Oct-2023 14:51:02 configfile: pytest.ini
31-Oct-2023 14:51:04 collecting ... collected 12 items
31-Oct-2023 14:51:04
31-Oct-2023 14:51:04
31-Oct-2023 14:51:04 test_task_imstat.py::imstat_test::test_CAS_2195_image_can_have_linear_rather_than_direction_coordinate
31-Oct-2023 14:51:04 verify fix for CAS-2195, image has linear, not direction, coordinate
31-Oct-2023 14:51:04 PASSED
31-Oct-2023 14:51:04
31-Oct-2023 14:51:04 test_task_imstat.py::imstat_test::test_biweight 2023-10-31 18:51:04        WARN        imstat::image::statistics (file src/tools/image/image_cmpt.cc, line 5995)        The biweight algorithm does not support computation of quantile-related (median, MADM, first/third quartile, IQR) statistics (robust=True). Proceeding without calculating those stats.
31-Oct-2023 14:51:15 ....10....20....30....40....50....60....70....80....90....100%
31-Oct-2023 14:51:15 2023-10-31 18:51:15        WARN        imstat::image::statistics (file src/tools/image/image_cmpt.cc, line 5995)        The biweight algorithm does not support computation of quantile-related (median, MADM, first/third quartile, IQR) statistics (robust=True). Proceeding without calculating those stats.
31-Oct-2023 14:51:24 ....10....20....30....40....50....60....70....80....90....100%
31-Oct-2023 14:51:24 2023-10-31 18:51:24        WARN        imstat::image::statistics (file src/tools/image/image_cmpt.cc, line 5995)        The biweight algorithm does not support computation of quantile-related (median, MADM, first/third quartile, IQR) statistics (robust=True). Proceeding without calculating those stats.
31-Oct-2023 14:51:30
31-Oct-2023 14:51:30 Test biweight algorithm CAS-11100
31-Oct-2023 14:51:30 PASSED
31-Oct-2023 14:51:30
31-Oct-2023 14:51:30 test_task_imstat.py::imstat_test::test_box_param_can_have_spaces
31-Oct-2023 14:51:30 test that box parameter can have spaces, CAS-2050 
31-Oct-2023 14:51:30 PASSED
31-Oct-2023 14:51:30
31-Oct-2023 14:51:31 test_task_imstat.py::imstat_test::test_chauvenet
31-Oct-2023 14:51:31 Test Chauvenet's criterion algorithm
31-Oct-2023 14:51:31 PASSED
31-Oct-2023 14:51:31
31-Oct-2023 14:51:31 test_task_imstat.py::imstat_test::test_fithalf
31-Oct-2023 14:51:31 Test fit to half algorithm
31-Oct-2023 14:51:31 PASSED
31-Oct-2023 14:51:31
31-Oct-2023 14:51:31 test_task_imstat.py::imstat_test::test_hingesfences
31-Oct-2023 14:51:31 Test hinges-fences algorithm
31-Oct-2023 14:51:31 PASSED
31-Oct-2023 14:51:31
31-Oct-2023 14:51:31 test_task_imstat.py::imstat_test::test_internal_region_exclusion 2023-10-31 18:51:32        WARN        imstat::::        Stokes cannot be determined because this region type is not handled yet. But chances are very good this is no need to be alarmed.
31-Oct-2023 14:51:32
31-Oct-2023 14:51:32 Verify data not returned for internally excluded regions
31-Oct-2023 14:51:32 PASSED
31-Oct-2023 14:51:32
31-Oct-2023 14:51:32 test_task_imstat.py::imstat_test::test_logfile_param
31-Oct-2023 14:51:32 test logfile 
31-Oct-2023 14:51:32 PASSED
31-Oct-2023 14:51:32
31-Oct-2023 14:51:32 test_task_imstat.py::imstat_test::test_moment_map_flux
31-Oct-2023 14:51:32 Test 1: verify moment maps can have flux densities computed in statistics
31-Oct-2023 14:51:32 PASSED
31-Oct-2023 14:51:32
31-Oct-2023 14:51:32 test_task_imstat.py::imstat_test::test_multiple_region_support
31-Oct-2023 14:51:32 test multiple region support
31-Oct-2023 14:51:32 PASSED
31-Oct-2023 14:51:32
31-Oct-2023 14:51:32 test_task_imstat.py::imstat_test::test_specifying_axes_param
31-Oct-2023 14:51:32 choose axes works
31-Oct-2023 14:51:32 PASSED
31-Oct-2023 14:51:32
31-Oct-2023 14:51:32 test_task_imstat.py::imstat_test::test_stretch 2023-10-31 18:51:33        SEVERE        imstat::image::statistics (file src/tools/image/image_cmpt.cc, line 5995)        Exception Reported: The input image shape ([200, 200, 1, 20]) and mask shape ([200, 200, 1, 1]) are different, and it was specified that the mask should not be extended, so the mask cannot be applied to the (sub)image. Specifying that the mask should be extended may resolve the issue at File: /source/casainstall/include/casacpp/imageanalysis/ImageAnalysis/SubImageFactory.tcc, line: 306
31-Oct-2023 14:51:32 2023-10-31 18:51:33        SEVERE        imstat::::casa        Task imstat raised an exception of class RuntimeError with the following message: The input image shape ([200, 200, 1, 20]) and mask shape ([200, 200, 1, 1]) are different, and it was specified that the mask should not be extended, so the mask cannot be applied to the (sub)image. Specifying that the mask should be extended may resolve the issue at File: /source/casainstall/include/casacpp/imageanalysis/ImageAnalysis/SubImageFactory.tcc, line: 306
31-Oct-2023 14:51:32
31-Oct-2023 14:51:32 imstat: Test stretch parameter
31-Oct-2023 14:51:32 PASSED
31-Oct-2023 14:51:32
31-Oct-2023 14:51:32 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_task_imstat/nose.xml -
31-Oct-2023 14:51:32 ======================= 12 passed, 2 warnings in 29.98s ========================
31-Oct-2023 14:51:33 ============================= test session starts ==============================
31-Oct-2023 14:51:33 platform linux -- Python 3.8.7, pytest-7.4.3, pluggy-1.3.0 -- /home/casatest/venv/bin/python3.8
31-Oct-2023 14:51:33 cachedir: .pytest_cache
31-Oct-2023 14:51:33 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_task_imtrans
31-Oct-2023 14:51:33 configfile: pytest.ini
31-Oct-2023 14:51:34 collecting ... collected 6 items
31-Oct-2023 14:51:34
31-Oct-2023 14:51:34
31-Oct-2023 14:51:34 test_task_imtrans.py::imtrans_test::test_cas_2364
31-Oct-2023 14:51:34 test CAS-2364 fix
31-Oct-2023 14:51:34 PASSED
31-Oct-2023 14:51:34
31-Oct-2023 14:51:34 test_task_imtrans.py::imtrans_test::test_exceptions 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image has 3 axes but 2 were given for reordering. Number of axes to reorder must match the number of image axes
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image has 3 axes but 2 were given for reordering. Number of axes to reorder must match the number of image axes
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::::casa        Task imtrans raised an exception of class RuntimeError with the following message: 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image has 3 axes but 2 were given for reordering. Number of axes to reorder must match the number of image axes
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Axis number 0 specified multiple times in order parameter 10 . It can only be specified once.
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Axis number 0 specified multiple times in order parameter 10 . It can only be specified once.
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::::casa        Task imtrans raised an exception of class RuntimeError with the following message: 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Axis number 0 specified multiple times in order parameter 10 . It can only be specified once.
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::CoordinateSystem::getWorldAxesOrder (file /source/casa6/casatools/casacore/coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Image has 3 axes but 2 were given. Number of given axes must match the number of image axes
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::CoordinateSystem::getWorldAxesOrder (file /source/casa6/casatools/casacore/coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Image has 3 axes but 2 were given. Number of given axes must match the number of image axes
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::::casa        Task imtrans raised an exception of class RuntimeError with the following message: 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::CoordinateSystem::getWorldAxesOrder (file /source/casa6/casatools/casacore/coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Image has 3 axes but 2 were given. Number of given axes must match the number of image axes
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 123 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 123 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::::casa        Task imtrans raised an exception of class RuntimeError with the following message: 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 123 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image has 3 axes but 5 were given for reordering. Number of axes to reorder must match the number of image axes
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image has 3 axes but 5 were given for reordering. Number of axes to reorder must match the number of image axes
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::::casa        Task imtrans raised an exception of class RuntimeError with the following message: 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image has 3 axes but 5 were given for reordering. Number of axes to reorder must match the number of image axes
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::CoordinateSystem::getWorldAxesOrder (file /source/casa6/casatools/casacore/coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Image has 3 axes but 4 were given. Number of given axes must match the number of image axes
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::CoordinateSystem::getWorldAxesOrder (file /source/casa6/casatools/casacore/coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Image has 3 axes but 4 were given. Number of given axes must match the number of image axes
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::::casa        Task imtrans raised an exception of class RuntimeError with the following message: 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::CoordinateSystem::getWorldAxesOrder (file /source/casa6/casatools/casacore/coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Image has 3 axes but 4 were given. Number of given axes must match the number of image axes
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 123 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 123 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::::casa        Task imtrans raised an exception of class RuntimeError with the following message: 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 123 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::CoordinateSystem::getWorldAxesOrder (file /source/casa6/casatools/casacore/coordinates/Coordinates/CoordinateSystem.cc, line 4665)        No axis matches requested axis s. Image axis names are [right ascension, declination, frequency]
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::CoordinateSystem::getWorldAxesOrder (file /source/casa6/casatools/casacore/coordinates/Coordinates/CoordinateSystem.cc, line 4665)        No axis matches requested axis s. Image axis names are [right ascension, declination, frequency]
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::::casa        Task imtrans raised an exception of class RuntimeError with the following message: 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::CoordinateSystem::getWorldAxesOrder (file /source/casa6/casatools/casacore/coordinates/Coordinates/CoordinateSystem.cc, line 4665)        No axis matches requested axis s. Image axis names are [right ascension, declination, frequency]
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::CoordinateSystem::getWorldAxesOrder (file /source/casa6/casatools/casacore/coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Ambiguous axis specification. Both r and r match image axis name right ascension
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::CoordinateSystem::getWorldAxesOrder (file /source/casa6/casatools/casacore/coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Ambiguous axis specification. Both r and r match image axis name right ascension
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::::casa        Task imtrans raised an exception of class RuntimeError with the following message: 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::CoordinateSystem::getWorldAxesOrder (file /source/casa6/casatools/casacore/coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Ambiguous axis specification. Both r and r match image axis name right ascension
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 103 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 103 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
31-Oct-2023 14:51:34 2023-10-31 18:51:35        SEVERE        imtrans::::casa        Task imtrans raised an exception of class RuntimeError with the following message: 2023-10-31 18:51:35        SEVERE        imtrans::image::transpose (file src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2023-10-31 18:51:35        SEVERE        imtrans::ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 103 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
31-Oct-2023 14:51:34
31-Oct-2023 14:51:34 imtrans: Test various exception cases
31-Oct-2023 14:51:34 PASSED
31-Oct-2023 14:51:34
31-Oct-2023 14:51:35 test_task_imtrans.py::imtrans_test::test_history 2023-10-31 18:51:35        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-Oct-2023 14:51:35
31-Oct-2023 14:51:35 Test history records are written
31-Oct-2023 14:51:35 PASSED
31-Oct-2023 14:51:35
31-Oct-2023 14:51:35 test_task_imtrans.py::imtrans_test::test_imageinfo 2023-10-31 18:51:35        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-Oct-2023 14:51:35
31-Oct-2023 14:51:35 Verify image info is copied
31-Oct-2023 14:51:35 PASSED
31-Oct-2023 14:51:35
31-Oct-2023 14:51:35 test_task_imtrans.py::imtrans_test::test_straight_copy
31-Oct-2023 14:51:35 No actual transposing
31-Oct-2023 14:51:35 PASSED
31-Oct-2023 14:51:35
31-Oct-2023 14:51:35 test_task_imtrans.py::imtrans_test::test_transpose
31-Oct-2023 14:51:35 Test transposing
31-Oct-2023 14:51:35 PASSED
31-Oct-2023 14:51:35
31-Oct-2023 14:51:35 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_task_imtrans/nose.xml -
31-Oct-2023 14:51:35 ======================== 6 passed, 2 warnings in 2.47s =========================
31-Oct-2023 14:51:36 ============================= test session starts ==============================
31-Oct-2023 14:51:36 platform linux -- Python 3.8.7, pytest-7.4.3, pluggy-1.3.0 -- /home/casatest/venv/bin/python3.8
31-Oct-2023 14:51:36 cachedir: .pytest_cache
31-Oct-2023 14:51:36 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_task_deconvolve
31-Oct-2023 14:51:36 configfile: pytest.ini
31-Oct-2023 14:51:37 collecting ... 2023-10-31 18:51:38        WARN        ::casa        casampi not available - not testing MPIEnvironment stuff
31-Oct-2023 14:51:37 collected 142 items
31-Oct-2023 14:51:37
31-Oct-2023 14:51:37
31-Oct-2023 14:51:37 test_task_deconvolve.py::test_onefield::test_onefield_asp
31-Oct-2023 14:51:37 [onefield] test_onefield_asp 
31-Oct-2023 14:51:37 SKIPPED (ASP deconvolver currently does not follow the same logic fo...)
31-Oct-2023 14:51:37
31-Oct-2023 14:51:41 test_task_deconvolve.py::test_onefield::test_onefield_clark
31-Oct-2023 14:51:41 [onefield] test_onefield_clark 
31-Oct-2023 14:51:41 /opt/casa/data/casatestdata/unittest/deconvolve/
31-Oct-2023 14:51:41 refim_twochan
31-Oct-2023 14:51:41 PASSED
31-Oct-2023 14:51:41
31-Oct-2023 14:51:44 test_task_deconvolve.py::test_onefield::test_onefield_hogbom
31-Oct-2023 14:51:44 [onefield] test_onefield_hogbom 
31-Oct-2023 14:51:44 /opt/casa/data/casatestdata/unittest/deconvolve/
31-Oct-2023 14:51:44 refim_twochan
31-Oct-2023 14:51:44 PASSED
31-Oct-2023 14:51:44
31-Oct-2023 14:51:49 test_task_deconvolve.py::test_onefield::test_onefield_mtmfs
31-Oct-2023 14:51:49 [onefield] test_onefield_mtmfs 
31-Oct-2023 14:51:49 /opt/casa/data/casatestdata/unittest/deconvolve/
31-Oct-2023 14:51:49 refim_twochan
31-Oct-2023 14:51:49 PASSED
31-Oct-2023 14:51:49
31-Oct-2023 14:51:53 test_task_deconvolve.py::test_onefield::test_onefield_multiscale
31-Oct-2023 14:51:53 [onefield] test_onefield_multiscale 
31-Oct-2023 14:51:53 /opt/casa/data/casatestdata/unittest/deconvolve/
31-Oct-2023 14:51:53 refim_eptwochan
31-Oct-2023 14:51:53 PASSED
31-Oct-2023 14:51:53
31-Oct-2023 14:51:59 test_task_deconvolve.py::test_onefield::test_onefield_rectangular_pixels
31-Oct-2023 14:51:59 [onefield] test_onefield_rectangular_pixels 
31-Oct-2023 14:51:59 /opt/casa/data/casatestdata/unittest/deconvolve/
31-Oct-2023 14:51:59 refim_point
31-Oct-2023 14:51:59 PASSED
31-Oct-2023 14:51:59
31-Oct-2023 14:52:03 test_task_deconvolve.py::test_iterbot::test_iterbot_cube_fullsummary_false 2023-10-31 18:52:03        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2465)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
31-Oct-2023 14:52:10
31-Oct-2023 14:52:10 [iterbot] test_iterbot_cube_fullsummary_false 
31-Oct-2023 14:52:10 /opt/casa/data/casatestdata/unittest/deconvolve/
31-Oct-2023 14:52:10 refim_point
31-Oct-2023 14:52:10 [check_stopcode] Stopcode is 2 (Pass : should be 2)
31-Oct-2023 14:52:10
31-Oct-2023 14:52:10 PASSED
31-Oct-2023 14:52:10
31-Oct-2023 14:52:10 test_task_deconvolve.py::test_iterbot::test_iterbot_cube_fullsummary_true 2023-10-31 18:52:11        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2465)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
31-Oct-2023 14:52:17
31-Oct-2023 14:52:17 [iterbot] test_iterbot_cube_fullsummary_true 
31-Oct-2023 14:52:17 /opt/casa/data/casatestdata/unittest/deconvolve/
31-Oct-2023 14:52:17 refim_point
31-Oct-2023 14:52:17 [check_stopcode] Stopcode is 2 (Pass : should be 2)
31-Oct-2023 14:52:17
31-Oct-2023 14:52:17 PASSED
31-Oct-2023 14:52:17
31-Oct-2023 14:52:18 test_task_deconvolve.py::test_iterbot::test_iterbot_mfs_4
31-Oct-2023 14:52:18 [iterbot] test_iterbot_mfs_4 
31-Oct-2023 14:52:18 [check_stopcode] Stopcode is 1 (Pass : should be 1)
31-Oct-2023 14:52:18
31-Oct-2023 14:52:18 PASSED
31-Oct-2023 14:52:18
31-Oct-2023 14:52:18 test_task_deconvolve.py::test_iterbot::test_iterbot_mfs_5
31-Oct-2023 14:52:18 [iterbot] test_iterbot_mfs_5 
31-Oct-2023 14:52:18 [check_stopcode] Stopcode is 2 (Pass : should be 2)
31-Oct-2023 14:52:18
31-Oct-2023 14:52:18 PASSED
31-Oct-2023 14:52:18
31-Oct-2023 14:52:22 test_task_deconvolve.py::test_iterbot::test_iterbot_mfs_fullsummary_false
31-Oct-2023 14:52:22 [iterbot] test_iterbot_mfs_fullsummary_false 
31-Oct-2023 14:52:22 /opt/casa/data/casatestdata/unittest/deconvolve/
31-Oct-2023 14:52:22 refim_twochan
31-Oct-2023 14:52:22 [check_stopcode] Stopcode is 2 (Pass : should be 2)
31-Oct-2023 14:52:22
31-Oct-2023 14:52:22 PASSED
31-Oct-2023 14:52:22
31-Oct-2023 14:52:23
Request to stop 'CASA-CMDT86-TTASKGML2014P38-2' received from vsuorant
31-Oct-2023 14:52:23 Failing task since return code of [/export/home/cbt-el7-6/bamboohome/temp/CASA-CMDT86-TTASKGML2014P38-2-ScriptBuildTask-1919949466644516111.sh] was 143 while expected 0
31-Oct-2023 14:52:23 Finished task 'Test wheel' with result: Failed
31-Oct-2023 14:52:23 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
31-Oct-2023 14:52:23
Beginning to execute external process for build 'CASA - CMake Dev Test - CAS-14234 - Test Tasks Many Linux 2014 #2 (CASA-CMDT86-TTASKGML2014P38-2)'
... running command line:
/export/home/cbt-el7-6/bamboohome/temp/CASA-CMDT86-TTASKGML2014P38-2-ScriptBuildTask-993044259745187844.sh
... in: /export/home/cbt-el7-6/bamboohome/xml-data/build-dir/CASA-CMDT86-TTASKGML2014P38
31-Oct-2023 14:52:23 Starting cleanup
31-Oct-2023 14:52:23 Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.
31-Oct-2023 14:52:23 Attempting to generate stack trace and terminate spawned sub-processes of process id: 10469
31-Oct-2023 14:52:23 Found related process: pid: 12795 ppid: 10469 pgid: 1843 %cpu: 0.0 %mem: 0.0 cmd: /bin/sh
31-Oct-2023 14:52:23 Found related process: pid: 12801 ppid: 12795 pgid: 1843 %cpu: 0.0 %mem: 0.0 cmd: docker
31-Oct-2023 14:52:23 Found related process: pid: 1029 ppid: 1 pgid: 1843 %cpu: 0.0 %mem: 0.0 cmd: docker
31-Oct-2023 14:52:23 getStackTraceAndKillRelatedProcesses for 3 processes
31-Oct-2023 14:52:23 Executing kill -3 1029
31-Oct-2023 14:52:23 Executing kill -3 12801
31-Oct-2023 14:52:23 Executing kill -3 12795
31-Oct-2023 14:52:23 SIGQUIT: quit
31-Oct-2023 14:52:23 PC=0x558d896665e1 m=0 sigcode=0
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 goroutine 0 [idle]:
31-Oct-2023 14:52:23 runtime.futex()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/sys_linux_amd64.s:554 +0x21 fp=0x7fff6aca2138 sp=0x7fff6aca2130 pc=0x558d896665e1
31-Oct-2023 14:52:23 runtime.futexsleep(0x558d896661d3?, 0x6aca21b8?, 0x558d8963f9ac?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/os_linux.go:69 +0x36 fp=0x7fff6aca2188 sp=0x7fff6aca2138 pc=0x558d8962c996
31-Oct-2023 14:52:23 runtime.notesleep(0x558d8ae84e88)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/lock_futex.go:160 +0x87 fp=0x7fff6aca21c0 sp=0x7fff6aca2188 pc=0x558d89604ae7
31-Oct-2023 14:52:23 runtime.mPark(...)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:1531
31-Oct-2023 14:52:23 runtime.stopm()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:2335 +0x8c fp=0x7fff6aca21f0 sp=0x7fff6aca21c0 pc=0x558d8963740c
31-Oct-2023 14:52:23 runtime.findRunnable()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:3025 +0xac9 fp=0x7fff6aca22f8 sp=0x7fff6aca21f0 pc=0x558d89638cc9
31-Oct-2023 14:52:23 runtime.schedule()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:3378 +0xb1 fp=0x7fff6aca2330 sp=0x7fff6aca22f8 pc=0x558d89639af1
31-Oct-2023 14:52:23 runtime.park_m(0xc000007040?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:3529 +0x12d fp=0x7fff6aca2360 sp=0x7fff6aca2330 pc=0x558d8963a00d
31-Oct-2023 14:52:23 runtime.mcall()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/asm_amd64.s:452 +0x45 fp=0x7fff6aca2370 sp=0x7fff6aca2360 pc=0x558d89662425
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 goroutine 1 [chan receive]:
31-Oct-2023 14:52:23 runtime.gopark(0x558d8963bab1?, 0xc00061fa60?, 0xe0?, 0xba?, 0xc000148140?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc00061fa50 sp=0xc00061fa30 pc=0x558d89633516
31-Oct-2023 14:52:23 runtime.chanrecv(0xc00004e780, 0xc00061fbe0, 0x1)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/chan.go:583 +0x49d fp=0xc00061fae0 sp=0xc00061fa50 pc=0x558d895ff6dd
31-Oct-2023 14:52:23 runtime.chanrecv1(0x558d8a6c15b0?, 0xc00004a0c8?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/chan.go:442 +0x18 fp=0xc00061fb08 sp=0xc00061fae0 pc=0x558d895ff1d8
31-Oct-2023 14:52:23 github.com/docker/cli/cli/command/container.runStop({0x558d8a6ca8f8, 0xc0003981e0}, 0xc00013a800)
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/cli/command/container/stop.go:63 +0x1b2 fp=0xc00061fc00 sp=0xc00061fb08 pc=0x558d89d1ef52
31-Oct-2023 14:52:23 github.com/docker/cli/cli/command/container.NewStopCommand.func1(0xc00016fb00?, {0xc000112f20?, 0x1?, 0x1?})
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/cli/command/container/stop.go:35 +0x85 fp=0xc00061fc40 sp=0xc00061fc00 pc=0x558d89d1ed45
31-Oct-2023 14:52:23 github.com/docker/cli/vendor/github.com/spf13/cobra.(*Command).execute(0xc00016fb00, {0xc000135650, 0x1, 0x1})
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/vendor/github.com/spf13/cobra/command.go:940 +0x862 fp=0xc00061fd78 sp=0xc00061fc40 pc=0x558d89bf9142
31-Oct-2023 14:52:23 github.com/docker/cli/vendor/github.com/spf13/cobra.(*Command).ExecuteC(0xc000004300)
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/vendor/github.com/spf13/cobra/command.go:1068 +0x3bd fp=0xc00061fe30 sp=0xc00061fd78 pc=0x558d89bf99bd
31-Oct-2023 14:52:23 github.com/docker/cli/vendor/github.com/spf13/cobra.(*Command).Execute(...)
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/vendor/github.com/spf13/cobra/command.go:992
31-Oct-2023 14:52:23 main.runDocker(0x0?)
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/cmd/docker/docker.go:263 +0x4b7 fp=0xc00061ff08 sp=0xc00061fe30 pc=0x558d8a090477
31-Oct-2023 14:52:23 main.main()
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/cmd/docker/docker.go:274 +0x97 fp=0xc00061ff80 sp=0xc00061ff08 pc=0x558d8a090597
31-Oct-2023 14:52:23 runtime.main()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:250 +0x212 fp=0xc00061ffe0 sp=0xc00061ff80 pc=0x558d896330d2
31-Oct-2023 14:52:23 runtime.goexit()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc00061ffe8 sp=0xc00061ffe0 pc=0x558d896646e1
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 goroutine 2 [force gc (idle)]:
31-Oct-2023 14:52:23 runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc00006efb0 sp=0xc00006ef90 pc=0x558d89633516
31-Oct-2023 14:52:23 runtime.goparkunlock(...)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:387
31-Oct-2023 14:52:23 runtime.forcegchelper()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:305 +0xb0 fp=0xc00006efe0 sp=0xc00006efb0 pc=0x558d89633350
31-Oct-2023 14:52:23 runtime.goexit()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc00006efe8 sp=0xc00006efe0 pc=0x558d896646e1
31-Oct-2023 14:52:23 created by runtime.init.6
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:293 +0x25
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 goroutine 3 [GC sweep wait]:
31-Oct-2023 14:52:23 runtime.gopark(0x1?, 0x0?, 0x0?, 0x0?, 0x0?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc00006f780 sp=0xc00006f760 pc=0x558d89633516
31-Oct-2023 14:52:23 runtime.goparkunlock(...)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:387
31-Oct-2023 14:52:23 runtime.bgsweep(0x0?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgcsweep.go:319 +0xde fp=0xc00006f7c8 sp=0xc00006f780 pc=0x558d8961e59e
31-Oct-2023 14:52:23 runtime.gcenable.func1()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgc.go:178 +0x26 fp=0xc00006f7e0 sp=0xc00006f7c8 pc=0x558d89613806
31-Oct-2023 14:52:23 runtime.goexit()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc00006f7e8 sp=0xc00006f7e0 pc=0x558d896646e1
31-Oct-2023 14:52:23 created by runtime.gcenable
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgc.go:178 +0x6b
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 goroutine 4 [GC scavenge wait]:
31-Oct-2023 14:52:23 runtime.gopark(0xc0000503f0?, 0x558d8a253f90?, 0x0?, 0x0?, 0x0?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc00006ff70 sp=0xc00006ff50 pc=0x558d89633516
31-Oct-2023 14:52:23 runtime.goparkunlock(...)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:387
31-Oct-2023 14:52:23 runtime.(*scavengerState).park(0x558d8ae843a0)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgcscavenge.go:400 +0x53 fp=0xc00006ffa0 sp=0xc00006ff70 pc=0x558d8961c473
31-Oct-2023 14:52:23 runtime.bgscavenge(0x0?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgcscavenge.go:633 +0x65 fp=0xc00006ffc8 sp=0xc00006ffa0 pc=0x558d8961ca65
31-Oct-2023 14:52:23 runtime.gcenable.func2()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgc.go:179 +0x26 fp=0xc00006ffe0 sp=0xc00006ffc8 pc=0x558d896137a6
31-Oct-2023 14:52:23 runtime.goexit()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc00006ffe8 sp=0xc00006ffe0 pc=0x558d896646e1
31-Oct-2023 14:52:23 created by runtime.gcenable
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgc.go:179 +0xaa
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 goroutine 5 [finalizer wait]:
31-Oct-2023 14:52:23 runtime.gopark(0x1a0?, 0x558d8ae84d40?, 0x60?, 0x78?, 0xc00006e770?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc00006e628 sp=0xc00006e608 pc=0x558d89633516
31-Oct-2023 14:52:23 runtime.runfinq()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mfinal.go:193 +0x107 fp=0xc00006e7e0 sp=0xc00006e628 pc=0x558d89612827
31-Oct-2023 14:52:23 runtime.goexit()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc00006e7e8 sp=0xc00006e7e0 pc=0x558d896646e1
31-Oct-2023 14:52:23 created by runtime.createfing
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mfinal.go:163 +0x45
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 goroutine 8 [GC worker (idle)]:
31-Oct-2023 14:52:23 runtime.gopark(0x558d89b0cbf5?, 0x558d895fe47d?, 0xc0?, 0x83?, 0xc0000707a8?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc000070750 sp=0xc000070730 pc=0x558d89633516
31-Oct-2023 14:52:23 runtime.gcBgMarkWorker()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgc.go:1275 +0xf1 fp=0xc0000707e0 sp=0xc000070750 pc=0x558d89615571
31-Oct-2023 14:52:23 runtime.goexit()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc0000707e8 sp=0xc0000707e0 pc=0x558d896646e1
31-Oct-2023 14:52:23 created by runtime.gcBgMarkStartWorkers
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgc.go:1199 +0x25
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 goroutine 9 [GC worker (idle)]:
31-Oct-2023 14:52:23 runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc000070f50 sp=0xc000070f30 pc=0x558d89633516
31-Oct-2023 14:52:23 runtime.gcBgMarkWorker()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgc.go:1275 +0xf1 fp=0xc000070fe0 sp=0xc000070f50 pc=0x558d89615571
31-Oct-2023 14:52:23 runtime.goexit()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc000070fe8 sp=0xc000070fe0 pc=0x558d896646e1
31-Oct-2023 14:52:23 created by runtime.gcBgMarkStartWorkers
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgc.go:1199 +0x25
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 goroutine 10 [GC worker (idle)]:
31-Oct-2023 14:52:23 runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc000071750 sp=0xc000071730 pc=0x558d89633516
31-Oct-2023 14:52:23 runtime.gcBgMarkWorker()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgc.go:1275 +0xf1 fp=0xc0000717e0 sp=0xc000071750 pc=0x558d89615571
31-Oct-2023 14:52:23 runtime.goexit()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc0000717e8 sp=0xc0000717e0 pc=0x558d896646e1
31-Oct-2023 14:52:23 created by runtime.gcBgMarkStartWorkers
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgc.go:1199 +0x25
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 goroutine 11 [GC worker (idle)]:
31-Oct-2023 14:52:23 runtime.gopark(0xb68542059b1d8?, 0x0?, 0x0?, 0x0?, 0x0?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc000071f50 sp=0xc000071f30 pc=0x558d89633516
31-Oct-2023 14:52:23 runtime.gcBgMarkWorker()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgc.go:1275 +0xf1 fp=0xc000071fe0 sp=0xc000071f50 pc=0x558d89615571
31-Oct-2023 14:52:23 runtime.goexit()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc000071fe8 sp=0xc000071fe0 pc=0x558d896646e1
31-Oct-2023 14:52:23 created by runtime.gcBgMarkStartWorkers
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/mgc.go:1199 +0x25
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 goroutine 12 [chan receive]:
31-Oct-2023 14:52:23 runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc00006a6b0 sp=0xc00006a690 pc=0x558d89633516
31-Oct-2023 14:52:23 runtime.chanrecv(0xc00010ec00, 0xc00006a7c0, 0x1)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/chan.go:583 +0x49d fp=0xc00006a740 sp=0xc00006a6b0 pc=0x558d895ff6dd
31-Oct-2023 14:52:23 runtime.chanrecv1(0x558d8a550260?, 0xc000041890?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/chan.go:442 +0x18 fp=0xc00006a768 sp=0xc00006a740 pc=0x558d895ff1d8
31-Oct-2023 14:52:23 github.com/docker/cli/cli/command/container.parallelOperation.func1()
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/cli/command/container/utils.go:147 +0x6b fp=0xc00006a7e0 sp=0xc00006a768 pc=0x558d89d231ab
31-Oct-2023 14:52:23 runtime.goexit()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc00006a7e8 sp=0xc00006a7e0 pc=0x558d896646e1
31-Oct-2023 14:52:23 created by github.com/docker/cli/cli/command/container.parallelOperation
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/cli/command/container/utils.go:145 +0x1d9
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 goroutine 14 [select]:
31-Oct-2023 14:52:23 runtime.gopark(0xc0005932b8?, 0x6?, 0x78?, 0x2f?, 0xc0005930cc?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc000592f30 sp=0xc000592f10 pc=0x558d89633516
31-Oct-2023 14:52:23 runtime.selectgo(0xc0005932b8, 0xc0005930c0, 0xc000113120?, 0x0, 0xc0005930a8?, 0x1)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/select.go:327 +0x7be fp=0xc000593070 sp=0xc000592f30 pc=0x558d896431fe
31-Oct-2023 14:52:23 net/http.(*persistConn).roundTrip(0xc0001766c0, 0xc00013aac0)
31-Oct-2023 14:52:23         /usr/local/go/src/net/http/transport.go:2638 +0x994 fp=0xc000593328 sp=0xc000593070 pc=0x558d89914134
31-Oct-2023 14:52:23 net/http.(*Transport).roundTrip(0xc0004e63c0, 0xc000170c00)
31-Oct-2023 14:52:23         /usr/local/go/src/net/http/transport.go:603 +0x7fa fp=0xc000593550 sp=0xc000593328 pc=0x558d8990805a
31-Oct-2023 14:52:23 net/http.(*Transport).RoundTrip(0x558d8a6583e0?, 0x558d8a6b5d00?)
31-Oct-2023 14:52:23         /usr/local/go/src/net/http/roundtrip.go:17 +0x19 fp=0xc000593570 sp=0xc000593550 pc=0x558d898fc2f9
31-Oct-2023 14:52:23 net/http.send(0xc000170c00, {0x558d8a6b5d00, 0xc0004e63c0}, {0x8?, 0x558d8a68fc40?, 0x0?})
31-Oct-2023 14:52:23         /usr/local/go/src/net/http/client.go:252 +0x5f7 fp=0xc000593768 sp=0xc000593570 pc=0x558d898d1f17
31-Oct-2023 14:52:23 net/http.(*Client).send(0xc000041980, 0xc000170c00, {0xc000593818?, 0x558d89648072?, 0x0?})
31-Oct-2023 14:52:23         /usr/local/go/src/net/http/client.go:176 +0x9b fp=0xc0005937e0 sp=0xc000593768 pc=0x558d898d179b
31-Oct-2023 14:52:23 net/http.(*Client).do(0xc000041980, 0xc000170c00)
31-Oct-2023 14:52:23         /usr/local/go/src/net/http/client.go:716 +0x8fb fp=0xc0005939e8 sp=0xc0005937e0 pc=0x558d898d3a9b
31-Oct-2023 14:52:23 net/http.(*Client).Do(...)
31-Oct-2023 14:52:23         /usr/local/go/src/net/http/client.go:582
31-Oct-2023 14:52:23 github.com/docker/cli/vendor/github.com/docker/docker/client.(*Client).doRequest(0xc00013e380, {0x558d8a6c15b0, 0xc00004a0c8}, 0xc000170b00)
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/vendor/github.com/docker/docker/client/request.go:135 +0x16a fp=0xc000593ad8 sp=0xc0005939e8 pc=0x558d899f796a
31-Oct-2023 14:52:23 github.com/docker/cli/vendor/github.com/docker/docker/client.(*Client).sendRequest(0x5?, {0x558d8a6c15b0, 0xc00004a0c8}, {0x558d8a09650f, 0x4}, {0xc000586120?, 0xc000586140?}, 0xc000593c68?, {0x0, 0x0}, ...)
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/vendor/github.com/docker/docker/client/request.go:119 +0xd3 fp=0xc000593bc0 sp=0xc000593ad8 pc=0x558d899f7533
31-Oct-2023 14:52:23 github.com/docker/cli/vendor/github.com/docker/docker/client.(*Client).post(0xc000041a40?, {0x558d8a6c15b0, 0xc00004a0c8}, {0xc000586120, 0x25}, 0x558d8a096ce5?, {0x0?, 0x0?}, 0x0?)
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/vendor/github.com/docker/docker/client/request.go:45 +0x118 fp=0xc000593c88 sp=0xc000593bc0 pc=0x558d899f6cb8
31-Oct-2023 14:52:23 github.com/docker/cli/vendor/github.com/docker/docker/client.(*Client).ContainerStop(0xc00013e380, {0x558d8a6c15b0, 0xc00004a0c8}, {0x7fff6aca33e1, 0x14}, {{0x0?, 0x0?}, 0x0?})
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/vendor/github.com/docker/docker/client/container_stop.go:27 +0x2dc fp=0xc000593f10 sp=0xc000593c88 pc=0x558d899e205c
31-Oct-2023 14:52:23 github.com/docker/cli/cli/command/container.runStop.func1({0x558d8a6c15b0, 0xc00004a0c8}, {0x7fff6aca33e1, 0x14})
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/cli/command/container/stop.go:56 +0x83 fp=0xc000593f70 sp=0xc000593f10 pc=0x558d89d1f223
31-Oct-2023 14:52:23 github.com/docker/cli/cli/command/container.parallelOperation.func2.1({0x7fff6aca33e1, 0x14})
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/cli/command/container/utils.go:156 +0x55 fp=0xc000593fc0 sp=0xc000593f70 pc=0x558d89d230d5
31-Oct-2023 14:52:23 github.com/docker/cli/cli/command/container.parallelOperation.func2.2()
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/cli/command/container/utils.go:158 +0x2e fp=0xc000593fe0 sp=0xc000593fc0 pc=0x558d89d2304e
31-Oct-2023 14:52:23 runtime.goexit()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc000593fe8 sp=0xc000593fe0 pc=0x558d896646e1
31-Oct-2023 14:52:23 created by github.com/docker/cli/cli/command/container.parallelOperation.func2
31-Oct-2023 14:52:23         /go/src/github.com/docker/cli/cli/command/container/utils.go:155 +0x5b
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 goroutine 16 [IO wait]:
31-Oct-2023 14:52:23 runtime.gopark(0x90?, 0xb?, 0x0?, 0x0?, 0x3?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc000083ab0 sp=0xc000083a90 pc=0x558d89633516
31-Oct-2023 14:52:23 runtime.netpollblock(0x558d89679985?, 0x895fbf6f?, 0x8d?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/netpoll.go:527 +0xf7 fp=0xc000083ae8 sp=0xc000083ab0 pc=0x558d8962bc77
31-Oct-2023 14:52:23 internal/poll.runtime_pollWait(0x7f6da047b398, 0x72)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/netpoll.go:306 +0x89 fp=0xc000083b08 sp=0xc000083ae8 pc=0x558d8965eb09
31-Oct-2023 14:52:23 internal/poll.(*pollDesc).wait(0xc00013e400?, 0xc000157000?, 0x0)
31-Oct-2023 14:52:23         /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x32 fp=0xc000083b30 sp=0xc000083b08 pc=0x558d896a0332
31-Oct-2023 14:52:23 internal/poll.(*pollDesc).waitRead(...)
31-Oct-2023 14:52:23         /usr/local/go/src/internal/poll/fd_poll_runtime.go:89
31-Oct-2023 14:52:23 internal/poll.(*FD).Read(0xc00013e400, {0xc000157000, 0x1000, 0x1000})
31-Oct-2023 14:52:23         /usr/local/go/src/internal/poll/fd_unix.go:167 +0x299 fp=0xc000083bc8 sp=0xc000083b30 pc=0x558d896a1719
31-Oct-2023 14:52:23 net.(*netFD).Read(0xc00013e400, {0xc000157000?, 0x2?, 0xc0004e63c0?})
31-Oct-2023 14:52:23         /usr/local/go/src/net/fd_posix.go:55 +0x29 fp=0xc000083c10 sp=0xc000083bc8 pc=0x558d89810749
31-Oct-2023 14:52:23 net.(*conn).Read(0xc000138268, {0xc000157000?, 0x0?, 0x0?})
31-Oct-2023 14:52:23         /usr/local/go/src/net/net.go:183 +0x45 fp=0xc000083c58 sp=0xc000083c10 pc=0x558d89822065
31-Oct-2023 14:52:23 net.(*UnixConn).Read(0x0?, {0xc000157000?, 0xc000128330?, 0x17?})
31-Oct-2023 14:52:23         <autogenerated>:1 +0x29 fp=0xc000083c88 sp=0xc000083c58 pc=0x558d898344a9
31-Oct-2023 14:52:23 net/http.(*persistConn).Read(0xc0001766c0, {0xc000157000?, 0x558d89643fa0?, 0xc000083ec8?})
31-Oct-2023 14:52:23         /usr/local/go/src/net/http/transport.go:1943 +0x4e fp=0xc000083ce8 sp=0xc000083c88 pc=0x558d899107ae
31-Oct-2023 14:52:23 bufio.(*Reader).fill(0xc00010eea0)
31-Oct-2023 14:52:23         /usr/local/go/src/bufio/bufio.go:106 +0xff fp=0xc000083d20 sp=0xc000083ce8 pc=0x558d897317bf
31-Oct-2023 14:52:23 bufio.(*Reader).Peek(0xc00010eea0, 0x1)
31-Oct-2023 14:52:23         /usr/local/go/src/bufio/bufio.go:144 +0x5d fp=0xc000083d40 sp=0xc000083d20 pc=0x558d8973191d
31-Oct-2023 14:52:23 net/http.(*persistConn).readLoop(0xc0001766c0)
31-Oct-2023 14:52:23         /usr/local/go/src/net/http/transport.go:2107 +0x1ac fp=0xc000083fc8 sp=0xc000083d40 pc=0x558d899115cc
31-Oct-2023 14:52:23 net/http.(*Transport).dialConn.func5()
31-Oct-2023 14:52:23         /usr/local/go/src/net/http/transport.go:1765 +0x26 fp=0xc000083fe0 sp=0xc000083fc8 pc=0x558d8990fd66
31-Oct-2023 14:52:23 runtime.goexit()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc000083fe8 sp=0xc000083fe0 pc=0x558d896646e1
31-Oct-2023 14:52:23 created by net/http.(*Transport).dialConn
31-Oct-2023 14:52:23         /usr/local/go/src/net/http/transport.go:1765 +0x16ea
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 goroutine 18 [select]:
31-Oct-2023 14:52:23 runtime.gopark(0xc000085f90?, 0x2?, 0xf8?, 0x5d?, 0xc000085f34?)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc000085db0 sp=0xc000085d90 pc=0x558d89633516
31-Oct-2023 14:52:23 runtime.selectgo(0xc000085f90, 0xc000085f30, 0xc00013aa80?, 0x0, 0x0?, 0x1)
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/select.go:327 +0x7be fp=0xc000085ef0 sp=0xc000085db0 pc=0x558d896431fe
31-Oct-2023 14:52:23 net/http.(*persistConn).writeLoop(0xc0001766c0)
31-Oct-2023 14:52:23         /usr/local/go/src/net/http/transport.go:2410 +0xf2 fp=0xc000085fc8 sp=0xc000085ef0 pc=0x558d89913292
31-Oct-2023 14:52:23 net/http.(*Transport).dialConn.func6()
31-Oct-2023 14:52:23         /usr/local/go/src/net/http/transport.go:1766 +0x26 fp=0xc000085fe0 sp=0xc000085fc8 pc=0x558d8990fd06
31-Oct-2023 14:52:23 runtime.goexit()
31-Oct-2023 14:52:23         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc000085fe8 sp=0xc000085fe0 pc=0x558d896646e1
31-Oct-2023 14:52:23 created by net/http.(*Transport).dialConn
31-Oct-2023 14:52:23         /usr/local/go/src/net/http/transport.go:1766 +0x173d
31-Oct-2023 14:52:23
31-Oct-2023 14:52:23 rax    0xca
31-Oct-2023 14:52:23 rbx    0x0
31-Oct-2023 14:52:23 rcx    0xffffffffffffffff
31-Oct-2023 14:52:23 rdx    0x0
31-Oct-2023 14:52:23 rdi    0x558d8ae84e88
31-Oct-2023 14:52:23 rsi    0x80
31-Oct-2023 14:52:23 rbp    0x7fff6aca2178
31-Oct-2023 14:52:23 rsp    0x7fff6aca2130
31-Oct-2023 14:52:23 r8     0x0
31-Oct-2023 14:52:23 r9     0x0
31-Oct-2023 14:52:23 r10    0x0
31-Oct-2023 14:52:23 r11    0x286
31-Oct-2023 14:52:23 r12    0x7fff6aca21a8
31-Oct-2023 14:52:23 r13    0x3
31-Oct-2023 14:52:23 r14    0x558d8ae84760
31-Oct-2023 14:52:23 r15    0x558d8ae21720
31-Oct-2023 14:52:23 rip    0x558d896665e1
31-Oct-2023 14:52:23 rflags 0x286
31-Oct-2023 14:52:23 cs     0x33
31-Oct-2023 14:52:23 fs     0x0
31-Oct-2023 14:52:23 gs     0x0
31-Oct-2023 14:52:23 test_task_deconvolve.py::test_iterbot::test_iterbot_mfs_fullsummary_true
31-Oct-2023 14:52:23 Cleanup complete
31-Oct-2023 14:52:23 Error response from daemon: You cannot remove a running container 1261f0861945edd83691f6a15febd76bf8c4b24682dcac46488124ea49905997. Stop the container before attempting removal or force remove
31-Oct-2023 14:52:23 Finished task 'Cleanup' with result: Success
31-Oct-2023 14:52:23 Running post build plugin 'Docker Container Cleanup'
31-Oct-2023 14:52:23
Beginning to execute external process for build 'CASA - CMake Dev Test - CAS-14234 - Test Tasks Many Linux 2014 #2 (CASA-CMDT86-TTASKGML2014P38-2)'
... running command line:
/bin/docker rm -f wheel-container-test
... in: /export/home/cbt-el7-6/bamboohome/xml-data/build-dir/CASA-CMDT86-TTASKGML2014P38
31-Oct-2023 14:52:26 wheel-container-test
31-Oct-2023 14:52:26 Running post build plugin 'NCover Results Collector'
31-Oct-2023 14:52:26 Running post build plugin 'Clover Results Collector'
31-Oct-2023 14:52:26 Running post build plugin 'npm Cache Cleanup'
31-Oct-2023 14:52:26 Running post build plugin 'Artifact Copier'
31-Oct-2023 14:52:26 Publishing an artifact: Workdir
31-Oct-2023 14:52:26 Unable to publish artifact [Workdir]:
31-Oct-2023 14:52:26 The artifact hasn't been successfully published after 77.83 ms
31-Oct-2023 14:52:26 Publishing an artifact: Tests
31-Oct-2023 14:52:26 Unable to publish artifact [Tests]:
31-Oct-2023 14:52:26 The artifact hasn't been successfully published after 11.76 ms
31-Oct-2023 14:52:26 Publishing an artifact: xml Directories
31-Oct-2023 14:52:26 Unable to publish artifact [xml Directories]:
31-Oct-2023 14:52:26 The artifact hasn't been successfully published after 11.27 ms
31-Oct-2023 14:52:26 Finalising the build...
31-Oct-2023 14:52:26 Stopping timer.
31-Oct-2023 14:52:26 Build CASA-CMDT86-TTASKGML2014P38-2 completed.
31-Oct-2023 14:52:26 Running on server: post build plugin 'NCover Results Collector'
31-Oct-2023 14:52:26 Running on server: post build plugin 'Build Hanging Detection Configuration'
31-Oct-2023 14:52:26 Running on server: post build plugin 'Clover Delta Calculator'
31-Oct-2023 14:52:26 Running on server: post build plugin 'Maven Dependencies Postprocessor'
31-Oct-2023 14:52:26 All post build plugins have finished
31-Oct-2023 14:52:26 Generating build results summary...
31-Oct-2023 14:52:26 Saving build results to disk...
31-Oct-2023 14:52:26 Store variable context...
31-Oct-2023 14:52:26 Indexing build results...
31-Oct-2023 14:52:26 Finished building CASA-CMDT86-TTASKGML2014P38-2.
31-Oct-2023 14:52:28 Killing: 1029
31-Oct-2023 14:52:28 Executing kill 1029
31-Oct-2023 14:52:28 Killing: 12801
31-Oct-2023 14:52:28 Executing kill 12801
31-Oct-2023 14:52:28 Killing: 12795
31-Oct-2023 14:52:28 Executing kill 12795
31-Oct-2023 14:52:33 Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.
31-Oct-2023 14:52:33 Has finished generating stack trace and terminating spawned sub-processes of process id: 10469