Build: #1 did not complete

Job: Test Casalith Many Linux 2014 did not complete

Build log

The build generated 917 lines of output. Download or view full build log.

25-Jul-2023 14:04:13 Build CASA - Release Engineering Casa 6 Master Test - Test Casalith Many Linux 2014 #1 (CASA-REC6MT-TSGC2014PY38-1) started building on agent cbt-el7-2.cv.nrao.edu, bamboo version: 7.2.10
25-Jul-2023 14:04:13 Remote agent on host cbt-el7-2.cv.nrao.edu
25-Jul-2023 14:04:13 Build working directory is /export/home/cbt-el7-2/bamboohome/xml-data/build-dir/CASA-REC6MT-TSGC2014PY38
25-Jul-2023 14:04:13 Executing build CASA - Release Engineering Casa 6 Master Test - Test Casalith Many Linux 2014 #1 (CASA-REC6MT-TSGC2014PY38-1)
25-Jul-2023 14:04:13 Starting task 'Clean working directory task' of type 'com.atlassian.bamboo.plugins.bamboo-artifact-downloader-plugin:cleanWorkingDirectoryTask'
25-Jul-2023 14:04:13 Cleaning working directory '/export/home/cbt-el7-2/bamboohome/xml-data/build-dir/CASA-REC6MT-TSGC2014PY38'
25-Jul-2023 14:04:13 Finished task 'Clean working directory task' with result: Success
25-Jul-2023 14:04:13 Running pre-build action: VCS Version Collector
25-Jul-2023 14:04:13
Substituting variable: ${bamboo.planRepository.branchName} with master
25-Jul-2023 14:04:13 Starting task 'Check if this is a 6.4.1 ticket' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
25-Jul-2023 14:04:13
Beginning to execute external process for build 'CASA - Release Engineering Casa 6 Master Test - Test Casalith Many Linux 2014 #1 (CASA-REC6MT-TSGC2014PY38-1)'
... running command line:
/export/home/cbt-el7-2/bamboohome/temp/CASA-REC6MT-TSGC2014PY38-1-ScriptBuildTask-2215612767604045524.sh
... in: /export/home/cbt-el7-2/bamboohome/xml-data/build-dir/CASA-REC6MT-TSGC2014PY38
25-Jul-2023 14:04:13   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
25-Jul-2023 14:04:13                                  Dload  Upload   Total   Spent    Left  Speed
25-Jul-2023 14:04:13
25-Jul-2023 14:04:13   0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
25-Jul-2023 14:04:13 100 6002k  100 6002k    0     0  19.0M      0 --:--:-- --:--:-- --:--:-- 19.2M
25-Jul-2023 14:04:14 [25370] Failed to execute script 'atlutil' due to unhandled exception!
25-Jul-2023 14:04:14 Traceback (most recent call last):
25-Jul-2023 14:04:14   File "atlutil.py", line 200, in <module>
25-Jul-2023 14:04:14   File "atlutil.py", line 165, in has_fix_version
25-Jul-2023 14:04:14 KeyError: 'fields'
25-Jul-2023 14:04:14 Finished task 'Check if this is a 6.4.1 ticket' with result: Success
25-Jul-2023 14:04:14 Starting task 'Inject is_641' of type 'com.atlassian.bamboo.plugins.bamboo-variable-inject-plugin:inject'
25-Jul-2023 14:04:14 Injected variable bamboo.inject.is_641=False in LOCAL scope
25-Jul-2023 14:04:14 Finished task 'Inject is_641' with result: Success
25-Jul-2023 14:04:14 Starting task 'Download casalith' of type 'com.atlassian.bamboo.plugins.bamboo-artifact-downloader-plugin:artifactdownloadertask'
25-Jul-2023 14:04:14 Preparing to download plan result CASA-CMBC-22 artifact: Non required shared artifact: [Sys GRPC ManyLinux2014 Python 3.8 Tar 2], pattern: [*.xz] anchored at: [source/wheels]
25-Jul-2023 14:05:01 Artifact [Sys GRPC ManyLinux2014 Python 3.8 Tar 2] downloaded successfully in 47.47 s to dockershare/
25-Jul-2023 14:05:01 Finished task 'Download casalith' with result: Success
25-Jul-2023 14:05:01
Substituting variable: ${bamboo.working.directory} with /export/home/cbt-el7-2/bamboohome/xml-data/build-dir/CASA-REC6MT-TSGC2014PY38
25-Jul-2023 14:05:01 Starting task 'Docker' of type 'com.atlassian.bamboo.plugins.bamboo-docker-plugin:task.docker.cli'
25-Jul-2023 14:05:01 Running image name (casa-docker.nrao.edu:5000/casa-test-casalith:1)
25-Jul-2023 14:05:01
Beginning to execute external process for build 'CASA - Release Engineering Casa 6 Master Test - Test Casalith Many Linux 2014 #1 (CASA-REC6MT-TSGC2014PY38-1)'
... running command line:
/bin/docker run --volume /opt/casa/data/:/opt/casa/data --volume /export/home/cbt-el7-2/bamboohome/xml-data/build-dir/CASA-REC6MT-TSGC2014PY38/dockershare:/dockershare --detach --privileged --name test-container casa-docker.nrao.edu:5000/casa-test-casalith:1 tail -f /dev/null
... in: /export/home/cbt-el7-2/bamboohome/xml-data/build-dir/CASA-REC6MT-TSGC2014PY38
25-Jul-2023 14:05:03 fe7a047382e7406af3ee0ebc4a2d28211c47ee49dc3d0672a8029f3c38917dd1
25-Jul-2023 14:05:03 Finished task 'Docker' with result: Success
25-Jul-2023 14:05:03
Substituting variable: ${bamboo.planRepository.branchName} with master
25-Jul-2023 14:05:03 Starting task 'Run plotms, imview, msview, wvrgcal, casampi tests' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
25-Jul-2023 14:05:03
Beginning to execute external process for build 'CASA - Release Engineering Casa 6 Master Test - Test Casalith Many Linux 2014 #1 (CASA-REC6MT-TSGC2014PY38-1)'
... running command line:
/export/home/cbt-el7-2/bamboohome/temp/CASA-REC6MT-TSGC2014PY38-1-ScriptBuildTask-2101132708556825693.sh
... in: /export/home/cbt-el7-2/bamboohome/xml-data/build-dir/CASA-REC6MT-TSGC2014PY38
25-Jul-2023 14:05:03 branch: master
25-Jul-2023 14:05:03 /export/home/cbt-el7-2/bamboohome/xml-data/build-dir/CASA-REC6MT-TSGC2014PY38
25-Jul-2023 14:05:03   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
25-Jul-2023 14:05:03                                  Dload  Upload   Total   Spent    Left  Speed
25-Jul-2023 14:05:03
25-Jul-2023 14:05:03   0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
25-Jul-2023 14:05:03 100 6002k  100 6002k    0     0  17.5M      0 --:--:-- --:--:-- --:--:-- 17.8M
25-Jul-2023 14:05:03 bamboo_jira_components:''
25-Jul-2023 14:05:03 bamboo_testlist:''
25-Jul-2023 14:05:03 Ticket components:
25-Jul-2023 14:05:03 Components:
25-Jul-2023 14:05:03 Test list:  -l "test_task_wvrgcal,test_casampi,test_task_msview,test_task_imview,test_task_plotms"
25-Jul-2023 14:05:03 pmode: both
25-Jul-2023 14:05:03 datapath=[ "/opt/casa/data/master","/opt/casa/data/casatestdata"]
25-Jul-2023 14:05:04 total 1187712
25-Jul-2023 14:05:04 -rw-r--r-- 1 9285 15001 1216211576 Jul 25 18:05 casa-6.6.0-3-py3.8.el7.tar.xz
25-Jul-2023 14:05:04 datapath=[ "/opt/casa/data/master","/opt/casa/data/casatestdata"]
25-Jul-2023 14:05:04 Cloning into 'casa6'...
25-Jul-2023 14:05:19 Checking out files:  22% (2077/9200)   
25-Jul-2023 14:05:19 Checking out files:  23% (2116/9200)   
25-Jul-2023 14:05:19 Checking out files:  24% (2208/9200)   
25-Jul-2023 14:05:19 Checking out files:  25% (2300/9200)   
25-Jul-2023 14:05:19 Checking out files:  26% (2392/9200)   
25-Jul-2023 14:05:20 Checking out files:  27% (2484/9200)   
25-Jul-2023 14:05:20 Checking out files:  28% (2576/9200)   
25-Jul-2023 14:05:20 Checking out files:  29% (2668/9200)   
25-Jul-2023 14:05:20 Checking out files:  30% (2760/9200)   
25-Jul-2023 14:05:20 Checking out files:  31% (2852/9200)   
25-Jul-2023 14:05:20 Checking out files:  32% (2944/9200)   
25-Jul-2023 14:05:20 Checking out files:  33% (3036/9200)   
25-Jul-2023 14:05:20 Checking out files:  34% (3128/9200)   
25-Jul-2023 14:05:20 Checking out files:  35% (3220/9200)   
25-Jul-2023 14:05:20 Checking out files:  36% (3312/9200)   
25-Jul-2023 14:05:20 Checking out files:  37% (3404/9200)   
25-Jul-2023 14:05:20 Checking out files:  38% (3496/9200)   
25-Jul-2023 14:05:20 Checking out files:  39% (3588/9200)   
25-Jul-2023 14:05:20 Checking out files:  40% (3680/9200)   
25-Jul-2023 14:05:20 Checking out files:  41% (3772/9200)   
25-Jul-2023 14:05:20 Checking out files:  42% (3864/9200)   
25-Jul-2023 14:05:20 Checking out files:  43% (3956/9200)   
25-Jul-2023 14:05:20 Checking out files:  44% (4048/9200)   
25-Jul-2023 14:05:20 Checking out files:  44% (4125/9200)   
25-Jul-2023 14:05:20 Checking out files:  45% (4140/9200)   
25-Jul-2023 14:05:20 Checking out files:  46% (4232/9200)   
25-Jul-2023 14:05:20 Checking out files:  47% (4324/9200)   
25-Jul-2023 14:05:21 Checking out files:  48% (4416/9200)   
25-Jul-2023 14:05:21 Checking out files:  49% (4508/9200)   
25-Jul-2023 14:05:21 Checking out files:  50% (4600/9200)   
25-Jul-2023 14:05:21 Checking out files:  51% (4692/9200)   
25-Jul-2023 14:05:21 Checking out files:  52% (4784/9200)   
25-Jul-2023 14:05:21 Checking out files:  53% (4876/9200)   
25-Jul-2023 14:05:21 Checking out files:  54% (4968/9200)   
25-Jul-2023 14:05:21 Checking out files:  55% (5060/9200)   
25-Jul-2023 14:05:21 Checking out files:  56% (5152/9200)   
25-Jul-2023 14:05:21 Checking out files:  57% (5244/9200)   
25-Jul-2023 14:05:21 Checking out files:  58% (5336/9200)   
25-Jul-2023 14:05:21 Checking out files:  59% (5428/9200)   
25-Jul-2023 14:05:21 Checking out files:  60% (5520/9200)   
25-Jul-2023 14:05:21 Checking out files:  61% (5612/9200)   
25-Jul-2023 14:05:21 Checking out files:  62% (5704/9200)   
25-Jul-2023 14:05:21 Checking out files:  63% (5796/9200)   
25-Jul-2023 14:05:21 Checking out files:  64% (5888/9200)   
25-Jul-2023 14:05:21 Checking out files:  65% (5980/9200)   
25-Jul-2023 14:05:21 Checking out files:  66% (6072/9200)   
25-Jul-2023 14:05:21 Checking out files:  66% (6089/9200)   
25-Jul-2023 14:05:21 Checking out files:  67% (6164/9200)   
25-Jul-2023 14:05:21 Checking out files:  68% (6256/9200)   
25-Jul-2023 14:05:22 Checking out files:  69% (6348/9200)   
25-Jul-2023 14:05:22 Checking out files:  70% (6440/9200)   
25-Jul-2023 14:05:22 Checking out files:  71% (6532/9200)   
25-Jul-2023 14:05:22 Checking out files:  72% (6624/9200)   
25-Jul-2023 14:05:22 Checking out files:  73% (6716/9200)   
25-Jul-2023 14:05:22 Checking out files:  74% (6808/9200)   
25-Jul-2023 14:05:22 Checking out files:  75% (6900/9200)   
25-Jul-2023 14:05:22 Checking out files:  76% (6992/9200)   
25-Jul-2023 14:05:22 Checking out files:  77% (7084/9200)   
25-Jul-2023 14:05:22 Checking out files:  78% (7176/9200)   
25-Jul-2023 14:05:22 Checking out files:  79% (7268/9200)   
25-Jul-2023 14:05:22 Checking out files:  80% (7360/9200)   
25-Jul-2023 14:05:22 Checking out files:  81% (7452/9200)   
25-Jul-2023 14:05:22 Checking out files:  82% (7544/9200)   
25-Jul-2023 14:05:22 Checking out files:  83% (7636/9200)   
25-Jul-2023 14:05:22 Checking out files:  84% (7728/9200)   
25-Jul-2023 14:05:22 Checking out files:  85% (7820/9200)   
25-Jul-2023 14:05:22 Checking out files:  86% (7912/9200)   
25-Jul-2023 14:05:22 Checking out files:  86% (7936/9200)   
25-Jul-2023 14:05:22 Checking out files:  87% (8004/9200)   
25-Jul-2023 14:05:22 Checking out files:  88% (8096/9200)   
25-Jul-2023 14:05:22 Checking out files:  89% (8188/9200)   
25-Jul-2023 14:05:23 Checking out files:  90% (8280/9200)   
25-Jul-2023 14:05:23 Checking out files:  91% (8372/9200)   
25-Jul-2023 14:05:23 Checking out files:  92% (8464/9200)   
25-Jul-2023 14:05:23 Checking out files:  93% (8556/9200)   
25-Jul-2023 14:05:23 Checking out files:  94% (8648/9200)   
25-Jul-2023 14:05:23 Checking out files:  95% (8740/9200)   
25-Jul-2023 14:05:23 Checking out files:  96% (8832/9200)   
25-Jul-2023 14:05:23 Checking out files:  97% (8924/9200)   
25-Jul-2023 14:05:23 Checking out files:  98% (9016/9200)   
25-Jul-2023 14:05:23 Checking out files:  99% (9108/9200)   
25-Jul-2023 14:05:23 Checking out files: 100% (9200/9200)   
25-Jul-2023 14:05:23 Checking out files: 100% (9200/9200), done.
25-Jul-2023 14:05:24 Already on 'master'
25-Jul-2023 14:07:03 HAVE_COVTEST: False
25-Jul-2023 14:07:03 HAVE_PYTEST: False
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03 Namespace(bamboo=True, branch='master', classes=None, dry_run=False, file=None, ignore_list=None, list=False, mapfile=None, ncores='4', pkg='/dockershare/casa-6.6.0-3-py3.8.el7.tar.xz', pmode='both', rcdir=None, test_config='/home/casatest/casa6/casatestutils/casatestutils/component_to_test_map.json', test_group=None, test_list='test_task_wvrgcal,test_casampi,test_task_msview,test_task_imview,test_task_plotms', test_paths=None, verbose=False, work_dir='/home/casatest/work')
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03 Operating system: Linux
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03 rcdir:
25-Jul-2023 14:07:03 Arguments Sent Direct To Pytest :  []
25-Jul-2023 14:07:03 Package: /dockershare/casa-6.6.0-3-py3.8.el7.tar.xz
25-Jul-2023 14:07:03 Test configuration file: /home/casatest/casa6/casatestutils/casatestutils/component_to_test_map.json
25-Jul-2023 14:07:03 Number of cores: 4
25-Jul-2023 14:07:03 Workdir: /home/casatest/work
25-Jul-2023 14:07:03 branch: master
25-Jul-2023 14:07:03 run_bamboo
25-Jul-2023 14:07:03 Test list: ['test_task_wvrgcal', 'test_casampi', 'test_task_msview', 'test_task_imview', 'test_task_plotms']
25-Jul-2023 14:07:03 Test group: None
25-Jul-2023 14:07:03 Unpacking tarball: /dockershare/casa-6.6.0-3-py3.8.el7.tar.xz to /home/casatest/work/pkg
25-Jul-2023 14:07:03 ['tar', '-xf', '/dockershare/casa-6.6.0-3-py3.8.el7.tar.xz', '-C', '/home/casatest/work/pkg']
25-Jul-2023 14:07:03 Executing: ['tar', '-xf', '/dockershare/casa-6.6.0-3-py3.8.el7.tar.xz', '-C', '/home/casatest/work/pkg']
25-Jul-2023 14:07:03 Executor result: 0
25-Jul-2023 14:07:03 Failed tests:[]
25-Jul-2023 14:07:03 outputdir contents:/home/casatest/work/pkg
25-Jul-2023 14:07:03 casa-6.6.0-3-py3.8.el7
25-Jul-2023 14:07:03 installpath: casa-6.6.0-3-py3.8.el7
25-Jul-2023 14:07:03 Package root: /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7
25-Jul-2023 14:07:03 Executable path: /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/bin
25-Jul-2023 14:07:03 casatestutils path: /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/casatestutils/runtest.py
25-Jul-2023 14:07:03 Starting Xvfb
25-Jul-2023 14:07:03 Xvfb :78 -screen 0 2048x2048x24+32 -auth /tmp/CASA_testrunner_xauth_c4tm62j/tmppwyg1nja
25-Jul-2023 14:07:03 Deployed virtual frame buffer at port :78 with PID 86
25-Jul-2023 14:07:03 run_bamboo fetch_tests branchmaster
25-Jul-2023 14:07:03 Running:  git clone https://open-bitbucket.nrao.edu/scm/casa/casa6
25-Jul-2023 14:07:03 Executing: ['git', 'clone', 'https://open-bitbucket.nrao.edu/scm/casa/casa6']
25-Jul-2023 14:07:03 Cloning into 'casa6'...
25-Jul-2023 14:07:03 Checking out files:  22% (2107/9200)   
25-Jul-2023 14:07:03 Checking out files:  23% (2116/9200)   
25-Jul-2023 14:07:03 Checking out files:  24% (2208/9200)   
25-Jul-2023 14:07:03 Checking out files:  25% (2300/9200)   
25-Jul-2023 14:07:03 Checking out files:  26% (2392/9200)   
25-Jul-2023 14:07:03 Checking out files:  27% (2484/9200)   
25-Jul-2023 14:07:03 Checking out files:  28% (2576/9200)   
25-Jul-2023 14:07:03 Checking out files:  29% (2668/9200)   
25-Jul-2023 14:07:03 Checking out files:  30% (2760/9200)   
25-Jul-2023 14:07:03 Checking out files:  31% (2852/9200)   
25-Jul-2023 14:07:03 Checking out files:  32% (2944/9200)   
25-Jul-2023 14:07:03 Checking out files:  33% (3036/9200)   
25-Jul-2023 14:07:03 Checking out files:  34% (3128/9200)   
25-Jul-2023 14:07:03 Checking out files:  35% (3220/9200)   
25-Jul-2023 14:07:03 Checking out files:  36% (3312/9200)   
25-Jul-2023 14:07:03 Checking out files:  37% (3404/9200)   
25-Jul-2023 14:07:03 Checking out files:  38% (3496/9200)   
25-Jul-2023 14:07:03 Checking out files:  39% (3588/9200)   
25-Jul-2023 14:07:03 Checking out files:  40% (3680/9200)   
25-Jul-2023 14:07:03 Checking out files:  41% (3772/9200)   
25-Jul-2023 14:07:03 Checking out files:  42% (3864/9200)   
25-Jul-2023 14:07:03 Checking out files:  42% (3897/9200)   
25-Jul-2023 14:07:03 Checking out files:  43% (3956/9200)   
25-Jul-2023 14:07:03 Checking out files:  44% (4048/9200)   
25-Jul-2023 14:07:03 Checking out files:  45% (4140/9200)   
25-Jul-2023 14:07:03 Checking out files:  46% (4232/9200)   
25-Jul-2023 14:07:03 Checking out files:  47% (4324/9200)   
25-Jul-2023 14:07:03 Checking out files:  48% (4416/9200)   
25-Jul-2023 14:07:03 Checking out files:  49% (4508/9200)   
25-Jul-2023 14:07:03 Checking out files:  50% (4600/9200)   
25-Jul-2023 14:07:03 Checking out files:  51% (4692/9200)   
25-Jul-2023 14:07:03 Checking out files:  52% (4784/9200)   
25-Jul-2023 14:07:03 Checking out files:  53% (4876/9200)   
25-Jul-2023 14:07:03 Checking out files:  54% (4968/9200)   
25-Jul-2023 14:07:03 Checking out files:  55% (5060/9200)   
25-Jul-2023 14:07:03 Checking out files:  56% (5152/9200)   
25-Jul-2023 14:07:03 Checking out files:  57% (5244/9200)   
25-Jul-2023 14:07:03 Checking out files:  58% (5336/9200)   
25-Jul-2023 14:07:03 Checking out files:  59% (5428/9200)   
25-Jul-2023 14:07:03 Checking out files:  60% (5520/9200)   
25-Jul-2023 14:07:03 Checking out files:  61% (5612/9200)   
25-Jul-2023 14:07:03 Checking out files:  61% (5618/9200)   
25-Jul-2023 14:07:03 Checking out files:  62% (5704/9200)   
25-Jul-2023 14:07:03 Checking out files:  63% (5796/9200)   
25-Jul-2023 14:07:03 Checking out files:  64% (5888/9200)   
25-Jul-2023 14:07:03 Checking out files:  65% (5980/9200)   
25-Jul-2023 14:07:03 Checking out files:  66% (6072/9200)   
25-Jul-2023 14:07:03 Checking out files:  67% (6164/9200)   
25-Jul-2023 14:07:03 Checking out files:  68% (6256/9200)   
25-Jul-2023 14:07:03 Checking out files:  69% (6348/9200)   
25-Jul-2023 14:07:03 Checking out files:  70% (6440/9200)   
25-Jul-2023 14:07:03 Checking out files:  71% (6532/9200)   
25-Jul-2023 14:07:03 Checking out files:  72% (6624/9200)   
25-Jul-2023 14:07:03 Checking out files:  73% (6716/9200)   
25-Jul-2023 14:07:03 Checking out files:  74% (6808/9200)   
25-Jul-2023 14:07:03 Checking out files:  75% (6900/9200)   
25-Jul-2023 14:07:03 Checking out files:  76% (6992/9200)   
25-Jul-2023 14:07:03 Checking out files:  77% (7084/9200)   
25-Jul-2023 14:07:03 Checking out files:  78% (7176/9200)   
25-Jul-2023 14:07:03 Checking out files:  79% (7268/9200)   
25-Jul-2023 14:07:03 Checking out files:  80% (7360/9200)   
25-Jul-2023 14:07:03 Checking out files:  81% (7452/9200)   
25-Jul-2023 14:07:03 Checking out files:  81% (7467/9200)   
25-Jul-2023 14:07:03 Checking out files:  82% (7544/9200)   
25-Jul-2023 14:07:03 Checking out files:  83% (7636/9200)   
25-Jul-2023 14:07:03 Checking out files:  84% (7728/9200)   
25-Jul-2023 14:07:03 Checking out files:  85% (7820/9200)   
25-Jul-2023 14:07:03 Checking out files:  86% (7912/9200)   
25-Jul-2023 14:07:03 Checking out files:  87% (8004/9200)   
25-Jul-2023 14:07:03 Checking out files:  88% (8096/9200)   
25-Jul-2023 14:07:03 Checking out files:  89% (8188/9200)   
25-Jul-2023 14:07:03 Checking out files:  90% (8280/9200)   
25-Jul-2023 14:07:03 Checking out files:  91% (8372/9200)   
25-Jul-2023 14:07:03 Checking out files:  92% (8464/9200)   
25-Jul-2023 14:07:03 Checking out files:  93% (8556/9200)   
25-Jul-2023 14:07:03 Checking out files:  94% (8648/9200)   
25-Jul-2023 14:07:03 Checking out files:  95% (8740/9200)   
25-Jul-2023 14:07:03 Checking out files:  96% (8832/9200)   
25-Jul-2023 14:07:03 Checking out files:  96% (8898/9200)   
25-Jul-2023 14:07:03 Checking out files:  97% (8924/9200)   
25-Jul-2023 14:07:03 Checking out files:  98% (9016/9200)   
25-Jul-2023 14:07:03 Checking out files:  99% (9108/9200)   
25-Jul-2023 14:07:03 Checking out files: 100% (9200/9200)   
25-Jul-2023 14:07:03 Checking out files: 100% (9200/9200), done.
25-Jul-2023 14:07:03 Executor result: 0
25-Jul-2023 14:07:03 Failed tests:[]
25-Jul-2023 14:07:03 ['git', 'checkout', 'master']
25-Jul-2023 14:07:03 Executing: ['git', 'checkout', 'master']
25-Jul-2023 14:07:03 Already on 'master'
25-Jul-2023 14:07:03 Executor result: 0
25-Jul-2023 14:07:03 Failed tests:[]
25-Jul-2023 14:07:03 Running:  git clone https://open-bitbucket.nrao.edu/scm/casa/casampi
25-Jul-2023 14:07:03 Executing: ['git', 'clone', 'https://open-bitbucket.nrao.edu/scm/casa/casampi']
25-Jul-2023 14:07:03 Cloning into 'casampi'...
25-Jul-2023 14:07:03 Executor result: 0
25-Jul-2023 14:07:03 Failed tests:[]
25-Jul-2023 14:07:03 ['git', 'checkout', 'tags/0.5.3']
25-Jul-2023 14:07:03 Executing: ['git', 'checkout', 'tags/0.5.3']
25-Jul-2023 14:07:03 Note: checking out 'tags/0.5.3'.
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03 You are in 'detached HEAD' state. You can look around, make experimental
25-Jul-2023 14:07:03 changes and commit them, and you can discard any commits you make in this
25-Jul-2023 14:07:03 state without impacting any branches by performing another checkout.
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03 If you want to create a new branch to retain commits you create, you may
25-Jul-2023 14:07:03 do so (now or later) by using -b with the checkout command again. Example:
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03   git checkout -b new_branch_name
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03 HEAD is now at ee2b0e4... Pull request #12: CAS-14034
25-Jul-2023 14:07:03 Executor result: 0
25-Jul-2023 14:07:03 Failed tests:[]
25-Jul-2023 14:07:03 Running:  git clone https://open-bitbucket.nrao.edu/scm/casa/casaplotms
25-Jul-2023 14:07:03 Executing: ['git', 'clone', 'https://open-bitbucket.nrao.edu/scm/casa/casaplotms']
25-Jul-2023 14:07:03 Cloning into 'casaplotms'...
25-Jul-2023 14:07:03 Executor result: 0
25-Jul-2023 14:07:03 Failed tests:[]
25-Jul-2023 14:07:03 ['git', 'checkout', 'tags/2.2.1']
25-Jul-2023 14:07:03 Executing: ['git', 'checkout', 'tags/2.2.1']
25-Jul-2023 14:07:03 Note: checking out 'tags/2.2.1'.
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03 You are in 'detached HEAD' state. You can look around, make experimental
25-Jul-2023 14:07:03 changes and commit them, and you can discard any commits you make in this
25-Jul-2023 14:07:03 state without impacting any branches by performing another checkout.
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03 If you want to create a new branch to retain commits you create, you may
25-Jul-2023 14:07:03 do so (now or later) by using -b with the checkout command again. Example:
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03   git checkout -b new_branch_name
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03 HEAD is now at f1f48de... Pull request #72: CAS-7164
25-Jul-2023 14:07:03 Executor result: 0
25-Jul-2023 14:07:03 Failed tests:[]
25-Jul-2023 14:07:03 Running:  git clone https://open-bitbucket.nrao.edu/scm/casa/almatasks
25-Jul-2023 14:07:03 Executing: ['git', 'clone', 'https://open-bitbucket.nrao.edu/scm/casa/almatasks']
25-Jul-2023 14:07:03 Cloning into 'almatasks'...
25-Jul-2023 14:07:03 Executor result: 0
25-Jul-2023 14:07:03 Failed tests:[]
25-Jul-2023 14:07:03 ['git', 'checkout', 'tags/1.7.1']
25-Jul-2023 14:07:03 Executing: ['git', 'checkout', 'tags/1.7.1']
25-Jul-2023 14:07:03 Note: checking out 'tags/1.7.1'.
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03 You are in 'detached HEAD' state. You can look around, make experimental
25-Jul-2023 14:07:03 changes and commit them, and you can discard any commits you make in this
25-Jul-2023 14:07:03 state without impacting any branches by performing another checkout.
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03 If you want to create a new branch to retain commits you create, you may
25-Jul-2023 14:07:03 do so (now or later) by using -b with the checkout command again. Example:
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03   git checkout -b new_branch_name
25-Jul-2023 14:07:03
25-Jul-2023 14:07:03 HEAD is now at 6c66833... Pull request #12: CAS-14608
25-Jul-2023 14:07:03 Executor result: 0
25-Jul-2023 14:07:03 Failed tests:[]
25-Jul-2023 14:07:03 Running:  git clone https://open-bitbucket.nrao.edu/scm/casa/casaviewer
25-Jul-2023 14:07:03 Executing: ['git', 'clone', 'https://open-bitbucket.nrao.edu/scm/casa/casaviewer']
25-Jul-2023 14:07:03 Cloning into 'casaviewer'...
25-Jul-2023 14:07:03 Executor result: 0
25-Jul-2023 14:07:03 Failed tests:[]
25-Jul-2023 14:07:32 ['git', 'checkout', 'tags/1.8.2']
25-Jul-2023 14:07:32 Executing: ['git', 'checkout', 'tags/1.8.2']
25-Jul-2023 14:07:32 Note: checking out 'tags/1.8.2'.
25-Jul-2023 14:07:32
25-Jul-2023 14:07:32 You are in 'detached HEAD' state. You can look around, make experimental
25-Jul-2023 14:07:32 changes and commit them, and you can discard any commits you make in this
25-Jul-2023 14:07:32 state without impacting any branches by performing another checkout.
25-Jul-2023 14:07:32
25-Jul-2023 14:07:32 If you want to create a new branch to retain commits you create, you may
25-Jul-2023 14:07:32 do so (now or later) by using -b with the checkout command again. Example:
25-Jul-2023 14:07:32
25-Jul-2023 14:07:32   git checkout -b new_branch_name
25-Jul-2023 14:07:32
25-Jul-2023 14:07:32 HEAD is now at c98f40d... Pull request #39: CAS-14106
25-Jul-2023 14:07:32 Executor result: 0
25-Jul-2023 14:07:32 Failed tests:[]
25-Jul-2023 14:07:32 Reading config from: /home/casatest/casa6/casatestutils/casatestutils/component_to_test_map.json
25-Jul-2023 14:07:32 Test Paths:  ['/home/casatest/work/casasources//casa6/casatests/regression/', '/home/casatest/work/casasources//casa6/casatests/stakeholder/', '/home/casatest/work/casasources//casa6/casatasks/tests/', '/home/casatest/work/casasources//casa6/casatools/tests/', '/home/casatest/work/casasources//casampi/src/casampi/tests', '/home/casatest/work/casasources//casaplotms/tests/plotms', '/home/casatest/work/casasources//almatasks/tests/tasks', '/home/casatest/work/casasources//casaviewer/tests/tasks']
25-Jul-2023 14:07:32 Test list provided. Filtering tests.
25-Jul-2023 14:07:32 Test test_task_imview configuration not found. Searching for the test...
25-Jul-2023 14:07:32 dir: /home/casatest/work/casasources//casaviewer/tests/tasks
25-Jul-2023 14:07:32 Found: /home/casatest/work/casasources//casaviewer/tests/tasks/test_task_imview.py
25-Jul-2023 14:07:32 No JSON configuration found. Test will be added to execution list without options.
25-Jul-2023 14:07:32 Subset tests:
25-Jul-2023 14:07:32 test_task_wvrgcal
25-Jul-2023 14:07:32 test_casampi
25-Jul-2023 14:07:32 test_task_msview
25-Jul-2023 14:07:32 test_task_imview
25-Jul-2023 14:07:32 test_task_plotms
25-Jul-2023 14:07:32
25-Jul-2023 14:07:32 Running test: test_task_wvrgcal in Serial mode
25-Jul-2023 14:07:32 Running cmd ['/home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/bin/casa', '--nogui', '--nologger', '--log2term', '-c', '/home/casatest/work/casasources//almatasks/tests/tasks/test_task_wvrgcal.py']in /home/casatest/work/test_task_wvrgcal
25-Jul-2023 14:07:32 Executing: ['/home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/bin/casa', '--nogui', '--nologger', '--log2term', '-c', '/home/casatest/work/casasources//almatasks/tests/tasks/test_task_wvrgcal.py']
25-Jul-2023 14:07:32 --> CrashReporter initialized.
25-Jul-2023 14:07:32 2023-07-25 18:07:08        INFO        ::casa        Using configuration file ~/.casa/config.py
25-Jul-2023 14:07:32 2023-07-25 18:07:08        INFO        ::casa        
25-Jul-2023 14:07:32 2023-07-25 18:07:08        INFO        ::casa        Checking Measures tables in data repository sub-directory /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/casadata/__data__/geodetic
25-Jul-2023 14:07:32 2023-07-25 18:07:08        INFO        ::casa          IERSeop2000 (version date, last date in table (UTC)): 2023/06/23/15:00, 2023/05/24/00:00:00
25-Jul-2023 14:07:32 2023-07-25 18:07:08        INFO        ::casa          IERSeop97 (version date, last date in table (UTC)): 2023/06/23/15:00, 2023/05/24/00:00:00
25-Jul-2023 14:07:32 2023-07-25 18:07:08        INFO        ::casa          IERSpredict (version date, last date in table (UTC)): 2023/07/16/15:00, 2023/10/14/00:00:00
25-Jul-2023 14:07:32 2023-07-25 18:07:08        INFO        ::casa          TAI_UTC (version date, last date in table (UTC)): 2023/07/09/15:00, 2017/01/01/00:00:00
25-Jul-2023 14:07:32 Telemetry initialized. Telemetry will send anonymized usage statistics to NRAO.
25-Jul-2023 14:07:32 You can disable telemetry by adding the following line to the config.py file in your rcdir (e.g. ~/.casa/config.py):
25-Jul-2023 14:07:32 telemetry_enabled = False
25-Jul-2023 14:07:32 .2023-07-25 18:07:15        INFO        wvrgcal::::casa        ##########################################
25-Jul-2023 14:07:32 2023-07-25 18:07:15        INFO        wvrgcal::::casa        ##### Begin Task: wvrgcal            #####
25-Jul-2023 14:07:32 2023-07-25 18:07:15        INFO        wvrgcal::::casa        wvrgcal( vis='myinput.ms', caltable='mycaltable.wvr', toffset=0.0, segsource=False, sourceflag=[], tie=[], nsol=1, disperse=False, wvrflag=[], statfield='', statsource='0455-462', smooth='', scale=1.0, spw=[], wvrspw=[], reversespw='', cont=False, maxdistm=500.0, minnumants=2, mingoodfrac=0.8, usefieldtab=False, refant=[], offsetstable='' )
25-Jul-2023 14:07:32 2023-07-25 18:07:15        INFO        wvrgcal::::casa        Running /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/almatasks/__bin__/wvrgcal standalone invoked as:
25-Jul-2023 14:07:32 2023-07-25 18:07:15        INFO        wvrgcal::::casa        /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/almatasks/__bin__/wvrgcal --ms myinput.ms --output mycaltable.wvr --toffset 0.0 --statsource "0455-462" --maxdistm 500.0 --minnumants 2 --mingoodfrac 0.8
25-Jul-2023 14:07:32 Calculating the coefficients now ...
25-Jul-2023 14:07:32 done!
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        WVRGCAL  -- Version 2.1
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        Developed by B. Nikolic at the University of Cambridge as part of EU FP6 ALMA Enhancement
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        Maintained and extended since 2013 by the European Southern Observatory as part of the ALMA project
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        GPLv2 License -- you have a right to the source code
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        Will use all WVR SPWs:
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa         0 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        Will produce solutions for all SPWs:
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa         0 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        All WVR data points for antenna 0 are flagged.
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        All WVR data points for antenna 1 are flagged.
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        Choosing (interpolated) antenna 0 == CM01 as reference antenna for dTdL calculations.
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa               Retrieved parameters     
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        ----------------------------------------------------------------
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        Evidence        PWV     PWV Error       dT1dL   dT2dL   dT3dL   dT4dL   
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        8.8022e-25      0.27555 0.00535097      29.3112 13.8616 6.20103 3.94196
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        Times used for the statistics calculation (in seconds from first astro datum)
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        -----------------------------------------------------------------------------
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        (138.24, 146.304)
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa             Antenna/WVR information:                     
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        -----------------------------------------------------------------------
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        #       Name    WVR?    Flag?   RMS (um)        Disc (um)
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        0       CM01    No      Yes     0.827           18.4
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        1       CM02    No      Yes     0.901           18.4
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        2       DA41    Yes     No      1.54            16.4
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        3       DA42    Yes     No      3.13            16.1
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        4       DV02    Yes     No      0.102              0
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        5       DV03    Yes     No      2.32            8.67
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        6       DV04    Yes     No       3.1            17.5
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        7       DV05    Yes     No      3.06            16.4
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        8       DV06    Yes     No       3.9            17.2
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        9       DV08    Yes     No      1.37            23.7
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        10      DV09    Yes     No      2.91            9.57
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        11      DV11    Yes     No      3.67            19.2
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        12      DV12    Yes     No      0.808           14.9
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        13      DV13    Yes     No      2.09            15.6
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        14      DV14    Yes     No      1.51            12.6
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        15      PM02    Yes     No      1.79            15.7
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        16      PM03    Yes     No       1.7            14.8
25-Jul-2023 14:07:32 2023-07-25 18:07:33        INFO        wvrgcal::::casa        17      PM04    Yes     No      1.25            25.5
25-Jul-2023 14:08:56 2023-07-25 18:07:33        INFO        wvrgcal::::casa          Expected performance
25-Jul-2023 14:08:56 2023-07-25 18:07:33        INFO        wvrgcal::::casa        ------------------------------------------------------------------
25-Jul-2023 14:08:56 2023-07-25 18:07:33        INFO        wvrgcal::::casa        * Estimated WVR thermal contribution to path fluctuations (micron per antenna): 2.84137
25-Jul-2023 14:08:56 2023-07-25 18:07:33        INFO        wvrgcal::::casa        * Greatest Estimated path fluctuation is (micron on a baseline): 7.18935
25-Jul-2023 14:08:56 2023-07-25 18:07:33        INFO        wvrgcal::::casa        * Rough estimate path error due to coefficient error (micron on a baseline): 0.030375
25-Jul-2023 14:08:56 2023-07-25 18:07:33        INFO        wvrgcal::::casa        
25-Jul-2023 14:08:56 2023-07-25 18:07:33        INFO        wvrgcal::::casa        Writing gain table ...
25-Jul-2023 14:08:56 2023-07-25 18:07:33        INFO        wvrgcal::::casa        Task wvrgcal complete. Start time: 2023-07-25 18:07:14.717481 End time: 2023-07-25 18:07:32.736686
25-Jul-2023 14:08:56 2023-07-25 18:07:33        INFO        wvrgcal::::casa        ##### End Task: wvrgcal              #####
25-Jul-2023 14:08:56 2023-07-25 18:07:33        INFO        wvrgcal::::casa        ##########################################
25-Jul-2023 14:08:56 .2023-07-25 18:07:34        INFO        wvrgcal::::casa        ##########################################
25-Jul-2023 14:08:56 2023-07-25 18:07:34        INFO        wvrgcal::::casa        ##### Begin Task: wvrgcal            #####
25-Jul-2023 14:08:56 2023-07-25 18:07:34        INFO        wvrgcal::::casa        wvrgcal( vis='myinput.ms', caltable='mycaltable.wvr', toffset=0.0, segsource=False, sourceflag=[], tie=[], nsol=5, disperse=False, wvrflag=[], statfield='', statsource='', smooth='', scale=1.0, spw=[], wvrspw=[], reversespw='', cont=False, maxdistm=500.0, minnumants=2, mingoodfrac=0.8, usefieldtab=False, refant=[], offsetstable='' )
25-Jul-2023 14:08:56 2023-07-25 18:07:34        INFO        wvrgcal::::casa        Running /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/almatasks/__bin__/wvrgcal standalone invoked as:
25-Jul-2023 14:08:56 2023-07-25 18:07:34        INFO        wvrgcal::::casa        /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/almatasks/__bin__/wvrgcal --ms myinput.ms --output mycaltable.wvr --toffset 0.0 --nsol 5 --maxdistm 500.0 --minnumants 2 --mingoodfrac 0.8
25-Jul-2023 14:08:56 Calculating the coefficients now ...
25-Jul-2023 14:08:56 done!
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        WVRGCAL  -- Version 2.1
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        Developed by B. Nikolic at the University of Cambridge as part of EU FP6 ALMA Enhancement
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        Maintained and extended since 2013 by the European Southern Observatory as part of the ALMA project
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        GPLv2 License -- you have a right to the source code
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        Will use all WVR SPWs:
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa         0 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        Will produce solutions for all SPWs:
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa         0 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        All WVR data points for antenna 0 are flagged.
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        All WVR data points for antenna 1 are flagged.
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        Choosing (interpolated) antenna 0 == CM01 as reference antenna for dTdL calculations.
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa               Retrieved parameters     
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        ----------------------------------------------------------------
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        Evidence        PWV     PWV Error       dT1dL   dT2dL   dT3dL   dT4dL   
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        1.11897e-21     0.276816        0.00460199      28.7068 13.879  6.28312 4.00843
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        9.72095e-21     0.273349        0.00551861      31.1751 14.292  6.332   4.0184 
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        3.62048e-22     0.276034        0.00646982      31.5963 14.2242 6.23896 3.94681
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        1.86363e-23     0.272148        0.00515628      29.2133 13.8989 6.24173 3.97306
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        5.17486e-23     0.271837        0.00450456      28.8935 13.9358 6.30771 4.02487
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        Times used for the statistics calculation (in seconds from first astro datum)
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        -----------------------------------------------------------------------------
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        (0, 449.28)
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa             Antenna/WVR information:                     
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        -----------------------------------------------------------------------
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        #       Name    WVR?    Flag?   RMS (um)        Disc (um)
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        0       CM01    No      Yes     87.5            83.1
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        1       CM02    No      Yes     87.6            84.1
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        2       DA41    Yes     No      84.7            88.6
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        3       DA42    Yes     No      68.9            66.9
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        4       DV02    Yes     No       529             120
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        5       DV03    Yes     No      92.8            90.8
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        6       DV04    Yes     No        90            90.4
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        7       DV05    Yes     No      89.2             102
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        8       DV06    Yes     No      86.8            84.1
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        9       DV08    Yes     No      87.1            86.6
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        10      DV09    Yes     No      86.3             111
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        11      DV11    Yes     No      90.4             103
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        12      DV12    Yes     No      88.4            85.8
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        13      DV13    Yes     No      86.1            92.9
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        14      DV14    Yes     No      91.2             102
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        15      PM02    Yes     No        86             105
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        16      PM03    Yes     No        89            97.5
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        17      PM04    Yes     No        87            78.9
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa          Expected performance
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        ------------------------------------------------------------------
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        * Estimated WVR thermal contribution to path fluctuations (micron per antenna): 2.67786
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        * Greatest Estimated path fluctuation is (micron on a baseline): 264.694
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        * Rough estimate path error due to coefficient error (micron on a baseline): 1.10365
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        Writing gain table ...
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        Task wvrgcal complete. Start time: 2023-07-25 18:07:34.006581 End time: 2023-07-25 18:08:54.898631
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        ##### End Task: wvrgcal              #####
25-Jul-2023 14:08:56 2023-07-25 18:08:55        INFO        wvrgcal::::casa        ##########################################
25-Jul-2023 14:08:56 .2023-07-25 18:08:56        INFO        wvrgcal::::casa        ##########################################
25-Jul-2023 14:08:56 2023-07-25 18:08:56        INFO        wvrgcal::::casa        ##### Begin Task: wvrgcal            #####
25-Jul-2023 14:08:56 2023-07-25 18:08:56        INFO        wvrgcal::::casa        wvrgcal( vis='myinput.ms', caltable='mycaltable.wvr', toffset=-1.0, segsource=True, sourceflag=[], tie=[], nsol=1, disperse=True, wvrflag=[], statfield='', statsource='', smooth='', scale=1.0, spw=[], wvrspw=[], reversespw='', cont=False, maxdistm=500.0, minnumants=2, mingoodfrac=0.8, usefieldtab=False, refant=[], offsetstable='' )
25-Jul-2023 14:08:56 2023-07-25 18:08:56        INFO        wvrgcal::::casa        Using dispersion table /opt/casa/data/master/alma/wvrgcal/libair-ddefault.csv
25-Jul-2023 14:08:56 2023-07-25 18:08:56        INFO        wvrgcal::::casa        Running /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/almatasks/__bin__/wvrgcal standalone invoked as:
25-Jul-2023 14:10:01 2023-07-25 18:08:56        INFO        wvrgcal::::casa        /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/almatasks/__bin__/wvrgcal --ms myinput.ms --output mycaltable.wvr --toffset -1.0 --segsource --disperse --maxdistm 500.0 --minnumants 2 --mingoodfrac 0.8
25-Jul-2023 14:10:01 Calculating the coefficients now ...
25-Jul-2023 14:10:01 done!
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        WVRGCAL  -- Version 2.1
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        Developed by B. Nikolic at the University of Cambridge as part of EU FP6 ALMA Enhancement
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        Maintained and extended since 2013 by the European Southern Observatory as part of the ALMA project
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        GPLv2 License -- you have a right to the source code
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        Will use all WVR SPWs:
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa         0 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        Will produce solutions for all SPWs:
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa         0 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        All WVR data points for antenna 0 are flagged.
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        All WVR data points for antenna 1 are flagged.
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        Choosing (interpolated) antenna 0 == CM01 as reference antenna for dTdL calculations.
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa               Retrieved parameters     
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        ----------------------------------------------------------------
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        Evidence        PWV     PWV Error       dT1dL   dT2dL   dT3dL   dT4dL   
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        1.11897e-21     0.276816        0.00460199      28.7068 13.879  6.28312 4.00843
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        4.0184e-20      0.273237        0.00571632      31.2569 14.2921 6.32313 4.01096
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        2.29389e-21     0.270434        0.00445918      28.8772 13.9561 6.32564 4.03818
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        2.97538e-23     0.272146        0.0068595       32.3114 14.2838 6.21361 3.92165
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        Times used for the statistics calculation (in seconds from first astro datum)
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        -----------------------------------------------------------------------------
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        (0, 449.28)
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa             Antenna/WVR information:                     
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        -----------------------------------------------------------------------
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        #       Name    WVR?    Flag?   RMS (um)        Disc (um)
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        0       CM01    No      Yes     67.1            46.2
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        1       CM02    No      Yes     67.2            46.1
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        2       DA41    Yes     No      66.9            48.5
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        3       DA42    Yes     No      66.1              49
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        4       DV02    Yes     No       333             171
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        5       DV03    Yes     No      65.7            51.3
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        6       DV04    Yes     No      70.8            49.8
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        7       DV05    Yes     No      65.2            49.3
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        8       DV06    Yes     No      67.4            51.6
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        9       DV08    Yes     No      66.2            48.1
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        10      DV09    Yes     No      65.9            56.1
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        11      DV11    Yes     No        66            50.8
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        12      DV12    Yes     No        66            42.1
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        13      DV13    Yes     No      65.4            56.5
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        14      DV14    Yes     No      64.3            57.6
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        15      PM02    Yes     No      68.1            50.5
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        16      PM03    Yes     No      67.6              50
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        17      PM04    Yes     No      66.8            52.1
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa          Expected performance
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        ------------------------------------------------------------------
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        * Estimated WVR thermal contribution to path fluctuations (micron per antenna): 2.86196
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        * Greatest Estimated path fluctuation is (micron on a baseline): 140.377
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        * Rough estimate path error due to coefficient error (micron on a baseline): 0.279781
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        Writing gain table ...
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        Task wvrgcal complete. Start time: 2023-07-25 18:08:56.056022 End time: 2023-07-25 18:09:59.990009
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        ##### End Task: wvrgcal              #####
25-Jul-2023 14:10:01 2023-07-25 18:10:00        INFO        wvrgcal::::casa        ##########################################
25-Jul-2023 14:10:01 .2023-07-25 18:10:01        INFO        flagdata::::casa        ##########################################
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        flagdata::::casa        flagdata( vis='myinput.ms', mode='manual', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='0', field='', antenna='', uvrange='', timerange='', correlation='', scan='', intent='', array='', observation='', feed='', clipminmax=[], datacolumn='DATA', clipoutside=True, channelavg=False, chanbin=1, timeavg=False, timebin='0s', clipzeros=False, quackinterval=1.0, quackmode='beg', quackincrement=False, tolerance=0.0, addantenna='', lowerlimit=0.0, upperlimit=90.0, ntime='scan', combinescans=False, timecutoff=4.0, freqcutoff=3.0, timefit='line', freqfit='poly', maxnpieces=7, flagdimension='freqtime', usewindowstats='none', halfwin=1, extendflags=True, winsize=3, timedev='', freqdev='', timedevscale=5.0, freqdevscale=5.0, spectralmax=1000000.0, spectralmin=0.0, antint_ref_antenna='', minchanfrac=0.6, verbose=False, extendpols=True, growtime=50.0, growfreq=50.0, growaround=False, flagneartime=False, flagnearfreq=False, minrel=0.0, maxrel=1.0, minabs=0, maxabs=-1, spwchan=False, spwcorr=False, basecnt=False, fieldcnt=False, name='Summary', action='apply', display='', flagbackup=True, savepars=False, cmdreason='', outfile='', overwrite=True, writeflags=True )
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        flagdata::::casa        Backup original flags before applying new flags
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        AgentFlagger::open        Table type is Measurement Set
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        FlagVersion::saveFlagVersion        Creating new backup flag file called flagdata_1
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        AgentFlagger::open        Table type is Measurement Set
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        flagdata::::casa        Manual mode is active
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        FlagMSHandler::selectData +        [0, 0, 3, 1]
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        flagdata::::casa        Initializing the agents
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: manual
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        Manual::setAgentParameters         autocorr is 0
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: manual
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        flagdata::::casa        Running the agentflagger tool
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        FlagMSHandler::        Chunk = 1 [progress: 26%], Observation = 0, Array = 0, Scan = 1, Field = 0 (0132-169), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 162
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        Manual::chunkSummary        => Data flagged so far 100% (648/648)
25-Jul-2023 14:10:01 2023-07-25 18:10:01        INFO        FlagMSHandler::        Chunk = 2 [progress: 50%], Observation = 0, Array = 0, Scan = 2, Field = 1 (0455-462), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 144
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        Manual::chunkSummary        => Data flagged so far 100% (576/576)
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        FlagMSHandler::        Chunk = 3 [progress: 76%], Observation = 0, Array = 0, Scan = 3, Field = 2 (0530+135), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 162
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        Manual::chunkSummary        => Data flagged so far 100% (648/648)
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        FlagMSHandler::        Chunk = 4 [progress: 100%], Observation = 0, Array = 0, Scan = 4, Field = 3 (0334-401), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 144
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        Manual::chunkSummary        => Data flagged so far 100% (576/576)
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        FlagMSHandler::nextChunk         ====================================================================================
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        Manual::tableSummary        => Percentage of data flagged in table selection: 100%
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        AgentFlagger::run        => Writing flags to the MS
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        flagdata::::casa        Task flagdata complete. Start time: 2023-07-25 18:10:01.142138 End time: 2023-07-25 18:10:01.244990
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        flagdata::::casa        ##### End Task: flagdata             #####
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        flagdata::::casa        ##########################################
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        ##########################################
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        ##### Begin Task: wvrgcal            #####
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        wvrgcal( vis='myinput.ms', caltable='mycaltable.wvr', toffset=-1.0, segsource=True, sourceflag=[], tie=[], nsol=1, disperse=True, wvrflag=[], statfield='', statsource='', smooth='', scale=1.0, spw=[], wvrspw=[], reversespw='', cont=False, maxdistm=500.0, minnumants=2, mingoodfrac=0.8, usefieldtab=False, refant=[], offsetstable='' )
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        Using dispersion table /opt/casa/data/master/alma/wvrgcal/libair-ddefault.csv
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        Running /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/almatasks/__bin__/wvrgcal standalone invoked as:
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/almatasks/__bin__/wvrgcal --ms myinput.ms --output mycaltable.wvr --toffset -1.0 --segsource --disperse --maxdistm 500.0 --minnumants 2 --mingoodfrac 0.8
25-Jul-2023 14:10:02 terminate called after throwing an instance of 'LibAIR2::MSInputDataError'
25-Jul-2023 14:10:02   what():  There is an error in the supplied input data which prevents the calculation of the calibration: Didn't find any WVR data points
25-Jul-2023 14:10:02 sh: line 1:   272 Aborted                 (core dumped) /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/almatasks/__bin__/wvrgcal --ms myinput.ms --output mycaltable.wvr --toffset -1.0 --segsource --disperse --maxdistm 500.0 --minnumants 2 --mingoodfrac 0.8 > wvrgcal_tmp_71841985
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        WVRGCAL  -- Version 2.1
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        Developed by B. Nikolic at the University of Cambridge as part of EU FP6 ALMA Enhancement
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        Maintained and extended since 2013 by the European Southern Observatory as part of the ALMA project
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        GPLv2 License -- you have a right to the source code
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        Will use all WVR SPWs:
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa         0 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        Will produce solutions for all SPWs:
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa         0 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26
25-Jul-2023 14:10:02 2023-07-25 18:10:01        WARN        wvrgcal::::casa        /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/almatasks/__bin__/wvrgcal terminated with exit status 134
25-Jul-2023 14:10:02 2023-07-25 18:10:01        SEVERE        wvrgcal::::casa        No useful input data.
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        Task wvrgcal complete. Start time: 2023-07-25 18:10:01.247047 End time: 2023-07-25 18:10:01.321871
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        ##### End Task: wvrgcal              #####
25-Jul-2023 14:10:02 2023-07-25 18:10:01        INFO        wvrgcal::::casa        ##########################################
25-Jul-2023 14:10:02 .2023-07-25 18:10:02        INFO        flagdata::::casa        ##########################################
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        flagdata::::casa        flagdata( vis='myinput.ms', mode='manual', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='', field='', antenna='DV14&&*', uvrange='', timerange='09:10:11~09:10:15', correlation='', scan='', intent='', array='', observation='', feed='', clipminmax=[], datacolumn='DATA', clipoutside=True, channelavg=False, chanbin=1, timeavg=False, timebin='0s', clipzeros=False, quackinterval=1.0, quackmode='beg', quackincrement=False, tolerance=0.0, addantenna='', lowerlimit=0.0, upperlimit=90.0, ntime='scan', combinescans=False, timecutoff=4.0, freqcutoff=3.0, timefit='line', freqfit='poly', maxnpieces=7, flagdimension='freqtime', usewindowstats='none', halfwin=1, extendflags=True, winsize=3, timedev='', freqdev='', timedevscale=5.0, freqdevscale=5.0, spectralmax=1000000.0, spectralmin=0.0, antint_ref_antenna='', minchanfrac=0.6, verbose=False, extendpols=True, growtime=50.0, growfreq=50.0, growaround=False, flagneartime=False, flagnearfreq=False, minrel=0.0, maxrel=1.0, minabs=0, maxabs=-1, spwchan=False, spwcorr=False, basecnt=False, fieldcnt=False, name='Summary', action='apply', display='', flagbackup=True, savepars=False, cmdreason='', outfile='', overwrite=True, writeflags=True )
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        flagdata::::casa        Backup original flags before applying new flags
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        AgentFlagger::open        Table type is Measurement Set
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        FlagVersion::saveFlagVersion        Creating new backup flag file called flagdata_1
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        AgentFlagger::open        Table type is Measurement Set
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        flagdata::::casa        Manual mode is active
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        flagdata::::casa        Initializing the agents
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: manual
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        Manual::setAgentParameters         autocorr is 0
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: manual
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        flagdata::::casa        Running the agentflagger tool
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        FlagMSHandler::        Chunk = 1 [progress: 100%], Observation = 0, Array = 0, Scan = 1, Field = 0 (0132-169), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 3
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        Manual::chunkSummary        => Data flagged so far 100% (12/12)
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        FlagMSHandler::nextChunk         ====================================================================================
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        Manual::tableSummary        => Percentage of data flagged in table selection: 100%
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        AgentFlagger::run        => Writing flags to the MS
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        flagdata::::casa        Task flagdata complete. Start time: 2023-07-25 18:10:02.315750 End time: 2023-07-25 18:10:02.439953
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        flagdata::::casa        ##### End Task: flagdata             #####
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        flagdata::::casa        ##########################################
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        split::::casa        ##########################################
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        split::::casa        ##### Begin Task: split              #####
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        split::::casa        split( vis='myinput.ms', outputvis='myinput2.ms', keepmms=True, field='', spw='', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='data', keepflags=False, width=1, timebin='0s', combine='' )
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        MSTransformManager::parseMsSpecParams        Input file name is myinput.ms
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        MSTransformManager::parseMsSpecParams        Data column is DATA
25-Jul-2023 14:10:02 2023-07-25 18:10:02        INFO        MSTransformManager::parseMsSpecParams        Output file name is myinput2.ms
25-Jul-2023 14:11:07 2023-07-25 18:10:02        INFO        MSTransformManager::colCheckInfo        Adding DATA column to output MS from input DATA column
25-Jul-2023 14:11:07 2023-07-25 18:10:02        INFO        MSTransformManager::open        Select data
25-Jul-2023 14:11:07 2023-07-25 18:10:02        INFO        MSTransformDataHandler::selectSpw        The following a priori selected input spw(s)
25-Jul-2023 14:11:07 2023-07-25 18:10:02        INFO        MSTransformDataHandler::selectSpw+        9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26
25-Jul-2023 14:11:07 2023-07-25 18:10:02        INFO        MSTransformDataHandler::selectSpw+        were not found in DATA_DESCRIPTION (i. e. no rows in the main table reference them) and therefore are not included to the output.
25-Jul-2023 14:11:07 2023-07-25 18:10:02        INFO        MSTransformManager::createOutputMSStructure        Create output MS structure
25-Jul-2023 14:11:07 2023-07-25 18:10:03        INFO        MSTransformDataHandler::makeSelection        609 out of 612 rows are going to be considered due to the selection criteria.
25-Jul-2023 14:11:07 2023-07-25 18:10:03        INFO        split::::casa        Task split complete. Start time: 2023-07-25 18:10:02.460377 End time: 2023-07-25 18:10:03.446019
25-Jul-2023 14:11:07 2023-07-25 18:10:03        INFO        split::::casa        ##### End Task: split                #####
25-Jul-2023 14:11:07 2023-07-25 18:10:03        INFO        split::::casa        ##########################################
25-Jul-2023 14:11:07 2023-07-25 18:10:03        INFO        wvrgcal::::casa        ##########################################
25-Jul-2023 14:11:07 2023-07-25 18:10:03        INFO        wvrgcal::::casa        ##### Begin Task: wvrgcal            #####
25-Jul-2023 14:11:07 2023-07-25 18:10:03        INFO        wvrgcal::::casa        wvrgcal( vis='myinput.ms', caltable='comp.W', toffset=0.0, segsource=True, sourceflag=[], tie=[], nsol=1, disperse=False, wvrflag=[], statfield='', statsource='', smooth='', scale=1.0, spw=[], wvrspw=[], reversespw='', cont=False, maxdistm=500.0, minnumants=2, mingoodfrac=0.8, usefieldtab=False, refant=[], offsetstable='' )
25-Jul-2023 14:11:07 2023-07-25 18:10:03        INFO        wvrgcal::::casa        Running /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/almatasks/__bin__/wvrgcal standalone invoked as:
25-Jul-2023 14:11:07 2023-07-25 18:10:03        INFO        wvrgcal::::casa        /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/almatasks/__bin__/wvrgcal --ms myinput.ms --output comp.W --toffset 0.0 --segsource --maxdistm 500.0 --minnumants 2 --mingoodfrac 0.8
25-Jul-2023 14:11:07 Calculating the coefficients now ...
25-Jul-2023 14:11:07 done!
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        WVRGCAL  -- Version 2.1
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        Developed by B. Nikolic at the University of Cambridge as part of EU FP6 ALMA Enhancement
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        Maintained and extended since 2013 by the European Southern Observatory as part of the ALMA project
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        GPLv2 License -- you have a right to the source code
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        Will use all WVR SPWs:
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa         0 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        Will produce solutions for all SPWs:
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa         0 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        All WVR data points for antenna 0 are flagged.
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        All WVR data points for antenna 1 are flagged.
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        Choosing (interpolated) antenna 0 == CM01 as reference antenna for dTdL calculations.
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa               Retrieved parameters     
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        ----------------------------------------------------------------
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        Evidence        PWV     PWV Error       dT1dL   dT2dL   dT3dL   dT4dL   
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        1.36063e-21     0.276254        0.00465181      28.7263 13.8802 6.28209 4.00758
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        4.2929e-22      0.274038        0.00588576      31.3997 14.2692 6.29146 3.98658
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        4.63014e-24     0.272522        0.00491532      29.1304 13.8873 6.24293 3.97518
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        5.88889e-21     0.2683  0.0056288       31.7198 14.3809 6.34222 4.02018
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        Times used for the statistics calculation (in seconds from first astro datum)
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        -----------------------------------------------------------------------------
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        (0, 449.28)
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa             Antenna/WVR information:                     
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        -----------------------------------------------------------------------
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        #       Name    WVR?    Flag?   RMS (um)        Disc (um)
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        0       CM01    No      Yes     59.2            42.5
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        1       CM02    No      Yes     59.3            43.4
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        2       DA41    Yes     No      58.4            53.9
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        3       DA42    Yes     No        60            44.3
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        4       DV02    Yes     No       338            91.7
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        5       DV03    Yes     No      58.2            52.6
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        6       DV04    Yes     No      61.9            50.8
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        7       DV05    Yes     No      58.5              58
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        8       DV06    Yes     No      59.5            41.2
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        9       DV08    Yes     No      58.6            46.8
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        10      DV09    Yes     No      57.5              72
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        11      DV11    Yes     No      57.4            61.2
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        12      DV12    Yes     No      58.2            45.1
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        13      DV13    Yes     No        57            56.1
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        14      DV14    Yes     No      55.6            64.3
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        15      PM02    Yes     No      59.3              65
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        16      PM03    Yes     No      58.9            54.3
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        17      PM04    Yes     No      58.9            41.6
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa          Expected performance
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        ------------------------------------------------------------------
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        * Estimated WVR thermal contribution to path fluctuations (micron per antenna): 2.85095
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        * Greatest Estimated path fluctuation is (micron on a baseline): 144.449
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        * Rough estimate path error due to coefficient error (micron on a baseline): 0.471086
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        Writing gain table ...
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        Task wvrgcal complete. Start time: 2023-07-25 18:10:03.449865 End time: 2023-07-25 18:11:07.364635
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        ##### End Task: wvrgcal              #####
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        ##########################################
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        ##########################################
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        ##### Begin Task: wvrgcal            #####
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        wvrgcal( vis='myinput2.ms', caltable='comp2.W', toffset=0.0, segsource=True, sourceflag=[], tie=[], nsol=1, disperse=False, wvrflag=[], statfield='', statsource='', smooth='', scale=1.0, spw=[], wvrspw=[], reversespw='', cont=False, maxdistm=500.0, minnumants=2, mingoodfrac=0.8, usefieldtab=False, refant=[], offsetstable='' )
25-Jul-2023 14:11:07 2023-07-25 18:11:07        INFO        wvrgcal::::casa        Running /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/almatasks/__bin__/wvrgcal standalone invoked as:
25-Jul-2023 14:12:57
Request to stop 'CASA-REC6MT-TSGC2014PY38-1' received from awells
25-Jul-2023 14:12:58 Failing task since return code of [/export/home/cbt-el7-2/bamboohome/temp/CASA-REC6MT-TSGC2014PY38-1-ScriptBuildTask-2101132708556825693.sh] was 143 while expected 0
25-Jul-2023 14:12:58 Finished task 'Run plotms, imview, msview, wvrgcal, casampi tests' with result: Failed
25-Jul-2023 14:12:58 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
25-Jul-2023 14:12:58
Beginning to execute external process for build 'CASA - Release Engineering Casa 6 Master Test - Test Casalith Many Linux 2014 #1 (CASA-REC6MT-TSGC2014PY38-1)'
... running command line:
/export/home/cbt-el7-2/bamboohome/temp/CASA-REC6MT-TSGC2014PY38-1-ScriptBuildTask-3239986464923710466.sh
... in: /export/home/cbt-el7-2/bamboohome/xml-data/build-dir/CASA-REC6MT-TSGC2014PY38
25-Jul-2023 14:12:58 Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.
25-Jul-2023 14:12:58 Attempting to generate stack trace and terminate spawned sub-processes of process id: 1862
25-Jul-2023 14:12:58 Starting cleanup
25-Jul-2023 14:12:58 Found related process: pid: 26520 ppid: 1862 pgid: 1825 %cpu: 0.0 %mem: 0.0 cmd: /bin/sh
25-Jul-2023 14:12:58 Found related process: pid: 26527 ppid: 26520 pgid: 1825 %cpu: 0.0 %mem: 0.0 cmd: docker
25-Jul-2023 14:12:58 Found related process: pid: 25735 ppid: 1 pgid: 1825 %cpu: 0.0 %mem: 0.0 cmd: docker
25-Jul-2023 14:12:58 getStackTraceAndKillRelatedProcesses for 3 processes
25-Jul-2023 14:12:58 Executing kill -3 25735
25-Jul-2023 14:12:58 Executing kill -3 26527
25-Jul-2023 14:12:58 2023-07-25 18:11:07        INFO        wvrgcal::::casa        /home/casatest/work/pkg/casa-6.6.0-3-py3.8.el7/lib/py/lib/python3.8/site-packages/almatasks/__bin__/wvrgcal --ms myinput2.ms --output comp2.W --toffset 0.0 --segsource --maxdistm 500.0 --minnumants 2 --mingoodfrac 0.8
25-Jul-2023 14:12:58 SIGQUIT: quit
25-Jul-2023 14:12:58 PC=0x556ced4b30ee m=0 sigcode=0
25-Jul-2023 14:12:58
25-Jul-2023 14:12:58 goroutine 1 [syscall]:
25-Jul-2023 14:12:58 syscall.Syscall6(0xc000448a50?, 0x21?, 0x2f6e556ced4c6670?, 0x16?, 0x1?, 0x20?, 0x7f4cb901f188?)
25-Jul-2023 14:12:58         /usr/local/go/src/syscall/syscall_linux.go:91 +0x36 fp=0xc00059fb58 sp=0xc00059fad0 pc=0x556ced5339b6
25-Jul-2023 14:12:58 syscall.openat(0x170?, {0xc000448a50?, 0x6ceed3ace0?}, 0x7f4c8eb94138?, 0x0)
25-Jul-2023 14:12:58         /usr/local/go/src/syscall/zsyscall_linux_amd64.go:83 +0x94 fp=0xc00059fbd0 sp=0xc00059fb58 pc=0x556ced5304b4
25-Jul-2023 14:12:58 syscall.Open(...)
25-Jul-2023 14:12:58         /usr/local/go/src/syscall/syscall_linux.go:272
25-Jul-2023 14:12:58 os.openFileNolog({0xc000448a50, 0x21}, 0x0, 0x0)
25-Jul-2023 14:12:58         /usr/local/go/src/os/file_unix.go:245 +0x9b fp=0xc00059fc18 sp=0xc00059fbd0 pc=0x556ced5681fb
25-Jul-2023 14:12:58 os.OpenFile({0xc000448a50, 0x21}, 0x0, 0x5?)
25-Jul-2023 14:12:58         /usr/local/go/src/os/file.go:326 +0x45 fp=0xc00059fc50 sp=0xc00059fc18 pc=0x556ced565f65
25-Jul-2023 14:12:58 os.Open(...)
25-Jul-2023 14:12:58         /usr/local/go/src/os/file.go:306
25-Jul-2023 14:12:58 github.com/docker/cli/cli/config.load({0xc00011a108?, 0x556cedf4d723?})
25-Jul-2023 14:12:58         /go/src/github.com/docker/cli/cli/config/config.go:118 +0x1d3 fp=0xc00059fd40 sp=0xc00059fc50 pc=0x556ced604e33
25-Jul-2023 14:12:58 github.com/docker/cli/cli/config.LoadDefaultConfigFile({0x556cee56cae0, 0xc000014020})
25-Jul-2023 14:12:58         /go/src/github.com/docker/cli/cli/config/config.go:142 +0x51 fp=0xc00059fda8 sp=0xc00059fd40 pc=0x556ced605151
25-Jul-2023 14:12:58 github.com/docker/cli/cli/command.(*DockerCli).Initialize(0xc0003980f0, 0xc0004000c0, {0x0, 0x0, 0xc000004300?})
25-Jul-2023 14:12:58         /go/src/github.com/docker/cli/cli/command/cli.go:231 +0x1a5 fp=0xc00059fdf8 sp=0xc00059fda8 pc=0x556cedabe865
25-Jul-2023 14:12:58 github.com/docker/cli/cli.(*TopLevelCommand).Initialize(0xc00042fec0, {0x0, 0x0, 0x0})
25-Jul-2023 14:12:58         /go/src/github.com/docker/cli/cli/cobra.go:185 +0x5c fp=0xc00059fe30 sp=0xc00059fdf8 pc=0x556cedac937c
25-Jul-2023 14:12:58 main.runDocker(0x0?)
25-Jul-2023 14:12:58         /go/src/github.com/docker/cli/cmd/docker/docker.go:227 +0x7c fp=0xc00059ff08 sp=0xc00059fe30 pc=0x556cedf46f7c
25-Jul-2023 14:12:58 main.main()
25-Jul-2023 14:12:58         /go/src/github.com/docker/cli/cmd/docker/docker.go:274 +0x97 fp=0xc00059ff80 sp=0xc00059ff08 pc=0x556cedf474d7
25-Jul-2023 14:12:58 runtime.main()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/proc.go:250 +0x212 fp=0xc00059ffe0 sp=0xc00059ff80 pc=0x556ced4eafb2
25-Jul-2023 14:12:58 runtime.goexit()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc00059ffe8 sp=0xc00059ffe0 pc=0x556ced51c5c1
25-Jul-2023 14:12:58 Executing kill -3 26520
25-Jul-2023 14:12:58
25-Jul-2023 14:12:58 goroutine 2 [force gc (idle)]:
25-Jul-2023 14:12:58 runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?)
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc00006cfb0 sp=0xc00006cf90 pc=0x556ced4eb3f6
25-Jul-2023 14:12:58 runtime.goparkunlock(...)
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/proc.go:387
25-Jul-2023 14:12:58 runtime.forcegchelper()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/proc.go:305 +0xb0 fp=0xc00006cfe0 sp=0xc00006cfb0 pc=0x556ced4eb230
25-Jul-2023 14:12:58 runtime.goexit()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc00006cfe8 sp=0xc00006cfe0 pc=0x556ced51c5c1
25-Jul-2023 14:12:58 created by runtime.init.6
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/proc.go:293 +0x25
25-Jul-2023 14:12:58
25-Jul-2023 14:12:58 goroutine 3 [GC sweep wait]:
25-Jul-2023 14:12:58 runtime.gopark(0x1?, 0x0?, 0x0?, 0x0?, 0x0?)
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc00006d780 sp=0xc00006d760 pc=0x556ced4eb3f6
25-Jul-2023 14:12:58 runtime.goparkunlock(...)
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/proc.go:387
25-Jul-2023 14:12:58 runtime.bgsweep(0x0?)
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgcsweep.go:319 +0xde fp=0xc00006d7c8 sp=0xc00006d780 pc=0x556ced4d647e
25-Jul-2023 14:12:58 runtime.gcenable.func1()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgc.go:178 +0x26 fp=0xc00006d7e0 sp=0xc00006d7c8 pc=0x556ced4cb6e6
25-Jul-2023 14:12:58 runtime.goexit()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc00006d7e8 sp=0xc00006d7e0 pc=0x556ced51c5c1
25-Jul-2023 14:12:58 created by runtime.gcenable
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgc.go:178 +0x6b
25-Jul-2023 14:12:58
25-Jul-2023 14:12:58 goroutine 4 [GC scavenge wait]:
25-Jul-2023 14:12:58 runtime.gopark(0xc00004e4d0?, 0x556cee10ac08?, 0x0?, 0x0?, 0x0?)
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc00006df70 sp=0xc00006df50 pc=0x556ced4eb3f6
25-Jul-2023 14:12:58 runtime.goparkunlock(...)
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/proc.go:387
25-Jul-2023 14:12:58 runtime.(*scavengerState).park(0x556ceed3a340)
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgcscavenge.go:400 +0x53 fp=0xc00006dfa0 sp=0xc00006df70 pc=0x556ced4d4353
25-Jul-2023 14:12:58 runtime.bgscavenge(0x0?)
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgcscavenge.go:633 +0x65 fp=0xc00006dfc8 sp=0xc00006dfa0 pc=0x556ced4d4945
25-Jul-2023 14:12:58 runtime.gcenable.func2()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgc.go:179 +0x26 fp=0xc00006dfe0 sp=0xc00006dfc8 pc=0x556ced4cb686
25-Jul-2023 14:12:58 runtime.goexit()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc00006dfe8 sp=0xc00006dfe0 pc=0x556ced51c5c1
25-Jul-2023 14:12:58 created by runtime.gcenable
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgc.go:179 +0xaa
25-Jul-2023 14:12:58
25-Jul-2023 14:12:58 goroutine 5 [finalizer wait]:
25-Jul-2023 14:12:58 runtime.gopark(0x1a0?, 0x556ceed3ace0?, 0x60?, 0x78?, 0xc00006c770?)
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc00006c628 sp=0xc00006c608 pc=0x556ced4eb3f6
25-Jul-2023 14:12:58 runtime.runfinq()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mfinal.go:193 +0x107 fp=0xc00006c7e0 sp=0xc00006c628 pc=0x556ced4ca707
25-Jul-2023 14:12:58 runtime.goexit()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc00006c7e8 sp=0xc00006c7e0 pc=0x556ced51c5c1
25-Jul-2023 14:12:58 created by runtime.createfing
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mfinal.go:163 +0x45
25-Jul-2023 14:12:58
25-Jul-2023 14:12:58 goroutine 34 [GC worker (idle)]:
25-Jul-2023 14:12:58 runtime.gopark(0x556ced9c4395?, 0x556ced4b635d?, 0xc0?, 0xe3?, 0xc00006e7a8?)
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc00006e750 sp=0xc00006e730 pc=0x556ced4eb3f6
25-Jul-2023 14:12:58 runtime.gcBgMarkWorker()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgc.go:1275 +0xf1 fp=0xc00006e7e0 sp=0xc00006e750 pc=0x556ced4cd451
25-Jul-2023 14:12:58 runtime.goexit()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc00006e7e8 sp=0xc00006e7e0 pc=0x556ced51c5c1
25-Jul-2023 14:12:58 created by runtime.gcBgMarkStartWorkers
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgc.go:1199 +0x25
25-Jul-2023 14:12:58
25-Jul-2023 14:12:58 goroutine 35 [GC worker (idle)]:
25-Jul-2023 14:12:58 runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?)
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc000068750 sp=0xc000068730 pc=0x556ced4eb3f6
25-Jul-2023 14:12:58 runtime.gcBgMarkWorker()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgc.go:1275 +0xf1 fp=0xc0000687e0 sp=0xc000068750 pc=0x556ced4cd451
25-Jul-2023 14:12:58 runtime.goexit()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc0000687e8 sp=0xc0000687e0 pc=0x556ced51c5c1
25-Jul-2023 14:12:58 created by runtime.gcBgMarkStartWorkers
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgc.go:1199 +0x25
25-Jul-2023 14:12:58
25-Jul-2023 14:12:58 goroutine 19 [GC worker (idle)]:
25-Jul-2023 14:12:58 runtime.gopark(0x10f6062d0019d6?, 0x0?, 0x0?, 0x0?, 0x0?)
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc000134750 sp=0xc000134730 pc=0x556ced4eb3f6
25-Jul-2023 14:12:58 runtime.gcBgMarkWorker()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgc.go:1275 +0xf1 fp=0xc0001347e0 sp=0xc000134750 pc=0x556ced4cd451
25-Jul-2023 14:12:58 runtime.goexit()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc0001347e8 sp=0xc0001347e0 pc=0x556ced51c5c1
25-Jul-2023 14:12:58 created by runtime.gcBgMarkStartWorkers
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgc.go:1199 +0x25
25-Jul-2023 14:12:58
25-Jul-2023 14:12:58 goroutine 36 [GC worker (idle)]:
25-Jul-2023 14:12:58 runtime.gopark(0x10f6062cfcfddf?, 0x0?, 0x0?, 0x0?, 0x0?)
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/proc.go:381 +0xd6 fp=0xc000068f50 sp=0xc000068f30 pc=0x556ced4eb3f6
25-Jul-2023 14:12:58 runtime.gcBgMarkWorker()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgc.go:1275 +0xf1 fp=0xc000068fe0 sp=0xc000068f50 pc=0x556ced4cd451
25-Jul-2023 14:12:58 runtime.goexit()
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/asm_amd64.s:1598 +0x1 fp=0xc000068fe8 sp=0xc000068fe0 pc=0x556ced51c5c1
25-Jul-2023 14:12:58 created by runtime.gcBgMarkStartWorkers
25-Jul-2023 14:12:58         /usr/local/go/src/runtime/mgc.go:1199 +0x25
25-Jul-2023 14:12:58
25-Jul-2023 14:12:58 rax    0x3
25-Jul-2023 14:12:58 rbx    0xffffffffffffff9c
25-Jul-2023 14:12:58 rcx    0xffffffffffffffff
25-Jul-2023 14:12:58 rdx    0x80000
25-Jul-2023 14:12:58 rdi    0xffffffffffffff9c
25-Jul-2023 14:12:58 rsi    0xc000448a80
25-Jul-2023 14:12:58 rbp    0xc00059fac0
25-Jul-2023 14:12:58 rsp    0xc00059fa80
25-Jul-2023 14:12:58 r8     0x0
25-Jul-2023 14:12:58 r9     0x0
25-Jul-2023 14:12:58 r10    0x0
25-Jul-2023 14:12:58 r11    0x206
25-Jul-2023 14:12:58 r12    0x0
25-Jul-2023 14:12:58 r13    0x39
25-Jul-2023 14:12:58 r14    0xc0000061a0
25-Jul-2023 14:12:58 r15    0x556ceed3ace0
25-Jul-2023 14:12:58 rip    0x556ced4b30ee
25-Jul-2023 14:12:58 rflags 0x206
25-Jul-2023 14:12:58 cs     0x33
25-Jul-2023 14:12:58 fs     0x0
25-Jul-2023 14:12:58 gs     0x0
25-Jul-2023 14:12:58 Error response from daemon: You cannot remove a running container fe7a047382e7406af3ee0ebc4a2d28211c47ee49dc3d0672a8029f3c38917dd1. Stop the container before attempting removal or force remove
25-Jul-2023 14:12:58 Cleanup complete
25-Jul-2023 14:12:58 Finished task 'Cleanup' with result: Success
25-Jul-2023 14:12:58 Running post build plugin 'Docker Container Cleanup'
25-Jul-2023 14:12:58
Beginning to execute external process for build 'CASA - Release Engineering Casa 6 Master Test - Test Casalith Many Linux 2014 #1 (CASA-REC6MT-TSGC2014PY38-1)'
... running command line:
/bin/docker rm -f test-container
... in: /export/home/cbt-el7-2/bamboohome/xml-data/build-dir/CASA-REC6MT-TSGC2014PY38
25-Jul-2023 14:13:01 test-container
25-Jul-2023 14:13:01 Running post build plugin 'NCover Results Collector'
25-Jul-2023 14:13:01 Running post build plugin 'Clover Results Collector'
25-Jul-2023 14:13:01 Running post build plugin 'npm Cache Cleanup'
25-Jul-2023 14:13:01 Running post build plugin 'Artifact Copier'
25-Jul-2023 14:13:01 Finalising the build...
25-Jul-2023 14:13:01 Stopping timer.
25-Jul-2023 14:13:01 Build CASA-REC6MT-TSGC2014PY38-1 completed.
25-Jul-2023 14:13:01 Running on server: post build plugin 'NCover Results Collector'
25-Jul-2023 14:13:01 Running on server: post build plugin 'Build Hanging Detection Configuration'
25-Jul-2023 14:13:01 Running on server: post build plugin 'Clover Delta Calculator'
25-Jul-2023 14:13:01 Running on server: post build plugin 'Maven Dependencies Postprocessor'
25-Jul-2023 14:13:01 All post build plugins have finished
25-Jul-2023 14:13:01 Generating build results summary...
25-Jul-2023 14:13:01 Saving build results to disk...
25-Jul-2023 14:13:01 Store variable context...
25-Jul-2023 14:13:01 Indexing build results...
25-Jul-2023 14:13:01 Finished building CASA-REC6MT-TSGC2014PY38-1.
25-Jul-2023 14:13:03 Killing: 25735
25-Jul-2023 14:13:03 Executing kill 25735
25-Jul-2023 14:13:03 Killing: 26527
25-Jul-2023 14:13:03 Executing kill 26527
25-Jul-2023 14:13:03 Killing: 26520
25-Jul-2023 14:13:03 Executing kill 26520
25-Jul-2023 14:13:08 Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.
25-Jul-2023 14:13:08 Has finished generating stack trace and terminating spawned sub-processes of process id: 1862