2023-09-04T14:30:06Z INFO __main__ 253: 
CONFIG

[Paths]
tm_archive = /data/stix/SOLSOC/from_edds/tm/processing
fits_archive = /data/stix/out/fits_v1.0.0
spice_kernels = /data/stix/spice/kernels
soop_files = /data/stix/SOLSOC/from_soc

[Logging]
stop_on_error = False

[IDLBridge]
enabled = True
gsw_path = /opt/STIX-GSW
batchsize = 30

[Pipeline]
status_server_port = 12388
parallel_batchsize_l0 = 100
parallel_batchsize_l1 = 100
parallel_batchsize_l2 = 100
log_level = INFO
log_dir = /data/stix/out/fits_v1.0.0/logging
error_mail_send = True
error_mail_receivers = nicky.hochmuth@ateleris.ch
error_mail_smpt_host = lmailer.fhnw.ch
error_mail_smpt_port = 25
error_mail_sender = nicky.hochmuth@fhnw.ch
start_with_unprocessed = True

[Publish]
db_file = /home/stixcore/soar_pub/published.sqlite
include_levels = L0, L1
waiting_period = 21d
target_dir = /home/solsoc/to_soc
report_mail_receivers = nicky.hochmuth@ateleris.ch
report_mail_send = True
same_esa_name_dir = /data/stix/out/esa_conflicts
rid_lut_file = ./stixcore/data/publish/rid_lut.csv
rid_lut_file_update_url = https://datacenter.stix.i4ds.net/api/bsd/info/
batch_size = 20000
fits_dir = /data/stix/out/fits_v1.0.0
target_host = pub026.cs.technik.fhnw.ch
supplement_report = /home/stixcore/soar_pub/supplement_report.csv
include_products = ql,hk,sci,cal
log_dir = /data/stix/out/fits_v1.0.0/logging

[SOOP]
endpoint = https://solarorbiter.esac.esa.int/soopkitchen/api
user = smaloney
password = xxxx
soop_files_download = ./stixcore/data/soop


2023-09-04T14:30:06Z INFO __main__ 270: 
SINGLETONS

SOOPManager: /data/stix/SOLSOC/from_soc
SPICE: /data/stix/spice/kernels/mk/solo_ANC_soc-flown-mk_V110_20230831_001.tm
IDBManager: /home/stixcore/STIXCore/stixcore/data/idb
Versions:
[{'label': '2.26.36', 'path': '/home/stixcore/STIXCore/stixcore/data/idb/v2.26.36', 'version': ['2', '26', '36']}, {'label': '2.26.32', 'path': '/home/stixcore/STIXCore/stixcore/data/idb/v2.26.32', 'version': ['2', '26', '32']}, {'label': '2.26.34', 'path': '/home/stixcore/STIXCore/stixcore/data/idb/v2.26.34', 'version': ['2', '26', '34']}, {'label': '2.26.33', 'path': '/home/stixcore/STIXCore/stixcore/data/idb/v2.26.33', 'version': ['2', '26', '33']}, {'label': '2.26.31', 'path': '/home/stixcore/STIXCore/stixcore/data/idb/v2.26.31', 'version': ['2', '26', '31']}, {'label': '2.26.35', 'path': '/home/stixcore/STIXCore/stixcore/data/idb/v2.26.35', 'version': ['2', '26', '35']}]
Force version: None
History:
IntervalTree([Interval(0.0, 640198039.0, '2.26.32'), Interval(640198039.0, 640265396.0, '2.26.31'), Interval(640265396.0, 642159993.0, '2.26.32'), Interval(642159993.0, 643123725.0, '2.26.33'), Interval(643123725.0, 677774249.0, '2.26.34'), Interval(677774249.0, 692380776.0, '2.26.35'), Interval(692380776.0, 4294967295.0, '2.26.36')])

