2022-06-24 08:15:11,148 UTC [609408] INFO    esmvalcore._main:473 
______________________________________________________________________
          _____ ____  __  ____     __    _ _____           _
         | ____/ ___||  \/  \ \   / /_ _| |_   _|__   ___ | |
         |  _| \___ \| |\/| |\ \ / / _` | | | |/ _ \ / _ \| |
         | |___ ___) | |  | | \ V / (_| | | | | (_) | (_) | |
         |_____|____/|_|  |_|  \_/ \__,_|_| |_|\___/ \___/|_|
______________________________________________________________________

ESMValTool - Earth System Model Evaluation Tool.

http://www.esmvaltool.org

CORE DEVELOPMENT TEAM AND CONTACTS:
  Birgit Hassler (Co-PI; DLR, Germany - birgit.hassler@dlr.de)
  Alistair Sellar (Co-PI; Met Office, UK - alistair.sellar@metoffice.gov.uk)
  Bouwe Andela (Netherlands eScience Center, The Netherlands - b.andela@esciencecenter.nl)
  Lee de Mora (PML, UK - ledm@pml.ac.uk)
  Niels Drost (Netherlands eScience Center, The Netherlands - n.drost@esciencecenter.nl)
  Veronika Eyring (DLR, Germany - veronika.eyring@dlr.de)
  Bettina Gier (UBremen, Germany - gier@uni-bremen.de)
  Remi Kazeroni (DLR, Germany - remi.kazeroni@dlr.de)
  Nikolay Koldunov (AWI, Germany - nikolay.koldunov@awi.de)
  Axel Lauer (DLR, Germany - axel.lauer@dlr.de)
  Saskia Loosveldt-Tomas (BSC, Spain - saskia.loosveldt@bsc.es)
  Ruth Lorenz (ETH Zurich, Switzerland - ruth.lorenz@env.ethz.ch)
  Benjamin Mueller (LMU, Germany - b.mueller@iggf.geo.uni-muenchen.de)
  Valeriu Predoi (URead, UK - valeriu.predoi@ncas.ac.uk)
  Mattia Righi (DLR, Germany - mattia.righi@dlr.de)
  Manuel Schlund (DLR, Germany - manuel.schlund@dlr.de)
  Breixo Solino Fernandez (DLR, Germany - breixo.solinofernandez@dlr.de)
  Javier Vegas-Regidor (BSC, Spain - javier.vegas@bsc.es)
  Klaus Zimmermann (SMHI, Sweden - klaus.zimmermann@smhi.se)

For further help, please read the documentation at
http://docs.esmvaltool.org. Have fun!

2022-06-24 08:15:11,150 UTC [609408] INFO    esmvalcore._main:474 Package versions
2022-06-24 08:15:11,150 UTC [609408] INFO    esmvalcore._main:475 ----------------
2022-06-24 08:15:11,150 UTC [609408] INFO    esmvalcore._main:476 ESMValCore: 2.5.0
2022-06-24 08:15:11,150 UTC [609408] INFO    esmvalcore._main:478 ESMValTool: 2.5.0
2022-06-24 08:15:11,150 UTC [609408] INFO    esmvalcore._main:479 ----------------
2022-06-24 08:15:11,150 UTC [609408] INFO    esmvalcore._main:480 Using config file /home/b/b309141/config/icon.yml
2022-06-24 08:15:11,150 UTC [609408] INFO    esmvalcore._main:481 Writing program log files to:
/home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/run/main_log.txt
/home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/run/main_log_debug.txt
2022-06-24 08:15:11,151 UTC [609408] INFO    esmvalcore._main:86 Starting the Earth System Model Evaluation Tool at time: 2022-06-24 08:15:11 UTC
2022-06-24 08:15:11,151 UTC [609408] INFO    esmvalcore._main:90 ----------------------------------------------------------------------
2022-06-24 08:15:11,151 UTC [609408] INFO    esmvalcore._main:91 RECIPE   = /home/b/b309141/recipes/recipe_icon_preprocs.yml
2022-06-24 08:15:11,151 UTC [609408] INFO    esmvalcore._main:92 RUNDIR     = /home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/run
2022-06-24 08:15:11,151 UTC [609408] INFO    esmvalcore._main:93 WORKDIR    = /home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/work
2022-06-24 08:15:11,151 UTC [609408] INFO    esmvalcore._main:94 PREPROCDIR = /home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/preproc
2022-06-24 08:15:11,151 UTC [609408] INFO    esmvalcore._main:95 PLOTDIR    = /home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/plots
2022-06-24 08:15:11,151 UTC [609408] INFO    esmvalcore._main:96 ----------------------------------------------------------------------
2022-06-24 08:15:11,151 UTC [609408] INFO    esmvalcore._main:100 Running tasks using at most 16 processes
2022-06-24 08:15:11,151 UTC [609408] INFO    esmvalcore._main:102 If your system hangs during execution, it may not have enough memory for keeping this number of tasks in memory.
2022-06-24 08:15:11,152 UTC [609408] INFO    esmvalcore._main:105 If you experience memory problems, try reducing 'max_parallel_tasks' in your user configuration file.
2022-06-24 08:15:11,155 UTC [609408] DEBUG   esmvalcore._recipe_checks:47 Checking recipe against schema /home/b/b309141/repos/ESMValCore/esmvalcore/recipe_schema.yml
2022-06-24 08:15:11,167 UTC [609408] DEBUG   esmvalcore._recipe:1342 Retrieving diagnostics from recipe
2022-06-24 08:15:11,167 UTC [609408] DEBUG   esmvalcore._recipe:1479 Populating list of variables for diagnostic test
2022-06-24 08:15:11,168 UTC [609408] DEBUG   esmvalcore._config._config:48 Loading extra facets from /home/b/b309141/repos/ESMValCore/esmvalcore/_config/extra_facets/icon-mappings.yml
2022-06-24 08:15:11,170 UTC [609408] INFO    esmvalcore._recipe:1845 Creating tasks from recipe
2022-06-24 08:15:11,170 UTC [609408] INFO    esmvalcore._recipe:1854 Creating tasks for diagnostic test
2022-06-24 08:15:11,170 UTC [609408] INFO    esmvalcore._recipe:1825 Creating preprocessor task test/tas_icon
2022-06-24 08:15:11,171 UTC [609408] INFO    esmvalcore._recipe:1214 Creating preprocessor 'test' task for variable 'ta'
2022-06-24 08:15:11,172 UTC [609408] DEBUG   esmvalcore._recipe:378 For fx variable 'areacella', found table 'fx'
2022-06-24 08:15:11,172 UTC [609408] DEBUG   esmvalcore._data_finder:19 Looking for files matching ['cool014_grid*.nc'] in ['/home/b/b309141/work/icon_eval/data/']
2022-06-24 08:15:11,173 UTC [609408] DEBUG   esmvalcore._recipe:382 Found fx variables 'areacella':
['/home/b/b309141/work/icon_eval/data/cool014_grid.nc']
2022-06-24 08:15:11,173 UTC [609408] DEBUG   esmvalcore._recipe:492 Using fx files for variable ta during step area_statistics: '/home/b/b309141/work/icon_eval/data/cool014_grid.nc'
2022-06-24 08:15:11,174 UTC [609408] DEBUG   esmvalcore._data_finder:19 Looking for files matching ['cool014_atm_3d_ml*.nc'] in ['/home/b/b309141/work/icon_eval/data/']
2022-06-24 08:15:11,175 UTC [609408] DEBUG   esmvalcore._recipe:624 Using input files for variable ta of dataset ICON:
/home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc
2022-06-24 08:15:11,176 UTC [609408] INFO    esmvalcore._recipe:633 Found input files for ICON
2022-06-24 08:15:11,237 UTC [609408] INFO    esmvalcore._recipe:1095 PreprocessingTask test/tas_icon created.
2022-06-24 08:15:11,237 UTC [609408] DEBUG   esmvalcore._recipe:1096 PreprocessingTask test/tas_icon will create the files:
/home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/preproc/test/tas_icon/ICON_cool014_atm_3d_ml_Amon_ta_1979-1980.nc
2022-06-24 08:15:11,237 UTC [609408] INFO    esmvalcore._recipe:1894 These tasks will be executed: test/tas_icon
2022-06-24 08:15:11,238 UTC [609408] DEBUG   esmvalcore._main:122 Recipe summary:
PreprocessingTask: test/tas_icon
order: ['fix_file', 'load', 'fix_metadata', 'concatenate', 'cmor_check_metadata', 'clip_timerange', 'fix_data', 'cmor_check_data', 'add_fx_variables', 'extract_levels', 'area_statistics', 'remove_fx_variables', 'save', 'cleanup']
PreprocessorFile: /home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/preproc/test/tas_icon/ICON_cool014_atm_3d_ml_Amon_ta_1979-1980.nc
{'add_fx_variables': {'check_level': <CheckLevels.DEFAULT: 3>,
                      'fx_variables': {'areacella': {'alias': 'ICON',
                                                     'dataset': 'ICON',
                                                     'diagnostic': 'test',
                                                     'filename': '/home/b/b309141/work/icon_eval/data/cool014_grid.nc',
                                                     'frequency': 'fx',
                                                     'latitude': 'grid_latitude',
                                                     'long_name': 'Grid-Cell '
                                                                  'Area for '
                                                                  'Atmospheric '
                                                                  'Grid '
                                                                  'Variables',
                                                     'longitude': 'grid_longitude',
                                                     'mip': 'fx',
                                                     'modeling_realm': ['atmos',
                                                                        'land'],
                                                     'original_short_name': 'areacella',
                                                     'preprocessor': 'test',
                                                     'project': 'ICON',
                                                     'raw_name': 'cell_area',
                                                     'recipe_dataset_index': 0,
                                                     'short_name': 'areacella',
                                                     'simulation': 'cool014_grid',
                                                     'standard_name': 'cell_area',
                                                     'timerange': '1979/1980',
                                                     'units': 'm2',
                                                     'variable_group': 'areacella'}}},
 'area_statistics': {'operator': 'mean'},
 'cleanup': {'remove': ['/home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/preproc/test/tas_icon/ICON_cool014_atm_3d_ml_Amon_ta_1979-1980_fixed']},
 'clip_timerange': {'timerange': '1979/1980'},
 'cmor_check_data': {'check_level': <CheckLevels.DEFAULT: 3>,
                     'cmor_table': 'ICON',
                     'frequency': 'mon',
                     'mip': 'Amon',
                     'short_name': 'ta'},
 'cmor_check_metadata': {'check_level': <CheckLevels.DEFAULT: 3>,
                         'cmor_table': 'ICON',
                         'frequency': 'mon',
                         'mip': 'Amon',
                         'short_name': 'ta'},
 'concatenate': {},
 'extract_levels': {'coordinate': 'air_pressure',
                    'levels': [100000.0,
                               92500.0,
                               85000.0,
                               70000.0,
                               60000.0,
                               50000.0,
                               40000.0,
                               30000.0,
                               25000.0,
                               20000.0,
                               17000.0,
                               15000.0,
                               13000.0,
                               11500.0,
                               10000.0,
                               9000.0,
                               8000.0,
                               7000.0,
                               5000.0,
                               3000.0,
                               2000.0,
                               1500.0,
                               1000.0,
                               700.0,
                               500.0,
                               300.0,
                               200.0,
                               150.0,
                               100.0,
                               70.0,
                               50.0,
                               40.0,
                               30.0,
                               20.0,
                               15.0,
                               10.0,
                               7.0,
                               5.0,
                               3.0],
                    'scheme': 'linear'},
 'fix_data': {'alias': 'ICON',
              'check_level': <CheckLevels.DEFAULT: 3>,
              'dataset': 'ICON',
              'diagnostic': 'test',
              'filename': '/home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/preproc/test/tas_icon/ICON_cool014_atm_3d_ml_Amon_ta_1979-1980.nc',
              'frequency': 'mon',
              'long_name': 'Air Temperature',
              'mip': 'Amon',
              'modeling_realm': ['atmos'],
              'original_short_name': 'ta',
              'preprocessor': 'test',
              'project': 'ICON',
              'recipe_dataset_index': 0,
              'short_name': 'ta',
              'simulation': 'cool014_atm_3d_ml',
              'standard_name': 'air_temperature',
              'timerange': '1979/1980',
              'units': 'K',
              'variable_group': 'tas_icon'},
 'fix_file': {'alias': 'ICON',
              'dataset': 'ICON',
              'diagnostic': 'test',
              'filename': '/home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/preproc/test/tas_icon/ICON_cool014_atm_3d_ml_Amon_ta_1979-1980.nc',
              'frequency': 'mon',
              'long_name': 'Air Temperature',
              'mip': 'Amon',
              'modeling_realm': ['atmos'],
              'original_short_name': 'ta',
              'output_dir': '/home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/preproc/test/tas_icon/ICON_cool014_atm_3d_ml_Amon_ta_1979-1980_fixed',
              'preprocessor': 'test',
              'project': 'ICON',
              'recipe_dataset_index': 0,
              'short_name': 'ta',
              'simulation': 'cool014_atm_3d_ml',
              'standard_name': 'air_temperature',
              'timerange': '1979/1980',
              'units': 'K',
              'variable_group': 'tas_icon'},
 'fix_metadata': {'alias': 'ICON',
                  'check_level': <CheckLevels.DEFAULT: 3>,
                  'dataset': 'ICON',
                  'diagnostic': 'test',
                  'filename': '/home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/preproc/test/tas_icon/ICON_cool014_atm_3d_ml_Amon_ta_1979-1980.nc',
                  'frequency': 'mon',
                  'long_name': 'Air Temperature',
                  'mip': 'Amon',
                  'modeling_realm': ['atmos'],
                  'original_short_name': 'ta',
                  'preprocessor': 'test',
                  'project': 'ICON',
                  'recipe_dataset_index': 0,
                  'short_name': 'ta',
                  'simulation': 'cool014_atm_3d_ml',
                  'standard_name': 'air_temperature',
                  'timerange': '1979/1980',
                  'units': 'K',
                  'variable_group': 'tas_icon'},
 'load': {'callback': <function concatenate_callback at 0x7fc3f3380670>},
 'remove_fx_variables': {},
 'save': {'compress': False,
          'filename': '/home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/preproc/test/tas_icon/ICON_cool014_atm_3d_ml_Amon_ta_1979-1980.nc'}}
ancestors:
None
2022-06-24 08:15:11,239 UTC [609408] INFO    esmvalcore._task:747 Running 1 tasks using 1 processes
2022-06-24 08:15:11,251 UTC [610436] INFO    esmvalcore._task:260 Starting task test/tas_icon in process [610436]
2022-06-24 08:15:11,254 UTC [610436] DEBUG   esmvalcore.preprocessor:596 Running block ['fix_metadata', 'concatenate', 'cmor_check_metadata', 'clip_timerange', 'fix_data', 'cmor_check_data', 'add_fx_variables', 'extract_levels', 'area_statistics', 'remove_fx_variables']
2022-06-24 08:15:11,254 UTC [610436] DEBUG   esmvalcore.preprocessor:603 Applying single-model steps to PreprocessorFile: /home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/preproc/test/tas_icon/ICON_cool014_atm_3d_ml_Amon_ta_1979-1980.nc
2022-06-24 08:15:11,255 UTC [610436] DEBUG   esmvalcore.preprocessor:343 Running preprocessor step fix_file
2022-06-24 08:15:11,255 UTC [610436] DEBUG   esmvalcore.preprocessor:304 Running preprocessor function 'fix_file' on the data
'/home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc'
with function argument(s)
short_name = 'ta',
mip = 'Amon',
preprocessor = 'test',
variable_group = 'tas_icon',
diagnostic = 'test',
project = 'ICON',
dataset = 'ICON',
simulation = 'cool014_atm_3d_ml',
recipe_dataset_index = 0,
timerange = '1979/1980',
alias = 'ICON',
original_short_name = 'ta',
standard_name = 'air_temperature',
long_name = 'Air Temperature',
units = 'K',
modeling_realm = ['atmos'],
frequency = 'mon',
filename = '/home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/preproc/test/tas_icon/ICON_cool014_atm_3d_ml_Amon_ta_1979-1980.nc',
output_dir = '/home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/preproc/test/tas_icon/ICON_cool014_atm_3d_ml_Amon_ta_1979-1980_fixed'
2022-06-24 08:15:11,258 UTC [610436] DEBUG   esmvalcore.preprocessor:343 Running preprocessor step load
2022-06-24 08:15:11,259 UTC [610436] DEBUG   esmvalcore.preprocessor:304 Running preprocessor function 'load' on the data
'/home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc'
with function argument(s)
callback = <function concatenate_callback at 0x7fc3f3380670>
2022-06-24 08:15:11,259 UTC [610436] DEBUG   esmvalcore.preprocessor._io:139 Loading:
/home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc
2022-06-24 08:15:11,339 UTC [610436] DEBUG   esmvalcore.preprocessor._io:169 Done with loading /home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc
2022-06-24 08:15:11,339 UTC [610436] DEBUG   esmvalcore.preprocessor:343 Running preprocessor step fix_metadata
2022-06-24 08:15:11,348 UTC [610436] DEBUG   esmvalcore.preprocessor:304 Running preprocessor function 'fix_metadata' on the data
[<iris 'Cube' of geometric height at full level center / (m) (height: 47; -- : 20480)>,
 <iris 'Cube' of surface_air_pressure / (Pa) (time: 432; -- : 20480)>,
 <iris 'Cube' of cloud area fraction / (m2 m-2) (time: 432; height: 47; -- : 20480)>,
 <iris 'Cube' of northward_wind / (m s-1) (time: 432; height: 47; -- : 20480)>,
 <iris 'Cube' of air_density / (kg m-3) (time: 432; height: 47; -- : 20480)>,
 <iris 'Cube' of specific cloud water content / (kg kg-1) (time: 432; height: 47; -- : 20480)>,
 <iris 'Cube' of eastward_wind / (m s-1) (time: 432; height: 47; -- : 20480)>,
 <iris 'Cube' of specific cloud ice content / (kg kg-1) (time: 432; height: 47; -- : 20480)>,
 <iris 'Cube' of specific_humidity / (kg kg-1) (time: 432; height: 47; -- : 20480)>,
 <iris 'Cube' of air_temperature / (K) (time: 432; height: 47; -- : 20480)>,
 <iris 'Cube' of relative_humidity / (unknown) (time: 432; height: 47; -- : 20480)>,
 <iris 'Cube' of air_pressure / (Pa) (time: 432; height: 47; -- : 20480)>,
 <iris 'Cube' of omega / (Pa s-1) (time: 432; height: 47; -- : 20480)>]
loaded from original input file(s)
['/home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc']
with function argument(s)
short_name = 'ta',
mip = 'Amon',
preprocessor = 'test',
variable_group = 'tas_icon',
diagnostic = 'test',
project = 'ICON',
dataset = 'ICON',
simulation = 'cool014_atm_3d_ml',
recipe_dataset_index = 0,
timerange = '1979/1980',
alias = 'ICON',
original_short_name = 'ta',
standard_name = 'air_temperature',
long_name = 'Air Temperature',
units = 'K',
modeling_realm = ['atmos'],
frequency = 'mon',
filename = '/home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/preproc/test/tas_icon/ICON_cool014_atm_3d_ml_Amon_ta_1979-1980.nc',
check_level = <CheckLevels.DEFAULT: 3>
2022-06-24 08:15:11,349 UTC [609408] INFO    esmvalcore._task:779 Progress: 1 tasks running, 0 tasks waiting for ancestors, 0/1 done
2022-06-24 08:15:11,352 UTC [610436] DEBUG   esmvalcore.cmor._fixes.icon._base_fixes:115 Using cached ICON grid file '/home/b/b309141/.esmvaltool/cache/icon_grid_0043_R02B04_G.nc'
2022-06-24 08:15:19,861 UTC [610436] DEBUG   esmvalcore.cmor.check:277 There were metadata changes in variable ta:
 Coordinate longitude appears to belong to an unstructured grid. Skipping monotonicity and direction tests.
 Coordinate latitude appears to belong to an unstructured grid. Skipping monotonicity and direction tests.
loaded from file /home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc
2022-06-24 08:15:19,862 UTC [610436] WARNING esmvalcore.cmor.check:259 There were warnings in variable ta:
 Cannot check requested values of 3D coordinate plev since it is not 1D
loaded from file /home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc
2022-06-24 08:15:19,864 UTC [610436] DEBUG   esmvalcore.preprocessor:343 Running preprocessor step concatenate
2022-06-24 08:15:19,865 UTC [610436] DEBUG   esmvalcore.preprocessor:304 Running preprocessor function 'concatenate' on the data
[<iris 'Cube' of air_temperature / (K) (time: 432; model level number: 47; first spatial index for variables stored on an unstructured grid: 20480)>]
loaded from original input file(s)
['/home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc']
with function argument(s)

2022-06-24 08:15:19,865 UTC [610436] DEBUG   esmvalcore.preprocessor:343 Running preprocessor step cmor_check_metadata
2022-06-24 08:15:19,865 UTC [610436] DEBUG   esmvalcore.preprocessor:304 Running preprocessor function 'cmor_check_metadata' on the data
<iris 'Cube' of air_temperature / (K) (time: 432; model level number: 47; first spatial index for variables stored on an unstructured grid: 20480)>
loaded from original input file(s)
['/home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc']
with function argument(s)
cmor_table = 'ICON',
mip = 'Amon',
short_name = 'ta',
frequency = 'mon',
check_level = <CheckLevels.DEFAULT: 3>
2022-06-24 08:15:20,097 UTC [610436] DEBUG   esmvalcore.cmor.check:277 There were metadata changes in variable ta:
 Coordinate longitude appears to belong to an unstructured grid. Skipping monotonicity and direction tests.
 Coordinate latitude appears to belong to an unstructured grid. Skipping monotonicity and direction tests.
loaded from file 
2022-06-24 08:15:20,097 UTC [610436] WARNING esmvalcore.cmor.check:259 There were warnings in variable ta:
 Cannot check requested values of 3D coordinate plev since it is not 1D
loaded from file 
2022-06-24 08:15:20,097 UTC [610436] DEBUG   esmvalcore.preprocessor:343 Running preprocessor step clip_timerange
2022-06-24 08:15:20,098 UTC [610436] DEBUG   esmvalcore.preprocessor:304 Running preprocessor function 'clip_timerange' on the data
<iris 'Cube' of air_temperature / (K) (time: 432; model level number: 47; first spatial index for variables stored on an unstructured grid: 20480)>
loaded from original input file(s)
['/home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc']
with function argument(s)
timerange = '1979/1980'
2022-06-24 08:15:20,386 UTC [610436] DEBUG   esmvalcore.preprocessor:343 Running preprocessor step fix_data
2022-06-24 08:15:20,387 UTC [610436] DEBUG   esmvalcore.preprocessor:304 Running preprocessor function 'fix_data' on the data
<iris 'Cube' of air_temperature / (K) (time: 24; model level number: 47; first spatial index for variables stored on an unstructured grid: 20480)>
loaded from original input file(s)
['/home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc']
with function argument(s)
short_name = 'ta',
mip = 'Amon',
preprocessor = 'test',
variable_group = 'tas_icon',
diagnostic = 'test',
project = 'ICON',
dataset = 'ICON',
simulation = 'cool014_atm_3d_ml',
recipe_dataset_index = 0,
timerange = '1979/1980',
alias = 'ICON',
original_short_name = 'ta',
standard_name = 'air_temperature',
long_name = 'Air Temperature',
units = 'K',
modeling_realm = ['atmos'],
frequency = 'mon',
filename = '/home/b/b309141/scratch/esmvaltool_output/recipe_icon_preprocs_20220624_081510/preproc/test/tas_icon/ICON_cool014_atm_3d_ml_Amon_ta_1979-1980.nc',
check_level = <CheckLevels.DEFAULT: 3>
2022-06-24 08:15:20,387 UTC [610436] DEBUG   esmvalcore.preprocessor:343 Running preprocessor step cmor_check_data
2022-06-24 08:15:20,387 UTC [610436] DEBUG   esmvalcore.preprocessor:304 Running preprocessor function 'cmor_check_data' on the data
<iris 'Cube' of air_temperature / (K) (time: 24; model level number: 47; first spatial index for variables stored on an unstructured grid: 20480)>
loaded from original input file(s)
['/home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc']
with function argument(s)
cmor_table = 'ICON',
mip = 'Amon',
short_name = 'ta',
frequency = 'mon',
check_level = <CheckLevels.DEFAULT: 3>
2022-06-24 08:15:20,388 UTC [610436] DEBUG   esmvalcore.preprocessor:343 Running preprocessor step add_fx_variables
2022-06-24 08:15:20,388 UTC [610436] DEBUG   esmvalcore.preprocessor:304 Running preprocessor function 'add_fx_variables' on the data
<iris 'Cube' of air_temperature / (K) (time: 24; model level number: 47; first spatial index for variables stored on an unstructured grid: 20480)>
loaded from original input file(s)
['/home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc']
with function argument(s)
fx_variables = {'areacella': {'alias': 'ICON',
               'dataset': 'ICON',
               'diagnostic': 'test',
               'filename': '/home/b/b309141/work/icon_eval/data/cool014_grid.nc',
               'frequency': 'fx',
               'latitude': 'grid_latitude',
               'long_name': 'Grid-Cell Area for Atmospheric Grid Variables',
               'longitude': 'grid_longitude',
               'mip': 'fx',
               'modeling_realm': ['atmos', 'land'],
               'original_short_name': 'areacella',
               'preprocessor': 'test',
               'project': 'ICON',
               'raw_name': 'cell_area',
               'recipe_dataset_index': 0,
               'short_name': 'areacella',
               'simulation': 'cool014_grid',
               'standard_name': 'cell_area',
               'timerange': '1979/1980',
               'units': 'm2',
               'variable_group': 'areacella'}},
check_level = <CheckLevels.DEFAULT: 3>
2022-06-24 08:15:20,388 UTC [610436] DEBUG   esmvalcore.preprocessor._io:139 Loading:
/home/b/b309141/work/icon_eval/data/cool014_grid.nc
2022-06-24 08:15:20,528 UTC [610436] DEBUG   esmvalcore.preprocessor._io:169 Done with loading /home/b/b309141/work/icon_eval/data/cool014_grid.nc
2022-06-24 08:15:20,580 UTC [610436] DEBUG   esmvalcore.cmor.check:277 There were metadata changes in variable areacella:
 Coordinate longitude appears to belong to an unstructured grid. Skipping monotonicity and direction tests.
 Coordinate latitude appears to belong to an unstructured grid. Skipping monotonicity and direction tests.
loaded from file /home/b/b309141/work/icon_eval/data/cool014_grid.nc
2022-06-24 08:15:20,582 UTC [610436] DEBUG   esmvalcore.cmor.check:277 There were metadata changes in variable areacella:
 Coordinate longitude appears to belong to an unstructured grid. Skipping monotonicity and direction tests.
 Coordinate latitude appears to belong to an unstructured grid. Skipping monotonicity and direction tests.
loaded from file 
2022-06-24 08:15:20,582 UTC [610436] DEBUG   esmvalcore.preprocessor._ancillary_vars:104 Added areacella as cell measure in cube of ta.
2022-06-24 08:15:20,582 UTC [610436] DEBUG   esmvalcore.preprocessor:343 Running preprocessor step extract_levels
2022-06-24 08:15:20,654 UTC [610436] DEBUG   esmvalcore.preprocessor:304 Running preprocessor function 'extract_levels' on the data
<iris 'Cube' of air_temperature / (K) (time: 24; model level number: 47; first spatial index for variables stored on an unstructured grid: 20480)>
loaded from original input file(s)
['/home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc']
with function argument(s)
levels = [100000.0,
 92500.0,
 85000.0,
 70000.0,
 60000.0,
 50000.0,
 40000.0,
 30000.0,
 25000.0,
 20000.0,
 17000.0,
 15000.0,
 13000.0,
 11500.0,
 10000.0,
 9000.0,
 8000.0,
 7000.0,
 5000.0,
 3000.0,
 2000.0,
 1500.0,
 1000.0,
 700.0,
 500.0,
 300.0,
 200.0,
 150.0,
 100.0,
 70.0,
 50.0,
 40.0,
 30.0,
 20.0,
 15.0,
 10.0,
 7.0,
 5.0,
 3.0],
scheme = 'linear',
coordinate = 'air_pressure'
2022-06-24 08:15:22,449 UTC [610436] DEBUG   esmvalcore.preprocessor._ancillary_vars:104 Added areacella as cell measure in cube of ta.
2022-06-24 08:15:22,450 UTC [610436] DEBUG   esmvalcore.preprocessor:343 Running preprocessor step area_statistics
2022-06-24 08:15:22,516 UTC [610436] DEBUG   esmvalcore.preprocessor:304 Running preprocessor function 'area_statistics' on the data
<iris 'Cube' of air_temperature / (K) (time: 24; air_pressure: 39; first spatial index for variables stored on an unstructured grid: 20480)>
loaded from original input file(s)
['/home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc']
with function argument(s)
operator = 'mean'
2022-06-24 08:15:22,678 UTC [610436] WARNING py.warnings:109 /work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/site-packages/iris/coords.py:2220: UserWarning: Collapsing a non-contiguous coordinate. Metadata may not be fully descriptive for 'first spatial index for variables stored on an unstructured grid'.

2022-06-24 08:15:22,816 UTC [610436] ERROR   esmvalcore.preprocessor:334 Failed to run preprocessor function 'area_statistics' on the data
[<iris 'Cube' of air_temperature / (K) (time: 24; air_pressure: 39; first spatial index for variables stored on an unstructured grid: 20480)>]
loaded from original input file(s)
['/home/b/b309141/work/icon_eval/data/cool014_atm_3d_ml_1979-2014.nc']
with function argument(s)
operator = 'mean'
2022-06-24 08:15:23,807 UTC [609408] INFO    esmvalcore._task:127 Maximum memory used (estimate): 2.3 GB
2022-06-24 08:15:23,807 UTC [609408] INFO    esmvalcore._task:129 Sampled every second. It may be inaccurate if short but high spikes in memory consumption occur.
2022-06-24 08:15:23,808 UTC [609408] ERROR   esmvalcore._main:511 Program terminated abnormally, see stack trace below for more information:
multiprocessing.pool.RemoteTraceback: 
"""
Traceback (most recent call last):
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/multiprocessing/pool.py", line 125, in worker
    result = (True, func(*args, **kwds))
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/_task.py", line 796, in _run_task
    output_files = task.run()
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/_task.py", line 263, in run
    self.output_files = self._run(input_files)
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/preprocessor/__init__.py", line 606, in _run
    product.apply(step, self.debug)
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/preprocessor/__init__.py", line 417, in apply
    self.cubes = preprocess(self.cubes, step,
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/preprocessor/__init__.py", line 353, in preprocess
    result.append(_run_preproc_function(function, item, settings,
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/preprocessor/__init__.py", line 309, in _run_preproc_function
    return function(items, **kwargs)
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/preprocessor/_area.py", line 301, in area_statistics
    result = cube.collapsed(coord_names, operation, weights=grid_areas)
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/site-packages/iris/cube.py", line 3744, in collapsed
    collapsed_cube.replace_coord(coord.collapsed(local_dims))
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/site-packages/iris/coords.py", line 2215, in collapsed
    elif not self.is_contiguous():
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/site-packages/iris/coords.py", line 2018, in is_contiguous
    contiguous, _ = self._discontiguity_in_bounds(rtol=rtol, atol=atol)
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/site-packages/iris/coords.py", line 1944, in _discontiguity_in_bounds
    self._sanity_check_bounds()
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/site-packages/iris/coords.py", line 1900, in _sanity_check_bounds
    raise ValueError(
ValueError: Invalid operation for 'latitude', with 3 bound(s). Contiguous bounds are only defined for 1D coordinates with 2 bounds.
"""

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/_main.py", line 499, in run
    fire.Fire(ESMValTool())
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/site-packages/fire/core.py", line 141, in Fire
    component_trace = _Fire(component, args, parsed_flag_args, context, name)
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/site-packages/fire/core.py", line 466, in _Fire
    component, remaining_args = _CallAndUpdateTrace(
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/site-packages/fire/core.py", line 681, in _CallAndUpdateTrace
    component = fn(*varargs, **kwargs)
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/_main.py", line 443, in run
    process_recipe(recipe_file=recipe, config_user=cfg)
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/_main.py", line 124, in process_recipe
    recipe.run()
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/_recipe.py", line 1921, in run
    self.tasks.run(max_parallel_tasks=self._cfg['max_parallel_tasks'])
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/_task.py", line 725, in run
    self._run_parallel(max_parallel_tasks)
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/_task.py", line 768, in _run_parallel
    _copy_results(task, running[task])
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/_task.py", line 791, in _copy_results
    task.output_files, task.products = future.get()
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/multiprocessing/pool.py", line 771, in get
    raise self._value
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/multiprocessing/pool.py", line 125, in worker
    result = (True, func(*args, **kwds))
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/_task.py", line 796, in _run_task
    output_files = task.run()
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/_task.py", line 263, in run
    self.output_files = self._run(input_files)
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/preprocessor/__init__.py", line 606, in _run
    product.apply(step, self.debug)
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/preprocessor/__init__.py", line 417, in apply
    self.cubes = preprocess(self.cubes, step,
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/preprocessor/__init__.py", line 353, in preprocess
    result.append(_run_preproc_function(function, item, settings,
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/preprocessor/__init__.py", line 309, in _run_preproc_function
    return function(items, **kwargs)
  File "/home/b/b309141/repos/ESMValCore/esmvalcore/preprocessor/_area.py", line 301, in area_statistics
    result = cube.collapsed(coord_names, operation, weights=grid_areas)
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/site-packages/iris/cube.py", line 3744, in collapsed
    collapsed_cube.replace_coord(coord.collapsed(local_dims))
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/site-packages/iris/coords.py", line 2215, in collapsed
    elif not self.is_contiguous():
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/site-packages/iris/coords.py", line 2018, in is_contiguous
    contiguous, _ = self._discontiguity_in_bounds(rtol=rtol, atol=atol)
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/site-packages/iris/coords.py", line 1944, in _discontiguity_in_bounds
    self._sanity_check_bounds()
  File "/work/bd0854/b309141/mambaforge/envs/esm/lib/python3.10/site-packages/iris/coords.py", line 1900, in _sanity_check_bounds
    raise ValueError(
ValueError: Invalid operation for 'latitude', with 3 bound(s). Contiguous bounds are only defined for 1D coordinates with 2 bounds.
2022-06-24 08:15:23,816 UTC [609408] INFO    esmvalcore._main:515 
If you have a question or need help, please start a new discussion on https://github.com/ESMValGroup/ESMValTool/discussions
If you suspect this is a bug, please open an issue on https://github.com/ESMValGroup/ESMValTool/issues
To make it easier to find out what the problem is, please consider attaching the files run/recipe_*.yml and run/main_log_debug.txt from the output directory.