loading system configuration
loading override configuration from /home/nexmap/pkwi/McCode/tools/Python/mctest/../mccodelib/mcstas-test/McStas_8GPU/mccode_config.json
INFO: Using directory: "1"
INFO: Using existing c-file: ./ILL_H512_D22.c
INFO: Using existing binary: ./ILL_H512_D22.out
INFO: ===
Simulation 'ILL_H512_D22' (/home/nexmap/pkwi/TESTS/2022-11-01/McStas_8GPU_5e7/ILL_H512_D22/ILL_H512_D22.instr): running on 7 nodes (master is 'hypatia.fysik.dtu.dk', MPI version 3.1).
Have found 8 GPU devices on rank 4. Will use device 4.
Have found 8 GPU devices on rank 5. Will use device 5.
Have found 8 GPU devices on rank 3. Will use device 3.
Have found 8 GPU devices on rank 6. Will use device 6.
Have found 8 GPU devices on rank 0. Will use device 0.
ILL_H512_D22: D22:  nu=28307.4 [rpm] lambda=4.5 [Angs] sample=H2O_liq.qSq
[ILL_H512_D22] Initialize
ILL_H512_D22: D22:  nu=28307.4 [rpm] lambda=4.5 [Angs] sample=H2O_liq.qSq
[ILL_H512_D22] Initialize
Source_gen: component HCS (square 0.1 x 0.1)
            focusing distance dist=2.155 area=0.123 x 0.12
            spectra 4.050 to 4.950 AA (3.339 to 4.988 meV)
            T1=40.1 K (4.865 AA), T2=145.8 K (2.552 AA), T3=413.5 K (1.515 AA)
  Flux is dPhi/dlambda in [n/s/cm2].
Opening input file '/u/data/pkwi/McStas/mcstas/3.x-dev/tools/Python/mcrun/../mccodelib/../../../data/Al.laz' (Table_Read_Offset)
Have found 8 GPU devices on rank 2. Will use device 2.
Table from file 'Al.laz' (block 1) is 26 x 18 (x=1:8), constant step. interpolation: linear
  '# TITLE *Aluminum-Al-[FM3-M] Miller, H.P.jr.;DuMond, J.W.M.[1942] at 298 K; ...'
PowderN: HCS_Al: Reading 26 rows from Al.laz
ILL_H512_D22: D22:  nu=28307.4 [rpm] lambda=4.5 [Angs] sample=H2O_liq.qSq
ILL_H512_D22: D22:  nu=28307.4 [rpm] lambda=4.5 [Angs] sample=H2O_liq.qSq
PowderN: HCS_Al: Read 26 reflections from file 'Al.laz'
PowderN: HCS_Al: Vc=66.4 [Angs] sigma_abs=0.924 [barn] sigma_inc=0.0328 [barn] reflections=Al.laz
Opening input file '/u/data/pkwi/McStas/mcstas/3.x-dev/tools/Python/mcrun/../mccodelib/../../../data/Al.laz' (Table_Read_Offset)
[ILL_H512_D22] Initialize
[ILL_H512_D22] Initialize
Table from file 'Al.laz' (block 1) is 26 x 18 (x=1:8), constant step. interpolation: linear
  '# TITLE *Aluminum-Al-[FM3-M] Miller, H.P.jr.;DuMond, J.W.M.[1942] at 298 K; ...'
PowderN: HCS_Al_4: Reading 26 rows from Al.laz
PowderN: HCS_Al_4: Read 26 reflections from file 'Al.laz'
PowderN: HCS_Al_4: Vc=66.4 [Angs] sigma_abs=0.924 [barn] sigma_inc=0.0328 [barn] reflections=Al.laz
Opening input file '/u/data/pkwi/McStas/mcstas/3.x-dev/tools/Python/mcrun/../mccodelib/../../../data/Al.laz' (Table_Read_Offset)
Table from file 'Al.laz' (block 1) is 26 x 18 (x=1:8), constant step. interpolation: linear
  '# TITLE *Aluminum-Al-[FM3-M] Miller, H.P.jr.;DuMond, J.W.M.[1942] at 298 K; ...'
