loading system configuration
loading override configuration from /home/nexmap/pkwi/McCode/tools/Python/mctest/../mccodelib/mcstas-test/McStas-2.x_CPU_MPICC/mccode_config.json
INFO: Using directory: "3"
INFO: Using existing c-file: ./Test_SX.c
INFO: Using existing binary: ./Test_SX.out
INFO: ===
Simulation 'Test_SX' (/home/nexmap/pkwi/TESTS/2022-11-01/McStas-2.x_CPU_MPICC_5e7/Test_SX/Test_SX.instr): running on 16 nodes (master is 'elearn1.fysik.dtu.dk', MPI version 3.1).
[Test_SX] Initialize
[Test_SX] Initialize
[Test_SX] Initialize
[Test_SX] Initialize
Opening input file '/home/nexmap/pkwi/McStas/mcstas/2.x-dev/tools/Python/mcrun/../mccodelib/../../../data/YBaCuO.lau' (Table_Read_Offset)
[Test_SX] Initialize
[Test_SX] Initialize
[Test_SX] Initialize
[Test_SX] Initialize
[Test_SX] Initialize
[Test_SX] Initialize
[Test_SX] Initialize
[Test_SX] Initialize
[Test_SX] Initialize
[Test_SX] Initialize
[Test_SX] Initialize
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
[Test_SX] Initialize
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: YBaCuO.lau structure a=3.8186 b=3.886 c=11.6777 aa=90 bb=90 cc=90 V0=173.286
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
Single_crystal: SX: Read 62 reflections from file 'YBaCuO.lau'
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau
Single_crystal: SX: Vc=173.286 [Angs] sigma_abs=14.82 [barn] sigma_inc=2.105 [barn] reflections=YBaCuO.lau

Trace ETA 1.53333 [min] % 11 
Trace ETA 1.55 [min] % 11 
Trace ETA 1.53333 [min] % 11 
Trace ETA 1.55 [min] % 11 
Trace ETA 1.53333 [min] % 11 
Trace ETA 1.55 [min] % 11 
Trace ETA 1.55 [min] % 11 
Trace ETA 1.53333 [min] % 11 
Trace ETA 1.53333 [min] % 11 
Trace ETA 1.55 [min] % 11 
Trace ETA 1.55 [min] % 11 
Trace ETA 1.55 [min] % 11 
Trace ETA 1.53333 [min] % 11 
Trace ETA 1.53333 [min] % 11 
Trace ETA 1.53333 [min] % 11 
Trace ETA 1.53333 [min] % 11 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 40 40 40 40 40 40 40 40 40 40 40 40 40 40 40 40 50 50 50 50 50 50 50 50 50 50 50 50 50 50 50 50 60 60 60 60 60 60 60 60 60 60 60 60 60 60 60 60 70 70 70 70 70 70 70 70 70 70 70 70 70 70 70 70 80 80 80 80 80 80 80 80 80 80 80 80 80 80 80 80 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 
Finally [Test_SX: 3]. Time: 1.45 [min] 

Finally [Test_SX: 3]. Time: 1.46667 [min] 

Finally [Test_SX: 3]. Time: 1.46667 [min] 

Finally [Test_SX: 3]. Time: 1.46667 [min] 

Finally [Test_SX: 3]. Time: 1.46667 [min] 

Finally [Test_SX: 3]. Time: 1.46667 [min] 

Finally [Test_SX: 3]. Time: 1.46667 [min] 

Finally [Test_SX: 3]. Time: 1.46667 [min] 

Save [Test_SX]
Detector: PSD_I=4.05043e+09 PSD_ERR=1.28116e+06 PSD_N=5e+07 "incoming.dat"

Finally [Test_SX: 3]. Time: 1.46667 [min] 

Finally [Test_SX: 3]. Time: 1.46667 [min] 

Finally [Test_SX: 3]. Time: 1.46667 [min] 
Detector: Full_mon_I=7.14855e+07 Full_mon_ERR=12912.4 Full_mon_N=4.995e+08 "FULL.dat"
Detector: Sph_tun_I=0 Sph_tun_ERR=0 Sph_tun_N=0 "tun.dat"
Detector: PSDBS_I=0 PSDBS_ERR=0 PSDBS_N=0 "BS0.dat"

Finally [Test_SX: 3]. Time: 1.46667 [min] 
Detector: Sph_mon_I=7.14855e+07 Sph_mon_ERR=12912.4 Sph_mon_N=4.995e+08 "Tot.dat"
Detector: Sph_coh_I=2.61341e+07 Sph_coh_ERR=11949.8 Sph_coh_N=1.3839e+07 "coh.dat"

Finally [Test_SX: 3]. Time: 1.46667 [min] 

Finally [Test_SX: 3]. Time: 1.46667 [min] 

Finally [Test_SX: 3]. Time: 1.46667 [min] 
Detector: Sph_inc_I=4.53514e+07 Sph_inc_ERR=4891.94 Sph_inc_N=4.85661e+08 "inc.dat"

Finally [Test_SX: 3]. Time: 1.46667 [min] 
INFO: Placing instr file copy /home/nexmap/pkwi/TESTS/2022-11-01/McStas-2.x_CPU_MPICC_5e7/Test_SX/Test_SX.instr in dataset 3