[15:00:34.053] INFO: *** Welcome to pxar ***
[15:00:34.053] INFO: *** Today: 2015/09/07
[15:00:34.053] INFO: readRocDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[15:00:34.055] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[15:00:34.055] INFO: readMaskFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[15:00:34.055] INFO: readTrimFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[15:00:34.146] INFO: clk: 4
[15:00:34.146] INFO: ctr: 4
[15:00:34.146] INFO: sda: 19
[15:00:34.146] INFO: tin: 9
[15:00:34.146] INFO: level: 15
[15:00:34.147] INFO: triggerdelay: 0
[15:00:34.147] QUIET: Instanciating API for pxar 2.5
[15:00:34.147] INFO: Log level: INFO
[15:00:34.154] INFO: Found DTB DTB_WZ4QKL
[15:00:34.166] QUIET: Connection to board DTB_WZ4QKL opened.
[15:00:34.170] 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:00:34.173] INFO: RPC call hashes of host and DTB match: 397073690
[15:00:35.994] INFO: DUT info:
[15:00:35.994] INFO: The DUT currently contains the following objects:
[15:00:35.994] INFO: 2 TBM Cores tbm08c (2 ON)
[15:00:35.994] INFO: TBM Core alpha (0): 7 registers set
[15:00:35.995] INFO: TBM Core beta (1): 7 registers set
[15:00:35.995] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:00:35.995] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.995] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.995] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.995] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.995] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.995] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.995] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.995] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.995] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.995] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.995] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.995] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.995] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.996] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.996] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:35.996] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:36.406] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[15:00:36.630] INFO: ######################################################################
[15:00:36.630] INFO: PixTestXray::doTest()
[15:00:36.630] INFO: ######################################################################
[15:00:36.630] INFO: ----------------------------------------------------------------------
[15:00:36.630] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:00:36.630] INFO: ----------------------------------------------------------------------
[15:00:37.641] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:00:41.523] INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.


















[15:01:41.222] INFO: Resuming triggers.
[15:01:45.124] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:02:43.406] INFO: Resuming triggers.
[15:02:47.291] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:03:46.258] INFO: Resuming triggers.
[15:03:50.156] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:04:48.174] INFO: Resuming triggers.
[15:04:52.053] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[15:05:49.723] INFO: Resuming triggers.
[15:05:53.603] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:06:49.612] INFO: Resuming triggers.
[15:06:53.495] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.






[15:07:50.195] INFO: Resuming triggers.
[15:07:54.076] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.




[15:08:48.159] INFO: Resuming triggers.
[15:08:52.039] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:09:46.275] INFO: Resuming triggers.
[15:09:50.151] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.








[15:10:43.692] INFO: Resuming triggers.
[15:10:47.572] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.

[15:11:41.754] INFO: Resuming triggers.
[15:11:45.631] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:12:39.283] INFO: Resuming triggers.
[15:12:43.163] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.







[15:13:36.684] INFO: Resuming triggers.
[15:13:40.561] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[15:14:34.224] INFO: Resuming triggers.
[15:14:38.101] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[15:15:32.067] INFO: Resuming triggers.
[15:15:35.944] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[15:16:30.005] INFO: Resuming triggers.
[15:16:33.881] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[15:17:27.736] INFO: Resuming triggers.
[15:17:31.616] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:18:22.499] INFO: Resuming triggers.
[15:18:26.377] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[15:19:16.803] INFO: Resuming triggers.
[15:19:20.680] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[15:20:10.943] INFO: Resuming triggers.
[15:20:14.822] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[15:21:05.229] INFO: Resuming triggers.
[15:21:09.107] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[15:21:58.400] INFO: Resuming triggers.
[15:22:02.279] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.

[15:22:52.464] INFO: Resuming triggers.
[15:22:56.341] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[15:23:46.698] INFO: Resuming triggers.
[15:23:50.576] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[15:24:41.018] INFO: Resuming triggers.
[15:24:44.269] INFO: data taking finished, elapsed time: 100 seconds.




[15:25:27.001] INFO: PixTest:: pg_setup set to default.
[15:25:27.003] INFO: PixTestXray::doPhRun() done
[15:25:27.003] INFO: PixTestXray::doTest() done
[15:25:27.383] QUIET: Connection to board 172 closed.
[15:25:27.403] INFO: pXar: this is the end, my friend

[15:25:33.074] INFO: *** Welcome to pxar ***
[15:25:33.074] INFO: *** Today: 2015/09/07
[15:25:33.074] INFO: readRocDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[15:25:33.076] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[15:25:33.077] INFO: readMaskFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[15:25:33.077] INFO: readTrimFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[15:25:33.158] INFO: clk: 4
[15:25:33.158] INFO: ctr: 4
[15:25:33.158] INFO: sda: 19
[15:25:33.158] INFO: tin: 9
[15:25:33.158] INFO: level: 15
[15:25:33.158] INFO: triggerdelay: 0
[15:25:33.158] QUIET: Instanciating API for pxar 2.5
[15:25:33.158] INFO: Log level: INFO
[15:25:33.166] INFO: Found DTB DTB_WZ4QKL
[15:25:33.178] QUIET: Connection to board DTB_WZ4QKL opened.
[15:25:33.182] 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:25:33.185] INFO: RPC call hashes of host and DTB match: 397073690
[15:25:35.014] INFO: DUT info:
[15:25:35.014] INFO: The DUT currently contains the following objects:
[15:25:35.014] INFO: 2 TBM Cores tbm08c (2 ON)
[15:25:35.014] INFO: TBM Core alpha (0): 7 registers set
[15:25:35.014] INFO: TBM Core beta (1): 7 registers set
[15:25:35.014] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:25:35.014] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.014] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.014] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.014] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.014] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.015] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.015] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.015] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.015] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.015] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.015] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.015] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.015] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.015] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.015] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.015] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:35.426] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[15:25:35.652] INFO: ######################################################################
[15:25:35.652] INFO: PixTestXray::doTest()
[15:25:35.652] INFO: ######################################################################
[15:25:35.652] INFO: ----------------------------------------------------------------------
[15:25:35.652] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:25:35.652] INFO: ----------------------------------------------------------------------
[15:25:36.663] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:25:44.622] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:26:41.489] INFO: Resuming triggers.
[15:26:49.462] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:27:46.202] INFO: Resuming triggers.
[15:27:54.159] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:28:50.084] INFO: Resuming triggers.
[15:28:58.025] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[15:29:54.575] INFO: Resuming triggers.
[15:30:02.519] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[15:30:58.279] INFO: Resuming triggers.
[15:31:06.227] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[15:32:02.516] INFO: Resuming triggers.
[15:32:10.473] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[15:33:06.050] INFO: Resuming triggers.
[15:33:14.019] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[15:34:07.900] INFO: Resuming triggers.
[15:34:15.849] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[15:35:07.041] INFO: Resuming triggers.
[15:35:14.988] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[15:36:05.204] INFO: Resuming triggers.
[15:36:13.156] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[15:37:03.254] INFO: Resuming triggers.
[15:37:11.191] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[15:38:01.288] INFO: Resuming triggers.
[15:38:06.126] INFO: data taking finished, elapsed time: 100 seconds.
[15:38:33.774] INFO: PixTest:: pg_setup set to default.
[15:38:33.776] INFO: PixTestXray::doPhRun() done
[15:38:33.776] INFO: PixTestXray::doTest() done
[15:38:34.165] QUIET: Connection to board 172 closed.
[15:38:34.181] INFO: pXar: this is the end, my friend

