[15:03:25.067] INFO: *** Welcome to pxar ***
[15:03:25.067] INFO: *** Today: 2015/09/08
[15:03:25.068] INFO: readRocDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[15:03:25.070] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[15:03:25.070] INFO: readMaskFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[15:03:25.070] INFO: readTrimFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[15:03:25.154] INFO: clk: 4
[15:03:25.154] INFO: ctr: 4
[15:03:25.154] INFO: sda: 19
[15:03:25.154] INFO: tin: 9
[15:03:25.154] INFO: level: 15
[15:03:25.154] INFO: triggerdelay: 0
[15:03:25.154] QUIET: Instanciating API for pxar 2.5
[15:03:25.154] INFO: Log level: INFO
[15:03:25.166] INFO: Found DTB DTB_WZ4QKL
[15:03:25.183] QUIET: Connection to board DTB_WZ4QKL opened.
[15:03:25.187] 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:03:25.191] INFO: RPC call hashes of host and DTB match: 397073690
[15:03:27.007] INFO: DUT info:
[15:03:27.007] INFO: The DUT currently contains the following objects:
[15:03:27.007] INFO: 2 TBM Cores tbm08c (2 ON)
[15:03:27.007] INFO: TBM Core alpha (0): 7 registers set
[15:03:27.007] INFO: TBM Core beta (1): 7 registers set
[15:03:27.007] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:03:27.007] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.007] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.007] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.007] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.008] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.008] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.008] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.008] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.008] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.008] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.008] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.008] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.008] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.008] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.008] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.008] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:27.418] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[15:03:27.654] INFO: ######################################################################
[15:03:27.654] INFO: PixTestXray::doTest()
[15:03:27.654] INFO: ######################################################################
[15:03:27.654] INFO: ----------------------------------------------------------------------
[15:03:27.654] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:03:27.654] INFO: ----------------------------------------------------------------------
[15:03:28.665] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:03:32.404] INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[15:04:32.394] INFO: Resuming triggers.
[15:04:36.145] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.




[15:05:36.520] INFO: Resuming triggers.
[15:05:40.269] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:06:40.291] INFO: Resuming triggers.
[15:06:44.034] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[15:07:44.078] INFO: Resuming triggers.
[15:07:47.826] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[15:08:47.261] INFO: Resuming triggers.
[15:08:51.008] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[15:09:49.738] INFO: Resuming triggers.
[15:09:53.486] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[15:10:51.429] INFO: Resuming triggers.
[15:10:55.178] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[15:11:51.131] INFO: Resuming triggers.
[15:11:54.874] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[15:12:49.244] INFO: Resuming triggers.
[15:12:52.985] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[15:13:46.394] INFO: Resuming triggers.
[15:13:50.136] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[15:14:43.079] INFO: Resuming triggers.
[15:14:46.827] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[15:15:39.907] INFO: Resuming triggers.
[15:15:43.648] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[15:16:36.036] INFO: Resuming triggers.
[15:16:39.784] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[15:17:33.046] INFO: Resuming triggers.
[15:17:36.787] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.





[15:18:29.020] INFO: Resuming triggers.
[15:18:32.761] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[15:19:24.433] INFO: Resuming triggers.
[15:19:28.180] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[15:20:21.040] INFO: Resuming triggers.
[15:20:24.780] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[15:21:16.508] INFO: Resuming triggers.
[15:21:20.256] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[15:22:13.795] INFO: Resuming triggers.
[15:22:17.535] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[15:23:10.241] INFO: Resuming triggers.
[15:23:13.990] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[15:24:07.463] INFO: Resuming triggers.
[15:24:11.204] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[15:25:04.051] INFO: Resuming triggers.
[15:25:07.808] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[15:26:00.412] INFO: Resuming triggers.
[15:26:04.154] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[15:26:56.242] INFO: Resuming triggers.
[15:26:59.990] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[15:27:52.525] INFO: Resuming triggers.
[15:27:56.268] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[15:28:48.488] INFO: Resuming triggers.
[15:28:51.385] INFO: data taking finished, elapsed time: 100 seconds.
[15:29:31.178] INFO: PixTest:: pg_setup set to default.
[15:29:31.180] INFO: PixTestXray::doPhRun() done
[15:29:31.180] INFO: PixTestXray::doTest() done
[15:29:31.586] QUIET: Connection to board 172 closed.
[15:29:31.608] INFO: pXar: this is the end, my friend

[14:09:03.701] INFO: *** Welcome to pxar ***
[14:09:03.701] INFO: *** Today: 2015/09/08
[14:09:03.701] INFO: readRocDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[14:09:03.702] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[14:09:03.702] INFO: readMaskFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[14:09:03.702] INFO: readTrimFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[14:09:03.781] INFO: clk: 4
[14:09:03.781] INFO: ctr: 4
[14:09:03.781] INFO: sda: 19
[14:09:03.781] INFO: tin: 9
[14:09:03.781] INFO: level: 15
[14:09:03.781] INFO: triggerdelay: 0
[14:09:03.782] QUIET: Instanciating API for pxar 2.5
[14:09:03.782] INFO: Log level: INFO
[14:09:03.789] INFO: Found DTB DTB_WZ4QKL
[14:09:03.802] QUIET: Connection to board DTB_WZ4QKL opened.
[14:09:03.806] 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:09:03.809] INFO: RPC call hashes of host and DTB match: 397073690
[14:09:05.637] INFO: DUT info:
[14:09:05.637] INFO: The DUT currently contains the following objects:
[14:09:05.637] INFO: 2 TBM Cores tbm08c (2 ON)
[14:09:05.637] INFO: TBM Core alpha (0): 7 registers set
[14:09:05.637] INFO: TBM Core beta (1): 7 registers set
[14:09:05.637] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:09:05.637] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.637] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.637] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.637] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.638] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.638] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.638] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.638] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.638] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.638] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.638] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.638] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.638] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.638] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.638] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:05.638] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:09:06.047] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[14:09:06.265] INFO: ######################################################################
[14:09:06.265] INFO: PixTestXray::doTest()
[14:09:06.265] INFO: ######################################################################
[14:09:06.265] INFO: ----------------------------------------------------------------------
[14:09:06.265] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:09:06.265] INFO: ----------------------------------------------------------------------
[14:09:07.276] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:09:15.043] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[14:10:12.004] INFO: Resuming triggers.
[14:10:19.787] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[14:11:16.607] INFO: Resuming triggers.
[14:11:24.367] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:12:21.469] INFO: Resuming triggers.
[14:12:29.238] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[14:13:25.934] INFO: Resuming triggers.
[14:13:33.693] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[14:14:29.369] INFO: Resuming triggers.
[14:14:37.142] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:15:32.413] INFO: Resuming triggers.
[14:15:40.171] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[14:16:34.524] INFO: Resuming triggers.
[14:16:42.288] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[14:17:35.416] INFO: Resuming triggers.
[14:17:43.184] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:18:34.176] INFO: Resuming triggers.
[14:18:41.937] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[14:19:32.108] INFO: Resuming triggers.
[14:19:39.861] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[14:20:29.588] INFO: Resuming triggers.
[14:20:37.341] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[14:21:26.853] INFO: Resuming triggers.
[14:21:33.957] INFO: data taking finished, elapsed time: 100 seconds.
[14:22:18.175] INFO: PixTest:: pg_setup set to default.
[14:22:18.177] INFO: PixTestXray::doPhRun() done
[14:22:18.177] INFO: PixTestXray::doTest() done
[14:22:18.676] QUIET: Connection to board 172 closed.
[14:22:18.692] INFO: pXar: this is the end, my friend

