--- Dependency check [begin] --- --- Dependency check [end] --- --- Distribution check [begin] --- Version 12.0.1 - 2022-06-07 (EO) Information: this ChangeLog entry matches the version number: Version 12.0.1 - 2022-06-07 (EO) Information: ./config/emchain.par is an XML parameter file, I cannot yet validate it. Skipped. Now checking git repository... Error: git repository already has tag emchain-12.0.1 from earlier commit. Update VERSION file? --- Distribution check [end] --- sed -e "s/DOC/emchain/" -e "s/TYPE/user/" < \ /workspaces/xmm_sas/packages/sas/doc/lib/package.tex | /workspaces/xmm_sas/packages/sas/bin/texcombine | \ /workspaces/xmm_sas/packages/sas/bin/fixdoc -type=user emchain 12.0.1 emchain 21.0.0-77a7a09d7-20250828-21.0.0 > emchain.tex including sasuser... including sasglobal... including emchain_description... latex emchain < /dev/null > makedoc.log || ( cat makedoc.log ; exit 1 ) bibtex emchain < /dev/null > makedoc.log latex emchain < /dev/null > makedoc.log || ( cat makedoc.log ; exit 1 ) latex emchain < /dev/null > makedoc.log || ( cat makedoc.log ; exit 1 ) dvips -o emchain.ps emchain > makedoc.log 2>&1 || ( cat makedoc.log ; exit 1 ) dvipdf emchain > makedoc.log 2>&1 || ( cat makedoc.log ; continue ) gzip -f emchain.ps SASRELDIR=../../.. ;\ export SASRELDIR ;\ latex2html -nouse_pdftex -init_file /workspaces/xmm_sas/packages/sas/doc/lib/latex2html.init emchain > /dev/null texexpand V2020 (Released January 1, 2020) Warning: No implementation found for option: `version' for `sasdoc' package Warning: No implementation found for option: `version' for `sasdoc' package This is dvips(k) 2024.1 (TeX Live 2024) Copyright 2024 Radical Eye Software (www.radicaleye.com) ' TeX output 2025.08.28:1508' -> images.ps /sasbuild/tools/texlive/bin/x86_64-linux/dvips: Font Times-Roman used in file imaging1.eps is not in the mapping file. /sasbuild/tools/texlive/bin/x86_64-linux/dvips: Font Times-Roman used in file imaging0.eps is not in the mapping file. /sasbuild/tools/texlive/bin/x86_64-linux/dvips: Font Times-Roman used in file imaging2.eps is not in the mapping file. /sasbuild/tools/texlive/bin/x86_64-linux/dvips: Font Times-Roman used in file imaging3.eps is not in the mapping file. . [1] [2 ] [3] [4 ] [5 ] [6] [7] [8] /usr/bin/echo "" mkpkgidx -t . > /dev/null || (rm -f emchain/index.pl ; exit 1) pointer=emchain/ChangeLog ; \ target=../../../packages/emchain/ChangeLog ; \ if [ -f $target -a \( ! -h $pointer \) ] ; then \ ln -s ../$target $pointer ; \ fi rm emchain.tex Testing emchain_test ... FAILED Message: will run ./emchain_test for no longer than 600 seconds. Options passed to individual tasks: -w 10 | OAL options: -o /workspaces/xmm_sas/lib/testodf/emodf | randomize = '' | rejectbadevents = N | keepintermediate = Y | stoponerror = Y | filterhk = Y | ODF directory = /workspaces/xmm_sas/lib/testodf/emodf/ List of files in working directory : 0043_0119700401_M1S44310OVE.FTZ ... not IM TI RI CT 0001_183536AIV-_M1S00170IME.FTZ ... OK 0001_183536AIV-_M1S00160RIE.FTZ ... OK 0001_183536AIV-_M1S00120IME.FTZ ... OK 0001_183536AIV-_M1S00220OVE.FTZ ... not IM TI RI CT 0001_183536AIV-_M1S00270OVE.FTZ ... not IM TI RI CT 0001_183536AIV-_M1S00110TIE.FTZ ... OK 0: 0001_183536AIV-_M1S001 Instrument: M1 exposure: S001 obsid=183536AIV- temp=T183536AIV-M1*S001 temp2=T183536AIV-M1*S001**.FIT call cleanFiles with options: M1 S001 1 2 3 4 5 6 7 * CMD: rm -f errors T183536AIV-M1*S001**.FIT CMD: atthkgen atthkset=P183536AIV-OBX000ATTTSR0000.FIT -o /workspaces/xmm_sas/lib/testodf/emodf -w 10 ++ ** atthkgen: warning (NoAttributesAdded), The orbit file `/workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_SCX00000ROS.ASC' appears to be empty - no data have been read - no new attributes will be added to data set ** atthkgen: warning (TooFewTimeCorrelationDataPoints), Number of time correlation data points (2) is less than 5 times the requested maximum degree of the OBT-MET fit polynomial (4) - reverting to linear fit with RMS=1.e-2 ** atthkgen: warning (TooFewTimeCorrelationDataPoints), Number of time correlation data points (2) is less than 5 times the requested maximum degree of the OBT-MET fit polynomial (4) - reverting to linear fit with RMS=1.e-2 ** atthkgen: warning (TooFewTimeCorrelationDataPoints), Number of time correlation data points (2) is less than 5 times the requested maximum degree of the OBT-MET fit polynomial (4) - reverting to linear fit with RMS=1.e-2 atthkgen:- Executing (routine): atthkgen atthkset=P183536AIV-OBX000ATTTSR0000.FIT timestep=1 timebegin=0 timeend=0 withtimeranges=no withpreqgti=no preqgtifile=pointings.fit -w 10 -V 4 atthkgen:- atthkgen (atthkgen-1.22.1) [21.0.0-77a7a09d7-20250828-21.0.0] started: 2025-08-28T15:08:24.000 atthkgen:- 20 % completed of 1st run (AHF/OM) atthkgen:- 40 % completed of 1st run (AHF/OM) atthkgen:- 60 % completed of 1st run (AHF/OM) atthkgen:- 80 % completed of 1st run (AHF/OM) atthkgen:- 100 % completed of 1st run (AHF/OM) atthkgen:- 20 % completed of 2nd run (PNT) atthkgen:- 40 % completed of 2nd run (PNT) atthkgen:- 60 % completed of 2nd run (PNT) atthkgen:- 80 % completed of 2nd run (PNT) atthkgen:- 100 % completed of 2nd run (PNT) atthkgen:- atthkgen (atthkgen-1.22.1) [21.0.0-77a7a09d7-20250828-21.0.0] ended: 2025-08-28T15:08:24.000 ++ CMD: tabgtigen table=P183536AIV-OBX000ATTTSR0000.FIT:ATTHK gtiset=P183536AIV-OBX000ATTGTI0000.FIT expression="!isNull(DAHFPNT) && DAHFPNT<0.05" prefraction=0.0 postfraction=0.0 -w 10 ++ tabgtigen:- Executing (routine): tabgtigen table=P183536AIV-OBX000ATTTSR0000.FIT:ATTHK gtiset=P183536AIV-OBX000ATTGTI0000.FIT expression='!isNull(DAHFPNT) && DAHFPNT<0.05' timecolumn=TIME prefraction=0 postfraction=0 mingtisize=0 -w 10 -V 4 tabgtigen:- tabgtigen (tabgtigen-2.13) [21.0.0-77a7a09d7-20250828-21.0.0] started: 2025-08-28T15:08:24.000 tabgtigen:- tabgtigen (tabgtigen-2.13) [21.0.0-77a7a09d7-20250828-21.0.0] ended: 2025-08-28T15:08:24.000 ++ CMD: cp P183536AIV-M1X000HK_GTI0000.FIT T183536AIV-M1X000EXTGTI0000.FIT ccd: 1 node: 0 event0 : /workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_M1S00110TIE.FTZ CMD: emframes auxiliaryset=/workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_M1S00100AUX.FIT frameset=T183536AIV-M1S001FRAMLI0001.FIT odfeventset=/workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_M1S00110TIE.FTZ outeventset=T183536AIV-M1S001RWEVLI0001.FIT outgtiset=T183536AIV-M1S001RAWGTI0001.FIT flagbadtimes=Y ingtiset=T183536AIV-M1X000EXTGTI0000.FIT -o /workspaces/xmm_sas/lib/testodf/emodf -w 10 ++ ** emframes: warning (NoSuchIPPV), There is no IPPV `CLOCK_WRAP_AROUND' in the actual exposure ** emframes: warning (TooFewTimeCorrelationDataPoints), Number of time correlation data points (2) is less than 5 times the requested maximum degree of the OBT-MET fit polynomial (4) - reverting to linear fit with RMS=1.e-2 ** emframes: warning (TooFewTimeCorrelationDataPoints), Number of time correlation data points (2) is less than 5 times the requested maximum degree of the OBT-MET fit polynomial (4) - reverting to linear fit with RMS=1.e-2 ** emframes: warning (TooFewTimeCorrelationDataPoints), Number of time correlation data points (2) is less than 5 times the requested maximum degree of the OBT-MET fit polynomial (4) - reverting to linear fit with RMS=1.e-2 ** emframes: warning (frames17), non increasing time after frame number 106 ** emframes: warning (NoSuchIPPV), There is no IPPV `FILTER' in the actual exposure ** emframes: warning (NoSuchIPPV), There is no IPPV `GAIN_CCD_1' in the actual exposure ** emframes: warning (NoAttributesAdded), The orbit file `/workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_SCX00000ROS.ASC' appears to be empty - no data have been read - no new attributes will be added to data set ** emframes: warning (FilterUnknown), Value of FILTER attribute is `UNDEFINED' - cannot determine active filter ** emframes: warning (RangeStateVariable::set), The value 0 for variable `exposure duration' is not in the allowed range: 300 <= value <= 156818.711725 emframes:- Executing (routine): emframes auxiliaryset=/workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_M1S00100AUX.FIT frameset=T183536AIV-M1S001FRAMLI0001.FIT newframeset=yes countingset='' withcountingset=no srcra=0 srcdec=0 withsrccoords=no checkframes=yes ingtiset=T183536AIV-M1X000EXTGTI0000.FIT flagbadtimes=yes flagfifooverflow=yes outgtiset=T183536AIV-M1S001RAWGTI0001.FIT writegtiset=yes setgatti=yes odfeventset=/workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_M1S00110TIE.FTZ outeventset=T183536AIV-M1S001RWEVLI0001.FIT neweventset=yes checknvalid=yes setdeadtime=yes withodfeventset=yes -w 10 -V 4 emframes:- emframes (emframes-5.11) [21.0.0-77a7a09d7-20250828-21.0.0] started: 2025-08-28T15:08:24.000 emframes:- 1 missing frames in FRAMES emframes:- Create T183536AIV-M1S001FRAMLI0001.FIT with 99 rows emframes:- Observing mode was FastUncompressed emframes:- Approximate start time = 24174000.731476851 emframes:- Approximate end time = 24174177.516589709 emframes:- 1 frames rejected by VALID emframes:- 0 frames rejected by FIFO emframes:- 13 frames rejected by FLAG_HK emframes:- Fraction of good time : 0.79833698168545841 emframes:- Writing extension STDGTI01 emframes:- Create T183536AIV-M1S001RAWGTI0001.FIT with 1 rows emframes:- GATTI was switched off. GATTIVAL is set to 0. emframes:- emframes (emframes-5.11) [21.0.0-77a7a09d7-20250828-21.0.0] ended: 2025-08-28T15:08:24.000 ++ ccd: 1 node: 1 : No data ccd: 2 node: 0 event0 : /workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_M1S00120IME.FTZ CMD: emframes auxiliaryset=/workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_M1S00100AUX.FIT frameset=T183536AIV-M1S001FRAMLI0002.FIT odfeventset=/workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_M1S00120IME.FTZ outeventset=T183536AIV-M1S001RWEVLI0002.FIT outgtiset=T183536AIV-M1S001RAWGTI0002.FIT flagbadtimes=Y ingtiset=T183536AIV-M1X000EXTGTI0000.FIT -o /workspaces/xmm_sas/lib/testodf/emodf -w 10 ++ ** emframes: warning (NoSuchIPPV), There is no IPPV `CLOCK_WRAP_AROUND' in the actual exposure ** emframes: warning (frames12), the last 1 frames are dropped because of a non-increasing frame number ** emframes: warning (TooFewTimeCorrelationDataPoints), Number of time correlation data points (2) is less than 5 times the requested maximum degree of the OBT-MET fit polynomial (4) - reverting to linear fit with RMS=1.e-2 ** emframes: warning (TooFewTimeCorrelationDataPoints), Number of time correlation data points (2) is less than 5 times the requested maximum degree of the OBT-MET fit polynomial (4) - reverting to linear fit with RMS=1.e-2 ** emframes: warning (TooFewTimeCorrelationDataPoints), Number of time correlation data points (2) is less than 5 times the requested maximum degree of the OBT-MET fit polynomial (4) - reverting to linear fit with RMS=1.e-2 ** emframes: warning (NoSuchIPPV), There is no IPPV `FILTER' in the actual exposure ** emframes: warning (NoSuchIPPV), There is no IPPV `GAIN_CCD_2' in the actual exposure ** emframes: warning (NoAttributesAdded), The orbit file `/workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_SCX00000ROS.ASC' appears to be empty - no data have been read - no new attributes will be added to data set ** emframes: warning (FilterUnknown), Value of FILTER attribute is `UNDEFINED' - cannot determine active filter ** emframes: warning (RangeStateVariable::set), The value 0 for variable `exposure duration' is not in the allowed range: 300 <= value <= 156818.711725 emframes:- Executing (routine): emframes auxiliaryset=/workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_M1S00100AUX.FIT frameset=T183536AIV-M1S001FRAMLI0002.FIT newframeset=yes countingset='' withcountingset=no srcra=0 srcdec=0 withsrccoords=no checkframes=yes ingtiset=T183536AIV-M1X000EXTGTI0000.FIT flagbadtimes=yes flagfifooverflow=yes outgtiset=T183536AIV-M1S001RAWGTI0002.FIT writegtiset=yes setgatti=yes odfeventset=/workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_M1S00120IME.FTZ outeventset=T183536AIV-M1S001RWEVLI0002.FIT neweventset=yes checknvalid=yes setdeadtime=yes withodfeventset=yes -w 10 -V 4 emframes:- emframes (emframes-5.11) [21.0.0-77a7a09d7-20250828-21.0.0] started: 2025-08-28T15:08:24.000 emframes:- Create T183536AIV-M1S001FRAMLI0002.FIT with 101 rows emframes:- Observing mode was PrimeFullWindow emframes:- Approximate start time = 24173997.079127785 emframes:- Approximate end time = 24174257.104461428 emframes:- 0 frames rejected by VALID emframes:- 0 frames rejected by FIFO emframes:- Area of window within field of view is 76.9555511 arcmin2 emframes:- Fraction of window within field of view is 0.635072231 emframes:- 13 frames rejected by FLAG_HK emframes:- Fraction of good time : 0.85688264452276663 emframes:- Writing extension STDGTI02 emframes:- Create T183536AIV-M1S001RAWGTI0002.FIT with 1 rows emframes:- emframes (emframes-5.11) [21.0.0-77a7a09d7-20250828-21.0.0] ended: 2025-08-28T15:08:24.000 ++ ccd: 2 node: 1 : No data CMD: emevents odfeventset=T183536AIV-M1S001RWEVLI0002.FIT eventset=T183536AIV-M1S001CLEVLI0002.FIT frameset=T183536AIV-M1S001FRAMLI0002.FIT offvarsets="/workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_M1S00220OVE.FTZ" analysepatterns=N flagbadpixels=N splitdiagonals=N randomizeposition=N -w 10 ++ ** emevents: warning (findBestTime12), No offset/variance file was obtained earlier than the current exposure. Use first one obtained. ** emevents: warning (FilterUnknown), Value of FILTER attribute is `UNDEFINED' - cannot determine active filter ** emevents: warning (RangeStateVariable::set), The value 0 for variable `exposure duration' is not in the allowed range: 300 <= value <= 156819 ** emevents: warning (rejectFlickering13), The number of frames 86 is not large enough compared to the frame tolerance 5 . The flickering rejection will not work optimally. emevents:- Executing (routine): emevents odfeventset=T183536AIV-M1S001RWEVLI0002.FIT eventset=T183536AIV-M1S001CLEVLI0002.FIT newoutput=yes frameset=T183536AIV-M1S001FRAMLI0002.FIT flagbadtimes=yes gattispecset=gattispec.out writegattispecset=no flagtruncatede1=yes othereventset='' otherframeset='' withothernode=no withframeset=yes offvarsets=/workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_M1S00220OVE.FTZ withoffvarsets=yes analysepatterns=no flagbadpixels=no keepsifluor=no splitdiagonals=no randomizeposition=no setcoordinates=yes maxeventsperrow=4 widthnexttorow=10 rejectrows=yes detectbadoffsets=yes rejectbade3=yes tolfxy='5 2 2' rejectflickering=yes randomizetime=no settimes=yes blocksize=10000 -w 10 -V 4 emevents:- emevents (emevents-8.8) [21.0.0-77a7a09d7-20250828-21.0.0] started: 2025-08-28T15:08:25.000 emevents:- 1 input names for parameter :offvarsets emevents:- offset/variance file /workspaces/xmm_sas/lib/testodf/emodf/0001_183536AIV-_M1S00220OVE.FTZ:M1OVE1 was selected emevents:- 3823 events in input emevents:- 0 events below the EMDH lower threshold emevents:- 52 events above the EMDH upper threshold emevents:- 3823 events in output emevents:- 579 events outside good frames emevents:- 315 cosmic-ray events emevents:- 9 events outside window emevents:- maximum number of expected events per row 0.18604651162790697 emevents:- 0 events in rows with more than 4 events above expected. emevents:- 0 flagged events next to bad rows emevents:- 0 events on row/column with wrong offset emevents:- 0 events with E3 < -68 + local median emevents:- maximum number of expected events per bin 0.322325587 emevents:- 0 events detected as flickering at tolerances 5 2 2 emevents:- 20 events with truncated E1 emevents:- Fraction of good events : 0.775307357 emevents:- emevents (emevents-8.8) [21.0.0-77a7a09d7-20250828-21.0.0] ended: 2025-08-28T15:08:25.000 ++ CMD: emenergy ineventset=T183536AIV-M1S001CAEVLI0002.FIT correctcti=N correctgain=N randomizeenergy=N -w 10 ++ ** emenergy: warning (FilterUnknown), Value of FILTER attribute is `UNDEFINED' - cannot determine active filter ** emenergy: warning (RangeStateVariable::set), The value 0 for variable `exposure duration' is not in the allowed range: 300 <= value <= 156819 emenergy:- Executing (routine): emenergy ineventset=T183536AIV-M1S001CAEVLI0002.FIT outeventset=events.out newoutput=no flagevents=yes correctcti=no minenergy=100 maxperipix=0 fillminnumber=10 fillccdbkg=yes backgroundset=bkgccd.map timebin=100 writebackgroundset=no getccdbkg=yes useccfdarkframe=no maskedccdset='' withmaskedccdset=no rejectbade3e4=yes randomizeenergy=no makepha=yes correctgain=no ontimepha=no -w 10 -V 4 emenergy:- emenergy (emenergy-8.9) [21.0.0-77a7a09d7-20250828-21.0.0] started: 2025-08-28T15:08:25.000 emenergy:- Input file : T183536AIV-M1S001CAEVLI0002.FIT emenergy:- The input file will be overwritten emenergy:- 3823 events in file emenergy:- EVFLAG will be applied emenergy:- Bad offsets will be taken from the OFFSETS extension. emenergy:- Energies will be corrected using E4 information emenergy:- Events with ENERGYE1 >= 100 will be used to build the background map. emenergy:- Events with PERIPIX <= 0 will be used to build the background map. emenergy:- Background data will be filled by row and column averaging when less than 10 events around. emenergy:- no BADPIX extension in input event file. Assume no bad pixel in EVFLAG. emenergy:- 287 events outside field of view emenergy:- 10 events outside the ellipse in E3/E1, E4/E1 emenergy:- 79 events rejected by 3.00000000 sigma clipping on E4 for CCDBKG emenergy:- 9 events outside the ellipse in E3/E1, E4/E1 emenergy:- 53 events with PHA out of range 25 - 4095 emenergy:- emenergy (emenergy-8.9) [21.0.0-77a7a09d7-20250828-21.0.0] ended: 2025-08-28T15:08:25.000 ++ CMD: evselect table=T183536AIV-M1S001CAEVLI0002.FIT destruct=Y keepfilteroutput=Y updateexposure=N expression="(FLAG & 0x762aa000) == 0" -w 10 ++ sh: evselect: command not found emchain: That task ended in error ! ++ emchain randomize='' filterhk=Y rejectbadevents=N stoponerror=Y keepintermediate=Y odf=/workspaces/xmm_sas/lib/testodf/emodf ended in error Error while running ./emchain_test ERROR: one of the tests failed