PowderN: HCS_Al_5: Reading 26 rows from Al.laz
PowderN: HCS_Al_5: Read 26 reflections from file 'Al.laz'
PowderN: HCS_Al_5: Vc=66.4 [Angs] sigma_abs=0.924 [barn] sigma_inc=0.0328 [barn] reflections=Al.laz
Opening input file '/u/data/pkwi/McStas/mcstas/3.x-dev/tools/Python/mcrun/../mccodelib/../../../data/Al.laz' (Table_Read_Offset)
Table from file 'Al.laz' (block 1) is 26 x 18 (x=1:8), constant step. interpolation: linear
  '# TITLE *Aluminum-Al-[FM3-M] Miller, H.P.jr.;DuMond, J.W.M.[1942] at 298 K; ...'
PowderN: HCS_Al_6: Reading 26 rows from Al.laz
PowderN: HCS_Al_6: Read 26 reflections from file 'Al.laz'
PowderN: HCS_Al_6: Vc=66.4 [Angs] sigma_abs=0.924 [barn] sigma_inc=0.0328 [barn] reflections=Al.laz
Opening input file '/u/data/pkwi/McStas/mcstas/3.x-dev/tools/Python/mcrun/../mccodelib/../../../data/Al.laz' (Table_Read_Offset)
Table from file 'Al.laz' (block 1) is 26 x 18 (x=1:8), constant step. interpolation: linear
  '# TITLE *Aluminum-Al-[FM3-M] Miller, H.P.jr.;DuMond, J.W.M.[1942] at 298 K; ...'
PowderN: HCS_Al_7: Reading 26 rows from Al.laz
PowderN: HCS_Al_7: Read 26 reflections from file 'Al.laz'
PowderN: HCS_Al_7: Vc=66.4 [Angs] sigma_abs=0.924 [barn] sigma_inc=0.0328 [barn] reflections=Al.laz
Opening input file '/u/data/pkwi/McStas/mcstas/3.x-dev/tools/Python/mcrun/../mccodelib/../../../data/Al.laz' (Table_Read_Offset)
Table from file 'Al.laz' (block 1) is 26 x 18 (x=1:8), constant step. interpolation: linear
  '# TITLE *Aluminum-Al-[FM3-M] Miller, H.P.jr.;DuMond, J.W.M.[1942] at 298 K; ...'
PowderN: HCS_Al_15: Reading 26 rows from Al.laz
PowderN: HCS_Al_15: Read 26 reflections from file 'Al.laz'
PowderN: HCS_Al_15: Vc=66.4 [Angs] sigma_abs=0.924 [barn] sigma_inc=0.0328 [barn] reflections=Al.laz
Opening input file '/u/data/pkwi/McStas/mcstas/3.x-dev/tools/Python/mcrun/../mccodelib/../../../data/Al.laz' (Table_Read_Offset)
Table from file 'Al.laz' (block 1) is 26 x 18 (x=1:8), constant step. interpolation: linear
  '# TITLE *Aluminum-Al-[FM3-M] Miller, H.P.jr.;DuMond, J.W.M.[1942] at 298 K; ...'
PowderN: HCS_Al_17: Reading 26 rows from Al.laz
PowderN: HCS_Al_17: Read 26 reflections from file 'Al.laz'
PowderN: HCS_Al_17: Vc=66.4 [Angs] sigma_abs=0.924 [barn] sigma_inc=0.0328 [barn] reflections=Al.laz
Opening input file '/u/data/pkwi/McStas/mcstas/3.x-dev/tools/Python/mcrun/../mccodelib/../../../data/Al.laz' (Table_Read_Offset)
Table from file 'Al.laz' (block 1) is 26 x 18 (x=1:8), constant step. interpolation: linear
  '# TITLE *Aluminum-Al-[FM3-M] Miller, H.P.jr.;DuMond, J.W.M.[1942] at 298 K; ...'
PowderN: HCS_Al_H51: Reading 26 rows from Al.laz
PowderN: HCS_Al_H51: Read 26 reflections from file 'Al.laz'
PowderN: HCS_Al_H51: Vc=66.4 [Angs] sigma_abs=0.924 [barn] sigma_inc=0.0328 [barn] reflections=Al.laz
Opening input file '/u/data/pkwi/McStas/mcstas/3.x-dev/tools/Python/mcrun/../mccodelib/../../../data/Al.laz' (Table_Read_Offset)
Table from file 'Al.laz' (block 1) is 26 x 18 (x=1:8), constant step. interpolation: linear
  '# TITLE *Aluminum-Al-[FM3-M] Miller, H.P.jr.;DuMond, J.W.M.[1942] at 298 K; ...'