[14:23:12.243] INFO: *** Welcome to pxar ***
[14:23:12.243] INFO: *** Today: 2015/09/08
[14:23:12.243] INFO: readRocDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[14:23:12.245] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[14:23:12.245] INFO: readMaskFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[14:23:12.246] INFO: readTrimFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[14:23:12.333] INFO: clk: 4
[14:23:12.333] INFO: ctr: 4
[14:23:12.333] INFO: sda: 19
[14:23:12.333] INFO: tin: 9
[14:23:12.333] INFO: level: 15
[14:23:12.333] INFO: triggerdelay: 0
[14:23:12.333] QUIET: Instanciating API for pxar 2.5
[14:23:12.333] INFO: Log level: INFO
[14:23:12.344] INFO: Found DTB DTB_WZ4QKL
[14:23:12.361] QUIET: Connection to board DTB_WZ4QKL opened.
[14:23:12.365] 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:23:12.368] INFO: RPC call hashes of host and DTB match: 397073690
[14:23:14.183] INFO: DUT info:
[14:23:14.183] INFO: The DUT currently contains the following objects:
[14:23:14.183] INFO: 2 TBM Cores tbm08c (2 ON)
[14:23:14.183] INFO: TBM Core alpha (0): 7 registers set
[14:23:14.183] INFO: TBM Core beta (1): 7 registers set
[14:23:14.183] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:23:14.183] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.183] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:14.585] INFO: enter 'restricted' command line mode
[14:23:14.585] INFO: enter test to run
[14:23:14.585] INFO: test: highrate no parameter change
[14:23:14.585] INFO: running: highrate
[14:23:14.594] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[14:23:14.820] INFO: ----------------------------------------------------------------------
[14:23:14.820] INFO: PixTestHighRate::xNoiseMaps() ntrig = 25, vcal = 200
[14:23:14.820] INFO: ----------------------------------------------------------------------
[14:23:14.828] INFO: ---> dac: vcal name: xNoiseMap ntrig: 25 dacrange: 0 .. 100 (1/-1) hits flags = 384 (plus default)
[14:23:14.869] INFO: dacScan step from 0 .. 0
[14:23:14.869] INFO: dacScan split into 1 runs with ntrig = 25
[14:23:14.869] INFO: run 1 of 1
[14:23:15.735] INFO: Expecting 104000 events.
[14:23:28.845] INFO: 104000 events read in total (12580ms).
[14:23:28.865] INFO: Test took 13996ms.
[14:23:28.954] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:29.275] INFO: dacScan step from 1 .. 1
[14:23:29.275] INFO: dacScan split into 1 runs with ntrig = 25
[14:23:29.275] INFO: run 1 of 1
[14:23:30.120] INFO: Expecting 104000 events.
[14:23:42.784] INFO: 104000 events read in total (12137ms).
[14:23:42.794] INFO: Test took 13519ms.
[14:23:42.882] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:43.182] INFO: dacScan step from 2 .. 2
[14:23:43.182] INFO: dacScan split into 1 runs with ntrig = 25
[14:23:43.182] INFO: run 1 of 1
[14:23:44.041] INFO: Expecting 104000 events.
[14:23:56.312] INFO: 104000 events read in total (11744ms).
[14:23:56.322] INFO: Test took 13140ms.
[14:23:56.413] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:56.731] INFO: dacScan step from 3 .. 3
[14:23:56.732] INFO: dacScan split into 1 runs with ntrig = 25
[14:23:56.732] INFO: run 1 of 1
[14:23:57.577] INFO: Expecting 104000 events.
[14:24:10.304] INFO: 104000 events read in total (12200ms).
[14:24:10.315] INFO: Test took 13583ms.
[14:24:10.416] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:10.733] INFO: dacScan step from 4 .. 4
[14:24:10.733] INFO: dacScan split into 1 runs with ntrig = 25
[14:24:10.733] INFO: run 1 of 1
[14:24:11.570] INFO: Expecting 104000 events.
[14:24:24.151] INFO: 104000 events read in total (12055ms).
[14:24:24.162] INFO: Test took 13429ms.
[14:24:24.256] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:24.566] INFO: dacScan step from 5 .. 5
[14:24:24.566] INFO: dacScan split into 1 runs with ntrig = 25
[14:24:24.566] INFO: run 1 of 1
[14:24:25.419] INFO: Expecting 104000 events.
[14:24:37.744] INFO: 104000 events read in total (11798ms).
[14:24:37.754] INFO: Test took 13187ms.
[14:24:37.847] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:38.164] INFO: dacScan step from 6 .. 6
[14:24:38.165] INFO: dacScan split into 1 runs with ntrig = 25
[14:24:38.165] INFO: run 1 of 1
[14:24:39.012] INFO: Expecting 104000 events.
[14:24:51.374] INFO: 104000 events read in total (11835ms).
[14:24:51.384] INFO: Test took 13219ms.
[14:24:51.474] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:51.784] INFO: dacScan step from 7 .. 7
[14:24:51.785] INFO: dacScan split into 1 runs with ntrig = 25
[14:24:51.785] INFO: run 1 of 1
[14:24:52.638] INFO: Expecting 104000 events.
[14:25:04.437] INFO: 104000 events read in total (11272ms).
[14:25:04.448] INFO: Test took 12663ms.
[14:25:04.547] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:04.859] INFO: dacScan step from 8 .. 8
[14:25:04.859] INFO: dacScan split into 1 runs with ntrig = 25
[14:25:04.859] INFO: run 1 of 1
[14:25:05.706] INFO: Expecting 104000 events.
[14:25:17.637] INFO: 104000 events read in total (11404ms).
[14:25:17.648] INFO: Test took 12789ms.
[14:25:17.739] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:18.055] INFO: dacScan step from 9 .. 9
[14:25:18.055] INFO: dacScan split into 1 runs with ntrig = 25
[14:25:18.055] INFO: run 1 of 1
[14:25:18.902] INFO: Expecting 104000 events.
[14:25:31.181] INFO: 104000 events read in total (11752ms).
[14:25:31.192] INFO: Test took 13137ms.
[14:25:31.284] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:31.598] INFO: dacScan step from 10 .. 10
[14:25:31.598] INFO: dacScan split into 1 runs with ntrig = 25
[14:25:31.598] INFO: run 1 of 1
[14:25:32.448] INFO: Expecting 104000 events.
[14:25:44.359] INFO: 104000 events read in total (11384ms).
[14:25:44.369] INFO: Test took 12770ms.
[14:25:44.462] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:44.779] INFO: dacScan step from 11 .. 11
[14:25:44.779] INFO: dacScan split into 1 runs with ntrig = 25
[14:25:44.779] INFO: run 1 of 1
[14:25:45.620] INFO: Expecting 104000 events.
[14:25:57.733] INFO: 104000 events read in total (11586ms).
[14:25:57.743] INFO: Test took 12963ms.
[14:25:57.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:58.159] INFO: dacScan step from 12 .. 12
[14:25:58.159] INFO: dacScan split into 1 runs with ntrig = 25
[14:25:58.159] INFO: run 1 of 1
[14:25:59.008] INFO: Expecting 104000 events.
[14:26:10.921] INFO: 104000 events read in total (11385ms).
[14:26:10.932] INFO: Test took 12772ms.
[14:26:11.028] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:11.339] INFO: dacScan step from 13 .. 13
[14:26:11.339] INFO: dacScan split into 1 runs with ntrig = 25
[14:26:11.339] INFO: run 1 of 1
[14:26:12.187] INFO: Expecting 104000 events.
[14:26:23.933] INFO: 104000 events read in total (11219ms).
[14:26:23.944] INFO: Test took 12604ms.
[14:26:24.037] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:24.353] INFO: dacScan step from 14 .. 14
[14:26:24.353] INFO: dacScan split into 1 runs with ntrig = 25
[14:26:24.353] INFO: run 1 of 1
[14:26:25.205] INFO: Expecting 104000 events.
[14:26:36.827] INFO: 104000 events read in total (11095ms).
[14:26:36.837] INFO: Test took 12483ms.
[14:26:36.931] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:37.250] INFO: dacScan step from 15 .. 15
[14:26:37.250] INFO: dacScan split into 1 runs with ntrig = 25
[14:26:37.251] INFO: run 1 of 1
[14:26:38.098] INFO: Expecting 104000 events.
[14:26:50.116] INFO: 104000 events read in total (11491ms).
[14:26:50.126] INFO: Test took 12875ms.
[14:26:50.225] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:50.542] INFO: dacScan step from 16 .. 16
[14:26:50.542] INFO: dacScan split into 1 runs with ntrig = 25
[14:26:50.542] INFO: run 1 of 1
[14:26:51.386] INFO: Expecting 104000 events.
[14:27:03.531] INFO: 104000 events read in total (11618ms).
[14:27:03.542] INFO: Test took 13000ms.
[14:27:03.634] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:03.940] INFO: dacScan step from 17 .. 17
[14:27:03.940] INFO: dacScan split into 1 runs with ntrig = 25
[14:27:03.940] INFO: run 1 of 1
[14:27:04.788] INFO: Expecting 104000 events.
[14:27:16.963] INFO: 104000 events read in total (11648ms).
[14:27:16.973] INFO: Test took 13032ms.
[14:27:17.063] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:17.359] INFO: dacScan step from 18 .. 18
[14:27:17.359] INFO: dacScan split into 1 runs with ntrig = 25
[14:27:17.359] INFO: run 1 of 1
[14:27:18.195] INFO: Expecting 104000 events.
[14:27:30.292] INFO: 104000 events read in total (11570ms).
[14:27:30.302] INFO: Test took 12942ms.
[14:27:30.397] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:30.698] INFO: dacScan step from 19 .. 19
[14:27:30.698] INFO: dacScan split into 1 runs with ntrig = 25
[14:27:30.698] INFO: run 1 of 1
[14:27:31.532] INFO: Expecting 104000 events.
[14:27:44.138] INFO: 104000 events read in total (12079ms).
[14:27:44.148] INFO: Test took 13449ms.
[14:27:44.235] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:44.540] INFO: dacScan step from 20 .. 20
[14:27:44.540] INFO: dacScan split into 1 runs with ntrig = 25
[14:27:44.540] INFO: run 1 of 1
[14:27:45.395] INFO: Expecting 104000 events.
[14:27:58.268] INFO: 104000 events read in total (12345ms).
[14:27:58.278] INFO: Test took 13737ms.
[14:27:58.370] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:58.676] INFO: dacScan step from 21 .. 21
[14:27:58.676] INFO: dacScan split into 1 runs with ntrig = 25
[14:27:58.676] INFO: run 1 of 1
[14:27:59.524] INFO: Expecting 104000 events.
[14:28:12.473] INFO: 104000 events read in total (12422ms).
[14:28:12.483] INFO: Test took 13806ms.
[14:28:12.570] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:12.884] INFO: dacScan step from 22 .. 22
[14:28:12.884] INFO: dacScan split into 1 runs with ntrig = 25
[14:28:12.884] INFO: run 1 of 1
[14:28:13.726] INFO: Expecting 104000 events.
[14:28:26.309] INFO: 104000 events read in total (12055ms).
[14:28:26.320] INFO: Test took 13435ms.
[14:28:26.419] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:26.723] INFO: dacScan step from 23 .. 23
[14:28:26.723] INFO: dacScan split into 1 runs with ntrig = 25
[14:28:26.723] INFO: run 1 of 1
[14:28:27.548] INFO: Expecting 104000 events.
[14:28:40.461] INFO: 104000 events read in total (12385ms).
[14:28:40.471] INFO: Test took 13747ms.
[14:28:40.566] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:40.871] INFO: dacScan step from 24 .. 24
[14:28:40.871] INFO: dacScan split into 1 runs with ntrig = 25
[14:28:40.871] INFO: run 1 of 1
[14:28:41.704] INFO: Expecting 104000 events.
[14:28:54.593] INFO: 104000 events read in total (12362ms).
[14:28:54.603] INFO: Test took 13731ms.
[14:28:54.695] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:55.009] INFO: dacScan step from 25 .. 25
[14:28:55.009] INFO: dacScan split into 1 runs with ntrig = 25
[14:28:55.009] INFO: run 1 of 1
[14:28:55.840] INFO: Expecting 104000 events.
[14:29:08.735] INFO: 104000 events read in total (12368ms).
[14:29:08.745] INFO: Test took 13736ms.
[14:29:08.839] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:09.159] INFO: dacScan step from 26 .. 26
[14:29:09.159] INFO: dacScan split into 1 runs with ntrig = 25
[14:29:09.159] INFO: run 1 of 1
[14:29:10.000] INFO: Expecting 104000 events.
[14:29:22.834] INFO: 104000 events read in total (12307ms).
[14:29:22.844] INFO: Test took 13685ms.
[14:29:22.932] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:23.246] INFO: dacScan step from 27 .. 27
[14:29:23.246] INFO: dacScan split into 1 runs with ntrig = 25
[14:29:23.246] INFO: run 1 of 1
[14:29:24.082] INFO: Expecting 104000 events.
[14:29:36.797] INFO: 104000 events read in total (12188ms).
[14:29:36.808] INFO: Test took 13561ms.
[14:29:36.899] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:37.213] INFO: dacScan step from 28 .. 28
[14:29:37.213] INFO: dacScan split into 1 runs with ntrig = 25
[14:29:37.213] INFO: run 1 of 1
[14:29:38.060] INFO: Expecting 104000 events.
[14:29:50.857] INFO: 104000 events read in total (12270ms).
[14:29:50.867] INFO: Test took 13653ms.
[14:29:50.960] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:51.274] INFO: dacScan step from 29 .. 29
[14:29:51.274] INFO: dacScan split into 1 runs with ntrig = 25
[14:29:51.274] INFO: run 1 of 1
[14:29:52.122] INFO: Expecting 104000 events.
[14:30:04.210] INFO: 104000 events read in total (11561ms).
[14:30:04.224] INFO: Test took 12949ms.
[14:30:04.316] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:30:04.635] INFO: dacScan step from 30 .. 30
[14:30:04.635] INFO: dacScan split into 1 runs with ntrig = 25
[14:30:04.635] INFO: run 1 of 1
[14:30:05.486] INFO: Expecting 104000 events.
[14:30:17.568] INFO: 104000 events read in total (11555ms).
[14:30:17.580] INFO: Test took 12944ms.
[14:30:17.671] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:30:17.985] INFO: dacScan step from 31 .. 31
[14:30:17.985] INFO: dacScan split into 1 runs with ntrig = 25
[14:30:17.985] INFO: run 1 of 1
[14:30:18.828] INFO: Expecting 104000 events.
[14:30:30.794] INFO: 104000 events read in total (11439ms).
[14:30:30.804] INFO: Test took 12818ms.
[14:30:30.896] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:30:31.213] INFO: dacScan step from 32 .. 32
[14:30:31.213] INFO: dacScan split into 1 runs with ntrig = 25
[14:30:31.213] INFO: run 1 of 1
[14:30:32.059] INFO: Expecting 104000 events.
[14:30:44.022] INFO: 104000 events read in total (11436ms).
[14:30:44.032] INFO: Test took 12818ms.
[14:30:44.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:30:44.443] INFO: dacScan step from 33 .. 33
[14:30:44.443] INFO: dacScan split into 1 runs with ntrig = 25
[14:30:44.443] INFO: run 1 of 1
[14:30:45.301] INFO: Expecting 104000 events.
[14:30:57.056] INFO: 104000 events read in total (11228ms).
[14:30:57.066] INFO: Test took 12622ms.
[14:30:57.159] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:30:57.478] INFO: dacScan step from 34 .. 34
[14:30:57.478] INFO: dacScan split into 1 runs with ntrig = 25
[14:30:57.478] INFO: run 1 of 1
[14:30:58.323] INFO: Expecting 104000 events.
[14:31:10.151] INFO: 104000 events read in total (11301ms).
[14:31:10.162] INFO: Test took 12683ms.
[14:31:10.253] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:10.572] INFO: dacScan step from 35 .. 35
[14:31:10.572] INFO: dacScan split into 1 runs with ntrig = 25
[14:31:10.572] INFO: run 1 of 1
[14:31:11.418] INFO: Expecting 104000 events.
[14:31:23.881] INFO: 104000 events read in total (11936ms).
[14:31:23.895] INFO: Test took 13322ms.
[14:31:23.996] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:24.324] INFO: dacScan step from 36 .. 36
[14:31:24.324] INFO: dacScan split into 1 runs with ntrig = 25
[14:31:24.324] INFO: run 1 of 1
[14:31:25.175] INFO: Expecting 104000 events.
[14:31:37.613] INFO: 104000 events read in total (11911ms).
[14:31:37.624] INFO: Test took 13299ms.
[14:31:37.720] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:38.043] INFO: dacScan step from 37 .. 37
[14:31:38.043] INFO: dacScan split into 1 runs with ntrig = 25
[14:31:38.043] INFO: run 1 of 1
[14:31:38.895] INFO: Expecting 104000 events.
[14:31:51.466] INFO: 104000 events read in total (12044ms).
[14:31:51.477] INFO: Test took 13434ms.
[14:31:51.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:51.896] INFO: dacScan step from 38 .. 38
[14:31:51.896] INFO: dacScan split into 1 runs with ntrig = 25
[14:31:51.896] INFO: run 1 of 1
[14:31:52.747] INFO: Expecting 104000 events.
[14:32:05.608] INFO: 104000 events read in total (12334ms).
[14:32:05.619] INFO: Test took 13722ms.
[14:32:05.721] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:06.047] INFO: dacScan step from 39 .. 39
[14:32:06.047] INFO: dacScan split into 1 runs with ntrig = 25
[14:32:06.047] INFO: run 1 of 1
[14:32:06.900] INFO: Expecting 104000 events.
[14:32:19.895] INFO: 104000 events read in total (12468ms).
[14:32:19.906] INFO: Test took 13858ms.
[14:32:20.000] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:20.331] INFO: dacScan step from 40 .. 40
[14:32:20.331] INFO: dacScan split into 1 runs with ntrig = 25
[14:32:20.331] INFO: run 1 of 1
[14:32:21.183] INFO: Expecting 104000 events.
[14:32:34.360] INFO: 104000 events read in total (12650ms).
[14:32:34.371] INFO: Test took 14039ms.
[14:32:34.475] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:34.803] INFO: dacScan step from 41 .. 41
[14:32:34.803] INFO: dacScan split into 1 runs with ntrig = 25
[14:32:34.803] INFO: run 1 of 1
[14:32:35.655] INFO: Expecting 104000 events.
[14:32:48.816] INFO: 104000 events read in total (12633ms).
[14:32:48.827] INFO: Test took 14023ms.
[14:32:48.919] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:49.250] INFO: dacScan step from 42 .. 42
[14:32:49.250] INFO: dacScan split into 1 runs with ntrig = 25
[14:32:49.250] INFO: run 1 of 1
[14:32:50.095] INFO: Expecting 104000 events.
[14:33:03.834] INFO: 104000 events read in total (13212ms).
[14:33:03.844] INFO: Test took 14593ms.
[14:33:03.947] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:04.284] INFO: dacScan step from 43 .. 43
[14:33:04.284] INFO: dacScan split into 1 runs with ntrig = 25
[14:33:04.284] INFO: run 1 of 1
[14:33:05.132] INFO: Expecting 104000 events.
[14:33:19.251] INFO: 104000 events read in total (13592ms).
[14:33:19.262] INFO: Test took 14977ms.
[14:33:19.355] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:19.683] INFO: dacScan step from 44 .. 44
[14:33:19.683] INFO: dacScan split into 1 runs with ntrig = 25
[14:33:19.683] INFO: run 1 of 1
[14:33:20.528] INFO: Expecting 104000 events.
[14:33:34.867] INFO: 104000 events read in total (13812ms).
[14:33:34.878] INFO: Test took 15194ms.
[14:33:34.974] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:35.303] INFO: dacScan step from 45 .. 45
[14:33:35.303] INFO: dacScan split into 1 runs with ntrig = 25
[14:33:35.303] INFO: run 1 of 1
[14:33:36.163] INFO: Expecting 104000 events.
[14:33:50.830] INFO: 104000 events read in total (14140ms).
[14:33:50.840] INFO: Test took 15536ms.
[14:33:50.936] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:51.266] INFO: dacScan step from 46 .. 46
[14:33:51.266] INFO: dacScan split into 1 runs with ntrig = 25
[14:33:51.266] INFO: run 1 of 1
[14:33:52.114] INFO: Expecting 104000 events.
[14:34:07.695] INFO: 104000 events read in total (15054ms).
[14:34:07.704] INFO: Test took 16437ms.
[14:34:07.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:08.130] INFO: dacScan step from 47 .. 47
[14:34:08.130] INFO: dacScan split into 1 runs with ntrig = 25
[14:34:08.130] INFO: run 1 of 1
[14:34:08.979] INFO: Expecting 104000 events.
[14:34:24.474] INFO: 104000 events read in total (14958ms).
[14:34:24.492] INFO: Test took 16361ms.
[14:34:24.585] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:24.914] INFO: dacScan step from 48 .. 48
[14:34:24.914] INFO: dacScan split into 1 runs with ntrig = 25
[14:34:24.914] INFO: run 1 of 1
[14:34:25.765] INFO: Expecting 104000 events.
[14:34:41.614] INFO: 104000 events read in total (15311ms).
[14:34:41.625] INFO: Test took 16710ms.
[14:34:41.712] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:42.047] INFO: dacScan step from 49 .. 49
[14:34:42.047] INFO: dacScan split into 1 runs with ntrig = 25
[14:34:42.047] INFO: run 1 of 1
[14:34:42.899] INFO: Expecting 104000 events.
[14:34:58.867] INFO: 104000 events read in total (15441ms).
[14:34:58.877] INFO: Test took 16829ms.
[14:34:58.964] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:59.290] INFO: dacScan step from 50 .. 50
[14:34:59.290] INFO: dacScan split into 1 runs with ntrig = 25
[14:34:59.290] INFO: run 1 of 1
[14:35:00.139] INFO: Expecting 104000 events.
[14:35:16.387] INFO: 104000 events read in total (15721ms).
[14:35:16.403] INFO: Test took 17112ms.
[14:35:16.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:16.826] INFO: dacScan step from 51 .. 51
[14:35:16.826] INFO: dacScan split into 1 runs with ntrig = 25
[14:35:16.826] INFO: run 1 of 1
[14:35:17.677] INFO: Expecting 104000 events.
[14:35:33.584] INFO: 104000 events read in total (15380ms).
[14:35:33.601] INFO: Test took 16774ms.
[14:35:33.695] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:34.016] INFO: dacScan step from 52 .. 52
[14:35:34.016] INFO: dacScan split into 1 runs with ntrig = 25
[14:35:34.016] INFO: run 1 of 1
[14:35:34.858] INFO: Expecting 104000 events.
[14:35:51.053] INFO: 104000 events read in total (15668ms).
[14:35:51.064] INFO: Test took 17047ms.
[14:35:51.156] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:51.483] INFO: dacScan step from 53 .. 53
[14:35:51.483] INFO: dacScan split into 1 runs with ntrig = 25
[14:35:51.483] INFO: run 1 of 1
[14:35:52.328] INFO: Expecting 104000 events.
[14:36:09.083] INFO: 104000 events read in total (16227ms).
[14:36:09.094] INFO: Test took 17610ms.
[14:36:09.179] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:09.519] INFO: dacScan step from 54 .. 54
[14:36:09.519] INFO: dacScan split into 1 runs with ntrig = 25
[14:36:09.519] INFO: run 1 of 1
[14:36:10.371] INFO: Expecting 104000 events.
[14:36:27.081] INFO: 104000 events read in total (16180ms).
[14:36:27.091] INFO: Test took 17571ms.
[14:36:27.193] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:27.521] INFO: dacScan step from 55 .. 55
[14:36:27.521] INFO: dacScan split into 1 runs with ntrig = 25
[14:36:27.521] INFO: run 1 of 1
[14:36:28.377] INFO: Expecting 104000 events.
[14:36:44.430] INFO: 104000 events read in total (15526ms).
[14:36:44.440] INFO: Test took 16917ms.
[14:36:44.526] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:44.851] INFO: dacScan step from 56 .. 56
[14:36:44.851] INFO: dacScan split into 1 runs with ntrig = 25
[14:36:44.852] INFO: run 1 of 1
[14:36:45.700] INFO: Expecting 104000 events.
[14:37:01.907] INFO: 104000 events read in total (15680ms).
[14:37:01.918] INFO: Test took 17065ms.
[14:37:02.014] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:02.346] INFO: dacScan step from 57 .. 57
[14:37:02.346] INFO: dacScan split into 1 runs with ntrig = 25
[14:37:02.346] INFO: run 1 of 1
[14:37:03.188] INFO: Expecting 104000 events.
[14:37:19.110] INFO: 104000 events read in total (15395ms).
[14:37:19.120] INFO: Test took 16773ms.
[14:37:19.217] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:19.553] INFO: dacScan step from 58 .. 58
[14:37:19.553] INFO: dacScan split into 1 runs with ntrig = 25
[14:37:19.553] INFO: run 1 of 1
[14:37:20.398] INFO: Expecting 104000 events.
[14:37:36.214] INFO: 104000 events read in total (15288ms).
[14:37:36.225] INFO: Test took 16671ms.
[14:37:36.318] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:36.654] INFO: dacScan step from 59 .. 59
[14:37:36.654] INFO: dacScan split into 1 runs with ntrig = 25
[14:37:36.655] INFO: run 1 of 1
[14:37:37.509] INFO: Expecting 104000 events.
[14:37:53.834] INFO: 104000 events read in total (15798ms).
[14:37:53.852] INFO: Test took 17196ms.
[14:37:53.952] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:54.281] INFO: dacScan step from 60 .. 60
[14:37:54.282] INFO: dacScan split into 1 runs with ntrig = 25
[14:37:54.282] INFO: run 1 of 1
[14:37:55.133] INFO: Expecting 104000 events.
[14:38:11.113] INFO: 104000 events read in total (15453ms).
[14:38:11.124] INFO: Test took 16841ms.
[14:38:11.222] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:38:11.550] INFO: dacScan step from 61 .. 61
[14:38:11.550] INFO: dacScan split into 1 runs with ntrig = 25
[14:38:11.550] INFO: run 1 of 1
[14:38:12.404] INFO: Expecting 104000 events.
[14:38:28.012] INFO: 104000 events read in total (15081ms).
[14:38:28.023] INFO: Test took 16471ms.
[14:38:28.108] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:38:28.447] INFO: dacScan step from 62 .. 62
[14:38:28.447] INFO: dacScan split into 1 runs with ntrig = 25
[14:38:28.447] INFO: run 1 of 1
[14:38:29.295] INFO: Expecting 104000 events.
[14:38:45.310] INFO: 104000 events read in total (15488ms).
[14:38:45.320] INFO: Test took 16871ms.
[14:38:45.411] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:38:45.755] INFO: dacScan step from 63 .. 63
[14:38:45.755] INFO: dacScan split into 1 runs with ntrig = 25
[14:38:45.755] INFO: run 1 of 1
[14:38:46.602] INFO: Expecting 104000 events.
[14:39:02.152] INFO: 104000 events read in total (15023ms).
[14:39:02.162] INFO: Test took 16406ms.
[14:39:02.258] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:02.586] INFO: dacScan step from 64 .. 64
[14:39:02.586] INFO: dacScan split into 1 runs with ntrig = 25
[14:39:02.586] INFO: run 1 of 1
[14:39:03.438] INFO: Expecting 104000 events.
[14:39:19.090] INFO: 104000 events read in total (15125ms).
[14:39:19.110] INFO: Test took 16523ms.
[14:39:19.200] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:19.532] INFO: dacScan step from 65 .. 65
[14:39:19.532] INFO: dacScan split into 1 runs with ntrig = 25
[14:39:19.532] INFO: run 1 of 1
[14:39:20.383] INFO: Expecting 104000 events.
[14:39:35.814] INFO: 104000 events read in total (14904ms).
[14:39:35.831] INFO: Test took 16298ms.
[14:39:35.913] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:36.244] INFO: dacScan step from 66 .. 66
[14:39:36.244] INFO: dacScan split into 1 runs with ntrig = 25
[14:39:36.244] INFO: run 1 of 1
[14:39:37.092] INFO: Expecting 104000 events.
[14:39:52.521] INFO: 104000 events read in total (14902ms).
[14:39:52.532] INFO: Test took 16287ms.
[14:39:52.618] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:52.948] INFO: dacScan step from 67 .. 67
[14:39:52.948] INFO: dacScan split into 1 runs with ntrig = 25
[14:39:52.948] INFO: run 1 of 1
[14:39:53.795] INFO: Expecting 104000 events.
[14:40:09.615] INFO: 104000 events read in total (15293ms).
[14:40:09.632] INFO: Test took 16682ms.
[14:40:09.720] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:10.049] INFO: dacScan step from 68 .. 68
[14:40:10.049] INFO: dacScan split into 1 runs with ntrig = 25
[14:40:10.049] INFO: run 1 of 1
[14:40:10.907] INFO: Expecting 104000 events.
[14:40:27.041] INFO: 104000 events read in total (15607ms).
[14:40:27.052] INFO: Test took 17002ms.
[14:40:27.134] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:27.462] INFO: dacScan step from 69 .. 69
[14:40:27.462] INFO: dacScan split into 1 runs with ntrig = 25
[14:40:27.462] INFO: run 1 of 1
[14:40:28.305] INFO: Expecting 104000 events.
[14:40:44.122] INFO: 104000 events read in total (15290ms).
[14:40:44.133] INFO: Test took 16670ms.
[14:40:44.219] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:44.544] INFO: dacScan step from 70 .. 70
[14:40:44.544] INFO: dacScan split into 1 runs with ntrig = 25
[14:40:44.544] INFO: run 1 of 1
[14:40:45.394] INFO: Expecting 104000 events.
[14:41:00.580] INFO: 104000 events read in total (14659ms).
[14:41:00.591] INFO: Test took 16046ms.
[14:41:00.688] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:01.021] INFO: dacScan step from 71 .. 71
[14:41:01.021] INFO: dacScan split into 1 runs with ntrig = 25
[14:41:01.021] INFO: run 1 of 1
[14:41:01.880] INFO: Expecting 104000 events.
[14:41:17.358] INFO: 104000 events read in total (14951ms).
[14:41:17.368] INFO: Test took 16346ms.
[14:41:17.470] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:17.803] INFO: dacScan step from 72 .. 72
[14:41:17.803] INFO: dacScan split into 1 runs with ntrig = 25
[14:41:17.803] INFO: run 1 of 1
[14:41:18.653] INFO: Expecting 104000 events.
[14:41:34.334] INFO: 104000 events read in total (15154ms).
[14:41:34.344] INFO: Test took 16539ms.
[14:41:34.429] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:34.762] INFO: dacScan step from 73 .. 73
[14:41:34.762] INFO: dacScan split into 1 runs with ntrig = 25
[14:41:34.762] INFO: run 1 of 1
[14:41:35.613] INFO: Expecting 104000 events.
[14:41:51.524] INFO: 104000 events read in total (15384ms).
[14:41:51.534] INFO: Test took 16771ms.
[14:41:51.616] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:51.948] INFO: dacScan step from 74 .. 74
[14:41:51.948] INFO: dacScan split into 1 runs with ntrig = 25
[14:41:51.948] INFO: run 1 of 1
[14:41:52.793] INFO: Expecting 104000 events.
[14:42:08.674] INFO: 104000 events read in total (15354ms).
[14:42:08.691] INFO: Test took 16741ms.
[14:42:08.789] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:09.099] INFO: dacScan step from 75 .. 75
[14:42:09.099] INFO: dacScan split into 1 runs with ntrig = 25
[14:42:09.099] INFO: run 1 of 1
[14:42:09.937] INFO: Expecting 104000 events.
[14:42:25.907] INFO: 104000 events read in total (15443ms).
[14:42:25.918] INFO: Test took 16817ms.
[14:42:26.011] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:26.323] INFO: dacScan step from 76 .. 76
[14:42:26.323] INFO: dacScan split into 1 runs with ntrig = 25
[14:42:26.323] INFO: run 1 of 1
[14:42:27.159] INFO: Expecting 104000 events.
[14:42:43.392] INFO: 104000 events read in total (15706ms).
[14:42:43.402] INFO: Test took 17077ms.
[14:42:43.484] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:43.805] INFO: dacScan step from 77 .. 77
[14:42:43.805] INFO: dacScan split into 1 runs with ntrig = 25
[14:42:43.805] INFO: run 1 of 1
[14:42:44.631] INFO: Expecting 104000 events.
[14:43:00.749] INFO: 104000 events read in total (15591ms).
[14:43:00.759] INFO: Test took 16953ms.
[14:43:00.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:01.164] INFO: dacScan step from 78 .. 78
[14:43:01.164] INFO: dacScan split into 1 runs with ntrig = 25
[14:43:01.164] INFO: run 1 of 1
[14:43:01.993] INFO: Expecting 104000 events.
[14:43:18.243] INFO: 104000 events read in total (15723ms).
[14:43:18.253] INFO: Test took 17087ms.
[14:43:18.345] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:18.651] INFO: dacScan step from 79 .. 79
[14:43:18.651] INFO: dacScan split into 1 runs with ntrig = 25
[14:43:18.651] INFO: run 1 of 1
[14:43:19.477] INFO: Expecting 104000 events.
[14:43:36.193] INFO: 104000 events read in total (16189ms).
[14:43:36.204] INFO: Test took 17552ms.
[14:43:36.310] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:36.641] INFO: dacScan step from 80 .. 80
[14:43:36.641] INFO: dacScan split into 1 runs with ntrig = 25
[14:43:36.641] INFO: run 1 of 1
[14:43:37.483] INFO: Expecting 104000 events.
[14:43:54.090] INFO: 104000 events read in total (16080ms).
[14:43:54.100] INFO: Test took 17458ms.
[14:43:54.191] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:54.517] INFO: dacScan step from 81 .. 81
[14:43:54.517] INFO: dacScan split into 1 runs with ntrig = 25
[14:43:54.517] INFO: run 1 of 1
[14:43:55.375] INFO: Expecting 104000 events.
[14:44:11.979] INFO: 104000 events read in total (16077ms).
[14:44:11.991] INFO: Test took 17472ms.
[14:44:12.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:12.398] INFO: dacScan step from 82 .. 82
[14:44:12.398] INFO: dacScan split into 1 runs with ntrig = 25
[14:44:12.398] INFO: run 1 of 1
[14:44:13.234] INFO: Expecting 104000 events.
[14:44:30.010] INFO: 104000 events read in total (16249ms).
[14:44:30.021] INFO: Test took 17621ms.
[14:44:30.112] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:30.442] INFO: dacScan step from 83 .. 83
[14:44:30.442] INFO: dacScan split into 1 runs with ntrig = 25
[14:44:30.442] INFO: run 1 of 1
[14:44:31.284] INFO: Expecting 104000 events.
[14:44:48.015] INFO: 104000 events read in total (16203ms).
[14:44:48.028] INFO: Test took 17585ms.
[14:44:48.125] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:48.450] INFO: dacScan step from 84 .. 84
[14:44:48.450] INFO: dacScan split into 1 runs with ntrig = 25
[14:44:48.450] INFO: run 1 of 1
[14:44:49.287] INFO: Expecting 104000 events.
[14:45:05.889] INFO: 104000 events read in total (16075ms).
[14:45:05.899] INFO: Test took 17448ms.
[14:45:05.982] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:06.301] INFO: dacScan step from 85 .. 85
[14:45:06.301] INFO: dacScan split into 1 runs with ntrig = 25
[14:45:06.301] INFO: run 1 of 1
[14:45:07.156] INFO: Expecting 104000 events.
[14:45:23.524] INFO: 104000 events read in total (15841ms).
[14:45:23.534] INFO: Test took 17232ms.
[14:45:23.628] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:23.964] INFO: dacScan step from 86 .. 86
[14:45:23.964] INFO: dacScan split into 1 runs with ntrig = 25
[14:45:23.964] INFO: run 1 of 1
[14:45:24.811] INFO: Expecting 104000 events.
[14:45:40.645] INFO: 104000 events read in total (15307ms).
[14:45:40.656] INFO: Test took 16691ms.
[14:45:40.743] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:41.073] INFO: dacScan step from 87 .. 87
[14:45:41.073] INFO: dacScan split into 1 runs with ntrig = 25
[14:45:41.073] INFO: run 1 of 1
[14:45:41.926] INFO: Expecting 104000 events.
[14:45:57.959] INFO: 104000 events read in total (15506ms).
[14:45:57.969] INFO: Test took 16894ms.
[14:45:58.052] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:58.387] INFO: dacScan step from 88 .. 88
[14:45:58.387] INFO: dacScan split into 1 runs with ntrig = 25
[14:45:58.387] INFO: run 1 of 1
[14:45:59.240] INFO: Expecting 104000 events.
[14:46:15.334] INFO: 104000 events read in total (15567ms).
[14:46:15.345] INFO: Test took 16956ms.
[14:46:15.434] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:15.764] INFO: dacScan step from 89 .. 89
[14:46:15.764] INFO: dacScan split into 1 runs with ntrig = 25
[14:46:15.764] INFO: run 1 of 1
[14:46:16.614] INFO: Expecting 104000 events.
[14:46:33.005] INFO: 104000 events read in total (15863ms).
[14:46:33.015] INFO: Test took 17249ms.
[14:46:33.102] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:33.435] INFO: dacScan step from 90 .. 90
[14:46:33.435] INFO: dacScan split into 1 runs with ntrig = 25
[14:46:33.435] INFO: run 1 of 1
[14:46:34.284] INFO: Expecting 104000 events.
[14:46:50.496] INFO: 104000 events read in total (15685ms).
[14:46:50.512] INFO: Test took 17076ms.
[14:46:50.609] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:50.950] INFO: dacScan step from 91 .. 91
[14:46:50.950] INFO: dacScan split into 1 runs with ntrig = 25
[14:46:50.950] INFO: run 1 of 1
[14:46:51.801] INFO: Expecting 104000 events.
[14:47:07.722] INFO: 104000 events read in total (15394ms).
[14:47:07.732] INFO: Test took 16781ms.
[14:47:07.819] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:08.154] INFO: dacScan step from 92 .. 92
[14:47:08.155] INFO: dacScan split into 1 runs with ntrig = 25
[14:47:08.155] INFO: run 1 of 1
[14:47:09.004] INFO: Expecting 104000 events.
[14:47:24.966] INFO: 104000 events read in total (15435ms).
[14:47:24.982] INFO: Test took 16826ms.
[14:47:25.072] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:25.413] INFO: dacScan step from 93 .. 93
[14:47:25.413] INFO: dacScan split into 1 runs with ntrig = 25
[14:47:25.413] INFO: run 1 of 1
[14:47:26.259] INFO: Expecting 104000 events.
[14:47:42.218] INFO: 104000 events read in total (15432ms).
[14:47:42.228] INFO: Test took 16814ms.
[14:47:42.335] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:42.671] INFO: dacScan step from 94 .. 94
[14:47:42.671] INFO: dacScan split into 1 runs with ntrig = 25
[14:47:42.671] INFO: run 1 of 1
[14:47:43.521] INFO: Expecting 104000 events.
[14:47:59.383] INFO: 104000 events read in total (15335ms).
[14:47:59.393] INFO: Test took 16720ms.
[14:47:59.479] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:59.816] INFO: dacScan step from 95 .. 95
[14:47:59.816] INFO: dacScan split into 1 runs with ntrig = 25
[14:47:59.816] INFO: run 1 of 1
[14:48:00.662] INFO: Expecting 104000 events.
[14:48:16.083] INFO: 104000 events read in total (14894ms).
[14:48:16.094] INFO: Test took 16276ms.
[14:48:16.177] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:16.509] INFO: dacScan step from 96 .. 96
[14:48:16.509] INFO: dacScan split into 1 runs with ntrig = 25
[14:48:16.509] INFO: run 1 of 1
[14:48:17.352] INFO: Expecting 104000 events.
[14:48:33.073] INFO: 104000 events read in total (15194ms).
[14:48:33.084] INFO: Test took 16573ms.
[14:48:33.171] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:33.505] INFO: dacScan step from 97 .. 97
[14:48:33.505] INFO: dacScan split into 1 runs with ntrig = 25
[14:48:33.505] INFO: run 1 of 1
[14:48:34.352] INFO: Expecting 104000 events.
[14:48:49.722] INFO: 104000 events read in total (14843ms).
[14:48:49.732] INFO: Test took 16226ms.
[14:48:49.823] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:50.155] INFO: dacScan step from 98 .. 98
[14:48:50.155] INFO: dacScan split into 1 runs with ntrig = 25
[14:48:50.155] INFO: run 1 of 1
[14:48:51.012] INFO: Expecting 104000 events.
[14:49:06.602] INFO: 104000 events read in total (15063ms).
[14:49:06.616] INFO: Test took 16459ms.
[14:49:06.701] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:07.033] INFO: dacScan step from 99 .. 99
[14:49:07.033] INFO: dacScan split into 1 runs with ntrig = 25
[14:49:07.033] INFO: run 1 of 1
[14:49:07.882] INFO: Expecting 104000 events.
[14:49:23.344] INFO: 104000 events read in total (14935ms).
[14:49:23.357] INFO: Test took 16322ms.
[14:49:23.447] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:23.781] INFO: dacScan step from 100 .. 100
[14:49:23.781] INFO: dacScan split into 1 runs with ntrig = 25
[14:49:23.781] INFO: run 1 of 1
[14:49:24.632] INFO: Expecting 104000 events.
[14:49:39.854] INFO: 104000 events read in total (14694ms).
[14:49:39.872] INFO: Test took 16089ms.
[14:49:39.959] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:40.294] INFO: dumping ASCII scurve output file: XSCurveData
[14:49:42.460] INFO: dumping ASCII scurve output file: XSCurveData
[14:49:44.578] INFO: dumping ASCII scurve output file: XSCurveData
[14:49:46.693] INFO: dumping ASCII scurve output file: XSCurveData
[14:49:48.950] INFO: dumping ASCII scurve output file: XSCurveData
[14:49:51.117] INFO: dumping ASCII scurve output file: XSCurveData
[14:49:53.277] INFO: dumping ASCII scurve output file: XSCurveData
[14:49:55.369] INFO: dumping ASCII scurve output file: XSCurveData
[14:49:57.538] INFO: dumping ASCII scurve output file: XSCurveData
[14:49:59.753] INFO: dumping ASCII scurve output file: XSCurveData
[14:50:01.987] INFO: dumping ASCII scurve output file: XSCurveData
[14:50:04.263] INFO: dumping ASCII scurve output file: XSCurveData
[14:50:06.428] INFO: dumping ASCII scurve output file: XSCurveData
[14:50:08.658] INFO: dumping ASCII scurve output file: XSCurveData
[14:50:10.745] INFO: dumping ASCII scurve output file: XSCurveData
[14:50:12.912] INFO: dumping ASCII scurve output file: XSCurveData
[14:50:15.092] INFO: PixTest:: pg_setup set to default.
[14:50:15.095] INFO: vcal mean: 39.46 38.57 38.84 40.88 41.41 42.23 41.74 41.44 41.62 42.06 42.99 41.64 42.92 40.03 40.62 40.69
[14:50:15.095] INFO: vcal RMS: 1.48 1.68 1.43 1.63 1.55 1.60 1.62 1.56 1.63 1.65 1.67 1.67 1.60 1.60 1.43 1.53
[14:50:15.095] INFO: number of X-ray hits detected: 154915 160811 132464 196537 199298 211717 192916 193664 197818 216342 236433 218582 220831 150269 171118 171845
[14:50:15.095] INFO: number of triggers sent (total per ROC): 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000
[14:50:15.095] INFO: X-ray hit rate [MHz/cm2]: 90.8 94.3 77.7 115.2 116.8 124.1 113.1 113.5 116.0 126.8 138.6 128.1 129.5 88.1 100.3 100.7
[14:50:15.095] INFO: PixTestHighRate::doXNoiseMaps() done
[14:50:15.230] INFO: enter test to run
[14:50:15.230] INFO: test: exit no parameter change
[14:50:15.427] QUIET: Connection to board 172 closed.
[14:50:15.443] INFO: pXar: this is the end, my friend