[15:39:47.827] INFO: *** Welcome to pxar ***
[15:39:47.827] INFO: *** Today: 2015/09/07
[15:39:47.828] INFO: readRocDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[15:39:47.830] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[15:39:47.830] INFO: readMaskFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[15:39:47.830] INFO: readTrimFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[15:39:47.911] INFO: clk: 4
[15:39:47.911] INFO: ctr: 4
[15:39:47.911] INFO: sda: 19
[15:39:47.911] INFO: tin: 9
[15:39:47.911] INFO: level: 15
[15:39:47.911] INFO: triggerdelay: 0
[15:39:47.911] QUIET: Instanciating API for pxar 2.5
[15:39:47.911] INFO: Log level: INFO
[15:39:47.918] INFO: Found DTB DTB_WZ4QKL
[15:39:47.930] QUIET: Connection to board DTB_WZ4QKL opened.
[15:39:47.934] 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:39:47.938] INFO: RPC call hashes of host and DTB match: 397073690
[15:39:49.760] INFO: DUT info:
[15:39:49.760] INFO: The DUT currently contains the following objects:
[15:39:49.760] INFO: 2 TBM Cores tbm08c (2 ON)
[15:39:49.760] INFO: TBM Core alpha (0): 7 registers set
[15:39:49.760] INFO: TBM Core beta (1): 7 registers set
[15:39:49.760] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:39:49.760] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.760] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.760] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.760] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.761] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.761] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.761] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.761] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.761] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.761] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.761] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.761] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.761] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.761] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.761] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:49.761] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:50.163] INFO: enter 'restricted' command line mode
[15:39:50.163] INFO: enter test to run
[15:39:50.163] INFO: test: highrate no parameter change
[15:39:50.163] INFO: running: highrate
[15:39:50.172] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[15:39:50.418] INFO: ----------------------------------------------------------------------
[15:39:50.418] INFO: PixTestHighRate::xNoiseMaps() ntrig = 25, vcal = 200
[15:39:50.418] INFO: ----------------------------------------------------------------------
[15:39:50.427] INFO: ---> dac: vcal name: xNoiseMap ntrig: 25 dacrange: 0 .. 100 (1/-1) hits flags = 384 (plus default)
[15:39:50.471] INFO: dacScan step from 0 .. 0
[15:39:50.472] INFO: dacScan split into 1 runs with ntrig = 25
[15:39:50.472] INFO: run 1 of 1
[15:39:51.314] INFO: Expecting 104000 events.
[15:40:03.912] INFO: 104000 events read in total (12067ms).
[15:40:03.926] INFO: Test took 13454ms.
[15:40:04.013] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:04.301] INFO: dacScan step from 1 .. 1
[15:40:04.301] INFO: dacScan split into 1 runs with ntrig = 25
[15:40:04.301] INFO: run 1 of 1
[15:40:05.150] INFO: Expecting 104000 events.
[15:40:18.051] INFO: 104000 events read in total (12374ms).
[15:40:18.061] INFO: Test took 13759ms.
[15:40:18.156] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:18.447] INFO: dacScan step from 2 .. 2
[15:40:18.447] INFO: dacScan split into 1 runs with ntrig = 25
[15:40:18.447] INFO: run 1 of 1
[15:40:19.278] INFO: Expecting 104000 events.
[15:40:31.674] INFO: 104000 events read in total (11869ms).
[15:40:31.684] INFO: Test took 13236ms.
[15:40:31.777] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:32.087] INFO: dacScan step from 3 .. 3
[15:40:32.087] INFO: dacScan split into 1 runs with ntrig = 25
[15:40:32.087] INFO: run 1 of 1
[15:40:32.928] INFO: Expecting 104000 events.
[15:40:45.645] INFO: 104000 events read in total (12190ms).
[15:40:45.656] INFO: Test took 13568ms.
[15:40:45.747] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:46.045] INFO: dacScan step from 4 .. 4
[15:40:46.045] INFO: dacScan split into 1 runs with ntrig = 25
[15:40:46.045] INFO: run 1 of 1
[15:40:46.875] INFO: Expecting 104000 events.
[15:40:59.309] INFO: 104000 events read in total (11907ms).
[15:40:59.319] INFO: Test took 13274ms.
[15:40:59.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:59.698] INFO: dacScan step from 5 .. 5
[15:40:59.698] INFO: dacScan split into 1 runs with ntrig = 25
[15:40:59.698] INFO: run 1 of 1
[15:41:00.532] INFO: Expecting 104000 events.
[15:41:13.051] INFO: 104000 events read in total (11992ms).
[15:41:13.060] INFO: Test took 13361ms.
[15:41:13.152] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:13.443] INFO: dacScan step from 6 .. 6
[15:41:13.443] INFO: dacScan split into 1 runs with ntrig = 25
[15:41:13.443] INFO: run 1 of 1
[15:41:14.273] INFO: Expecting 104000 events.
[15:41:26.615] INFO: 104000 events read in total (11815ms).
[15:41:26.625] INFO: Test took 13181ms.
[15:41:26.717] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:27.010] INFO: dacScan step from 7 .. 7
[15:41:27.010] INFO: dacScan split into 1 runs with ntrig = 25
[15:41:27.010] INFO: run 1 of 1
[15:41:27.842] INFO: Expecting 104000 events.
[15:41:40.789] INFO: 104000 events read in total (12420ms).
[15:41:40.801] INFO: Test took 13791ms.
[15:41:40.888] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:41.191] INFO: dacScan step from 8 .. 8
[15:41:41.191] INFO: dacScan split into 1 runs with ntrig = 25
[15:41:41.191] INFO: run 1 of 1
[15:41:42.020] INFO: Expecting 104000 events.
[15:41:54.883] INFO: 104000 events read in total (12336ms).
[15:41:54.892] INFO: Test took 13701ms.
[15:41:54.981] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:55.271] INFO: dacScan step from 9 .. 9
[15:41:55.271] INFO: dacScan split into 1 runs with ntrig = 25
[15:41:55.271] INFO: run 1 of 1
[15:41:56.102] INFO: Expecting 104000 events.
[15:42:08.462] INFO: 104000 events read in total (11833ms).
[15:42:08.475] INFO: Test took 13203ms.
[15:42:08.576] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:08.881] INFO: dacScan step from 10 .. 10
[15:42:08.881] INFO: dacScan split into 1 runs with ntrig = 25
[15:42:08.881] INFO: run 1 of 1
[15:42:09.726] INFO: Expecting 104000 events.
[15:42:22.665] INFO: 104000 events read in total (12412ms).
[15:42:22.674] INFO: Test took 13792ms.
[15:42:22.766] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:23.062] INFO: dacScan step from 11 .. 11
[15:42:23.062] INFO: dacScan split into 1 runs with ntrig = 25
[15:42:23.062] INFO: run 1 of 1
[15:42:23.890] INFO: Expecting 104000 events.
[15:42:36.487] INFO: 104000 events read in total (12070ms).
[15:42:36.496] INFO: Test took 13434ms.
[15:42:36.583] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:36.871] INFO: dacScan step from 12 .. 12
[15:42:36.871] INFO: dacScan split into 1 runs with ntrig = 25
[15:42:36.871] INFO: run 1 of 1
[15:42:37.703] INFO: Expecting 104000 events.
[15:42:50.211] INFO: 104000 events read in total (11981ms).
[15:42:50.221] INFO: Test took 13350ms.
[15:42:50.307] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:50.593] INFO: dacScan step from 13 .. 13
[15:42:50.593] INFO: dacScan split into 1 runs with ntrig = 25
[15:42:50.593] INFO: run 1 of 1
[15:42:51.414] INFO: Expecting 104000 events.
[15:43:03.577] INFO: 104000 events read in total (11636ms).
[15:43:03.586] INFO: Test took 12992ms.
[15:43:03.672] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:03.964] INFO: dacScan step from 14 .. 14
[15:43:03.964] INFO: dacScan split into 1 runs with ntrig = 25
[15:43:03.964] INFO: run 1 of 1
[15:43:04.789] INFO: Expecting 104000 events.
[15:43:17.532] INFO: 104000 events read in total (12216ms).
[15:43:17.542] INFO: Test took 13578ms.
[15:43:17.631] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:17.928] INFO: dacScan step from 15 .. 15
[15:43:17.928] INFO: dacScan split into 1 runs with ntrig = 25
[15:43:17.928] INFO: run 1 of 1
[15:43:18.754] INFO: Expecting 104000 events.
[15:43:31.319] INFO: 104000 events read in total (12038ms).
[15:43:31.328] INFO: Test took 13399ms.
[15:43:31.411] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:31.698] INFO: dacScan step from 16 .. 16
[15:43:31.698] INFO: dacScan split into 1 runs with ntrig = 25
[15:43:31.698] INFO: run 1 of 1
[15:43:32.525] INFO: Expecting 104000 events.
[15:43:44.946] INFO: 104000 events read in total (11894ms).
[15:43:44.955] INFO: Test took 13257ms.
[15:43:45.054] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:45.346] INFO: dacScan step from 17 .. 17
[15:43:45.346] INFO: dacScan split into 1 runs with ntrig = 25
[15:43:45.346] INFO: run 1 of 1
[15:43:46.175] INFO: Expecting 104000 events.
[15:43:58.753] INFO: 104000 events read in total (12051ms).
[15:43:58.762] INFO: Test took 13415ms.
[15:43:58.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:59.137] INFO: dacScan step from 18 .. 18
[15:43:59.137] INFO: dacScan split into 1 runs with ntrig = 25
[15:43:59.137] INFO: run 1 of 1
[15:43:59.973] INFO: Expecting 104000 events.
[15:44:12.416] INFO: 104000 events read in total (11916ms).
[15:44:12.426] INFO: Test took 13288ms.
[15:44:12.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:12.804] INFO: dacScan step from 19 .. 19
[15:44:12.804] INFO: dacScan split into 1 runs with ntrig = 25
[15:44:12.804] INFO: run 1 of 1
[15:44:13.633] INFO: Expecting 104000 events.
[15:44:26.231] INFO: 104000 events read in total (12071ms).
[15:44:26.241] INFO: Test took 13436ms.
[15:44:26.326] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:26.618] INFO: dacScan step from 20 .. 20
[15:44:26.618] INFO: dacScan split into 1 runs with ntrig = 25
[15:44:26.618] INFO: run 1 of 1
[15:44:27.447] INFO: Expecting 104000 events.
[15:44:39.793] INFO: 104000 events read in total (11819ms).
[15:44:39.803] INFO: Test took 13184ms.
[15:44:39.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:40.186] INFO: dacScan step from 21 .. 21
[15:44:40.186] INFO: dacScan split into 1 runs with ntrig = 25
[15:44:40.186] INFO: run 1 of 1
[15:44:41.013] INFO: Expecting 104000 events.
[15:44:53.530] INFO: 104000 events read in total (11990ms).
[15:44:53.539] INFO: Test took 13352ms.
[15:44:53.627] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:53.918] INFO: dacScan step from 22 .. 22
[15:44:53.918] INFO: dacScan split into 1 runs with ntrig = 25
[15:44:53.918] INFO: run 1 of 1
[15:44:54.752] INFO: Expecting 104000 events.
[15:45:07.148] INFO: 104000 events read in total (11869ms).
[15:45:07.157] INFO: Test took 13238ms.
[15:45:07.252] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:07.551] INFO: dacScan step from 23 .. 23
[15:45:07.551] INFO: dacScan split into 1 runs with ntrig = 25
[15:45:07.551] INFO: run 1 of 1
[15:45:08.380] INFO: Expecting 104000 events.
[15:45:20.710] INFO: 104000 events read in total (11803ms).
[15:45:20.720] INFO: Test took 13168ms.
[15:45:20.807] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:21.095] INFO: dacScan step from 24 .. 24
[15:45:21.095] INFO: dacScan split into 1 runs with ntrig = 25
[15:45:21.095] INFO: run 1 of 1
[15:45:21.928] INFO: Expecting 104000 events.
[15:45:33.950] INFO: 104000 events read in total (11495ms).
[15:45:33.960] INFO: Test took 12864ms.
[15:45:34.055] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:34.344] INFO: dacScan step from 25 .. 25
[15:45:34.344] INFO: dacScan split into 1 runs with ntrig = 25
[15:45:34.344] INFO: run 1 of 1
[15:45:35.178] INFO: Expecting 104000 events.
[15:45:47.312] INFO: 104000 events read in total (11607ms).
[15:45:47.322] INFO: Test took 12977ms.
[15:45:47.417] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:47.707] INFO: dacScan step from 26 .. 26
[15:45:47.707] INFO: dacScan split into 1 runs with ntrig = 25
[15:45:47.707] INFO: run 1 of 1
[15:45:48.544] INFO: Expecting 104000 events.
[15:46:01.099] INFO: 104000 events read in total (12028ms).
[15:46:01.109] INFO: Test took 13401ms.
[15:46:01.209] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:01.512] INFO: dacScan step from 27 .. 27
[15:46:01.512] INFO: dacScan split into 1 runs with ntrig = 25
[15:46:01.512] INFO: run 1 of 1
[15:46:02.346] INFO: Expecting 104000 events.
[15:46:14.886] INFO: 104000 events read in total (12013ms).
[15:46:14.895] INFO: Test took 13382ms.
[15:46:14.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:15.269] INFO: dacScan step from 28 .. 28
[15:46:15.269] INFO: dacScan split into 1 runs with ntrig = 25
[15:46:15.269] INFO: run 1 of 1
[15:46:16.094] INFO: Expecting 104000 events.
[15:46:28.763] INFO: 104000 events read in total (12142ms).
[15:46:28.773] INFO: Test took 13503ms.
[15:46:28.860] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:29.146] INFO: dacScan step from 29 .. 29
[15:46:29.146] INFO: dacScan split into 1 runs with ntrig = 25
[15:46:29.146] INFO: run 1 of 1
[15:46:29.974] INFO: Expecting 104000 events.
[15:46:42.402] INFO: 104000 events read in total (11900ms).
[15:46:42.411] INFO: Test took 13264ms.
[15:46:42.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:42.786] INFO: dacScan step from 30 .. 30
[15:46:42.786] INFO: dacScan split into 1 runs with ntrig = 25
[15:46:42.786] INFO: run 1 of 1
[15:46:43.610] INFO: Expecting 104000 events.
[15:46:55.869] INFO: 104000 events read in total (11732ms).
[15:46:55.879] INFO: Test took 13092ms.
[15:46:55.972] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:56.264] INFO: dacScan step from 31 .. 31
[15:46:56.264] INFO: dacScan split into 1 runs with ntrig = 25
[15:46:56.264] INFO: run 1 of 1
[15:46:57.093] INFO: Expecting 104000 events.
[15:47:09.420] INFO: 104000 events read in total (11800ms).
[15:47:09.430] INFO: Test took 13166ms.
[15:47:09.517] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:09.809] INFO: dacScan step from 32 .. 32
[15:47:09.809] INFO: dacScan split into 1 runs with ntrig = 25
[15:47:09.809] INFO: run 1 of 1
[15:47:10.650] INFO: Expecting 104000 events.
[15:47:23.012] INFO: 104000 events read in total (11835ms).
[15:47:23.021] INFO: Test took 13211ms.
[15:47:23.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:23.404] INFO: dacScan step from 33 .. 33
[15:47:23.404] INFO: dacScan split into 1 runs with ntrig = 25
[15:47:23.404] INFO: run 1 of 1
[15:47:24.227] INFO: Expecting 104000 events.
[15:47:36.812] INFO: 104000 events read in total (12058ms).
[15:47:36.821] INFO: Test took 13416ms.
[15:47:36.907] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:37.201] INFO: dacScan step from 34 .. 34
[15:47:37.202] INFO: dacScan split into 1 runs with ntrig = 25
[15:47:37.202] INFO: run 1 of 1
[15:47:38.034] INFO: Expecting 104000 events.
[15:47:50.677] INFO: 104000 events read in total (12116ms).
[15:47:50.686] INFO: Test took 13484ms.
[15:47:50.773] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:51.064] INFO: dacScan step from 35 .. 35
[15:47:51.064] INFO: dacScan split into 1 runs with ntrig = 25
[15:47:51.064] INFO: run 1 of 1
[15:47:51.888] INFO: Expecting 104000 events.
[15:48:04.279] INFO: 104000 events read in total (11864ms).
[15:48:04.289] INFO: Test took 13224ms.
[15:48:04.378] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:04.683] INFO: dacScan step from 36 .. 36
[15:48:04.683] INFO: dacScan split into 1 runs with ntrig = 25
[15:48:04.683] INFO: run 1 of 1
[15:48:05.508] INFO: Expecting 104000 events.
[15:48:18.022] INFO: 104000 events read in total (11986ms).
[15:48:18.033] INFO: Test took 13349ms.
[15:48:18.127] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:18.428] INFO: dacScan step from 37 .. 37
[15:48:18.428] INFO: dacScan split into 1 runs with ntrig = 25
[15:48:18.428] INFO: run 1 of 1
[15:48:19.262] INFO: Expecting 104000 events.
[15:48:32.326] INFO: 104000 events read in total (12537ms).
[15:48:32.337] INFO: Test took 13908ms.
[15:48:32.438] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:32.756] INFO: dacScan step from 38 .. 38
[15:48:32.756] INFO: dacScan split into 1 runs with ntrig = 25
[15:48:32.756] INFO: run 1 of 1
[15:48:33.606] INFO: Expecting 104000 events.
[15:48:46.583] INFO: 104000 events read in total (12449ms).
[15:48:46.593] INFO: Test took 13836ms.
[15:48:46.687] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:46.998] INFO: dacScan step from 39 .. 39
[15:48:46.998] INFO: dacScan split into 1 runs with ntrig = 25
[15:48:46.998] INFO: run 1 of 1
[15:48:47.851] INFO: Expecting 104000 events.
[15:49:01.260] INFO: 104000 events read in total (12882ms).
[15:49:01.271] INFO: Test took 14272ms.
[15:49:01.369] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:01.689] INFO: dacScan step from 40 .. 40
[15:49:01.690] INFO: dacScan split into 1 runs with ntrig = 25
[15:49:01.690] INFO: run 1 of 1
[15:49:02.537] INFO: Expecting 104000 events.
[15:49:15.997] INFO: 104000 events read in total (12933ms).
[15:49:16.012] INFO: Test took 14322ms.
[15:49:16.106] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:16.424] INFO: dacScan step from 41 .. 41
[15:49:16.424] INFO: dacScan split into 1 runs with ntrig = 25
[15:49:16.424] INFO: run 1 of 1
[15:49:17.270] INFO: Expecting 104000 events.
[15:49:30.905] INFO: 104000 events read in total (13108ms).
[15:49:30.916] INFO: Test took 14491ms.
[15:49:31.014] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:31.327] INFO: dacScan step from 42 .. 42
[15:49:31.327] INFO: dacScan split into 1 runs with ntrig = 25
[15:49:31.327] INFO: run 1 of 1
[15:49:32.182] INFO: Expecting 104000 events.
[15:49:46.706] INFO: 104000 events read in total (13997ms).
[15:49:46.715] INFO: Test took 15387ms.
[15:49:46.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:47.109] INFO: dacScan step from 43 .. 43
[15:49:47.109] INFO: dacScan split into 1 runs with ntrig = 25
[15:49:47.109] INFO: run 1 of 1
[15:49:47.944] INFO: Expecting 104000 events.
[15:50:02.613] INFO: 104000 events read in total (14142ms).
[15:50:02.623] INFO: Test took 15513ms.
[15:50:02.702] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:02.998] INFO: dacScan step from 44 .. 44
[15:50:02.998] INFO: dacScan split into 1 runs with ntrig = 25
[15:50:02.998] INFO: run 1 of 1
[15:50:03.821] INFO: Expecting 104000 events.
[15:50:18.530] INFO: 104000 events read in total (14182ms).
[15:50:18.540] INFO: Test took 15541ms.
[15:50:18.626] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:18.943] INFO: dacScan step from 45 .. 45
[15:50:18.943] INFO: dacScan split into 1 runs with ntrig = 25
[15:50:18.943] INFO: run 1 of 1
[15:50:19.792] INFO: Expecting 104000 events.
[15:50:34.528] INFO: 104000 events read in total (14199ms).
[15:50:34.539] INFO: Test took 15595ms.
[15:50:34.631] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:34.957] INFO: dacScan step from 46 .. 46
[15:50:34.957] INFO: dacScan split into 1 runs with ntrig = 25
[15:50:34.958] INFO: run 1 of 1
[15:50:35.806] INFO: Expecting 104000 events.
[15:50:50.606] INFO: 104000 events read in total (14273ms).
[15:50:50.616] INFO: Test took 15657ms.
[15:50:50.706] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:51.025] INFO: dacScan step from 47 .. 47
[15:50:51.025] INFO: dacScan split into 1 runs with ntrig = 25
[15:50:51.025] INFO: run 1 of 1
[15:50:51.870] INFO: Expecting 104000 events.
[15:51:07.164] INFO: 104000 events read in total (14767ms).
[15:51:07.174] INFO: Test took 16148ms.
[15:51:07.270] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:07.587] INFO: dacScan step from 48 .. 48
[15:51:07.587] INFO: dacScan split into 1 runs with ntrig = 25
[15:51:07.587] INFO: run 1 of 1
[15:51:08.446] INFO: Expecting 104000 events.
[15:51:24.067] INFO: 104000 events read in total (15094ms).
[15:51:24.078] INFO: Test took 16490ms.
[15:51:24.168] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:24.469] INFO: dacScan step from 49 .. 49
[15:51:24.469] INFO: dacScan split into 1 runs with ntrig = 25
[15:51:24.469] INFO: run 1 of 1
[15:51:25.301] INFO: Expecting 104000 events.
[15:51:40.941] INFO: 104000 events read in total (15113ms).
[15:51:40.952] INFO: Test took 16483ms.
[15:51:41.056] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:41.374] INFO: dacScan step from 50 .. 50
[15:51:41.374] INFO: dacScan split into 1 runs with ntrig = 25
[15:51:41.374] INFO: run 1 of 1
[15:51:42.225] INFO: Expecting 104000 events.
[15:51:57.414] INFO: 104000 events read in total (14662ms).
[15:51:57.424] INFO: Test took 16049ms.
[15:51:57.528] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:57.849] INFO: dacScan step from 51 .. 51
[15:51:57.849] INFO: dacScan split into 1 runs with ntrig = 25
[15:51:57.849] INFO: run 1 of 1
[15:51:58.701] INFO: Expecting 104000 events.
[15:52:13.660] INFO: 104000 events read in total (14432ms).
[15:52:13.671] INFO: Test took 15821ms.
[15:52:13.763] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:14.076] INFO: dacScan step from 52 .. 52
[15:52:14.076] INFO: dacScan split into 1 runs with ntrig = 25
[15:52:14.076] INFO: run 1 of 1
[15:52:14.926] INFO: Expecting 104000 events.
[15:52:30.531] INFO: 104000 events read in total (15078ms).
[15:52:30.541] INFO: Test took 16464ms.
[15:52:30.630] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:30.934] INFO: dacScan step from 53 .. 53
[15:52:30.934] INFO: dacScan split into 1 runs with ntrig = 25
[15:52:30.934] INFO: run 1 of 1
[15:52:31.768] INFO: Expecting 104000 events.
[15:52:47.677] INFO: 104000 events read in total (15378ms).
[15:52:47.688] INFO: Test took 16753ms.
[15:52:47.784] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:48.095] INFO: dacScan step from 54 .. 54
[15:52:48.095] INFO: dacScan split into 1 runs with ntrig = 25
[15:52:48.095] INFO: run 1 of 1
[15:52:48.949] INFO: Expecting 104000 events.
[15:53:04.709] INFO: 104000 events read in total (15233ms).
[15:53:04.720] INFO: Test took 16624ms.
[15:53:04.812] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:05.119] INFO: dacScan step from 55 .. 55
[15:53:05.119] INFO: dacScan split into 1 runs with ntrig = 25
[15:53:05.119] INFO: run 1 of 1
[15:53:05.966] INFO: Expecting 104000 events.
[15:53:21.629] INFO: 104000 events read in total (15136ms).
[15:53:21.639] INFO: Test took 16518ms.
[15:53:21.721] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:22.035] INFO: dacScan step from 56 .. 56
[15:53:22.035] INFO: dacScan split into 1 runs with ntrig = 25
[15:53:22.035] INFO: run 1 of 1
[15:53:22.886] INFO: Expecting 104000 events.
[15:53:38.585] INFO: 104000 events read in total (15172ms).
[15:53:38.595] INFO: Test took 16559ms.
[15:53:38.685] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:38.982] INFO: dacScan step from 57 .. 57
[15:53:38.982] INFO: dacScan split into 1 runs with ntrig = 25
[15:53:38.982] INFO: run 1 of 1
[15:53:39.809] INFO: Expecting 104000 events.
[15:53:55.283] INFO: 104000 events read in total (14946ms).
[15:53:55.293] INFO: Test took 16310ms.
[15:53:55.379] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:55.707] INFO: dacScan step from 58 .. 58
[15:53:55.707] INFO: dacScan split into 1 runs with ntrig = 25
[15:53:55.707] INFO: run 1 of 1
[15:53:56.556] INFO: Expecting 104000 events.
[15:54:12.159] INFO: 104000 events read in total (15076ms).
[15:54:12.168] INFO: Test took 16460ms.
[15:54:12.252] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:12.577] INFO: dacScan step from 59 .. 59
[15:54:12.577] INFO: dacScan split into 1 runs with ntrig = 25
[15:54:12.577] INFO: run 1 of 1
[15:54:13.421] INFO: Expecting 104000 events.
[15:54:29.103] INFO: 104000 events read in total (15155ms).
[15:54:29.121] INFO: Test took 16543ms.
[15:54:29.201] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:29.528] INFO: dacScan step from 60 .. 60
[15:54:29.528] INFO: dacScan split into 1 runs with ntrig = 25
[15:54:29.528] INFO: run 1 of 1
[15:54:30.377] INFO: Expecting 104000 events.
[15:54:45.979] INFO: 104000 events read in total (15074ms).
[15:54:45.988] INFO: Test took 16459ms.
[15:54:46.084] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:46.393] INFO: dacScan step from 61 .. 61
[15:54:46.393] INFO: dacScan split into 1 runs with ntrig = 25
[15:54:46.393] INFO: run 1 of 1
[15:54:47.225] INFO: Expecting 104000 events.
[15:55:02.871] INFO: 104000 events read in total (15119ms).
[15:55:02.881] INFO: Test took 16487ms.
[15:55:02.978] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:03.301] INFO: dacScan step from 62 .. 62
[15:55:03.301] INFO: dacScan split into 1 runs with ntrig = 25
[15:55:03.301] INFO: run 1 of 1
[15:55:04.149] INFO: Expecting 104000 events.
[15:55:18.980] INFO: 104000 events read in total (14304ms).
[15:55:18.990] INFO: Test took 15688ms.
[15:55:19.072] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:19.394] INFO: dacScan step from 63 .. 63
[15:55:19.394] INFO: dacScan split into 1 runs with ntrig = 25
[15:55:19.394] INFO: run 1 of 1
[15:55:20.237] INFO: Expecting 104000 events.
[15:55:35.910] INFO: 104000 events read in total (15146ms).
[15:55:35.920] INFO: Test took 16525ms.
[15:55:36.012] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:36.334] INFO: dacScan step from 64 .. 64
[15:55:36.334] INFO: dacScan split into 1 runs with ntrig = 25
[15:55:36.334] INFO: run 1 of 1
[15:55:37.182] INFO: Expecting 104000 events.
[15:55:52.272] INFO: 104000 events read in total (14563ms).
[15:55:52.283] INFO: Test took 15948ms.
[15:55:52.373] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:52.693] INFO: dacScan step from 65 .. 65
[15:55:52.693] INFO: dacScan split into 1 runs with ntrig = 25
[15:55:52.693] INFO: run 1 of 1
[15:55:53.539] INFO: Expecting 104000 events.
[15:56:09.121] INFO: 104000 events read in total (15055ms).
[15:56:09.130] INFO: Test took 16436ms.
[15:56:09.219] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:09.526] INFO: dacScan step from 66 .. 66
[15:56:09.526] INFO: dacScan split into 1 runs with ntrig = 25
[15:56:09.526] INFO: run 1 of 1
[15:56:10.355] INFO: Expecting 104000 events.
[15:56:25.680] INFO: 104000 events read in total (14798ms).
[15:56:25.690] INFO: Test took 16163ms.
[15:56:25.784] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:26.105] INFO: dacScan step from 67 .. 67
[15:56:26.105] INFO: dacScan split into 1 runs with ntrig = 25
[15:56:26.105] INFO: run 1 of 1
[15:56:26.957] INFO: Expecting 104000 events.
[15:56:42.603] INFO: 104000 events read in total (15119ms).
[15:56:42.613] INFO: Test took 16507ms.
[15:56:42.704] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:43.021] INFO: dacScan step from 68 .. 68
[15:56:43.021] INFO: dacScan split into 1 runs with ntrig = 25
[15:56:43.021] INFO: run 1 of 1
[15:56:43.871] INFO: Expecting 104000 events.
[15:56:59.034] INFO: 104000 events read in total (14628ms).
[15:56:59.051] INFO: Test took 16029ms.
[15:56:59.152] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:59.483] INFO: dacScan step from 69 .. 69
[15:56:59.483] INFO: dacScan split into 1 runs with ntrig = 25
[15:56:59.483] INFO: run 1 of 1
[15:57:00.334] INFO: Expecting 104000 events.
[15:57:15.996] INFO: 104000 events read in total (15135ms).
[15:57:16.006] INFO: Test took 16522ms.
[15:57:16.098] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:16.404] INFO: dacScan step from 70 .. 70
[15:57:16.404] INFO: dacScan split into 1 runs with ntrig = 25
[15:57:16.404] INFO: run 1 of 1
[15:57:17.244] INFO: Expecting 104000 events.
[15:57:32.907] INFO: 104000 events read in total (15136ms).
[15:57:32.917] INFO: Test took 16511ms.
[15:57:32.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:33.310] INFO: dacScan step from 71 .. 71
[15:57:33.310] INFO: dacScan split into 1 runs with ntrig = 25
[15:57:33.310] INFO: run 1 of 1
[15:57:34.160] INFO: Expecting 104000 events.
[15:57:49.611] INFO: 104000 events read in total (14924ms).
[15:57:49.630] INFO: Test took 16319ms.
[15:57:49.722] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:50.047] INFO: dacScan step from 72 .. 72
[15:57:50.047] INFO: dacScan split into 1 runs with ntrig = 25
[15:57:50.047] INFO: run 1 of 1
[15:57:50.897] INFO: Expecting 104000 events.
[15:58:05.949] INFO: 104000 events read in total (14525ms).
[15:58:05.959] INFO: Test took 15911ms.
[15:58:06.042] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:06.360] INFO: dacScan step from 73 .. 73
[15:58:06.360] INFO: dacScan split into 1 runs with ntrig = 25
[15:58:06.360] INFO: run 1 of 1
[15:58:07.206] INFO: Expecting 104000 events.
[15:58:22.662] INFO: 104000 events read in total (14929ms).
[15:58:22.675] INFO: Test took 16314ms.
[15:58:22.763] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:23.073] INFO: dacScan step from 74 .. 74
[15:58:23.073] INFO: dacScan split into 1 runs with ntrig = 25
[15:58:23.073] INFO: run 1 of 1
[15:58:23.900] INFO: Expecting 104000 events.
[15:58:39.489] INFO: 104000 events read in total (15061ms).
[15:58:39.498] INFO: Test took 16424ms.
[15:58:39.588] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:39.903] INFO: dacScan step from 75 .. 75
[15:58:39.903] INFO: dacScan split into 1 runs with ntrig = 25
[15:58:39.903] INFO: run 1 of 1
[15:58:40.747] INFO: Expecting 104000 events.
[15:58:55.891] INFO: 104000 events read in total (14617ms).
[15:58:55.902] INFO: Test took 15997ms.
[15:58:55.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:56.312] INFO: dacScan step from 76 .. 76
[15:58:56.312] INFO: dacScan split into 1 runs with ntrig = 25
[15:58:56.312] INFO: run 1 of 1
[15:58:57.174] INFO: Expecting 104000 events.
[15:59:12.269] INFO: 104000 events read in total (14568ms).
[15:59:12.279] INFO: Test took 15966ms.
[15:59:12.358] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:12.676] INFO: dacScan step from 77 .. 77
[15:59:12.676] INFO: dacScan split into 1 runs with ntrig = 25
[15:59:12.676] INFO: run 1 of 1
[15:59:13.517] INFO: Expecting 104000 events.
[15:59:28.531] INFO: 104000 events read in total (14487ms).
[15:59:28.541] INFO: Test took 15863ms.
[15:59:28.632] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:28.950] INFO: dacScan step from 78 .. 78
[15:59:28.950] INFO: dacScan split into 1 runs with ntrig = 25
[15:59:28.950] INFO: run 1 of 1
[15:59:29.795] INFO: Expecting 104000 events.
[15:59:45.290] INFO: 104000 events read in total (14968ms).
[15:59:45.300] INFO: Test took 16348ms.
[15:59:45.380] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:45.693] INFO: dacScan step from 79 .. 79
[15:59:45.693] INFO: dacScan split into 1 runs with ntrig = 25
[15:59:45.693] INFO: run 1 of 1
[15:59:46.532] INFO: Expecting 104000 events.
[16:00:02.442] INFO: 104000 events read in total (15383ms).
[16:00:02.451] INFO: Test took 16756ms.
[16:00:02.539] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:02.851] INFO: dacScan step from 80 .. 80
[16:00:02.851] INFO: dacScan split into 1 runs with ntrig = 25
[16:00:02.851] INFO: run 1 of 1
[16:00:03.685] INFO: Expecting 104000 events.
[16:00:19.019] INFO: 104000 events read in total (14808ms).
[16:00:19.030] INFO: Test took 16178ms.
[16:00:19.132] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:19.452] INFO: dacScan step from 81 .. 81
[16:00:19.452] INFO: dacScan split into 1 runs with ntrig = 25
[16:00:19.452] INFO: run 1 of 1
[16:00:20.302] INFO: Expecting 104000 events.
[16:00:35.900] INFO: 104000 events read in total (15071ms).
[16:00:35.913] INFO: Test took 16459ms.
[16:00:35.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:36.324] INFO: dacScan step from 82 .. 82
[16:00:36.324] INFO: dacScan split into 1 runs with ntrig = 25
[16:00:36.324] INFO: run 1 of 1
[16:00:37.172] INFO: Expecting 104000 events.
[16:00:52.138] INFO: 104000 events read in total (14439ms).
[16:00:52.153] INFO: Test took 15827ms.
[16:00:52.255] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:52.570] INFO: dacScan step from 83 .. 83
[16:00:52.570] INFO: dacScan split into 1 runs with ntrig = 25
[16:00:52.570] INFO: run 1 of 1
[16:00:53.400] INFO: Expecting 104000 events.
[16:01:09.131] INFO: 104000 events read in total (15204ms).
[16:01:09.141] INFO: Test took 16569ms.
[16:01:09.236] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:01:09.557] INFO: dacScan step from 84 .. 84
[16:01:09.557] INFO: dacScan split into 1 runs with ntrig = 25
[16:01:09.557] INFO: run 1 of 1
[16:01:10.411] INFO: Expecting 104000 events.
[16:01:25.533] INFO: 104000 events read in total (14595ms).
[16:01:25.543] INFO: Test took 15985ms.
[16:01:25.635] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:01:25.950] INFO: dacScan step from 85 .. 85
[16:01:25.950] INFO: dacScan split into 1 runs with ntrig = 25
[16:01:25.950] INFO: run 1 of 1
[16:01:26.805] INFO: Expecting 104000 events.
[16:01:42.251] INFO: 104000 events read in total (14919ms).
[16:01:42.261] INFO: Test took 16309ms.
[16:01:42.354] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:01:42.675] INFO: dacScan step from 86 .. 86
[16:01:42.675] INFO: dacScan split into 1 runs with ntrig = 25
[16:01:42.675] INFO: run 1 of 1
[16:01:43.516] INFO: Expecting 104000 events.
[16:01:58.818] INFO: 104000 events read in total (14765ms).
[16:01:58.829] INFO: Test took 16153ms.
[16:01:58.928] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:01:59.242] INFO: dacScan step from 87 .. 87
[16:01:59.242] INFO: dacScan split into 1 runs with ntrig = 25
[16:01:59.242] INFO: run 1 of 1
[16:02:00.083] INFO: Expecting 104000 events.
[16:02:15.928] INFO: 104000 events read in total (15318ms).
[16:02:15.939] INFO: Test took 16696ms.
[16:02:16.019] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:02:16.324] INFO: dacScan step from 88 .. 88
[16:02:16.324] INFO: dacScan split into 1 runs with ntrig = 25
[16:02:16.325] INFO: run 1 of 1
[16:02:17.152] INFO: Expecting 104000 events.
[16:02:33.112] INFO: 104000 events read in total (15433ms).
[16:02:33.123] INFO: Test took 16797ms.
[16:02:33.210] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:02:33.515] INFO: dacScan step from 89 .. 89
[16:02:33.515] INFO: dacScan split into 1 runs with ntrig = 25
[16:02:33.515] INFO: run 1 of 1
[16:02:34.360] INFO: Expecting 104000 events.
[16:02:49.922] INFO: 104000 events read in total (15035ms).
[16:02:49.934] INFO: Test took 16418ms.
[16:02:50.016] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:02:50.344] INFO: dacScan step from 90 .. 90
[16:02:50.344] INFO: dacScan split into 1 runs with ntrig = 25
[16:02:50.344] INFO: run 1 of 1
[16:02:51.195] INFO: Expecting 104000 events.
[16:03:06.466] INFO: 104000 events read in total (14744ms).
[16:03:06.476] INFO: Test took 16130ms.
[16:03:06.569] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:06.892] INFO: dacScan step from 91 .. 91
[16:03:06.892] INFO: dacScan split into 1 runs with ntrig = 25
[16:03:06.892] INFO: run 1 of 1
[16:03:07.738] INFO: Expecting 104000 events.
[16:03:23.137] INFO: 104000 events read in total (14872ms).
[16:03:23.148] INFO: Test took 16255ms.
[16:03:23.231] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:23.540] INFO: dacScan step from 92 .. 92
[16:03:23.540] INFO: dacScan split into 1 runs with ntrig = 25
[16:03:23.540] INFO: run 1 of 1
[16:03:24.370] INFO: Expecting 104000 events.
[16:03:40.179] INFO: 104000 events read in total (15282ms).
[16:03:40.190] INFO: Test took 16648ms.
[16:03:40.270] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:40.586] INFO: dacScan step from 93 .. 93
[16:03:40.586] INFO: dacScan split into 1 runs with ntrig = 25
[16:03:40.586] INFO: run 1 of 1
[16:03:41.416] INFO: Expecting 104000 events.
[16:03:56.818] INFO: 104000 events read in total (14875ms).
[16:03:56.828] INFO: Test took 16241ms.
[16:03:56.929] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:57.247] INFO: dacScan step from 94 .. 94
[16:03:57.247] INFO: dacScan split into 1 runs with ntrig = 25
[16:03:57.247] INFO: run 1 of 1
[16:03:58.103] INFO: Expecting 104000 events.
[16:04:13.577] INFO: 104000 events read in total (14947ms).
[16:04:13.587] INFO: Test took 16338ms.
[16:04:13.681] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:13.997] INFO: dacScan step from 95 .. 95
[16:04:13.998] INFO: dacScan split into 1 runs with ntrig = 25
[16:04:13.998] INFO: run 1 of 1
[16:04:14.845] INFO: Expecting 104000 events.
[16:04:30.667] INFO: 104000 events read in total (15295ms).
[16:04:30.678] INFO: Test took 16679ms.
[16:04:30.770] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:31.076] INFO: dacScan step from 96 .. 96
[16:04:31.077] INFO: dacScan split into 1 runs with ntrig = 25
[16:04:31.077] INFO: run 1 of 1
[16:04:31.922] INFO: Expecting 104000 events.
[16:04:47.466] INFO: 104000 events read in total (15017ms).
[16:04:47.476] INFO: Test took 16398ms.
[16:04:47.556] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:47.856] INFO: dacScan step from 97 .. 97
[16:04:47.856] INFO: dacScan split into 1 runs with ntrig = 25
[16:04:47.856] INFO: run 1 of 1
[16:04:48.702] INFO: Expecting 104000 events.
[16:05:04.107] INFO: 104000 events read in total (14877ms).
[16:05:04.117] INFO: Test took 16259ms.
[16:05:04.217] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:04.536] INFO: dacScan step from 98 .. 98
[16:05:04.536] INFO: dacScan split into 1 runs with ntrig = 25
[16:05:04.536] INFO: run 1 of 1
[16:05:05.387] INFO: Expecting 104000 events.
[16:05:20.586] INFO: 104000 events read in total (14672ms).
[16:05:20.598] INFO: Test took 16060ms.
[16:05:20.701] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:21.011] INFO: dacScan step from 99 .. 99
[16:05:21.011] INFO: dacScan split into 1 runs with ntrig = 25
[16:05:21.011] INFO: run 1 of 1
[16:05:21.854] INFO: Expecting 104000 events.
[16:05:37.317] INFO: 104000 events read in total (14935ms).
[16:05:37.326] INFO: Test took 16314ms.
[16:05:37.419] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:37.723] INFO: dacScan step from 100 .. 100
[16:05:37.723] INFO: dacScan split into 1 runs with ntrig = 25
[16:05:37.723] INFO: run 1 of 1
[16:05:38.554] INFO: Expecting 104000 events.
[16:05:54.235] INFO: 104000 events read in total (15154ms).
[16:05:54.245] INFO: Test took 16520ms.
[16:05:54.340] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:54.652] INFO: dumping ASCII scurve output file: XSCurveData
[16:05:56.762] INFO: dumping ASCII scurve output file: XSCurveData
[16:05:58.855] INFO: dumping ASCII scurve output file: XSCurveData
[16:06:00.907] INFO: dumping ASCII scurve output file: XSCurveData
[16:06:03.046] INFO: dumping ASCII scurve output file: XSCurveData
[16:06:05.081] INFO: dumping ASCII scurve output file: XSCurveData
[16:06:07.106] INFO: dumping ASCII scurve output file: XSCurveData
[16:06:09.219] INFO: dumping ASCII scurve output file: XSCurveData
[16:06:11.360] INFO: dumping ASCII scurve output file: XSCurveData
[16:06:13.495] INFO: dumping ASCII scurve output file: XSCurveData
[16:06:15.646] INFO: dumping ASCII scurve output file: XSCurveData
[16:06:17.707] INFO: dumping ASCII scurve output file: XSCurveData
[16:06:19.809] INFO: dumping ASCII scurve output file: XSCurveData
[16:06:21.943] INFO: dumping ASCII scurve output file: XSCurveData
[16:06:24.031] INFO: dumping ASCII scurve output file: XSCurveData
[16:06:26.128] INFO: dumping ASCII scurve output file: XSCurveData
[16:06:28.247] INFO: PixTest:: pg_setup set to default.
[16:06:28.256] INFO: vcal mean: 38.34 38.13 38.26 40.99 39.81 40.78 40.46 39.62 41.12 41.01 41.66 41.02 41.46 38.03 39.22 40.19
[16:06:28.256] INFO: vcal RMS: 1.49 1.69 1.29 1.70 3.73 1.72 1.74 1.63 1.60 1.93 1.62 1.77 1.74 1.56 1.54 1.59
[16:06:28.256] INFO: number of X-ray hits detected: 147989 154349 125714 194072 195180 208031 184192 183825 196790 195313 225316 204870 213280 142263 164943 165619
[16:06:28.256] INFO: number of triggers sent (total per ROC): 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000 104000
[16:06:28.257] INFO: X-ray hit rate [MHz/cm2]: 86.8 90.5 73.7 113.8 114.4 122.0 108.0 107.8 115.4 114.5 132.1 120.1 125.0 83.4 96.7 97.1
[16:06:28.257] INFO: PixTestHighRate::doXNoiseMaps() done
[16:06:28.394] INFO: enter test to run
[16:06:28.394] INFO: test: exit no parameter change
[16:06:28.500] QUIET: Connection to board 172 closed.
[16:06:28.516] INFO: pXar: this is the end, my friend