PowderN: HCS_Al_H512: Reading 26 rows from Al.laz
PowderN: HCS_Al_H512: Read 26 reflections from file 'Al.laz'
PowderN: HCS_Al_H512: Vc=66.4 [Angs] sigma_abs=0.924 [barn] sigma_inc=0.0328 [barn] reflections=Al.laz
Opening input file '/u/data/pkwi/McStas/mcstas/3.x-dev/tools/Python/mcrun/../mccodelib/../../../data/Al.laz' (Table_Read_Offset)
Table from file 'Al.laz' (block 1) is 26 x 18 (x=1:8), constant step. interpolation: linear
  '# TITLE *Aluminum-Al-[FM3-M] Miller, H.P.jr.;DuMond, J.W.M.[1942] at 298 K; ...'
PowderN: HCS_Al_45: Reading 26 rows from Al.laz
PowderN: HCS_Al_45: Read 26 reflections from file 'Al.laz'
PowderN: HCS_Al_45: Vc=66.4 [Angs] sigma_abs=0.924 [barn] sigma_inc=0.0328 [barn] reflections=Al.laz
Opening input file '/u/data/pkwi/McStas/mcstas/3.x-dev/tools/Python/mcrun/../mccodelib/../../../data/Al.laz' (Table_Read_Offset)
Table from file 'Al.laz' (block 1) is 26 x 18 (x=1:8), constant step. interpolation: linear
  '# TITLE *Aluminum-Al-[FM3-M] Miller, H.P.jr.;DuMond, J.W.M.[1942] at 298 K; ...'
PowderN: HCS_Al_50: Reading 26 rows from Al.laz
PowderN: HCS_Al_50: Read 26 reflections from file 'Al.laz'
PowderN: HCS_Al_50: Vc=66.4 [Angs] sigma_abs=0.924 [barn] sigma_inc=0.0328 [barn] reflections=Al.laz
Opening input file '/u/data/pkwi/McStas/mcstas/3.x-dev/tools/Python/mcrun/../mccodelib/../../../data/Al.laz' (Table_Read_Offset)
Table from file 'Al.laz' (block 1) is 26 x 18 (x=1:8), constant step. interpolation: linear
  '# TITLE *Aluminum-Al-[FM3-M] Miller, H.P.jr.;DuMond, J.W.M.[1942] at 298 K; ...'
PowderN: HCS_Al_52: Reading 26 rows from Al.laz
PowderN: HCS_Al_52: Read 26 reflections from file 'Al.laz'
PowderN: HCS_Al_52: Vc=66.4 [Angs] sigma_abs=0.924 [barn] sigma_inc=0.0328 [barn] reflections=Al.laz
Isotropic_Sqw: H51_D22_Sample: is a cylinder: radius=0.005000 thickness=0.000000 height=0.050000 [J Comp Phys 228 (2009) 5251]
Isotropic_Sqw: H51_D22_Sample: Computing scattering unit density V_rho=0.0332279 [AA^-3] from density=0.994 [g/cm^3] weight=18.015 [g/mol].
Isotropic_sqw: Converting 150 S(q) from H2O_liq.qSq into S(q,w) data
Isotropic_sqw: q range [0:14.8], creating 150 elements vector
Isotropic_Sqw: H51_D22_Sample: Temperature set to T=293 [K]
Isotropic_Sqw: H51_D22_Sample: Applying exp(hw/2kT) factor with T=293 [K] on H2O_liq.qSq file (classical/symmetric) using 'Frommhold' quantum correction
Isotropic_Sqw: H51_D22_Sample: Generated H2O_liq.qSq coherent Sqw
                   q=[0:14.8 Angs-1] w=[0:0 meV] |S|=1.49025 size=[152x1] sigma=7.751 [barns]
               Mainly elastic scattering.
Isotropic_Sqw: H51_D22_Sample: Using Isotropic elastic incoherent scattering (sigma=160.521 [barns])
Isotropic_Sqw: H51_D22_Sample: Focusing can only apply for single
               scattering. Setting to order=1.