[14:52:55.296] INFO: *** Welcome to pxar ***
[14:52:55.296] INFO: *** Today: 2015/09/08
[14:52:55.296] INFO: readRocDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[14:52:55.298] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[14:52:55.299] INFO: readMaskFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[14:52:55.299] INFO: readTrimFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[14:52:55.381] INFO: clk: 4
[14:52:55.381] INFO: ctr: 4
[14:52:55.381] INFO: sda: 19
[14:52:55.381] INFO: tin: 9
[14:52:55.381] INFO: level: 15
[14:52:55.381] INFO: triggerdelay: 0
[14:52:55.381] QUIET: Instanciating API for pxar 2.5
[14:52:55.381] INFO: Log level: INFO
[14:52:55.391] INFO: Found DTB DTB_WZ4QKL
[14:52:55.402] QUIET: Connection to board DTB_WZ4QKL opened.
[14:52:55.406] 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:52:55.409] INFO: RPC call hashes of host and DTB match: 397073690
[14:52:57.224] INFO: DUT info:
[14:52:57.224] INFO: The DUT currently contains the following objects:
[14:52:57.224] INFO: 2 TBM Cores tbm08c (2 ON)
[14:52:57.224] INFO: TBM Core alpha (0): 7 registers set
[14:52:57.224] INFO: TBM Core beta (1): 7 registers set
[14:52:57.224] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:52:57.224] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.224] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.224] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.224] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.225] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.225] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.225] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.225] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.225] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.225] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.225] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.225] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.225] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.225] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.225] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.225] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:57.627] INFO: enter 'restricted' command line mode
[14:52:57.627] INFO: enter test to run
[14:52:57.627] INFO: test: highrate no parameter change
[14:52:57.627] INFO: running: highrate
[14:52:57.637] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[14:52:57.857] INFO: ----------------------------------------------------------------------
[14:52:57.857] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:52:57.857] INFO: ----------------------------------------------------------------------
[14:52:58.002] INFO: Expecting 768 events.
[14:52:59.284] INFO: 768 events read in total (499ms).
[14:52:59.473] INFO: Test took 1610ms.
[14:53:00.329] INFO: Expecting 41600 events.
[14:53:05.267] INFO: 41600 events read in total (4411ms).
[14:53:05.271] INFO: Test took 5788ms.
[14:53:05.362] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:06.268] INFO: Expecting 41600 events.
[14:53:11.561] INFO: 41600 events read in total (4766ms).
[14:53:11.565] INFO: Test took 6146ms.
[14:53:11.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:12.554] INFO: Expecting 41600 events.
[14:53:17.709] INFO: 41600 events read in total (4628ms).
[14:53:17.713] INFO: Test took 6001ms.
[14:53:17.813] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:18.705] INFO: Expecting 41600 events.
[14:53:23.864] INFO: 41600 events read in total (4632ms).
[14:53:23.868] INFO: Test took 5997ms.
[14:53:23.962] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:24.866] INFO: Expecting 41600 events.
[14:53:30.469] INFO: 41600 events read in total (5076ms).
[14:53:30.473] INFO: Test took 6453ms.
[14:53:30.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:31.471] INFO: Expecting 41600 events.
[14:53:36.542] INFO: 41600 events read in total (4543ms).
[14:53:36.545] INFO: Test took 5913ms.
[14:53:36.651] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:37.556] INFO: Expecting 41600 events.
[14:53:42.748] INFO: 41600 events read in total (4665ms).
[14:53:42.751] INFO: Test took 6040ms.
[14:53:42.844] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:43.748] INFO: Expecting 41600 events.
[14:53:49.139] INFO: 41600 events read in total (4864ms).
[14:53:49.143] INFO: Test took 6240ms.
[14:53:49.241] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:50.153] INFO: Expecting 41600 events.
[14:53:55.381] INFO: 41600 events read in total (4701ms).
[14:53:55.384] INFO: Test took 6083ms.
[14:53:55.482] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:56.401] INFO: Expecting 41600 events.
[14:54:01.688] INFO: 41600 events read in total (4760ms).
[14:54:01.692] INFO: Test took 6152ms.
[14:54:01.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:02.710] INFO: Expecting 41600 events.
[14:54:07.989] INFO: 41600 events read in total (4751ms).
[14:54:07.992] INFO: Test took 6136ms.
[14:54:08.088] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:08.988] INFO: Expecting 41600 events.
[14:54:13.881] INFO: 41600 events read in total (4366ms).
[14:54:13.884] INFO: Test took 5738ms.
[14:54:13.986] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:14.888] INFO: Expecting 41600 events.
[14:54:20.071] INFO: 41600 events read in total (4656ms).
[14:54:20.075] INFO: Test took 6029ms.
[14:54:20.169] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:21.068] INFO: Expecting 41600 events.
[14:54:26.357] INFO: 41600 events read in total (4762ms).
[14:54:26.360] INFO: Test took 6135ms.
[14:54:26.455] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:27.361] INFO: Expecting 41600 events.
[14:54:32.659] INFO: 41600 events read in total (4771ms).
[14:54:32.666] INFO: Test took 6152ms.
[14:54:32.765] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:33.677] INFO: Expecting 41600 events.
[14:54:38.781] INFO: 41600 events read in total (4577ms).
[14:54:38.784] INFO: Test took 5961ms.
[14:54:38.879] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:39.795] INFO: Expecting 41600 events.
[14:54:45.146] INFO: 41600 events read in total (4824ms).
[14:54:45.150] INFO: Test took 6212ms.
[14:54:45.246] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:46.144] INFO: Expecting 41600 events.
[14:54:51.388] INFO: 41600 events read in total (4717ms).
[14:54:51.392] INFO: Test took 6086ms.
[14:54:51.486] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:52.393] INFO: Expecting 41600 events.
[14:54:57.588] INFO: 41600 events read in total (4668ms).
[14:54:57.592] INFO: Test took 6049ms.
[14:54:57.688] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:58.593] INFO: Expecting 41600 events.
[14:55:03.592] INFO: 41600 events read in total (4472ms).
[14:55:03.595] INFO: Test took 5850ms.
[14:55:03.690] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:04.143] INFO: enter test to run
[14:55:04.143] INFO: test: savedacs no parameter change
[14:55:04.143] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat
[14:55:04.143] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C1.dat
[14:55:04.143] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C2.dat
[14:55:04.143] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C3.dat
[14:55:04.143] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C4.dat
[14:55:04.144] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C5.dat
[14:55:04.144] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C6.dat
[14:55:04.144] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C7.dat
[14:55:04.144] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C8.dat
[14:55:04.144] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C9.dat
[14:55:04.144] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C10.dat
[14:55:04.144] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C11.dat
[14:55:04.144] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C12.dat
[14:55:04.144] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C13.dat
[14:55:04.145] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C14.dat
[14:55:04.145] INFO: write dac parameters into /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[14:55:04.145] INFO: enter test to run
[14:55:04.145] INFO: test: exit no parameter change
[14:55:04.240] QUIET: Connection to board 172 closed.
[14:55:04.256] INFO: pXar: this is the end, my friend