[16:11:34.327] INFO: *** Welcome to pxar ***
[16:11:34.327] INFO: *** Today: 2015/09/07
[16:11:34.327] INFO: readRocDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[16:11:34.330] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[16:11:34.330] INFO: readMaskFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[16:11:34.330] INFO: readTrimFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[16:11:34.415] INFO: clk: 4
[16:11:34.415] INFO: ctr: 4
[16:11:34.415] INFO: sda: 19
[16:11:34.415] INFO: tin: 9
[16:11:34.415] INFO: level: 15
[16:11:34.415] INFO: triggerdelay: 0
[16:11:34.415] QUIET: Instanciating API for pxar 2.5
[16:11:34.415] INFO: Log level: INFO
[16:11:34.423] INFO: Found DTB DTB_WZ4QKL
[16:11:34.436] QUIET: Connection to board DTB_WZ4QKL opened.
[16:11:34.439] 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:
------------------------------------------------------
[16:11:34.443] INFO: RPC call hashes of host and DTB match: 397073690
[16:11:36.278] INFO: DUT info:
[16:11:36.279] INFO: The DUT currently contains the following objects:
[16:11:36.279] INFO: 2 TBM Cores tbm08c (2 ON)
[16:11:36.279] INFO: TBM Core alpha (0): 7 registers set
[16:11:36.279] INFO: TBM Core beta (1): 7 registers set
[16:11:36.279] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:11:36.279] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.279] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.279] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.279] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.279] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.279] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.279] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.279] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.279] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.279] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.279] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.280] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.280] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.280] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.280] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.280] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:36.682] INFO: enter 'restricted' command line mode
[16:11:36.682] INFO: enter test to run
[16:11:36.682] INFO: test: highrate no parameter change
[16:11:36.682] INFO: running: highrate
[16:11:36.685] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[16:11:36.911] INFO: ----------------------------------------------------------------------
[16:11:36.911] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:11:36.911] INFO: ----------------------------------------------------------------------
[16:11:37.058] INFO: Expecting 768 events.
[16:11:38.341] INFO: 768 events read in total (498ms).
[16:11:38.530] INFO: Test took 1612ms.
[16:11:39.395] INFO: Expecting 41600 events.
[16:11:44.452] INFO: 41600 events read in total (4530ms).
[16:11:44.458] INFO: Test took 5919ms.
[16:11:44.550] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:11:45.456] INFO: Expecting 41600 events.
[16:11:50.618] INFO: 41600 events read in total (4635ms).
[16:11:50.621] INFO: Test took 6014ms.
[16:11:50.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:11:51.596] INFO: Expecting 41600 events.
[16:11:56.733] INFO: 41600 events read in total (4610ms).
[16:11:56.736] INFO: Test took 5969ms.
[16:11:56.830] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:11:57.728] INFO: Expecting 41600 events.
[16:12:02.769] INFO: 41600 events read in total (4514ms).
[16:12:02.775] INFO: Test took 5887ms.
[16:12:02.870] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:03.775] INFO: Expecting 41600 events.
[16:12:08.742] INFO: 41600 events read in total (4440ms).
[16:12:08.745] INFO: Test took 5819ms.
[16:12:08.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:09.746] INFO: Expecting 41600 events.
[16:12:14.563] INFO: 41600 events read in total (4289ms).
[16:12:14.566] INFO: Test took 5670ms.
[16:12:14.661] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:15.564] INFO: Expecting 41600 events.
[16:12:20.851] INFO: 41600 events read in total (4760ms).
[16:12:20.854] INFO: Test took 6137ms.
[16:12:20.946] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:21.850] INFO: Expecting 41600 events.
[16:12:26.768] INFO: 41600 events read in total (4391ms).
[16:12:26.772] INFO: Test took 5768ms.
[16:12:26.868] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:27.776] INFO: Expecting 41600 events.
[16:12:32.919] INFO: 41600 events read in total (4616ms).
[16:12:32.922] INFO: Test took 5999ms.
[16:12:33.019] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:33.927] INFO: Expecting 41600 events.
[16:12:38.777] INFO: 41600 events read in total (4323ms).
[16:12:38.780] INFO: Test took 5703ms.
[16:12:38.873] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:39.777] INFO: Expecting 41600 events.
[16:12:44.860] INFO: 41600 events read in total (4556ms).
[16:12:44.864] INFO: Test took 5934ms.
[16:12:44.962] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:45.868] INFO: Expecting 41600 events.
[16:12:50.730] INFO: 41600 events read in total (4335ms).
[16:12:50.733] INFO: Test took 5714ms.
[16:12:50.827] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:51.734] INFO: Expecting 41600 events.
[16:12:56.615] INFO: 41600 events read in total (4354ms).
[16:12:56.618] INFO: Test took 5736ms.
[16:12:56.715] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:57.622] INFO: Expecting 41600 events.
[16:13:02.678] INFO: 41600 events read in total (4529ms).
[16:13:02.681] INFO: Test took 5910ms.
[16:13:02.773] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:03.678] INFO: Expecting 41600 events.
[16:13:08.741] INFO: 41600 events read in total (4536ms).
[16:13:08.748] INFO: Test took 5920ms.
[16:13:08.845] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:09.750] INFO: Expecting 41600 events.
[16:13:14.655] INFO: 41600 events read in total (4378ms).
[16:13:14.658] INFO: Test took 5755ms.
[16:13:14.751] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:15.655] INFO: Expecting 41600 events.
[16:13:20.457] INFO: 41600 events read in total (4275ms).
[16:13:20.460] INFO: Test took 5653ms.
[16:13:20.556] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:21.459] INFO: Expecting 41600 events.
[16:13:26.461] INFO: 41600 events read in total (4475ms).
[16:13:26.465] INFO: Test took 5854ms.
[16:13:26.562] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:27.470] INFO: Expecting 41600 events.
[16:13:32.336] INFO: 41600 events read in total (4339ms).
[16:13:32.340] INFO: Test took 5721ms.
[16:13:32.434] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:33.337] INFO: Expecting 41600 events.
[16:13:37.994] INFO: 41600 events read in total (4130ms).
[16:13:38.000] INFO: Test took 5510ms.
[16:13:38.100] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:38.529] INFO: enter test to run
[16:13:38.529] INFO: test: savedacs no parameter change
[16:13:38.529] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat
[16:13:38.529] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C1.dat
[16:13:38.529] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C2.dat
[16:13:38.529] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C3.dat
[16:13:38.529] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C4.dat
[16:13:38.529] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C5.dat
[16:13:38.529] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C6.dat
[16:13:38.530] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C7.dat
[16:13:38.530] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C8.dat
[16:13:38.530] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C9.dat
[16:13:38.530] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C10.dat
[16:13:38.530] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C11.dat
[16:13:38.530] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C12.dat
[16:13:38.530] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C13.dat
[16:13:38.530] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C14.dat
[16:13:38.530] INFO: write dac parameters into /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[16:13:38.530] INFO: enter test to run
[16:13:38.530] INFO: test: exit no parameter change
[16:13:38.622] QUIET: Connection to board 172 closed.
[16:13:38.637] INFO: pXar: this is the end, my friend