2023-09-04T14:30:06Z INFO stixcore.ephemeris.manager 160: LOADING NEW META KERNEL: /data/stix/spice/kernels/mk/solo_ANC_soc-flown-mk_V110_20230904_001.tm
2023-09-04T14:30:07Z INFO __main__ 204: new spice kernel detected and loaded
2023-09-04T14:30:07Z INFO stixcore.processing.TMTCtoLB 30: Processing file: SOCPacketFile('/data/stix/SOLSOC/from_edds/tm/processing/STIX_TM_12_BatchRequest.PktTmRaw.SOL.0.2023.214.07.23.44.901.ALYZ@2023.245.12.00.01.441.1.xml')
2023-09-04T14:30:07Z INFO stixcore.io.fits.processors 435: Writing fits file to /data/stix/out/fits_v1.0.0/LB/5/2/21551/solo_LB_stix-5-2-21551_0746841600_V01.fits
2023-09-04T14:30:07Z INFO stixcore.io.fits.processors 435: Writing fits file to /data/stix/out/fits_v1.0.0/LB/1/1/solo_LB_stix-1-1_0746841600_V01.fits
2023-09-04T14:30:07Z INFO stixcore.io.fits.processors 435: Writing fits file to /data/stix/out/fits_v1.0.0/LB/1/7/solo_LB_stix-1-7_0746841600_V01.fits
2023-09-04T14:30:07Z INFO stixcore.io.fits.processors 435: Writing fits file to /data/stix/out/fits_v1.0.0/LB/5/2/21551/solo_LB_stix-5-2-21551_0746928000_V01.fits
2023-09-04T14:30:07Z INFO stixcore.io.fits.processors 404: Fits file solo_LB_stix-3-25-2_0746841600_V01.fits exists appending data
2023-09-04T14:30:07Z INFO stixcore.io.fits.processors 435: Writing fits file to /data/stix/out/fits_v1.0.0/LB/3/25/2/solo_LB_stix-3-25-2_0746841600_V01.fits
2023-09-04T14:30:07Z INFO __main__ 207: generated LB files: 
{PosixPath('/data/stix/out/fits_v1.0.0/LB/1/1/solo_LB_stix-1-1_0746841600_V01.fits'),
 PosixPath('/data/stix/out/fits_v1.0.0/LB/1/7/solo_LB_stix-1-7_0746841600_V01.fits'),
 PosixPath('/data/stix/out/fits_v1.0.0/LB/3/25/2/solo_LB_stix-3-25-2_0746841600_V01.fits'),
 PosixPath('/data/stix/out/fits_v1.0.0/LB/5/2/21551/solo_LB_stix-5-2-21551_0746841600_V01.fits'),
 PosixPath('/data/stix/out/fits_v1.0.0/LB/5/2/21551/solo_LB_stix-5-2-21551_0746928000_V01.fits')}
