[19:42:41.083] INFO: *** Welcome to pxar ***
[19:42:41.084] INFO: *** Today: 2015/08/04
[19:42:41.084] INFO: readRocDacs: /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C15.dat
[19:42:41.084] INFO: readTbmDacs: /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[19:42:41.084] INFO: readMaskFile: /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[19:42:41.084] INFO: readTrimFile: /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters_C15.dat
[19:42:41.180] INFO: clk: 4
[19:42:41.180] INFO: ctr: 4
[19:42:41.180] INFO: sda: 19
[19:42:41.180] INFO: tin: 9
[19:42:41.180] INFO: level: 15
[19:42:41.180] INFO: triggerdelay: 0
[19:42:41.180] QUIET: Instanciating API for pxar prod-01+36~geb5ac3f
[19:42:41.180] INFO: Log level: INFO
[19:42:41.194] INFO: Found DTB DTB_WZ4THG
[19:42:41.207] QUIET: Connection to board DTB_WZ4THG opened.
[19:42:41.210] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 173
HW version: DTB1.2
FW version: 4.2
SW version: 4.2
USB id: DTB_WZ4THG
MAC address: 40D8551180AD
Hostname: pixelDTB173
Comment:
------------------------------------------------------
[19:42:41.213] INFO: RPC call hashes of host and DTB match: 447413373
[19:42:42.964] INFO: DUT info:
[19:42:42.964] INFO: The DUT currently contains the following objects:
[19:42:42.964] INFO: 2 TBM Cores tbm08c (2 ON)
[19:42:42.964] INFO: TBM Core alpha (0): 7 registers set
[19:42:42.964] INFO: TBM Core beta (1): 7 registers set
[19:42:42.964] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[19:42:42.964] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:42.965] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.366] INFO: enter 'restricted' command line mode
[19:42:43.366] INFO: enter test to run
[19:42:43.366] INFO: test: pretest no parameter change
[19:42:43.366] INFO: running: pretest
[19:42:43.371] INFO: ######################################################################
[19:42:43.371] INFO: PixTestPretest::doTest()
[19:42:43.372] INFO: ######################################################################
[19:42:43.373] INFO: ----------------------------------------------------------------------
[19:42:43.373] INFO: PixTestPretest::programROC()
[19:42:43.373] INFO: ----------------------------------------------------------------------
[19:43:01.397] INFO: PixTestPretest::programROC() done: ROCs are all programmable
[19:43:01.397] INFO: IA differences per ROC: 18.5 18.5 17.7 19.3 18.5 16.9 16.1 18.5 18.5 16.9 17.7 18.5 17.7 17.7 18.5 17.7
[19:43:01.489] INFO: ----------------------------------------------------------------------
[19:43:01.489] INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[19:43:01.489] INFO: ----------------------------------------------------------------------
[19:43:21.156] INFO: PixTestPretest::setVana() done, Module Ia 387.5 mA = 24.2188 mA/ROC
[19:43:21.160] INFO: ----------------------------------------------------------------------
[19:43:21.160] INFO: PixTestPretest::findWorkingPixel()
[19:43:21.160] INFO: ----------------------------------------------------------------------














































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































