[16:13:43.622] INFO: *** Welcome to pxar ***
[16:13:43.622] INFO: *** Today: 2015/09/07
[16:13:43.622] INFO: readRocDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[16:13:43.623] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[16:13:43.623] INFO: readMaskFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[16:13:43.623] INFO: readTrimFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[16:13:43.705] INFO: clk: 4
[16:13:43.705] INFO: ctr: 4
[16:13:43.705] INFO: sda: 19
[16:13:43.705] INFO: tin: 9
[16:13:43.705] INFO: level: 15
[16:13:43.705] INFO: triggerdelay: 0
[16:13:43.705] QUIET: Instanciating API for pxar 2.5
[16:13:43.705] INFO: Log level: INFO
[16:13:43.717] INFO: Found DTB DTB_WZ4QKL
[16:13:43.734] QUIET: Connection to board DTB_WZ4QKL opened.
[16:13:43.737] 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:
------------------------------------------------------
[16:13:43.741] INFO: RPC call hashes of host and DTB match: 397073690
[16:13:45.579] INFO: DUT info:
[16:13:45.579] INFO: The DUT currently contains the following objects:
[16:13:45.579] INFO: 2 TBM Cores tbm08c (2 ON)
[16:13:45.579] INFO: TBM Core alpha (0): 7 registers set
[16:13:45.579] INFO: TBM Core beta (1): 7 registers set
[16:13:45.579] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:13:45.579] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.579] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.579] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.580] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.580] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.580] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.580] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.580] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.580] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.580] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.580] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.580] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.580] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.580] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.580] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.580] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:13:45.982] INFO: enter 'restricted' command line mode
[16:13:45.982] INFO: enter test to run
[16:13:45.982] INFO: test: highrate no parameter change
[16:13:45.982] INFO: running: highrate
[16:13:45.989] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[16:13:46.217] INFO: ----------------------------------------------------------------------
[16:13:46.217] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:13:46.217] INFO: ----------------------------------------------------------------------
[16:13:47.100] INFO: Expecting 208000 events.
[16:14:10.308] INFO: 208000 events read in total (22677ms).
[16:14:10.321] INFO: Test took 24095ms.
[16:14:10.751] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:14:11.262] INFO: number of dead pixels (per ROC): 0 4 0 0 1 0 0 0 0 1 0 0 0 0 0 0
[16:14:11.262] INFO: number of red-efficiency pixels: 228 312 211 362 375 361 275 326 336 440 466 364 438 289 270 268
[16:14:11.262] INFO: number of X-ray hits detected: 148318 156349 125610 194738 195805 208046 184811 183207 197377 197008 226050 205985 212592 143182 164922 166364
[16:14:11.262] 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:14:11.262] INFO: number of Vcal hits detected: 207764 207476 207772 207624 207552 207623 207709 207648 207644 207478 207490 207614 207542 207692 207721 207726
[16:14:11.262] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[16:14:11.262] INFO: Vcal hit overall efficiency (%): 99.9 99.7 99.9 99.8 99.8 99.8 99.9 99.8 99.8 99.7 99.8 99.8 99.8 99.9 99.9 99.9
[16:14:11.262] INFO: X-ray hit rate [MHz/cm2]: 43.5 45.8 36.8 57.1 57.4 61.0 54.2 53.7 57.9 57.7 66.3 60.4 62.3 42.0 48.3 48.8
[16:14:11.262] INFO: PixTestHighRate::doXPixelAlive() done
[16:14:11.326] INFO: PixTest:: pg_setup set to default.
[16:14:11.383] INFO: enter test to run
[16:14:11.383] INFO: test: exit no parameter change
[16:14:11.473] QUIET: Connection to board 172 closed.
[16:14:11.489] INFO: pXar: this is the end, my friend