[14:55:13.286] INFO: *** Welcome to pxar ***
[14:55:13.286] INFO: *** Today: 2015/09/08
[14:55:13.286] INFO: readRocDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[14:55:13.288] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[14:55:13.289] INFO: readMaskFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[14:55:13.289] INFO: readTrimFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[14:55:13.371] INFO: clk: 4
[14:55:13.371] INFO: ctr: 4
[14:55:13.371] INFO: sda: 19
[14:55:13.371] INFO: tin: 9
[14:55:13.371] INFO: level: 15
[14:55:13.371] INFO: triggerdelay: 0
[14:55:13.371] QUIET: Instanciating API for pxar 2.5
[14:55:13.371] INFO: Log level: INFO
[14:55:13.377] INFO: Found DTB DTB_WZ4QKL
[14:55:13.389] QUIET: Connection to board DTB_WZ4QKL opened.
[14:55:13.393] 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:55:13.396] INFO: RPC call hashes of host and DTB match: 397073690
[14:55:15.214] INFO: DUT info:
[14:55:15.214] INFO: The DUT currently contains the following objects:
[14:55:15.214] INFO: 2 TBM Cores tbm08c (2 ON)
[14:55:15.214] INFO: TBM Core alpha (0): 7 registers set
[14:55:15.214] INFO: TBM Core beta (1): 7 registers set
[14:55:15.214] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:55:15.214] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.214] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.214] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.214] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.214] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.215] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.215] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.215] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.215] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.215] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.215] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.215] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.215] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.215] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.215] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.215] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:15.617] INFO: enter 'restricted' command line mode
[14:55:15.617] INFO: enter test to run
[14:55:15.617] INFO: test: highrate no parameter change
[14:55:15.617] INFO: running: highrate
[14:55:15.627] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[14:55:15.860] INFO: ----------------------------------------------------------------------
[14:55:15.860] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:55:15.860] INFO: ----------------------------------------------------------------------
[14:55:16.721] INFO: Expecting 208000 events.
[14:55:40.235] INFO: 208000 events read in total (22983ms).
[14:55:40.249] INFO: Test took 24382ms.
[14:55:40.693] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:41.220] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:55:41.220] INFO: number of red-efficiency pixels: 299 246 251 337 425 432 461 392 358 434 526 451 437 262 303 351
[14:55:41.220] INFO: number of X-ray hits detected: 156165 160903 132857 196884 201147 213006 193417 194522 198137 215587 238304 218283 222680 149966 171596 173888
[14:55:41.220] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:55:41.220] INFO: number of Vcal hits detected: 207691 207741 207731 207652 207547 207534 207499 207586 207629 207542 207431 207514 207531 207725 207682 207629
[14:55:41.220] 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.7 99.8 99.8 99.9 99.9 99.8
[14:55:41.221] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.7 99.8 99.8 99.9 99.8 99.8
[14:55:41.221] INFO: X-ray hit rate [MHz/cm2]: 45.8 47.2 38.9 57.7 59.0 62.4 56.7 57.0 58.1 63.2 69.8 64.0 65.3 44.0 50.3 51.0
[14:55:41.221] INFO: PixTestHighRate::doXPixelAlive() done
[14:55:41.270] INFO: PixTest:: pg_setup set to default.
[14:55:41.321] INFO: enter test to run
[14:55:41.321] INFO: test: exit no parameter change
[14:55:41.393] QUIET: Connection to board 172 closed.
[14:55:41.409] INFO: pXar: this is the end, my friend