2023-09-04T14:30:07Z INFO stixcore.idb.idb 522: IDB loaded from /home/stixcore/STIXCore/stixcore/data/idb/v2.26.36/idb.sqlite
2023-09-04T14:30:07Z INFO stixcore.idb.idb 522: IDB loaded from /home/stixcore/STIXCore/stixcore/data/idb/v2.26.36/idb.sqlite
2023-09-04T14:30:07Z INFO stixcore.idb.idb 522: IDB loaded from /home/stixcore/STIXCore/stixcore/data/idb/v2.26.36/idb.sqlite
2023-09-04T14:30:07Z INFO stixcore.idb.idb 522: IDB loaded from /home/stixcore/STIXCore/stixcore/data/idb/v2.26.36/idb.sqlite
2023-09-04T14:30:07Z INFO stixcore.ephemeris.manager 160: LOADING NEW META KERNEL: /data/stix/spice/kernels/mk/solo_ANC_soc-flown-mk_V110_20230904_001.tm
2023-09-04T14:30:07Z INFO stixcore.ephemeris.manager 160: LOADING NEW META KERNEL: /data/stix/spice/kernels/mk/solo_ANC_soc-flown-mk_V110_20230904_001.tm
2023-09-04T14:30:07Z INFO stixcore.ephemeris.manager 160: LOADING NEW META KERNEL: /data/stix/spice/kernels/mk/solo_ANC_soc-flown-mk_V110_20230904_001.tm
2023-09-04T14:30:07Z INFO stixcore.ephemeris.manager 160: LOADING NEW META KERNEL: /data/stix/spice/kernels/mk/solo_ANC_soc-flown-mk_V110_20230904_001.tm
2023-09-04T14:30:07Z INFO stixcore.processing.LBtoL0 80: processing file: /data/stix/out/fits_v1.0.0/LB/5/2/21551/solo_LB_stix-5-2-21551_0746928000_V01.fits
2023-09-04T14:30:07Z INFO stixcore.processing.LBtoL0 80: processing file: /data/stix/out/fits_v1.0.0/LB/1/1/solo_LB_stix-1-1_0746841600_V01.fits
2023-09-04T14:30:07Z INFO stixcore.processing.LBtoL0 80: processing file: /data/stix/out/fits_v1.0.0/LB/1/7/solo_LB_stix-1-7_0746841600_V01.fits
2023-09-04T14:30:07Z INFO stixcore.processing.LBtoL0 80: processing file: /data/stix/out/fits_v1.0.0/LB/3/25/2/solo_LB_stix-3-25-2_0746841600_V01.fits
2023-09-04T14:30:07Z INFO stixcore.processing.LBtoL0 80: processing file: /data/stix/out/fits_v1.0.0/LB/5/2/21551/solo_LB_stix-5-2-21551_0746841600_V01.fits
2023-09-04T14:30:12Z INFO stixcore.io.fits.processors 484: Fits file solo_L0_stix-hk-maxi_0746841600_V01.fits exists appending data
2023-09-04T14:30:13Z INFO __main__ 211: generated L0 files: 
[PosixPath('/data/stix/out/fits_v1.0.0/L0/3/25/2/solo_L0_stix-hk-maxi_0746841600_V01.fits'),
 PosixPath('/data/stix/out/fits_v1.0.0/L0/5/2/21551/solo_L0_stix-events-2-21551_0746841600_V01.fits'),
 PosixPath('/data/stix/out/fits_v1.0.0/L0/1/7/solo_L0_stix-tc-verify-7_0746841600_V01.fits'),
 PosixPath('/data/stix/out/fits_v1.0.0/L0/1/1/solo_L0_stix-tc-verify-1_0746841600_V01.fits'),
 PosixPath('/data/stix/out/fits_v1.0.0/L0/5/2/21551/solo_L0_stix-events-2-21551_0746928000_V01.fits')]