[16:14:17.542] INFO: *** Welcome to pxar ***
[16:14:17.542] INFO: *** Today: 2015/09/07
[16:14:17.542] INFO: readRocDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[16:14:17.544] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[16:14:17.544] INFO: readMaskFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[16:14:17.544] INFO: readTrimFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[16:14:17.630] INFO: clk: 4
[16:14:17.630] INFO: ctr: 4
[16:14:17.630] INFO: sda: 19
[16:14:17.630] INFO: tin: 9
[16:14:17.630] INFO: level: 15
[16:14:17.630] INFO: triggerdelay: 0
[16:14:17.630] QUIET: Instanciating API for pxar 2.5
[16:14:17.630] INFO: Log level: INFO
[16:14:17.640] INFO: Found DTB DTB_WZ4QKL
[16:14:17.657] QUIET: Connection to board DTB_WZ4QKL opened.
[16:14:17.661] 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:
------------------------------------------------------
[16:14:17.664] INFO: RPC call hashes of host and DTB match: 397073690
[16:14:19.499] INFO: DUT info:
[16:14:19.499] INFO: The DUT currently contains the following objects:
[16:14:19.499] INFO: 2 TBM Cores tbm08c (2 ON)
[16:14:19.499] INFO: TBM Core alpha (0): 7 registers set
[16:14:19.499] INFO: TBM Core beta (1): 7 registers set
[16:14:19.499] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:14:19.500] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.500] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.500] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.500] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.500] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.500] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.500] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.500] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.500] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.500] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.500] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.500] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.500] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.500] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.500] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.501] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:19.902] INFO: enter 'restricted' command line mode
[16:14:19.902] INFO: enter test to run
[16:14:19.902] INFO: test: highrate no parameter change
[16:14:19.903] INFO: running: highrate
[16:14:19.909] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[16:14:20.134] INFO: ----------------------------------------------------------------------
[16:14:20.134] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:14:20.134] INFO: ----------------------------------------------------------------------
[16:14:20.999] INFO: Expecting 208000 events.
[16:14:52.867] INFO: 208000 events read in total (31334ms).
[16:14:52.889] INFO: Test took 32747ms.
[16:14:53.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:14:54.414] INFO: number of dead pixels (per ROC): 0 4 0 0 1 0 0 0 0 1 0 0 0 0 0 0
[16:14:54.414] INFO: number of red-efficiency pixels: 765 995 669 1299 1296 1436 1070 1086 1200 1517 1603 1361 1553 993 924 866
[16:14:54.414] INFO: number of X-ray hits detected: 294164 308045 249646 385842 386588 412419 365504 365552 390815 389544 444529 406339 422390 284795 325817 328103
[16:14:54.414] 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:14:54.414] INFO: number of Vcal hits detected: 207142 206618 207099 206315 206207 206076 206711 206663 206520 205693 205696 206160 205891 206588 206933 207010
[16:14:54.414] INFO: Vcal hit fiducial efficiency (%): 99.6 99.5 99.6 99.3 99.3 99.2 99.4 99.4 99.3 99.1 99.0 99.2 99.1 99.4 99.5 99.6
[16:14:54.414] INFO: Vcal hit overall efficiency (%): 99.6 99.3 99.6 99.2 99.1 99.1 99.4 99.4 99.3 98.9 98.9 99.1 99.0 99.3 99.5 99.5
[16:14:54.414] INFO: X-ray hit rate [MHz/cm2]: 86.2 90.3 73.2 113.1 113.3 120.9 107.1 107.1 114.6 114.2 130.3 119.1 123.8 83.5 95.5 96.2
[16:14:54.414] INFO: PixTestHighRate::doXPixelAlive() done
[16:14:54.461] INFO: PixTest:: pg_setup set to default.
[16:14:54.521] INFO: enter test to run
[16:14:54.521] INFO: test: exit no parameter change
[16:14:54.616] QUIET: Connection to board 172 closed.
[16:14:54.625] INFO: pXar: this is the end, my friend