[14:55:47.649] INFO: *** Welcome to pxar ***
[14:55:47.649] INFO: *** Today: 2015/09/08
[14:55:47.649] INFO: readRocDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[14:55:47.652] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[14:55:47.660] INFO: readMaskFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[14:55:47.660] INFO: readTrimFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[14:55:47.749] INFO: clk: 4
[14:55:47.749] INFO: ctr: 4
[14:55:47.749] INFO: sda: 19
[14:55:47.749] INFO: tin: 9
[14:55:47.749] INFO: level: 15
[14:55:47.749] INFO: triggerdelay: 0
[14:55:47.749] QUIET: Instanciating API for pxar 2.5
[14:55:47.749] INFO: Log level: INFO
[14:55:47.756] INFO: Found DTB DTB_WZ4QKL
[14:55:47.768] QUIET: Connection to board DTB_WZ4QKL opened.
[14:55:47.772] 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:55:47.776] INFO: RPC call hashes of host and DTB match: 397073690
[14:55:49.592] INFO: DUT info:
[14:55:49.592] INFO: The DUT currently contains the following objects:
[14:55:49.592] INFO: 2 TBM Cores tbm08c (2 ON)
[14:55:49.593] INFO: TBM Core alpha (0): 7 registers set
[14:55:49.593] INFO: TBM Core beta (1): 7 registers set
[14:55:49.593] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:55:49.593] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.593] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.593] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.593] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.593] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.593] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.593] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.593] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.593] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.593] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.593] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.594] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.594] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.594] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.594] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.594] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:49.996] INFO: enter 'restricted' command line mode
[14:55:49.996] INFO: enter test to run
[14:55:49.996] INFO: test: highrate no parameter change
[14:55:49.996] INFO: running: highrate
[14:55:50.005] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[14:55:50.242] INFO: ----------------------------------------------------------------------
[14:55:50.242] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:55:50.242] INFO: ----------------------------------------------------------------------
[14:55:51.102] INFO: Expecting 208000 events.
[14:56:23.771] INFO: 208000 events read in total (32138ms).
[14:56:23.796] INFO: Test took 33547ms.
[14:56:24.702] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:56:25.452] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:56:25.452] INFO: number of red-efficiency pixels: 980 808 872 1279 1576 1464 1592 1248 1256 1484 1832 1578 1740 858 1016 1262
[14:56:25.452] INFO: number of X-ray hits detected: 309651 320318 264705 391252 398067 423316 382012 386558 392863 428123 470856 430757 438537 297273 340203 342585
[14:56:25.452] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:56:25.452] INFO: number of Vcal hits detected: 206833 207068 206750 206354 205776 206033 205697 206442 206430 205855 205353 205710 205508 206866 206790 206383
[14:56:25.452] INFO: Vcal hit fiducial efficiency (%): 99.5 99.6 99.5 99.3 99.0 99.1 99.0 99.3 99.3 99.1 98.9 99.0 98.9 99.5 99.5 99.3
[14:56:25.452] INFO: Vcal hit overall efficiency (%): 99.4 99.6 99.4 99.2 98.9 99.1 98.9 99.3 99.2 99.0 98.7 98.9 98.8 99.5 99.4 99.2
[14:56:25.452] INFO: X-ray hit rate [MHz/cm2]: 90.8 93.9 77.6 114.7 116.7 124.1 112.0 113.3 115.2 125.5 138.0 126.3 128.5 87.1 99.7 100.4
[14:56:25.452] INFO: PixTestHighRate::doXPixelAlive() done
[14:56:25.500] INFO: PixTest:: pg_setup set to default.
[14:56:25.559] INFO: enter test to run
[14:56:25.559] INFO: test: exit no parameter change
[14:56:25.679] QUIET: Connection to board 172 closed.
[14:56:25.704] INFO: pXar: this is the end, my friend