Have found 8 GPU devices on rank 1. Will use device 1.
ILL_H512_D22: D22:  nu=28307.4 [rpm] lambda=4.5 [Angs] sample=H2O_liq.qSq
ILL_H512_D22: D22:  nu=28307.4 [rpm] lambda=4.5 [Angs] sample=H2O_liq.qSq
ILL_H512_D22: D22:  nu=28307.4 [rpm] lambda=4.5 [Angs] sample=H2O_liq.qSq
[ILL_H512_D22] Initialize
[ILL_H512_D22] Initialize
[ILL_H512_D22] Initialize
*** TRACE end *** 

Save [ILL_H512_D22]
Detector: H5_I=6.10426e+12 H5_ERR=1.00191e+09 H5_N=3.83745e+07 "H5_1667268909.x_y"
Detector: H51_origin_I=1.0035e+12 H51_origin_ERR=4.06367e+08 H51_origin_N=6.2903e+06 "H51_origin_1667268909.hd_vd"
Detector: H51_Obt_Out_I=1.15644e+11 H51_Obt_Out_ERR=1.3526e+08 H51_Obt_Out_N=2.72972e+06 "H51_Obt_Out_1667268909.x_y"
Detector: H51_S2_Out_I=8.88924e+10 H51_S2_Out_ERR=1.24365e+08 H51_S2_Out_N=2.32377e+06 "H51_S2_Out_1667268909.x_y"
Detector: H51_split_2_I=4.11239e+10 H51_split_2_ERR=8.47091e+07 H51_split_2_N=1.06448e+06 "H51_split_2_1667268909.x_y"
Detector: H51_split_1_I=4.09099e+10 H51_split_1_ERR=8.44646e+07 H51_split_1_N=1.0639e+06 "H51_split_1_1667268909.x_y"
Detector: H512_VTE_I=2.93546e+10 H512_VTE_ERR=7.3357e+07 H512_VTE_N=974727 "H512_VTE_1667268909.x_y"
Detector: D22_PreV_I=2.4087e+10 D22_PreV_ERR=7.11444e+07 D22_PreV_N=696476 "D22_PreV_1667268909.x_y"
Detector: H51_D22_Sample_Div_I=2.15883e+09 H51_D22_Sample_Div_ERR=1.7159e+07 H51_D22_Sample_Div_N=58314 "H51_D22_Sample_Div_1667268909.hd_vd"
Detector: H51_D22_Sample_XY_I=2.15883e+09 H51_D22_Sample_XY_ERR=1.7159e+07 H51_D22_Sample_XY_N=58314 "H51_D22_Sample_XY_1667268909.x_y"
Detector: H51_D22_Sample_L_I=2.15883e+09 H51_D22_Sample_L_ERR=1.7159e+07 H51_D22_Sample_L_N=58314 "H51_D22_Sample_L_1667268909.L"

Finally [ILL_H512_D22: 1]. Time: 2 [s] 

Finally [ILL_H512_D22: 1]. Time: 2 [s] 

Finally [ILL_H512_D22: 1]. Time: 2 [s] 

Finally [ILL_H512_D22: 1]. Time: 2 [s] 
Detector: D22_Detector_I=1.9045e+07 D22_Detector_ERR=260409 D22_Detector_N=21420 "D22_Detector_1667268909.x_y"

Finally [ILL_H512_D22: 1]. Time: 2 [s] 

Finally [ILL_H512_D22: 1]. Time: 2 [s] 

Finally [ILL_H512_D22: 1]. Time: 2 [s] 
Isotropic_Sqw: H51_D22_Sample: 436 neutron events (out of 183349) that should have
               scattered were transmitted because scattering conditions
WARNING        could not be satisfied after 100 tries.
Isotropic_Sqw: H51_D22_Sample: Scattering fraction=0.970531 of incoming intensity
               Absorption fraction           =0.00976102
               Single   scattering intensity =1.54075e+08 (coh=1.07068e+07 inc=1.43368e+08)
               Multiple scattering intensity =0
INFO: Placing instr file copy /home/nexmap/pkwi/TESTS/2022-11-01/McStas_8GPU_5e7/ILL_H512_D22/ILL_H512_D22.instr in dataset 1