Test Date: 2015-09-04 15:40
Analysis date: 2015-11-23 18:14
Logfile
pxar.log
[14:44:20.532] INFO: *** Welcome to pxar ***
[14:44:20.533] INFO: *** Today: 2015/09/04
[14:44:20.533] INFO: readRocDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C15.dat
[14:44:20.535] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0b.dat
[14:44:20.535] INFO: readMaskFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/defaultMaskFile.dat
[14:44:20.535] INFO: readTrimFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C15.dat
[14:44:20.614] INFO: clk: 4
[14:44:20.614] INFO: ctr: 4
[14:44:20.614] INFO: sda: 19
[14:44:20.614] INFO: tin: 9
[14:44:20.614] INFO: level: 15
[14:44:20.614] INFO: triggerdelay: 0
[14:44:20.614] QUIET: Instanciating API for pxar 2.5
[14:44:20.614] INFO: Log level: INFO
[14:44:20.621] INFO: Found DTB DTB_WZ4QKL
[14:44:20.633] QUIET: Connection to board DTB_WZ4QKL opened.
[14:44:20.637] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 172
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WZ4QKL
MAC address: 40D8551180AC
Hostname: pixelDTB172
Comment:
------------------------------------------------------
[14:44:20.641] INFO: RPC call hashes of host and DTB match: 397073690
[14:44:22.458] INFO: DUT info:
[14:44:22.458] INFO: The DUT currently contains the following objects:
[14:44:22.458] INFO: 2 TBM Cores tbm08c (2 ON)
[14:44:22.458] INFO: TBM Core alpha (0): 7 registers set
[14:44:22.458] INFO: TBM Core beta (1): 7 registers set
[14:44:22.458] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:44:22.458] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.458] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.458] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.458] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.459] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.459] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.459] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.459] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.459] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.459] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.459] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.459] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.459] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.459] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.459] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.459] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:22.870] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[14:44:23.113] INFO: ######################################################################
[14:44:23.113] INFO: PixTestXray::doTest()
[14:44:23.113] INFO: ######################################################################
[14:44:23.113] INFO: ----------------------------------------------------------------------
[14:44:23.113] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:44:23.113] INFO: ----------------------------------------------------------------------
[14:44:24.132] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:44:27.863] INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[14:45:27.084] INFO: Resuming triggers.
[14:45:30.837] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[14:46:29.710] INFO: Resuming triggers.
[14:46:33.466] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:47:29.612] INFO: Resuming triggers.
[14:47:33.363] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[14:48:28.310] INFO: Resuming triggers.
[14:48:32.069] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[14:49:26.109] INFO: Resuming triggers.
[14:49:29.864] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[14:50:23.124] INFO: Resuming triggers.
[14:50:26.880] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[14:51:19.750] INFO: Resuming triggers.
[14:51:23.507] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[14:52:16.293] INFO: Resuming triggers.
[14:52:20.056] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[14:53:11.678] INFO: Resuming triggers.
[14:53:15.435] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[14:54:07.213] INFO: Resuming triggers.
[14:54:10.983] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[14:55:03.149] INFO: Resuming triggers.
[14:55:06.897] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[14:55:58.914] INFO: Resuming triggers.
[14:56:02.662] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[14:56:54.601] INFO: Resuming triggers.
[14:56:58.350] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[14:57:50.810] INFO: Resuming triggers.
[14:57:54.557] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[14:58:46.710] INFO: Resuming triggers.
[14:58:50.456] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[14:59:42.983] INFO: Resuming triggers.
[14:59:46.729] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[15:00:38.363] INFO: Resuming triggers.
[15:00:42.108] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[15:01:34.180] INFO: Resuming triggers.
[15:01:37.931] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[15:02:30.178] INFO: Resuming triggers.
[15:02:33.924] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[15:03:26.250] INFO: Resuming triggers.
[15:03:29.997] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[15:04:21.931] INFO: Resuming triggers.
[15:04:25.673] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[15:05:17.848] INFO: Resuming triggers.
[15:05:21.597] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[15:06:13.828] INFO: Resuming triggers.
[15:06:17.571] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[15:07:09.600] INFO: Resuming triggers.
[15:07:13.344] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[15:08:05.558] INFO: Resuming triggers.
[15:08:09.301] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[15:09:01.395] INFO: Resuming triggers.
[15:09:04.156] INFO: data taking finished, elapsed time: 100 seconds.
[15:09:45.735] INFO: PixTest:: pg_setup set to default.
[15:09:45.737] INFO: PixTestXray::doPhRun() done
[15:09:45.737] INFO: PixTestXray::doTest() done
[15:09:46.154] QUIET: Connection to board 172 closed.
[15:09:46.174] INFO: pXar: this is the end, my friend
pxar.log
[15:12:23.300] INFO: *** Welcome to pxar ***
[15:12:23.300] INFO: *** Today: 2015/09/04
[15:12:23.300] INFO: readRocDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C15.dat
[15:12:23.301] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0b.dat
[15:12:23.301] INFO: readMaskFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/defaultMaskFile.dat
[15:12:23.301] INFO: readTrimFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C15.dat
[15:12:23.377] INFO: clk: 4
[15:12:23.377] INFO: ctr: 4
[15:12:23.377] INFO: sda: 19
[15:12:23.377] INFO: tin: 9
[15:12:23.377] INFO: level: 15
[15:12:23.377] INFO: triggerdelay: 0
[15:12:23.377] QUIET: Instanciating API for pxar 2.5
[15:12:23.377] INFO: Log level: INFO
[15:12:23.389] INFO: Found DTB DTB_WZ4QKL
[15:12:23.407] QUIET: Connection to board DTB_WZ4QKL opened.
[15:12:23.411] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 172
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WZ4QKL
MAC address: 40D8551180AC
Hostname: pixelDTB172
Comment:
------------------------------------------------------
[15:12:23.414] INFO: RPC call hashes of host and DTB match: 397073690
[15:12:25.222] INFO: DUT info:
[15:12:25.222] INFO: The DUT currently contains the following objects:
[15:12:25.223] INFO: 2 TBM Cores tbm08c (2 ON)
[15:12:25.223] INFO: TBM Core alpha (0): 7 registers set
[15:12:25.223] INFO: TBM Core beta (1): 7 registers set
[15:12:25.223] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:12:25.223] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.223] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.223] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.223] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.223] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.223] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.223] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.223] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.223] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.223] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.223] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.223] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.224] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.224] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.224] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.224] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:12:25.634] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[15:12:25.857] INFO: ######################################################################
[15:12:25.857] INFO: PixTestXray::doTest()
[15:12:25.857] INFO: ######################################################################
[15:12:25.857] INFO: ----------------------------------------------------------------------
[15:12:25.857] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:12:25.857] INFO: ----------------------------------------------------------------------
[15:12:26.869] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:12:34.644] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:13:31.298] INFO: Resuming triggers.
[15:13:39.085] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:14:35.607] INFO: Resuming triggers.
[15:14:43.389] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:15:39.634] INFO: Resuming triggers.
[15:15:47.409] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[15:16:42.252] INFO: Resuming triggers.
[15:16:50.022] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[15:17:43.377] INFO: Resuming triggers.
[15:17:51.163] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:18:41.700] INFO: Resuming triggers.
[15:18:49.469] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[15:19:38.927] INFO: Resuming triggers.
[15:19:46.705] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[15:20:35.509] INFO: Resuming triggers.
[15:20:43.284] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:21:31.805] INFO: Resuming triggers.
[15:21:39.570] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[15:22:27.817] INFO: Resuming triggers.
[15:22:35.590] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[15:23:23.755] INFO: Resuming triggers.
[15:23:31.525] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[15:24:19.377] INFO: Resuming triggers.
[15:24:26.338] INFO: data taking finished, elapsed time: 100 seconds.
[15:25:09.010] INFO: PixTest:: pg_setup set to default.
[15:25:09.012] INFO: PixTestXray::doPhRun() done
[15:25:09.012] INFO: PixTestXray::doTest() done
[15:25:09.413] QUIET: Connection to board 172 closed.
[15:25:09.414] INFO: pXar: this is the end, my friend
pxar.log
[15:26:20.153] INFO: *** Welcome to pxar ***
[15:26:20.153] INFO: *** Today: 2015/09/04
[15:26:20.153] INFO: readRocDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C15.dat
[15:26:20.155] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0b.dat
[15:26:20.156] INFO: readMaskFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/defaultMaskFile.dat
[15:26:20.156] INFO: readTrimFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C15.dat
[15:26:20.232] INFO: clk: 4
[15:26:20.232] INFO: ctr: 4
[15:26:20.232] INFO: sda: 19
[15:26:20.232] INFO: tin: 9
[15:26:20.232] INFO: level: 15
[15:26:20.232] INFO: triggerdelay: 0
[15:26:20.232] QUIET: Instanciating API for pxar 2.5
[15:26:20.232] INFO: Log level: INFO
[15:26:20.241] INFO: Found DTB DTB_WZ4QKL
[15:26:20.253] QUIET: Connection to board DTB_WZ4QKL opened.
[15:26:20.257] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 172
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WZ4QKL
MAC address: 40D8551180AC
Hostname: pixelDTB172
Comment:
------------------------------------------------------
[15:26:20.261] INFO: RPC call hashes of host and DTB match: 397073690
[15:26:22.077] INFO: DUT info:
[15:26:22.077] INFO: The DUT currently contains the following objects:
[15:26:22.077] INFO: 2 TBM Cores tbm08c (2 ON)
[15:26:22.077] INFO: TBM Core alpha (0): 7 registers set
[15:26:22.077] INFO: TBM Core beta (1): 7 registers set
[15:26:22.077] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:26:22.077] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.077] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.077] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.078] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.078] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.078] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.078] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.078] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.078] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.078] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.078] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.078] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.078] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.078] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.078] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.078] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:22.480] INFO: enter 'restricted' command line mode
[15:26:22.480] INFO: enter test to run
[15:26:22.480] INFO: test: highrate no parameter change
[15:26:22.481] INFO: running: highrate
[15:26:22.489] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[15:26:22.727] INFO: ----------------------------------------------------------------------
[15:26:22.727] INFO: PixTestHighRate::xNoiseMaps() ntrig = 25, vcal = 200
[15:26:22.727] INFO: ----------------------------------------------------------------------
[15:26:22.736] INFO: ---> dac: vcal name: xNoiseMap ntrig: 25 dacrange: 0 .. 100 (1/-1) hits flags = 384 (plus default)
[15:26:22.781] INFO: dacScan step from 0 .. 0
[15:26:22.781] INFO: dacScan split into 1 runs with ntrig = 25
[15:26:22.781] INFO: run 1 of 1
[15:26:23.628] INFO: Expecting 104000 events.
[15:26:36.551] INFO: 104000 events read in total (12392ms).
[15:26:36.567] INFO: Test took 13785ms.
[15:26:36.653] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:36.976] INFO: dacScan step from 1 .. 1
[15:26:36.976] INFO: dacScan split into 1 runs with ntrig = 25
[15:26:36.976] INFO: run 1 of 1
[15:26:37.819] INFO: Expecting 104000 events.
[15:26:50.462] INFO: 104000 events read in total (12116ms).
[15:26:50.480] INFO: Test took 13504ms.
[15:26:50.578] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:50.895] INFO: dacScan step from 2 .. 2
[15:26:50.896] INFO: dacScan split into 1 runs with ntrig = 25
[15:26:50.896] INFO: run 1 of 1
[15:26:51.746] INFO: Expecting 104000 events.
[15:27:04.322] INFO: 104000 events read in total (12049ms).
[15:27:04.332] INFO: Test took 13436ms.
[15:27:04.427] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:04.751] INFO: dacScan step from 3 .. 3
[15:27:04.751] INFO: dacScan split into 1 runs with ntrig = 25
[15:27:04.751] INFO: run 1 of 1
[15:27:05.597] INFO: Expecting 104000 events.
[15:27:17.412] INFO: 104000 events read in total (11288ms).
[15:27:17.423] INFO: Test took 12671ms.
[15:27:17.516] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:17.831] INFO: dacScan step from 4 .. 4
[15:27:17.831] INFO: dacScan split into 1 runs with ntrig = 25
[15:27:17.831] INFO: run 1 of 1
[15:27:18.682] INFO: Expecting 104000 events.
[15:27:30.875] INFO: 104000 events read in total (11666ms).
[15:27:30.886] INFO: Test took 13055ms.
[15:27:30.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:31.296] INFO: dacScan step from 5 .. 5
[15:27:31.296] INFO: dacScan split into 1 runs with ntrig = 25
[15:27:31.296] INFO: run 1 of 1
[15:27:32.142] INFO: Expecting 104000 events.
[15:27:43.982] INFO: 104000 events read in total (11312ms).
[15:27:43.992] INFO: Test took 12695ms.
[15:27:44.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:44.399] INFO: dacScan step from 6 .. 6
[15:27:44.399] INFO: dacScan split into 1 runs with ntrig = 25
[15:27:44.399] INFO: run 1 of 1
[15:27:45.246] INFO: Expecting 104000 events.
[15:27:56.597] INFO: 104000 events read in total (10825ms).
[15:27:56.613] INFO: Test took 12213ms.
[15:27:56.707] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:57.025] INFO: dacScan step from 7 .. 7
[15:27:57.025] INFO: dacScan split into 1 runs with ntrig = 25
[15:27:57.025] INFO: run 1 of 1
[15:27:57.872] INFO: Expecting 104000 events.
[15:28:09.464] INFO: 104000 events read in total (11065ms).
[15:28:09.475] INFO: Test took 12450ms.
[15:28:09.567] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:09.887] INFO: dacScan step from 8 .. 8
[15:28:09.887] INFO: dacScan split into 1 runs with ntrig = 25
[15:28:09.887] INFO: run 1 of 1
[15:28:10.738] INFO: Expecting 104000 events.
[15:28:22.632] INFO: 104000 events read in total (11367ms).
[15:28:22.645] INFO: Test took 12758ms.
[15:28:22.739] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:23.056] INFO: dacScan step from 9 .. 9
[15:28:23.056] INFO: dacScan split into 1 runs with ntrig = 25
[15:28:23.056] INFO: run 1 of 1
[15:28:23.903] INFO: Expecting 104000 events.
[15:28:35.644] INFO: 104000 events read in total (11214ms).
[15:28:35.654] INFO: Test took 12598ms.
[15:28:35.747] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:36.062] INFO: dacScan step from 10 .. 10
[15:28:36.062] INFO: dacScan split into 1 runs with ntrig = 25
[15:28:36.062] INFO: run 1 of 1
[15:28:36.910] INFO: Expecting 104000 events.
[15:28:49.059] INFO: 104000 events read in total (11621ms).
[15:28:49.070] INFO: Test took 13007ms.
[15:28:49.160] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:49.460] INFO: dacScan step from 11 .. 11
[15:28:49.460] INFO: dacScan split into 1 runs with ntrig = 25
[15:28:49.460] INFO: run 1 of 1
[15:28:50.307] INFO: Expecting 104000 events.
[15:29:02.416] INFO: 104000 events read in total (11582ms).
[15:29:02.426] INFO: Test took 12966ms.
[15:29:02.515] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:02.823] INFO: dacScan step from 12 .. 12
[15:29:02.823] INFO: dacScan split into 1 runs with ntrig = 25
[15:29:02.823] INFO: run 1 of 1
[15:29:03.647] INFO: Expecting 104000 events.
[15:29:16.323] INFO: 104000 events read in total (12149ms).
[15:29:16.333] INFO: Test took 13510ms.
[15:29:16.423] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:16.721] INFO: dacScan step from 13 .. 13
[15:29:16.721] INFO: dacScan split into 1 runs with ntrig = 25
[15:29:16.721] INFO: run 1 of 1
[15:29:17.553] INFO: Expecting 104000 events.
[15:29:30.423] INFO: 104000 events read in total (12343ms).
[15:29:30.433] INFO: Test took 13711ms.
[15:29:30.521] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:30.825] INFO: dacScan step from 14 .. 14
[15:29:30.825] INFO: dacScan split into 1 runs with ntrig = 25
[15:29:30.825] INFO: run 1 of 1
[15:29:31.653] INFO: Expecting 104000 events.
[15:29:44.382] INFO: 104000 events read in total (12202ms).
[15:29:44.393] INFO: Test took 13567ms.
[15:29:44.489] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:44.804] INFO: dacScan step from 15 .. 15
[15:29:44.804] INFO: dacScan split into 1 runs with ntrig = 25
[15:29:44.804] INFO: run 1 of 1
[15:29:45.656] INFO: Expecting 104000 events.
[15:29:58.431] INFO: 104000 events read in total (12248ms).
[15:29:58.442] INFO: Test took 13637ms.
[15:29:58.536] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:58.847] INFO: dacScan step from 16 .. 16
[15:29:58.847] INFO: dacScan split into 1 runs with ntrig = 25
[15:29:58.847] INFO: run 1 of 1
[15:29:59.683] INFO: Expecting 104000 events.
[15:30:12.673] INFO: 104000 events read in total (12463ms).
[15:30:12.683] INFO: Test took 13835ms.
[15:30:12.788] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:13.103] INFO: dacScan step from 17 .. 17
[15:30:13.103] INFO: dacScan split into 1 runs with ntrig = 25
[15:30:13.103] INFO: run 1 of 1
[15:30:13.940] INFO: Expecting 104000 events.
[15:30:26.165] INFO: 104000 events read in total (11698ms).
[15:30:26.176] INFO: Test took 13073ms.
[15:30:26.277] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:26.593] INFO: dacScan step from 18 .. 18
[15:30:26.593] INFO: dacScan split into 1 runs with ntrig = 25
[15:30:26.593] INFO: run 1 of 1
[15:30:27.448] INFO: Expecting 104000 events.
[15:30:39.943] INFO: 104000 events read in total (11968ms).
[15:30:39.953] INFO: Test took 13359ms.
[15:30:40.045] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:40.365] INFO: dacScan step from 19 .. 19
[15:30:40.365] INFO: dacScan split into 1 runs with ntrig = 25
[15:30:40.365] INFO: run 1 of 1
[15:30:41.212] INFO: Expecting 104000 events.
[15:30:53.189] INFO: 104000 events read in total (11450ms).
[15:30:53.200] INFO: Test took 12834ms.
[15:30:53.291] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:53.607] INFO: dacScan step from 20 .. 20
[15:30:53.607] INFO: dacScan split into 1 runs with ntrig = 25
[15:30:53.607] INFO: run 1 of 1
[15:30:54.456] INFO: Expecting 104000 events.
[15:31:06.488] INFO: 104000 events read in total (11505ms).
[15:31:06.499] INFO: Test took 12892ms.
[15:31:06.597] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:06.915] INFO: dacScan step from 21 .. 21
[15:31:06.915] INFO: dacScan split into 1 runs with ntrig = 25
[15:31:06.915] INFO: run 1 of 1
[15:31:07.767] INFO: Expecting 104000 events.
[15:31:19.599] INFO: 104000 events read in total (11305ms).
[15:31:19.610] INFO: Test took 12695ms.
[15:31:19.702] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:20.019] INFO: dacScan step from 22 .. 22
[15:31:20.019] INFO: dacScan split into 1 runs with ntrig = 25
[15:31:20.019] INFO: run 1 of 1
[15:31:20.863] INFO: Expecting 104000 events.
[15:31:32.859] INFO: 104000 events read in total (11469ms).
[15:31:32.869] INFO: Test took 12849ms.
[15:31:32.969] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:33.287] INFO: dacScan step from 23 .. 23
[15:31:33.287] INFO: dacScan split into 1 runs with ntrig = 25
[15:31:33.287] INFO: run 1 of 1
[15:31:34.140] INFO: Expecting 104000 events.
[15:31:46.066] INFO: 104000 events read in total (11399ms).
[15:31:46.077] INFO: Test took 12789ms.
[15:31:46.170] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:46.489] INFO: dacScan step from 24 .. 24
[15:31:46.489] INFO: dacScan split into 1 runs with ntrig = 25
[15:31:46.489] INFO: run 1 of 1
[15:31:47.337] INFO: Expecting 104000 events.
[15:31:59.249] INFO: 104000 events read in total (11386ms).
[15:31:59.259] INFO: Test took 12769ms.
[15:31:59.351] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:59.666] INFO: dacScan step from 25 .. 25
[15:31:59.666] INFO: dacScan split into 1 runs with ntrig = 25
[15:31:59.666] INFO: run 1 of 1
[15:32:00.517] INFO: Expecting 104000 events.
[15:32:12.552] INFO: 104000 events read in total (11508ms).
[15:32:12.562] INFO: Test took 12895ms.
[15:32:12.655] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:12.974] INFO: dacScan step from 26 .. 26
[15:32:12.974] INFO: dacScan split into 1 runs with ntrig = 25
[15:32:12.974] INFO: run 1 of 1
[15:32:13.820] INFO: Expecting 104000 events.
[15:32:25.755] INFO: 104000 events read in total (11408ms).
[15:32:25.766] INFO: Test took 12791ms.
[15:32:25.858] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:26.177] INFO: dacScan step from 27 .. 27
[15:32:26.177] INFO: dacScan split into 1 runs with ntrig = 25
[15:32:26.177] INFO: run 1 of 1
[15:32:27.021] INFO: Expecting 104000 events.
[15:32:39.120] INFO: 104000 events read in total (11572ms).
[15:32:39.130] INFO: Test took 12952ms.
[15:32:39.228] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:39.526] INFO: dacScan step from 28 .. 28
[15:32:39.526] INFO: dacScan split into 1 runs with ntrig = 25
[15:32:39.526] INFO: run 1 of 1
[15:32:40.357] INFO: Expecting 104000 events.
[15:32:52.754] INFO: 104000 events read in total (11870ms).
[15:32:52.764] INFO: Test took 13237ms.
[15:32:52.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:53.151] INFO: dacScan step from 29 .. 29
[15:32:53.151] INFO: dacScan split into 1 runs with ntrig = 25
[15:32:53.151] INFO: run 1 of 1
[15:32:53.985] INFO: Expecting 104000 events.
[15:33:06.386] INFO: 104000 events read in total (11874ms).
[15:33:06.395] INFO: Test took 13243ms.
[15:33:06.483] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:06.780] INFO: dacScan step from 30 .. 30
[15:33:06.780] INFO: dacScan split into 1 runs with ntrig = 25
[15:33:06.780] INFO: run 1 of 1
[15:33:07.607] INFO: Expecting 104000 events.
[15:33:20.334] INFO: 104000 events read in total (12200ms).
[15:33:20.345] INFO: Test took 13564ms.
[15:33:20.440] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:20.758] INFO: dacScan step from 31 .. 31
[15:33:20.758] INFO: dacScan split into 1 runs with ntrig = 25
[15:33:20.758] INFO: run 1 of 1
[15:33:21.587] INFO: Expecting 104000 events.
[15:33:34.455] INFO: 104000 events read in total (12341ms).
[15:33:34.472] INFO: Test took 13713ms.
[15:33:34.564] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:34.871] INFO: dacScan step from 32 .. 32
[15:33:34.871] INFO: dacScan split into 1 runs with ntrig = 25
[15:33:34.872] INFO: run 1 of 1
[15:33:35.714] INFO: Expecting 104000 events.
[15:33:48.566] INFO: 104000 events read in total (12325ms).
[15:33:48.577] INFO: Test took 13705ms.
[15:33:48.674] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:48.990] INFO: dacScan step from 33 .. 33
[15:33:48.990] INFO: dacScan split into 1 runs with ntrig = 25
[15:33:48.990] INFO: run 1 of 1
[15:33:49.824] INFO: Expecting 104000 events.
[15:34:02.851] INFO: 104000 events read in total (12500ms).
[15:34:02.865] INFO: Test took 13875ms.
[15:34:02.969] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:03.288] INFO: dacScan step from 34 .. 34
[15:34:03.288] INFO: dacScan split into 1 runs with ntrig = 25
[15:34:03.288] INFO: run 1 of 1
[15:34:04.129] INFO: Expecting 104000 events.
[15:34:16.478] INFO: 104000 events read in total (11822ms).
[15:34:16.489] INFO: Test took 13200ms.
[15:34:16.589] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:16.908] INFO: dacScan step from 35 .. 35
[15:34:16.908] INFO: dacScan split into 1 runs with ntrig = 25
[15:34:16.908] INFO: run 1 of 1
[15:34:17.760] INFO: Expecting 104000 events.
[15:34:30.192] INFO: 104000 events read in total (11905ms).
[15:34:30.203] INFO: Test took 13294ms.
[15:34:30.296] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:30.617] INFO: dacScan step from 36 .. 36
[15:34:30.617] INFO: dacScan split into 1 runs with ntrig = 25
[15:34:30.617] INFO: run 1 of 1
[15:34:31.466] INFO: Expecting 104000 events.
[15:34:43.969] INFO: 104000 events read in total (11976ms).
[15:34:43.981] INFO: Test took 13363ms.
[15:34:44.087] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:44.411] INFO: dacScan step from 37 .. 37
[15:34:44.412] INFO: dacScan split into 1 runs with ntrig = 25
[15:34:44.412] INFO: run 1 of 1
[15:34:45.264] INFO: Expecting 104000 events.
[15:34:57.987] INFO: 104000 events read in total (12196ms).
[15:34:57.997] INFO: Test took 13585ms.
[15:34:58.096] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:58.419] INFO: dacScan step from 38 .. 38
[15:34:58.419] INFO: dacScan split into 1 runs with ntrig = 25
[15:34:58.419] INFO: run 1 of 1
[15:34:59.262] INFO: Expecting 104000 events.
[15:35:11.963] INFO: 104000 events read in total (12174ms).
[15:35:11.973] INFO: Test took 13553ms.
[15:35:12.067] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:12.393] INFO: dacScan step from 39 .. 39
[15:35:12.393] INFO: dacScan split into 1 runs with ntrig = 25
[15:35:12.393] INFO: run 1 of 1
[15:35:13.241] INFO: Expecting 104000 events.
[15:35:26.024] INFO: 104000 events read in total (12256ms).
[15:35:26.034] INFO: Test took 13640ms.
[15:35:26.138] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:26.461] INFO: dacScan step from 40 .. 40
[15:35:26.461] INFO: dacScan split into 1 runs with ntrig = 25
[15:35:26.461] INFO: run 1 of 1
[15:35:27.315] INFO: Expecting 104000 events.
[15:35:39.992] INFO: 104000 events read in total (12150ms).
[15:35:40.002] INFO: Test took 13540ms.
[15:35:40.099] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:40.422] INFO: dacScan step from 41 .. 41
[15:35:40.422] INFO: dacScan split into 1 runs with ntrig = 25
[15:35:40.422] INFO: run 1 of 1
[15:35:41.275] INFO: Expecting 104000 events.
[15:35:54.628] INFO: 104000 events read in total (12826ms).
[15:35:54.639] INFO: Test took 14216ms.
[15:35:54.736] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:55.064] INFO: dacScan step from 42 .. 42
[15:35:55.064] INFO: dacScan split into 1 runs with ntrig = 25
[15:35:55.064] INFO: run 1 of 1
[15:35:55.908] INFO: Expecting 104000 events.
[15:36:09.444] INFO: 104000 events read in total (13009ms).
[15:36:09.455] INFO: Test took 14390ms.
[15:36:09.559] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:09.888] INFO: dacScan step from 43 .. 43
[15:36:09.888] INFO: dacScan split into 1 runs with ntrig = 25
[15:36:09.888] INFO: run 1 of 1
[15:36:10.732] INFO: Expecting 104000 events.
[15:36:24.525] INFO: 104000 events read in total (13266ms).
[15:36:24.536] INFO: Test took 14648ms.
[15:36:24.633] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:24.969] INFO: dacScan step from 44 .. 44
[15:36:24.969] INFO: dacScan split into 1 runs with ntrig = 25
[15:36:24.969] INFO: run 1 of 1
[15:36:25.816] INFO: Expecting 104000 events.
[15:36:39.880] INFO: 104000 events read in total (13537ms).
[15:36:39.895] INFO: Test took 14925ms.
[15:36:39.981] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:40.311] INFO: dacScan step from 45 .. 45
[15:36:40.311] INFO: dacScan split into 1 runs with ntrig = 25
[15:36:40.311] INFO: run 1 of 1
[15:36:41.155] INFO: Expecting 104000 events.
[15:36:55.748] INFO: 104000 events read in total (14066ms).
[15:36:55.759] INFO: Test took 15447ms.
[15:36:55.844] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:56.175] INFO: dacScan step from 46 .. 46
[15:36:56.175] INFO: dacScan split into 1 runs with ntrig = 25
[15:36:56.175] INFO: run 1 of 1
[15:36:57.028] INFO: Expecting 104000 events.
[15:37:11.919] INFO: 104000 events read in total (14364ms).
[15:37:11.929] INFO: Test took 15753ms.
[15:37:12.024] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:12.353] INFO: dacScan step from 47 .. 47
[15:37:12.353] INFO: dacScan split into 1 runs with ntrig = 25
[15:37:12.353] INFO: run 1 of 1
[15:37:13.203] INFO: Expecting 104000 events.
[15:37:28.070] INFO: 104000 events read in total (14341ms).
[15:37:28.090] INFO: Test took 15736ms.
[15:37:28.184] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:28.514] INFO: dacScan step from 48 .. 48
[15:37:28.514] INFO: dacScan split into 1 runs with ntrig = 25
[15:37:28.514] INFO: run 1 of 1
[15:37:29.368] INFO: Expecting 104000 events.
[15:37:45.190] INFO: 104000 events read in total (15295ms).
[15:37:45.201] INFO: Test took 16686ms.
[15:37:45.295] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:45.626] INFO: dacScan step from 49 .. 49
[15:37:45.626] INFO: dacScan split into 1 runs with ntrig = 25
[15:37:45.626] INFO: run 1 of 1
[15:37:46.476] INFO: Expecting 104000 events.
[15:38:01.758] INFO: 104000 events read in total (14755ms).
[15:38:01.773] INFO: Test took 16146ms.
[15:38:01.872] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:02.206] INFO: dacScan step from 50 .. 50
[15:38:02.206] INFO: dacScan split into 1 runs with ntrig = 25
[15:38:02.206] INFO: run 1 of 1
[15:38:03.057] INFO: Expecting 104000 events.
[15:38:18.487] INFO: 104000 events read in total (14903ms).
[15:38:18.497] INFO: Test took 16290ms.
[15:38:18.585] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:18.919] INFO: dacScan step from 51 .. 51
[15:38:18.919] INFO: dacScan split into 1 runs with ntrig = 25
[15:38:18.919] INFO: run 1 of 1
[15:38:19.766] INFO: Expecting 104000 events.
[15:38:36.114] INFO: 104000 events read in total (15821ms).
[15:38:36.125] INFO: Test took 17205ms.
[15:38:36.217] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:36.552] INFO: dacScan step from 52 .. 52
[15:38:36.552] INFO: dacScan split into 1 runs with ntrig = 25
[15:38:36.552] INFO: run 1 of 1
[15:38:37.396] INFO: Expecting 104000 events.
[15:38:53.223] INFO: 104000 events read in total (15300ms).
[15:38:53.234] INFO: Test took 16681ms.
[15:38:53.321] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:53.654] INFO: dacScan step from 53 .. 53
[15:38:53.654] INFO: dacScan split into 1 runs with ntrig = 25
[15:38:53.654] INFO: run 1 of 1
[15:38:54.499] INFO: Expecting 104000 events.
[15:39:10.508] INFO: 104000 events read in total (15482ms).
[15:39:10.519] INFO: Test took 16864ms.
[15:39:10.602] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:10.934] INFO: dacScan step from 54 .. 54
[15:39:10.934] INFO: dacScan split into 1 runs with ntrig = 25
[15:39:10.934] INFO: run 1 of 1
[15:39:11.783] INFO: Expecting 104000 events.
[15:39:27.172] INFO: 104000 events read in total (14862ms).
[15:39:27.183] INFO: Test took 16248ms.
[15:39:27.280] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:27.614] INFO: dacScan step from 55 .. 55
[15:39:27.615] INFO: dacScan split into 1 runs with ntrig = 25
[15:39:27.615] INFO: run 1 of 1
[15:39:28.463] INFO: Expecting 104000 events.
[15:39:44.017] INFO: 104000 events read in total (15027ms).
[15:39:44.027] INFO: Test took 16411ms.
[15:39:44.114] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:44.451] INFO: dacScan step from 56 .. 56
[15:39:44.451] INFO: dacScan split into 1 runs with ntrig = 25
[15:39:44.451] INFO: run 1 of 1
[15:39:45.297] INFO: Expecting 104000 events.
[15:40:01.000] INFO: 104000 events read in total (15176ms).
[15:40:01.010] INFO: Test took 16558ms.
[15:40:01.097] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:01.433] INFO: dacScan step from 57 .. 57
[15:40:01.433] INFO: dacScan split into 1 runs with ntrig = 25
[15:40:01.433] INFO: run 1 of 1
[15:40:02.283] INFO: Expecting 104000 events.
[15:40:17.885] INFO: 104000 events read in total (15075ms).
[15:40:17.898] INFO: Test took 16464ms.
[15:40:17.997] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:18.329] INFO: dacScan step from 58 .. 58
[15:40:18.329] INFO: dacScan split into 1 runs with ntrig = 25
[15:40:18.329] INFO: run 1 of 1
[15:40:19.177] INFO: Expecting 104000 events.
[15:40:35.031] INFO: 104000 events read in total (15327ms).
[15:40:35.041] INFO: Test took 16711ms.
[15:40:35.126] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:35.465] INFO: dacScan step from 59 .. 59
[15:40:35.465] INFO: dacScan split into 1 runs with ntrig = 25
[15:40:35.465] INFO: run 1 of 1
[15:40:36.310] INFO: Expecting 104000 events.
[15:40:51.898] INFO: 104000 events read in total (15060ms).
[15:40:51.919] INFO: Test took 16453ms.
[15:40:52.010] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:52.330] INFO: dacScan step from 60 .. 60
[15:40:52.330] INFO: dacScan split into 1 runs with ntrig = 25
[15:40:52.330] INFO: run 1 of 1
[15:40:53.177] INFO: Expecting 104000 events.
[15:41:09.249] INFO: 104000 events read in total (15545ms).
[15:41:09.259] INFO: Test took 16928ms.
[15:41:09.340] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:09.656] INFO: dacScan step from 61 .. 61
[15:41:09.656] INFO: dacScan split into 1 runs with ntrig = 25
[15:41:09.656] INFO: run 1 of 1
[15:41:10.492] INFO: Expecting 104000 events.
[15:41:26.558] INFO: 104000 events read in total (15539ms).
[15:41:26.568] INFO: Test took 16911ms.
[15:41:26.659] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:26.978] INFO: dacScan step from 62 .. 62
[15:41:26.978] INFO: dacScan split into 1 runs with ntrig = 25
[15:41:26.978] INFO: run 1 of 1
[15:41:27.803] INFO: Expecting 104000 events.
[15:41:44.042] INFO: 104000 events read in total (15712ms).
[15:41:44.056] INFO: Test took 17077ms.
[15:41:44.150] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:44.474] INFO: dacScan step from 63 .. 63
[15:41:44.474] INFO: dacScan split into 1 runs with ntrig = 25
[15:41:44.474] INFO: run 1 of 1
[15:41:45.325] INFO: Expecting 104000 events.
[15:42:02.172] INFO: 104000 events read in total (16320ms).
[15:42:02.182] INFO: Test took 17707ms.
[15:42:02.269] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:02.600] INFO: dacScan step from 64 .. 64
[15:42:02.600] INFO: dacScan split into 1 runs with ntrig = 25
[15:42:02.600] INFO: run 1 of 1
[15:42:03.448] INFO: Expecting 104000 events.
[15:42:19.595] INFO: 104000 events read in total (15619ms).
[15:42:19.606] INFO: Test took 17005ms.
[15:42:19.701] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:20.050] INFO: dacScan step from 65 .. 65
[15:42:20.050] INFO: dacScan split into 1 runs with ntrig = 25
[15:42:20.050] INFO: run 1 of 1
[15:42:20.891] INFO: Expecting 104000 events.
[15:42:37.539] INFO: 104000 events read in total (16121ms).
[15:42:37.553] INFO: Test took 17502ms.
[15:42:37.650] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:37.980] INFO: dacScan step from 66 .. 66
[15:42:37.980] INFO: dacScan split into 1 runs with ntrig = 25
[15:42:37.980] INFO: run 1 of 1
[15:42:38.821] INFO: Expecting 104000 events.
[15:42:54.452] INFO: 104000 events read in total (15104ms).
[15:42:54.463] INFO: Test took 16482ms.
[15:42:54.564] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:54.889] INFO: dacScan step from 67 .. 67
[15:42:54.889] INFO: dacScan split into 1 runs with ntrig = 25
[15:42:54.889] INFO: run 1 of 1
[15:42:55.734] INFO: Expecting 104000 events.
[15:43:12.081] INFO: 104000 events read in total (15820ms).
[15:43:12.092] INFO: Test took 17202ms.
[15:43:12.179] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:12.507] INFO: dacScan step from 68 .. 68
[15:43:12.507] INFO: dacScan split into 1 runs with ntrig = 25
[15:43:12.507] INFO: run 1 of 1
[15:43:13.350] INFO: Expecting 104000 events.
[15:43:29.447] INFO: 104000 events read in total (15570ms).
[15:43:29.458] INFO: Test took 16950ms.
[15:43:29.551] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:29.881] INFO: dacScan step from 69 .. 69
[15:43:29.881] INFO: dacScan split into 1 runs with ntrig = 25
[15:43:29.881] INFO: run 1 of 1
[15:43:30.737] INFO: Expecting 104000 events.
[15:43:46.606] INFO: 104000 events read in total (15342ms).
[15:43:46.617] INFO: Test took 16734ms.
[15:43:46.703] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:47.040] INFO: dacScan step from 70 .. 70
[15:43:47.040] INFO: dacScan split into 1 runs with ntrig = 25
[15:43:47.040] INFO: run 1 of 1
[15:43:47.888] INFO: Expecting 104000 events.
[15:44:03.792] INFO: 104000 events read in total (15378ms).
[15:44:03.803] INFO: Test took 16762ms.
[15:44:03.888] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:04.220] INFO: dacScan step from 71 .. 71
[15:44:04.220] INFO: dacScan split into 1 runs with ntrig = 25
[15:44:04.220] INFO: run 1 of 1
[15:44:05.068] INFO: Expecting 104000 events.
[15:44:20.395] INFO: 104000 events read in total (14800ms).
[15:44:20.405] INFO: Test took 16184ms.
[15:44:20.488] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:20.821] INFO: dacScan step from 72 .. 72
[15:44:20.821] INFO: dacScan split into 1 runs with ntrig = 25
[15:44:20.821] INFO: run 1 of 1
[15:44:21.663] INFO: Expecting 104000 events.
[15:44:37.243] INFO: 104000 events read in total (15053ms).
[15:44:37.254] INFO: Test took 16432ms.
[15:44:37.340] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:37.673] INFO: dacScan step from 73 .. 73
[15:44:37.673] INFO: dacScan split into 1 runs with ntrig = 25
[15:44:37.673] INFO: run 1 of 1
[15:44:38.520] INFO: Expecting 104000 events.
[15:44:54.374] INFO: 104000 events read in total (15327ms).
[15:44:54.384] INFO: Test took 16709ms.
[15:44:54.471] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:54.804] INFO: dacScan step from 74 .. 74
[15:44:54.804] INFO: dacScan split into 1 runs with ntrig = 25
[15:44:54.804] INFO: run 1 of 1
[15:44:55.644] INFO: Expecting 104000 events.
[15:45:11.105] INFO: 104000 events read in total (14934ms).
[15:45:11.115] INFO: Test took 16310ms.
[15:45:11.199] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:11.533] INFO: dacScan step from 75 .. 75
[15:45:11.533] INFO: dacScan split into 1 runs with ntrig = 25
[15:45:11.533] INFO: run 1 of 1
[15:45:12.387] INFO: Expecting 104000 events.
[15:45:28.247] INFO: 104000 events read in total (15333ms).
[15:45:28.262] INFO: Test took 16728ms.
[15:45:28.354] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:28.696] INFO: dacScan step from 76 .. 76
[15:45:28.696] INFO: dacScan split into 1 runs with ntrig = 25
[15:45:28.696] INFO: run 1 of 1
[15:45:29.540] INFO: Expecting 104000 events.
[15:45:45.132] INFO: 104000 events read in total (15066ms).
[15:45:45.143] INFO: Test took 16446ms.
[15:45:45.228] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:45.563] INFO: dacScan step from 77 .. 77
[15:45:45.563] INFO: dacScan split into 1 runs with ntrig = 25
[15:45:45.563] INFO: run 1 of 1
[15:45:46.410] INFO: Expecting 104000 events.
[15:46:02.224] INFO: 104000 events read in total (15287ms).
[15:46:02.236] INFO: Test took 16672ms.
[15:46:02.325] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:02.658] INFO: dacScan step from 78 .. 78
[15:46:02.658] INFO: dacScan split into 1 runs with ntrig = 25
[15:46:02.658] INFO: run 1 of 1
[15:46:03.505] INFO: Expecting 104000 events.
[15:46:19.428] INFO: 104000 events read in total (15395ms).
[15:46:19.438] INFO: Test took 16779ms.
[15:46:19.518] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:19.834] INFO: dacScan step from 79 .. 79
[15:46:19.834] INFO: dacScan split into 1 runs with ntrig = 25
[15:46:19.834] INFO: run 1 of 1
[15:46:20.660] INFO: Expecting 104000 events.
[15:46:37.021] INFO: 104000 events read in total (15834ms).
[15:46:37.031] INFO: Test took 17196ms.
[15:46:37.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:37.427] INFO: dacScan step from 80 .. 80
[15:46:37.427] INFO: dacScan split into 1 runs with ntrig = 25
[15:46:37.427] INFO: run 1 of 1
[15:46:38.259] INFO: Expecting 104000 events.
[15:46:54.950] INFO: 104000 events read in total (16164ms).
[15:46:54.960] INFO: Test took 17531ms.
[15:46:55.044] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:55.372] INFO: dacScan step from 81 .. 81
[15:46:55.372] INFO: dacScan split into 1 runs with ntrig = 25
[15:46:55.372] INFO: run 1 of 1
[15:46:56.201] INFO: Expecting 104000 events.
[15:47:12.765] INFO: 104000 events read in total (16037ms).
[15:47:12.775] INFO: Test took 17402ms.
[15:47:12.864] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:13.187] INFO: dacScan step from 82 .. 82
[15:47:13.187] INFO: dacScan split into 1 runs with ntrig = 25
[15:47:13.187] INFO: run 1 of 1
[15:47:14.020] INFO: Expecting 104000 events.
[15:47:30.911] INFO: 104000 events read in total (16363ms).
[15:47:30.931] INFO: Test took 17743ms.
[15:47:31.012] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:31.340] INFO: dacScan step from 83 .. 83
[15:47:31.340] INFO: dacScan split into 1 runs with ntrig = 25
[15:47:31.340] INFO: run 1 of 1
[15:47:32.176] INFO: Expecting 104000 events.
[15:47:48.810] INFO: 104000 events read in total (16107ms).
[15:47:48.820] INFO: Test took 17478ms.
[15:47:48.906] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:49.232] INFO: dacScan step from 84 .. 84
[15:47:49.232] INFO: dacScan split into 1 runs with ntrig = 25
[15:47:49.232] INFO: run 1 of 1
[15:47:50.083] INFO: Expecting 104000 events.
[15:48:06.771] INFO: 104000 events read in total (16160ms).
[15:48:06.782] INFO: Test took 17549ms.
[15:48:06.868] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:07.204] INFO: dacScan step from 85 .. 85
[15:48:07.204] INFO: dacScan split into 1 runs with ntrig = 25
[15:48:07.204] INFO: run 1 of 1
[15:48:08.057] INFO: Expecting 104000 events.
[15:48:23.894] INFO: 104000 events read in total (15310ms).
[15:48:23.904] INFO: Test took 16699ms.
[15:48:24.001] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:24.334] INFO: dacScan step from 86 .. 86
[15:48:24.334] INFO: dacScan split into 1 runs with ntrig = 25
[15:48:24.334] INFO: run 1 of 1
[15:48:25.180] INFO: Expecting 104000 events.
[15:48:41.188] INFO: 104000 events read in total (15481ms).
[15:48:41.203] INFO: Test took 16868ms.
[15:48:41.288] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:41.619] INFO: dacScan step from 87 .. 87
[15:48:41.619] INFO: dacScan split into 1 runs with ntrig = 25
[15:48:41.619] INFO: run 1 of 1
[15:48:42.475] INFO: Expecting 104000 events.
[15:48:58.470] INFO: 104000 events read in total (15468ms).
[15:48:58.481] INFO: Test took 16860ms.
[15:48:58.564] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:58.899] INFO: dacScan step from 88 .. 88
[15:48:58.899] INFO: dacScan split into 1 runs with ntrig = 25
[15:48:58.899] INFO: run 1 of 1
[15:48:59.744] INFO: Expecting 104000 events.
[15:49:15.360] INFO: 104000 events read in total (15089ms).
[15:49:15.371] INFO: Test took 16471ms.
[15:49:15.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:15.789] INFO: dacScan step from 89 .. 89
[15:49:15.789] INFO: dacScan split into 1 runs with ntrig = 25
[15:49:15.789] INFO: run 1 of 1
[15:49:16.635] INFO: Expecting 104000 events.
[15:49:32.225] INFO: 104000 events read in total (15063ms).
[15:49:32.235] INFO: Test took 16445ms.
[15:49:32.321] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:32.659] INFO: dacScan step from 90 .. 90
[15:49:32.659] INFO: dacScan split into 1 runs with ntrig = 25
[15:49:32.659] INFO: run 1 of 1
[15:49:33.504] INFO: Expecting 104000 events.
[15:49:49.195] INFO: 104000 events read in total (15164ms).
[15:49:49.206] INFO: Test took 16545ms.
[15:49:49.295] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:49.627] INFO: dacScan step from 91 .. 91
[15:49:49.627] INFO: dacScan split into 1 runs with ntrig = 25
[15:49:49.627] INFO: run 1 of 1
[15:49:50.474] INFO: Expecting 104000 events.
[15:50:06.544] INFO: 104000 events read in total (15543ms).
[15:50:06.554] INFO: Test took 16926ms.
[15:50:06.639] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:06.969] INFO: dacScan step from 92 .. 92
[15:50:06.969] INFO: dacScan split into 1 runs with ntrig = 25
[15:50:06.969] INFO: run 1 of 1
[15:50:07.817] INFO: Expecting 104000 events.
[15:50:23.133] INFO: 104000 events read in total (14789ms).
[15:50:23.143] INFO: Test took 16172ms.
[15:50:23.237] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:23.565] INFO: dacScan step from 93 .. 93
[15:50:23.565] INFO: dacScan split into 1 runs with ntrig = 25
[15:50:23.565] INFO: run 1 of 1
[15:50:24.415] INFO: Expecting 104000 events.
[15:50:40.077] INFO: 104000 events read in total (15135ms).
[15:50:40.088] INFO: Test took 16521ms.
[15:50:40.173] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:40.502] INFO: dacScan step from 94 .. 94
[15:50:40.502] INFO: dacScan split into 1 runs with ntrig = 25
[15:50:40.502] INFO: run 1 of 1
[15:50:41.354] INFO: Expecting 104000 events.
[15:50:56.968] INFO: 104000 events read in total (15086ms).
[15:50:56.984] INFO: Test took 16480ms.
[15:50:57.078] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:57.408] INFO: dacScan step from 95 .. 95
[15:50:57.408] INFO: dacScan split into 1 runs with ntrig = 25
[15:50:57.408] INFO: run 1 of 1
[15:50:58.255] INFO: Expecting 104000 events.
[15:51:13.650] INFO: 104000 events read in total (14868ms).
[15:51:13.661] INFO: Test took 16251ms.
[15:51:13.746] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:14.080] INFO: dacScan step from 96 .. 96
[15:51:14.080] INFO: dacScan split into 1 runs with ntrig = 25
[15:51:14.080] INFO: run 1 of 1
[15:51:14.934] INFO: Expecting 104000 events.
[15:51:30.537] INFO: 104000 events read in total (15076ms).
[15:51:30.548] INFO: Test took 16466ms.
[15:51:30.633] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:30.963] INFO: dacScan step from 97 .. 97
[15:51:30.963] INFO: dacScan split into 1 runs with ntrig = 25
[15:51:30.963] INFO: run 1 of 1
[15:51:31.812] INFO: Expecting 104000 events.
[15:51:47.992] INFO: 104000 events read in total (15653ms).
[15:51:48.003] INFO: Test took 17038ms.
[15:51:48.089] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:48.418] INFO: dacScan step from 98 .. 98
[15:51:48.418] INFO: dacScan split into 1 runs with ntrig = 25
[15:51:48.418] INFO: run 1 of 1
[15:51:49.272] INFO: Expecting 104000 events.
[15:52:05.053] INFO: 104000 events read in total (15254ms).
[15:52:05.063] INFO: Test took 16643ms.
[15:52:05.143] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:05.454] INFO: dacScan step from 99 .. 99
[15:52:05.454] INFO: dacScan split into 1 runs with ntrig = 25
[15:52:05.454] INFO: run 1 of 1
[15:52:06.289] INFO: Expecting 104000 events.
[15:52:22.509] INFO: 104000 events read in total (15693ms).
[15:52:22.520] INFO: Test took 17064ms.
[15:52:22.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:22.922] INFO: dacScan step from 100 .. 100
[15:52:22.922] INFO: dacScan split into 1 runs with ntrig = 25
[15:52:22.922] INFO: run 1 of 1
[15:52:23.749] INFO: Expecting 104000 events.
[15:52:40.077] INFO: 104000 events read in total (15801ms).
[15:52:40.088] INFO: Test took 17165ms.
[15:52:40.181] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:40.499] INFO: dumping ASCII scurve output file: XSCurveData
[15:52:42.539] INFO: dumping ASCII scurve output file: XSCurveData
[15:52:44.641] INFO: dumping ASCII scurve output file: XSCurveData
[15:52:46.719] INFO: dumping ASCII scurve output file: XSCurveData
[15:52:48.913] INFO: dumping ASCII scurve output file: XSCurveData
[15:52:51.092] INFO: dumping ASCII scurve output file: XSCurveData
[15:52:53.262] INFO: dumping ASCII scurve output file: XSCurveData
[15:52:55.380] INFO: dumping ASCII scurve output file: XSCurveData
[15:52:57.441] INFO: dumping ASCII scurve output file: XSCurveData
[15:52:59.580] INFO: dumping ASCII scurve output file: XSCurveData
[15:53:01.795] INFO: dumping ASCII scurve output file: XSCurveData
[15:53:04.065] INFO: dumping ASCII scurve output file: XSCurveData
[15:53:06.290] INFO: dumping ASCII scurve output file: XSCurveData
[15:53:08.488] INFO: dumping ASCII scurve output file: XSCurveData
[15:53:10.553] INFO: dumping ASCII scurve output file: XSCurveData
[15:53:12.584] INFO: dumping ASCII scurve output file: XSCurveData
[15:53:14.632] INFO: PixTest:: pg_setup set to default.
[15:53:14.641] INFO: vcal mean: 40.38 40.23 39.43 42.86 42.02 42.58 41.96 42.07 42.44 42.17 43.35 43.64 42.88 41.12 41.23 41.52
[15:53:14.641] INFO: vcal RMS: 1.77 1.47 1.31 1.68 1.72 1.64 1.66 1.53 1.64 1.58 1.61 1.77 1.73 1.61 1.55 1.43
[15:53:14.641] INFO: number of X-ray hits detected: 156344 154660 131404 203486 201136 217265 195517 190792 203203 207757 230574 226209 222274 149272 170268 168310
[15:53:14.641] INFO: number of triggers sent (total per ROC): 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000
[15:53:14.641] INFO: X-ray hit rate [MHz/cm2]: 91.7 90.7 77.0 119.3 117.9 127.4 114.6 111.8 119.1 121.8 135.2 132.6 130.3 87.5 99.8 98.7
[15:53:14.641] INFO: PixTestHighRate::doXNoiseMaps() done
[15:53:14.772] INFO: enter test to run
[15:53:14.772] INFO: test: exit no parameter change
[15:53:14.895] QUIET: Connection to board 172 closed.
[15:53:14.911] INFO: pXar: this is the end, my friend
pxar.log
[15:54:41.348] INFO: *** Welcome to pxar ***
[15:54:41.348] INFO: *** Today: 2015/09/04
[15:54:41.348] INFO: readRocDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C15.dat
[15:54:41.349] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0b.dat
[15:54:41.349] INFO: readMaskFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/defaultMaskFile.dat
[15:54:41.349] INFO: readTrimFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C15.dat
[15:54:41.424] INFO: clk: 4
[15:54:41.424] INFO: ctr: 4
[15:54:41.424] INFO: sda: 19
[15:54:41.424] INFO: tin: 9
[15:54:41.424] INFO: level: 15
[15:54:41.424] INFO: triggerdelay: 0
[15:54:41.424] QUIET: Instanciating API for pxar 2.5
[15:54:41.424] INFO: Log level: INFO
[15:54:41.436] INFO: Found DTB DTB_WZ4QKL
[15:54:41.449] QUIET: Connection to board DTB_WZ4QKL opened.
[15:54:41.453] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 172
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WZ4QKL
MAC address: 40D8551180AC
Hostname: pixelDTB172
Comment:
------------------------------------------------------
[15:54:41.456] INFO: RPC call hashes of host and DTB match: 397073690
[15:54:43.280] INFO: DUT info:
[15:54:43.280] INFO: The DUT currently contains the following objects:
[15:54:43.281] INFO: 2 TBM Cores tbm08c (2 ON)
[15:54:43.281] INFO: TBM Core alpha (0): 7 registers set
[15:54:43.281] INFO: TBM Core beta (1): 7 registers set
[15:54:43.281] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:54:43.281] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.281] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.281] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.281] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.281] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.281] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.281] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.281] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.281] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.281] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.281] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.281] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.282] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.282] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.282] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.282] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:43.683] INFO: enter 'restricted' command line mode
[15:54:43.683] INFO: enter test to run
[15:54:43.684] INFO: test: highrate no parameter change
[15:54:43.684] INFO: running: highrate
[15:54:43.693] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[15:54:43.917] INFO: ----------------------------------------------------------------------
[15:54:43.917] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:54:43.917] INFO: ----------------------------------------------------------------------
[15:54:44.064] INFO: Expecting 768 events.
[15:54:45.345] INFO: 768 events read in total (498ms).
[15:54:45.534] INFO: Test took 1610ms.
[15:54:46.402] INFO: Expecting 41600 events.
[15:54:51.417] INFO: 41600 events read in total (4488ms).
[15:54:51.421] INFO: Test took 5880ms.
[15:54:51.521] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:52.423] INFO: Expecting 41600 events.
[15:54:57.383] INFO: 41600 events read in total (4433ms).
[15:54:57.387] INFO: Test took 5814ms.
[15:54:57.484] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:58.380] INFO: Expecting 41600 events.
[15:55:03.598] INFO: 41600 events read in total (4691ms).
[15:55:03.602] INFO: Test took 6061ms.
[15:55:03.702] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:04.601] INFO: Expecting 41600 events.
[15:55:09.940] INFO: 41600 events read in total (4812ms).
[15:55:09.947] INFO: Test took 6185ms.
[15:55:10.046] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:10.943] INFO: Expecting 41600 events.
[15:55:16.127] INFO: 41600 events read in total (4657ms).
[15:55:16.130] INFO: Test took 6028ms.
[15:55:16.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:17.149] INFO: Expecting 41600 events.
[15:55:22.429] INFO: 41600 events read in total (4753ms).
[15:55:22.432] INFO: Test took 6144ms.
[15:55:22.530] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:23.431] INFO: Expecting 41600 events.
[15:55:28.886] INFO: 41600 events read in total (4928ms).
[15:55:28.889] INFO: Test took 6299ms.
[15:55:28.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:29.893] INFO: Expecting 41600 events.
[15:55:35.205] INFO: 41600 events read in total (4785ms).
[15:55:35.208] INFO: Test took 6157ms.
[15:55:35.309] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:36.227] INFO: Expecting 41600 events.
[15:55:41.241] INFO: 41600 events read in total (4487ms).
[15:55:41.245] INFO: Test took 5879ms.
[15:55:41.344] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:42.244] INFO: Expecting 41600 events.
[15:55:47.263] INFO: 41600 events read in total (4492ms).
[15:55:47.269] INFO: Test took 5868ms.
[15:55:47.367] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:48.277] INFO: Expecting 41600 events.
[15:55:53.347] INFO: 41600 events read in total (4543ms).
[15:55:53.350] INFO: Test took 5925ms.
[15:55:53.449] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:54.357] INFO: Expecting 41600 events.
[15:55:59.445] INFO: 41600 events read in total (4561ms).
[15:55:59.448] INFO: Test took 5937ms.
[15:55:59.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:00.459] INFO: Expecting 41600 events.
[15:56:05.649] INFO: 41600 events read in total (4662ms).
[15:56:05.652] INFO: Test took 6045ms.
[15:56:05.753] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:06.655] INFO: Expecting 41600 events.
[15:56:11.866] INFO: 41600 events read in total (4684ms).
[15:56:11.869] INFO: Test took 6057ms.
[15:56:11.969] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:12.884] INFO: Expecting 41600 events.
[15:56:17.972] INFO: 41600 events read in total (4561ms).
[15:56:17.976] INFO: Test took 5951ms.
[15:56:18.074] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:18.981] INFO: Expecting 41600 events.
[15:56:24.055] INFO: 41600 events read in total (4547ms).
[15:56:24.059] INFO: Test took 5927ms.
[15:56:24.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:25.085] INFO: Expecting 41600 events.
[15:56:30.145] INFO: 41600 events read in total (4532ms).
[15:56:30.149] INFO: Test took 5929ms.
[15:56:30.246] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:31.149] INFO: Expecting 41600 events.
[15:56:36.304] INFO: 41600 events read in total (4628ms).
[15:56:36.307] INFO: Test took 6003ms.
[15:56:36.408] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:37.311] INFO: Expecting 41600 events.
[15:56:42.264] INFO: 41600 events read in total (4426ms).
[15:56:42.268] INFO: Test took 5800ms.
[15:56:42.363] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:43.273] INFO: Expecting 41600 events.
[15:56:47.818] INFO: 41600 events read in total (4018ms).
[15:56:47.822] INFO: Test took 5397ms.
[15:56:47.917] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:48.350] INFO: enter test to run
[15:56:48.350] INFO: test: savedacs no parameter change
[15:56:48.350] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C0.dat
[15:56:48.350] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C1.dat
[15:56:48.350] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C2.dat
[15:56:48.350] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C3.dat
[15:56:48.351] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C4.dat
[15:56:48.351] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C5.dat
[15:56:48.351] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C6.dat
[15:56:48.351] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C7.dat
[15:56:48.351] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C8.dat
[15:56:48.351] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C9.dat
[15:56:48.351] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C10.dat
[15:56:48.351] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C11.dat
[15:56:48.351] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C12.dat
[15:56:48.351] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C13.dat
[15:56:48.351] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C14.dat
[15:56:48.352] INFO: write dac parameters into /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C15.dat
[15:56:48.352] INFO: enter test to run
[15:56:48.352] INFO: test: exit no parameter change
[15:56:48.452] QUIET: Connection to board 172 closed.
[15:56:48.468] INFO: pXar: this is the end, my friend
pxar.log
[15:56:52.943] INFO: *** Welcome to pxar ***
[15:56:52.943] INFO: *** Today: 2015/09/04
[15:56:52.943] INFO: readRocDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C15.dat
[15:56:52.946] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0b.dat
[15:56:52.946] INFO: readMaskFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/defaultMaskFile.dat
[15:56:52.946] INFO: readTrimFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C15.dat
[15:56:53.022] INFO: clk: 4
[15:56:53.022] INFO: ctr: 4
[15:56:53.022] INFO: sda: 19
[15:56:53.022] INFO: tin: 9
[15:56:53.022] INFO: level: 15
[15:56:53.022] INFO: triggerdelay: 0
[15:56:53.022] QUIET: Instanciating API for pxar 2.5
[15:56:53.022] INFO: Log level: INFO
[15:56:53.029] INFO: Found DTB DTB_WZ4QKL
[15:56:53.041] QUIET: Connection to board DTB_WZ4QKL opened.
[15:56:53.045] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 172
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WZ4QKL
MAC address: 40D8551180AC
Hostname: pixelDTB172
Comment:
------------------------------------------------------
[15:56:53.049] INFO: RPC call hashes of host and DTB match: 397073690
[15:56:54.862] INFO: DUT info:
[15:56:54.862] INFO: The DUT currently contains the following objects:
[15:56:54.862] INFO: 2 TBM Cores tbm08c (2 ON)
[15:56:54.862] INFO: TBM Core alpha (0): 7 registers set
[15:56:54.862] INFO: TBM Core beta (1): 7 registers set
[15:56:54.862] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:56:54.862] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.862] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.862] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.862] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.863] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.863] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.863] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.863] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.863] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.863] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.863] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.863] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.863] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.863] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.863] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:54.863] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:55.265] INFO: enter 'restricted' command line mode
[15:56:55.265] INFO: enter test to run
[15:56:55.265] INFO: test: highrate no parameter change
[15:56:55.265] INFO: running: highrate
[15:56:55.270] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[15:56:55.491] INFO: ----------------------------------------------------------------------
[15:56:55.491] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:56:55.491] INFO: ----------------------------------------------------------------------
[15:56:56.366] INFO: Expecting 208000 events.
[15:57:19.818] INFO: 208000 events read in total (22922ms).
[15:57:19.836] INFO: Test took 24333ms.
[15:57:20.291] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:20.825] INFO: number of dead pixels (per ROC): 2 0 0 1 2 0 0 0 0 0 0 1 1 0 0 0
[15:57:20.825] INFO: number of red-efficiency pixels: 290 234 244 373 413 432 387 364 457 471 477 485 434 1510 357 283
[15:57:20.825] INFO: number of X-ray hits detected: 156987 156228 132517 203313 200740 217387 194281 189490 203404 207123 230631 226636 221278 149987 171211 168814
[15:57:20.825] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:57:20.825] INFO: number of Vcal hits detected: 207597 207749 207739 207550 207470 207541 207575 207623 207521 207472 207493 207434 207479 206008 207624 207705
[15:57:20.825] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.1 99.8 99.9
[15:57:20.825] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.9 99.8 99.7 99.8 99.8 99.8 99.8 99.7 99.8 99.7 99.7 99.0 99.8 99.9
[15:57:20.825] INFO: X-ray hit rate [MHz/cm2]: 46.0 45.8 38.8 59.6 58.8 63.7 56.9 55.5 59.6 60.7 67.6 66.4 64.9 44.0 50.2 49.5
[15:57:20.825] INFO: PixTestHighRate::doXPixelAlive() done
[15:57:20.870] INFO: PixTest:: pg_setup set to default.
[15:57:20.923] INFO: enter test to run
[15:57:20.923] INFO: test: exit no parameter change
[15:57:20.994] QUIET: Connection to board 172 closed.
[15:57:21.010] INFO: pXar: this is the end, my friend
pxar.log
[15:57:27.439] INFO: *** Welcome to pxar ***
[15:57:27.439] INFO: *** Today: 2015/09/04
[15:57:27.439] INFO: readRocDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C15.dat
[15:57:27.441] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0b.dat
[15:57:27.442] INFO: readMaskFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/defaultMaskFile.dat
[15:57:27.442] INFO: readTrimFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C15.dat
[15:57:27.526] INFO: clk: 4
[15:57:27.526] INFO: ctr: 4
[15:57:27.526] INFO: sda: 19
[15:57:27.526] INFO: tin: 9
[15:57:27.526] INFO: level: 15
[15:57:27.526] INFO: triggerdelay: 0
[15:57:27.526] QUIET: Instanciating API for pxar 2.5
[15:57:27.526] INFO: Log level: INFO
[15:57:27.533] INFO: Found DTB DTB_WZ4QKL
[15:57:27.545] QUIET: Connection to board DTB_WZ4QKL opened.
[15:57:27.549] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 172
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WZ4QKL
MAC address: 40D8551180AC
Hostname: pixelDTB172
Comment:
------------------------------------------------------
[15:57:27.552] INFO: RPC call hashes of host and DTB match: 397073690
[15:57:29.378] INFO: DUT info:
[15:57:29.378] INFO: The DUT currently contains the following objects:
[15:57:29.378] INFO: 2 TBM Cores tbm08c (2 ON)
[15:57:29.378] INFO: TBM Core alpha (0): 7 registers set
[15:57:29.378] INFO: TBM Core beta (1): 7 registers set
[15:57:29.378] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:57:29.378] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.378] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.378] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.378] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.378] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.378] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.378] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.378] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.379] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.379] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.379] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.379] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.379] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.379] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.379] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.379] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:29.781] INFO: enter 'restricted' command line mode
[15:57:29.781] INFO: enter test to run
[15:57:29.781] INFO: test: highrate no parameter change
[15:57:29.781] INFO: running: highrate
[15:57:29.790] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[15:57:30.023] INFO: ----------------------------------------------------------------------
[15:57:30.023] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:57:30.023] INFO: ----------------------------------------------------------------------
[15:57:30.885] INFO: Expecting 208000 events.
[15:58:03.528] INFO: 208000 events read in total (32112ms).
[15:58:03.555] INFO: Test took 33526ms.
[15:58:04.417] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:05.143] INFO: number of dead pixels (per ROC): 2 0 0 1 2 0 0 0 0 0 0 1 1 0 0 0
[15:58:05.143] INFO: number of red-efficiency pixels: 1061 723 739 1416 1351 1610 1292 1513 1743 1715 1773 1754 1676 2047 1296 975
[15:58:05.143] INFO: number of X-ray hits detected: 311874 308347 261513 401843 399487 431070 387963 377776 402161 411076 456353 448391 438559 296962 338228 334112
[15:58:05.143] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:58:05.143] INFO: number of Vcal hits detected: 206660 207185 207004 206049 206019 205716 206289 206070 205589 205377 205401 205410 205561 204801 206382 206843
[15:58:05.143] INFO: Vcal hit fiducial efficiency (%): 99.4 99.6 99.6 99.2 99.2 99.1 99.3 99.1 98.9 98.9 98.9 98.9 99.0 98.5 99.3 99.5
[15:58:05.143] INFO: Vcal hit overall efficiency (%): 99.4 99.6 99.5 99.1 99.0 98.9 99.2 99.1 98.8 98.7 98.8 98.8 98.8 98.5 99.2 99.4
[15:58:05.143] INFO: X-ray hit rate [MHz/cm2]: 91.4 90.4 76.7 117.8 117.1 126.3 113.7 110.7 117.9 120.5 133.8 131.4 128.5 87.0 99.1 97.9
[15:58:05.143] INFO: PixTestHighRate::doXPixelAlive() done
[15:58:05.190] INFO: PixTest:: pg_setup set to default.
[15:58:05.239] INFO: enter test to run
[15:58:05.239] INFO: test: exit no parameter change
[15:58:05.314] QUIET: Connection to board 172 closed.
[15:58:05.320] INFO: pXar: this is the end, my friend
pxar.log
[15:58:12.918] INFO: *** Welcome to pxar ***
[15:58:12.918] INFO: *** Today: 2015/09/04
[15:58:12.918] INFO: readRocDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C15.dat
[15:58:12.921] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0b.dat
[15:58:12.921] INFO: readMaskFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/defaultMaskFile.dat
[15:58:12.921] INFO: readTrimFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C15.dat
[15:58:13.001] INFO: clk: 4
[15:58:13.001] INFO: ctr: 4
[15:58:13.001] INFO: sda: 19
[15:58:13.001] INFO: tin: 9
[15:58:13.001] INFO: level: 15
[15:58:13.001] INFO: triggerdelay: 0
[15:58:13.001] QUIET: Instanciating API for pxar 2.5
[15:58:13.001] INFO: Log level: INFO
[15:58:13.010] INFO: Found DTB DTB_WZ4QKL
[15:58:13.022] QUIET: Connection to board DTB_WZ4QKL opened.
[15:58:13.026] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 172
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WZ4QKL
MAC address: 40D8551180AC
Hostname: pixelDTB172
Comment:
------------------------------------------------------
[15:58:13.029] INFO: RPC call hashes of host and DTB match: 397073690
[15:58:14.837] INFO: DUT info:
[15:58:14.837] INFO: The DUT currently contains the following objects:
[15:58:14.837] INFO: 2 TBM Cores tbm08c (2 ON)
[15:58:14.837] INFO: TBM Core alpha (0): 7 registers set
[15:58:14.837] INFO: TBM Core beta (1): 7 registers set
[15:58:14.837] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:58:14.837] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.837] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.838] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.838] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.838] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.838] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.838] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.838] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.838] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.838] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.838] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.838] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.838] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.838] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.838] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:14.838] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:15.240] INFO: enter 'restricted' command line mode
[15:58:15.240] INFO: enter test to run
[15:58:15.240] INFO: test: highrate no parameter change
[15:58:15.240] INFO: running: highrate
[15:58:15.249] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[15:58:15.473] INFO: ----------------------------------------------------------------------
[15:58:15.473] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:58:15.473] INFO: ----------------------------------------------------------------------
[15:58:16.332] INFO: Expecting 208000 events.
[15:58:58.154] INFO: 208000 events read in total (41292ms).
[15:58:58.192] INFO: Test took 42712ms.
[15:58:59.526] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:00.469] INFO: number of dead pixels (per ROC): 2 0 0 1 1 0 0 0 0 0 0 0 0 0 0 0
[15:59:00.469] INFO: number of red-efficiency pixels: 2322 1706 1448 2763 2834 3128 2729 3078 3317 3216 3328 3243 3258 2689 2700 2052
[15:59:00.469] INFO: number of X-ray hits detected: 463795 459931 390485 602471 595298 645778 580302 566387 601981 617681 682244 668062 653708 439131 502019 497265
[15:59:00.469] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:59:00.469] INFO: number of Vcal hits detected: 204133 205639 205029 202257 201870 200821 202521 201815 200253 199755 199605 199964 200148 201389 203208 204766
[15:59:00.469] INFO: Vcal hit fiducial efficiency (%): 98.3 99.0 98.8 97.6 97.4 97.0 97.7 97.2 96.6 96.5 96.4 96.6 96.6 97.2 97.9 98.6
[15:59:00.469] INFO: Vcal hit overall efficiency (%): 98.1 98.9 98.6 97.2 97.1 96.5 97.4 97.0 96.3 96.0 96.0 96.1 96.2 96.8 97.7 98.4
[15:59:00.469] INFO: X-ray hit rate [MHz/cm2]: 135.9 134.8 114.5 176.6 174.5 189.3 170.1 166.0 176.4 181.0 200.0 195.8 191.6 128.7 147.1 145.8
[15:59:00.470] INFO: PixTestHighRate::doXPixelAlive() done
[15:59:00.516] INFO: PixTest:: pg_setup set to default.
[15:59:00.568] INFO: enter test to run
[15:59:00.568] INFO: test: exit no parameter change
[15:59:00.672] QUIET: Connection to board 172 closed.
[15:59:00.688] INFO: pXar: this is the end, my friend
pxar.log
[15:59:10.358] INFO: *** Welcome to pxar ***
[15:59:10.359] INFO: *** Today: 2015/09/04
[15:59:10.359] INFO: readRocDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C15.dat
[15:59:10.361] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0b.dat
[15:59:10.361] INFO: readMaskFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/defaultMaskFile.dat
[15:59:10.361] INFO: readTrimFile: /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C15.dat
[15:59:10.445] INFO: clk: 4
[15:59:10.445] INFO: ctr: 4
[15:59:10.445] INFO: sda: 19
[15:59:10.445] INFO: tin: 9
[15:59:10.445] INFO: level: 15
[15:59:10.445] INFO: triggerdelay: 0
[15:59:10.445] QUIET: Instanciating API for pxar 2.5
[15:59:10.445] INFO: Log level: INFO
[15:59:10.452] INFO: Found DTB DTB_WZ4QKL
[15:59:10.464] QUIET: Connection to board DTB_WZ4QKL opened.
[15:59:10.468] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 172
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WZ4QKL
MAC address: 40D8551180AC
Hostname: pixelDTB172
Comment:
------------------------------------------------------
[15:59:10.471] INFO: RPC call hashes of host and DTB match: 397073690
[15:59:12.292] INFO: DUT info:
[15:59:12.293] INFO: The DUT currently contains the following objects:
[15:59:12.293] INFO: 2 TBM Cores tbm08c (2 ON)
[15:59:12.293] INFO: TBM Core alpha (0): 7 registers set
[15:59:12.293] INFO: TBM Core beta (1): 7 registers set
[15:59:12.293] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:59:12.293] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.293] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.293] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.293] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.293] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.293] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.293] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.293] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.293] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.293] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.293] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.294] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.294] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.294] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.294] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.294] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:12.696] INFO: enter 'restricted' command line mode
[15:59:12.696] INFO: enter test to run
[15:59:12.696] INFO: test: highrate no parameter change
[15:59:12.696] INFO: running: highrate
[15:59:12.705] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4570_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[15:59:12.926] INFO: ----------------------------------------------------------------------
[15:59:12.926] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:59:12.926] INFO: ----------------------------------------------------------------------
[15:59:13.786] INFO: Expecting 208000 events.
[15:59:21.654] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 Number of ROCs (6) != Token Chain Length (8)