[14:56:32.852] INFO: *** Welcome to pxar ***
[14:56:32.852] INFO: *** Today: 2015/09/08
[14:56:32.852] INFO: readRocDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[14:56:32.854] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[14:56:32.855] INFO: readMaskFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[14:56:32.855] INFO: readTrimFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[14:56:32.932] INFO: clk: 4
[14:56:32.932] INFO: ctr: 4
[14:56:32.932] INFO: sda: 19
[14:56:32.932] INFO: tin: 9
[14:56:32.932] INFO: level: 15
[14:56:32.932] INFO: triggerdelay: 0
[14:56:32.932] QUIET: Instanciating API for pxar 2.5
[14:56:32.932] INFO: Log level: INFO
[14:56:32.940] INFO: Found DTB DTB_WZ4QKL
[14:56:32.952] QUIET: Connection to board DTB_WZ4QKL opened.
[14:56:32.956] 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:56:32.959] INFO: RPC call hashes of host and DTB match: 397073690
[14:56:34.776] INFO: DUT info:
[14:56:34.776] INFO: The DUT currently contains the following objects:
[14:56:34.776] INFO: 2 TBM Cores tbm08c (2 ON)
[14:56:34.776] INFO: TBM Core alpha (0): 7 registers set
[14:56:34.776] INFO: TBM Core beta (1): 7 registers set
[14:56:34.776] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:56:34.776] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.776] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.777] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.777] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.777] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.777] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.777] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.777] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.777] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.777] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.777] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.777] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.777] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.777] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.777] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:34.777] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:56:35.179] INFO: enter 'restricted' command line mode
[14:56:35.179] INFO: enter test to run
[14:56:35.180] INFO: test: highrate no parameter change
[14:56:35.180] INFO: running: highrate
[14:56:35.188] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[14:56:35.415] INFO: ----------------------------------------------------------------------
[14:56:35.415] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:56:35.415] INFO: ----------------------------------------------------------------------
[14:56:36.279] INFO: Expecting 208000 events.
[14:57:18.815] INFO: 208000 events read in total (42006ms).
[14:57:18.849] INFO: Test took 43426ms.
[14:57:20.155] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:57:21.119] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:57:21.120] INFO: number of red-efficiency pixels: 2065 1937 1695 2675 3053 2865 3012 2673 2673 2909 3324 3106 3208 1666 2226 2664
[14:57:21.120] INFO: number of X-ray hits detected: 460524 475409 393065 583040 592184 628378 569708 574282 585071 637086 698078 642459 651466 441852 506647 511078
[14:57:21.120] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:57:21.120] INFO: number of Vcal hits detected: 204755 205218 204269 202967 200987 201927 201143 203248 203060 201144 199509 200651 200367 204659 204593 202887
[14:57:21.120] INFO: Vcal hit fiducial efficiency (%): 98.6 98.8 98.5 97.9 97.0 97.4 97.1 97.9 97.9 97.1 96.4 96.9 96.7 98.6 98.5 97.8
[14:57:21.120] INFO: Vcal hit overall efficiency (%): 98.4 98.7 98.2 97.6 96.6 97.1 96.7 97.7 97.6 96.7 95.9 96.5 96.3 98.4 98.4 97.5
[14:57:21.120] INFO: X-ray hit rate [MHz/cm2]: 135.0 139.3 115.2 170.9 173.6 184.2 167.0 168.3 171.5 186.7 204.6 188.3 190.9 129.5 148.5 149.8
[14:57:21.120] INFO: PixTestHighRate::doXPixelAlive() done
[14:57:21.165] INFO: PixTest:: pg_setup set to default.
[14:57:21.219] INFO: enter test to run
[14:57:21.219] INFO: test: exit no parameter change
[14:57:21.311] QUIET: Connection to board 172 closed.
[14:57:21.339] INFO: pXar: this is the end, my friend

