------------------ 20220606 00:00 Waiting up to 200 seconds to get a lock for GEO satellite MSG4 ... flock: getting lock took 0.000010 seconds ... 202206060000 Decompressed file: H-000-MSG4__-MSG4________-IR_039___-000007___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_039___-000008___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_039___-000003___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_039___-000006___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_039___-000005___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_039___-000002___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_039___-000004___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_039___-000001___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_108___-000007___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_108___-000004___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_108___-000003___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_108___-000001___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_108___-000005___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_108___-000002___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_108___-000008___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_108___-000006___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_120___-000001___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_120___-000003___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_120___-000002___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_120___-000008___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_120___-000004___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_120___-000007___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_120___-000006___-202206060000-__ Decompressed file: H-000-MSG4__-MSG4________-IR_120___-000005___-202206060000-__ [DEBUG: 2022-06-06 19:14:08 : satpy.readers.yaml_reader] Reading ('/home/eumetcast/miniconda3/envs/pytroll/lib/python3.8/site-packages/satpy/etc/readers/seviri_l1b_hrit.yaml',) /home/eumetcast/miniconda3/envs/pytroll/lib/python3.8/site-packages/satpy/readers/seviri_base.py:453: DeprecationWarning: `np.bool` is a deprecated alias for the builtin `bool`. To silence this warning, use `bool` by itself. Doing this will not modify any behavior and is safe. If you specifically wanted the numpy scalar type, use `np.bool_` here. Deprecated in NumPy 1.20; for more details and guidance: https://numpy.org/devdocs/release/1.20.0-notes.html#deprecations ('GsicsCalMode', np.bool), /home/eumetcast/miniconda3/envs/pytroll/lib/python3.8/site-packages/satpy/readers/seviri_base.py:454: DeprecationWarning: `np.bool` is a deprecated alias for the builtin `bool`. To silence this warning, use `bool` by itself. Doing this will not modify any behavior and is safe. If you specifically wanted the numpy scalar type, use `np.bool_` here. Deprecated in NumPy 1.20; for more details and guidance: https://numpy.org/devdocs/release/1.20.0-notes.html#deprecations ('GsicsCalValidity', np.bool), [DEBUG: 2022-06-06 19:14:08 : satpy.readers.yaml_reader] Assigning to seviri_l1b_hrit: ['/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_039___-000008___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_039___-000001___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_039___-000004___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_039___-000005___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_039___-000007___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_039___-000006___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_039___-000002___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_039___-000003___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_108___-000003___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_108___-000006___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_108___-000008___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_108___-000005___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_108___-000002___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_108___-000007___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_108___-000001___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_108___-000004___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_120___-000008___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_120___-000001___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_120___-000005___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_120___-000006___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_120___-000007___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_120___-000002___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_120___-000003___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-IR_120___-000004___-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-_________-PRO______-202206060000-__', '/home/eumetcast/EMCdata/tmpdirs/xmsg4/H-000-MSG4__-MSG4________-_________-EPI______-202206060000-__'] [INFO: 2022-06-06 19:14:08 : hrit_msg] No IMPF configuration field found in prologue. [DEBUG: 2022-06-06 19:14:08 : satpy.composites.config_loader] Looking for composites config file seviri.yaml [DEBUG: 2022-06-06 19:14:08 : satpy.composites.config_loader] Looking for composites config file visir.yaml [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.012185 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.012123 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.011922 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.011951 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.012161 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.012073 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.012378 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.012090 [DEBUG: 2022-06-06 19:14:10 : satpy.readers.yaml_reader] Requested orientation for Dataset None is 'native' (default). No flipping is applied. [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.011949 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.012012 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.011380 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.012080 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.012091 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.011934 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.012064 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.011285 [DEBUG: 2022-06-06 19:14:10 : satpy.readers.yaml_reader] Requested orientation for Dataset None is 'native' (default). No flipping is applied. [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.011330 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.012019 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.012332 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.012153 [DEBUG: 2022-06-06 19:14:10 : hrit_msg] Calibration time 0:00:00.012110 [DEBUG: 2022-06-06 19:14:11 : hrit_msg] Calibration time 0:00:00.011349 [DEBUG: 2022-06-06 19:14:11 : hrit_msg] Calibration time 0:00:00.012742 [DEBUG: 2022-06-06 19:14:11 : hrit_msg] Calibration time 0:00:00.011912 [DEBUG: 2022-06-06 19:14:11 : satpy.readers.yaml_reader] Requested orientation for Dataset None is 'native' (default). No flipping is applied. [DEBUG: 2022-06-06 19:14:11 : satpy.readers.yaml_reader] Reading ('/home/eumetcast/miniconda3/envs/pytroll/lib/python3.8/site-packages/satpy/etc/readers/generic_image.yaml',) [DEBUG: 2022-06-06 19:14:11 : satpy.readers.yaml_reader] Assigning to generic_image: ['/home/eumetcast/.local/share/satpy/composites/BlackMarble_2016_3km_geo.tif'] [DEBUG: 2022-06-06 19:14:11 : rasterio.env] Entering env context: [DEBUG: 2022-06-06 19:14:11 : rasterio.env] Starting outermost env [DEBUG: 2022-06-06 19:14:11 : rasterio.env] No GDAL environment exists [DEBUG: 2022-06-06 19:14:11 : rasterio.env] New GDAL environment created [DEBUG: 2022-06-06 19:14:11 : rasterio._env] GDAL_DATA found in environment. [DEBUG: 2022-06-06 19:14:11 : rasterio._env] PROJ_LIB found in environment. [DEBUG: 2022-06-06 19:14:11 : rasterio._env] Started GDALEnv: self=. [DEBUG: 2022-06-06 19:14:11 : rasterio.env] Entered env context: [DEBUG: 2022-06-06 19:14:11 : rasterio._base] Sharing flag: 0 [DEBUG: 2022-06-06 19:14:11 : rasterio._base] Nodata success: 0, Nodata value: -10000000000.000000 [DEBUG: 2022-06-06 19:14:11 : rasterio._base] Nodata success: 0, Nodata value: -10000000000.000000 [DEBUG: 2022-06-06 19:14:11 : rasterio._base] Nodata success: 0, Nodata value: -10000000000.000000 [DEBUG: 2022-06-06 19:14:11 : rasterio._base] Dataset is started. [DEBUG: 2022-06-06 19:14:11 : rasterio.env] Exiting env context: [DEBUG: 2022-06-06 19:14:11 : rasterio.env] Cleared existing options [DEBUG: 2022-06-06 19:14:11 : rasterio._env] Stopped GDALEnv . [DEBUG: 2022-06-06 19:14:11 : rasterio.env] Exiting outermost env [DEBUG: 2022-06-06 19:14:11 : rasterio.env] Exited env context: /home/eumetcast/miniconda3/envs/pytroll/lib/python3.8/site-packages/satpy/readers/generic_image.py:78: DeprecationWarning: open_rasterio is Deprecated in favor of rioxarray. For information about transitioning, see: https://corteva.github.io/rioxarray/stable/getting_started/getting_started.html data = xr.open_rasterio(dataset, chunks=(1, CHUNK_SIZE, CHUNK_SIZE)) [DEBUG: 2022-06-06 19:14:11 : rasterio.env] Entering env context: [DEBUG: 2022-06-06 19:14:11 : rasterio.env] Starting outermost env [DEBUG: 2022-06-06 19:14:11 : rasterio.env] No GDAL environment exists [DEBUG: 2022-06-06 19:14:11 : rasterio.env] New GDAL environment created [DEBUG: 2022-06-06 19:14:11 : rasterio._env] GDAL_DATA found in environment. [DEBUG: 2022-06-06 19:14:11 : rasterio._env] PROJ_LIB found in environment. [DEBUG: 2022-06-06 19:14:11 : rasterio._env] Started GDALEnv: self=. [DEBUG: 2022-06-06 19:14:11 : rasterio.env] Entered env context: [DEBUG: 2022-06-06 19:14:11 : rasterio._base] Sharing flag: 0 [DEBUG: 2022-06-06 19:14:11 : rasterio._base] Nodata success: 0, Nodata value: -10000000000.000000 [DEBUG: 2022-06-06 19:14:11 : rasterio._base] Nodata success: 0, Nodata value: -10000000000.000000 [DEBUG: 2022-06-06 19:14:11 : rasterio._base] Nodata success: 0, Nodata value: -10000000000.000000 [DEBUG: 2022-06-06 19:14:11 : rasterio._base] Dataset is started. [DEBUG: 2022-06-06 19:14:11 : rasterio.env] Exiting env context: [DEBUG: 2022-06-06 19:14:11 : rasterio.env] Cleared existing options [DEBUG: 2022-06-06 19:14:11 : rasterio._env] Stopped GDALEnv . [DEBUG: 2022-06-06 19:14:11 : rasterio.env] Exiting outermost env [DEBUG: 2022-06-06 19:14:11 : rasterio.env] Exited env context: [DEBUG: 2022-06-06 19:14:11 : rasterio._crs] Matched. confidence=100, c_code=b'4326', c_name=b'EPSG' [DEBUG: 2022-06-06 19:14:11 : satpy.composites.config_loader] Looking for composites config file images.yaml [DEBUG: 2022-06-06 19:14:11 : satpy.composites.config_loader] No composite config found called images.yaml [DEBUG: 2022-06-06 19:14:11 : satpy.readers.generic_image] Reading 'image.' [WARNING: 2022-06-06 19:14:11 : satpy.readers.generic_image] cannot convert float NaN to integer [DEBUG: 2022-06-06 19:14:11 : satpy.scene] Delaying generation of DataID(name='night_ir_with_background_hires') because of incompatible areas [WARNING: 2022-06-06 19:14:11 : satpy.scene] The following datasets were not created and may require resampling to be generated: DataID(name='night_ir_with_background_hires') [DEBUG: 2022-06-06 19:14:11 : satpy.scene] Unloading dataset: DataID(name='IR_039', wavelength=WavelengthRange(min=3.48, central=3.92, max=4.36, unit='µm'), resolution=3000.403165817, calibration=, modifiers=()) [DEBUG: 2022-06-06 19:14:11 : satpy.scene] Unloading dataset: DataID(name='IR_120', wavelength=WavelengthRange(min=11.0, central=12.0, max=13.0, unit='µm'), resolution=3000.403165817, calibration=, modifiers=()) [DEBUG: 2022-06-06 19:14:11 : satpy.scene] Unloading dataset: DataID(name='IR_108', wavelength=WavelengthRange(min=9.8, central=10.8, max=11.8, unit='µm'), resolution=3000.403165817, calibration=, modifiers=()) [DEBUG: 2022-06-06 19:14:11 : satpy.scene] Resampling DataID(name='_night_background_hires') [INFO: 2022-06-06 19:14:11 : satpy.scene] Not reducing data before resampling. [INFO: 2022-06-06 19:14:11 : satpy.resample] Using default KDTree resampler [DEBUG: 2022-06-06 19:14:11 : satpy.resample] Computing kd-tree parameters [DEBUG: 2022-06-06 19:14:11 : satpy.resample] Resampling None [DEBUG: 2022-06-06 19:14:11 : satpy.scene] Resampling DataID(name='night_ir_alpha', resolution=3000.403165817) [DEBUG: 2022-06-06 19:14:11 : pyresample.geometry] Projections for data and slice areas are identical: PROJCRS["unknown",BASEGEOGCRS["unknown",DATUM["unknown",ELLIPSOID["unknown",6378169,295.488065897001,LENGTHUNIT["metre",1,ID["EPSG",9001]]]],PRIMEM["Greenwich",0,ANGLEUNIT["degree",0.0174532925199433],ID["EPSG",8901]]],CONVERSION["unknown",METHOD["Geostationary Satellite (Sweep Y)"],PARAMETER["Longitude of natural origin",0,ANGLEUNIT["degree",0.0174532925199433],ID["EPSG",8802]],PARAMETER["Satellite Height",35785831,LENGTHUNIT["metre",1,ID["EPSG",9001]]],PARAMETER["False easting",0,LENGTHUNIT["metre",1],ID["EPSG",8806]],PARAMETER["False northing",0,LENGTHUNIT["metre",1],ID["EPSG",8807]]],CS[Cartesian,2],AXIS["(E)",east,ORDER[1],LENGTHUNIT["metre",1,ID["EPSG",9001]]],AXIS["(N)",north,ORDER[2],LENGTHUNIT["metre",1,ID["EPSG",9001]]]] [INFO: 2022-06-06 19:14:11 : satpy.resample] Using default KDTree resampler [DEBUG: 2022-06-06 19:14:11 : satpy.resample] Computing kd-tree parameters [DEBUG: 2022-06-06 19:14:11 : satpy.resample] Resampling concatenate-bfae926c0bdb66cd2842c1744f395890 [DEBUG: 2022-06-06 19:14:12 : satpy.writers] Enhancement configuration options: [{'name': 'stretch', 'method': , 'kwargs': {'stretch': 'linear', 'cutoffs': [0.02, 0.02]}}, {'name': 'inverse', 'method': , 'args': [[True, True, True, True]]}] [DEBUG: 2022-06-06 19:14:12 : trollimage.xrimage] Applying stretch linear with parameters {'cutoffs': [0.02, 0.02]} [DEBUG: 2022-06-06 19:14:12 : trollimage.xrimage] Perform a linear contrast stretch. [DEBUG: 2022-06-06 19:14:12 : trollimage.xrimage] Calculate the histogram quantiles: [DEBUG: 2022-06-06 19:14:12 : trollimage.xrimage] Left and right quantiles: 0.02 0.02 [DEBUG: 2022-06-06 19:14:12 : trollimage.xrimage] Applying invert with parameters [True, True, True, True] [DEBUG: 2022-06-06 19:14:12 : satpy.writers] Enhancement configuration options: [{'name': 'stretch', 'method': , 'kwargs': {'stretch': 'linear'}}] [DEBUG: 2022-06-06 19:14:12 : trollimage.xrimage] Applying stretch linear with parameters {} [DEBUG: 2022-06-06 19:14:12 : trollimage.xrimage] Perform a linear contrast stretch. [DEBUG: 2022-06-06 19:14:12 : trollimage.xrimage] Calculate the histogram quantiles: [DEBUG: 2022-06-06 19:14:12 : trollimage.xrimage] Left and right quantiles: 0.005 0.005 [DEBUG: 2022-06-06 19:14:12 : satpy.scene] Unloading dataset: DataID(name='_night_background_hires') [DEBUG: 2022-06-06 19:14:12 : satpy.scene] Unloading dataset: DataID(name='night_ir_alpha', resolution=3000.403165817) [DEBUG: 2022-06-06 19:14:12 : satpy.writers] Reading ['/home/eumetcast/miniconda3/envs/pytroll/lib/python3.8/site-packages/satpy/etc/writers/simple_image.yaml'] [DEBUG: 2022-06-06 19:14:12 : satpy.writers] Enhancement configuration options: [{'name': 'stretch', 'method': , 'kwargs': {'stretch': 'linear'}}] [DEBUG: 2022-06-06 19:14:12 : trollimage.xrimage] Applying stretch linear with parameters {} [DEBUG: 2022-06-06 19:14:12 : trollimage.xrimage] Perform a linear contrast stretch. [DEBUG: 2022-06-06 19:14:12 : trollimage.xrimage] Calculate the histogram quantiles: [DEBUG: 2022-06-06 19:14:12 : trollimage.xrimage] Left and right quantiles: 0.005 0.005 [INFO: 2022-06-06 19:14:12 : satpy.writers] Add coastlines and political borders to image. /home/eumetcast/miniconda3/envs/pytroll/lib/python3.8/site-packages/pyproj/crs/crs.py:1282: UserWarning: You will likely lose important projection information when converting to a PROJ string from another format. See: https://proj.org/faq.html#what-is-the-best-format-for-describing-coordinate-reference-systems proj = self._crs.to_proj4(version=version) [DEBUG: 2022-06-06 19:14:12 : satpy.writers.simple_image] Saving to image: MSG4.png [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing BlpImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing BmpImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing BufrStubImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing CurImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing DcxImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing DdsImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing EpsImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing FitsImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing FitsStubImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing FliImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing FpxImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Image: failed to import FpxImagePlugin: No module named 'olefile' [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing FtexImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing GbrImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing GifImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing GribStubImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing Hdf5StubImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing IcnsImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing IcoImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing ImImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing ImtImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing IptcImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing JpegImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing Jpeg2KImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing McIdasImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing MicImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Image: failed to import MicImagePlugin: No module named 'olefile' [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing MpegImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing MpoImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing MspImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing PalmImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing PcdImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing PcxImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing PdfImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing PixarImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing PngImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing PpmImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing PsdImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing SgiImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing SpiderImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing SunImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing TgaImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing TiffImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing WebPImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing WmfImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing XbmImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing XpmImagePlugin [DEBUG: 2022-06-06 19:14:12 : PIL.Image] Importing XVThumbImagePlugin /home/eumetcast/miniconda3/envs/pytroll/lib/python3.8/site-packages/dask/core.py:119: RuntimeWarning: divide by zero encountered in true_divide return func(*(_execute_task(a, cache) for a in args)) [DEBUG: 2022-06-06 19:14:13 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:13 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:13 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:13 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:13 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:13 : rasterio._io] Window: Window(col_off=0, row_off=0, width=4096, height=4096) [DEBUG: 2022-06-06 19:14:13 : rasterio._io] IO window xoff=0.0 yoff=0.0 width=4096.0 height=4096.0 [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:14 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:14 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Window: Window(col_off=4096, row_off=0, width=4096, height=4096) [DEBUG: 2022-06-06 19:14:14 : rasterio._io] IO window xoff=4096.0 yoff=0.0 width=4096.0 height=4096.0 [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:14 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:14 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Window: Window(col_off=8192, row_off=0, width=4096, height=4096) [DEBUG: 2022-06-06 19:14:14 : rasterio._io] IO window xoff=8192.0 yoff=0.0 width=4096.0 height=4096.0 [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:14 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:14 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Window: Window(col_off=12288, row_off=0, width=1212, height=4096) [DEBUG: 2022-06-06 19:14:14 : rasterio._io] IO window xoff=12288.0 yoff=0.0 width=1212.0 height=4096.0 [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:14 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:14 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:14 : rasterio._io] Window: Window(col_off=0, row_off=4096, width=4096, height=2654) [DEBUG: 2022-06-06 19:14:14 : rasterio._io] IO window xoff=0.0 yoff=4096.0 width=4096.0 height=2654.0 [DEBUG: 2022-06-06 19:14:15 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:15 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:15 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:15 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:15 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:15 : rasterio._io] Window: Window(col_off=4096, row_off=4096, width=4096, height=2654) [DEBUG: 2022-06-06 19:14:15 : rasterio._io] IO window xoff=4096.0 yoff=4096.0 width=4096.0 height=2654.0 [DEBUG: 2022-06-06 19:14:15 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:15 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:15 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:15 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:15 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:15 : rasterio._io] Window: Window(col_off=8192, row_off=4096, width=4096, height=2654) [DEBUG: 2022-06-06 19:14:15 : rasterio._io] IO window xoff=8192.0 yoff=4096.0 width=4096.0 height=2654.0 [DEBUG: 2022-06-06 19:14:15 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:15 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:15 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:15 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:15 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:15 : rasterio._io] Window: Window(col_off=12288, row_off=4096, width=1212, height=2654) [DEBUG: 2022-06-06 19:14:15 : rasterio._io] IO window xoff=12288.0 yoff=4096.0 width=1212.0 height=2654.0 [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:16 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:16 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Window: Window(col_off=0, row_off=0, width=4096, height=4096) [DEBUG: 2022-06-06 19:14:16 : rasterio._io] IO window xoff=0.0 yoff=0.0 width=4096.0 height=4096.0 [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:16 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:16 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Window: Window(col_off=4096, row_off=0, width=4096, height=4096) [DEBUG: 2022-06-06 19:14:16 : rasterio._io] IO window xoff=4096.0 yoff=0.0 width=4096.0 height=4096.0 [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:16 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:16 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Window: Window(col_off=8192, row_off=0, width=4096, height=4096) [DEBUG: 2022-06-06 19:14:16 : rasterio._io] IO window xoff=8192.0 yoff=0.0 width=4096.0 height=4096.0 [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:16 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:16 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:16 : rasterio._io] Window: Window(col_off=12288, row_off=0, width=1212, height=4096) [DEBUG: 2022-06-06 19:14:16 : rasterio._io] IO window xoff=12288.0 yoff=0.0 width=1212.0 height=4096.0 [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:17 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:17 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Window: Window(col_off=0, row_off=4096, width=4096, height=2654) [DEBUG: 2022-06-06 19:14:17 : rasterio._io] IO window xoff=0.0 yoff=4096.0 width=4096.0 height=2654.0 [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:17 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:17 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Window: Window(col_off=4096, row_off=4096, width=4096, height=2654) [DEBUG: 2022-06-06 19:14:17 : rasterio._io] IO window xoff=4096.0 yoff=4096.0 width=4096.0 height=2654.0 [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:17 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:17 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Window: Window(col_off=8192, row_off=4096, width=4096, height=2654) [DEBUG: 2022-06-06 19:14:17 : rasterio._io] IO window xoff=8192.0 yoff=4096.0 width=4096.0 height=2654.0 [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:17 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:17 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Window: Window(col_off=12288, row_off=4096, width=1212, height=2654) [DEBUG: 2022-06-06 19:14:17 : rasterio._io] IO window xoff=12288.0 yoff=4096.0 width=1212.0 height=2654.0 [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:17 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:17 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:17 : rasterio._io] Window: Window(col_off=0, row_off=0, width=4096, height=4096) [DEBUG: 2022-06-06 19:14:17 : rasterio._io] IO window xoff=0.0 yoff=0.0 width=4096.0 height=4096.0 /home/eumetcast/miniconda3/envs/pytroll/lib/python3.8/site-packages/dask/core.py:119: RuntimeWarning: invalid value encountered in sin return func(*(_execute_task(a, cache) for a in args)) /home/eumetcast/miniconda3/envs/pytroll/lib/python3.8/site-packages/dask/core.py:119: RuntimeWarning: invalid value encountered in cos return func(*(_execute_task(a, cache) for a in args)) [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:19 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:19 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Window: Window(col_off=4096, row_off=0, width=4096, height=4096) [DEBUG: 2022-06-06 19:14:19 : rasterio._io] IO window xoff=4096.0 yoff=0.0 width=4096.0 height=4096.0 [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:19 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:19 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Window: Window(col_off=8192, row_off=0, width=4096, height=4096) [DEBUG: 2022-06-06 19:14:19 : rasterio._io] IO window xoff=8192.0 yoff=0.0 width=4096.0 height=4096.0 [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:19 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:19 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Window: Window(col_off=12288, row_off=0, width=1212, height=4096) [DEBUG: 2022-06-06 19:14:19 : rasterio._io] IO window xoff=12288.0 yoff=0.0 width=1212.0 height=4096.0 [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:19 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:19 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Window: Window(col_off=0, row_off=4096, width=4096, height=2654) [DEBUG: 2022-06-06 19:14:19 : rasterio._io] IO window xoff=0.0 yoff=4096.0 width=4096.0 height=2654.0 [DEBUG: 2022-06-06 19:14:19 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:20 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:20 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:20 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:20 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:20 : rasterio._io] Window: Window(col_off=4096, row_off=4096, width=4096, height=2654) [DEBUG: 2022-06-06 19:14:20 : rasterio._io] IO window xoff=4096.0 yoff=4096.0 width=4096.0 height=2654.0 [DEBUG: 2022-06-06 19:14:20 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:20 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:20 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:20 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:20 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:20 : rasterio._io] Window: Window(col_off=8192, row_off=4096, width=4096, height=2654) [DEBUG: 2022-06-06 19:14:20 : rasterio._io] IO window xoff=8192.0 yoff=4096.0 width=4096.0 height=2654.0 [DEBUG: 2022-06-06 19:14:20 : rasterio._io] Output nodata value read from file: None [DEBUG: 2022-06-06 19:14:20 : rasterio._io] Output nodata values: [None] [DEBUG: 2022-06-06 19:14:20 : rasterio._io] all_valid: True [DEBUG: 2022-06-06 19:14:20 : rasterio._io] mask_flags: ([], [], []) [DEBUG: 2022-06-06 19:14:20 : rasterio._io] Jump straight to _read() [DEBUG: 2022-06-06 19:14:20 : rasterio._io] Window: Window(col_off=12288, row_off=4096, width=1212, height=2654) [DEBUG: 2022-06-06 19:14:20 : rasterio._io] IO window xoff=12288.0 yoff=4096.0 width=1212.0 height=2654.0 [DEBUG: 2022-06-06 19:14:24 : trollimage.xrimage] Interval: left= array([230.73802185, 224.44198608, 222.97506714, 224.44198608]) Coordinates: quantile float64 0.02 Dimensions without coordinates: bands, right= array([294.0579834 , 296.23468018, 295.80908203, 296.23468018]) Coordinates: quantile float64 0.98 Dimensions without coordinates: bands [DEBUG: 2022-06-06 19:14:34 : trollimage.xrimage] Interval: left= array([ 3., 4., 14.]) Coordinates: quantile float64 0.005 Dimensions without coordinates: bands, right= array([255., 255., 255.]) Coordinates: quantile float64 0.995 Dimensions without coordinates: bands [DEBUG: 2022-06-06 19:14:35 : trollimage.xrimage] Interval: left= array([0.00173023, 0.00398406, 0.00034529]) Coordinates: quantile float64 0.005 Dimensions without coordinates: bands, right= array([1., 1., 1.]) Coordinates: quantile float64 0.995 Dimensions without coordinates: bands [INFO: 2022-06-06 19:14:38 : pycoast.cw_base] Coasts added [INFO: 2022-06-06 19:14:39 : pycoast.cw_base] Rivers added [INFO: 2022-06-06 19:14:39 : pycoast.cw_base] Borders added [DEBUG: 2022-06-06 19:14:41 : pycoast.cw_base] Point (2136, 300) has been added to the image [DEBUG: 2022-06-06 19:14:41 : pycoast.cw_base] Point (1910, 362) has been added to the image [DEBUG: 2022-06-06 19:14:41 : pycoast.cw_base] Point (1859, 313) has been added to the image [DEBUG: 2022-06-06 19:14:41 : pycoast.cw_base] Point (1794, 240) has been added to the image [DEBUG: 2022-06-06 19:14:41 : pycoast.cw_base] Point (1727, 282) has been added to the image [DEBUG: 2022-06-06 19:14:41 : pycoast.cw_base] Point (2036, 403) has been added to the image