[19:43:55.958] INFO: Test took 7807ms.
[19:43:56.297] INFO: Found working pixel in all ROCs: col/row = 5/5
[19:43:56.377] INFO: ----------------------------------------------------------------------
[19:43:56.377] INFO: PixTestPretest::setVthrCompCalDel()
[19:43:56.377] INFO: ----------------------------------------------------------------------
[19:44:04.276] INFO: Test took 7892ms.
[19:44:04.637] INFO: PixTestPretest::setVthrCompCalDel() done
[19:44:04.637] INFO: CalDel: 110 111 110 94 115 112 137 124 113 125 144 128 108 112 120 136
[19:44:04.637] INFO: VthrComp: 51 56 51 51 51 51 51 51 51 55 51 51 51 51 51 51
[19:44:04.644] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C0.dat
[19:44:04.645] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C1.dat
[19:44:04.645] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C2.dat
[19:44:04.646] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C3.dat
[19:44:04.646] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C4.dat
[19:44:04.646] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C5.dat
[19:44:04.647] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C6.dat
[19:44:04.647] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C7.dat
[19:44:04.647] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C8.dat
[19:44:04.648] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C9.dat
[19:44:04.648] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C10.dat
[19:44:04.648] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C11.dat
[19:44:04.648] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C12.dat
[19:44:04.648] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C13.dat
[19:44:04.648] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C14.dat
[19:44:04.649] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C15.dat
[19:44:04.649] INFO: PixTestPretest::doTest() done, duration: 81 seconds
[19:44:04.758] INFO: enter test to run
[19:44:04.758] INFO: test: fulltest no parameter change
[19:44:04.758] INFO: running: fulltest
[19:44:04.758] INFO: ######################################################################
[19:44:04.758] INFO: PixTestFullTest::doTest()
[19:44:04.758] INFO: ######################################################################
[19:44:04.760] INFO: ######################################################################
[19:44:04.760] INFO: PixTestAlive::doTest()
[19:44:04.760] INFO: ######################################################################
[19:44:04.762] INFO: ----------------------------------------------------------------------
[19:44:04.762] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[19:44:04.762] INFO: ----------------------------------------------------------------------
[19:44:08.944] INFO: Test took 4180ms.
[19:44:08.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:44:09.229] INFO: PixTestAlive::aliveTest() done
[19:44:09.229] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[19:44:09.231] INFO: ----------------------------------------------------------------------
[19:44:09.231] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[19:44:09.231] INFO: ----------------------------------------------------------------------
[19:44:12.313] INFO: Test took 3078ms.
[19:44:12.317] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:44:12.318] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[19:44:12.572] INFO: PixTestAlive::maskTest() done
[19:44:12.572] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[19:44:12.574] INFO: ----------------------------------------------------------------------
[19:44:12.574] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[19:44:12.574] INFO: ----------------------------------------------------------------------
[19:44:16.778] INFO: Test took 4201ms.
[19:44:16.816] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:44:17.072] INFO: PixTestAlive::addressDecodingTest() done
[19:44:17.073] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[19:44:17.073] INFO: PixTestAlive::doTest() done, duration: 12 seconds
[19:44:17.085] INFO: ######################################################################
[19:44:17.085] INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[19:44:17.085] INFO: ######################################################################
[19:44:17.090] INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30/5) hits flags = 2 (plus default)
[19:44:17.113] INFO: dacScan step from 0 .. 29
[19:44:17.113] INFO: dacScan split into 1 runs with ntrig = 5
[19:44:17.113] INFO: run 1 of 1
[19:44:36.344] INFO: Test took 19231ms.
[19:44:36.413] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:44:36.413] INFO: dacScan step from 30 .. 59
[19:44:36.413] INFO: dacScan split into 1 runs with ntrig = 5
[19:44:36.413] INFO: run 1 of 1
[19:44:55.597] INFO: Test took 19184ms.
[19:44:55.677] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:44:55.680] INFO: dacScan step from 60 .. 89
[19:44:55.680] INFO: dacScan split into 1 runs with ntrig = 5
[19:44:55.680] INFO: run 1 of 1
[19:45:19.473] INFO: Test took 23793ms.
[19:45:19.812] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:45:19.895] INFO: dacScan step from 90 .. 119
[19:45:19.895] INFO: dacScan split into 1 runs with ntrig = 5
[19:45:19.895] INFO: run 1 of 1
[19:45:47.092] INFO: Test took 27197ms.
[19:45:47.618] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:45:47.745] INFO: dacScan step from 120 .. 149
[19:45:47.745] INFO: dacScan split into 1 runs with ntrig = 5
[19:45:47.745] INFO: run 1 of 1
[19:46:13.798] INFO: Test took 26053ms.
[19:46:14.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:46:53.570] INFO: PixTestBBMap::doTest() done, duration: 156 seconds
[19:46:53.570] INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[19:46:53.570] INFO: separation cut (per ROC): 124 135 103 115 108 100 126 115 135 116 94 113 111 116 119 123
[19:46:53.670] INFO: ######################################################################
[19:46:53.670] INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = 1
[19:46:53.670] INFO: ######################################################################
[19:46:53.670] INFO: ----------------------------------------------------------------------
[19:46:53.670] INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = 1
[19:46:53.670] INFO: ----------------------------------------------------------------------
[19:46:53.670] INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (-1/1) hits flags = 16 (plus default)
[19:46:53.684] INFO: dacScan split into 50 runs with ntrig = 1
[19:46:53.684] INFO: run 1 of 50
[19:47:42.273] INFO: Test took 48589ms.
[19:47:42.589] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:47:42.764] INFO: run 2 of 50
[19:48:31.259] INFO: Test took 48495ms.
[19:48:31.688] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:48:31.850] INFO: run 3 of 50
[19:49:20.349] INFO: Test took 48499ms.
[19:49:20.719] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:49:20.886] INFO: run 4 of 50
[19:50:09.244] INFO: Test took 48358ms.
[19:50:09.613] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:50:09.795] INFO: run 5 of 50
[19:50:58.029] INFO: Test took 48234ms.
[19:50:58.527] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:50:58.711] INFO: run 6 of 50
[19:51:47.088] INFO: Test took 48377ms.
[19:51:47.429] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:51:47.593] INFO: run 7 of 50
[19:52:36.170] INFO: Test took 48577ms.
[19:52:36.539] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:52:36.717] INFO: run 8 of 50
[19:53:25.304] INFO: Test took 48587ms.
[19:53:25.629] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:53:25.786] INFO: run 9 of 50
[19:54:14.357] INFO: Test took 48571ms.
[19:54:14.667] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:54:14.827] INFO: run 10 of 50
[19:55:03.409] INFO: Test took 48582ms.
[19:55:03.783] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:55:03.953] INFO: run 11 of 50
[19:55:52.521] INFO: Test took 48567ms.
[19:55:52.894] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:55:53.068] INFO: run 12 of 50
[19:56:41.666] INFO: Test took 48598ms.
[19:56:42.015] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:56:42.177] INFO: run 13 of 50
[19:57:30.757] INFO: Test took 48579ms.
[19:57:31.138] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:57:31.314] INFO: run 14 of 50
[19:58:19.945] INFO: Test took 48630ms.
[19:58:20.335] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:58:20.512] INFO: run 15 of 50
[19:59:09.053] INFO: Test took 48541ms.
[19:59:09.371] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:59:09.545] INFO: run 16 of 50
[19:59:58.020] INFO: Test took 48475ms.
[19:59:58.402] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:59:58.579] INFO: run 17 of 50
[20:00:46.969] INFO: Test took 48389ms.
[20:00:47.361] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:00:47.542] INFO: run 18 of 50
[20:01:35.929] INFO: Test took 48387ms.
[20:01:36.307] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:01:36.477] INFO: run 19 of 50
[20:02:24.817] INFO: Test took 48340ms.
[20:02:25.157] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:02:25.327] INFO: run 20 of 50
[20:03:13.771] INFO: Test took 48444ms.
[20:03:14.157] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:03:14.335] INFO: run 21 of 50
[20:04:02.815] INFO: Test took 48479ms.
[20:04:03.179] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:04:03.356] INFO: run 22 of 50
[20:04:51.909] INFO: Test took 48553ms.
[20:04:52.271] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:04:52.448] INFO: run 23 of 50
[20:05:40.973] INFO: Test took 48524ms.
[20:05:41.371] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:05:41.548] INFO: run 24 of 50
[20:06:29.969] INFO: Test took 48421ms.
[20:06:30.337] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:06:30.511] INFO: run 25 of 50
[20:07:18.960] INFO: Test took 48449ms.
[20:07:19.364] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:07:19.537] INFO: run 26 of 50
[20:08:07.991] INFO: Test took 48453ms.
[20:08:08.364] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:08:08.543] INFO: run 27 of 50
[20:08:56.955] INFO: Test took 48412ms.
[20:08:57.321] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:08:57.487] INFO: run 28 of 50
[20:09:45.750] INFO: Test took 48263ms.
[20:09:46.150] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:09:46.329] INFO: run 29 of 50
[20:10:34.595] INFO: Test took 48265ms.
[20:10:34.982] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:10:35.204] INFO: run 30 of 50
[20:11:23.514] INFO: Test took 48310ms.
[20:11:23.907] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:24.091] INFO: run 31 of 50
[20:12:12.432] INFO: Test took 48341ms.
[20:12:12.783] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:12:12.952] INFO: run 32 of 50
[20:13:01.433] INFO: Test took 48481ms.
[20:13:01.902] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:13:02.076] INFO: run 33 of 50
[20:13:50.476] INFO: Test took 48400ms.
[20:13:50.860] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:13:51.041] INFO: run 34 of 50
[20:14:39.384] INFO: Test took 48343ms.
[20:14:39.767] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:14:39.943] INFO: run 35 of 50
[20:15:28.301] INFO: Test took 48357ms.
[20:15:28.673] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:15:28.850] INFO: run 36 of 50
[20:16:17.413] INFO: Test took 48563ms.
[20:16:17.789] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:16:17.967] INFO: run 37 of 50
[20:17:06.576] INFO: Test took 48609ms.
[20:17:06.934] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:17:07.106] INFO: run 38 of 50
[20:17:55.708] INFO: Test took 48602ms.
[20:17:56.077] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:17:56.260] INFO: run 39 of 50
[20:18:44.867] INFO: Test took 48607ms.
[20:18:45.238] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:18:45.415] INFO: run 40 of 50
[20:19:34.093] INFO: Test took 48678ms.
[20:19:34.497] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:19:34.703] INFO: run 41 of 50
[20:20:23.235] INFO: Test took 48531ms.
[20:20:23.607] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:20:23.782] INFO: run 42 of 50
[20:21:12.255] INFO: Test took 48473ms.
[20:21:12.619] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:21:12.801] INFO: run 43 of 50
[20:22:01.275] INFO: Test took 48474ms.
[20:22:01.642] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:22:01.817] INFO: run 44 of 50
[20:22:50.444] INFO: Test took 48626ms.
[20:22:50.833] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:22:51.011] INFO: run 45 of 50
[20:23:39.689] INFO: Test took 48678ms.
[20:23:40.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:23:40.197] INFO: run 46 of 50
[20:24:28.739] INFO: Test took 48541ms.
[20:24:29.093] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:24:29.273] INFO: run 47 of 50
[20:25:17.778] INFO: Test took 48505ms.
[20:25:18.170] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:25:18.352] INFO: run 48 of 50
[20:26:06.769] INFO: Test took 48417ms.
[20:26:07.158] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:26:07.334] INFO: run 49 of 50
[20:26:55.842] INFO: Test took 48508ms.
[20:26:56.190] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:26:56.366] INFO: run 50 of 50
[20:27:44.952] INFO: Test took 48586ms.
[20:27:45.278] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:27:45.451] INFO: dumping ASCII scurve output file: SCurveData
[20:27:47.650] INFO: dumping ASCII scurve output file: SCurveData
[20:27:49.763] INFO: dumping ASCII scurve output file: SCurveData
[20:27:51.973] INFO: dumping ASCII scurve output file: SCurveData
[20:27:54.161] INFO: dumping ASCII scurve output file: SCurveData
[20:27:56.413] INFO: dumping ASCII scurve output file: SCurveData
[20:27:58.676] INFO: dumping ASCII scurve output file: SCurveData
[20:28:00.950] INFO: dumping ASCII scurve output file: SCurveData
[20:28:03.326] INFO: dumping ASCII scurve output file: SCurveData
[20:28:05.510] INFO: dumping ASCII scurve output file: SCurveData
[20:28:07.852] INFO: dumping ASCII scurve output file: SCurveData
[20:28:10.337] INFO: dumping ASCII scurve output file: SCurveData
[20:28:12.812] INFO: dumping ASCII scurve output file: SCurveData
[20:28:15.194] INFO: dumping ASCII scurve output file: SCurveData
[20:28:17.779] INFO: dumping ASCII scurve output file: SCurveData
[20:28:20.146] INFO: dumping ASCII scurve output file: SCurveData
[20:28:22.619] INFO: PixTestScurves::scurves() done
[20:28:22.619] INFO: Vcal mean: 111.24 125.30 97.94 106.37 108.02 96.00 101.35 89.50 110.16 107.44 92.93 107.60 103.56 113.55 107.83 95.45
[20:28:22.619] INFO: Vcal RMS: 5.51 6.80 5.78 5.34 6.16 5.77 5.61 6.14 5.07 5.96 6.43 5.25 5.51 6.33 5.63 5.30
[20:28:22.619] INFO: PixTestScurves::fullTest() done, duration: 2488 seconds
[20:28:22.729] INFO: ######################################################################
[20:28:22.729] INFO: PixTestTrim::doTest()
[20:28:22.729] INFO: ######################################################################
[20:28:22.731] INFO: ----------------------------------------------------------------------
[20:28:22.731] INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[20:28:22.731] INFO: ----------------------------------------------------------------------
[20:28:22.886] INFO: ---> VthrComp thr map (minimal VthrComp)
[20:28:22.886] INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[20:28:22.900] INFO: dacScan step from 0 .. 19
[20:28:22.900] INFO: dacScan split into 1 runs with ntrig = 5
[20:28:22.900] INFO: run 1 of 1
[20:28:36.571] INFO: Test took 13671ms.
[20:28:36.615] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:28:36.615] INFO: dacScan step from 20 .. 39
[20:28:36.615] INFO: dacScan split into 1 runs with ntrig = 5
[20:28:36.615] INFO: run 1 of 1
[20:28:50.242] INFO: Test took 13627ms.
[20:28:50.287] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:28:50.287] INFO: dacScan step from 40 .. 59
[20:28:50.287] INFO: dacScan split into 1 runs with ntrig = 5
[20:28:50.287] INFO: run 1 of 1
[20:29:03.960] INFO: Test took 13672ms.
[20:29:04.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:29:04.004] INFO: dacScan step from 60 .. 79
[20:29:04.004] INFO: dacScan split into 1 runs with ntrig = 5
[20:29:04.004] INFO: run 1 of 1
[20:29:17.691] INFO: Test took 13687ms.
[20:29:17.737] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:29:17.737] INFO: dacScan step from 80 .. 99
[20:29:17.737] INFO: dacScan split into 1 runs with ntrig = 5
[20:29:17.737] INFO: run 1 of 1
[20:29:31.554] INFO: Test took 13817ms.
[20:29:31.619] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:29:31.623] INFO: dacScan step from 100 .. 119
[20:29:31.623] INFO: dacScan split into 1 runs with ntrig = 5
[20:29:31.624] INFO: run 1 of 1
[20:29:47.247] INFO: Test took 15623ms.
[20:29:47.447] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:29:47.494] INFO: dacScan step from 120 .. 139
[20:29:47.494] INFO: dacScan split into 1 runs with ntrig = 5
[20:29:47.494] INFO: run 1 of 1
[20:30:06.269] INFO: Test took 18775ms.
[20:30:06.550] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:30:06.617] INFO: dacScan step from 140 .. 159
[20:30:06.617] INFO: dacScan split into 1 runs with ntrig = 5
[20:30:06.617] INFO: run 1 of 1
[20:30:22.256] INFO: Test took 15639ms.
[20:30:22.414] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:30:58.765] INFO: ROC 0 VthrComp = 110
[20:30:58.765] INFO: ROC 1 VthrComp = 112
[20:30:58.765] INFO: ROC 2 VthrComp = 98
[20:30:58.765] INFO: ROC 3 VthrComp = 107
[20:30:58.766] INFO: ROC 4 VthrComp = 98
[20:30:58.766] INFO: ROC 5 VthrComp = 96
[20:30:58.766] INFO: ROC 6 VthrComp = 97
[20:30:58.766] INFO: ROC 7 VthrComp = 88
[20:30:58.766] INFO: ROC 8 VthrComp = 107
[20:30:58.766] INFO: ROC 9 VthrComp = 104
[20:30:58.766] INFO: ROC 10 VthrComp = 84
[20:30:58.767] INFO: ROC 11 VthrComp = 104
[20:30:58.767] INFO: ROC 12 VthrComp = 101
[20:30:58.767] INFO: ROC 13 VthrComp = 109
[20:30:58.767] INFO: ROC 14 VthrComp = 106
[20:30:58.767] INFO: ROC 15 VthrComp = 96
[20:30:58.767] INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[20:30:58.767] INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[20:30:58.783] INFO: dacScan step from 0 .. 19
[20:30:58.783] INFO: dacScan split into 1 runs with ntrig = 5
[20:30:58.783] INFO: run 1 of 1
[20:31:12.549] INFO: Test took 13766ms.
[20:31:12.598] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:31:12.598] INFO: dacScan step from 20 .. 39
[20:31:12.598] INFO: dacScan split into 1 runs with ntrig = 5
[20:31:12.598] INFO: run 1 of 1
[20:31:26.314] INFO: Test took 13716ms.
[20:31:26.377] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:31:26.381] INFO: dacScan step from 40 .. 59
[20:31:26.381] INFO: dacScan split into 1 runs with ntrig = 5
[20:31:26.381] INFO: run 1 of 1
[20:31:43.036] INFO: Test took 16655ms.
[20:31:43.310] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:31:43.373] INFO: dacScan step from 60 .. 79
[20:31:43.373] INFO: dacScan split into 1 runs with ntrig = 5
[20:31:43.373] INFO: run 1 of 1
[20:32:02.376] INFO: Test took 19003ms.
[20:32:02.688] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:32:02.772] INFO: dacScan step from 80 .. 99
[20:32:02.772] INFO: dacScan split into 1 runs with ntrig = 5
[20:32:02.772] INFO: run 1 of 1
[20:32:21.927] INFO: Test took 19155ms.
[20:32:22.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:32:22.331] INFO: dacScan step from 100 .. 119
[20:32:22.331] INFO: dacScan split into 1 runs with ntrig = 5
[20:32:22.331] INFO: run 1 of 1
[20:32:41.458] INFO: Test took 19127ms.
[20:32:41.777] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:32:41.854] INFO: dacScan step from 120 .. 139
[20:32:41.854] INFO: dacScan split into 1 runs with ntrig = 5
[20:32:41.854] INFO: run 1 of 1
[20:33:02.598] INFO: Test took 20743ms.
[20:33:02.915] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:33:02.995] INFO: dacScan step from 140 .. 159
[20:33:02.995] INFO: dacScan split into 1 runs with ntrig = 5
[20:33:02.995] INFO: run 1 of 1
[20:33:22.322] INFO: Test took 19327ms.
[20:33:22.642] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:34:07.011] INFO: roc 0 with ID = 0 has maximal Vcal 62.1037 for pixel 10/2 mean/min/max = 48.4466/34.7131/62.18
[20:34:07.011] INFO: roc 1 with ID = 1 has maximal Vcal 75.778 for pixel 9/79 mean/min/max = 58.2166/40.5576/75.8755
[20:34:07.011] INFO: roc 2 with ID = 2 has maximal Vcal 58.2853 for pixel 18/10 mean/min/max = 44.8824/31.239/58.5257
[20:34:07.012] INFO: roc 3 with ID = 3 has maximal Vcal 60.8198 for pixel 20/74 mean/min/max = 47.5378/34.0998/60.9757
[20:34:07.012] INFO: roc 4 with ID = 4 has maximal Vcal 64.886 for pixel 0/8 mean/min/max = 48.4406/31.9893/64.8919
[20:34:07.012] INFO: roc 5 with ID = 5 has maximal Vcal 57.9003 for pixel 10/64 mean/min/max = 45.0446/32.1603/57.9289
[20:34:07.013] INFO: roc 6 with ID = 6 has maximal Vcal 58.2266 for pixel 0/3 mean/min/max = 45.6808/32.9477/58.4139
[20:34:07.013] INFO: roc 7 with ID = 7 has maximal Vcal 61.4195 for pixel 18/79 mean/min/max = 47.2711/33.0919/61.4503
[20:34:07.014] INFO: roc 8 with ID = 8 has maximal Vcal 60.5607 for pixel 0/1 mean/min/max = 47.244/33.9069/60.5812
[20:34:07.014] INFO: roc 9 with ID = 9 has maximal Vcal 67.0691 for pixel 2/41 mean/min/max = 50.5708/33.9133/67.2284
[20:34:07.015] INFO: roc 10 with ID = 10 has maximal Vcal 64.1269 for pixel 9/51 mean/min/max = 48.1384/32.142/64.1347
[20:34:07.015] INFO: roc 11 with ID = 11 has maximal Vcal 62.2068 for pixel 15/73 mean/min/max = 48.36/34.4008/62.3192
[20:34:07.016] INFO: roc 12 with ID = 12 has maximal Vcal 59.127 for pixel 9/78 mean/min/max = 45.6239/31.9501/59.2976
[20:34:07.016] INFO: roc 13 with ID = 13 has maximal Vcal 63.8253 for pixel 14/51 mean/min/max = 49.0296/34.1956/63.8636
[20:34:07.017] INFO: roc 14 with ID = 14 has maximal Vcal 61.3093 for pixel 4/0 mean/min/max = 47.3268/33.3395/61.3141
[20:34:07.017] INFO: roc 15 with ID = 15 has maximal Vcal 58.6855 for pixel 1/17 mean/min/max = 45.3647/31.906/58.8234
[20:34:07.017] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:35:29.934] INFO: Test took 82917ms.
[20:35:32.245] INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[20:35:32.260] INFO: dacScan step from 0 .. 19
[20:35:32.260] INFO: dacScan split into 2 runs with ntrig = 5
[20:35:32.260] INFO: run 1 of 2
[20:35:45.807] INFO: Test took 13546ms.
[20:35:45.865] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:35:45.868] INFO: run 2 of 2
[20:35:59.574] INFO: Test took 13706ms.
[20:35:59.622] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:35:59.623] INFO: dacScan step from 20 .. 39
[20:35:59.623] INFO: dacScan split into 2 runs with ntrig = 5
[20:35:59.623] INFO: run 1 of 2
[20:36:15.709] INFO: Test took 16086ms.
[20:36:15.944] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:36:16.012] INFO: run 2 of 2
[20:36:31.931] INFO: Test took 15919ms.
[20:36:32.181] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:36:32.241] INFO: dacScan step from 40 .. 59
[20:36:32.241] INFO: dacScan split into 2 runs with ntrig = 5
[20:36:32.241] INFO: run 1 of 2
[20:36:51.086] INFO: Test took 18845ms.
[20:36:51.428] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:36:51.518] INFO: run 2 of 2
[20:37:10.482] INFO: Test took 18963ms.
[20:37:10.817] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:37:10.897] INFO: dacScan step from 60 .. 79
[20:37:10.897] INFO: dacScan split into 2 runs with ntrig = 5
[20:37:10.897] INFO: run 1 of 2
[20:37:29.995] INFO: Test took 19098ms.
[20:37:30.326] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:37:30.407] INFO: run 2 of 2
[20:37:49.417] INFO: Test took 19010ms.
[20:37:49.737] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:37:49.824] INFO: dacScan step from 80 .. 99
[20:37:49.824] INFO: dacScan split into 2 runs with ntrig = 5
[20:37:49.824] INFO: run 1 of 2
[20:38:08.790] INFO: Test took 18966ms.
[20:38:09.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:38:09.215] INFO: run 2 of 2
[20:38:28.292] INFO: Test took 19077ms.
[20:38:28.603] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:38:28.689] INFO: dacScan step from 100 .. 119
[20:38:28.689] INFO: dacScan split into 2 runs with ntrig = 5
[20:38:28.689] INFO: run 1 of 2
[20:38:47.987] INFO: Test took 19298ms.
[20:38:48.309] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:38:48.393] INFO: run 2 of 2
[20:39:07.575] INFO: Test took 19181ms.
[20:39:07.906] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:39:07.986] INFO: dacScan step from 120 .. 139
[20:39:07.986] INFO: dacScan split into 2 runs with ntrig = 5
[20:39:07.986] INFO: run 1 of 2
[20:39:28.684] INFO: Test took 20697ms.
[20:39:29.017] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:39:29.099] INFO: run 2 of 2
[20:39:49.780] INFO: Test took 20681ms.
[20:39:50.114] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:39:50.197] INFO: dacScan step from 140 .. 159
[20:39:50.197] INFO: dacScan split into 2 runs with ntrig = 5
[20:39:50.197] INFO: run 1 of 2
[20:40:09.737] INFO: Test took 19539ms.
[20:40:10.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:40:10.146] INFO: run 2 of 2
[20:40:29.531] INFO: Test took 19384ms.
[20:40:29.859] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:40:29.939] INFO: dacScan step from 160 .. 179
[20:40:29.939] INFO: dacScan split into 2 runs with ntrig = 5
[20:40:29.939] INFO: run 1 of 2
[20:40:49.272] INFO: Test took 19333ms.
[20:40:49.582] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:40:49.662] INFO: run 2 of 2
[20:41:08.918] INFO: Test took 19256ms.
[20:41:09.243] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:41:09.328] INFO: dacScan step from 180 .. 199
[20:41:09.328] INFO: dacScan split into 2 runs with ntrig = 5
[20:41:09.328] INFO: run 1 of 2
[20:41:29.301] INFO: Test took 19973ms.
[20:41:29.631] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:41:29.717] INFO: run 2 of 2
[20:41:49.741] INFO: Test took 20024ms.
[20:41:50.067] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:42:33.896] INFO: ---> TrimStepCorr4 extremal thresholds: 0.020090 .. 255.000000
[20:42:34.050] INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20/-1) hits flags = 16 (plus default)
[20:42:34.066] INFO: dacScan step from 0 .. 19
[20:42:34.066] INFO: dacScan split into 1 runs with ntrig = 4
[20:42:34.066] INFO: run 1 of 1
[20:42:46.423] INFO: Test took 12357ms.
[20:42:46.467] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:42:46.467] INFO: dacScan step from 20 .. 39
[20:42:46.467] INFO: dacScan split into 1 runs with ntrig = 4
[20:42:46.467] INFO: run 1 of 1
[20:42:59.697] INFO: Test took 13230ms.
[20:42:59.833] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:42:59.864] INFO: dacScan step from 40 .. 59
[20:42:59.864] INFO: dacScan split into 1 runs with ntrig = 4
[20:42:59.864] INFO: run 1 of 1
[20:43:16.327] INFO: Test took 16463ms.
[20:43:16.618] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:43:16.703] INFO: dacScan step from 60 .. 79
[20:43:16.704] INFO: dacScan split into 1 runs with ntrig = 4
[20:43:16.704] INFO: run 1 of 1
[20:43:33.536] INFO: Test took 16832ms.
[20:43:33.826] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:43:33.901] INFO: dacScan step from 80 .. 99
[20:43:33.901] INFO: dacScan split into 1 runs with ntrig = 4
[20:43:33.901] INFO: run 1 of 1
[20:43:50.662] INFO: Test took 16760ms.
[20:43:50.945] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:43:51.027] INFO: dacScan step from 100 .. 119
[20:43:51.027] INFO: dacScan split into 1 runs with ntrig = 4
[20:43:51.027] INFO: run 1 of 1
[20:44:07.802] INFO: Test took 16775ms.
[20:44:08.071] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:44:08.153] INFO: dacScan step from 120 .. 139
[20:44:08.153] INFO: dacScan split into 1 runs with ntrig = 4
[20:44:08.153] INFO: run 1 of 1
[20:44:26.305] INFO: Test took 18151ms.
[20:44:26.586] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:44:26.689] INFO: dacScan step from 140 .. 159
[20:44:26.689] INFO: dacScan split into 1 runs with ntrig = 4
[20:44:26.689] INFO: run 1 of 1
[20:44:43.561] INFO: Test took 16871ms.
[20:44:43.857] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:44:43.931] INFO: dacScan step from 160 .. 179
[20:44:43.931] INFO: dacScan split into 1 runs with ntrig = 4
[20:44:43.931] INFO: run 1 of 1
[20:45:00.750] INFO: Test took 16819ms.
[20:45:01.035] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:45:01.114] INFO: dacScan step from 180 .. 199
[20:45:01.114] INFO: dacScan split into 1 runs with ntrig = 4
[20:45:01.114] INFO: run 1 of 1
[20:45:18.812] INFO: Test took 17698ms.
[20:45:19.117] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:45:19.205] INFO: dacScan step from 200 .. 219
[20:45:19.205] INFO: dacScan split into 1 runs with ntrig = 4
[20:45:19.205] INFO: run 1 of 1
[20:45:36.028] INFO: Test took 16823ms.
[20:45:36.387] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:45:36.479] INFO: dacScan step from 220 .. 239
[20:45:36.479] INFO: dacScan split into 1 runs with ntrig = 4
[20:45:36.479] INFO: run 1 of 1
[20:45:53.126] INFO: Test took 16647ms.
[20:45:53.426] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:45:53.512] INFO: dacScan step from 240 .. 255
[20:45:53.512] INFO: dacScan split into 1 runs with ntrig = 4
[20:45:53.512] INFO: run 1 of 1
[20:46:07.375] INFO: Test took 13863ms.
[20:46:07.622] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:01.594] INFO: ---> TrimStepCorr2 extremal thresholds: 15.820860 .. 44.166242
[20:47:01.780] INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 5 .. 54 (20/-1) hits flags = 16 (plus default)
[20:47:01.795] INFO: dacScan step from 5 .. 24
[20:47:01.795] INFO: dacScan split into 1 runs with ntrig = 4
[20:47:01.795] INFO: run 1 of 1
[20:47:14.148] INFO: Test took 12353ms.
[20:47:14.193] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:14.194] INFO: dacScan step from 25 .. 44
[20:47:14.194] INFO: dacScan split into 1 runs with ntrig = 4
[20:47:14.194] INFO: run 1 of 1
[20:47:28.345] INFO: Test took 14151ms.
[20:47:28.558] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:28.615] INFO: dacScan step from 45 .. 54
[20:47:28.615] INFO: dacScan split into 1 runs with ntrig = 4
[20:47:28.615] INFO: run 1 of 1
[20:47:38.549] INFO: Test took 9934ms.
[20:47:38.694] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:48:03.349] INFO: ---> TrimStepCorr1a extremal thresholds: 1.927685 .. 40.616745
[20:48:03.498] INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 1 .. 50 (20/-1) hits flags = 16 (plus default)
[20:48:03.512] INFO: dacScan step from 1 .. 20
[20:48:03.513] INFO: dacScan split into 1 runs with ntrig = 4
[20:48:03.513] INFO: run 1 of 1
[20:48:15.861] INFO: Test took 12348ms.
[20:48:15.901] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:48:15.901] INFO: dacScan step from 21 .. 40
[20:48:15.901] INFO: dacScan split into 1 runs with ntrig = 4
[20:48:15.901] INFO: run 1 of 1
[20:48:29.306] INFO: Test took 13405ms.
[20:48:29.450] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:48:29.488] INFO: dacScan step from 41 .. 50
[20:48:29.488] INFO: dacScan split into 1 runs with ntrig = 4
[20:48:29.488] INFO: run 1 of 1
[20:48:39.275] INFO: Test took 9787ms.
[20:48:39.420] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:49:03.226] INFO: ---> TrimStepCorr1b extremal thresholds: 1.791770 .. 38.498529
[20:49:03.376] INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 1 .. 48 (20/-1) hits flags = 16 (plus default)
[20:49:03.391] INFO: dacScan step from 1 .. 20
[20:49:03.391] INFO: dacScan split into 1 runs with ntrig = 4
[20:49:03.391] INFO: run 1 of 1
[20:49:15.718] INFO: Test took 12327ms.
[20:49:15.761] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:49:15.761] INFO: dacScan step from 21 .. 40
[20:49:15.761] INFO: dacScan split into 1 runs with ntrig = 4
[20:49:15.761] INFO: run 1 of 1
[20:49:29.314] INFO: Test took 13553ms.
[20:49:29.455] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:49:29.491] INFO: dacScan step from 41 .. 48
[20:49:29.491] INFO: dacScan split into 1 runs with ntrig = 4
[20:49:29.491] INFO: run 1 of 1
[20:49:37.797] INFO: Test took 8305ms.
[20:49:37.915] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:50:01.632] INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[20:50:01.632] INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20/5) hits flags = 16 (plus default)
[20:50:01.649] INFO: dacScan step from 15 .. 34
[20:50:01.649] INFO: dacScan split into 2 runs with ntrig = 5
[20:50:01.649] INFO: run 1 of 2
[20:50:15.668] INFO: Test took 14019ms.
[20:50:15.753] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:50:15.768] INFO: run 2 of 2
[20:50:29.663] INFO: Test took 13895ms.
[20:50:29.750] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:50:29.764] INFO: dacScan step from 35 .. 54
[20:50:29.764] INFO: dacScan split into 2 runs with ntrig = 5
[20:50:29.764] INFO: run 1 of 2
[20:50:48.289] INFO: Test took 18525ms.
[20:50:48.625] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:50:48.709] INFO: run 2 of 2
[20:51:07.260] INFO: Test took 18550ms.
[20:51:07.584] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:51:07.667] INFO: dacScan step from 55 .. 55
[20:51:07.667] INFO: dacScan split into 2 runs with ntrig = 5
[20:51:07.667] INFO: run 1 of 2
[20:51:11.349] INFO: Test took 3681ms.
[20:51:11.372] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:51:11.380] INFO: run 2 of 2
[20:51:15.046] INFO: Test took 3666ms.
[20:51:15.068] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:51:35.996] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat
[20:51:35.996] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C1.dat
[20:51:35.997] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C2.dat
[20:51:35.997] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C3.dat
[20:51:35.997] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C4.dat
[20:51:35.998] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C5.dat
[20:51:35.998] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C6.dat
[20:51:35.998] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C7.dat
[20:51:35.998] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C8.dat
[20:51:35.999] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C9.dat
[20:51:35.999] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C10.dat
[20:51:35.999] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C11.dat
[20:51:35.000] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C12.dat
[20:51:35.000] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C13.dat
[20:51:35.000] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C14.dat
[20:51:35.000] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[20:51:35.000] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat
[20:51:36.017] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C1.dat
[20:51:36.030] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C2.dat
[20:51:36.042] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C3.dat
[20:51:36.055] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C4.dat
[20:51:36.068] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C5.dat
[20:51:36.080] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C6.dat
[20:51:36.093] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C7.dat
[20:51:36.105] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C8.dat
[20:51:36.118] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C9.dat
[20:51:36.131] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C10.dat
[20:51:36.144] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C11.dat
[20:51:36.156] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C12.dat
[20:51:36.168] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C13.dat
[20:51:36.179] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C14.dat
[20:51:36.192] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[20:51:36.203] INFO: PixTestTrim::trimTest() done
[20:51:36.203] INFO: vtrim: 131 161 108 120 116 92 99 112 102 135 106 115 104 126 115 98
[20:51:36.203] INFO: vthrcomp: 110 112 98 107 98 96 97 88 107 104 84 104 101 109 106 96
[20:51:36.203] INFO: vcal mean: 35.13 35.12 35.11 35.06 35.13 35.08 35.16 35.14 35.13 35.14 35.08 35.15 35.10 35.14 35.09 35.11
[20:51:36.203] INFO: vcal RMS: 1.11 1.26 1.21 1.05 1.18 1.11 1.16 1.12 1.04 1.30 1.24 1.13 1.25 1.17 1.10 1.10
[20:51:36.203] INFO: bits mean: 8.99 7.19 10.26 9.20 9.28 10.05 9.68 9.28 9.08 9.13 9.43 9.25 9.79 9.24 9.20 10.01
[20:51:36.203] INFO: bits RMS: 2.36 2.08 2.49 2.41 2.60 2.45 2.56 2.55 2.53 2.30 2.60 2.32 2.58 2.32 2.52 2.51
[20:51:36.216] INFO: ----------------------------------------------------------------------
[20:51:36.216] INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[20:51:36.216] INFO: ----------------------------------------------------------------------
[20:51:36.221] INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[20:51:36.237] INFO: dacScan step from 0 .. 19
[20:51:36.237] INFO: dacScan split into 2 runs with ntrig = 5
[20:51:36.237] INFO: run 1 of 2
[20:51:49.825] INFO: Test took 13588ms.
[20:51:49.871] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:51:49.871] INFO: run 2 of 2
[20:52:03.485] INFO: Test took 13614ms.
[20:52:03.535] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:52:03.535] INFO: dacScan step from 20 .. 39
[20:52:03.535] INFO: dacScan split into 2 runs with ntrig = 5
[20:52:03.535] INFO: run 1 of 2
[20:52:17.315] INFO: Test took 13780ms.
[20:52:17.365] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:52:17.365] INFO: run 2 of 2
[20:52:30.003] INFO: Test took 13638ms.
[20:52:31.050] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:52:31.050] INFO: dacScan step from 40 .. 59
[20:52:31.050] INFO: dacScan split into 2 runs with ntrig = 5
[20:52:31.050] INFO: run 1 of 2
[20:52:44.783] INFO: Test took 13732ms.
[20:52:44.827] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:52:44.827] INFO: run 2 of 2
[20:52:58.526] INFO: Test took 13699ms.
[20:52:58.571] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:52:58.571] INFO: dacScan step from 60 .. 79
[20:52:58.571] INFO: dacScan split into 2 runs with ntrig = 5
[20:52:58.572] INFO: run 1 of 2
[20:53:12.274] INFO: Test took 13702ms.
[20:53:12.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:53:12.319] INFO: run 2 of 2
[20:53:25.909] INFO: Test took 13589ms.
[20:53:25.958] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:53:25.958] INFO: dacScan step from 80 .. 99
[20:53:25.958] INFO: dacScan split into 2 runs with ntrig = 5
[20:53:25.958] INFO: run 1 of 2
[20:53:39.580] INFO: Test took 13622ms.
[20:53:39.628] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:53:39.628] INFO: run 2 of 2
[20:53:53.303] INFO: Test took 13675ms.
[20:53:53.351] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:53:53.352] INFO: dacScan step from 100 .. 119
[20:53:53.352] INFO: dacScan split into 2 runs with ntrig = 5
[20:53:53.352] INFO: run 1 of 2
[20:54:07.195] INFO: Test took 13843ms.
[20:54:07.266] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:54:07.273] INFO: run 2 of 2
[20:54:21.168] INFO: Test took 13895ms.
[20:54:21.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:54:21.248] INFO: dacScan step from 120 .. 139
[20:54:21.248] INFO: dacScan split into 2 runs with ntrig = 5
[20:54:21.248] INFO: run 1 of 2
[20:54:38.355] INFO: Test took 17107ms.
[20:54:38.567] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:54:38.606] INFO: run 2 of 2
[20:54:55.661] INFO: Test took 17055ms.
[20:54:55.870] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:54:55.911] INFO: dacScan step from 140 .. 159
[20:54:55.911] INFO: dacScan split into 2 runs with ntrig = 5
[20:54:55.911] INFO: run 1 of 2
[20:55:14.494] INFO: Test took 18583ms.
[20:55:14.825] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:55:14.896] INFO: run 2 of 2
[20:55:33.450] INFO: Test took 18554ms.
[20:55:33.764] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:55:33.844] INFO: dacScan step from 160 .. 179
[20:55:33.844] INFO: dacScan split into 2 runs with ntrig = 5
[20:55:33.844] INFO: run 1 of 2
[20:55:53.084] INFO: Test took 19240ms.
[20:55:53.425] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:55:53.509] INFO: run 2 of 2
[20:56:12.736] INFO: Test took 19226ms.
[20:56:13.080] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:56:13.182] INFO: dacScan step from 180 .. 199
[20:56:13.182] INFO: dacScan split into 2 runs with ntrig = 5
[20:56:13.182] INFO: run 1 of 2
[20:56:33.097] INFO: Test took 19915ms.
[20:56:33.493] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:56:33.588] INFO: run 2 of 2
[20:56:53.620] INFO: Test took 20032ms.
[20:56:53.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:57:36.453] INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 200 (20/5) hits flags = 16 (plus default)
[20:57:36.468] INFO: dacScan step from 0 .. 19
[20:57:36.468] INFO: dacScan split into 2 runs with ntrig = 5
[20:57:36.468] INFO: run 1 of 2
[20:57:50.084] INFO: Test took 13616ms.
[20:57:50.135] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:57:50.135] INFO: run 2 of 2
[20:58:03.833] INFO: Test took 13698ms.
[20:58:03.882] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:58:03.882] INFO: dacScan step from 20 .. 39
[20:58:03.882] INFO: dacScan split into 2 runs with ntrig = 5
[20:58:03.882] INFO: run 1 of 2
[20:58:17.451] INFO: Test took 13569ms.
[20:58:17.498] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:58:17.499] INFO: run 2 of 2
[20:58:31.135] INFO: Test took 13636ms.
[20:58:31.185] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:58:31.185] INFO: dacScan step from 40 .. 59
[20:58:31.185] INFO: dacScan split into 2 runs with ntrig = 5
[20:58:31.185] INFO: run 1 of 2
[20:58:44.853] INFO: Test took 13668ms.
[20:58:44.895] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:58:44.895] INFO: run 2 of 2
[20:58:58.549] INFO: Test took 13654ms.
[20:58:58.598] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:58:58.598] INFO: dacScan step from 60 .. 79
[20:58:58.598] INFO: dacScan split into 2 runs with ntrig = 5
[20:58:58.598] INFO: run 1 of 2
[20:59:12.358] INFO: Test took 13760ms.
[20:59:12.410] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:59:12.410] INFO: run 2 of 2
[20:59:26.134] INFO: Test took 13724ms.
[20:59:26.176] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:59:26.176] INFO: dacScan step from 80 .. 99
[20:59:26.176] INFO: dacScan split into 2 runs with ntrig = 5
[20:59:26.176] INFO: run 1 of 2
[20:59:39.861] INFO: Test took 13685ms.
[20:59:39.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:59:39.905] INFO: run 2 of 2
[20:59:53.673] INFO: Test took 13767ms.
[20:59:53.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:59:53.715] INFO: dacScan step from 100 .. 119
[20:59:53.715] INFO: dacScan split into 2 runs with ntrig = 5
[20:59:53.715] INFO: run 1 of 2
[21:00:08.325] INFO: Test took 14610ms.
[21:00:08.440] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:00:08.459] INFO: run 2 of 2
[21:00:22.995] INFO: Test took 14536ms.
[21:00:23.127] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:00:23.146] INFO: dacScan step from 120 .. 139
[21:00:23.146] INFO: dacScan split into 2 runs with ntrig = 5
[21:00:23.146] INFO: run 1 of 2
[21:00:41.960] INFO: Test took 18813ms.
[21:00:42.250] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:00:42.312] INFO: run 2 of 2
[21:01:01.080] INFO: Test took 18768ms.
[21:01:01.372] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:01:01.438] INFO: dacScan step from 140 .. 159
[21:01:01.438] INFO: dacScan split into 2 runs with ntrig = 5
[21:01:01.438] INFO: run 1 of 2
[21:01:20.473] INFO: Test took 19035ms.
[21:01:20.886] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:01:20.981] INFO: run 2 of 2
[21:01:40.151] INFO: Test took 19170ms.
[21:01:40.461] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:01:40.545] INFO: dacScan step from 160 .. 179
[21:01:40.545] INFO: dacScan split into 2 runs with ntrig = 5
[21:01:40.545] INFO: run 1 of 2
[21:01:59.708] INFO: Test took 19163ms.
[21:02:00.049] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:02:00.136] INFO: run 2 of 2
[21:02:19.303] INFO: Test took 19167ms.
[21:02:19.630] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:02:19.711] INFO: dacScan step from 180 .. 199
[21:02:19.711] INFO: dacScan split into 2 runs with ntrig = 5
[21:02:19.711] INFO: run 1 of 2
[21:02:39.646] INFO: Test took 19935ms.
[21:02:40.061] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:02:40.150] INFO: run 2 of 2
[21:03:00.081] INFO: Test took 19931ms.
[21:03:00.426] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:03:00.513] INFO: dacScan step from 200 .. 200
[21:03:00.513] INFO: dacScan split into 2 runs with ntrig = 5
[21:03:00.513] INFO: run 1 of 2
[21:03:04.183] INFO: Test took 3670ms.
[21:03:04.204] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:03:04.211] INFO: run 2 of 2
[21:03:07.875] INFO: Test took 3664ms.
[21:03:07.898] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:03:55.586] INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 192 (20/5) hits flags = 16 (plus default)
[21:03:55.602] INFO: dacScan step from 0 .. 19
[21:03:55.602] INFO: dacScan split into 2 runs with ntrig = 5
[21:03:55.602] INFO: run 1 of 2
[21:04:09.295] INFO: Test took 13694ms.
[21:04:09.344] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:04:09.344] INFO: run 2 of 2
[21:04:23.020] INFO: Test took 13675ms.
[21:04:23.061] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:04:23.061] INFO: dacScan step from 20 .. 39
[21:04:23.061] INFO: dacScan split into 2 runs with ntrig = 5
[21:04:23.061] INFO: run 1 of 2
[21:04:36.626] INFO: Test took 13565ms.
[21:04:36.677] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:04:36.677] INFO: run 2 of 2
[21:04:50.287] INFO: Test took 13610ms.
[21:04:50.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:04:50.332] INFO: dacScan step from 40 .. 59
[21:04:50.332] INFO: dacScan split into 2 runs with ntrig = 5
[21:04:50.332] INFO: run 1 of 2
[21:05:03.886] INFO: Test took 13554ms.
[21:05:03.929] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:05:03.929] INFO: run 2 of 2
[21:05:17.418] INFO: Test took 13489ms.
[21:05:17.464] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:05:17.464] INFO: dacScan step from 60 .. 79
[21:05:17.464] INFO: dacScan split into 2 runs with ntrig = 5
[21:05:17.464] INFO: run 1 of 2
[21:05:31.021] INFO: Test took 13557ms.
[21:05:31.071] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:05:31.071] INFO: run 2 of 2
[21:05:44.647] INFO: Test took 13575ms.
[21:05:44.692] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:05:44.692] INFO: dacScan step from 80 .. 99
[21:05:44.692] INFO: dacScan split into 2 runs with ntrig = 5
[21:05:44.692] INFO: run 1 of 2
[21:05:58.319] INFO: Test took 13627ms.
[21:05:58.364] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:05:58.364] INFO: run 2 of 2
[21:06:12.023] INFO: Test took 13659ms.
[21:06:12.076] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:06:12.077] INFO: dacScan step from 100 .. 119
[21:06:12.077] INFO: dacScan split into 2 runs with ntrig = 5
[21:06:12.077] INFO: run 1 of 2
[21:06:26.545] INFO: Test took 14468ms.
[21:06:26.671] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:06:26.691] INFO: run 2 of 2
[21:06:41.185] INFO: Test took 14494ms.
[21:06:41.317] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:06:41.335] INFO: dacScan step from 120 .. 139
[21:06:41.335] INFO: dacScan split into 2 runs with ntrig = 5
[21:06:41.335] INFO: run 1 of 2
[21:07:00.144] INFO: Test took 18809ms.
[21:07:00.428] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:07:00.500] INFO: run 2 of 2
[21:07:19.349] INFO: Test took 18849ms.
[21:07:19.639] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:07:19.705] INFO: dacScan step from 140 .. 159
[21:07:19.705] INFO: dacScan split into 2 runs with ntrig = 5
[21:07:19.705] INFO: run 1 of 2
[21:07:38.673] INFO: Test took 18968ms.
[21:07:39.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:07:39.086] INFO: run 2 of 2
[21:07:58.005] INFO: Test took 18919ms.
[21:07:58.345] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:07:58.439] INFO: dacScan step from 160 .. 179
[21:07:58.439] INFO: dacScan split into 2 runs with ntrig = 5
[21:07:58.439] INFO: run 1 of 2
[21:08:17.661] INFO: Test took 19222ms.
[21:08:17.948] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:08:18.024] INFO: run 2 of 2
[21:08:37.106] INFO: Test took 19082ms.
[21:08:37.436] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:08:37.520] INFO: dacScan step from 180 .. 192
[21:08:37.520] INFO: dacScan split into 2 runs with ntrig = 5
[21:08:37.520] INFO: run 1 of 2
[21:08:51.833] INFO: Test took 14313ms.
[21:08:52.053] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:08:52.114] INFO: run 2 of 2
[21:09:06.349] INFO: Test took 14235ms.
[21:09:06.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:09:50.307] INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 193 (20/5) hits flags = 16 (plus default)
[21:09:50.321] INFO: dacScan step from 0 .. 19
[21:09:50.321] INFO: dacScan split into 2 runs with ntrig = 5
[21:09:50.321] INFO: run 1 of 2
[21:10:04.010] INFO: Test took 13689ms.
[21:10:04.059] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:10:04.059] INFO: run 2 of 2
[21:10:17.647] INFO: Test took 13587ms.
[21:10:17.690] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:10:17.690] INFO: dacScan step from 20 .. 39
[21:10:17.690] INFO: dacScan split into 2 runs with ntrig = 5
[21:10:17.690] INFO: run 1 of 2
[21:10:31.322] INFO: Test took 13631ms.
[21:10:31.369] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:10:31.369] INFO: run 2 of 2
[21:10:44.002] INFO: Test took 13633ms.
[21:10:45.047] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:10:45.048] INFO: dacScan step from 40 .. 59
[21:10:45.048] INFO: dacScan split into 2 runs with ntrig = 5
[21:10:45.048] INFO: run 1 of 2
[21:10:58.650] INFO: Test took 13602ms.
[21:10:58.689] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:10:58.689] INFO: run 2 of 2
[21:11:12.294] INFO: Test took 13605ms.
[21:11:12.341] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:11:12.341] INFO: dacScan step from 60 .. 79
[21:11:12.341] INFO: dacScan split into 2 runs with ntrig = 5
[21:11:12.341] INFO: run 1 of 2
[21:11:25.959] INFO: Test took 13617ms.
[21:11:25.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:11:25.002] INFO: run 2 of 2
[21:11:39.677] INFO: Test took 13675ms.
[21:11:39.718] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:11:39.718] INFO: dacScan step from 80 .. 99
[21:11:39.718] INFO: dacScan split into 2 runs with ntrig = 5
[21:11:39.718] INFO: run 1 of 2
[21:11:53.343] INFO: Test took 13625ms.
[21:11:53.389] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:11:53.390] INFO: run 2 of 2
[21:12:07.080] INFO: Test took 13690ms.
[21:12:07.128] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:12:07.129] INFO: dacScan step from 100 .. 119
[21:12:07.129] INFO: dacScan split into 2 runs with ntrig = 5
[21:12:07.129] INFO: run 1 of 2
[21:12:21.628] INFO: Test took 14499ms.
[21:12:21.760] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:12:21.782] INFO: run 2 of 2
[21:12:36.382] INFO: Test took 14600ms.
[21:12:36.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:12:36.541] INFO: dacScan step from 120 .. 139
[21:12:36.541] INFO: dacScan split into 2 runs with ntrig = 5
[21:12:36.541] INFO: run 1 of 2
[21:12:55.577] INFO: Test took 19035ms.
[21:12:55.846] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:12:55.906] INFO: run 2 of 2
[21:13:14.815] INFO: Test took 18909ms.
[21:13:15.096] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:13:15.158] INFO: dacScan step from 140 .. 159
[21:13:15.158] INFO: dacScan split into 2 runs with ntrig = 5
[21:13:15.158] INFO: run 1 of 2
[21:13:34.170] INFO: Test took 19012ms.
[21:13:34.491] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:13:34.571] INFO: run 2 of 2
[21:13:53.450] INFO: Test took 18879ms.
[21:13:53.796] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:13:53.878] INFO: dacScan step from 160 .. 179
[21:13:53.879] INFO: dacScan split into 2 runs with ntrig = 5
[21:13:53.879] INFO: run 1 of 2
[21:14:12.985] INFO: Test took 19106ms.
[21:14:13.348] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:14:13.437] INFO: run 2 of 2
[21:14:32.631] INFO: Test took 19194ms.
[21:14:32.974] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:14:33.061] INFO: dacScan step from 180 .. 193
[21:14:33.061] INFO: dacScan split into 2 runs with ntrig = 5
[21:14:33.061] INFO: run 1 of 2
[21:14:48.243] INFO: Test took 15182ms.
[21:14:48.483] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:14:48.546] INFO: run 2 of 2
[21:15:03.718] INFO: Test took 15172ms.
[21:15:03.955] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:15:46.167] INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 192 (20/5) hits flags = 16 (plus default)
[21:15:46.183] INFO: dacScan step from 0 .. 19
[21:15:46.183] INFO: dacScan split into 2 runs with ntrig = 5
[21:15:46.183] INFO: run 1 of 2
[21:15:59.801] INFO: Test took 13618ms.
[21:15:59.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:15:59.850] INFO: run 2 of 2
[21:16:13.491] INFO: Test took 13641ms.
[21:16:13.537] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:16:13.537] INFO: dacScan step from 20 .. 39
[21:16:13.537] INFO: dacScan split into 2 runs with ntrig = 5
[21:16:13.537] INFO: run 1 of 2
[21:16:27.201] INFO: Test took 13663ms.
[21:16:27.246] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:16:27.246] INFO: run 2 of 2
[21:16:40.999] INFO: Test took 13753ms.
[21:16:41.040] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:16:41.040] INFO: dacScan step from 40 .. 59
[21:16:41.040] INFO: dacScan split into 2 runs with ntrig = 5
[21:16:41.040] INFO: run 1 of 2
[21:16:54.762] INFO: Test took 13721ms.
[21:16:54.804] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:16:54.804] INFO: run 2 of 2
[21:17:08.560] INFO: Test took 13756ms.
[21:17:08.604] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:17:08.604] INFO: dacScan step from 60 .. 79
[21:17:08.604] INFO: dacScan split into 2 runs with ntrig = 5
[21:17:08.604] INFO: run 1 of 2
[21:17:22.249] INFO: Test took 13644ms.
[21:17:22.290] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:17:22.290] INFO: run 2 of 2
[21:17:35.940] INFO: Test took 13650ms.
[21:17:35.982] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:17:35.982] INFO: dacScan step from 80 .. 99
[21:17:35.982] INFO: dacScan split into 2 runs with ntrig = 5
[21:17:35.982] INFO: run 1 of 2
[21:17:49.544] INFO: Test took 13562ms.
[21:17:49.591] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:17:49.591] INFO: run 2 of 2
[21:18:03.314] INFO: Test took 13723ms.
[21:18:03.365] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:18:03.366] INFO: dacScan step from 100 .. 119
[21:18:03.366] INFO: dacScan split into 2 runs with ntrig = 5
[21:18:03.366] INFO: run 1 of 2
[21:18:18.008] INFO: Test took 14642ms.
[21:18:18.138] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:18:18.156] INFO: run 2 of 2
[21:18:32.677] INFO: Test took 14520ms.
[21:18:32.804] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:18:32.823] INFO: dacScan step from 120 .. 139
[21:18:32.823] INFO: dacScan split into 2 runs with ntrig = 5
[21:18:32.823] INFO: run 1 of 2
[21:18:51.778] INFO: Test took 18955ms.
[21:18:52.060] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:18:52.127] INFO: run 2 of 2
[21:19:10.003] INFO: Test took 18876ms.
[21:19:11.284] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:19:11.354] INFO: dacScan step from 140 .. 159
[21:19:11.354] INFO: dacScan split into 2 runs with ntrig = 5
[21:19:11.354] INFO: run 1 of 2
[21:19:30.247] INFO: Test took 18893ms.
[21:19:30.598] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:19:30.731] INFO: run 2 of 2
[21:19:49.860] INFO: Test took 19129ms.
[21:19:50.200] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:19:50.285] INFO: dacScan step from 160 .. 179
[21:19:50.285] INFO: dacScan split into 2 runs with ntrig = 5
[21:19:50.285] INFO: run 1 of 2
[21:20:09.447] INFO: Test took 19162ms.
[21:20:09.780] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:20:09.863] INFO: run 2 of 2
[21:20:28.955] INFO: Test took 19092ms.
[21:20:29.287] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:20:29.369] INFO: dacScan step from 180 .. 192
[21:20:29.369] INFO: dacScan split into 2 runs with ntrig = 5
[21:20:29.369] INFO: run 1 of 2
[21:20:43.637] INFO: Test took 14268ms.
[21:20:43.842] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:20:43.894] INFO: run 2 of 2
[21:20:58.248] INFO: Test took 14354ms.
[21:20:58.474] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:21:41.105] INFO: PixTestTrim::trimBitTest() done
[21:21:41.107] INFO: PixTestTrim::doTest() done, duration: 3198 seconds
[21:21:42.103] INFO: ######################################################################
[21:21:42.103] INFO: PixTestPhOptimization::doTest() Ntrig = 10
[21:21:42.103] INFO: ######################################################################
[21:21:46.365] INFO: Test took 4260ms.
[21:21:46.407] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:21:50.976] INFO: Test took 4366ms.
[21:21:51.117] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:21:55.348] INFO: Test took 4212ms.
[21:21:55.494] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:21:55.506] INFO: The DUT currently contains the following objects:
[21:21:55.506] INFO: 2 TBM Cores tbm08c (2 ON)
[21:21:55.506] INFO: TBM Core alpha (0): 7 registers set
[21:21:55.506] INFO: TBM Core beta (1): 7 registers set
[21:21:55.506] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:21:55.506] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.506] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.507] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.507] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.507] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.507] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.507] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.507] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.507] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.507] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.507] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.507] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.507] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.507] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.507] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:55.507] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.603] INFO: Test took 1096ms.
[21:21:56.605] INFO: The DUT currently contains the following objects:
[21:21:56.605] INFO: 2 TBM Cores tbm08c (2 ON)
[21:21:56.605] INFO: TBM Core alpha (0): 7 registers set
[21:21:56.605] INFO: TBM Core beta (1): 7 registers set
[21:21:56.605] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:21:56.605] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:56.605] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.703] INFO: Test took 1098ms.
[21:21:57.704] INFO: The DUT currently contains the following objects:
[21:21:57.704] INFO: 2 TBM Cores tbm08c (2 ON)
[21:21:57.704] INFO: TBM Core alpha (0): 7 registers set
[21:21:57.704] INFO: TBM Core beta (1): 7 registers set
[21:21:57.704] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:21:57.704] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.704] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.704] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.704] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.704] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.705] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.705] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.705] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.705] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.705] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.705] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.705] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.705] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.705] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.705] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:57.705] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.803] INFO: Test took 1098ms.
[21:21:58.805] INFO: The DUT currently contains the following objects:
[21:21:58.805] INFO: 2 TBM Cores tbm08c (2 ON)
[21:21:58.805] INFO: TBM Core alpha (0): 7 registers set
[21:21:58.805] INFO: TBM Core beta (1): 7 registers set
[21:21:58.805] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:21:58.805] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.805] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.805] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.805] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.805] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.805] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.805] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.806] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.806] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.806] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.806] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.806] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.806] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.806] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.806] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:58.806] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.906] INFO: Test took 1100ms.
[21:21:59.908] INFO: The DUT currently contains the following objects:
[21:21:59.908] INFO: 2 TBM Cores tbm08c (2 ON)
[21:21:59.908] INFO: TBM Core alpha (0): 7 registers set
[21:21:59.908] INFO: TBM Core beta (1): 7 registers set
[21:21:59.908] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:21:59.908] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:21:59.908] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.007] INFO: Test took 1099ms.
[21:22:01.009] INFO: The DUT currently contains the following objects:
[21:22:01.009] INFO: 2 TBM Cores tbm08c (2 ON)
[21:22:01.009] INFO: TBM Core alpha (0): 7 registers set
[21:22:01.009] INFO: TBM Core beta (1): 7 registers set
[21:22:01.009] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:22:01.009] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:01.009] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.109] INFO: Test took 1100ms.
[21:22:02.111] INFO: The DUT currently contains the following objects:
[21:22:02.111] INFO: 2 TBM Cores tbm08c (2 ON)
[21:22:02.111] INFO: TBM Core alpha (0): 7 registers set
[21:22:02.111] INFO: TBM Core beta (1): 7 registers set
[21:22:02.111] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:22:02.111] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:02.112] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.212] INFO: Test took 1100ms.
[21:22:03.214] INFO: The DUT currently contains the following objects:
[21:22:03.214] INFO: 2 TBM Cores tbm08c (2 ON)
[21:22:03.214] INFO: TBM Core alpha (0): 7 registers set
[21:22:03.214] INFO: TBM Core beta (1): 7 registers set
[21:22:03.214] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:22:03.214] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.214] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.214] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.214] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.214] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.214] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.215] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.215] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.215] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.215] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.215] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.215] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.215] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.215] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.215] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:03.215] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.315] INFO: Test took 1100ms.
[21:22:04.318] INFO: The DUT currently contains the following objects:
[21:22:04.318] INFO: 2 TBM Cores tbm08c (2 ON)
[21:22:04.318] INFO: TBM Core alpha (0): 7 registers set
[21:22:04.318] INFO: TBM Core beta (1): 7 registers set
[21:22:04.318] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:22:04.318] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.318] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.318] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.318] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.318] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.318] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.318] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.318] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.318] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.318] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.318] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.318] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.318] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.318] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.318] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:04.319] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.419] INFO: Test took 1100ms.
[21:22:05.420] INFO: The DUT currently contains the following objects:
[21:22:05.420] INFO: 2 TBM Cores tbm08c (2 ON)
[21:22:05.420] INFO: TBM Core alpha (0): 7 registers set
[21:22:05.420] INFO: TBM Core beta (1): 7 registers set
[21:22:05.420] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:22:05.420] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.420] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.420] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.420] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.420] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.421] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.421] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.421] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.421] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.421] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.421] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.421] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.421] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.421] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.421] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:05.421] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.520] INFO: Test took 1099ms.
[21:22:06.522] INFO: The DUT currently contains the following objects:
[21:22:06.522] INFO: 2 TBM Cores tbm08c (2 ON)
[21:22:06.522] INFO: TBM Core alpha (0): 7 registers set
[21:22:06.522] INFO: TBM Core beta (1): 7 registers set
[21:22:06.523] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:22:06.523] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:06.523] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.621] INFO: Test took 1098ms.
[21:22:07.624] INFO: The DUT currently contains the following objects:
[21:22:07.624] INFO: 2 TBM Cores tbm08c (2 ON)
[21:22:07.624] INFO: TBM Core alpha (0): 7 registers set
[21:22:07.624] INFO: TBM Core beta (1): 7 registers set
[21:22:07.624] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:22:07.624] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:07.624] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.723] INFO: Test took 1099ms.
[21:22:08.726] INFO: The DUT currently contains the following objects:
[21:22:08.726] INFO: 2 TBM Cores tbm08c (2 ON)
[21:22:08.726] INFO: TBM Core alpha (0): 7 registers set
[21:22:08.726] INFO: TBM Core beta (1): 7 registers set
[21:22:08.726] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:22:08.726] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:08.726] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.822] INFO: Test took 1096ms.
[21:22:09.824] INFO: The DUT currently contains the following objects:
[21:22:09.824] INFO: 2 TBM Cores tbm08c (2 ON)
[21:22:09.824] INFO: TBM Core alpha (0): 7 registers set
[21:22:09.824] INFO: TBM Core beta (1): 7 registers set
[21:22:09.824] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:22:09.824] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:09.824] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.919] INFO: Test took 1095ms.
[21:22:10.921] INFO: The DUT currently contains the following objects:
[21:22:10.921] INFO: 2 TBM Cores tbm08c (2 ON)
[21:22:10.921] INFO: TBM Core alpha (0): 7 registers set
[21:22:10.921] INFO: TBM Core beta (1): 7 registers set
[21:22:10.921] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:22:10.921] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:10.921] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.020] INFO: Test took 1099ms.
[21:22:12.022] INFO: The DUT currently contains the following objects:
[21:22:12.022] INFO: 2 TBM Cores tbm08c (2 ON)
[21:22:12.022] INFO: TBM Core alpha (0): 7 registers set
[21:22:12.022] INFO: TBM Core beta (1): 7 registers set
[21:22:12.022] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:22:12.022] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.022] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.022] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.022] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.022] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.022] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.022] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.022] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.023] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.023] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.023] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.023] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.023] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.023] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.023] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:12.023] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:22:13.121] INFO: Test took 1098ms.
[21:22:13.127] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:25:25.745] INFO: Test took 192618ms.
[21:25:29.012] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:28:41.612] INFO: Test took 192600ms.
[21:28:44.990] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:44.002] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.013] INFO: safety margin for low PH: adding 1, margin is now 21
[21:28:45.025] INFO: safety margin for low PH: adding 2, margin is now 22
[21:28:45.036] INFO: safety margin for low PH: adding 3, margin is now 23
[21:28:45.047] INFO: safety margin for low PH: adding 4, margin is now 24
[21:28:45.061] INFO: safety margin for low PH: adding 5, margin is now 25
[21:28:45.072] INFO: safety margin for low PH: adding 6, margin is now 26
[21:28:45.084] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.095] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.108] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.118] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.128] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.138] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.149] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.161] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.172] INFO: safety margin for low PH: adding 1, margin is now 21
[21:28:45.182] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.192] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.202] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.213] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.223] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.234] INFO: safety margin for low PH: adding 0, margin is now 20
[21:28:45.297] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat
[21:28:45.297] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C1.dat
[21:28:45.297] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C2.dat
[21:28:45.298] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C3.dat
[21:28:45.298] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C4.dat
[21:28:45.298] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C5.dat
[21:28:45.299] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C6.dat
[21:28:45.299] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C7.dat
[21:28:45.299] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C8.dat
[21:28:45.299] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C9.dat
[21:28:45.300] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C10.dat
[21:28:45.300] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C11.dat
[21:28:45.300] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C12.dat
[21:28:45.300] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C13.dat
[21:28:45.301] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C14.dat
[21:28:45.301] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[21:28:49.569] INFO: Test took 4263ms.
[21:28:54.157] INFO: Test took 4207ms.
[21:28:58.735] INFO: Test took 4214ms.
[21:28:59.117] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:00.027] INFO: Test took 910ms.
[21:29:00.031] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:01.127] INFO: Test took 1096ms.
[21:29:01.132] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:02.230] INFO: Test took 1098ms.
[21:29:02.236] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:03.335] INFO: Test took 1099ms.
[21:29:03.340] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:04.439] INFO: Test took 1099ms.
[21:29:04.447] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:05.541] INFO: Test took 1094ms.
[21:29:05.545] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:06.649] INFO: Test took 1104ms.
[21:29:06.653] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:07.752] INFO: Test took 1099ms.
[21:29:07.757] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:08.853] INFO: Test took 1097ms.
[21:29:08.858] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:09.954] INFO: Test took 1096ms.
[21:29:09.958] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:11.053] INFO: Test took 1095ms.
[21:29:11.057] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:12.154] INFO: Test took 1097ms.
[21:29:12.158] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:13.258] INFO: Test took 1100ms.
[21:29:13.263] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:14.363] INFO: Test took 1100ms.
[21:29:14.369] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:15.465] INFO: Test took 1097ms.
[21:29:15.469] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:16.563] INFO: Test took 1095ms.
[21:29:16.567] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:17.667] INFO: Test took 1100ms.
[21:29:17.675] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:18.769] INFO: Test took 1095ms.
[21:29:18.774] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:19.872] INFO: Test took 1099ms.
[21:29:19.878] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:20.974] INFO: Test took 1097ms.
[21:29:20.978] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:22.075] INFO: Test took 1097ms.
[21:29:22.082] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:23.179] INFO: Test took 1098ms.
[21:29:23.187] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:24.281] INFO: Test took 1095ms.
[21:29:24.286] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:25.381] INFO: Test took 1095ms.
[21:29:25.385] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:26.482] INFO: Test took 1097ms.
[21:29:26.488] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:27.584] INFO: Test took 1097ms.
[21:29:27.588] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:28.686] INFO: Test took 1098ms.
[21:29:28.691] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:29.786] INFO: Test took 1096ms.
[21:29:29.790] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:30.887] INFO: Test took 1098ms.
[21:29:30.892] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:31.987] INFO: Test took 1096ms.
[21:29:31.992] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:33.088] INFO: Test took 1096ms.
[21:29:33.093] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:29:34.190] INFO: Test took 1098ms.
[21:29:34.785] INFO: PixTestPhOptimization::doTest() done, duration: 472 seconds
[21:29:34.785] INFO: PH scale (per ROC): 63 67 63 78 75 74 59 75 64 60 66 64 67 67 71 70
[21:29:34.785] INFO: PH offset (per ROC): 186 176 177 174 187 177 182 160 181 195 187 179 175 188 179 178
[21:29:35.025] INFO: ######################################################################
[21:29:35.025] INFO: PixTestGainPedestal::fullTest() ntrig = 10
[21:29:35.025] INFO: ######################################################################
[21:29:35.044] INFO: scanning low vcal = 10
[21:29:38.796] INFO: Test took 3752ms.
[21:29:38.804] INFO: scanning low vcal = 20
[21:29:42.531] INFO: Test took 3727ms.
[21:29:42.537] INFO: scanning low vcal = 30
[21:29:46.364] INFO: Test took 3827ms.
[21:29:46.385] INFO: scanning low vcal = 40
[21:29:50.654] INFO: Test took 4269ms.
[21:29:50.792] INFO: scanning low vcal = 50
[21:29:55.097] INFO: Test took 4306ms.
[21:29:55.260] INFO: scanning low vcal = 60
[21:29:59.480] INFO: Test took 4220ms.
[21:29:59.637] INFO: scanning low vcal = 70
[21:30:03.986] INFO: Test took 4349ms.
[21:30:04.133] INFO: scanning low vcal = 80
[21:30:08.401] INFO: Test took 4268ms.
[21:30:08.578] INFO: scanning low vcal = 90
[21:30:12.889] INFO: Test took 4311ms.
[21:30:13.038] INFO: scanning low vcal = 100
[21:30:17.325] INFO: Test took 4287ms.
[21:30:17.474] INFO: scanning low vcal = 110
[21:30:21.728] INFO: Test took 4254ms.
[21:30:21.875] INFO: scanning low vcal = 120
[21:30:26.173] INFO: Test took 4298ms.
[21:30:26.322] INFO: scanning low vcal = 130
[21:30:30.701] INFO: Test took 4379ms.
[21:30:30.853] INFO: scanning low vcal = 140
[21:30:35.260] INFO: Test took 4407ms.
[21:30:35.400] INFO: scanning low vcal = 150
[21:30:39.667] INFO: Test took 4267ms.
[21:30:39.813] INFO: scanning low vcal = 160
[21:30:44.078] INFO: Test took 4265ms.
[21:30:44.211] INFO: scanning low vcal = 170
[21:30:48.487] INFO: Test took 4276ms.
[21:30:48.628] INFO: scanning low vcal = 180
[21:30:52.927] INFO: Test took 4299ms.
[21:30:53.079] INFO: scanning low vcal = 190
[21:30:57.320] INFO: Test took 4241ms.
[21:30:57.459] INFO: scanning low vcal = 200
[21:31:01.719] INFO: Test took 4259ms.
[21:31:01.863] INFO: scanning low vcal = 210
[21:31:06.164] INFO: Test took 4301ms.
[21:31:06.294] INFO: scanning low vcal = 220
[21:31:10.507] INFO: Test took 4213ms.
[21:31:10.649] INFO: scanning low vcal = 230
[21:31:14.955] INFO: Test took 4306ms.
[21:31:15.093] INFO: scanning low vcal = 240
[21:31:19.380] INFO: Test took 4287ms.
[21:31:19.524] INFO: scanning low vcal = 250
[21:31:23.834] INFO: Test took 4310ms.
[21:31:23.969] INFO: scanning high vcal = 30 (= 210 in low range)
[21:31:28.222] INFO: Test took 4253ms.
[21:31:28.364] INFO: scanning high vcal = 50 (= 350 in low range)
[21:31:32.658] INFO: Test took 4294ms.
[21:31:32.782] INFO: scanning high vcal = 70 (= 490 in low range)
[21:31:37.047] INFO: Test took 4266ms.
[21:31:37.189] INFO: scanning high vcal = 90 (= 630 in low range)
[21:31:41.482] INFO: Test took 4293ms.
[21:31:41.623] INFO: scanning high vcal = 200 (= 1400 in low range)
[21:31:45.924] INFO: Test took 4301ms.
[21:31:46.839] INFO: PixTestGainPedestal::measure() done
[21:32:43.354] INFO: PixTestGainPedestal::fit() done
[21:32:43.354] INFO: non-linearity mean: 0.954 0.968 0.956 0.958 0.958 0.957 0.959 0.958 0.953 0.957 0.957 0.955 0.963 0.962 0.957 0.955
[21:32:43.354] INFO: non-linearity RMS: 0.006 0.005 0.008 0.006 0.008 0.007 0.006 0.006 0.007 0.008 0.008 0.007 0.005 0.006 0.006 0.007
[21:32:43.355] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat
[21:32:43.389] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C1.dat
[21:32:43.418] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C2.dat
[21:32:43.446] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C3.dat
[21:32:43.474] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C4.dat
[21:32:43.502] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C5.dat
[21:32:43.529] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C6.dat
[21:32:43.556] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C7.dat
[21:32:43.584] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C8.dat
[21:32:43.612] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C9.dat
[21:32:43.639] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C10.dat
[21:32:43.666] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C11.dat
[21:32:43.693] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C12.dat
[21:32:43.721] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C13.dat
[21:32:43.748] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C14.dat
[21:32:43.776] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[21:32:43.804] INFO: PixTestGainPedestal::doTest() done, duration: 188 seconds
[21:32:43.813] INFO: readReadbackCal: /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C15.dat
[21:32:43.815] INFO: PixTestReadback::doTest() start.
[21:32:43.816] INFO: PixTestReadback::RES sent once
[21:33:00.447] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C0.dat
[21:33:00.447] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C1.dat
[21:33:00.448] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C2.dat
[21:33:00.448] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C3.dat
[21:33:00.448] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C4.dat
[21:33:00.448] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C5.dat
[21:33:00.448] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C6.dat
[21:33:00.448] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C7.dat
[21:33:00.448] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C8.dat
[21:33:00.448] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C9.dat
[21:33:00.448] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C10.dat
[21:33:00.448] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C11.dat
[21:33:00.448] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C12.dat
[21:33:00.449] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C13.dat
[21:33:00.449] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C14.dat
[21:33:00.449] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C15.dat
[21:33:00.496] INFO: PixTestPattern:: pg_setup set to default.
[21:33:00.496] INFO: PixTestReadback::RES sent once
[21:33:17.030] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C0.dat
[21:33:17.030] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C1.dat
[21:33:17.030] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C2.dat
[21:33:17.030] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C3.dat
[21:33:17.030] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C4.dat
[21:33:17.030] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C5.dat
[21:33:17.030] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C6.dat
[21:33:17.031] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C7.dat
[21:33:17.031] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C8.dat
[21:33:17.031] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C9.dat
[21:33:17.031] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C10.dat
[21:33:17.031] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C11.dat
[21:33:17.031] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C12.dat
[21:33:17.031] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C13.dat
[21:33:17.031] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C14.dat
[21:33:17.032] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C15.dat
[21:33:17.096] INFO: PixTestPattern:: pg_setup set to default.
[21:33:17.097] INFO: PixTestReadback::RES sent once
[21:33:29.905] INFO: PixTestPattern:: pg_setup set to default.
[21:33:29.905] INFO: Vbg will be calibrated using Vd calibration
[21:33:29.905] INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.8calibrated Vbg = 1.25536 :::*/*/*/*/
[21:33:29.905] INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 154.4calibrated Vbg = 1.25469 :::*/*/*/*/
[21:33:29.905] INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 149.9calibrated Vbg = 1.26473 :::*/*/*/*/
[21:33:29.905] INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156.2calibrated Vbg = 1.25971 :::*/*/*/*/
[21:33:29.905] INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 151.2calibrated Vbg = 1.27329 :::*/*/*/*/
[21:33:29.905] INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 157calibrated Vbg = 1.26672 :::*/*/*/*/
[21:33:29.905] INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 157.7calibrated Vbg = 1.26913 :::*/*/*/*/
[21:33:29.905] INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152.1calibrated Vbg = 1.27055 :::*/*/*/*/
[21:33:29.905] INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.3calibrated Vbg = 1.2655 :::*/*/*/*/
[21:33:29.905] INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 155.4calibrated Vbg = 1.26981 :::*/*/*/*/
[21:33:29.905] INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154calibrated Vbg = 1.26838 :::*/*/*/*/
[21:33:29.906] INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 160.5calibrated Vbg = 1.25896 :::*/*/*/*/
[21:33:29.906] INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155.1calibrated Vbg = 1.25827 :::*/*/*/*/
[21:33:29.906] INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 162.3calibrated Vbg = 1.25398 :::*/*/*/*/
[21:33:29.906] INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.1calibrated Vbg = 1.25712 :::*/*/*/*/
[21:33:29.906] INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 158.2calibrated Vbg = 1.25312 :::*/*/*/*/
[21:33:29.910] INFO: PixTestReadback::RES sent once
[21:37:25.570] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C0.dat
[21:37:25.570] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C1.dat
[21:37:25.570] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C2.dat
[21:37:25.570] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C3.dat
[21:37:25.570] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C4.dat
[21:37:25.570] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C5.dat
[21:37:25.571] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C6.dat
[21:37:25.571] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C7.dat
[21:37:25.571] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C8.dat
[21:37:25.571] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C9.dat
[21:37:25.571] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C10.dat
[21:37:25.571] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C11.dat
[21:37:25.571] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C12.dat
[21:37:25.571] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C13.dat
[21:37:25.572] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C14.dat
[21:37:25.572] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C15.dat
[21:37:25.621] INFO: PixTestPattern:: pg_setup set to default.
[21:37:25.623] INFO: PixTestReadback::doTest() done
[21:37:25.639] INFO: enter test to run
[21:37:25.639] INFO: test: q no parameter change
[21:37:25.834] QUIET: Connection to board 173 closed.
[21:37:25.838] INFO: pXar: this is the end, my friend