2023-09-04T14:30:13Z INFO stixcore.ephemeris.manager 160: LOADING NEW META KERNEL: /data/stix/spice/kernels/mk/solo_ANC_soc-flown-mk_V110_20230904_001.tm
2023-09-04T14:30:13Z INFO stixcore.ephemeris.manager 160: LOADING NEW META KERNEL: /data/stix/spice/kernels/mk/solo_ANC_soc-flown-mk_V110_20230904_001.tm
2023-09-04T14:30:13Z INFO stixcore.ephemeris.manager 160: LOADING NEW META KERNEL: /data/stix/spice/kernels/mk/solo_ANC_soc-flown-mk_V110_20230904_001.tm
2023-09-04T14:30:13Z INFO stixcore.ephemeris.manager 160: LOADING NEW META KERNEL: /data/stix/spice/kernels/mk/solo_ANC_soc-flown-mk_V110_20230904_001.tm
2023-09-04T14:30:13Z INFO stixcore.processing.L0toL1 89: processing file: /data/stix/out/fits_v1.0.0/L0/1/1/solo_L0_stix-tc-verify-1_0746841600_V01.fits
2023-09-04T14:30:13Z INFO stixcore.processing.L0toL1 89: processing file: /data/stix/out/fits_v1.0.0/L0/1/7/solo_L0_stix-tc-verify-7_0746841600_V01.fits
2023-09-04T14:30:13Z INFO stixcore.processing.L0toL1 89: processing file: /data/stix/out/fits_v1.0.0/L0/5/2/21551/solo_L0_stix-events-2-21551_0746841600_V01.fits
2023-09-04T14:30:13Z INFO stixcore.soop.manager 482: No soops found for time: 2023-09-02 00:00:00.920506 - 2023-09-02 00:01:42.920690
2023-09-04T14:30:13Z INFO stixcore.soop.manager 482: No soops found for time: 2023-09-01 17:05:26.703108 - 2023-09-01 17:05:26.703108
2023-09-04T14:30:13Z INFO stixcore.soop.manager 489: No observations found for time: 2023-09-01 17:05:26.703108 - 2023-09-01 17:05:26.703108 : SoopObservationType.ALL
2023-09-04T14:30:13Z INFO stixcore.soop.manager 482: No soops found for time: 2023-09-02 00:00:00.935917 - 2023-09-02 00:01:42.936101
2023-09-04T14:30:13Z INFO stixcore.processing.L0toL1 89: processing file: /data/stix/out/fits_v1.0.0/L0/3/25/2/solo_L0_stix-hk-maxi_0746841600_V01.fits
2023-09-04T14:30:13Z INFO stixcore.processing.L0toL1 89: processing file: /data/stix/out/fits_v1.0.0/L0/5/2/21551/solo_L0_stix-events-2-21551_0746928000_V01.fits
2023-09-04T14:30:13Z INFO stixcore.soop.manager 482: No soops found for time: 2023-09-02 11:17:41.257471 - 2023-09-02 11:17:41.257471
2023-09-04T14:30:13Z INFO stixcore.soop.manager 489: No observations found for time: 2023-09-02 11:17:41.257471 - 2023-09-02 11:17:41.257471 : SoopObservationType.ALL
2023-09-04T14:30:14Z INFO stixcore.io.fits.processors 772: Fits file solo_L1_stix-hk-maxi_20230901_V01.fits exists appending data
2023-09-04T14:30:14Z INFO stixcore.soop.manager 482: No soops found for time: 2023-09-01 00:00:48.127834 - 2023-09-01 17:58:07.670668
2023-09-04T14:30:15Z INFO __main__ 215: generated L1 files: 
[PosixPath('/data/stix/out/fits_v1.0.0/L1/2023/09/02/EVENTS/solo_L1_stix-events-2-21551_20230902_V01.fits'),
 PosixPath('/data/stix/out/fits_v1.0.0/L1/2023/09/02/TC-VERIFY/solo_L1_stix-tc-verify-1_20230902_V01.fits'),
 PosixPath('/data/stix/out/fits_v1.0.0/L1/2023/09/01/EVENTS/solo_L1_stix-events-2-21551_20230901_V01.fits'),
 PosixPath('/data/stix/out/fits_v1.0.0/L1/2023/09/02/TC-VERIFY/solo_L1_stix-tc-verify-7_20230902_V01.fits'),
 PosixPath('/data/stix/out/fits_v1.0.0/L1/2023/09/01/HK/solo_L1_stix-hk-maxi_20230901_V01.fits')]