[16:17:12.608] INFO: *** Welcome to pxar ***
[16:17:12.608] INFO: *** Today: 2015/09/07
[16:17:12.608] INFO: readRocDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[16:17:12.610] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[16:17:12.610] INFO: readMaskFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[16:17:12.611] INFO: readTrimFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[16:17:12.693] INFO: clk: 4
[16:17:12.693] INFO: ctr: 4
[16:17:12.693] INFO: sda: 19
[16:17:12.693] INFO: tin: 9
[16:17:12.693] INFO: level: 15
[16:17:12.693] INFO: triggerdelay: 0
[16:17:12.693] QUIET: Instanciating API for pxar 2.5
[16:17:12.693] INFO: Log level: INFO
[16:17:12.701] INFO: Found DTB DTB_WZ4QKL
[16:17:12.713] QUIET: Connection to board DTB_WZ4QKL opened.
[16:17:12.717] 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:
------------------------------------------------------
[16:17:12.720] INFO: RPC call hashes of host and DTB match: 397073690
[16:17:14.558] INFO: DUT info:
[16:17:14.558] INFO: The DUT currently contains the following objects:
[16:17:14.558] INFO: 2 TBM Cores tbm08c (2 ON)
[16:17:14.558] INFO: TBM Core alpha (0): 7 registers set
[16:17:14.558] INFO: TBM Core beta (1): 7 registers set
[16:17:14.558] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:17:14.558] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.558] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.558] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.559] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.559] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.559] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.559] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.559] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.559] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.559] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.559] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.559] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.559] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.559] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.559] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.559] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:14.961] INFO: enter 'restricted' command line mode
[16:17:14.961] INFO: enter test to run
[16:17:14.961] INFO: test: highrate no parameter change
[16:17:14.961] INFO: running: highrate
[16:17:14.971] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[16:17:15.195] INFO: ----------------------------------------------------------------------
[16:17:15.195] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:17:15.195] INFO: ----------------------------------------------------------------------
[16:17:16.052] INFO: Expecting 208000 events.
[16:17:57.403] INFO: 208000 events read in total (40821ms).
[16:17:57.433] INFO: Test took 42232ms.
[16:17:58.742] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:17:59.650] INFO: number of dead pixels (per ROC): 0 3 0 0 0 0 0 0 0 1 0 0 0 0 0 0
[16:17:59.650] INFO: number of red-efficiency pixels: 1621 2125 1356 2679 2575 2860 2242 2334 2633 3060 3164 2762 3004 1914 1979 1972
[16:17:59.650] INFO: number of X-ray hits detected: 437732 458280 372162 574850 576448 612278 544038 545075 581896 578071 663019 604877 626740 420744 484339 489808
[16:17:59.650] 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:17:59.650] INFO: number of Vcal hits detected: 205772 204626 205391 202712 202783 202142 204037 204167 203317 200863 200638 202160 201645 203554 205066 204977
[16:17:59.650] INFO: Vcal hit fiducial efficiency (%): 99.0 98.5 99.0 97.8 97.8 97.5 98.3 98.3 97.9 97.0 96.9 97.6 97.3 98.2 98.7 98.7
[16:17:59.650] INFO: Vcal hit overall efficiency (%): 98.9 98.4 98.7 97.5 97.5 97.2 98.1 98.2 97.7 96.6 96.5 97.2 96.9 97.9 98.6 98.5
[16:17:59.650] INFO: X-ray hit rate [MHz/cm2]: 128.3 134.3 109.1 168.5 169.0 179.5 159.5 159.8 170.6 169.4 194.3 177.3 183.7 123.3 142.0 143.6
[16:17:59.650] INFO: PixTestHighRate::doXPixelAlive() done
[16:17:59.696] INFO: PixTest:: pg_setup set to default.
[16:17:59.752] INFO: enter test to run
[16:17:59.752] INFO: test: exit no parameter change
[16:17:59.856] QUIET: Connection to board 172 closed.
[16:17:59.872] INFO: pXar: this is the end, my friend