[15:59:21.654] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 Number of ROCs (7) != Token Chain Length (8)

[15:59:21.660] WARNING: Channel 0 ROC 7: Readback start marker after 15 readouts!

[15:59:24.646] ERROR: <datapipe.cc/CheckEventValidity:L469> Channel 0 has NoTokenPass but 8 ROCs were found

[15:59:24.646] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 Number of ROCs (6) != Token Chain Length (8)

[15:59:24.646] WARNING: Channel 1 ROC 7: Readback start marker after 15 readouts!

[15:59:38.809] WARNING: Channel 1 ROC 5: Readback start marker after 1 readouts!

[15:59:38.814] WARNING: Channel 1 ROC 5: Readback start marker after 15 readouts!

[16:00:07.446] INFO: 208000 events read in total (53130ms).
[16:00:07.491] INFO: Test took 54559ms.
[16:00:09.302] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:10.527] INFO: number of dead pixels (per ROC): 2 0 0 1 1 0 0 0 0 0 0 1 0 0 0 0
[16:00:10.527] INFO: number of red-efficiency pixels: 3465 2853 2186 3822 3812 3989 3744 3994 4057 4000 4065 4022 4015 3124 3740 3216
[16:00:10.527] INFO: number of X-ray hits detected: 616285 612453 518775 802507 793131 864127 775890 757240 799736 823103 913078 890283 869740 583124 668297 658196
[16:00:10.527] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:00:10.527] INFO: number of Vcal hits detected: 199257 202515 201096 194137 193546 190575 194933 192727 189987 188426 187748 189209 189942 195608 196936 200381
[16:00:10.528] INFO: Vcal hit fiducial efficiency (%): 96.2 97.6 97.3 94.1 93.8 92.5 94.5 93.2 92.0 91.5 91.2 91.9 92.1 94.7 95.1 96.7
[16:00:10.528] INFO: Vcal hit overall efficiency (%): 95.8 97.4 96.7 93.3 93.1 91.6 93.7 92.7 91.3 90.6 90.3 91.0 91.3 94.0 94.7 96.3
[16:00:10.528] INFO: X-ray hit rate [MHz/cm2]: 180.6 179.5 152.1 235.2 232.5 253.3 227.4 222.0 234.4 241.3 267.6 260.9 254.9 170.9 195.9 192.9
[16:00:10.528] INFO: PixTestHighRate::doXPixelAlive() done
[16:00:10.575] INFO: PixTest:: pg_setup set to default.
[16:00:10.629] INFO: enter test to run
[16:00:10.629] INFO: test: exit no parameter change
[16:00:10.738] QUIET: Connection to board 172 closed.
[16:00:10.763] INFO: pXar: this is the end, my friend
MoReWeb-v0.6.7-73-g24fed82 on branch dev-v0.7.0