2023-09-04T14:30:15Z INFO stixcore.ephemeris.manager 160: LOADING NEW META KERNEL: /data/stix/spice/kernels/mk/solo_ANC_soc-flown-mk_V110_20230904_001.tm
2023-09-04T14:30:15Z INFO stixcore.ephemeris.manager 160: LOADING NEW META KERNEL: /data/stix/spice/kernels/mk/solo_ANC_soc-flown-mk_V110_20230904_001.tm
2023-09-04T14:30:15Z INFO stixcore.ephemeris.manager 160: LOADING NEW META KERNEL: /data/stix/spice/kernels/mk/solo_ANC_soc-flown-mk_V110_20230904_001.tm
2023-09-04T14:30:15Z INFO stixcore.ephemeris.manager 160: LOADING NEW META KERNEL: /data/stix/spice/kernels/mk/solo_ANC_soc-flown-mk_V110_20230904_001.tm
2023-09-04T14:30:15Z INFO stixcore.io.fits.processors 858: no writeout of L2 events-2-21551 FITS files.
2023-09-04T14:30:15Z INFO stixcore.io.fits.processors 858: no writeout of L2 tc-verify-1 FITS files.
2023-09-04T14:30:15Z INFO stixcore.io.fits.processors 858: no writeout of L2 tc-verify-7 FITS files.
2023-09-04T14:30:15Z INFO stixcore.io.fits.processors 858: no writeout of L2 events-2-21551 FITS files.
2023-09-04T14:30:16Z INFO stixcore.io.fits.processors 858: no writeout of L2 hk-maxi FITS files.
2023-09-04T14:30:16Z INFO stixcore.products.level2.housekeepingL2 229: calling IDL for hk files:
2023-09-04T14:30:16Z INFO stixcore.products.level2.housekeepingL2 231:     /data/stix/out/fits_v1.0.0/L1/2023/09/01/HK/solo_L1_stix-hk-maxi_20230901_V01.fits
2023-09-04T14:31:03Z INFO stixcore.products.level2.housekeepingL2 254: returning from IDL
2023-09-04T14:31:03Z INFO stixcore.products.level2.housekeepingL2 258: IDL postprocessing HK file: b'/data/stix/out/fits_v1.0.0/L1/2023/09/01/HK/solo_L1_stix-hk-maxi_20230901_V01.fits'
2023-09-04T14:31:04Z ERROR stixcore.processing.L1toL2 68: error
concurrent.futures.process._RemoteTraceback: 
"""
Traceback (most recent call last):
  File "/usr/lib/python3.9/concurrent/futures/process.py", line 246, in _process_worker
    r = call_item.fn(*call_item.args, **call_item.kwargs)
  File "/home/stixcore/STIXCore/stixcore/processing/L1toL2.py", line 106, in process_type
    all_files.extend(idlprocessor.process())
  File "/home/stixcore/STIXCore/stixcore/processing/sswidl.py", line 205, in process
    files.extend(task.run(self.fits_processor))
  File "/home/stixcore/STIXCore/stixcore/processing/sswidl.py", line 153, in run
    self._results = self.postprocessing(results, fits_processor)
  File "/home/stixcore/STIXCore/stixcore/products/level2/housekeepingL2.py", line 289, in postprocessing
    orient, dist, car, heeq = Spice.instance.get_auxiliary_positional_data(date=d)
  File "/home/stixcore/STIXCore/stixcore/ephemeris/manager.py", line 303, in get_auxiliary_positional_data
    cmat, _ = spiceypy.ckgp(SOLAR_ORBITER_STIX_ILS_FRAME_ID, sc, 1.0, 'SOLO_SUN_RTN')
  File "/home/stixcore/STIXCore/venv/lib/python3.9/site-packages/spiceypy/spiceypy.py", line 138, in with_errcheck
    res = f(*args, **kwargs)
  File "/home/stixcore/STIXCore/venv/lib/python3.9/site-packages/spiceypy/spiceypy.py", line 158, in wrapper
    raise NotFoundError(
spiceypy.utils.exceptions.NotFoundError: Spice returns not found for function: ckgp
"""

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

Traceback (most recent call last):
  File "/home/stixcore/STIXCore/stixcore/processing/L1toL2.py", line 65, in process_fits_files
    new_files = job.result()
  File "/usr/lib/python3.9/concurrent/futures/_base.py", line 439, in result
    return self.__get_result()
  File "/usr/lib/python3.9/concurrent/futures/_base.py", line 391, in __get_result
    raise self._exception
spiceypy.utils.exceptions.NotFoundError: Spice returns not found for function: ckgp
2023-09-04T14:31:04Z INFO __main__ 219: generated L2 files: 
[]