[20:49:13.470] INFO: *** Welcome to pxar ***
[20:49:13.470] INFO: *** Today: 2015/08/10
[20:49:13.470] INFO: readRocDacs: /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C15.dat
[20:49:13.471] INFO: readTbmDacs: /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/tbmParameters_C0b.dat
[20:49:13.471] INFO: readMaskFile: /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/defaultMaskFile.dat
[20:49:13.471] INFO: readTrimFile: /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters_C15.dat
[20:49:13.574] INFO: clk: 4
[20:49:13.574] INFO: ctr: 4
[20:49:13.574] INFO: sda: 19
[20:49:13.574] INFO: tin: 9
[20:49:13.574] INFO: level: 15
[20:49:13.574] INFO: triggerdelay: 0
[20:49:13.575] QUIET: Instanciating API for pxar prod-01+36~geb5ac3f
[20:49:13.575] INFO: Log level: INFO
[20:49:13.587] INFO: Found DTB DTB_X0DB1G
[20:49:13.602] QUIET: Connection to board DTB_X0DB1G opened.
[20:49:13.605] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 178
HW version: DTB1.2
FW version: 4.2
SW version: 4.2
USB id: DTB_X0DB1G
MAC address: 40D8551180B2
Hostname: pixelDTB178
Comment:
------------------------------------------------------
[20:49:13.608] INFO: RPC call hashes of host and DTB match: 447413373
[20:49:15.354] INFO: DUT info:
[20:49:15.354] INFO: The DUT currently contains the following objects:
[20:49:15.354] INFO: 2 TBM Cores tbm08c (2 ON)
[20:49:15.354] INFO: TBM Core alpha (0): 7 registers set
[20:49:15.354] INFO: TBM Core beta (1): 7 registers set
[20:49:15.354] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[20:49:15.354] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.354] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.355] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.355] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.355] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.355] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.355] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.355] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.355] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.355] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.355] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.355] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.355] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.355] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.355] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.355] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[20:49:15.756] INFO: enter 'restricted' command line mode
[20:49:15.756] INFO: enter test to run
[20:49:15.756] INFO: test: pretest no parameter change
[20:49:15.756] INFO: running: pretest
[20:49:15.761] INFO: ######################################################################
[20:49:15.761] INFO: PixTestPretest::doTest()
[20:49:15.761] INFO: ######################################################################
[20:49:15.762] INFO: ----------------------------------------------------------------------
[20:49:15.762] INFO: PixTestPretest::programROC()
[20:49:15.762] INFO: ----------------------------------------------------------------------
[20:49:33.782] INFO: PixTestPretest::programROC() done: ROCs are all programmable
[20:49:33.782] INFO: IA differences per ROC: 17.7 19.3 17.7 16.9 18.5 16.9 17.7 16.9 17.7 18.5 18.5 18.5 16.9 17.7 17.7 16.9
[20:49:33.856] INFO: ----------------------------------------------------------------------
[20:49:33.856] INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[20:49:33.856] INFO: ----------------------------------------------------------------------
[20:49:53.491] INFO: PixTestPretest::setVana() done, Module Ia 384.3 mA = 24.0187 mA/ROC
[20:49:53.494] INFO: ----------------------------------------------------------------------
[20:49:53.494] INFO: PixTestPretest::findWorkingPixel()
[20:49:53.494] INFO: ----------------------------------------------------------------------
[20:50:01.294] INFO: Test took 7793ms.
[20:50:01.635] INFO: Found working pixel in all ROCs: col/row = 12/22
[20:50:01.680] INFO: ----------------------------------------------------------------------
[20:50:01.680] INFO: PixTestPretest::setVthrCompCalDel()
[20:50:01.680] INFO: ----------------------------------------------------------------------
[20:50:09.511] INFO: Test took 7824ms.
[20:50:09.866] INFO: PixTestPretest::setVthrCompCalDel() done
[20:50:09.866] INFO: CalDel: 143 123 131 114 127 146 112 133 127 126 125 120 124 138 147 114
[20:50:09.866] INFO: VthrComp: 51 51 51 51 51 51 53 51 51 51 51 51 51 51 51 51
[20:50:09.872] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C0.dat
[20:50:09.873] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C1.dat
[20:50:09.873] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C2.dat
[20:50:09.873] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C3.dat
[20:50:09.873] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C4.dat
[20:50:09.874] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C5.dat
[20:50:09.874] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C6.dat
[20:50:09.874] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C7.dat
[20:50:09.874] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C8.dat
[20:50:09.875] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C9.dat
[20:50:09.875] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C10.dat
[20:50:09.875] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C11.dat
[20:50:09.875] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C12.dat
[20:50:09.875] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C13.dat
[20:50:09.876] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C14.dat
[20:50:09.876] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters_C15.dat
[20:50:09.876] INFO: PixTestPretest::doTest() done, duration: 54 seconds
[20:50:09.968] INFO: enter test to run
[20:50:09.968] INFO: test: fulltest no parameter change
[20:50:09.968] INFO: running: fulltest
[20:50:09.968] INFO: ######################################################################
[20:50:09.968] INFO: PixTestFullTest::doTest()
[20:50:09.968] INFO: ######################################################################
[20:50:09.969] INFO: ######################################################################
[20:50:09.969] INFO: PixTestAlive::doTest()
[20:50:09.969] INFO: ######################################################################
[20:50:09.971] INFO: ----------------------------------------------------------------------
[20:50:09.971] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:50:09.971] INFO: ----------------------------------------------------------------------
[20:50:14.161] INFO: Test took 4188ms.
[20:50:14.192] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:50:14.446] INFO: PixTestAlive::aliveTest() done
[20:50:14.446] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[20:50:14.448] INFO: ----------------------------------------------------------------------
[20:50:14.448] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:50:14.448] INFO: ----------------------------------------------------------------------
[20:50:17.482] INFO: Test took 3033ms.
[20:50:17.486] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:50:17.487] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[20:50:17.746] INFO: PixTestAlive::maskTest() done
[20:50:17.746] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[20:50:17.748] INFO: ----------------------------------------------------------------------
[20:50:17.748] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:50:17.748] INFO: ----------------------------------------------------------------------
[20:50:21.891] INFO: Test took 4141ms.
[20:50:21.925] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:50:22.164] INFO: PixTestAlive::addressDecodingTest() done
[20:50:22.164] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[20:50:22.164] INFO: PixTestAlive::doTest() done, duration: 12 seconds
[20:50:22.176] INFO: ######################################################################
[20:50:22.176] INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[20:50:22.176] INFO: ######################################################################
[20:50:22.179] INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30/5) hits flags = 2 (plus default)
[20:50:22.199] INFO: dacScan step from 0 .. 29
[20:50:22.199] INFO: dacScan split into 1 runs with ntrig = 5
[20:50:22.199] INFO: run 1 of 1
[20:50:41.251] INFO: Test took 19052ms.
[20:50:41.309] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:50:41.309] INFO: dacScan step from 30 .. 59
[20:50:41.309] INFO: dacScan split into 1 runs with ntrig = 5
[20:50:41.309] INFO: run 1 of 1
[20:51:00.751] INFO: Test took 19442ms.
[20:51:00.837] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:51:00.842] INFO: dacScan step from 60 .. 89
[20:51:00.842] INFO: dacScan split into 1 runs with ntrig = 5
[20:51:00.842] INFO: run 1 of 1
[20:51:25.880] INFO: Test took 25038ms.
[20:51:26.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:51:26.335] INFO: dacScan step from 90 .. 119
[20:51:26.335] INFO: dacScan split into 1 runs with ntrig = 5
[20:51:26.335] INFO: run 1 of 1
[20:51:53.692] INFO: Test took 27357ms.
[20:51:54.107] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:51:54.210] INFO: dacScan step from 120 .. 149
[20:51:54.210] INFO: dacScan split into 1 runs with ntrig = 5
[20:51:54.210] INFO: run 1 of 1
[20:52:19.854] INFO: Test took 25644ms.
[20:52:20.198] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:52:56.717] INFO: PixTestBBMap::doTest() done, duration: 154 seconds
[20:52:56.717] INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[20:52:56.717] INFO: separation cut (per ROC): 112 97 104 115 105 93 109 111 104 119 103 109 101 106 101 112
[20:52:56.811] INFO: ######################################################################
[20:52:56.812] INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = 1
[20:52:56.812] INFO: ######################################################################
[20:52:56.812] INFO: ----------------------------------------------------------------------
[20:52:56.812] INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = 1
[20:52:56.812] INFO: ----------------------------------------------------------------------
[20:52:56.812] INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (-1/1) hits flags = 16 (plus default)
[20:52:56.828] INFO: dacScan split into 50 runs with ntrig = 1
[20:52:56.828] INFO: run 1 of 50
[20:53:46.450] INFO: Test took 49622ms.
[20:53:46.834] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:53:47.022] INFO: run 2 of 50
[20:54:36.726] INFO: Test took 49704ms.
[20:54:37.057] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:54:37.228] INFO: run 3 of 50
[20:55:26.945] INFO: Test took 49717ms.
[20:55:27.290] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:55:27.460] INFO: run 4 of 50
[20:56:17.046] INFO: Test took 49586ms.
[20:56:17.381] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:56:17.541] INFO: run 5 of 50
[20:57:07.292] INFO: Test took 49751ms.
[20:57:07.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:57:07.776] INFO: run 6 of 50
[20:57:57.568] INFO: Test took 49792ms.
[20:57:57.903] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:57:58.064] INFO: run 7 of 50
[20:58:47.740] INFO: Test took 49676ms.
[20:58:48.069] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:58:48.230] INFO: run 8 of 50
[20:59:37.926] INFO: Test took 49696ms.
[20:59:38.266] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:59:38.433] INFO: run 9 of 50
[21:00:28.212] INFO: Test took 49779ms.
[21:00:28.541] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:00:28.702] INFO: run 10 of 50
[21:01:18.434] INFO: Test took 49732ms.
[21:01:18.756] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:01:18.921] INFO: run 11 of 50
[21:02:08.527] INFO: Test took 49606ms.
[21:02:08.864] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:02:09.030] INFO: run 12 of 50
[21:02:58.539] INFO: Test took 49509ms.
[21:02:58.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:02:59.021] INFO: run 13 of 50
[21:03:48.837] INFO: Test took 49816ms.
[21:03:49.161] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:03:49.332] INFO: run 14 of 50
[21:04:38.968] INFO: Test took 49636ms.
[21:04:39.290] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:04:39.449] INFO: run 15 of 50
[21:05:29.333] INFO: Test took 49884ms.
[21:05:29.654] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:05:29.820] INFO: run 16 of 50
[21:06:19.683] INFO: Test took 49863ms.
[21:06:20.006] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:06:20.166] INFO: run 17 of 50
[21:07:09.975] INFO: Test took 49809ms.
[21:07:10.298] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:07:10.459] INFO: run 18 of 50
[21:08:00.274] INFO: Test took 49815ms.
[21:08:00.596] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:08:00.758] INFO: run 19 of 50
[21:08:50.551] INFO: Test took 49793ms.
[21:08:50.875] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:08:51.039] INFO: run 20 of 50
[21:09:40.721] INFO: Test took 49681ms.
[21:09:41.049] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:09:41.207] INFO: run 21 of 50
[21:10:30.994] INFO: Test took 49787ms.
[21:10:31.314] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:10:31.480] INFO: run 22 of 50
[21:11:21.420] INFO: Test took 49940ms.
[21:11:21.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:11:21.909] INFO: run 23 of 50
[21:12:11.651] INFO: Test took 49741ms.
[21:12:11.966] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:12:12.132] INFO: run 24 of 50
[21:13:01.851] INFO: Test took 49719ms.
[21:13:02.175] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:13:02.337] INFO: run 25 of 50
[21:13:52.069] INFO: Test took 49732ms.
[21:13:52.393] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:13:52.555] INFO: run 26 of 50
[21:14:42.273] INFO: Test took 49718ms.
[21:14:42.594] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:14:42.758] INFO: run 27 of 50
[21:15:32.498] INFO: Test took 49740ms.
[21:15:32.827] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:15:32.986] INFO: run 28 of 50
[21:16:22.871] INFO: Test took 49885ms.
[21:16:23.200] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:16:23.363] INFO: run 29 of 50
[21:17:13.208] INFO: Test took 49845ms.
[21:17:13.526] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:17:13.689] INFO: run 30 of 50
[21:18:03.654] INFO: Test took 49965ms.
[21:18:03.974] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:18:04.138] INFO: run 31 of 50
[21:18:54.040] INFO: Test took 49902ms.
[21:18:54.356] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:18:54.516] INFO: run 32 of 50
[21:19:44.324] INFO: Test took 49808ms.
[21:19:44.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:19:44.808] INFO: run 33 of 50
[21:20:34.673] INFO: Test took 49864ms.
[21:20:34.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:20:35.164] INFO: run 34 of 50
[21:21:25.005] INFO: Test took 49841ms.
[21:21:25.326] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:21:25.484] INFO: run 35 of 50
[21:22:15.298] INFO: Test took 49814ms.
[21:22:15.622] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:22:15.782] INFO: run 36 of 50
[21:23:05.696] INFO: Test took 49914ms.
[21:23:06.021] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:23:06.182] INFO: run 37 of 50
[21:23:56.095] INFO: Test took 49913ms.
[21:23:56.529] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:23:56.692] INFO: run 38 of 50
[21:24:46.531] INFO: Test took 49839ms.
[21:24:46.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:24:47.012] INFO: run 39 of 50
[21:25:36.863] INFO: Test took 49851ms.
[21:25:37.192] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:25:37.355] INFO: run 40 of 50
[21:26:27.072] INFO: Test took 49717ms.
[21:26:27.393] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:26:27.551] INFO: run 41 of 50
[21:27:17.224] INFO: Test took 49673ms.
[21:27:17.549] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:27:17.710] INFO: run 42 of 50
[21:28:07.496] INFO: Test took 49786ms.
[21:28:07.824] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:28:07.988] INFO: run 43 of 50
[21:28:57.751] INFO: Test took 49763ms.
[21:28:58.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:28:58.252] INFO: run 44 of 50
[21:29:48.107] INFO: Test took 49855ms.
[21:29:48.431] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:29:48.591] INFO: run 45 of 50
[21:30:38.414] INFO: Test took 49823ms.
[21:30:38.750] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:30:38.909] INFO: run 46 of 50
[21:31:28.740] INFO: Test took 49831ms.
[21:31:29.071] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:31:29.230] INFO: run 47 of 50
[21:32:19.108] INFO: Test took 49878ms.
[21:32:19.422] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:32:19.582] INFO: run 48 of 50
[21:33:09.377] INFO: Test took 49795ms.
[21:33:09.704] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:33:09.866] INFO: run 49 of 50
[21:33:59.706] INFO: Test took 49840ms.
[21:34:00.030] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:34:00.198] INFO: run 50 of 50
[21:34:49.736] INFO: Test took 49538ms.
[21:34:50.126] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:34:50.316] INFO: dumping ASCII scurve output file: SCurveData
[21:34:52.695] INFO: dumping ASCII scurve output file: SCurveData
[21:34:55.200] INFO: dumping ASCII scurve output file: SCurveData
[21:34:57.734] INFO: dumping ASCII scurve output file: SCurveData
[21:35:00.201] INFO: dumping ASCII scurve output file: SCurveData
[21:35:02.586] INFO: dumping ASCII scurve output file: SCurveData
[21:35:04.987] INFO: dumping ASCII scurve output file: SCurveData
[21:35:07.295] INFO: dumping ASCII scurve output file: SCurveData
[21:35:09.475] INFO: dumping ASCII scurve output file: SCurveData
[21:35:11.803] INFO: dumping ASCII scurve output file: SCurveData
[21:35:13.887] INFO: dumping ASCII scurve output file: SCurveData
[21:35:16.011] INFO: dumping ASCII scurve output file: SCurveData
[21:35:18.237] INFO: dumping ASCII scurve output file: SCurveData
[21:35:20.617] INFO: dumping ASCII scurve output file: SCurveData
[21:35:22.858] INFO: dumping ASCII scurve output file: SCurveData
[21:35:25.209] INFO: dumping ASCII scurve output file: SCurveData
[21:35:27.439] INFO: PixTestScurves::scurves() done
[21:35:27.439] INFO: Vcal mean: 94.40 95.70 98.26 104.47 97.87 93.40 106.96 93.98 93.16 108.92 97.21 113.79 92.19 98.48 101.02 108.65
[21:35:27.439] INFO: Vcal RMS: 6.71 5.64 5.74 6.34 6.07 5.38 5.79 6.14 5.76 5.43 5.57 5.69 5.30 5.53 5.38 4.95
[21:35:27.439] INFO: PixTestScurves::fullTest() done, duration: 2550 seconds
[21:35:27.546] INFO: ######################################################################
[21:35:27.547] INFO: PixTestTrim::doTest()
[21:35:27.547] INFO: ######################################################################
[21:35:27.548] INFO: ----------------------------------------------------------------------
[21:35:27.548] INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[21:35:27.548] INFO: ----------------------------------------------------------------------
[21:35:27.702] INFO: ---> VthrComp thr map (minimal VthrComp)
[21:35:27.702] INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[21:35:27.717] INFO: dacScan step from 0 .. 19
[21:35:27.717] INFO: dacScan split into 1 runs with ntrig = 5
[21:35:27.717] INFO: run 1 of 1
[21:35:41.705] INFO: Test took 13988ms.
[21:35:41.742] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:35:41.742] INFO: dacScan step from 20 .. 39
[21:35:41.742] INFO: dacScan split into 1 runs with ntrig = 5
[21:35:41.742] INFO: run 1 of 1
[21:35:55.654] INFO: Test took 13912ms.
[21:35:55.693] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:35:55.693] INFO: dacScan step from 40 .. 59
[21:35:55.693] INFO: dacScan split into 1 runs with ntrig = 5
[21:35:55.693] INFO: run 1 of 1
[21:36:09.513] INFO: Test took 13819ms.
[21:36:09.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:36:09.553] INFO: dacScan step from 60 .. 79
[21:36:09.553] INFO: dacScan split into 1 runs with ntrig = 5
[21:36:09.553] INFO: run 1 of 1
[21:36:23.430] INFO: Test took 13877ms.
[21:36:23.466] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:36:23.466] INFO: dacScan step from 80 .. 99
[21:36:23.466] INFO: dacScan split into 1 runs with ntrig = 5
[21:36:23.466] INFO: run 1 of 1
[21:36:37.374] INFO: Test took 13907ms.
[21:36:37.427] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:36:37.431] INFO: dacScan step from 100 .. 119
[21:36:37.431] INFO: dacScan split into 1 runs with ntrig = 5
[21:36:37.431] INFO: run 1 of 1
[21:36:53.706] INFO: Test took 16275ms.
[21:36:53.914] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:36:53.961] INFO: dacScan step from 120 .. 139
[21:36:53.961] INFO: dacScan split into 1 runs with ntrig = 5
[21:36:53.961] INFO: run 1 of 1
[21:37:12.678] INFO: Test took 18717ms.
[21:37:12.938] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:37:12.001] INFO: dacScan step from 140 .. 159
[21:37:12.001] INFO: dacScan split into 1 runs with ntrig = 5
[21:37:12.001] INFO: run 1 of 1
[21:37:27.986] INFO: Test took 14985ms.
[21:37:28.120] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:38:02.866] INFO: ROC 0 VthrComp = 93
[21:38:02.866] INFO: ROC 1 VthrComp = 93
[21:38:02.866] INFO: ROC 2 VthrComp = 97
[21:38:02.866] INFO: ROC 3 VthrComp = 100
[21:38:02.866] INFO: ROC 4 VthrComp = 99
[21:38:02.866] INFO: ROC 5 VthrComp = 92
[21:38:02.866] INFO: ROC 6 VthrComp = 104
[21:38:02.866] INFO: ROC 7 VthrComp = 87
[21:38:02.867] INFO: ROC 8 VthrComp = 93
[21:38:02.867] INFO: ROC 9 VthrComp = 107
[21:38:02.867] INFO: ROC 10 VthrComp = 97
[21:38:02.867] INFO: ROC 11 VthrComp = 106
[21:38:02.867] INFO: ROC 12 VthrComp = 92
[21:38:02.867] INFO: ROC 13 VthrComp = 99
[21:38:02.867] INFO: ROC 14 VthrComp = 100
[21:38:02.867] INFO: ROC 15 VthrComp = 102
[21:38:02.868] INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[21:38:02.868] INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[21:38:02.884] INFO: dacScan step from 0 .. 19
[21:38:02.884] INFO: dacScan split into 1 runs with ntrig = 5
[21:38:02.884] INFO: run 1 of 1
[21:38:16.829] INFO: Test took 13945ms.
[21:38:16.864] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:38:16.865] INFO: dacScan step from 20 .. 39
[21:38:16.865] INFO: dacScan split into 1 runs with ntrig = 5
[21:38:16.865] INFO: run 1 of 1
[21:38:30.661] INFO: Test took 13796ms.
[21:38:30.712] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:38:30.715] INFO: dacScan step from 40 .. 59
[21:38:30.715] INFO: dacScan split into 1 runs with ntrig = 5
[21:38:30.715] INFO: run 1 of 1
[21:38:47.600] INFO: Test took 16885ms.
[21:38:47.821] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:38:47.877] INFO: dacScan step from 60 .. 79
[21:38:47.877] INFO: dacScan split into 1 runs with ntrig = 5
[21:38:47.877] INFO: run 1 of 1
[21:39:06.746] INFO: Test took 18869ms.
[21:39:07.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:39:07.098] INFO: dacScan step from 80 .. 99
[21:39:07.098] INFO: dacScan split into 1 runs with ntrig = 5
[21:39:07.098] INFO: run 1 of 1
[21:39:25.991] INFO: Test took 18894ms.
[21:39:26.300] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:39:26.366] INFO: dacScan step from 100 .. 119
[21:39:26.366] INFO: dacScan split into 1 runs with ntrig = 5
[21:39:26.366] INFO: run 1 of 1
[21:39:45.230] INFO: Test took 18864ms.
[21:39:45.510] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:39:45.584] INFO: dacScan step from 120 .. 139
[21:39:45.584] INFO: dacScan split into 1 runs with ntrig = 5
[21:39:45.584] INFO: run 1 of 1
[21:40:05.782] INFO: Test took 20198ms.
[21:40:06.067] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:40:06.141] INFO: dacScan step from 140 .. 159
[21:40:06.141] INFO: dacScan split into 1 runs with ntrig = 5
[21:40:06.141] INFO: run 1 of 1
[21:40:25.285] INFO: Test took 19144ms.
[21:40:25.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:41:07.939] INFO: roc 0 with ID = 0 has maximal Vcal 63.9049 for pixel 6/5 mean/min/max = 47.6951/31.4669/63.9234
[21:41:07.940] INFO: roc 1 with ID = 1 has maximal Vcal 60.7587 for pixel 0/48 mean/min/max = 46.6119/32.3656/60.8583
[21:41:07.941] INFO: roc 2 with ID = 2 has maximal Vcal 59.1074 for pixel 24/16 mean/min/max = 45.5118/31.7493/59.2743
[21:41:07.942] INFO: roc 3 with ID = 3 has maximal Vcal 64.2579 for pixel 3/79 mean/min/max = 47.873/31.474/64.272
[21:41:07.943] INFO: roc 4 with ID = 4 has maximal Vcal 59.917 for pixel 0/14 mean/min/max = 45.7595/31.5719/59.9471
[21:41:07.944] INFO: roc 5 with ID = 5 has maximal Vcal 58.5832 for pixel 8/20 mean/min/max = 46.2171/33.8148/58.6194
[21:41:07.945] INFO: roc 6 with ID = 6 has maximal Vcal 64.0742 for pixel 24/73 mean/min/max = 48.8909/33.6343/64.1474
[21:41:07.946] INFO: roc 7 with ID = 7 has maximal Vcal 63.0764 for pixel 6/37 mean/min/max = 47.3158/31.4974/63.1342
[21:41:07.947] INFO: roc 8 with ID = 8 has maximal Vcal 59.665 for pixel 6/73 mean/min/max = 46.3834/33.0772/59.6896
[21:41:07.947] INFO: roc 9 with ID = 9 has maximal Vcal 63.3447 for pixel 0/7 mean/min/max = 48.4679/33.4324/63.5034
[21:41:07.948] INFO: roc 10 with ID = 10 has maximal Vcal 58.8682 for pixel 0/54 mean/min/max = 45.4974/32.0675/58.9273
[21:41:07.949] INFO: roc 11 with ID = 11 has maximal Vcal 65.1003 for pixel 16/1 mean/min/max = 49.7433/34.2597/65.2269
[21:41:07.950] INFO: roc 12 with ID = 12 has maximal Vcal 59.479 for pixel 0/75 mean/min/max = 46.3123/33.0526/59.5721
[21:41:07.951] INFO: roc 13 with ID = 13 has maximal Vcal 58.1661 for pixel 15/72 mean/min/max = 45.1258/32.0661/58.1854
[21:41:07.952] INFO: roc 14 with ID = 14 has maximal Vcal 59.2641 for pixel 27/11 mean/min/max = 45.5532/31.8417/59.2646
[21:41:07.952] INFO: roc 15 with ID = 15 has maximal Vcal 60.2058 for pixel 23/75 mean/min/max = 46.5334/32.8518/60.215
[21:41:07.953] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:42:29.861] INFO: Test took 81909ms.
[21:42:31.939] INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[21:42:31.955] INFO: dacScan step from 0 .. 19
[21:42:31.955] INFO: dacScan split into 2 runs with ntrig = 5
[21:42:31.955] INFO: run 1 of 2
[21:42:45.693] INFO: Test took 13738ms.
[21:42:45.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:42:45.747] INFO: run 2 of 2
[21:42:59.356] INFO: Test took 13609ms.
[21:42:59.402] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:42:59.404] INFO: dacScan step from 20 .. 39
[21:42:59.404] INFO: dacScan split into 2 runs with ntrig = 5
[21:42:59.404] INFO: run 1 of 2
[21:43:15.485] INFO: Test took 16081ms.
[21:43:15.699] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:43:15.748] INFO: run 2 of 2
[21:43:31.792] INFO: Test took 16044ms.
[21:43:31.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:43:32.049] INFO: dacScan step from 40 .. 59
[21:43:32.049] INFO: dacScan split into 2 runs with ntrig = 5
[21:43:32.049] INFO: run 1 of 2
[21:43:50.675] INFO: Test took 18626ms.
[21:43:50.973] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:43:51.071] INFO: run 2 of 2
[21:44:09.692] INFO: Test took 18621ms.
[21:44:10.010] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:44:10.081] INFO: dacScan step from 60 .. 79
[21:44:10.081] INFO: dacScan split into 2 runs with ntrig = 5
[21:44:10.081] INFO: run 1 of 2
[21:44:28.857] INFO: Test took 18776ms.
[21:44:29.172] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:44:29.251] INFO: run 2 of 2
[21:44:48.212] INFO: Test took 18961ms.
[21:44:48.476] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:44:48.545] INFO: dacScan step from 80 .. 99
[21:44:48.545] INFO: dacScan split into 2 runs with ntrig = 5
[21:44:48.545] INFO: run 1 of 2
[21:45:07.337] INFO: Test took 18792ms.
[21:45:07.621] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:45:07.695] INFO: run 2 of 2
[21:45:26.547] INFO: Test took 18852ms.
[21:45:26.862] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:45:26.940] INFO: dacScan step from 100 .. 119
[21:45:26.940] INFO: dacScan split into 2 runs with ntrig = 5
[21:45:26.940] INFO: run 1 of 2
[21:45:46.021] INFO: Test took 19081ms.
[21:45:46.320] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:45:46.394] INFO: run 2 of 2
[21:46:05.223] INFO: Test took 18829ms.
[21:46:05.492] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:46:05.566] INFO: dacScan step from 120 .. 139
[21:46:05.566] INFO: dacScan split into 2 runs with ntrig = 5
[21:46:05.566] INFO: run 1 of 2
[21:46:25.931] INFO: Test took 20365ms.
[21:46:26.199] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:46:26.266] INFO: run 2 of 2
[21:46:46.553] INFO: Test took 20286ms.
[21:46:46.829] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:46:46.900] INFO: dacScan step from 140 .. 159
[21:46:46.900] INFO: dacScan split into 2 runs with ntrig = 5
[21:46:46.900] INFO: run 1 of 2
[21:47:05.855] INFO: Test took 18955ms.
[21:47:06.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:47:06.218] INFO: run 2 of 2
[21:47:25.392] INFO: Test took 19174ms.
[21:47:25.664] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:47:25.731] INFO: dacScan step from 160 .. 179
[21:47:25.731] INFO: dacScan split into 2 runs with ntrig = 5
[21:47:25.731] INFO: run 1 of 2
[21:47:44.838] INFO: Test took 19107ms.
[21:47:45.112] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:47:45.182] INFO: run 2 of 2
[21:48:04.216] INFO: Test took 19034ms.
[21:48:04.509] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:48:04.576] INFO: dacScan step from 180 .. 199
[21:48:04.576] INFO: dacScan split into 2 runs with ntrig = 5
[21:48:04.576] INFO: run 1 of 2
[21:48:24.488] INFO: Test took 19912ms.
[21:48:24.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:48:24.846] INFO: run 2 of 2
[21:48:44.561] INFO: Test took 19715ms.
[21:48:44.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:49:27.000] INFO: ---> TrimStepCorr4 extremal thresholds: 0.003861 .. 255.000000
[21:49:28.139] INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20/-1) hits flags = 16 (plus default)
[21:49:28.155] INFO: dacScan step from 0 .. 19
[21:49:28.155] INFO: dacScan split into 1 runs with ntrig = 4
[21:49:28.155] INFO: run 1 of 1
[21:49:40.590] INFO: Test took 12435ms.
[21:49:40.641] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:49:40.641] INFO: dacScan step from 20 .. 39
[21:49:40.641] INFO: dacScan split into 1 runs with ntrig = 4
[21:49:40.641] INFO: run 1 of 1
[21:49:54.076] INFO: Test took 13435ms.
[21:49:54.206] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:49:54.238] INFO: dacScan step from 40 .. 59
[21:49:54.238] INFO: dacScan split into 1 runs with ntrig = 4
[21:49:54.238] INFO: run 1 of 1
[21:50:10.818] INFO: Test took 16580ms.
[21:50:11.082] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:50:11.153] INFO: dacScan step from 60 .. 79
[21:50:11.153] INFO: dacScan split into 1 runs with ntrig = 4
[21:50:11.153] INFO: run 1 of 1
[21:50:27.853] INFO: Test took 16700ms.
[21:50:28.075] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:50:28.144] INFO: dacScan step from 80 .. 99
[21:50:28.144] INFO: dacScan split into 1 runs with ntrig = 4
[21:50:28.144] INFO: run 1 of 1
[21:50:44.861] INFO: Test took 16717ms.
[21:50:45.098] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:50:45.165] INFO: dacScan step from 100 .. 119
[21:50:45.165] INFO: dacScan split into 1 runs with ntrig = 4
[21:50:45.165] INFO: run 1 of 1
[21:51:01.871] INFO: Test took 16706ms.
[21:51:02.120] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:51:02.211] INFO: dacScan step from 120 .. 139
[21:51:02.211] INFO: dacScan split into 1 runs with ntrig = 4
[21:51:02.211] INFO: run 1 of 1
[21:51:20.354] INFO: Test took 18142ms.
[21:51:20.600] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:51:20.667] INFO: dacScan step from 140 .. 159
[21:51:20.667] INFO: dacScan split into 1 runs with ntrig = 4
[21:51:20.667] INFO: run 1 of 1
[21:51:37.572] INFO: Test took 16904ms.
[21:51:37.833] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:51:37.902] INFO: dacScan step from 160 .. 179
[21:51:37.902] INFO: dacScan split into 1 runs with ntrig = 4
[21:51:37.902] INFO: run 1 of 1
[21:51:54.697] INFO: Test took 16795ms.
[21:51:55.011] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:51:55.134] INFO: dacScan step from 180 .. 199
[21:51:55.134] INFO: dacScan split into 1 runs with ntrig = 4
[21:51:55.134] INFO: run 1 of 1
[21:52:12.826] INFO: Test took 17692ms.
[21:52:13.071] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:52:13.144] INFO: dacScan step from 200 .. 219
[21:52:13.144] INFO: dacScan split into 1 runs with ntrig = 4
[21:52:13.144] INFO: run 1 of 1
[21:52:29.788] INFO: Test took 16644ms.
[21:52:30.042] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:52:30.113] INFO: dacScan step from 220 .. 239
[21:52:30.113] INFO: dacScan split into 1 runs with ntrig = 4
[21:52:30.113] INFO: run 1 of 1
[21:52:46.818] INFO: Test took 16704ms.
[21:52:47.081] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:52:47.157] INFO: dacScan step from 240 .. 255
[21:52:47.157] INFO: dacScan split into 1 runs with ntrig = 4
[21:52:47.157] INFO: run 1 of 1
[21:53:01.088] INFO: Test took 13930ms.
[21:53:01.308] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:53:53.531] INFO: ---> TrimStepCorr2 extremal thresholds: 13.858881 .. 44.982773
[21:53:53.668] INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 3 .. 54 (20/-1) hits flags = 16 (plus default)
[21:53:53.684] INFO: dacScan step from 3 .. 22
[21:53:53.684] INFO: dacScan split into 1 runs with ntrig = 4
[21:53:53.684] INFO: run 1 of 1
[21:54:06.017] INFO: Test took 12333ms.
[21:54:06.054] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:54:06.055] INFO: dacScan step from 23 .. 42
[21:54:06.055] INFO: dacScan split into 1 runs with ntrig = 4
[21:54:06.055] INFO: run 1 of 1
[21:54:20.066] INFO: Test took 14011ms.
[21:54:20.224] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:54:20.268] INFO: dacScan step from 43 .. 54
[21:54:20.268] INFO: dacScan split into 1 runs with ntrig = 4
[21:54:20.268] INFO: run 1 of 1
[21:54:31.345] INFO: Test took 11077ms.
[21:54:31.488] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:54:54.028] INFO: ---> TrimStepCorr1a extremal thresholds: 0.074288 .. 41.348027
[21:54:54.166] INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 0 .. 51 (20/-1) hits flags = 16 (plus default)
[21:54:54.181] INFO: dacScan step from 0 .. 19
[21:54:54.181] INFO: dacScan split into 1 runs with ntrig = 4
[21:54:54.181] INFO: run 1 of 1
[21:55:06.516] INFO: Test took 12335ms.
[21:55:06.555] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:55:06.556] INFO: dacScan step from 20 .. 39
[21:55:06.556] INFO: dacScan split into 1 runs with ntrig = 4
[21:55:06.556] INFO: run 1 of 1
[21:55:19.890] INFO: Test took 13334ms.
[21:55:20.019] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:55:20.052] INFO: dacScan step from 40 .. 51
[21:55:20.052] INFO: dacScan split into 1 runs with ntrig = 4
[21:55:20.052] INFO: run 1 of 1
[21:55:31.231] INFO: Test took 11179ms.
[21:55:31.407] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:55:54.928] INFO: ---> TrimStepCorr1b extremal thresholds: 0.500000 .. 40.020085
[21:55:55.073] INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 0 .. 50 (20/-1) hits flags = 16 (plus default)
[21:55:55.088] INFO: dacScan step from 0 .. 19
[21:55:55.088] INFO: dacScan split into 1 runs with ntrig = 4
[21:55:55.088] INFO: run 1 of 1
[21:56:07.502] INFO: Test took 12414ms.
[21:56:07.539] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:56:07.539] INFO: dacScan step from 20 .. 39
[21:56:07.539] INFO: dacScan split into 1 runs with ntrig = 4
[21:56:07.539] INFO: run 1 of 1
[21:56:20.884] INFO: Test took 13345ms.
[21:56:21.008] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:56:21.045] INFO: dacScan step from 40 .. 50
[21:56:21.045] INFO: dacScan split into 1 runs with ntrig = 4
[21:56:21.045] INFO: run 1 of 1
[21:56:31.479] INFO: Test took 10434ms.
[21:56:31.630] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:56:54.387] INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[21:56:54.387] INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20/5) hits flags = 16 (plus default)
[21:56:54.403] INFO: dacScan step from 15 .. 34
[21:56:54.403] INFO: dacScan split into 2 runs with ntrig = 5
[21:56:54.403] INFO: run 1 of 2
[21:57:08.354] INFO: Test took 13951ms.
[21:57:08.434] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:57:08.449] INFO: run 2 of 2
[21:57:22.411] INFO: Test took 13962ms.
[21:57:22.495] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:57:22.510] INFO: dacScan step from 35 .. 54
[21:57:22.510] INFO: dacScan split into 2 runs with ntrig = 5
[21:57:22.510] INFO: run 1 of 2
[21:57:41.093] INFO: Test took 18583ms.
[21:57:41.419] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:57:41.501] INFO: run 2 of 2
[21:58:00.100] INFO: Test took 18599ms.
[21:58:00.413] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:58:00.495] INFO: dacScan step from 55 .. 55
[21:58:00.495] INFO: dacScan split into 2 runs with ntrig = 5
[21:58:00.495] INFO: run 1 of 2
[21:58:04.175] INFO: Test took 3680ms.
[21:58:04.193] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:58:04.200] INFO: run 2 of 2
[21:58:07.904] INFO: Test took 3704ms.
[21:58:07.928] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:58:27.975] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C0.dat
[21:58:27.976] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C1.dat
[21:58:27.976] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C2.dat
[21:58:27.976] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C3.dat
[21:58:27.976] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C4.dat
[21:58:27.977] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C5.dat
[21:58:27.977] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C6.dat
[21:58:27.977] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C7.dat
[21:58:27.978] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C8.dat
[21:58:27.978] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C9.dat
[21:58:27.978] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C10.dat
[21:58:27.978] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C11.dat
[21:58:27.979] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C12.dat
[21:58:27.979] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C13.dat
[21:58:27.979] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C14.dat
[21:58:27.979] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C15.dat
[21:58:27.980] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C0.dat
[21:58:27.991] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C1.dat
[21:58:28.003] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C2.dat
[21:58:28.014] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C3.dat
[21:58:28.023] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C4.dat
[21:58:28.032] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C5.dat
[21:58:28.042] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C6.dat
[21:58:28.053] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C7.dat
[21:58:28.066] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C8.dat
[21:58:28.078] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C9.dat
[21:58:28.089] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C10.dat
[21:58:28.100] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C11.dat
[21:58:28.112] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C12.dat
[21:58:28.123] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C13.dat
[21:58:28.135] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C14.dat
[21:58:28.147] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/trimParameters35_C15.dat
[21:58:28.159] INFO: PixTestTrim::trimTest() done
[21:58:28.159] INFO: vtrim: 119 100 114 128 107 97 130 112 111 121 111 121 111 108 112 102
[21:58:28.159] INFO: vthrcomp: 93 93 97 100 99 92 104 87 93 107 97 106 92 99 100 102
[21:58:28.159] INFO: vcal mean: 35.08 35.12 35.10 35.07 35.10 35.06 35.14 35.09 35.09 35.11 35.03 35.10 35.12 35.09 35.11 35.09
[21:58:28.159] INFO: vcal RMS: 1.29 1.17 1.13 1.53 1.11 1.11 1.25 1.42 1.12 1.09 1.30 1.24 1.11 1.16 1.12 1.20
[21:58:28.159] INFO: bits mean: 9.64 9.18 10.17 9.55 9.34 9.40 9.59 9.97 9.88 8.50 9.99 8.55 9.62 10.02 10.02 9.38
[21:58:28.159] INFO: bits RMS: 2.55 2.78 2.42 2.60 2.86 2.49 2.22 2.50 2.32 2.73 2.52 2.49 2.51 2.51 2.48 2.63
[21:58:28.169] INFO: ----------------------------------------------------------------------
[21:58:28.169] INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[21:58:28.169] INFO: ----------------------------------------------------------------------
[21:58:28.173] INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[21:58:28.193] INFO: dacScan step from 0 .. 19
[21:58:28.193] INFO: dacScan split into 2 runs with ntrig = 5
[21:58:28.193] INFO: run 1 of 2
[21:58:41.591] INFO: Test took 13397ms.
[21:58:41.637] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:58:41.637] INFO: run 2 of 2
[21:58:55.089] INFO: Test took 13451ms.
[21:58:55.134] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:58:55.134] INFO: dacScan step from 20 .. 39
[21:58:55.134] INFO: dacScan split into 2 runs with ntrig = 5
[21:58:55.134] INFO: run 1 of 2
[21:59:08.563] INFO: Test took 13429ms.
[21:59:08.606] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:59:08.606] INFO: run 2 of 2
[21:59:22.018] INFO: Test took 13412ms.
[21:59:22.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:59:22.066] INFO: dacScan step from 40 .. 59
[21:59:22.066] INFO: dacScan split into 2 runs with ntrig = 5
[21:59:22.066] INFO: run 1 of 2
[21:59:35.603] INFO: Test took 13537ms.
[21:59:35.648] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:59:35.648] INFO: run 2 of 2
[21:59:49.247] INFO: Test took 13600ms.
[21:59:49.293] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:59:49.293] INFO: dacScan step from 60 .. 79
[21:59:49.293] INFO: dacScan split into 2 runs with ntrig = 5
[21:59:49.293] INFO: run 1 of 2
[22:00:02.678] INFO: Test took 13386ms.
[22:00:02.725] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:00:02.725] INFO: run 2 of 2
[22:00:16.185] INFO: Test took 13460ms.
[22:00:16.233] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:00:16.233] INFO: dacScan step from 80 .. 99
[22:00:16.233] INFO: dacScan split into 2 runs with ntrig = 5
[22:00:16.233] INFO: run 1 of 2
[22:00:29.737] INFO: Test took 13504ms.
[22:00:29.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:00:29.786] INFO: run 2 of 2
[22:00:43.540] INFO: Test took 13754ms.
[22:00:43.580] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:00:43.580] INFO: dacScan step from 100 .. 119
[22:00:43.580] INFO: dacScan split into 2 runs with ntrig = 5
[22:00:43.580] INFO: run 1 of 2
[22:00:57.598] INFO: Test took 14018ms.
[22:00:57.676] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:00:57.684] INFO: run 2 of 2
[22:01:11.746] INFO: Test took 14062ms.
[22:01:11.817] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:01:11.829] INFO: dacScan step from 120 .. 139
[22:01:11.829] INFO: dacScan split into 2 runs with ntrig = 5
[22:01:11.829] INFO: run 1 of 2
[22:01:29.953] INFO: Test took 18124ms.
[22:01:30.181] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:01:30.232] INFO: run 2 of 2
[22:01:48.341] INFO: Test took 18109ms.
[22:01:48.565] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:01:48.616] INFO: dacScan step from 140 .. 159
[22:01:48.616] INFO: dacScan split into 2 runs with ntrig = 5
[22:01:48.616] INFO: run 1 of 2
[22:02:07.616] INFO: Test took 18999ms.
[22:02:07.917] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:02:07.986] INFO: run 2 of 2
[22:02:27.005] INFO: Test took 19018ms.
[22:02:27.277] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:02:27.344] INFO: dacScan step from 160 .. 179
[22:02:27.344] INFO: dacScan split into 2 runs with ntrig = 5
[22:02:27.344] INFO: run 1 of 2
[22:02:46.601] INFO: Test took 19257ms.
[22:02:46.881] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:02:46.950] INFO: run 2 of 2
[22:03:06.026] INFO: Test took 19076ms.
[22:03:06.287] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:03:06.360] INFO: dacScan step from 180 .. 199
[22:03:06.360] INFO: dacScan split into 2 runs with ntrig = 5
[22:03:06.360] INFO: run 1 of 2
[22:03:26.267] INFO: Test took 19907ms.
[22:03:26.560] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:03:26.634] INFO: run 2 of 2
[22:03:46.621] INFO: Test took 19986ms.
[22:03:46.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:04:27.458] INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 184 (20/5) hits flags = 16 (plus default)
[22:04:27.475] INFO: dacScan step from 0 .. 19
[22:04:27.475] INFO: dacScan split into 2 runs with ntrig = 5
[22:04:27.475] INFO: run 1 of 2
[22:04:41.303] INFO: Test took 13828ms.
[22:04:41.348] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:04:41.348] INFO: run 2 of 2
[22:04:55.239] INFO: Test took 13891ms.
[22:04:55.287] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:04:55.287] INFO: dacScan step from 20 .. 39
[22:04:55.287] INFO: dacScan split into 2 runs with ntrig = 5
[22:04:55.287] INFO: run 1 of 2
[22:05:09.185] INFO: Test took 13898ms.
[22:05:09.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:05:09.229] INFO: run 2 of 2
[22:05:22.991] INFO: Test took 13762ms.
[22:05:23.037] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:05:23.037] INFO: dacScan step from 40 .. 59
[22:05:23.037] INFO: dacScan split into 2 runs with ntrig = 5
[22:05:23.037] INFO: run 1 of 2
[22:05:36.935] INFO: Test took 13897ms.
[22:05:36.978] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:05:36.978] INFO: run 2 of 2
[22:05:50.742] INFO: Test took 13763ms.
[22:05:50.782] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:05:50.782] INFO: dacScan step from 60 .. 79
[22:05:50.783] INFO: dacScan split into 2 runs with ntrig = 5
[22:05:50.783] INFO: run 1 of 2
[22:06:04.569] INFO: Test took 13786ms.
[22:06:04.612] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:06:04.612] INFO: run 2 of 2
[22:06:18.448] INFO: Test took 13836ms.
[22:06:18.492] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:06:18.492] INFO: dacScan step from 80 .. 99
[22:06:18.492] INFO: dacScan split into 2 runs with ntrig = 5
[22:06:18.492] INFO: run 1 of 2
[22:06:32.196] INFO: Test took 13704ms.
[22:06:32.245] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:06:32.246] INFO: run 2 of 2
[22:06:45.918] INFO: Test took 13672ms.
[22:06:45.957] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:06:45.958] INFO: dacScan step from 100 .. 119
[22:06:45.958] INFO: dacScan split into 2 runs with ntrig = 5
[22:06:45.958] INFO: run 1 of 2
[22:07:00.920] INFO: Test took 14962ms.
[22:07:01.083] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:07:01.111] INFO: run 2 of 2
[22:07:16.047] INFO: Test took 14936ms.
[22:07:16.198] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:07:16.224] INFO: dacScan step from 120 .. 139
[22:07:16.224] INFO: dacScan split into 2 runs with ntrig = 5
[22:07:16.224] INFO: run 1 of 2
[22:07:35.769] INFO: Test took 19545ms.
[22:07:36.046] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:07:36.114] INFO: run 2 of 2
[22:07:55.694] INFO: Test took 19580ms.
[22:07:55.955] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:07:56.022] INFO: dacScan step from 140 .. 159
[22:07:56.022] INFO: dacScan split into 2 runs with ntrig = 5
[22:07:56.022] INFO: run 1 of 2
[22:08:15.236] INFO: Test took 19214ms.
[22:08:15.534] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:08:15.610] INFO: run 2 of 2
[22:08:34.741] INFO: Test took 19131ms.
[22:08:35.033] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:08:35.105] INFO: dacScan step from 160 .. 179
[22:08:35.105] INFO: dacScan split into 2 runs with ntrig = 5
[22:08:35.105] INFO: run 1 of 2
[22:08:54.119] INFO: Test took 19014ms.
[22:08:54.412] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:08:54.485] INFO: run 2 of 2
[22:09:13.499] INFO: Test took 19014ms.
[22:09:13.797] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:09:13.871] INFO: dacScan step from 180 .. 184
[22:09:13.871] INFO: dacScan split into 2 runs with ntrig = 5
[22:09:13.871] INFO: run 1 of 2
[22:09:21.105] INFO: Test took 7234ms.
[22:09:21.177] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:09:21.196] INFO: run 2 of 2
[22:09:28.435] INFO: Test took 7239ms.
[22:09:28.505] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:10:05.846] INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 172 (20/5) hits flags = 16 (plus default)
[22:10:05.862] INFO: dacScan step from 0 .. 19
[22:10:05.862] INFO: dacScan split into 2 runs with ntrig = 5
[22:10:05.862] INFO: run 1 of 2
[22:10:19.691] INFO: Test took 13829ms.
[22:10:19.730] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:10:19.730] INFO: run 2 of 2
[22:10:33.474] INFO: Test took 13744ms.
[22:10:33.518] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:10:33.518] INFO: dacScan step from 20 .. 39
[22:10:33.518] INFO: dacScan split into 2 runs with ntrig = 5
[22:10:33.518] INFO: run 1 of 2
[22:10:47.355] INFO: Test took 13837ms.
[22:10:47.399] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:10:47.399] INFO: run 2 of 2
[22:11:01.147] INFO: Test took 13748ms.
[22:11:01.192] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:11:01.192] INFO: dacScan step from 40 .. 59
[22:11:01.192] INFO: dacScan split into 2 runs with ntrig = 5
[22:11:01.192] INFO: run 1 of 2
[22:11:14.939] INFO: Test took 13746ms.
[22:11:14.987] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:11:14.987] INFO: run 2 of 2
[22:11:28.851] INFO: Test took 13864ms.
[22:11:28.890] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:11:28.890] INFO: dacScan step from 60 .. 79
[22:11:28.890] INFO: dacScan split into 2 runs with ntrig = 5
[22:11:28.890] INFO: run 1 of 2
[22:11:42.836] INFO: Test took 13946ms.
[22:11:42.880] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:11:42.880] INFO: run 2 of 2
[22:11:56.696] INFO: Test took 13816ms.
[22:11:56.743] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:11:56.743] INFO: dacScan step from 80 .. 99
[22:11:56.743] INFO: dacScan split into 2 runs with ntrig = 5
[22:11:56.743] INFO: run 1 of 2
[22:12:10.528] INFO: Test took 13785ms.
[22:12:10.574] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:12:10.575] INFO: run 2 of 2
[22:12:24.401] INFO: Test took 13826ms.
[22:12:24.443] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:12:24.444] INFO: dacScan step from 100 .. 119
[22:12:24.444] INFO: dacScan split into 2 runs with ntrig = 5
[22:12:24.444] INFO: run 1 of 2
[22:12:39.477] INFO: Test took 15033ms.
[22:12:39.627] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:12:39.654] INFO: run 2 of 2
[22:12:54.800] INFO: Test took 15146ms.
[22:12:54.937] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:12:54.965] INFO: dacScan step from 120 .. 139
[22:12:54.965] INFO: dacScan split into 2 runs with ntrig = 5
[22:12:54.965] INFO: run 1 of 2
[22:13:14.642] INFO: Test took 19677ms.
[22:13:14.904] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:13:14.972] INFO: run 2 of 2
[22:13:34.457] INFO: Test took 19485ms.
[22:13:34.698] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:13:34.760] INFO: dacScan step from 140 .. 159
[22:13:34.760] INFO: dacScan split into 2 runs with ntrig = 5
[22:13:34.760] INFO: run 1 of 2
[22:13:54.099] INFO: Test took 19339ms.
[22:13:54.358] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:13:54.428] INFO: run 2 of 2
[22:14:13.599] INFO: Test took 19171ms.
[22:14:13.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:14:14.037] INFO: dacScan step from 160 .. 172
[22:14:14.037] INFO: dacScan split into 2 runs with ntrig = 5
[22:14:14.037] INFO: run 1 of 2
[22:14:27.341] INFO: Test took 13303ms.
[22:14:27.530] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:14:27.579] INFO: run 2 of 2
[22:14:41.112] INFO: Test took 13532ms.
[22:14:41.318] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:19.505] INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 172 (20/5) hits flags = 16 (plus default)
[22:15:19.523] INFO: dacScan step from 0 .. 19
[22:15:19.523] INFO: dacScan split into 2 runs with ntrig = 5
[22:15:19.523] INFO: run 1 of 2
[22:15:33.050] INFO: Test took 13527ms.
[22:15:33.093] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:33.093] INFO: run 2 of 2
[22:15:46.484] INFO: Test took 13390ms.
[22:15:46.527] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:46.527] INFO: dacScan step from 20 .. 39
[22:15:46.527] INFO: dacScan split into 2 runs with ntrig = 5
[22:15:46.527] INFO: run 1 of 2
[22:16:00.015] INFO: Test took 13488ms.
[22:16:00.058] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:16:00.058] INFO: run 2 of 2
[22:16:13.556] INFO: Test took 13498ms.
[22:16:13.604] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:16:13.604] INFO: dacScan step from 40 .. 59
[22:16:13.605] INFO: dacScan split into 2 runs with ntrig = 5
[22:16:13.605] INFO: run 1 of 2
[22:16:27.046] INFO: Test took 13441ms.
[22:16:27.091] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:16:27.091] INFO: run 2 of 2
[22:16:40.577] INFO: Test took 13486ms.
[22:16:40.618] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:16:40.618] INFO: dacScan step from 60 .. 79
[22:16:40.618] INFO: dacScan split into 2 runs with ntrig = 5
[22:16:40.618] INFO: run 1 of 2
[22:16:54.207] INFO: Test took 13589ms.
[22:16:54.248] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:16:54.248] INFO: run 2 of 2
[22:17:07.704] INFO: Test took 13456ms.
[22:17:07.749] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:17:07.749] INFO: dacScan step from 80 .. 99
[22:17:07.749] INFO: dacScan split into 2 runs with ntrig = 5
[22:17:07.749] INFO: run 1 of 2
[22:17:21.372] INFO: Test took 13623ms.
[22:17:21.416] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:17:21.417] INFO: run 2 of 2
[22:17:35.105] INFO: Test took 13688ms.
[22:17:35.154] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:17:35.155] INFO: dacScan step from 100 .. 119
[22:17:35.155] INFO: dacScan split into 2 runs with ntrig = 5
[22:17:35.155] INFO: run 1 of 2
[22:17:50.337] INFO: Test took 15182ms.
[22:17:50.488] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:17:50.518] INFO: run 2 of 2
[22:18:05.562] INFO: Test took 15044ms.
[22:18:05.710] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:18:05.742] INFO: dacScan step from 120 .. 139
[22:18:05.742] INFO: dacScan split into 2 runs with ntrig = 5
[22:18:05.742] INFO: run 1 of 2
[22:18:25.464] INFO: Test took 19722ms.
[22:18:25.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:18:25.810] INFO: run 2 of 2
[22:18:45.507] INFO: Test took 19697ms.
[22:18:45.770] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:18:45.833] INFO: dacScan step from 140 .. 159
[22:18:45.833] INFO: dacScan split into 2 runs with ntrig = 5
[22:18:45.833] INFO: run 1 of 2
[22:19:05.042] INFO: Test took 19208ms.
[22:19:05.315] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:19:05.384] INFO: run 2 of 2
[22:19:24.698] INFO: Test took 19314ms.
[22:19:24.983] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:19:25.050] INFO: dacScan step from 160 .. 172
[22:19:25.050] INFO: dacScan split into 2 runs with ntrig = 5
[22:19:25.051] INFO: run 1 of 2
[22:19:38.560] INFO: Test took 13509ms.
[22:19:38.742] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:19:38.789] INFO: run 2 of 2
[22:19:52.131] INFO: Test took 13342ms.
[22:19:52.328] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:20:30.485] INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 171 (20/5) hits flags = 16 (plus default)
[22:20:30.500] INFO: dacScan step from 0 .. 19
[22:20:30.500] INFO: dacScan split into 2 runs with ntrig = 5
[22:20:30.500] INFO: run 1 of 2
[22:20:44.318] INFO: Test took 13817ms.
[22:20:44.356] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:20:44.356] INFO: run 2 of 2
[22:20:58.189] INFO: Test took 13833ms.
[22:20:58.228] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:20:58.228] INFO: dacScan step from 20 .. 39
[22:20:58.228] INFO: dacScan split into 2 runs with ntrig = 5
[22:20:58.228] INFO: run 1 of 2
[22:21:11.001] INFO: Test took 13772ms.
[22:21:12.043] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:21:12.043] INFO: run 2 of 2
[22:21:25.737] INFO: Test took 13694ms.
[22:21:25.782] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:21:25.782] INFO: dacScan step from 40 .. 59
[22:21:25.782] INFO: dacScan split into 2 runs with ntrig = 5
[22:21:25.782] INFO: run 1 of 2
[22:21:39.574] INFO: Test took 13792ms.
[22:21:39.621] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:21:39.622] INFO: run 2 of 2
[22:21:53.520] INFO: Test took 13898ms.
[22:21:53.563] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:21:53.563] INFO: dacScan step from 60 .. 79
[22:21:53.563] INFO: dacScan split into 2 runs with ntrig = 5
[22:21:53.563] INFO: run 1 of 2
[22:22:07.419] INFO: Test took 13856ms.
[22:22:07.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:22:07.458] INFO: run 2 of 2
[22:22:21.296] INFO: Test took 13838ms.
[22:22:21.338] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:22:21.338] INFO: dacScan step from 80 .. 99
[22:22:21.338] INFO: dacScan split into 2 runs with ntrig = 5
[22:22:21.338] INFO: run 1 of 2
[22:22:34.932] INFO: Test took 13594ms.
[22:22:34.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:22:34.980] INFO: run 2 of 2
[22:22:48.537] INFO: Test took 13557ms.
[22:22:48.589] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:22:48.590] INFO: dacScan step from 100 .. 119
[22:22:48.590] INFO: dacScan split into 2 runs with ntrig = 5
[22:22:48.590] INFO: run 1 of 2
[22:23:03.596] INFO: Test took 15006ms.
[22:23:03.752] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:23:03.780] INFO: run 2 of 2
[22:23:18.842] INFO: Test took 15062ms.
[22:23:19.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:23:19.038] INFO: dacScan step from 120 .. 139
[22:23:19.038] INFO: dacScan split into 2 runs with ntrig = 5
[22:23:19.038] INFO: run 1 of 2
[22:23:38.778] INFO: Test took 19739ms.
[22:23:39.080] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:23:39.154] INFO: run 2 of 2
[22:23:58.769] INFO: Test took 19615ms.
[22:23:59.049] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:23:59.119] INFO: dacScan step from 140 .. 159
[22:23:59.119] INFO: dacScan split into 2 runs with ntrig = 5
[22:23:59.119] INFO: run 1 of 2
[22:24:18.430] INFO: Test took 19311ms.
[22:24:18.708] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:24:18.781] INFO: run 2 of 2
[22:24:38.040] INFO: Test took 19259ms.
[22:24:38.339] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:24:38.410] INFO: dacScan step from 160 .. 171
[22:24:38.410] INFO: dacScan split into 2 runs with ntrig = 5
[22:24:38.410] INFO: run 1 of 2
[22:24:50.958] INFO: Test took 12548ms.
[22:24:51.124] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:24:51.172] INFO: run 2 of 2
[22:25:03.654] INFO: Test took 12482ms.
[22:25:03.827] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:25:40.944] INFO: PixTestTrim::trimBitTest() done
[22:25:40.946] INFO: PixTestTrim::doTest() done, duration: 3013 seconds
[22:25:41.912] INFO: ######################################################################
[22:25:41.912] INFO: PixTestPhOptimization::doTest() Ntrig = 10
[22:25:41.912] INFO: ######################################################################
[22:25:46.128] INFO: Test took 4214ms.
[22:25:46.166] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:25:50.608] INFO: Test took 4225ms.
[22:25:50.752] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:25:54.987] INFO: Test took 4218ms.
[22:25:55.169] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:25:55.180] INFO: The DUT currently contains the following objects:
[22:25:55.180] INFO: 2 TBM Cores tbm08c (2 ON)
[22:25:55.180] INFO: TBM Core alpha (0): 7 registers set
[22:25:55.180] INFO: TBM Core beta (1): 7 registers set
[22:25:55.180] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:25:55.180] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:55.180] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.315] INFO: Test took 1135ms.
[22:25:56.316] INFO: The DUT currently contains the following objects:
[22:25:56.316] INFO: 2 TBM Cores tbm08c (2 ON)
[22:25:56.317] INFO: TBM Core alpha (0): 7 registers set
[22:25:56.317] INFO: TBM Core beta (1): 7 registers set
[22:25:56.317] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:25:56.317] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:56.317] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.449] INFO: Test took 1132ms.
[22:25:57.451] INFO: The DUT currently contains the following objects:
[22:25:57.452] INFO: 2 TBM Cores tbm08c (2 ON)
[22:25:57.452] INFO: TBM Core alpha (0): 7 registers set
[22:25:57.452] INFO: TBM Core beta (1): 7 registers set
[22:25:57.452] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:25:57.452] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:57.452] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.547] INFO: Test took 1095ms.
[22:25:58.548] INFO: The DUT currently contains the following objects:
[22:25:58.548] INFO: 2 TBM Cores tbm08c (2 ON)
[22:25:58.548] INFO: TBM Core alpha (0): 7 registers set
[22:25:58.548] INFO: TBM Core beta (1): 7 registers set
[22:25:58.548] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:25:58.548] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.548] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.549] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.549] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.549] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.549] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.549] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.549] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.549] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.549] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.549] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.549] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.549] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.549] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.549] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:58.549] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.680] INFO: Test took 1131ms.
[22:25:59.683] INFO: The DUT currently contains the following objects:
[22:25:59.683] INFO: 2 TBM Cores tbm08c (2 ON)
[22:25:59.683] INFO: TBM Core alpha (0): 7 registers set
[22:25:59.683] INFO: TBM Core beta (1): 7 registers set
[22:25:59.683] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:25:59.683] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:25:59.683] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.780] INFO: Test took 1097ms.
[22:26:00.782] INFO: The DUT currently contains the following objects:
[22:26:00.782] INFO: 2 TBM Cores tbm08c (2 ON)
[22:26:00.782] INFO: TBM Core alpha (0): 7 registers set
[22:26:00.782] INFO: TBM Core beta (1): 7 registers set
[22:26:00.782] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:26:00.782] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:00.782] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.910] INFO: Test took 1128ms.
[22:26:01.910] INFO: The DUT currently contains the following objects:
[22:26:01.910] INFO: 2 TBM Cores tbm08c (2 ON)
[22:26:01.910] INFO: TBM Core alpha (0): 7 registers set
[22:26:01.910] INFO: TBM Core beta (1): 7 registers set
[22:26:01.910] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:26:01.910] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.910] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.911] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.911] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.911] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.911] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.911] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.911] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.911] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.911] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.911] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.911] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.911] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.911] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.911] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:01.911] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.040] INFO: Test took 1129ms.
[22:26:03.041] INFO: The DUT currently contains the following objects:
[22:26:03.041] INFO: 2 TBM Cores tbm08c (2 ON)
[22:26:03.041] INFO: TBM Core alpha (0): 7 registers set
[22:26:03.041] INFO: TBM Core beta (1): 7 registers set
[22:26:03.041] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:26:03.041] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.041] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.041] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.041] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.041] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.042] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.042] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.042] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.042] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.042] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.042] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.042] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.042] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.042] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.042] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:03.042] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.171] INFO: Test took 1129ms.
[22:26:04.172] INFO: The DUT currently contains the following objects:
[22:26:04.172] INFO: 2 TBM Cores tbm08c (2 ON)
[22:26:04.172] INFO: TBM Core alpha (0): 7 registers set
[22:26:04.172] INFO: TBM Core beta (1): 7 registers set
[22:26:04.172] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:26:04.172] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.172] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.172] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.172] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.172] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.172] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.172] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.172] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.173] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.173] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.173] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.173] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.173] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.173] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.173] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:04.173] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.269] INFO: Test took 1096ms.
[22:26:05.270] INFO: The DUT currently contains the following objects:
[22:26:05.270] INFO: 2 TBM Cores tbm08c (2 ON)
[22:26:05.270] INFO: TBM Core alpha (0): 7 registers set
[22:26:05.270] INFO: TBM Core beta (1): 7 registers set
[22:26:05.270] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:26:05.270] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.270] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.270] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.271] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.271] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.271] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.271] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.271] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.271] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.271] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.271] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.271] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.271] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.271] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.271] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:05.271] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.368] INFO: Test took 1097ms.
[22:26:06.371] INFO: The DUT currently contains the following objects:
[22:26:06.371] INFO: 2 TBM Cores tbm08c (2 ON)
[22:26:06.371] INFO: TBM Core alpha (0): 7 registers set
[22:26:06.371] INFO: TBM Core beta (1): 7 registers set
[22:26:06.371] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:26:06.371] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:06.371] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.502] INFO: Test took 1131ms.
[22:26:07.504] INFO: The DUT currently contains the following objects:
[22:26:07.504] INFO: 2 TBM Cores tbm08c (2 ON)
[22:26:07.505] INFO: TBM Core alpha (0): 7 registers set
[22:26:07.505] INFO: TBM Core beta (1): 7 registers set
[22:26:07.505] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:26:07.505] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:07.505] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.597] INFO: Test took 1092ms.
[22:26:08.598] INFO: The DUT currently contains the following objects:
[22:26:08.598] INFO: 2 TBM Cores tbm08c (2 ON)
[22:26:08.598] INFO: TBM Core alpha (0): 7 registers set
[22:26:08.599] INFO: TBM Core beta (1): 7 registers set
[22:26:08.599] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:26:08.599] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:08.599] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.728] INFO: Test took 1129ms.
[22:26:09.730] INFO: The DUT currently contains the following objects:
[22:26:09.730] INFO: 2 TBM Cores tbm08c (2 ON)
[22:26:09.730] INFO: TBM Core alpha (0): 7 registers set
[22:26:09.730] INFO: TBM Core beta (1): 7 registers set
[22:26:09.730] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:26:09.730] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:09.730] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.862] INFO: Test took 1132ms.
[22:26:10.864] INFO: The DUT currently contains the following objects:
[22:26:10.865] INFO: 2 TBM Cores tbm08c (2 ON)
[22:26:10.865] INFO: TBM Core alpha (0): 7 registers set
[22:26:10.865] INFO: TBM Core beta (1): 7 registers set
[22:26:10.865] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:26:10.865] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:10.865] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.995] INFO: Test took 1130ms.
[22:26:11.997] INFO: The DUT currently contains the following objects:
[22:26:11.997] INFO: 2 TBM Cores tbm08c (2 ON)
[22:26:11.997] INFO: TBM Core alpha (0): 7 registers set
[22:26:11.997] INFO: TBM Core beta (1): 7 registers set
[22:26:11.997] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[22:26:11.997] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.997] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.997] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.997] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.997] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.997] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.997] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.997] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.997] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.997] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.997] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.997] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.997] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.997] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.998] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:11.998] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[22:26:13.125] INFO: Test took 1127ms.
[22:26:13.130] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:29:24.454] INFO: Test took 191324ms.
[22:29:27.406] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:32:37.526] INFO: Test took 190120ms.
[22:32:40.521] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.532] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.542] INFO: safety margin for low PH: adding 1, margin is now 21
[22:32:40.553] INFO: safety margin for low PH: adding 2, margin is now 22
[22:32:40.563] INFO: safety margin for low PH: adding 3, margin is now 23
[22:32:40.573] INFO: safety margin for low PH: adding 4, margin is now 24
[22:32:40.581] INFO: safety margin for low PH: adding 5, margin is now 25
[22:32:40.591] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.599] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.608] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.618] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.627] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.636] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.645] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.655] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.663] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.672] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.682] INFO: safety margin for low PH: adding 1, margin is now 21
[22:32:40.691] INFO: safety margin for low PH: adding 2, margin is now 22
[22:32:40.700] INFO: safety margin for low PH: adding 3, margin is now 23
[22:32:40.709] INFO: safety margin for low PH: adding 4, margin is now 24
[22:32:40.719] INFO: safety margin for low PH: adding 5, margin is now 25
[22:32:40.728] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.738] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.746] INFO: safety margin for low PH: adding 1, margin is now 21
[22:32:40.756] INFO: safety margin for low PH: adding 2, margin is now 22
[22:32:40.765] INFO: safety margin for low PH: adding 3, margin is now 23
[22:32:40.774] INFO: safety margin for low PH: adding 4, margin is now 24
[22:32:40.784] INFO: safety margin for low PH: adding 5, margin is now 25
[22:32:40.794] INFO: safety margin for low PH: adding 6, margin is now 26
[22:32:40.803] INFO: safety margin for low PH: adding 7, margin is now 27
[22:32:40.814] INFO: safety margin for low PH: adding 8, margin is now 28
[22:32:40.823] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.833] INFO: safety margin for low PH: adding 0, margin is now 20
[22:32:40.886] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C0.dat
[22:32:40.886] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C1.dat
[22:32:40.887] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C2.dat
[22:32:40.887] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C3.dat
[22:32:40.887] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C4.dat
[22:32:40.888] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C5.dat
[22:32:40.888] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C6.dat
[22:32:40.888] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C7.dat
[22:32:40.889] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C8.dat
[22:32:40.889] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C9.dat
[22:32:40.889] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C10.dat
[22:32:40.890] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C11.dat
[22:32:40.890] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C12.dat
[22:32:40.890] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C13.dat
[22:32:40.891] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C14.dat
[22:32:40.891] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/dacParameters35_C15.dat
[22:32:45.120] INFO: Test took 4226ms.
[22:32:49.571] INFO: Test took 4106ms.
[22:32:54.113] INFO: Test took 4183ms.
[22:32:54.479] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:32:55.385] INFO: Test took 907ms.
[22:32:55.388] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:32:56.523] INFO: Test took 1135ms.
[22:32:56.527] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:32:57.659] INFO: Test took 1132ms.
[22:32:57.663] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:32:58.798] INFO: Test took 1135ms.
[22:32:58.802] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:32:59.938] INFO: Test took 1136ms.
[22:32:59.943] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:01.074] INFO: Test took 1131ms.
[22:33:01.077] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:02.172] INFO: Test took 1095ms.
[22:33:02.176] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:03.307] INFO: Test took 1131ms.
[22:33:03.311] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:04.443] INFO: Test took 1132ms.
[22:33:04.449] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:05.544] INFO: Test took 1095ms.
[22:33:05.549] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:06.645] INFO: Test took 1096ms.
[22:33:06.649] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:07.778] INFO: Test took 1129ms.
[22:33:07.784] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:08.914] INFO: Test took 1130ms.
[22:33:08.920] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:10.049] INFO: Test took 1130ms.
[22:33:10.054] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:11.183] INFO: Test took 1129ms.
[22:33:11.188] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:12.314] INFO: Test took 1126ms.
[22:33:12.319] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:13.446] INFO: Test took 1127ms.
[22:33:13.450] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:14.580] INFO: Test took 1130ms.
[22:33:14.584] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:15.683] INFO: Test took 1099ms.
[22:33:15.688] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:16.785] INFO: Test took 1098ms.
[22:33:16.789] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:17.919] INFO: Test took 1131ms.
[22:33:17.924] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:19.053] INFO: Test took 1129ms.
[22:33:19.057] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:20.150] INFO: Test took 1094ms.
[22:33:20.154] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:21.247] INFO: Test took 1094ms.
[22:33:21.251] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:22.383] INFO: Test took 1133ms.
[22:33:22.389] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:23.517] INFO: Test took 1129ms.
[22:33:23.523] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:24.652] INFO: Test took 1130ms.
[22:33:24.658] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:25.754] INFO: Test took 1096ms.
[22:33:25.759] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:26.857] INFO: Test took 1098ms.
[22:33:26.863] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:27.956] INFO: Test took 1094ms.
[22:33:27.961] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:29.055] INFO: Test took 1095ms.
[22:33:29.060] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:33:30.190] INFO: Test took 1130ms.
[22:33:30.772] INFO: PixTestPhOptimization::doTest() done, duration: 468 seconds
[22:33:30.772] INFO: PH scale (per ROC): 63 69 75 74 67 67 68 62 70 73 72 72 72 76 65 71
[22:33:30.772] INFO: PH offset (per ROC): 175 176 176 178 173 166 179 175 177 196 176 176 190 176 185 197
[22:33:30.956] INFO: ######################################################################
[22:33:30.956] INFO: PixTestGainPedestal::fullTest() ntrig = 10
[22:33:30.956] INFO: ######################################################################
[22:33:30.972] INFO: scanning low vcal = 10
[22:33:34.672] INFO: Test took 3700ms.
[22:33:34.678] INFO: scanning low vcal = 20
[22:33:38.440] INFO: Test took 3762ms.
[22:33:38.445] INFO: scanning low vcal = 30
[22:33:42.203] INFO: Test took 3758ms.
[22:33:42.223] INFO: scanning low vcal = 40
[22:33:46.391] INFO: Test took 4168ms.
[22:33:46.504] INFO: scanning low vcal = 50
[22:33:50.785] INFO: Test took 4281ms.
[22:33:50.908] INFO: scanning low vcal = 60
[22:33:55.096] INFO: Test took 4188ms.
[22:33:55.214] INFO: scanning low vcal = 70
[22:33:59.361] INFO: Test took 4147ms.
[22:33:59.504] INFO: scanning low vcal = 80
[22:34:03.796] INFO: Test took 4292ms.
[22:34:03.913] INFO: scanning low vcal = 90
[22:34:08.181] INFO: Test took 4268ms.
[22:34:08.323] INFO: scanning low vcal = 100
[22:34:12.578] INFO: Test took 4255ms.
[22:34:12.717] INFO: scanning low vcal = 110
[22:34:17.059] INFO: Test took 4342ms.
[22:34:17.184] INFO: scanning low vcal = 120
[22:34:21.398] INFO: Test took 4214ms.
[22:34:21.514] INFO: scanning low vcal = 130
[22:34:25.851] INFO: Test took 4337ms.
[22:34:25.973] INFO: scanning low vcal = 140
[22:34:30.315] INFO: Test took 4342ms.
[22:34:30.449] INFO: scanning low vcal = 150
[22:34:34.683] INFO: Test took 4234ms.
[22:34:34.816] INFO: scanning low vcal = 160
[22:34:39.119] INFO: Test took 4303ms.
[22:34:39.256] INFO: scanning low vcal = 170
[22:34:43.494] INFO: Test took 4238ms.
[22:34:43.640] INFO: scanning low vcal = 180
[22:34:47.940] INFO: Test took 4300ms.
[22:34:48.085] INFO: scanning low vcal = 190
[22:34:52.395] INFO: Test took 4310ms.
[22:34:52.545] INFO: scanning low vcal = 200
[22:34:56.792] INFO: Test took 4247ms.
[22:34:56.973] INFO: scanning low vcal = 210
[22:35:01.340] INFO: Test took 4367ms.
[22:35:01.480] INFO: scanning low vcal = 220
[22:35:05.755] INFO: Test took 4275ms.
[22:35:05.899] INFO: scanning low vcal = 230
[22:35:10.148] INFO: Test took 4249ms.
[22:35:10.293] INFO: scanning low vcal = 240
[22:35:14.637] INFO: Test took 4344ms.
[22:35:14.773] INFO: scanning low vcal = 250
[22:35:19.038] INFO: Test took 4265ms.
[22:35:19.176] INFO: scanning high vcal = 30 (= 210 in low range)
[22:35:23.410] INFO: Test took 4234ms.
[22:35:23.536] INFO: scanning high vcal = 50 (= 350 in low range)
[22:35:27.838] INFO: Test took 4302ms.
[22:35:27.974] INFO: scanning high vcal = 70 (= 490 in low range)
[22:35:32.205] INFO: Test took 4231ms.
[22:35:32.331] INFO: scanning high vcal = 90 (= 630 in low range)
[22:35:36.638] INFO: Test took 4307ms.
[22:35:36.780] INFO: scanning high vcal = 200 (= 1400 in low range)
[22:35:41.073] INFO: Test took 4293ms.
[22:35:41.899] INFO: PixTestGainPedestal::measure() done
[22:36:36.942] INFO: PixTestGainPedestal::fit() done
[22:36:36.942] INFO: non-linearity mean: 0.958 0.963 0.957 0.958 0.954 0.956 0.953 0.956 0.948 0.959 0.958 0.971 0.958 0.964 0.959 0.962
[22:36:36.942] INFO: non-linearity RMS: 0.007 0.005 0.007 0.006 0.008 0.007 0.007 0.008 0.008 0.006 0.006 0.004 0.007 0.004 0.006 0.006
[22:36:36.942] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C0.dat
[22:36:36.974] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C1.dat
[22:36:36.000] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C2.dat
[22:36:37.026] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C3.dat
[22:36:37.053] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C4.dat
[22:36:37.079] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C5.dat
[22:36:37.106] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C6.dat
[22:36:37.132] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C7.dat
[22:36:37.159] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C8.dat
[22:36:37.185] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C9.dat
[22:36:37.211] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C10.dat
[22:36:37.238] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C11.dat
[22:36:37.265] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C12.dat
[22:36:37.291] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C13.dat
[22:36:37.317] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C14.dat
[22:36:37.344] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[22:36:37.370] INFO: PixTestGainPedestal::doTest() done, duration: 186 seconds
[22:36:37.378] INFO: readReadbackCal: /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C15.dat
[22:36:37.380] INFO: PixTestReadback::doTest() start.
[22:36:37.381] INFO: PixTestReadback::RES sent once
[22:36:54.120] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C0.dat
[22:36:54.120] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C1.dat
[22:36:54.120] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C2.dat
[22:36:54.120] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C3.dat
[22:36:54.121] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C4.dat
[22:36:54.121] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C5.dat
[22:36:54.121] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C6.dat
[22:36:54.121] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C7.dat
[22:36:54.121] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C8.dat
[22:36:54.121] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C9.dat
[22:36:54.121] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C10.dat
[22:36:54.121] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C11.dat
[22:36:54.121] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C12.dat
[22:36:54.121] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C13.dat
[22:36:54.121] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C14.dat
[22:36:54.121] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C15.dat
[22:36:54.156] INFO: PixTestPattern:: pg_setup set to default.
[22:36:54.156] INFO: PixTestReadback::RES sent once
[22:37:10.759] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C0.dat
[22:37:10.759] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C1.dat
[22:37:10.759] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C2.dat
[22:37:10.759] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C3.dat
[22:37:10.759] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C4.dat
[22:37:10.759] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C5.dat
[22:37:10.759] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C6.dat
[22:37:10.760] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C7.dat
[22:37:10.760] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C8.dat
[22:37:10.760] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C9.dat
[22:37:10.760] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C10.dat
[22:37:10.760] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C11.dat
[22:37:10.760] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C12.dat
[22:37:10.760] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C13.dat
[22:37:10.760] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C14.dat
[22:37:10.760] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C15.dat
[22:37:10.804] INFO: PixTestPattern:: pg_setup set to default.
[22:37:10.805] INFO: PixTestReadback::RES sent once
[22:37:23.694] INFO: PixTestPattern:: pg_setup set to default.
[22:37:23.695] INFO: Vbg will be calibrated using Vd calibration
[22:37:23.695] INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151.9calibrated Vbg = 1.24172 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 148.6calibrated Vbg = 1.23017 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.9calibrated Vbg = 1.23976 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153.2calibrated Vbg = 1.24263 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 155.1calibrated Vbg = 1.25206 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 156.1calibrated Vbg = 1.24867 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 166.2calibrated Vbg = 1.23992 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152.4calibrated Vbg = 1.25082 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.2calibrated Vbg = 1.25137 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 153.1calibrated Vbg = 1.24377 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153.4calibrated Vbg = 1.2428 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 151.4calibrated Vbg = 1.24162 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 157.5calibrated Vbg = 1.23651 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 141.9calibrated Vbg = 1.24219 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.4calibrated Vbg = 1.24717 :::*/*/*/*/
[22:37:23.695] INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156.7calibrated Vbg = 1.24078 :::*/*/*/*/
[22:37:23.699] INFO: PixTestReadback::RES sent once
[22:41:19.131] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C0.dat
[22:41:19.131] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C1.dat
[22:41:19.132] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C2.dat
[22:41:19.132] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C3.dat
[22:41:19.132] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C4.dat
[22:41:19.132] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C5.dat
[22:41:19.132] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C6.dat
[22:41:19.132] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C7.dat
[22:41:19.132] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C8.dat
[22:41:19.132] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C9.dat
[22:41:19.132] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C10.dat
[22:41:19.132] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C11.dat
[22:41:19.133] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C12.dat
[22:41:19.133] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C13.dat
[22:41:19.133] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C14.dat
[22:41:19.133] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/004_Fulltest_p17/readbackCal_C15.dat
[22:41:19.164] INFO: PixTestPattern:: pg_setup set to default.
[22:41:19.166] INFO: PixTestReadback::doTest() done
[22:41:19.181] INFO: enter test to run
[22:41:19.181] INFO: test: q no parameter change
[22:41:19.345] QUIET: Connection to board 178 closed.
[22:41:19.348] INFO: pXar: this is the end, my friend