[14:57:28.878] INFO: *** Welcome to pxar ***
[14:57:28.878] INFO: *** Today: 2015/09/08
[14:57:28.878] INFO: readRocDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[14:57:28.881] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[14:57:28.881] INFO: readMaskFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[14:57:28.881] INFO: readTrimFile: /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[14:57:28.961] INFO: clk: 4
[14:57:28.961] INFO: ctr: 4
[14:57:28.961] INFO: sda: 19
[14:57:28.961] INFO: tin: 9
[14:57:28.961] INFO: level: 15
[14:57:28.961] INFO: triggerdelay: 0
[14:57:28.961] QUIET: Instanciating API for pxar 2.5
[14:57:28.961] INFO: Log level: INFO
[14:57:28.972] INFO: Found DTB DTB_WZ4QKL
[14:57:28.989] QUIET: Connection to board DTB_WZ4QKL opened.
[14:57:28.993] 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:57:28.997] INFO: RPC call hashes of host and DTB match: 397073690
[14:57:30.814] INFO: DUT info:
[14:57:30.814] INFO: The DUT currently contains the following objects:
[14:57:30.814] INFO: 2 TBM Cores tbm08c (2 ON)
[14:57:30.814] INFO: TBM Core alpha (0): 7 registers set
[14:57:30.814] INFO: TBM Core beta (1): 7 registers set
[14:57:30.814] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:57:30.814] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.814] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.814] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.814] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.814] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.814] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.815] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.815] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.815] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.815] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.815] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.815] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.815] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.815] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.815] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.815] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:31.217] INFO: enter 'restricted' command line mode
[14:57:31.217] INFO: enter test to run
[14:57:31.217] INFO: test: highrate no parameter change
[14:57:31.217] INFO: running: highrate
[14:57:31.226] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[14:57:31.455] INFO: ----------------------------------------------------------------------
[14:57:31.455] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:57:31.455] INFO: ----------------------------------------------------------------------
[14:57:32.318] INFO: Expecting 208000 events.
[14:58:24.641] INFO: 208000 events read in total (51792ms).
[14:58:24.684] INFO: Test took 53222ms.
[14:58:26.358] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:27.485] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:58:27.486] INFO: number of red-efficiency pixels: 3189 3039 2364 3673 3936 3878 3879 3731 3782 3884 4063 3930 3978 2398 3313 3712
[14:58:27.486] INFO: number of X-ray hits detected: 612422 636825 520548 774596 789778 836160 758572 762140 778350 846350 927860 850726 864268 585168 670901 675777
[14:58:27.486] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:58:27.486] INFO: number of Vcal hits detected: 200386 201652 199788 196011 191452 193331 191951 196783 196116 191881 188013 191289 190077 200147 200136 196389
[14:58:27.486] INFO: Vcal hit fiducial efficiency (%): 96.7 97.2 96.6 94.9 92.9 93.7 93.1 95.1 94.8 93.1 91.3 92.9 92.2 96.8 96.5 95.0
[14:58:27.486] INFO: Vcal hit overall efficiency (%): 96.3 96.9 96.1 94.2 92.0 92.9 92.3 94.6 94.3 92.3 90.4 92.0 91.4 96.2 96.2 94.4
[14:58:27.486] INFO: X-ray hit rate [MHz/cm2]: 179.5 186.7 152.6 227.0 231.5 245.1 222.3 223.4 228.1 248.1 272.0 249.4 253.3 171.5 196.6 198.1
[14:58:27.486] INFO: PixTestHighRate::doXPixelAlive() done
[14:58:27.532] INFO: PixTest:: pg_setup set to default.
[14:58:27.586] INFO: enter test to run
[14:58:27.586] INFO: test: exit no parameter change
[14:58:27.699] QUIET: Connection to board 172 closed.
[14:58:27.721] INFO: pXar: this is the end, my friend