2023-05-30 13:11:50,594 UTC [370529] INFO esmvalcore._main:495 ______________________________________________________________________ _____ ____ __ ____ __ _ _____ _ | ____/ ___|| \/ \ \ / /_ _| |_ _|__ ___ | | | _| \___ \| |\/| |\ \ / / _` | | | |/ _ \ / _ \| | | |___ ___) | | | | \ 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! 2023-05-30 13:11:50,594 UTC [370529] INFO esmvalcore._main:496 Package versions 2023-05-30 13:11:50,594 UTC [370529] INFO esmvalcore._main:497 ---------------- 2023-05-30 13:11:50,594 UTC [370529] INFO esmvalcore._main:498 ESMValCore: 2.8.0 2023-05-30 13:11:50,594 UTC [370529] INFO esmvalcore._main:500 ESMValTool: 2.8.0 2023-05-30 13:11:50,595 UTC [370529] INFO esmvalcore._main:501 ---------------- 2023-05-30 13:11:50,595 UTC [370529] INFO esmvalcore._main:502 Using config file /nird/home/adagj/.esmvaltool/config-user.yml 2023-05-30 13:11:50,595 UTC [370529] INFO esmvalcore._main:503 Writing program log files to: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/run/main_log.txt /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/run/main_log_debug.txt 2023-05-30 13:11:50,708 UTC [370529] INFO esmvalcore.config._esgf_pyclient:116 Using default ESGF configuration, configuration file /nird/home/adagj/.esmvaltool/esgf-pyclient.yml not present. 2023-05-30 13:11:50,709 UTC [370529] WARNING esmvalcore.config._esgf_pyclient:181 ESGF credentials missing, only data that is accessible without logging in will be available. See https://esgf.github.io/esgf-user-support/user_guide.html for instructions on how to create an account if you do not have one yet. Next, configure your system so esmvaltool can use your credentials. This can be done using the keyring package, or you can just enter them in /nird/home/adagj/.esmvaltool/esgf-pyclient.yml. keyring ======= First install the keyring package (requires a supported backend, see https://pypi.org/project/keyring/): $ pip install keyring Next, set your username and password by running the commands: $ keyring set ESGF hostname $ keyring set ESGF username $ keyring set ESGF password To check that you entered your credentials correctly, run: $ keyring get ESGF hostname $ keyring get ESGF username $ keyring get ESGF password configuration file ================== You can store the hostname, username, and password or your OpenID account in a plain text in the file /nird/home/adagj/.esmvaltool/esgf-pyclient.yml like this: logon: hostname: "your-hostname" username: "your-username" password: "your-password" or your can configure an interactive log in: logon: interactive: true Note that storing your password in plain text in the configuration file is less secure. On shared systems, make sure the permissions of the file are set so only you can read it, i.e. $ ls -l /nird/home/adagj/.esmvaltool/esgf-pyclient.yml shows permissions -rw-------. 2023-05-30 13:11:56,168 UTC [370529] INFO esmvalcore._main:85 Starting the Earth System Model Evaluation Tool at time: 2023-05-30 13:11:56 UTC 2023-05-30 13:11:56,169 UTC [370529] INFO esmvalcore._main:89 ---------------------------------------------------------------------- 2023-05-30 13:11:56,169 UTC [370529] INFO esmvalcore._main:90 RECIPE = /nird/home/adagj/esmvaltool_workshop/recipe_runoff_et.yml 2023-05-30 13:11:56,169 UTC [370529] INFO esmvalcore._main:91 RUNDIR = /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/run 2023-05-30 13:11:56,169 UTC [370529] INFO esmvalcore._main:92 WORKDIR = /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/work 2023-05-30 13:11:56,169 UTC [370529] INFO esmvalcore._main:93 PREPROCDIR = /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc 2023-05-30 13:11:56,169 UTC [370529] INFO esmvalcore._main:94 PLOTDIR = /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/plots 2023-05-30 13:11:56,169 UTC [370529] INFO esmvalcore._main:95 ---------------------------------------------------------------------- 2023-05-30 13:11:56,169 UTC [370529] INFO esmvalcore._main:98 Running tasks using at most 2 processes 2023-05-30 13:11:56,169 UTC [370529] INFO esmvalcore._main:100 If your system hangs during execution, it may not have enough memory for keeping this number of tasks in memory. 2023-05-30 13:11:56,170 UTC [370529] INFO esmvalcore._main:103 If you experience memory problems, try reducing 'max_parallel_tasks' in your user configuration file. 2023-05-30 13:11:56,170 UTC [370529] DEBUG esmvalcore._recipe.check:52 Checking recipe against schema /diagnostics/esmvaltool/2.8.0/lib/python3.10/site-packages/esmvalcore/_recipe/recipe_schema.yml 2023-05-30 13:11:56,192 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:344 Populating list of datasets for variable evspsbl in diagnostic catchment_analysis 2023-05-30 13:11:56,192 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:368 Found Dataset: evspsbl, Amon, CMIP5, MPI-ESM-LR, historical, r1i1p1 2023-05-30 13:11:56,193 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:368 Found Dataset: evspsbl, Amon, CMIP5, IPSL-CM5B-LR, historical, r1i1p1 2023-05-30 13:11:56,193 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:368 Found Dataset: evspsbl, Amon, CMIP5, NorESM1-ME, historical, r1i1p1 2023-05-30 13:11:56,193 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:368 Found Dataset: evspsbl, Amon, CMIP5, NorESM1-M, historical, r1i1p1 2023-05-30 13:11:56,193 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:344 Populating list of datasets for variable mrro in diagnostic catchment_analysis 2023-05-30 13:11:56,193 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:368 Found Dataset: mrro, Lmon, CMIP5, MPI-ESM-LR, historical, r1i1p1 2023-05-30 13:11:56,193 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:368 Found Dataset: mrro, Lmon, CMIP5, IPSL-CM5B-LR, historical, r1i1p1 2023-05-30 13:11:56,193 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:368 Found Dataset: mrro, Lmon, CMIP5, NorESM1-ME, historical, r1i1p1 2023-05-30 13:11:56,194 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:368 Found Dataset: mrro, Lmon, CMIP5, NorESM1-M, historical, r1i1p1 2023-05-30 13:11:56,194 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:344 Populating list of datasets for variable pr in diagnostic catchment_analysis 2023-05-30 13:11:56,194 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:368 Found Dataset: pr, Amon, CMIP5, MPI-ESM-LR, historical, r1i1p1 2023-05-30 13:11:56,194 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:368 Found Dataset: pr, Amon, CMIP5, IPSL-CM5B-LR, historical, r1i1p1 2023-05-30 13:11:56,194 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:368 Found Dataset: pr, Amon, CMIP5, NorESM1-ME, historical, r1i1p1 2023-05-30 13:11:56,194 UTC [370529] DEBUG esmvalcore._recipe.to_datasets:368 Found Dataset: pr, Amon, CMIP5, NorESM1-M, historical, r1i1p1 2023-05-30 13:11:56,195 UTC [370529] DEBUG esmvalcore._recipe.recipe:1014 Retrieving diagnostics from recipe 2023-05-30 13:11:56,195 UTC [370529] DEBUG esmvalcore._recipe.recipe:1042 Setting script for diagnostic catchment_analysis 2023-05-30 13:11:56,195 UTC [370529] INFO esmvalcore._recipe.recipe:1232 Creating tasks from recipe 2023-05-30 13:11:56,195 UTC [370529] INFO esmvalcore._recipe.recipe:1241 Creating tasks for diagnostic catchment_analysis 2023-05-30 13:11:56,195 UTC [370529] INFO esmvalcore._recipe.recipe:1163 Creating diagnostic task catchment_analysis/catchment_analysis 2023-05-30 13:11:56,196 UTC [370529] INFO esmvalcore._recipe.recipe:1216 Creating preprocessor task catchment_analysis/evspsbl 2023-05-30 13:11:56,196 UTC [370529] INFO esmvalcore._recipe.recipe:849 Creating preprocessor 'default' task for variable 'evspsbl' 2023-05-30 13:11:56,196 UTC [370529] DEBUG esmvalcore.config._config:48 Loading extra facets from /diagnostics/esmvaltool/2.8.0/lib/python3.10/site-packages/esmvalcore/config/extra_facets/cmip5-fx.yml 2023-05-30 13:11:56,197 UTC [370529] DEBUG esmvalcore.config._config:48 Loading extra facets from /diagnostics/esmvaltool/2.8.0/lib/python3.10/site-packages/esmvalcore/config/extra_facets/cmip5-institutes.yml 2023-05-30 13:11:56,225 UTC [370529] DEBUG esmvalcore.config._config:48 Loading extra facets from /diagnostics/esmvaltool/2.8.0/lib/python3.10/site-packages/esmvalcore/config/extra_facets/cmip5-product.yml 2023-05-30 13:11:56,232 UTC [370529] WARNING esmvalcore.local:426 'CMIP5' rootpaths '/scratch/adagj/ESGF/cmip5/output1' set in config-user.yml do not exist 2023-05-30 13:11:56,232 UTC [370529] DEBUG esmvalcore.local:458 Looking for files matching: /projects/NS9034K/CMIP5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/*/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1*.nc /projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/*/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1*.nc /scratch/adagj/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/*/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1*.nc 2023-05-30 13:11:56,235 UTC [370529] DEBUG esmvalcore._recipe.recipe:446 Using input files for variable evspsbl of dataset MPI-ESM-LR: /projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,235 UTC [370529] INFO esmvalcore._recipe.recipe:717 Found input files for Dataset: evspsbl, Amon, CMIP5, MPI-ESM-LR, historical, r1i1p1, v20120315 2023-05-30 13:11:56,236 UTC [370529] DEBUG esmvalcore.local:458 Looking for files matching: /projects/NS9034K/CMIP5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/*/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1*.nc /projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/*/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1*.nc /scratch/adagj/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/*/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1*.nc 2023-05-30 13:11:56,237 UTC [370529] DEBUG esmvalcore._recipe.recipe:446 Using input files for variable evspsbl of dataset IPSL-CM5B-LR: /projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,237 UTC [370529] INFO esmvalcore._recipe.recipe:717 Found input files for Dataset: evspsbl, Amon, CMIP5, IPSL-CM5B-LR, historical, r1i1p1, v20120114 2023-05-30 13:11:56,238 UTC [370529] DEBUG esmvalcore.local:458 Looking for files matching: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/*/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1*.nc /projects/NS9560K-datalake/ESGF/cmip5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/*/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1*.nc /scratch/adagj/ESGF/cmip5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/*/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1*.nc 2023-05-30 13:11:56,240 UTC [370529] DEBUG esmvalcore._recipe.recipe:446 Using input files for variable evspsbl of dataset NorESM1-ME: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,240 UTC [370529] INFO esmvalcore._recipe.recipe:717 Found input files for Dataset: evspsbl, Amon, CMIP5, NorESM1-ME, historical, r1i1p1, v20140327 2023-05-30 13:11:56,241 UTC [370529] DEBUG esmvalcore.local:458 Looking for files matching: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/*/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1*.nc /projects/NS9560K-datalake/ESGF/cmip5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/*/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1*.nc /scratch/adagj/ESGF/cmip5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/*/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1*.nc 2023-05-30 13:11:56,246 UTC [370529] DEBUG esmvalcore._recipe.recipe:446 Using input files for variable evspsbl of dataset NorESM1-M: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,246 UTC [370529] INFO esmvalcore._recipe.recipe:717 Found input files for Dataset: evspsbl, Amon, CMIP5, NorESM1-M, historical, r1i1p1, v20120412 2023-05-30 13:11:56,248 UTC [370529] INFO esmvalcore._recipe.recipe:873 PreprocessingTask catchment_analysis/evspsbl created. 2023-05-30 13:11:56,248 UTC [370529] DEBUG esmvalcore._recipe.recipe:874 PreprocessingTask catchment_analysis/evspsbl will create the files: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-M_Amon_historical_r1i1p1_evspsbl_1970-2000.nc /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_evspsbl_1970-2000.nc /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_evspsbl_1970-2000.nc /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_evspsbl_1970-2000.nc 2023-05-30 13:11:56,248 UTC [370529] INFO esmvalcore._recipe.recipe:1216 Creating preprocessor task catchment_analysis/mrro 2023-05-30 13:11:56,248 UTC [370529] INFO esmvalcore._recipe.recipe:849 Creating preprocessor 'default' task for variable 'mrro' 2023-05-30 13:11:56,248 UTC [370529] DEBUG esmvalcore.local:458 Looking for files matching: /projects/NS9034K/CMIP5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/*/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1*.nc /projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/*/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1*.nc /scratch/adagj/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/*/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1*.nc 2023-05-30 13:11:56,250 UTC [370529] DEBUG esmvalcore._recipe.recipe:446 Using input files for variable mrro of dataset MPI-ESM-LR: /projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,250 UTC [370529] INFO esmvalcore._recipe.recipe:717 Found input files for Dataset: mrro, Lmon, CMIP5, MPI-ESM-LR, historical, r1i1p1, v20120625 2023-05-30 13:11:56,250 UTC [370529] DEBUG esmvalcore.local:458 Looking for files matching: /projects/NS9034K/CMIP5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/*/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1*.nc /projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/*/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1*.nc /scratch/adagj/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/*/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1*.nc 2023-05-30 13:11:56,252 UTC [370529] DEBUG esmvalcore._recipe.recipe:446 Using input files for variable mrro of dataset IPSL-CM5B-LR: /projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,252 UTC [370529] INFO esmvalcore._recipe.recipe:717 Found input files for Dataset: mrro, Lmon, CMIP5, IPSL-CM5B-LR, historical, r1i1p1, v20130506 2023-05-30 13:11:56,252 UTC [370529] DEBUG esmvalcore.local:458 Looking for files matching: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/*/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1*.nc /projects/NS9560K-datalake/ESGF/cmip5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/*/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1*.nc /scratch/adagj/ESGF/cmip5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/*/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1*.nc 2023-05-30 13:11:56,254 UTC [370529] DEBUG esmvalcore._recipe.recipe:446 Using input files for variable mrro of dataset NorESM1-ME: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,254 UTC [370529] INFO esmvalcore._recipe.recipe:717 Found input files for Dataset: mrro, Lmon, CMIP5, NorESM1-ME, historical, r1i1p1, v20120920 2023-05-30 13:11:56,255 UTC [370529] DEBUG esmvalcore.local:458 Looking for files matching: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/*/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1*.nc /projects/NS9560K-datalake/ESGF/cmip5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/*/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1*.nc /scratch/adagj/ESGF/cmip5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/*/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1*.nc 2023-05-30 13:11:56,260 UTC [370529] DEBUG esmvalcore._recipe.recipe:446 Using input files for variable mrro of dataset NorESM1-M: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,260 UTC [370529] INFO esmvalcore._recipe.recipe:717 Found input files for Dataset: mrro, Lmon, CMIP5, NorESM1-M, historical, r1i1p1, v20120920 2023-05-30 13:11:56,261 UTC [370529] INFO esmvalcore._recipe.recipe:873 PreprocessingTask catchment_analysis/mrro created. 2023-05-30 13:11:56,262 UTC [370529] DEBUG esmvalcore._recipe.recipe:874 PreprocessingTask catchment_analysis/mrro will create the files: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_MPI-ESM-LR_Lmon_historical_r1i1p1_mrro_1970-2000.nc /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-ME_Lmon_historical_r1i1p1_mrro_1970-2000.nc /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_IPSL-CM5B-LR_Lmon_historical_r1i1p1_mrro_1970-2000.nc /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-M_Lmon_historical_r1i1p1_mrro_1970-2000.nc 2023-05-30 13:11:56,262 UTC [370529] INFO esmvalcore._recipe.recipe:1216 Creating preprocessor task catchment_analysis/pr 2023-05-30 13:11:56,262 UTC [370529] INFO esmvalcore._recipe.recipe:849 Creating preprocessor 'default' task for variable 'pr' 2023-05-30 13:11:56,262 UTC [370529] DEBUG esmvalcore.local:458 Looking for files matching: /projects/NS9034K/CMIP5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/*/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1*.nc /projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/*/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1*.nc /scratch/adagj/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/*/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1*.nc 2023-05-30 13:11:56,264 UTC [370529] DEBUG esmvalcore._recipe.recipe:446 Using input files for variable pr of dataset MPI-ESM-LR: /projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,264 UTC [370529] INFO esmvalcore._recipe.recipe:717 Found input files for Dataset: pr, Amon, CMIP5, MPI-ESM-LR, historical, r1i1p1, v20120315 2023-05-30 13:11:56,264 UTC [370529] DEBUG esmvalcore.local:458 Looking for files matching: /projects/NS9034K/CMIP5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/*/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1*.nc /projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/*/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1*.nc /scratch/adagj/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/*/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1*.nc 2023-05-30 13:11:56,265 UTC [370529] DEBUG esmvalcore._recipe.recipe:446 Using input files for variable pr of dataset IPSL-CM5B-LR: /projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,266 UTC [370529] INFO esmvalcore._recipe.recipe:717 Found input files for Dataset: pr, Amon, CMIP5, IPSL-CM5B-LR, historical, r1i1p1, v20120114 2023-05-30 13:11:56,266 UTC [370529] DEBUG esmvalcore.local:458 Looking for files matching: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/*/pr/pr_Amon_NorESM1-ME_historical_r1i1p1*.nc /projects/NS9560K-datalake/ESGF/cmip5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/*/pr/pr_Amon_NorESM1-ME_historical_r1i1p1*.nc /scratch/adagj/ESGF/cmip5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/*/pr/pr_Amon_NorESM1-ME_historical_r1i1p1*.nc 2023-05-30 13:11:56,268 UTC [370529] DEBUG esmvalcore._recipe.recipe:446 Using input files for variable pr of dataset NorESM1-ME: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,268 UTC [370529] INFO esmvalcore._recipe.recipe:717 Found input files for Dataset: pr, Amon, CMIP5, NorESM1-ME, historical, r1i1p1, v20140327 2023-05-30 13:11:56,269 UTC [370529] DEBUG esmvalcore.local:458 Looking for files matching: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/*/pr/pr_Amon_NorESM1-M_historical_r1i1p1*.nc /projects/NS9560K-datalake/ESGF/cmip5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/*/pr/pr_Amon_NorESM1-M_historical_r1i1p1*.nc /scratch/adagj/ESGF/cmip5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/*/pr/pr_Amon_NorESM1-M_historical_r1i1p1*.nc 2023-05-30 13:11:56,272 UTC [370529] DEBUG esmvalcore._recipe.recipe:446 Using input files for variable pr of dataset NorESM1-M: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,272 UTC [370529] INFO esmvalcore._recipe.recipe:717 Found input files for Dataset: pr, Amon, CMIP5, NorESM1-M, historical, r1i1p1, v20120412 2023-05-30 13:11:56,273 UTC [370529] INFO esmvalcore._recipe.recipe:873 PreprocessingTask catchment_analysis/pr created. 2023-05-30 13:11:56,274 UTC [370529] DEBUG esmvalcore._recipe.recipe:874 PreprocessingTask catchment_analysis/pr will create the files: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_pr_1970-2000.nc /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-M_Amon_historical_r1i1p1_pr_1970-2000.nc /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_pr_1970-2000.nc /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_pr_1970-2000.nc 2023-05-30 13:11:56,274 UTC [370529] DEBUG esmvalcore._recipe.recipe:1091 Linking tasks for diagnostic catchment_analysis script catchment_analysis 2023-05-30 13:11:56,274 UTC [370529] DEBUG esmvalcore._recipe.recipe:1100 Pattern catchment_analysis/evspsbl matches ['catchment_analysis/evspsbl'] 2023-05-30 13:11:56,274 UTC [370529] DEBUG esmvalcore._recipe.recipe:1100 Pattern catchment_analysis/mrro matches ['catchment_analysis/mrro'] 2023-05-30 13:11:56,274 UTC [370529] DEBUG esmvalcore._recipe.recipe:1100 Pattern catchment_analysis/pr matches ['catchment_analysis/pr'] 2023-05-30 13:11:56,274 UTC [370529] INFO esmvalcore._recipe.recipe:1281 These tasks will be executed: catchment_analysis/mrro, catchment_analysis/pr, catchment_analysis/catchment_analysis, catchment_analysis/evspsbl 2023-05-30 13:11:56,277 UTC [370529] DEBUG esmvalcore._main:120 Recipe summary: DiagnosticTask: catchment_analysis/catchment_analysis script: runoff_et/catchment_analysis.py settings: {'auxiliary_data_dir': PosixPath('/projects/NS9560K-datalake/ESGF/auxiliary_data'), 'catchmentmask': 'catchmentmask_v1.nc', 'log_level': 'info', 'output_file_type': 'png', 'plot_dir': '/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/plots/catchment_analysis/catchment_analysis', 'profile_diagnostic': False, 'recipe': PosixPath('recipe_runoff_et.yml'), 'run_dir': '/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/run/catchment_analysis/catchment_analysis', 'script': 'catchment_analysis', 'version': '2.8.0', 'work_dir': '/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/work/catchment_analysis/catchment_analysis'} ancestors: PreprocessingTask: catchment_analysis/evspsbl order: ['load', 'remove_supplementary_variables', 'save'] PreprocessorFile: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-M_Amon_historical_r1i1p1_evspsbl_1970-2000.nc input files: [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] settings: {'load': {'callback': 'default'}, 'remove_supplementary_variables': {}, 'save': {'compress': False, 'filename': PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-M_Amon_historical_r1i1p1_evspsbl_1970-2000.nc')}} PreprocessorFile: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_evspsbl_1970-2000.nc input files: [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] settings: {'load': {'callback': 'default'}, 'remove_supplementary_variables': {}, 'save': {'compress': False, 'filename': PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_evspsbl_1970-2000.nc')}} PreprocessorFile: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_evspsbl_1970-2000.nc input files: [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] settings: {'load': {'callback': 'default'}, 'remove_supplementary_variables': {}, 'save': {'compress': False, 'filename': PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_evspsbl_1970-2000.nc')}} PreprocessorFile: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_evspsbl_1970-2000.nc input files: [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] settings: {'load': {'callback': 'default'}, 'remove_supplementary_variables': {}, 'save': {'compress': False, 'filename': PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_evspsbl_1970-2000.nc')}} ancestors: None PreprocessingTask: catchment_analysis/mrro order: ['load', 'remove_supplementary_variables', 'save'] PreprocessorFile: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_MPI-ESM-LR_Lmon_historical_r1i1p1_mrro_1970-2000.nc input files: [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] settings: {'load': {'callback': 'default'}, 'remove_supplementary_variables': {}, 'save': {'compress': False, 'filename': PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_MPI-ESM-LR_Lmon_historical_r1i1p1_mrro_1970-2000.nc')}} PreprocessorFile: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-ME_Lmon_historical_r1i1p1_mrro_1970-2000.nc input files: [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] settings: {'load': {'callback': 'default'}, 'remove_supplementary_variables': {}, 'save': {'compress': False, 'filename': PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-ME_Lmon_historical_r1i1p1_mrro_1970-2000.nc')}} PreprocessorFile: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_IPSL-CM5B-LR_Lmon_historical_r1i1p1_mrro_1970-2000.nc input files: [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] settings: {'load': {'callback': 'default'}, 'remove_supplementary_variables': {}, 'save': {'compress': False, 'filename': PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_IPSL-CM5B-LR_Lmon_historical_r1i1p1_mrro_1970-2000.nc')}} PreprocessorFile: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-M_Lmon_historical_r1i1p1_mrro_1970-2000.nc input files: [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] settings: {'load': {'callback': 'default'}, 'remove_supplementary_variables': {}, 'save': {'compress': False, 'filename': PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-M_Lmon_historical_r1i1p1_mrro_1970-2000.nc')}} ancestors: None PreprocessingTask: catchment_analysis/pr order: ['load', 'remove_supplementary_variables', 'save'] PreprocessorFile: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_pr_1970-2000.nc input files: [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] settings: {'load': {'callback': 'default'}, 'remove_supplementary_variables': {}, 'save': {'compress': False, 'filename': PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_pr_1970-2000.nc')}} PreprocessorFile: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-M_Amon_historical_r1i1p1_pr_1970-2000.nc input files: [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] settings: {'load': {'callback': 'default'}, 'remove_supplementary_variables': {}, 'save': {'compress': False, 'filename': PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-M_Amon_historical_r1i1p1_pr_1970-2000.nc')}} PreprocessorFile: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_pr_1970-2000.nc input files: [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] settings: {'load': {'callback': 'default'}, 'remove_supplementary_variables': {}, 'save': {'compress': False, 'filename': PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_pr_1970-2000.nc')}} PreprocessorFile: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_pr_1970-2000.nc input files: [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] settings: {'load': {'callback': 'default'}, 'remove_supplementary_variables': {}, 'save': {'compress': False, 'filename': PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_pr_1970-2000.nc')}} ancestors: None 2023-05-30 13:11:56,285 UTC [370529] INFO esmvalcore._recipe.recipe:1344 Wrote recipe with version numbers and wildcards to: file:///projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/run/recipe_runoff_et_filled.yml 2023-05-30 13:11:56,285 UTC [370529] DEBUG esmvalcore.esgf._download:535 All required data is available locally, not downloading anything. 2023-05-30 13:11:56,285 UTC [370529] INFO esmvalcore._task:746 Running 4 tasks using 2 processes 2023-05-30 13:11:56,338 UTC [370716] INFO esmvalcore._task:259 Starting task catchment_analysis/evspsbl in process [370716] 2023-05-30 13:11:56,338 UTC [370717] INFO esmvalcore._task:259 Starting task catchment_analysis/mrro in process [370717] 2023-05-30 13:11:56,433 UTC [370717] DEBUG esmvalcore.esgf._download:535 All required data is available locally, not downloading anything. 2023-05-30 13:11:56,434 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_file 2023-05-30 13:11:56,434 UTC [370529] INFO esmvalcore._task:778 Progress: 2 tasks running, 2 tasks waiting for ancestors, 0/4 done 2023-05-30 13:11:56,434 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_file' on the data LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) output_dir = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/fixed_files/mrro_Lmon_CMIP5_MPI-ESM-LR_historical_r1i1p1_v20120625_'), add_unique_suffix = True, mip = 'Lmon', dataset = 'MPI-ESM-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'mrro', timerange = '1970/2000', variable_group = 'mrro', diagnostic = 'catchment_analysis', recipe_dataset_index = 0, alias = 'MPI-ESM-LR', product = ['output1', 'output2'], institute = ['MPI-M'], original_short_name = 'mrro', standard_name = 'runoff_flux', long_name = 'Total Runoff', units = 'kg m-2 s-1', modeling_realm = ['land'], frequency = 'mon', version = 'v20120625' 2023-05-30 13:11:56,436 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] 2023-05-30 13:11:56,436 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step load 2023-05-30 13:11:56,436 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'load' on the data LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) callback = 'default' 2023-05-30 13:11:56,436 UTC [370717] DEBUG esmvalcore.preprocessor._io:152 Loading: /projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,457 UTC [370716] DEBUG esmvalcore.esgf._download:535 All required data is available locally, not downloading anything. 2023-05-30 13:11:56,457 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_file 2023-05-30 13:11:56,457 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_file' on the data LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) output_dir = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/fixed_files/evspsbl_Amon_CMIP5_NorESM1-M_historical_r1i1p1_v20120412_'), add_unique_suffix = True, mip = 'Amon', dataset = 'NorESM1-M', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'evspsbl', timerange = '1970/2000', variable_group = 'evspsbl', diagnostic = 'catchment_analysis', recipe_dataset_index = 3, alias = 'NorESM1-M', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'evspsbl', standard_name = 'water_evaporation_flux', long_name = 'Evaporation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120412' 2023-05-30 13:11:56,459 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] 2023-05-30 13:11:56,459 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step load 2023-05-30 13:11:56,459 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'load' on the data LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) callback = 'default' 2023-05-30 13:11:56,459 UTC [370716] DEBUG esmvalcore.preprocessor._io:152 Loading: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,521 UTC [370717] DEBUG esmvalcore.preprocessor._io:182 Done with loading /projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,522 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:11:56,525 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_MPI-ESM-LR_Lmon_historical_r1i1p1_mrro_1970-2000/00_load.nc 2023-05-30 13:11:56,716 UTC [370716] DEBUG esmvalcore.preprocessor._io:182 Done with loading /projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc 2023-05-30 13:11:56,717 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:11:56,719 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-M_Amon_historical_r1i1p1_evspsbl_1970-2000/00_load.nc 2023-05-30 13:12:00,260 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_metadata 2023-05-30 13:12:00,262 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_metadata' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Lmon', dataset = 'MPI-ESM-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'mrro', timerange = '1970/2000', variable_group = 'mrro', diagnostic = 'catchment_analysis', recipe_dataset_index = 0, alias = 'MPI-ESM-LR', product = ['output1', 'output2'], institute = ['MPI-M'], original_short_name = 'mrro', standard_name = 'runoff_flux', long_name = 'Total Runoff', units = 'kg m-2 s-1', modeling_realm = ['land'], frequency = 'mon', version = 'v20120625' 2023-05-30 13:12:00,269 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:00,270 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_MPI-ESM-LR_Lmon_historical_r1i1p1_mrro_1970-2000/01_fix_metadata.nc 2023-05-30 13:12:00,389 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_metadata 2023-05-30 13:12:00,390 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_metadata' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'NorESM1-M', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'evspsbl', timerange = '1970/2000', variable_group = 'evspsbl', diagnostic = 'catchment_analysis', recipe_dataset_index = 3, alias = 'NorESM1-M', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'evspsbl', standard_name = 'water_evaporation_flux', long_name = 'Evaporation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120412' 2023-05-30 13:12:00,397 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:00,397 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-M_Amon_historical_r1i1p1_evspsbl_1970-2000/01_fix_metadata.nc 2023-05-30 13:12:00,613 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step concatenate 2023-05-30 13:12:00,614 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'concatenate' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) 2023-05-30 13:12:00,614 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:00,615 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-M_Amon_historical_r1i1p1_evspsbl_1970-2000/02_concatenate.nc 2023-05-30 13:12:00,717 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step concatenate 2023-05-30 13:12:00,719 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'concatenate' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) 2023-05-30 13:12:00,719 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:00,720 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_MPI-ESM-LR_Lmon_historical_r1i1p1_mrro_1970-2000/02_concatenate.nc 2023-05-30 13:12:00,880 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_metadata 2023-05-30 13:12:00,881 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_metadata' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'evspsbl' 2023-05-30 13:12:00,888 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:00,889 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-M_Amon_historical_r1i1p1_evspsbl_1970-2000/03_cmor_check_metadata.nc 2023-05-30 13:12:01,029 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_metadata 2023-05-30 13:12:01,030 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_metadata' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Lmon', frequency = 'mon', short_name = 'mrro' 2023-05-30 13:12:01,037 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:01,038 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_MPI-ESM-LR_Lmon_historical_r1i1p1_mrro_1970-2000/03_cmor_check_metadata.nc 2023-05-30 13:12:01,121 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step clip_timerange 2023-05-30 13:12:01,123 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'clip_timerange' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) timerange = '1970/2000' 2023-05-30 13:12:01,136 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:01,137 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-M_Amon_historical_r1i1p1_evspsbl_1970-2000/04_clip_timerange.nc 2023-05-30 13:12:01,363 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step clip_timerange 2023-05-30 13:12:01,365 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'clip_timerange' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) timerange = '1970/2000' 2023-05-30 13:12:01,381 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:01,382 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_MPI-ESM-LR_Lmon_historical_r1i1p1_mrro_1970-2000/04_clip_timerange.nc 2023-05-30 13:12:01,424 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_data 2023-05-30 13:12:01,425 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'NorESM1-M', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'evspsbl', timerange = '1970/2000', variable_group = 'evspsbl', diagnostic = 'catchment_analysis', recipe_dataset_index = 3, alias = 'NorESM1-M', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'evspsbl', standard_name = 'water_evaporation_flux', long_name = 'Evaporation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120412' 2023-05-30 13:12:01,425 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:01,426 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-M_Amon_historical_r1i1p1_evspsbl_1970-2000/05_fix_data.nc 2023-05-30 13:12:01,604 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_data 2023-05-30 13:12:01,606 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Lmon', dataset = 'MPI-ESM-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'mrro', timerange = '1970/2000', variable_group = 'mrro', diagnostic = 'catchment_analysis', recipe_dataset_index = 0, alias = 'MPI-ESM-LR', product = ['output1', 'output2'], institute = ['MPI-M'], original_short_name = 'mrro', standard_name = 'runoff_flux', long_name = 'Total Runoff', units = 'kg m-2 s-1', modeling_realm = ['land'], frequency = 'mon', version = 'v20120625' 2023-05-30 13:12:01,607 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:01,608 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_MPI-ESM-LR_Lmon_historical_r1i1p1_mrro_1970-2000/05_fix_data.nc 2023-05-30 13:12:01,673 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_data 2023-05-30 13:12:01,674 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'evspsbl' 2023-05-30 13:12:01,675 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:01,676 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-M_Amon_historical_r1i1p1_evspsbl_1970-2000/06_cmor_check_data.nc 2023-05-30 13:12:01,835 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_data 2023-05-30 13:12:01,836 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Lmon', frequency = 'mon', short_name = 'mrro' 2023-05-30 13:12:01,836 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:01,837 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_MPI-ESM-LR_Lmon_historical_r1i1p1_mrro_1970-2000/06_cmor_check_data.nc 2023-05-30 13:12:01,945 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step add_supplementary_variables 2023-05-30 13:12:01,946 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'add_supplementary_variables' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) supplementary_cubes = [] 2023-05-30 13:12:01,946 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:01,947 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-M_Amon_historical_r1i1p1_evspsbl_1970-2000/07_add_supplementary_variables.nc 2023-05-30 13:12:02,072 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step add_supplementary_variables 2023-05-30 13:12:02,073 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'add_supplementary_variables' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) supplementary_cubes = [] 2023-05-30 13:12:02,073 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:02,074 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_MPI-ESM-LR_Lmon_historical_r1i1p1_mrro_1970-2000/07_add_supplementary_variables.nc 2023-05-30 13:12:02,219 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step save 2023-05-30 13:12:02,221 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'save' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/evspsbl/evspsbl_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) compress = False, filename = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-M_Amon_historical_r1i1p1_evspsbl_1970-2000.nc') 2023-05-30 13:12:02,221 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-M_Amon_historical_r1i1p1_evspsbl_1970-2000.nc 2023-05-30 13:12:02,272 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step save 2023-05-30 13:12:02,273 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'save' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/land/Lmon/r1i1p1/v20120625/mrro/mrro_Lmon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) compress = False, filename = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_MPI-ESM-LR_Lmon_historical_r1i1p1_mrro_1970-2000.nc') 2023-05-30 13:12:02,274 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_MPI-ESM-LR_Lmon_historical_r1i1p1_mrro_1970-2000.nc 2023-05-30 13:12:07,636 UTC [370717] DEBUG esmvalcore.esgf._download:535 All required data is available locally, not downloading anything. 2023-05-30 13:12:07,636 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_file 2023-05-30 13:12:07,637 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_file' on the data LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) output_dir = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/fixed_files/mrro_Lmon_CMIP5_NorESM1-ME_historical_r1i1p1_v20120920_'), add_unique_suffix = True, mip = 'Lmon', dataset = 'NorESM1-ME', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'mrro', timerange = '1970/2000', variable_group = 'mrro', diagnostic = 'catchment_analysis', recipe_dataset_index = 2, alias = 'NorESM1-ME', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'mrro', standard_name = 'runoff_flux', long_name = 'Total Runoff', units = 'kg m-2 s-1', modeling_realm = ['land'], frequency = 'mon', version = 'v20120920' 2023-05-30 13:12:07,637 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] 2023-05-30 13:12:07,637 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step load 2023-05-30 13:12:07,637 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'load' on the data LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) callback = 'default' 2023-05-30 13:12:07,637 UTC [370717] DEBUG esmvalcore.preprocessor._io:152 Loading: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:07,688 UTC [370717] DEBUG esmvalcore.preprocessor._io:182 Done with loading /projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:07,688 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:07,689 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-ME_Lmon_historical_r1i1p1_mrro_1970-2000/00_load.nc 2023-05-30 13:12:07,732 UTC [370716] DEBUG esmvalcore.esgf._download:535 All required data is available locally, not downloading anything. 2023-05-30 13:12:07,732 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_file 2023-05-30 13:12:07,733 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_file' on the data LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) output_dir = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/fixed_files/evspsbl_Amon_CMIP5_IPSL-CM5B-LR_historical_r1i1p1_v20120114_'), add_unique_suffix = True, mip = 'Amon', dataset = 'IPSL-CM5B-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'evspsbl', timerange = '1970/2000', variable_group = 'evspsbl', diagnostic = 'catchment_analysis', recipe_dataset_index = 1, alias = 'IPSL-CM5B-LR', product = ['output1', 'output2'], institute = ['IPSL'], original_short_name = 'evspsbl', standard_name = 'water_evaporation_flux', long_name = 'Evaporation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120114' 2023-05-30 13:12:07,733 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] 2023-05-30 13:12:07,733 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step load 2023-05-30 13:12:07,733 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'load' on the data LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) callback = 'default' 2023-05-30 13:12:07,733 UTC [370716] DEBUG esmvalcore.preprocessor._io:152 Loading: /projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:07,815 UTC [370716] DEBUG esmvalcore.preprocessor._io:182 Done with loading /projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:07,815 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:07,816 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/00_load.nc 2023-05-30 13:12:07,919 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_metadata 2023-05-30 13:12:07,921 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_metadata' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Lmon', dataset = 'NorESM1-ME', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'mrro', timerange = '1970/2000', variable_group = 'mrro', diagnostic = 'catchment_analysis', recipe_dataset_index = 2, alias = 'NorESM1-ME', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'mrro', standard_name = 'runoff_flux', long_name = 'Total Runoff', units = 'kg m-2 s-1', modeling_realm = ['land'], frequency = 'mon', version = 'v20120920' 2023-05-30 13:12:07,928 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:07,929 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-ME_Lmon_historical_r1i1p1_mrro_1970-2000/01_fix_metadata.nc 2023-05-30 13:12:08,012 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_metadata 2023-05-30 13:12:08,014 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_metadata' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'IPSL-CM5B-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'evspsbl', timerange = '1970/2000', variable_group = 'evspsbl', diagnostic = 'catchment_analysis', recipe_dataset_index = 1, alias = 'IPSL-CM5B-LR', product = ['output1', 'output2'], institute = ['IPSL'], original_short_name = 'evspsbl', standard_name = 'water_evaporation_flux', long_name = 'Evaporation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120114' 2023-05-30 13:12:08,019 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:08,020 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/01_fix_metadata.nc 2023-05-30 13:12:08,168 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step concatenate 2023-05-30 13:12:08,169 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'concatenate' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) 2023-05-30 13:12:08,170 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:08,170 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-ME_Lmon_historical_r1i1p1_mrro_1970-2000/02_concatenate.nc 2023-05-30 13:12:08,218 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step concatenate 2023-05-30 13:12:08,220 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'concatenate' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) 2023-05-30 13:12:08,220 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:08,221 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/02_concatenate.nc 2023-05-30 13:12:08,412 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_metadata 2023-05-30 13:12:08,413 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_metadata' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Lmon', frequency = 'mon', short_name = 'mrro' 2023-05-30 13:12:08,415 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_metadata 2023-05-30 13:12:08,416 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_metadata' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'evspsbl' 2023-05-30 13:12:08,421 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:08,422 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-ME_Lmon_historical_r1i1p1_mrro_1970-2000/03_cmor_check_metadata.nc 2023-05-30 13:12:08,424 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:08,425 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/03_cmor_check_metadata.nc 2023-05-30 13:12:08,626 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step clip_timerange 2023-05-30 13:12:08,627 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'clip_timerange' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) timerange = '1970/2000' 2023-05-30 13:12:08,637 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:08,638 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/04_clip_timerange.nc 2023-05-30 13:12:08,654 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step clip_timerange 2023-05-30 13:12:08,655 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'clip_timerange' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) timerange = '1970/2000' 2023-05-30 13:12:08,667 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:08,667 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-ME_Lmon_historical_r1i1p1_mrro_1970-2000/04_clip_timerange.nc 2023-05-30 13:12:08,791 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_data 2023-05-30 13:12:08,792 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'IPSL-CM5B-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'evspsbl', timerange = '1970/2000', variable_group = 'evspsbl', diagnostic = 'catchment_analysis', recipe_dataset_index = 1, alias = 'IPSL-CM5B-LR', product = ['output1', 'output2'], institute = ['IPSL'], original_short_name = 'evspsbl', standard_name = 'water_evaporation_flux', long_name = 'Evaporation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120114' 2023-05-30 13:12:08,792 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:08,793 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/05_fix_data.nc 2023-05-30 13:12:08,923 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_data 2023-05-30 13:12:08,924 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Lmon', dataset = 'NorESM1-ME', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'mrro', timerange = '1970/2000', variable_group = 'mrro', diagnostic = 'catchment_analysis', recipe_dataset_index = 2, alias = 'NorESM1-ME', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'mrro', standard_name = 'runoff_flux', long_name = 'Total Runoff', units = 'kg m-2 s-1', modeling_realm = ['land'], frequency = 'mon', version = 'v20120920' 2023-05-30 13:12:08,924 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:08,925 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-ME_Lmon_historical_r1i1p1_mrro_1970-2000/05_fix_data.nc 2023-05-30 13:12:08,936 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_data 2023-05-30 13:12:08,937 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'evspsbl' 2023-05-30 13:12:08,937 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:08,938 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/06_cmor_check_data.nc 2023-05-30 13:12:09,095 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step add_supplementary_variables 2023-05-30 13:12:09,096 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'add_supplementary_variables' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) supplementary_cubes = [] 2023-05-30 13:12:09,096 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:09,097 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/07_add_supplementary_variables.nc 2023-05-30 13:12:12,207 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step save 2023-05-30 13:12:12,208 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'save' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/evspsbl/evspsbl_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) compress = False, filename = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_evspsbl_1970-2000.nc') 2023-05-30 13:12:12,208 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_evspsbl_1970-2000.nc 2023-05-30 13:12:12,253 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_data 2023-05-30 13:12:12,254 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Lmon', frequency = 'mon', short_name = 'mrro' 2023-05-30 13:12:12,254 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:12,255 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-ME_Lmon_historical_r1i1p1_mrro_1970-2000/06_cmor_check_data.nc 2023-05-30 13:12:12,372 UTC [370716] DEBUG esmvalcore.esgf._download:535 All required data is available locally, not downloading anything. 2023-05-30 13:12:12,372 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_file 2023-05-30 13:12:12,373 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_file' on the data LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) output_dir = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/fixed_files/evspsbl_Amon_CMIP5_MPI-ESM-LR_historical_r1i1p1_v20120315_'), add_unique_suffix = True, mip = 'Amon', dataset = 'MPI-ESM-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'evspsbl', timerange = '1970/2000', variable_group = 'evspsbl', diagnostic = 'catchment_analysis', recipe_dataset_index = 0, alias = 'MPI-ESM-LR', product = ['output1', 'output2'], institute = ['MPI-M'], original_short_name = 'evspsbl', standard_name = 'water_evaporation_flux', long_name = 'Evaporation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120315' 2023-05-30 13:12:12,373 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] 2023-05-30 13:12:12,373 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step load 2023-05-30 13:12:12,373 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'load' on the data LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) callback = 'default' 2023-05-30 13:12:12,373 UTC [370716] DEBUG esmvalcore.preprocessor._io:152 Loading: /projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:12,518 UTC [370716] DEBUG esmvalcore.preprocessor._io:182 Done with loading /projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:12,518 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:12,520 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/00_load.nc 2023-05-30 13:12:12,545 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step add_supplementary_variables 2023-05-30 13:12:12,546 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'add_supplementary_variables' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) supplementary_cubes = [] 2023-05-30 13:12:12,546 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:12,547 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-ME_Lmon_historical_r1i1p1_mrro_1970-2000/07_add_supplementary_variables.nc 2023-05-30 13:12:12,808 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step save 2023-05-30 13:12:12,810 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'save' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) compress = False, filename = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-ME_Lmon_historical_r1i1p1_mrro_1970-2000.nc') 2023-05-30 13:12:12,810 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-ME_Lmon_historical_r1i1p1_mrro_1970-2000.nc 2023-05-30 13:12:12,838 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_metadata 2023-05-30 13:12:12,839 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_metadata' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'MPI-ESM-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'evspsbl', timerange = '1970/2000', variable_group = 'evspsbl', diagnostic = 'catchment_analysis', recipe_dataset_index = 0, alias = 'MPI-ESM-LR', product = ['output1', 'output2'], institute = ['MPI-M'], original_short_name = 'evspsbl', standard_name = 'water_evaporation_flux', long_name = 'Evaporation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120315' 2023-05-30 13:12:12,846 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:12,847 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/01_fix_metadata.nc 2023-05-30 13:12:13,064 UTC [370717] DEBUG esmvalcore.esgf._download:535 All required data is available locally, not downloading anything. 2023-05-30 13:12:13,064 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_file 2023-05-30 13:12:13,065 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_file' on the data LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) output_dir = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/fixed_files/mrro_Lmon_CMIP5_IPSL-CM5B-LR_historical_r1i1p1_v20130506_'), add_unique_suffix = True, mip = 'Lmon', dataset = 'IPSL-CM5B-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'mrro', timerange = '1970/2000', variable_group = 'mrro', diagnostic = 'catchment_analysis', recipe_dataset_index = 1, alias = 'IPSL-CM5B-LR', product = ['output1', 'output2'], institute = ['IPSL'], original_short_name = 'mrro', standard_name = 'runoff_flux', long_name = 'Total Runoff', units = 'kg m-2 s-1', modeling_realm = ['land'], frequency = 'mon', version = 'v20130506' 2023-05-30 13:12:13,065 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] 2023-05-30 13:12:13,065 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step load 2023-05-30 13:12:13,066 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'load' on the data LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) callback = 'default' 2023-05-30 13:12:13,066 UTC [370717] DEBUG esmvalcore.preprocessor._io:152 Loading: /projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:13,176 UTC [370717] DEBUG esmvalcore.preprocessor._io:182 Done with loading /projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:13,176 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:13,178 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_IPSL-CM5B-LR_Lmon_historical_r1i1p1_mrro_1970-2000/00_load.nc 2023-05-30 13:12:13,269 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step concatenate 2023-05-30 13:12:13,271 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'concatenate' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) 2023-05-30 13:12:13,271 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:13,272 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/02_concatenate.nc 2023-05-30 13:12:13,489 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_metadata 2023-05-30 13:12:13,491 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_metadata' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Lmon', dataset = 'IPSL-CM5B-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'mrro', timerange = '1970/2000', variable_group = 'mrro', diagnostic = 'catchment_analysis', recipe_dataset_index = 1, alias = 'IPSL-CM5B-LR', product = ['output1', 'output2'], institute = ['IPSL'], original_short_name = 'mrro', standard_name = 'runoff_flux', long_name = 'Total Runoff', units = 'kg m-2 s-1', modeling_realm = ['land'], frequency = 'mon', version = 'v20130506' 2023-05-30 13:12:13,497 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:13,498 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_IPSL-CM5B-LR_Lmon_historical_r1i1p1_mrro_1970-2000/01_fix_metadata.nc 2023-05-30 13:12:13,602 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_metadata 2023-05-30 13:12:13,603 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_metadata' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'evspsbl' 2023-05-30 13:12:13,610 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:13,611 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/03_cmor_check_metadata.nc 2023-05-30 13:12:13,723 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step concatenate 2023-05-30 13:12:13,724 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'concatenate' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) 2023-05-30 13:12:13,725 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:13,725 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_IPSL-CM5B-LR_Lmon_historical_r1i1p1_mrro_1970-2000/02_concatenate.nc 2023-05-30 13:12:13,926 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step clip_timerange 2023-05-30 13:12:13,927 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'clip_timerange' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) timerange = '1970/2000' 2023-05-30 13:12:13,939 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:13,940 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/04_clip_timerange.nc 2023-05-30 13:12:13,947 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_metadata 2023-05-30 13:12:13,948 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_metadata' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Lmon', frequency = 'mon', short_name = 'mrro' 2023-05-30 13:12:13,954 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:13,955 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_IPSL-CM5B-LR_Lmon_historical_r1i1p1_mrro_1970-2000/03_cmor_check_metadata.nc 2023-05-30 13:12:14,131 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_data 2023-05-30 13:12:14,132 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'MPI-ESM-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'evspsbl', timerange = '1970/2000', variable_group = 'evspsbl', diagnostic = 'catchment_analysis', recipe_dataset_index = 0, alias = 'MPI-ESM-LR', product = ['output1', 'output2'], institute = ['MPI-M'], original_short_name = 'evspsbl', standard_name = 'water_evaporation_flux', long_name = 'Evaporation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120315' 2023-05-30 13:12:14,133 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:14,134 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/05_fix_data.nc 2023-05-30 13:12:14,198 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step clip_timerange 2023-05-30 13:12:14,199 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'clip_timerange' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) timerange = '1970/2000' 2023-05-30 13:12:14,209 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:14,210 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_IPSL-CM5B-LR_Lmon_historical_r1i1p1_mrro_1970-2000/04_clip_timerange.nc 2023-05-30 13:12:14,326 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_data 2023-05-30 13:12:14,327 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'evspsbl' 2023-05-30 13:12:14,328 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:14,329 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/06_cmor_check_data.nc 2023-05-30 13:12:14,387 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_data 2023-05-30 13:12:14,389 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Lmon', dataset = 'IPSL-CM5B-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'mrro', timerange = '1970/2000', variable_group = 'mrro', diagnostic = 'catchment_analysis', recipe_dataset_index = 1, alias = 'IPSL-CM5B-LR', product = ['output1', 'output2'], institute = ['IPSL'], original_short_name = 'mrro', standard_name = 'runoff_flux', long_name = 'Total Runoff', units = 'kg m-2 s-1', modeling_realm = ['land'], frequency = 'mon', version = 'v20130506' 2023-05-30 13:12:14,389 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:14,390 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_IPSL-CM5B-LR_Lmon_historical_r1i1p1_mrro_1970-2000/05_fix_data.nc 2023-05-30 13:12:14,535 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step add_supplementary_variables 2023-05-30 13:12:14,536 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'add_supplementary_variables' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) supplementary_cubes = [] 2023-05-30 13:12:14,536 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:14,537 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_evspsbl_1970-2000/07_add_supplementary_variables.nc 2023-05-30 13:12:14,558 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_data 2023-05-30 13:12:14,559 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Lmon', frequency = 'mon', short_name = 'mrro' 2023-05-30 13:12:14,559 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:14,560 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_IPSL-CM5B-LR_Lmon_historical_r1i1p1_mrro_1970-2000/06_cmor_check_data.nc 2023-05-30 13:12:14,720 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step add_supplementary_variables 2023-05-30 13:12:14,721 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'add_supplementary_variables' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) supplementary_cubes = [] 2023-05-30 13:12:14,721 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:14,722 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_IPSL-CM5B-LR_Lmon_historical_r1i1p1_mrro_1970-2000/07_add_supplementary_variables.nc 2023-05-30 13:12:14,729 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step save 2023-05-30 13:12:14,731 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'save' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/evspsbl/evspsbl_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) compress = False, filename = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_evspsbl_1970-2000.nc') 2023-05-30 13:12:14,731 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_evspsbl_1970-2000.nc 2023-05-30 13:12:14,914 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step save 2023-05-30 13:12:14,915 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'save' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/land/Lmon/r1i1p1/v20130506/mrro/mrro_Lmon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) compress = False, filename = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_IPSL-CM5B-LR_Lmon_historical_r1i1p1_mrro_1970-2000.nc') 2023-05-30 13:12:14,916 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_IPSL-CM5B-LR_Lmon_historical_r1i1p1_mrro_1970-2000.nc 2023-05-30 13:12:14,935 UTC [370716] DEBUG esmvalcore.esgf._download:535 All required data is available locally, not downloading anything. 2023-05-30 13:12:14,935 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_file 2023-05-30 13:12:14,936 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_file' on the data LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) output_dir = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/fixed_files/evspsbl_Amon_CMIP5_NorESM1-ME_historical_r1i1p1_v20140327_'), add_unique_suffix = True, mip = 'Amon', dataset = 'NorESM1-ME', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'evspsbl', timerange = '1970/2000', variable_group = 'evspsbl', diagnostic = 'catchment_analysis', recipe_dataset_index = 2, alias = 'NorESM1-ME', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'evspsbl', standard_name = 'water_evaporation_flux', long_name = 'Evaporation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20140327' 2023-05-30 13:12:14,936 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] 2023-05-30 13:12:14,936 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step load 2023-05-30 13:12:14,936 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'load' on the data LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) callback = 'default' 2023-05-30 13:12:14,936 UTC [370716] DEBUG esmvalcore.preprocessor._io:152 Loading: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:15,052 UTC [370716] DEBUG esmvalcore.preprocessor._io:182 Done with loading /projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:15,052 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:15,053 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_evspsbl_1970-2000/00_load.nc 2023-05-30 13:12:15,081 UTC [370717] DEBUG esmvalcore.esgf._download:535 All required data is available locally, not downloading anything. 2023-05-30 13:12:15,081 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_file 2023-05-30 13:12:15,081 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_file' on the data LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) output_dir = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/fixed_files/mrro_Lmon_CMIP5_NorESM1-M_historical_r1i1p1_v20120920_'), add_unique_suffix = True, mip = 'Lmon', dataset = 'NorESM1-M', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'mrro', timerange = '1970/2000', variable_group = 'mrro', diagnostic = 'catchment_analysis', recipe_dataset_index = 3, alias = 'NorESM1-M', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'mrro', standard_name = 'runoff_flux', long_name = 'Total Runoff', units = 'kg m-2 s-1', modeling_realm = ['land'], frequency = 'mon', version = 'v20120920' 2023-05-30 13:12:15,082 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] 2023-05-30 13:12:15,082 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step load 2023-05-30 13:12:15,082 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'load' on the data LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) callback = 'default' 2023-05-30 13:12:15,082 UTC [370717] DEBUG esmvalcore.preprocessor._io:152 Loading: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:15,272 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_metadata 2023-05-30 13:12:15,274 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_metadata' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'NorESM1-ME', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'evspsbl', timerange = '1970/2000', variable_group = 'evspsbl', diagnostic = 'catchment_analysis', recipe_dataset_index = 2, alias = 'NorESM1-ME', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'evspsbl', standard_name = 'water_evaporation_flux', long_name = 'Evaporation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20140327' 2023-05-30 13:12:15,281 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:15,282 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_evspsbl_1970-2000/01_fix_metadata.nc 2023-05-30 13:12:15,302 UTC [370717] DEBUG esmvalcore.preprocessor._io:182 Done with loading /projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:15,302 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:15,303 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-M_Lmon_historical_r1i1p1_mrro_1970-2000/00_load.nc 2023-05-30 13:12:15,495 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step concatenate 2023-05-30 13:12:15,497 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'concatenate' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) 2023-05-30 13:12:15,497 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:15,498 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_evspsbl_1970-2000/02_concatenate.nc 2023-05-30 13:12:15,571 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_metadata 2023-05-30 13:12:15,573 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_metadata' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Lmon', dataset = 'NorESM1-M', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'mrro', timerange = '1970/2000', variable_group = 'mrro', diagnostic = 'catchment_analysis', recipe_dataset_index = 3, alias = 'NorESM1-M', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'mrro', standard_name = 'runoff_flux', long_name = 'Total Runoff', units = 'kg m-2 s-1', modeling_realm = ['land'], frequency = 'mon', version = 'v20120920' 2023-05-30 13:12:15,581 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:15,582 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-M_Lmon_historical_r1i1p1_mrro_1970-2000/01_fix_metadata.nc 2023-05-30 13:12:15,711 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_metadata 2023-05-30 13:12:15,713 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_metadata' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'evspsbl' 2023-05-30 13:12:15,718 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:15,719 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_evspsbl_1970-2000/03_cmor_check_metadata.nc 2023-05-30 13:12:15,792 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step concatenate 2023-05-30 13:12:15,793 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'concatenate' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) 2023-05-30 13:12:15,794 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:15,794 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-M_Lmon_historical_r1i1p1_mrro_1970-2000/02_concatenate.nc 2023-05-30 13:12:15,949 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step clip_timerange 2023-05-30 13:12:15,950 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'clip_timerange' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) timerange = '1970/2000' 2023-05-30 13:12:15,962 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:15,963 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_evspsbl_1970-2000/04_clip_timerange.nc 2023-05-30 13:12:16,022 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_metadata 2023-05-30 13:12:16,023 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_metadata' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Lmon', frequency = 'mon', short_name = 'mrro' 2023-05-30 13:12:16,028 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:16,029 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-M_Lmon_historical_r1i1p1_mrro_1970-2000/03_cmor_check_metadata.nc 2023-05-30 13:12:16,242 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_data 2023-05-30 13:12:16,244 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'NorESM1-ME', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'evspsbl', timerange = '1970/2000', variable_group = 'evspsbl', diagnostic = 'catchment_analysis', recipe_dataset_index = 2, alias = 'NorESM1-ME', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'evspsbl', standard_name = 'water_evaporation_flux', long_name = 'Evaporation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20140327' 2023-05-30 13:12:16,244 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:16,245 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_evspsbl_1970-2000/05_fix_data.nc 2023-05-30 13:12:16,259 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step clip_timerange 2023-05-30 13:12:16,260 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'clip_timerange' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) timerange = '1970/2000' 2023-05-30 13:12:16,270 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:16,271 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-M_Lmon_historical_r1i1p1_mrro_1970-2000/04_clip_timerange.nc 2023-05-30 13:12:16,508 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_data 2023-05-30 13:12:16,509 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'evspsbl' 2023-05-30 13:12:16,509 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:16,510 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_evspsbl_1970-2000/06_cmor_check_data.nc 2023-05-30 13:12:16,526 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_data 2023-05-30 13:12:16,527 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Lmon', dataset = 'NorESM1-M', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'mrro', timerange = '1970/2000', variable_group = 'mrro', diagnostic = 'catchment_analysis', recipe_dataset_index = 3, alias = 'NorESM1-M', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'mrro', standard_name = 'runoff_flux', long_name = 'Total Runoff', units = 'kg m-2 s-1', modeling_realm = ['land'], frequency = 'mon', version = 'v20120920' 2023-05-30 13:12:16,528 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:16,529 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-M_Lmon_historical_r1i1p1_mrro_1970-2000/05_fix_data.nc 2023-05-30 13:12:16,760 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step add_supplementary_variables 2023-05-30 13:12:16,761 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'add_supplementary_variables' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) supplementary_cubes = [] 2023-05-30 13:12:16,761 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:16,762 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_evspsbl_1970-2000/07_add_supplementary_variables.nc 2023-05-30 13:12:16,808 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_data 2023-05-30 13:12:16,809 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Lmon', frequency = 'mon', short_name = 'mrro' 2023-05-30 13:12:16,810 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:16,810 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-M_Lmon_historical_r1i1p1_mrro_1970-2000/06_cmor_check_data.nc 2023-05-30 13:12:17,023 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step save 2023-05-30 13:12:17,025 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'save' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/evspsbl/evspsbl_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) compress = False, filename = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_evspsbl_1970-2000.nc') 2023-05-30 13:12:17,025 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/evspsbl/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_evspsbl_1970-2000.nc 2023-05-30 13:12:17,091 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step add_supplementary_variables 2023-05-30 13:12:17,092 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'add_supplementary_variables' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) supplementary_cubes = [] 2023-05-30 13:12:17,092 UTC [370717] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:17,093 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-M_Lmon_historical_r1i1p1_mrro_1970-2000/07_add_supplementary_variables.nc 2023-05-30 13:12:17,291 UTC [370716] INFO esmvalcore._task:264 Successfully completed task catchment_analysis/evspsbl (priority 1) in 0:00:20.953092 2023-05-30 13:12:17,343 UTC [370717] DEBUG esmvalcore.preprocessor:369 Running preprocessor step save 2023-05-30 13:12:17,345 UTC [370717] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'save' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/land/Lmon/r1i1p1/v20120920/mrro/mrro_Lmon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) compress = False, filename = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-M_Lmon_historical_r1i1p1_mrro_1970-2000.nc') 2023-05-30 13:12:17,345 UTC [370717] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/mrro/CMIP5_NorESM1-M_Lmon_historical_r1i1p1_mrro_1970-2000.nc 2023-05-30 13:12:17,468 UTC [370529] INFO esmvalcore._task:778 Progress: 1 tasks running, 2 tasks waiting for ancestors, 1/4 done 2023-05-30 13:12:17,470 UTC [370716] INFO esmvalcore._task:259 Starting task catchment_analysis/pr in process [370716] 2023-05-30 13:12:17,568 UTC [370529] INFO esmvalcore._task:778 Progress: 2 tasks running, 1 tasks waiting for ancestors, 1/4 done 2023-05-30 13:12:17,618 UTC [370717] INFO esmvalcore._task:264 Successfully completed task catchment_analysis/mrro (priority 2) in 0:00:21.280071 2023-05-30 13:12:17,622 UTC [370716] DEBUG esmvalcore.esgf._download:535 All required data is available locally, not downloading anything. 2023-05-30 13:12:17,622 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_file 2023-05-30 13:12:17,623 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_file' on the data LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) output_dir = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/fixed_files/pr_Amon_CMIP5_MPI-ESM-LR_historical_r1i1p1_v20120315_'), add_unique_suffix = True, mip = 'Amon', dataset = 'MPI-ESM-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'pr', timerange = '1970/2000', variable_group = 'pr', diagnostic = 'catchment_analysis', recipe_dataset_index = 0, alias = 'MPI-ESM-LR', product = ['output1', 'output2'], institute = ['MPI-M'], original_short_name = 'pr', standard_name = 'precipitation_flux', long_name = 'Precipitation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120315' 2023-05-30 13:12:17,623 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] 2023-05-30 13:12:17,623 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step load 2023-05-30 13:12:17,623 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'load' on the data LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) callback = 'default' 2023-05-30 13:12:17,623 UTC [370716] DEBUG esmvalcore.preprocessor._io:152 Loading: /projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:17,764 UTC [370716] DEBUG esmvalcore.preprocessor._io:182 Done with loading /projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:17,764 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:17,766 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_pr_1970-2000/00_load.nc 2023-05-30 13:12:17,769 UTC [370529] INFO esmvalcore._task:778 Progress: 1 tasks running, 1 tasks waiting for ancestors, 2/4 done 2023-05-30 13:12:18,110 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_metadata 2023-05-30 13:12:18,112 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_metadata' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'MPI-ESM-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'pr', timerange = '1970/2000', variable_group = 'pr', diagnostic = 'catchment_analysis', recipe_dataset_index = 0, alias = 'MPI-ESM-LR', product = ['output1', 'output2'], institute = ['MPI-M'], original_short_name = 'pr', standard_name = 'precipitation_flux', long_name = 'Precipitation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120315' 2023-05-30 13:12:18,119 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:18,120 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_pr_1970-2000/01_fix_metadata.nc 2023-05-30 13:12:18,454 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step concatenate 2023-05-30 13:12:18,456 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'concatenate' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) 2023-05-30 13:12:18,456 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:18,457 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_pr_1970-2000/02_concatenate.nc 2023-05-30 13:12:18,793 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_metadata 2023-05-30 13:12:18,794 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_metadata' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'pr' 2023-05-30 13:12:18,801 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:18,801 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_pr_1970-2000/03_cmor_check_metadata.nc 2023-05-30 13:12:19,123 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step clip_timerange 2023-05-30 13:12:19,124 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'clip_timerange' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) timerange = '1970/2000' 2023-05-30 13:12:19,135 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:19,136 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_pr_1970-2000/04_clip_timerange.nc 2023-05-30 13:12:19,334 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_data 2023-05-30 13:12:19,335 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'MPI-ESM-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'pr', timerange = '1970/2000', variable_group = 'pr', diagnostic = 'catchment_analysis', recipe_dataset_index = 0, alias = 'MPI-ESM-LR', product = ['output1', 'output2'], institute = ['MPI-M'], original_short_name = 'pr', standard_name = 'precipitation_flux', long_name = 'Precipitation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120315' 2023-05-30 13:12:19,335 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:19,336 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_pr_1970-2000/05_fix_data.nc 2023-05-30 13:12:19,550 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_data 2023-05-30 13:12:19,551 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'pr' 2023-05-30 13:12:19,552 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:19,553 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_pr_1970-2000/06_cmor_check_data.nc 2023-05-30 13:12:19,760 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step add_supplementary_variables 2023-05-30 13:12:19,762 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'add_supplementary_variables' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) supplementary_cubes = [] 2023-05-30 13:12:19,762 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:19,763 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_pr_1970-2000/07_add_supplementary_variables.nc 2023-05-30 13:12:19,956 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step save 2023-05-30 13:12:19,958 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'save' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/MPI-M/MPI-ESM-LR/historical/mon/atmos/Amon/r1i1p1/v20120315/pr/pr_Amon_MPI-ESM-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) compress = False, filename = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_pr_1970-2000.nc') 2023-05-30 13:12:19,958 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_MPI-ESM-LR_Amon_historical_r1i1p1_pr_1970-2000.nc 2023-05-30 13:12:20,168 UTC [370716] DEBUG esmvalcore.esgf._download:535 All required data is available locally, not downloading anything. 2023-05-30 13:12:20,168 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_file 2023-05-30 13:12:20,168 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_file' on the data LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) output_dir = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/fixed_files/pr_Amon_CMIP5_IPSL-CM5B-LR_historical_r1i1p1_v20120114_'), add_unique_suffix = True, mip = 'Amon', dataset = 'IPSL-CM5B-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'pr', timerange = '1970/2000', variable_group = 'pr', diagnostic = 'catchment_analysis', recipe_dataset_index = 1, alias = 'IPSL-CM5B-LR', product = ['output1', 'output2'], institute = ['IPSL'], original_short_name = 'pr', standard_name = 'precipitation_flux', long_name = 'Precipitation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120114' 2023-05-30 13:12:20,169 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] 2023-05-30 13:12:20,169 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step load 2023-05-30 13:12:20,169 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'load' on the data LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) callback = 'default' 2023-05-30 13:12:20,169 UTC [370716] DEBUG esmvalcore.preprocessor._io:152 Loading: /projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:20,240 UTC [370716] DEBUG esmvalcore.preprocessor._io:182 Done with loading /projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:20,240 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:20,241 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_pr_1970-2000/00_load.nc 2023-05-30 13:12:20,430 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_metadata 2023-05-30 13:12:20,432 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_metadata' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'IPSL-CM5B-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'pr', timerange = '1970/2000', variable_group = 'pr', diagnostic = 'catchment_analysis', recipe_dataset_index = 1, alias = 'IPSL-CM5B-LR', product = ['output1', 'output2'], institute = ['IPSL'], original_short_name = 'pr', standard_name = 'precipitation_flux', long_name = 'Precipitation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120114' 2023-05-30 13:12:20,437 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:20,438 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_pr_1970-2000/01_fix_metadata.nc 2023-05-30 13:12:20,647 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step concatenate 2023-05-30 13:12:20,649 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'concatenate' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) 2023-05-30 13:12:20,649 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:20,650 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_pr_1970-2000/02_concatenate.nc 2023-05-30 13:12:20,825 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_metadata 2023-05-30 13:12:20,826 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_metadata' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'pr' 2023-05-30 13:12:20,832 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:20,833 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_pr_1970-2000/03_cmor_check_metadata.nc 2023-05-30 13:12:21,019 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step clip_timerange 2023-05-30 13:12:21,020 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'clip_timerange' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) timerange = '1970/2000' 2023-05-30 13:12:21,030 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:21,031 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_pr_1970-2000/04_clip_timerange.nc 2023-05-30 13:12:21,178 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_data 2023-05-30 13:12:21,179 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'IPSL-CM5B-LR', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'pr', timerange = '1970/2000', variable_group = 'pr', diagnostic = 'catchment_analysis', recipe_dataset_index = 1, alias = 'IPSL-CM5B-LR', product = ['output1', 'output2'], institute = ['IPSL'], original_short_name = 'pr', standard_name = 'precipitation_flux', long_name = 'Precipitation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120114' 2023-05-30 13:12:21,180 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:21,181 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_pr_1970-2000/05_fix_data.nc 2023-05-30 13:12:21,322 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_data 2023-05-30 13:12:21,323 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'pr' 2023-05-30 13:12:21,323 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:21,324 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_pr_1970-2000/06_cmor_check_data.nc 2023-05-30 13:12:21,465 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step add_supplementary_variables 2023-05-30 13:12:21,466 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'add_supplementary_variables' on the data loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) supplementary_cubes = [] 2023-05-30 13:12:21,466 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:21,467 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_pr_1970-2000/07_add_supplementary_variables.nc 2023-05-30 13:12:21,608 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step save 2023-05-30 13:12:21,610 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'save' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9560K-datalake/ESGF/cmip5/output1/IPSL/IPSL-CM5B-LR/historical/mon/atmos/Amon/r1i1p1/v20120114/pr/pr_Amon_IPSL-CM5B-LR_historical_r1i1p1_185001-200512.nc')] with function argument(s) compress = False, filename = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_pr_1970-2000.nc') 2023-05-30 13:12:21,610 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_IPSL-CM5B-LR_Amon_historical_r1i1p1_pr_1970-2000.nc 2023-05-30 13:12:21,757 UTC [370716] DEBUG esmvalcore.esgf._download:535 All required data is available locally, not downloading anything. 2023-05-30 13:12:21,757 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_file 2023-05-30 13:12:21,757 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_file' on the data LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) output_dir = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/fixed_files/pr_Amon_CMIP5_NorESM1-M_historical_r1i1p1_v20120412_'), add_unique_suffix = True, mip = 'Amon', dataset = 'NorESM1-M', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'pr', timerange = '1970/2000', variable_group = 'pr', diagnostic = 'catchment_analysis', recipe_dataset_index = 3, alias = 'NorESM1-M', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'pr', standard_name = 'precipitation_flux', long_name = 'Precipitation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120412' 2023-05-30 13:12:21,757 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] 2023-05-30 13:12:21,757 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step load 2023-05-30 13:12:21,758 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'load' on the data LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) callback = 'default' 2023-05-30 13:12:21,758 UTC [370716] DEBUG esmvalcore.preprocessor._io:152 Loading: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:22,018 UTC [370716] DEBUG esmvalcore.preprocessor._io:182 Done with loading /projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:22,019 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:22,020 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-M_Amon_historical_r1i1p1_pr_1970-2000/00_load.nc 2023-05-30 13:12:22,238 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_metadata 2023-05-30 13:12:22,239 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_metadata' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'NorESM1-M', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'pr', timerange = '1970/2000', variable_group = 'pr', diagnostic = 'catchment_analysis', recipe_dataset_index = 3, alias = 'NorESM1-M', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'pr', standard_name = 'precipitation_flux', long_name = 'Precipitation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120412' 2023-05-30 13:12:22,245 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:22,246 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-M_Amon_historical_r1i1p1_pr_1970-2000/01_fix_metadata.nc 2023-05-30 13:12:22,463 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step concatenate 2023-05-30 13:12:22,465 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'concatenate' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) 2023-05-30 13:12:22,465 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:22,466 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-M_Amon_historical_r1i1p1_pr_1970-2000/02_concatenate.nc 2023-05-30 13:12:22,711 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_metadata 2023-05-30 13:12:22,712 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_metadata' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'pr' 2023-05-30 13:12:22,717 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:22,718 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-M_Amon_historical_r1i1p1_pr_1970-2000/03_cmor_check_metadata.nc 2023-05-30 13:12:22,919 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step clip_timerange 2023-05-30 13:12:22,920 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'clip_timerange' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) timerange = '1970/2000' 2023-05-30 13:12:22,930 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:22,931 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-M_Amon_historical_r1i1p1_pr_1970-2000/04_clip_timerange.nc 2023-05-30 13:12:23,175 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_data 2023-05-30 13:12:23,176 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'NorESM1-M', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'pr', timerange = '1970/2000', variable_group = 'pr', diagnostic = 'catchment_analysis', recipe_dataset_index = 3, alias = 'NorESM1-M', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'pr', standard_name = 'precipitation_flux', long_name = 'Precipitation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20120412' 2023-05-30 13:12:23,177 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:23,177 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-M_Amon_historical_r1i1p1_pr_1970-2000/05_fix_data.nc 2023-05-30 13:12:23,414 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_data 2023-05-30 13:12:23,415 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'pr' 2023-05-30 13:12:23,415 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:23,416 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-M_Amon_historical_r1i1p1_pr_1970-2000/06_cmor_check_data.nc 2023-05-30 13:12:23,663 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step add_supplementary_variables 2023-05-30 13:12:23,664 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'add_supplementary_variables' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) supplementary_cubes = [] 2023-05-30 13:12:23,664 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:23,665 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-M_Amon_historical_r1i1p1_pr_1970-2000/07_add_supplementary_variables.nc 2023-05-30 13:12:23,893 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step save 2023-05-30 13:12:23,895 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'save' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-M/historical/mon/atmos/Amon/r1i1p1/v20120412/pr/pr_Amon_NorESM1-M_historical_r1i1p1_185001-200512.nc')] with function argument(s) compress = False, filename = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-M_Amon_historical_r1i1p1_pr_1970-2000.nc') 2023-05-30 13:12:23,895 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-M_Amon_historical_r1i1p1_pr_1970-2000.nc 2023-05-30 13:12:24,137 UTC [370716] DEBUG esmvalcore.esgf._download:535 All required data is available locally, not downloading anything. 2023-05-30 13:12:24,137 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_file 2023-05-30 13:12:24,138 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_file' on the data LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) output_dir = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/fixed_files/pr_Amon_CMIP5_NorESM1-ME_historical_r1i1p1_v20140327_'), add_unique_suffix = True, mip = 'Amon', dataset = 'NorESM1-ME', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'pr', timerange = '1970/2000', variable_group = 'pr', diagnostic = 'catchment_analysis', recipe_dataset_index = 2, alias = 'NorESM1-ME', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'pr', standard_name = 'precipitation_flux', long_name = 'Precipitation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20140327' 2023-05-30 13:12:24,138 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] 2023-05-30 13:12:24,138 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step load 2023-05-30 13:12:24,138 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'load' on the data LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc') loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) callback = 'default' 2023-05-30 13:12:24,138 UTC [370716] DEBUG esmvalcore.preprocessor._io:152 Loading: /projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:24,209 UTC [370716] DEBUG esmvalcore.preprocessor._io:182 Done with loading /projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc 2023-05-30 13:12:24,210 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:24,211 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_pr_1970-2000/00_load.nc 2023-05-30 13:12:24,418 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_metadata 2023-05-30 13:12:24,420 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_metadata' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'NorESM1-ME', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'pr', timerange = '1970/2000', variable_group = 'pr', diagnostic = 'catchment_analysis', recipe_dataset_index = 2, alias = 'NorESM1-ME', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'pr', standard_name = 'precipitation_flux', long_name = 'Precipitation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20140327' 2023-05-30 13:12:24,429 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:24,430 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_pr_1970-2000/01_fix_metadata.nc 2023-05-30 13:12:24,693 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step concatenate 2023-05-30 13:12:24,696 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'concatenate' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) 2023-05-30 13:12:24,696 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:24,697 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_pr_1970-2000/02_concatenate.nc 2023-05-30 13:12:24,929 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_metadata 2023-05-30 13:12:24,930 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_metadata' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'pr' 2023-05-30 13:12:24,936 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:24,937 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_pr_1970-2000/03_cmor_check_metadata.nc 2023-05-30 13:12:25,182 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step clip_timerange 2023-05-30 13:12:25,183 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'clip_timerange' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) timerange = '1970/2000' 2023-05-30 13:12:25,193 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:25,194 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_pr_1970-2000/04_clip_timerange.nc 2023-05-30 13:12:25,455 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step fix_data 2023-05-30 13:12:25,456 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'fix_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , mip = 'Amon', dataset = 'NorESM1-ME', project = 'CMIP5', exp = 'historical', ensemble = 'r1i1p1', short_name = 'pr', timerange = '1970/2000', variable_group = 'pr', diagnostic = 'catchment_analysis', recipe_dataset_index = 2, alias = 'NorESM1-ME', product = ['output1', 'output2'], institute = ['NCC'], original_short_name = 'pr', standard_name = 'precipitation_flux', long_name = 'Precipitation', units = 'kg m-2 s-1', modeling_realm = ['atmos'], frequency = 'mon', version = 'v20140327' 2023-05-30 13:12:25,457 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:25,458 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_pr_1970-2000/05_fix_data.nc 2023-05-30 13:12:25,714 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step cmor_check_data 2023-05-30 13:12:25,715 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'cmor_check_data' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) check_level = , cmor_table = 'CMIP5', mip = 'Amon', frequency = 'mon', short_name = 'pr' 2023-05-30 13:12:25,716 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:25,716 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_pr_1970-2000/06_cmor_check_data.nc 2023-05-30 13:12:25,952 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step add_supplementary_variables 2023-05-30 13:12:25,953 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'add_supplementary_variables' on the data loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) supplementary_cubes = [] 2023-05-30 13:12:25,953 UTC [370716] DEBUG esmvalcore.preprocessor:390 Result [] 2023-05-30 13:12:25,953 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_pr_1970-2000/07_add_supplementary_variables.nc 2023-05-30 13:12:26,185 UTC [370716] DEBUG esmvalcore.preprocessor:369 Running preprocessor step save 2023-05-30 13:12:26,187 UTC [370716] DEBUG esmvalcore.preprocessor:323 Running preprocessor function 'save' on the data [] loaded from original input file(s) [LocalFile('/projects/NS9034K/CMIP5/output1/NCC/NorESM1-ME/historical/mon/atmos/Amon/r1i1p1/v20140327/pr/pr_Amon_NorESM1-ME_historical_r1i1p1_185001-200512.nc')] with function argument(s) compress = False, filename = PosixPath('/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_pr_1970-2000.nc') 2023-05-30 13:12:26,187 UTC [370716] DEBUG esmvalcore.preprocessor._io:307 Saving cubes [] to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/preproc/catchment_analysis/pr/CMIP5_NorESM1-ME_Amon_historical_r1i1p1_pr_1970-2000.nc 2023-05-30 13:12:26,444 UTC [370716] INFO esmvalcore._task:264 Successfully completed task catchment_analysis/pr (priority 3) in 0:00:08.973129 2023-05-30 13:12:26,481 UTC [370529] INFO esmvalcore._task:778 Progress: 0 tasks running, 1 tasks waiting for ancestors, 3/4 done 2023-05-30 13:12:26,494 UTC [370717] INFO esmvalcore._task:259 Starting task catchment_analysis/catchment_analysis in process [370717] 2023-05-30 13:12:26,498 UTC [370717] INFO esmvalcore._task:500 Running command ['/diagnostics/esmvaltool/2.8.0/bin/python', '/diagnostics/esmvaltool/2.8.0/lib/python3.10/site-packages/esmvaltool/diag_scripts/runoff_et/catchment_analysis.py', '/projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/run/catchment_analysis/catchment_analysis/settings.yml'] 2023-05-30 13:12:26,498 UTC [370717] DEBUG esmvalcore._task:501 in environment {'MPLBACKEND': 'Agg'} 2023-05-30 13:12:26,498 UTC [370717] DEBUG esmvalcore._task:503 in current working directory: /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/run/catchment_analysis/catchment_analysis 2023-05-30 13:12:26,498 UTC [370717] INFO esmvalcore._task:504 Writing output to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/work/catchment_analysis/catchment_analysis 2023-05-30 13:12:26,498 UTC [370717] INFO esmvalcore._task:505 Writing plots to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/plots/catchment_analysis/catchment_analysis 2023-05-30 13:12:26,498 UTC [370717] INFO esmvalcore._task:506 Writing log to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/run/catchment_analysis/catchment_analysis/log.txt 2023-05-30 13:12:26,499 UTC [370717] INFO esmvalcore._task:516 To re-run this diagnostic script, run: cd /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/run/catchment_analysis/catchment_analysis; MPLBACKEND="Agg" /diagnostics/esmvaltool/2.8.0/bin/python /diagnostics/esmvaltool/2.8.0/lib/python3.10/site-packages/esmvaltool/diag_scripts/runoff_et/catchment_analysis.py /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/run/catchment_analysis/catchment_analysis/settings.yml 2023-05-30 13:12:26,582 UTC [370529] INFO esmvalcore._task:778 Progress: 1 tasks running, 0 tasks waiting for ancestors, 3/4 done 2023-05-30 13:13:31,711 UTC [370717] INFO esmvalcore._task:126 Maximum memory used (estimate): 0.7 GB 2023-05-30 13:13:31,711 UTC [370717] INFO esmvalcore._task:128 Sampled every second. It may be inaccurate if short but high spikes in memory consumption occur. 2023-05-30 13:13:31,712 UTC [370717] DEBUG esmvalcore._task:589 Script runoff_et/catchment_analysis.py completed successfully 2023-05-30 13:13:31,712 UTC [370717] WARNING esmvalcore._task:602 No provenance information was written to /projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/run/catchment_analysis/catchment_analysis/diagnostic_provenance.yml. Unable to record provenance for files created by diagnostic script runoff_et/catchment_analysis.py in task catchment_analysis/catchment_analysis 2023-05-30 13:13:31,712 UTC [370717] INFO esmvalcore._task:264 Successfully completed task catchment_analysis/catchment_analysis (priority 0) in 0:01:05.217689 2023-05-30 13:13:31,764 UTC [370529] INFO esmvalcore._task:778 Progress: 0 tasks running, 0 tasks waiting for ancestors, 4/4 done 2023-05-30 13:13:31,764 UTC [370529] INFO esmvalcore._task:783 Successfully completed all tasks. 2023-05-30 13:13:31,807 UTC [370529] INFO esmvalcore._recipe.recipe:1309 Wrote recipe with version numbers and wildcards to: file:///projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/run/recipe_runoff_et_filled.yml 2023-05-30 13:13:31,885 UTC [370529] INFO esmvalcore.experimental.recipe_output:205 Wrote recipe output to: file:///projects/NS9560K/www/diagnostics/esmvaltool/adagj/tmp/recipe_runoff_et_20230530_131150/index.html 2023-05-30 13:13:31,886 UTC [370529] INFO esmvalcore._main:125 Ending the Earth System Model Evaluation Tool at time: 2023-05-30 13:13:31 UTC 2023-05-30 13:13:31,886 UTC [370529] INFO esmvalcore._main:128 Time for running the recipe was: 0:01:35.717700 2023-05-30 13:13:32,072 UTC [370529] INFO esmvalcore._task:126 Maximum memory used (estimate): 2.5 GB 2023-05-30 13:13:32,072 UTC [370529] INFO esmvalcore._task:128 Sampled every second. It may be inaccurate if short but high spikes in memory consumption occur. 2023-05-30 13:13:32,073 UTC [370529] INFO esmvalcore._main:453 Run was successful