[16:18:09.179] INFO: *** Welcome to pxar ***
[16:18:09.179] INFO: *** Today: 2015/09/07
[16:18:09.179] INFO: readRocDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[16:18:09.182] INFO: readTbmDacs: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[16:18:09.182] INFO: readMaskFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[16:18:09.182] INFO: readTrimFile: /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[16:18:09.270] INFO: clk: 4
[16:18:09.270] INFO: ctr: 4
[16:18:09.270] INFO: sda: 19
[16:18:09.270] INFO: tin: 9
[16:18:09.270] INFO: level: 15
[16:18:09.270] INFO: triggerdelay: 0
[16:18:09.270] QUIET: Instanciating API for pxar 2.5
[16:18:09.270] INFO: Log level: INFO
[16:18:09.277] INFO: Found DTB DTB_WZ4QKL
[16:18:09.289] QUIET: Connection to board DTB_WZ4QKL opened.
[16:18:09.293] 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:
------------------------------------------------------
[16:18:09.297] INFO: RPC call hashes of host and DTB match: 397073690
[16:18:11.140] INFO: DUT info:
[16:18:11.140] INFO: The DUT currently contains the following objects:
[16:18:11.140] INFO: 2 TBM Cores tbm08c (2 ON)
[16:18:11.140] INFO: TBM Core alpha (0): 7 registers set
[16:18:11.140] INFO: TBM Core beta (1): 7 registers set
[16:18:11.140] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:18:11.140] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.140] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.140] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.141] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.141] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.141] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.141] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.141] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.141] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.141] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.141] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.141] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.141] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.141] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.141] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.141] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:11.543] INFO: enter 'restricted' command line mode
[16:18:11.543] INFO: enter test to run
[16:18:11.543] INFO: test: highrate no parameter change
[16:18:11.543] INFO: running: highrate
[16:18:11.546] INFO: readGainPedestalParameters /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat .. /home/l_tester/XRayTesting/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[16:18:11.775] INFO: ----------------------------------------------------------------------
[16:18:11.775] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:18:11.775] INFO: ----------------------------------------------------------------------
[16:18:12.640] INFO: Expecting 208000 events.
[16:19:05.203] INFO: 208000 events read in total (52032ms).
[16:19:05.246] INFO: Test took 53462ms.
[16:19:06.907] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:08.025] INFO: number of dead pixels (per ROC): 0 4 0 0 1 0 0 0 0 1 0 0 0 0 0 0
[16:19:08.025] INFO: number of red-efficiency pixels: 2686 3283 1989 3727 3654 3797 3309 3538 3696 3911 3988 3803 3900 2557 3122 3076
[16:19:08.025] INFO: number of X-ray hits detected: 580837 606978 492096 760165 765700 814098 722618 725375 772885 767109 879595 803836 830085 558840 642319 649506
[16:19:08.025] 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:19:08.025] INFO: number of Vcal hits detected: 202821 200528 202169 195557 196235 194371 198762 198793 197033 191587 190585 194532 193405 198532 201281 200954
[16:19:08.025] INFO: Vcal hit fiducial efficiency (%): 97.8 96.7 97.7 94.7 95.1 94.2 96.1 95.9 95.2 92.9 92.5 94.3 93.7 96.1 97.1 97.0
[16:19:08.025] INFO: Vcal hit overall efficiency (%): 97.5 96.4 97.2 94.0 94.3 93.4 95.6 95.6 94.7 92.1 91.6 93.5 93.0 95.4 96.8 96.6
[16:19:08.025] INFO: X-ray hit rate [MHz/cm2]: 170.2 177.9 144.2 222.8 224.4 238.6 211.8 212.6 226.5 224.8 257.8 235.6 243.3 163.8 188.3 190.4
[16:19:08.025] INFO: PixTestHighRate::doXPixelAlive() done
[16:19:08.071] INFO: PixTest:: pg_setup set to default.
[16:19:08.128] INFO: enter test to run
[16:19:08.128] INFO: test: exit no parameter change
[16:19:08.223] QUIET: Connection to board 172 closed.
[16:19:08.238] INFO: pXar: this is the end, my friend