[14:43:02.835] <TB3> INFO: *** Welcome to pxar ***
[14:43:02.835] <TB3> INFO: *** Today: 2015/09/03
[14:43:02.835] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C15.dat
[14:43:02.837] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:43:02.837] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//defaultMaskFile.dat
[14:43:02.837] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters_C15.dat
[14:43:02.915] <TB3> INFO: clk: 4
[14:43:02.915] <TB3> INFO: ctr: 4
[14:43:02.915] <TB3> INFO: sda: 19
[14:43:02.915] <TB3> INFO: tin: 9
[14:43:02.915] <TB3> INFO: level: 15
[14:43:02.915] <TB3> INFO: triggerdelay: 0
[14:43:02.915] <TB3> QUIET: Instanciating API for pxar prod-10
[14:43:02.915] <TB3> INFO: Log level: INFO
[14:43:02.922] <TB3> INFO: Found DTB DTB_WZ4I6J
[14:43:02.935] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[14:43:02.938] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[14:43:02.940] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[14:43:04.486] <TB3> INFO: DUT info:
[14:43:04.486] <TB3> INFO: The DUT currently contains the following objects:
[14:43:04.486] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:43:04.486] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:43:04.486] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:43:04.486] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:43:04.486] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.486] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:04.888] <TB3> INFO: enter 'restricted' command line mode
[14:43:04.888] <TB3> INFO: enter test to run
[14:43:04.888] <TB3> INFO: test: pretest no parameter change
[14:43:04.888] <TB3> INFO: running: pretest
[14:43:04.895] <TB3> INFO: ######################################################################
[14:43:04.895] <TB3> INFO: PixTestPretest::doTest()
[14:43:04.895] <TB3> INFO: ######################################################################
[14:43:04.897] <TB3> INFO: ----------------------------------------------------------------------
[14:43:04.897] <TB3> INFO: PixTestPretest::programROC()
[14:43:04.897] <TB3> INFO: ----------------------------------------------------------------------
[14:43:22.915] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:43:22.915] <TB3> INFO: IA differences per ROC: 19.3 19.3 18.5 19.3 19.3 19.3 20.1 20.1 17.7 18.5 19.3 20.1 21.7 19.3 17.7 20.9
[14:43:22.984] <TB3> INFO: ----------------------------------------------------------------------
[14:43:22.984] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:43:22.984] <TB3> INFO: ----------------------------------------------------------------------
[14:43:29.574] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 393.9 mA = 24.6187 mA/ROC
[14:43:29.577] <TB3> INFO: ----------------------------------------------------------------------
[14:43:29.577] <TB3> INFO: PixTestPretest::findWorkingPixel()
[14:43:29.577] <TB3> INFO: ----------------------------------------------------------------------
[14:43:29.716] <TB3> INFO: Expecting 231680 events.
[14:43:39.034] <TB3> INFO: 231680 events read in total (8599ms).
[14:43:39.096] <TB3> INFO: Test took 9513ms.
[14:43:39.368] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[14:43:39.403] <TB3> INFO: ----------------------------------------------------------------------
[14:43:39.403] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[14:43:39.403] <TB3> INFO: ----------------------------------------------------------------------
[14:43:39.541] <TB3> INFO: Expecting 231680 events.
[14:43:48.829] <TB3> INFO: 231680 events read in total (8572ms).
[14:43:48.833] <TB3> INFO: Test took 9424ms.
[14:43:49.169] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[14:43:49.169] <TB3> INFO: CalDel: 145 130 139 131 142 125 124 154 129 143 135 159 143 118 114 144
[14:43:49.169] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[14:43:49.172] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C0.dat
[14:43:49.173] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C1.dat
[14:43:49.173] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C2.dat
[14:43:49.173] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C3.dat
[14:43:49.173] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C4.dat
[14:43:49.174] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C5.dat
[14:43:49.174] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C6.dat
[14:43:49.174] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C7.dat
[14:43:49.174] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C8.dat
[14:43:49.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C9.dat
[14:43:49.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C10.dat
[14:43:49.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C11.dat
[14:43:49.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C12.dat
[14:43:49.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C13.dat
[14:43:49.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C14.dat
[14:43:49.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters_C15.dat
[14:43:49.175] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//tbmParameters_C0a.dat
[14:43:49.176] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:43:49.176] <TB3> INFO: PixTestPretest::doTest() done, duration: 44 seconds
[14:43:49.264] <TB3> INFO: enter test to run
[14:43:49.264] <TB3> INFO: test: fulltest no parameter change
[14:43:49.264] <TB3> INFO: running: fulltest
[14:43:49.264] <TB3> INFO: ######################################################################
[14:43:49.264] <TB3> INFO: PixTestFullTest::doTest()
[14:43:49.264] <TB3> INFO: ######################################################################
[14:43:49.265] <TB3> INFO: ######################################################################
[14:43:49.265] <TB3> INFO: PixTestAlive::doTest()
[14:43:49.265] <TB3> INFO: ######################################################################
[14:43:49.267] <TB3> INFO: ----------------------------------------------------------------------
[14:43:49.267] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:43:49.267] <TB3> INFO: ----------------------------------------------------------------------
[14:43:49.576] <TB3> INFO: Expecting 41600 events.
[14:43:54.017] <TB3> INFO: 41600 events read in total (3726ms).
[14:43:54.018] <TB3> INFO: Test took 4750ms.
[14:43:54.025] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:54.318] <TB3> INFO: PixTestAlive::aliveTest() done
[14:43:54.318] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 1 1 0 0 0 0 0 0 0 0 0 0 2
[14:43:54.320] <TB3> INFO: ----------------------------------------------------------------------
[14:43:54.320] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:43:54.320] <TB3> INFO: ----------------------------------------------------------------------
[14:43:54.628] <TB3> INFO: Expecting 41600 events.
[14:43:57.785] <TB3> INFO: 41600 events read in total (2441ms).
[14:43:57.785] <TB3> INFO: Test took 3464ms.
[14:43:57.785] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:57.785] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:43:58.104] <TB3> INFO: PixTestAlive::maskTest() done
[14:43:58.104] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:43:58.106] <TB3> INFO: ----------------------------------------------------------------------
[14:43:58.106] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:43:58.106] <TB3> INFO: ----------------------------------------------------------------------
[14:43:58.424] <TB3> INFO: Expecting 41600 events.
[14:44:02.857] <TB3> INFO: 41600 events read in total (3717ms).
[14:44:02.857] <TB3> INFO: Test took 4750ms.
[14:44:02.864] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:03.153] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[14:44:03.153] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:44:03.153] <TB3> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[14:44:03.167] <TB3> INFO: ######################################################################
[14:44:03.167] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[14:44:03.167] <TB3> INFO: ######################################################################
[14:44:03.170] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 2 (plus default)
[14:44:03.184] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[14:44:03.184] <TB3> INFO: run 1 of 1
[14:44:03.493] <TB3> INFO: Expecting 3120000 events.
[14:44:39.330] <TB3> INFO: 853380 events read in total (35121ms).
[14:45:14.151] <TB3> INFO: 1694980 events read in total (69943ms).
[14:45:49.093] <TB3> INFO: 2546025 events read in total (104884ms).
[14:46:11.611] <TB3> INFO: 3120000 events read in total (127402ms).
[14:46:11.664] <TB3> INFO: Test took 128479ms.
[14:46:11.762] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:35.308] <TB3> INFO: PixTestBBMap::doTest() done, duration: 152 seconds
[14:46:35.308] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 163
[14:46:35.308] <TB3> INFO: separation cut (per ROC): 69 74 79 89 94 95 94 82 79 82 90 87 93 88 101 64
[14:46:35.395] <TB3> INFO: ######################################################################
[14:46:35.395] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:46:35.395] <TB3> INFO: ######################################################################
[14:46:35.395] <TB3> INFO: ----------------------------------------------------------------------
[14:46:35.395] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:46:35.395] <TB3> INFO: ----------------------------------------------------------------------
[14:46:35.395] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (-1/-1) hits flags = 16 (plus default)
[14:46:35.405] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[14:46:35.405] <TB3> INFO: run 1 of 1
[14:46:35.714] <TB3> INFO: Expecting 31200000 events.
[14:46:58.135] <TB3> INFO: 957350 events read in total (21705ms).
[14:47:22.516] <TB3> INFO: 1899200 events read in total (46086ms).
[14:47:46.643] <TB3> INFO: 2834700 events read in total (70213ms).
[14:48:11.138] <TB3> INFO: 3774400 events read in total (94708ms).
[14:48:35.513] <TB3> INFO: 4708500 events read in total (119083ms).
[14:48:59.778] <TB3> INFO: 5642500 events read in total (143348ms).
[14:49:24.084] <TB3> INFO: 6574600 events read in total (167654ms).
[14:49:48.780] <TB3> INFO: 7507950 events read in total (192350ms).
[14:50:13.214] <TB3> INFO: 8442700 events read in total (216784ms).
[14:50:37.682] <TB3> INFO: 9373200 events read in total (241252ms).
[14:51:02.026] <TB3> INFO: 10305000 events read in total (265596ms).
[14:51:26.478] <TB3> INFO: 11234650 events read in total (290048ms).
[14:51:50.806] <TB3> INFO: 12165900 events read in total (314376ms).
[14:52:15.102] <TB3> INFO: 13094150 events read in total (338672ms).
[14:52:39.356] <TB3> INFO: 14023950 events read in total (362926ms).
[14:53:03.714] <TB3> INFO: 14952900 events read in total (387284ms).
[14:53:27.908] <TB3> INFO: 15874550 events read in total (411478ms).
[14:53:52.185] <TB3> INFO: 16792400 events read in total (435755ms).
[14:54:16.210] <TB3> INFO: 17709650 events read in total (459780ms).
[14:54:40.467] <TB3> INFO: 18629200 events read in total (484037ms).
[14:55:04.570] <TB3> INFO: 19543850 events read in total (508140ms).
[14:55:28.886] <TB3> INFO: 20462500 events read in total (532456ms).
[14:55:53.251] <TB3> INFO: 21377150 events read in total (556821ms).
[14:56:17.625] <TB3> INFO: 22294050 events read in total (581195ms).
[14:56:41.726] <TB3> INFO: 23204200 events read in total (605296ms).
[14:57:06.255] <TB3> INFO: 24122400 events read in total (629825ms).
[14:57:30.554] <TB3> INFO: 25035700 events read in total (654124ms).
[14:57:54.538] <TB3> INFO: 25951800 events read in total (678108ms).
[14:58:18.778] <TB3> INFO: 26863400 events read in total (702348ms).
[14:58:42.939] <TB3> INFO: 27780050 events read in total (726509ms).
[14:59:07.183] <TB3> INFO: 28697850 events read in total (750753ms).
[14:59:31.325] <TB3> INFO: 29616800 events read in total (774895ms).
[14:59:55.607] <TB3> INFO: 30535650 events read in total (799177ms).
[15:00:12.082] <TB3> INFO: 31200000 events read in total (815652ms).
[15:00:12.110] <TB3> INFO: Test took 816705ms.
[15:00:12.190] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:12.303] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:13.818] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:15.255] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:16.759] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:18.252] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:19.958] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:21.701] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:23.165] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:24.604] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:26.059] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:27.512] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:28.897] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:30.311] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:31.866] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:33.360] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:34.833] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:00:36.341] <TB3> INFO: PixTestScurves::scurves() done
[15:00:36.341] <TB3> INFO: Vcal mean: 84.51 81.40 80.36 88.99 101.57 99.36 99.71 85.68 83.56 85.54 89.12 85.66 89.68 89.34 105.14 87.27
[15:00:36.341] <TB3> INFO: Vcal RMS: 4.47 5.06 4.59 5.37 5.53 5.82 5.78 4.69 4.35 5.36 5.55 5.09 4.93 5.87 4.92 5.75
[15:00:36.342] <TB3> INFO: PixTestScurves::fullTest() done, duration: 840 seconds
[15:00:36.413] <TB3> INFO: ######################################################################
[15:00:36.413] <TB3> INFO: PixTestTrim::doTest()
[15:00:36.413] <TB3> INFO: ######################################################################
[15:00:36.415] <TB3> INFO: ----------------------------------------------------------------------
[15:00:36.415] <TB3> INFO: PixTestTrim::trimTest() ntrig = 20, vcal = 35
[15:00:36.415] <TB3> INFO: ----------------------------------------------------------------------
[15:00:36.498] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:00:36.498] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 20 dacrange: 0 .. 159 (-1/-1) hits flags = 16 (plus default)
[15:00:36.507] <TB3> INFO: dacScan split into 1 runs with ntrig = 20
[15:00:36.507] <TB3> INFO: run 1 of 1
[15:00:36.810] <TB3> INFO: Expecting 13312000 events.
[15:01:05.413] <TB3> INFO: 1076020 events read in total (27886ms).
[15:01:30.731] <TB3> INFO: 2146380 events read in total (53204ms).
[15:01:55.729] <TB3> INFO: 3214220 events read in total (78202ms).
[15:02:23.258] <TB3> INFO: 4280960 events read in total (105731ms).
[15:02:50.730] <TB3> INFO: 5342860 events read in total (133203ms).
[15:03:18.354] <TB3> INFO: 6400460 events read in total (160827ms).
[15:03:45.820] <TB3> INFO: 7459840 events read in total (188293ms).
[15:04:13.432] <TB3> INFO: 8523580 events read in total (215905ms).
[15:04:40.956] <TB3> INFO: 9589100 events read in total (243429ms).
[15:05:08.069] <TB3> INFO: 10657060 events read in total (270542ms).
[15:05:34.217] <TB3> INFO: 11726660 events read in total (296690ms).
[15:06:01.886] <TB3> INFO: 12797960 events read in total (324359ms).
[15:06:14.225] <TB3> INFO: 13312000 events read in total (336698ms).
[15:06:14.260] <TB3> INFO: Test took 337753ms.
[15:06:14.314] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:32.500] <TB3> INFO: ROC 0 VthrComp = 86
[15:06:32.500] <TB3> INFO: ROC 1 VthrComp = 83
[15:06:32.501] <TB3> INFO: ROC 2 VthrComp = 84
[15:06:32.501] <TB3> INFO: ROC 3 VthrComp = 95
[15:06:32.501] <TB3> INFO: ROC 4 VthrComp = 100
[15:06:32.501] <TB3> INFO: ROC 5 VthrComp = 100
[15:06:32.501] <TB3> INFO: ROC 6 VthrComp = 101
[15:06:32.501] <TB3> INFO: ROC 7 VthrComp = 89
[15:06:32.501] <TB3> INFO: ROC 8 VthrComp = 87
[15:06:32.502] <TB3> INFO: ROC 9 VthrComp = 87
[15:06:32.502] <TB3> INFO: ROC 10 VthrComp = 91
[15:06:32.502] <TB3> INFO: ROC 11 VthrComp = 86
[15:06:32.502] <TB3> INFO: ROC 12 VthrComp = 96
[15:06:32.502] <TB3> INFO: ROC 13 VthrComp = 90
[15:06:32.502] <TB3> INFO: ROC 14 VthrComp = 104
[15:06:32.502] <TB3> INFO: ROC 15 VthrComp = 89
[15:06:32.502] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:06:32.502] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 20 dacrange: 0 .. 159 (-1/-1) hits flags = 16 (plus default)
[15:06:32.513] <TB3> INFO: dacScan split into 1 runs with ntrig = 20
[15:06:32.513] <TB3> INFO: run 1 of 1
[15:06:32.850] <TB3> INFO: Expecting 13312000 events.
[15:06:56.310] <TB3> INFO: 782220 events read in total (22744ms).
[15:07:18.804] <TB3> INFO: 1560500 events read in total (45238ms).
[15:07:42.194] <TB3> INFO: 2337700 events read in total (68628ms).
[15:08:06.501] <TB3> INFO: 3114700 events read in total (92935ms).
[15:08:30.093] <TB3> INFO: 3891820 events read in total (116527ms).
[15:08:54.943] <TB3> INFO: 4668420 events read in total (141377ms).
[15:09:19.858] <TB3> INFO: 5445580 events read in total (166292ms).
[15:09:44.962] <TB3> INFO: 6222820 events read in total (191396ms).
[15:10:09.778] <TB3> INFO: 6996400 events read in total (216212ms).
[15:10:34.469] <TB3> INFO: 7766280 events read in total (240903ms).
[15:10:59.436] <TB3> INFO: 8534380 events read in total (265870ms).
[15:11:24.344] <TB3> INFO: 9301820 events read in total (290778ms).
[15:11:48.975] <TB3> INFO: 10067000 events read in total (315409ms).
[15:12:13.789] <TB3> INFO: 10832300 events read in total (340223ms).
[15:12:38.241] <TB3> INFO: 11596260 events read in total (364675ms).
[15:13:03.003] <TB3> INFO: 12361220 events read in total (389437ms).
[15:13:27.918] <TB3> INFO: 13127040 events read in total (414352ms).
[15:13:33.592] <TB3> INFO: 13312000 events read in total (420026ms).
[15:13:33.634] <TB3> INFO: Test took 421121ms.
[15:13:33.770] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:13:57.586] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 56.3042 for pixel 27/1 mean/min/max = 44.609/32.6735/56.5444
[15:13:57.586] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 59.5093 for pixel 0/11 mean/min/max = 45.8451/32.0967/59.5936
[15:13:57.587] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 56.9013 for pixel 2/61 mean/min/max = 44.7119/32.3384/57.0855
[15:13:57.587] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 56.2006 for pixel 0/65 mean/min/max = 44.6212/32.6636/56.5788
[15:13:57.587] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 59.5887 for pixel 10/74 mean/min/max = 46.3011/32.9318/59.6703
[15:13:57.587] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 58.9416 for pixel 5/7 mean/min/max = 45.6696/32.1762/59.1631
[15:13:57.588] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 59.5954 for pixel 51/79 mean/min/max = 46.3471/32.7012/59.9931
[15:13:57.588] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 57.009 for pixel 0/44 mean/min/max = 45.6942/34.3117/57.0768
[15:13:57.588] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 55.5193 for pixel 20/6 mean/min/max = 43.9028/32.2077/55.598
[15:13:57.588] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 58.7595 for pixel 0/8 mean/min/max = 45.3151/31.8393/58.7909
[15:13:57.589] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 58.5684 for pixel 16/17 mean/min/max = 45.3971/32.2092/58.585
[15:13:57.589] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 57.4205 for pixel 26/74 mean/min/max = 44.9726/32.422/57.5233
[15:13:57.589] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 55.7791 for pixel 19/1 mean/min/max = 44.0281/32.0981/55.9581
[15:13:57.590] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 62.3108 for pixel 1/16 mean/min/max = 47.3989/32.0587/62.7392
[15:13:57.590] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 60.58 for pixel 19/18 mean/min/max = 47.6888/34.6281/60.7495
[15:13:57.590] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 60.6927 for pixel 5/30 mean/min/max = 46.8863/33.0485/60.724
[15:13:57.591] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:13:57.722] <TB3> INFO: Expecting 1029120 events.
[15:14:21.649] <TB3> INFO: 1029120 events read in total (23210ms).
[15:14:21.654] <TB3> INFO: Expecting 1029120 events.
[15:14:45.368] <TB3> INFO: 1029120 events read in total (23168ms).
[15:14:45.375] <TB3> INFO: Expecting 1029120 events.
[15:15:09.395] <TB3> INFO: 1029120 events read in total (23481ms).
[15:15:09.404] <TB3> INFO: Expecting 1029120 events.
[15:15:31.575] <TB3> INFO: 1029120 events read in total (21635ms).
[15:15:31.585] <TB3> INFO: Expecting 1029120 events.
[15:15:54.118] <TB3> INFO: 1029120 events read in total (21987ms).
[15:15:54.133] <TB3> INFO: Expecting 1029120 events.
[15:16:16.504] <TB3> INFO: 1029120 events read in total (21840ms).
[15:16:16.520] <TB3> INFO: Expecting 1029120 events.
[15:16:39.165] <TB3> INFO: 1029120 events read in total (22118ms).
[15:16:39.181] <TB3> INFO: Expecting 1029120 events.
[15:17:02.758] <TB3> INFO: 1029120 events read in total (23040ms).
[15:17:02.775] <TB3> INFO: Expecting 1029120 events.
[15:17:26.787] <TB3> INFO: 1029120 events read in total (23479ms).
[15:17:26.806] <TB3> INFO: Expecting 1029120 events.
[15:17:50.649] <TB3> INFO: 1029120 events read in total (23316ms).
[15:17:50.670] <TB3> INFO: Expecting 1029120 events.
[15:18:14.652] <TB3> INFO: 1029120 events read in total (23454ms).
[15:18:14.677] <TB3> INFO: Expecting 1029120 events.
[15:18:38.548] <TB3> INFO: 1029120 events read in total (23344ms).
[15:18:38.575] <TB3> INFO: Expecting 1029120 events.
[15:19:02.389] <TB3> INFO: 1029120 events read in total (23287ms).
[15:19:02.427] <TB3> INFO: Expecting 1029120 events.
[15:19:26.400] <TB3> INFO: 1029120 events read in total (23446ms).
[15:19:26.430] <TB3> INFO: Expecting 1029120 events.
[15:19:50.010] <TB3> INFO: 1029120 events read in total (23050ms).
[15:19:50.042] <TB3> INFO: Expecting 1029120 events.
[15:20:13.750] <TB3> INFO: 1029120 events read in total (23180ms).
[15:20:13.789] <TB3> INFO: Test took 376198ms.
[15:20:14.906] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 20 dacrange: 0 .. 199 (-1/-1) hits flags = 16 (plus default)
[15:20:14.915] <TB3> INFO: dacScan split into 1 runs with ntrig = 20
[15:20:14.915] <TB3> INFO: run 1 of 1
[15:20:15.250] <TB3> INFO: Expecting 16640000 events.
[15:20:40.390] <TB3> INFO: 727320 events read in total (24423ms).
[15:21:04.862] <TB3> INFO: 1451440 events read in total (48895ms).
[15:21:29.331] <TB3> INFO: 2175020 events read in total (73364ms).
[15:21:54.002] <TB3> INFO: 2898780 events read in total (98035ms).
[15:22:18.434] <TB3> INFO: 3622060 events read in total (122467ms).
[15:22:42.764] <TB3> INFO: 4345540 events read in total (146797ms).
[15:23:07.139] <TB3> INFO: 5068880 events read in total (171172ms).
[15:23:31.408] <TB3> INFO: 5792740 events read in total (195441ms).
[15:23:55.556] <TB3> INFO: 6515900 events read in total (219589ms).
[15:24:19.820] <TB3> INFO: 7238960 events read in total (243853ms).
[15:24:44.081] <TB3> INFO: 7962340 events read in total (268114ms).
[15:25:08.729] <TB3> INFO: 8682560 events read in total (292762ms).
[15:25:33.097] <TB3> INFO: 9400640 events read in total (317130ms).
[15:25:57.310] <TB3> INFO: 10117140 events read in total (341343ms).
[15:26:21.863] <TB3> INFO: 10832060 events read in total (365896ms).
[15:26:46.277] <TB3> INFO: 11547420 events read in total (390310ms).
[15:27:10.552] <TB3> INFO: 12261340 events read in total (414585ms).
[15:27:34.954] <TB3> INFO: 12974540 events read in total (438987ms).
[15:27:59.375] <TB3> INFO: 13687400 events read in total (463408ms).
[15:28:23.637] <TB3> INFO: 14399740 events read in total (487670ms).
[15:28:47.848] <TB3> INFO: 15113640 events read in total (511881ms).
[15:29:11.981] <TB3> INFO: 15826160 events read in total (536014ms).
[15:29:36.116] <TB3> INFO: 16540380 events read in total (560149ms).
[15:29:39.941] <TB3> INFO: 16640000 events read in total (563974ms).
[15:29:39.998] <TB3> INFO: Test took 565083ms.
[15:29:40.214] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:07.181] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.204320 .. 138.825145
[15:30:07.267] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 20 dacrange: 0 .. 148 (-1/-1) hits flags = 16 (plus default)
[15:30:07.276] <TB3> INFO: dacScan split into 1 runs with ntrig = 20
[15:30:07.276] <TB3> INFO: run 1 of 1
[15:30:07.595] <TB3> INFO: Expecting 12396800 events.
[15:30:32.809] <TB3> INFO: 750880 events read in total (24496ms).
[15:30:57.438] <TB3> INFO: 1503220 events read in total (49125ms).
[15:31:21.850] <TB3> INFO: 2255320 events read in total (73537ms).
[15:31:46.377] <TB3> INFO: 3008240 events read in total (98064ms).
[15:32:11.009] <TB3> INFO: 3759980 events read in total (122696ms).
[15:32:35.867] <TB3> INFO: 4512500 events read in total (147554ms).
[15:33:00.471] <TB3> INFO: 5264380 events read in total (172158ms).
[15:33:25.254] <TB3> INFO: 6016340 events read in total (196941ms).
[15:33:49.575] <TB3> INFO: 6769040 events read in total (221262ms).
[15:34:11.903] <TB3> INFO: 7520920 events read in total (243590ms).
[15:34:36.009] <TB3> INFO: 8271680 events read in total (267696ms).
[15:34:58.268] <TB3> INFO: 9021560 events read in total (289955ms).
[15:35:20.691] <TB3> INFO: 9770700 events read in total (312378ms).
[15:35:45.237] <TB3> INFO: 10519360 events read in total (336924ms).
[15:36:09.906] <TB3> INFO: 11268680 events read in total (361594ms).
[15:36:34.395] <TB3> INFO: 12017320 events read in total (386082ms).
[15:36:47.169] <TB3> INFO: 12396800 events read in total (398856ms).
[15:36:47.224] <TB3> INFO: Test took 399948ms.
[15:36:47.371] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:11.552] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 4.620287 .. 106.153948
[15:37:11.628] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 20 dacrange: 4 .. 116 (-1/-1) hits flags = 16 (plus default)
[15:37:11.637] <TB3> INFO: dacScan split into 1 runs with ntrig = 20
[15:37:11.637] <TB3> INFO: run 1 of 1
[15:37:11.952] <TB3> INFO: Expecting 9401600 events.
[15:37:35.384] <TB3> INFO: 769280 events read in total (22716ms).
[15:37:58.125] <TB3> INFO: 1538120 events read in total (45457ms).
[15:38:22.192] <TB3> INFO: 2307260 events read in total (69524ms).
[15:38:47.191] <TB3> INFO: 3076540 events read in total (94523ms).
[15:39:12.135] <TB3> INFO: 3845320 events read in total (119467ms).
[15:39:37.023] <TB3> INFO: 4614140 events read in total (144355ms).
[15:40:01.920] <TB3> INFO: 5382740 events read in total (169252ms).
[15:40:27.010] <TB3> INFO: 6151400 events read in total (194342ms).
[15:40:52.038] <TB3> INFO: 6919700 events read in total (219370ms).
[15:41:17.014] <TB3> INFO: 7687560 events read in total (244346ms).
[15:41:42.031] <TB3> INFO: 8455300 events read in total (269363ms).
[15:42:05.937] <TB3> INFO: 9223260 events read in total (293269ms).
[15:42:11.569] <TB3> INFO: 9401600 events read in total (298901ms).
[15:42:11.609] <TB3> INFO: Test took 299972ms.
[15:42:11.738] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:32.740] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 4.620287 .. 43.786958
[15:42:32.818] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 20 dacrange: 4 .. 53 (-1/-1) hits flags = 16 (plus default)
[15:42:32.827] <TB3> INFO: dacScan split into 1 runs with ntrig = 20
[15:42:32.828] <TB3> INFO: run 1 of 1
[15:42:33.134] <TB3> INFO: Expecting 4160000 events.
[15:43:00.497] <TB3> INFO: 962420 events read in total (26646ms).
[15:43:27.152] <TB3> INFO: 1924980 events read in total (53301ms).
[15:43:53.866] <TB3> INFO: 2886840 events read in total (80015ms).
[15:44:20.653] <TB3> INFO: 3846860 events read in total (106802ms).
[15:44:29.372] <TB3> INFO: 4160000 events read in total (115521ms).
[15:44:29.385] <TB3> INFO: Test took 116557ms.
[15:44:29.418] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:42.434] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 11.456220 .. 43.670460
[15:44:42.509] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 20 dacrange: 1 .. 53 (-1/-1) hits flags = 16 (plus default)
[15:44:42.518] <TB3> INFO: dacScan split into 1 runs with ntrig = 20
[15:44:42.518] <TB3> INFO: run 1 of 1
[15:44:42.821] <TB3> INFO: Expecting 4409600 events.
[15:45:10.417] <TB3> INFO: 977680 events read in total (26880ms).
[15:45:37.481] <TB3> INFO: 1955400 events read in total (53944ms).
[15:46:04.474] <TB3> INFO: 2933000 events read in total (80938ms).
[15:46:29.772] <TB3> INFO: 3910000 events read in total (106235ms).
[15:46:43.996] <TB3> INFO: 4409600 events read in total (120459ms).
[15:46:44.017] <TB3> INFO: Test took 121500ms.
[15:46:44.053] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:57.666] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:46:57.666] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 20 dacrange: 15 .. 55 (-1/-1) hits flags = 16 (plus default)
[15:46:57.675] <TB3> INFO: dacScan split into 1 runs with ntrig = 20
[15:46:57.675] <TB3> INFO: run 1 of 1
[15:46:57.990] <TB3> INFO: Expecting 3411200 events.
[15:47:24.602] <TB3> INFO: 878720 events read in total (25896ms).
[15:47:50.305] <TB3> INFO: 1757360 events read in total (51599ms).
[15:48:14.094] <TB3> INFO: 2635220 events read in total (75388ms).
[15:48:36.698] <TB3> INFO: 3411200 events read in total (97992ms).
[15:48:36.727] <TB3> INFO: Test took 99053ms.
[15:48:36.775] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:50.539] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:48:50.539] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:48:50.539] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:48:50.539] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:48:50.539] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:48:50.539] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:48:50.539] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:48:50.539] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:48:50.539] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:48:50.539] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:48:50.540] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:48:50.540] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:48:50.540] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:48:50.540] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:48:50.540] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:48:50.540] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:48:50.540] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C0.dat
[15:48:50.547] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C1.dat
[15:48:50.553] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C2.dat
[15:48:50.560] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C3.dat
[15:48:50.567] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C4.dat
[15:48:50.573] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C5.dat
[15:48:50.580] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C6.dat
[15:48:50.587] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C7.dat
[15:48:50.593] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C8.dat
[15:48:50.600] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C9.dat
[15:48:50.607] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C10.dat
[15:48:50.613] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C11.dat
[15:48:50.620] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C12.dat
[15:48:50.627] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C13.dat
[15:48:50.633] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C14.dat
[15:48:50.640] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//trimParameters35_C15.dat
[15:48:50.647] <TB3> INFO: PixTestTrim::trimTest() done
[15:48:50.647] <TB3> INFO: vtrim: 90 103 92 88 99 108 86 88 93 95 106 96 95 113 99 105
[15:48:50.647] <TB3> INFO: vthrcomp: 86 83 84 95 100 100 101 89 87 87 91 86 96 90 104 89
[15:48:50.647] <TB3> INFO: vcal mean: 34.91 34.96 35.00 34.99 34.96 34.99 35.04 34.97 34.95 34.99 34.97 34.98 34.99 34.96 34.98 34.90
[15:48:50.647] <TB3> INFO: vcal RMS: 0.70 0.72 0.67 0.87 0.74 0.77 0.77 0.63 0.71 0.71 0.76 0.67 0.70 0.75 0.73 0.89
[15:48:50.647] <TB3> INFO: bits mean: 9.62 9.26 9.30 8.88 8.80 9.38 8.17 8.21 9.58 8.95 9.62 9.28 9.77 9.09 8.49 8.63
[15:48:50.647] <TB3> INFO: bits RMS: 2.65 2.79 2.83 2.97 2.83 2.75 3.09 2.90 2.77 3.06 2.66 2.76 2.69 2.78 2.64 2.86
[15:48:50.654] <TB3> INFO: ----------------------------------------------------------------------
[15:48:50.654] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[15:48:50.654] <TB3> INFO: ----------------------------------------------------------------------
[15:48:50.657] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 16 (plus default)
[15:48:50.666] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:48:50.666] <TB3> INFO: run 1 of 1
[15:48:50.976] <TB3> INFO: Expecting 8320000 events.
[15:49:21.316] <TB3> INFO: 936020 events read in total (29622ms).
[15:49:50.707] <TB3> INFO: 1863240 events read in total (59013ms).
[15:50:19.352] <TB3> INFO: 2788260 events read in total (87658ms).
[15:50:48.938] <TB3> INFO: 3710470 events read in total (117244ms).
[15:51:15.989] <TB3> INFO: 4626040 events read in total (144295ms).
[15:51:45.393] <TB3> INFO: 5538360 events read in total (173699ms).
[15:52:14.674] <TB3> INFO: 6449920 events read in total (202980ms).
[15:52:43.785] <TB3> INFO: 7361530 events read in total (232091ms).
[15:53:13.207] <TB3> INFO: 8278780 events read in total (261513ms).
[15:53:14.921] <TB3> INFO: 8320000 events read in total (263227ms).
[15:53:14.954] <TB3> INFO: Test took 264288ms.
[15:53:15.061] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:41.596] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 174 (-1/-1) hits flags = 16 (plus default)
[15:53:41.605] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:53:41.605] <TB3> INFO: run 1 of 1
[15:53:41.920] <TB3> INFO: Expecting 7280000 events.
[15:54:10.910] <TB3> INFO: 956130 events read in total (28273ms).
[15:54:39.557] <TB3> INFO: 1901980 events read in total (56920ms).
[15:55:08.028] <TB3> INFO: 2845130 events read in total (85391ms).
[15:55:37.641] <TB3> INFO: 3782040 events read in total (115004ms).
[15:56:06.620] <TB3> INFO: 4710580 events read in total (143983ms).
[15:56:36.142] <TB3> INFO: 5638250 events read in total (173505ms).
[15:57:04.373] <TB3> INFO: 6565850 events read in total (201736ms).
[15:57:27.310] <TB3> INFO: 7280000 events read in total (224673ms).
[15:57:27.345] <TB3> INFO: Test took 225740ms.
[15:57:27.432] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:51.924] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 161 (-1/-1) hits flags = 16 (plus default)
[15:57:51.933] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:57:51.933] <TB3> INFO: run 1 of 1
[15:57:52.238] <TB3> INFO: Expecting 6739200 events.
[15:58:23.282] <TB3> INFO: 989960 events read in total (30328ms).
[15:58:51.136] <TB3> INFO: 1969580 events read in total (58182ms).
[15:59:20.440] <TB3> INFO: 2943720 events read in total (87487ms).
[15:59:50.537] <TB3> INFO: 3908420 events read in total (117583ms).
[16:00:20.557] <TB3> INFO: 4866540 events read in total (147603ms).
[16:00:50.477] <TB3> INFO: 5824460 events read in total (177523ms).
[16:01:19.003] <TB3> INFO: 6739200 events read in total (206049ms).
[16:01:19.033] <TB3> INFO: Test took 207100ms.
[16:01:19.109] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:01:41.584] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 159 (-1/-1) hits flags = 16 (plus default)
[16:01:41.592] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:01:41.592] <TB3> INFO: run 1 of 1
[16:01:41.895] <TB3> INFO: Expecting 6656000 events.
[16:02:11.532] <TB3> INFO: 995780 events read in total (28919ms).
[16:02:40.561] <TB3> INFO: 1980520 events read in total (57949ms).
[16:03:09.936] <TB3> INFO: 2960380 events read in total (87323ms).
[16:03:39.514] <TB3> INFO: 3929010 events read in total (116901ms).
[16:04:08.044] <TB3> INFO: 4892640 events read in total (145431ms).
[16:04:38.103] <TB3> INFO: 5855680 events read in total (175490ms).
[16:05:03.122] <TB3> INFO: 6656000 events read in total (200509ms).
[16:05:03.151] <TB3> INFO: Test took 201559ms.
[16:05:03.225] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:27.118] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 161 (-1/-1) hits flags = 16 (plus default)
[16:05:27.127] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:05:27.127] <TB3> INFO: run 1 of 1
[16:05:27.434] <TB3> INFO: Expecting 6739200 events.
[16:05:56.167] <TB3> INFO: 989480 events read in total (28017ms).
[16:06:26.769] <TB3> INFO: 1968330 events read in total (58619ms).
[16:06:56.662] <TB3> INFO: 2941740 events read in total (88513ms).
[16:07:24.633] <TB3> INFO: 3905300 events read in total (116483ms).
[16:07:52.369] <TB3> INFO: 4862970 events read in total (144219ms).
[16:08:19.790] <TB3> INFO: 5820130 events read in total (171640ms).
[16:08:47.016] <TB3> INFO: 6739200 events read in total (198866ms).
[16:08:47.048] <TB3> INFO: Test took 199921ms.
[16:08:47.120] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:09.548] <TB3> INFO: PixTestTrim::trimBitTest() done
[16:09:09.549] <TB3> INFO: PixTestTrim::doTest() done, duration: 4113 seconds
[16:09:10.227] <TB3> INFO: ######################################################################
[16:09:10.227] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:09:10.227] <TB3> INFO: ######################################################################
[16:09:10.530] <TB3> INFO: Expecting 41600 events.
[16:09:14.772] <TB3> INFO: 41600 events read in total (3524ms).
[16:09:14.772] <TB3> INFO: Test took 4544ms.
[16:09:14.779] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:15.348] <TB3> INFO: Expecting 41600 events.
[16:09:19.634] <TB3> INFO: 41600 events read in total (3570ms).
[16:09:19.635] <TB3> INFO: Test took 4591ms.
[16:09:19.641] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:19.995] <TB3> INFO: Expecting 41600 events.
[16:09:24.500] <TB3> INFO: 41600 events read in total (3790ms).
[16:09:24.501] <TB3> INFO: Test took 4830ms.
[16:09:24.507] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:24.858] <TB3> INFO: Expecting 41600 events.
[16:09:29.343] <TB3> INFO: 41600 events read in total (3769ms).
[16:09:29.344] <TB3> INFO: Test took 4812ms.
[16:09:29.350] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:29.694] <TB3> INFO: Expecting 41600 events.
[16:09:34.143] <TB3> INFO: 41600 events read in total (3733ms).
[16:09:34.144] <TB3> INFO: Test took 4775ms.
[16:09:34.151] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:34.490] <TB3> INFO: Expecting 41600 events.
[16:09:38.986] <TB3> INFO: 41600 events read in total (3780ms).
[16:09:38.986] <TB3> INFO: Test took 4821ms.
[16:09:38.992] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:38.999] <TB3> INFO: The DUT currently contains the following objects:
[16:09:38.999] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:38.999] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:38.999] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:38.999] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:38.999] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:38.999] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:38.000] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:39.000] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:39.000] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:39.000] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:39.000] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:39.000] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:39.000] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:39.000] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:39.000] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:39.000] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:39.000] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:39.000] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:39.000] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:39.000] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:39.341] <TB3> INFO: Expecting 2560 events.
[16:09:40.408] <TB3> INFO: 2560 events read in total (350ms).
[16:09:40.408] <TB3> INFO: Test took 1408ms.
[16:09:40.409] <TB3> INFO: The DUT currently contains the following objects:
[16:09:40.409] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:40.409] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:40.409] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:40.409] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:40.409] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.409] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.409] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.409] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.409] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.409] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.409] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.409] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.409] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.409] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.410] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.410] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.410] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.410] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.410] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.410] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:40.823] <TB3> INFO: Expecting 2560 events.
[16:09:41.892] <TB3> INFO: 2560 events read in total (353ms).
[16:09:41.892] <TB3> INFO: Test took 1482ms.
[16:09:41.892] <TB3> INFO: The DUT currently contains the following objects:
[16:09:41.892] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:41.892] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:41.892] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:41.892] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:41.892] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.892] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.892] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.892] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.893] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.893] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.893] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.893] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.893] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.893] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.893] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.893] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.893] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.893] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.893] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:41.893] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:42.307] <TB3> INFO: Expecting 2560 events.
[16:09:43.375] <TB3> INFO: 2560 events read in total (353ms).
[16:09:43.376] <TB3> INFO: Test took 1483ms.
[16:09:43.376] <TB3> INFO: The DUT currently contains the following objects:
[16:09:43.376] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:43.376] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:43.376] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:43.376] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:43.376] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.376] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.376] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.376] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.377] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.377] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.377] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.377] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.377] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.377] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.377] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.377] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.377] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.377] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.377] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.377] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:43.790] <TB3> INFO: Expecting 2560 events.
[16:09:44.861] <TB3> INFO: 2560 events read in total (354ms).
[16:09:44.861] <TB3> INFO: Test took 1484ms.
[16:09:44.861] <TB3> INFO: The DUT currently contains the following objects:
[16:09:44.861] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:44.861] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:44.861] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:44.861] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:44.862] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:44.862] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:45.276] <TB3> INFO: Expecting 2560 events.
[16:09:46.344] <TB3> INFO: 2560 events read in total (352ms).
[16:09:46.344] <TB3> INFO: Test took 1482ms.
[16:09:46.344] <TB3> INFO: The DUT currently contains the following objects:
[16:09:46.344] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:46.345] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:46.345] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:46.345] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:46.345] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.345] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:46.758] <TB3> INFO: Expecting 2560 events.
[16:09:47.827] <TB3> INFO: 2560 events read in total (352ms).
[16:09:47.827] <TB3> INFO: Test took 1482ms.
[16:09:47.828] <TB3> INFO: The DUT currently contains the following objects:
[16:09:47.828] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:47.828] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:47.828] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:47.828] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:47.828] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:47.828] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:48.242] <TB3> INFO: Expecting 2560 events.
[16:09:49.310] <TB3> INFO: 2560 events read in total (352ms).
[16:09:49.310] <TB3> INFO: Test took 1482ms.
[16:09:49.311] <TB3> INFO: The DUT currently contains the following objects:
[16:09:49.311] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:49.311] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:49.311] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:49.311] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:49.311] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.311] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.312] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.312] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.312] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.312] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.312] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.312] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.312] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.312] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.312] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.312] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.312] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.312] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.312] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.312] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:49.725] <TB3> INFO: Expecting 2560 events.
[16:09:50.796] <TB3> INFO: 2560 events read in total (354ms).
[16:09:50.797] <TB3> INFO: Test took 1485ms.
[16:09:50.797] <TB3> INFO: The DUT currently contains the following objects:
[16:09:50.797] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:50.797] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:50.797] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:50.797] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:50.797] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.797] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.797] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.798] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.798] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.798] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.798] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.798] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.798] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.798] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.798] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.798] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.798] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.798] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.798] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:50.798] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:51.212] <TB3> INFO: Expecting 2560 events.
[16:09:52.295] <TB3> INFO: 2560 events read in total (367ms).
[16:09:52.296] <TB3> INFO: Test took 1498ms.
[16:09:52.296] <TB3> INFO: The DUT currently contains the following objects:
[16:09:52.296] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:52.296] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:52.296] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:52.296] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:52.296] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.296] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.297] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.297] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.297] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.297] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.297] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.297] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.297] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.297] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.297] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.297] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.297] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.297] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.297] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.297] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:52.711] <TB3> INFO: Expecting 2560 events.
[16:09:53.782] <TB3> INFO: 2560 events read in total (355ms).
[16:09:53.782] <TB3> INFO: Test took 1485ms.
[16:09:53.782] <TB3> INFO: The DUT currently contains the following objects:
[16:09:53.783] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:53.783] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:53.783] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:53.783] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:53.783] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:53.783] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:54.197] <TB3> INFO: Expecting 2560 events.
[16:09:55.267] <TB3> INFO: 2560 events read in total (354ms).
[16:09:55.267] <TB3> INFO: Test took 1484ms.
[16:09:55.268] <TB3> INFO: The DUT currently contains the following objects:
[16:09:55.268] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:55.268] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:55.268] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:55.268] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:55.268] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.268] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:55.682] <TB3> INFO: Expecting 2560 events.
[16:09:56.752] <TB3> INFO: 2560 events read in total (354ms).
[16:09:56.752] <TB3> INFO: Test took 1484ms.
[16:09:56.752] <TB3> INFO: The DUT currently contains the following objects:
[16:09:56.752] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:56.752] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:56.753] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:56.753] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:56.753] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:56.753] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:57.167] <TB3> INFO: Expecting 2560 events.
[16:09:58.236] <TB3> INFO: 2560 events read in total (353ms).
[16:09:58.236] <TB3> INFO: Test took 1483ms.
[16:09:58.237] <TB3> INFO: The DUT currently contains the following objects:
[16:09:58.237] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:58.237] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:58.237] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:58.237] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:58.237] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.237] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.237] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.237] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.237] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.237] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.237] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.237] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.237] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.237] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.237] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.238] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.238] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.238] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.238] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.238] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:58.651] <TB3> INFO: Expecting 2560 events.
[16:09:59.721] <TB3> INFO: 2560 events read in total (354ms).
[16:09:59.721] <TB3> INFO: Test took 1483ms.
[16:09:59.722] <TB3> INFO: The DUT currently contains the following objects:
[16:09:59.722] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:09:59.722] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:09:59.722] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:09:59.722] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:09:59.722] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:09:59.722] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:00.136] <TB3> INFO: Expecting 2560 events.
[16:10:01.207] <TB3> INFO: 2560 events read in total (355ms).
[16:10:01.207] <TB3> INFO: Test took 1485ms.
[16:10:01.208] <TB3> INFO: The DUT currently contains the following objects:
[16:10:01.208] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:10:01.208] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:10:01.208] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:10:01.208] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:10:01.208] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.208] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.208] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.208] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.208] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.209] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.209] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.209] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.209] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.209] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.209] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.209] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.209] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.209] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.209] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.209] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:10:01.622] <TB3> INFO: Expecting 2560 events.
[16:10:02.690] <TB3> INFO: 2560 events read in total (352ms).
[16:10:02.690] <TB3> INFO: Test took 1481ms.
[16:10:02.693] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:03.107] <TB3> INFO: Expecting 655360 events.
[16:10:19.784] <TB3> INFO: 655360 events read in total (15961ms).
[16:10:19.794] <TB3> INFO: Expecting 655360 events.
[16:10:36.224] <TB3> INFO: 655360 events read in total (15902ms).
[16:10:36.237] <TB3> INFO: Expecting 655360 events.
[16:10:52.626] <TB3> INFO: 655360 events read in total (15862ms).
[16:10:52.642] <TB3> INFO: Expecting 655360 events.
[16:11:09.234] <TB3> INFO: 655360 events read in total (16064ms).
[16:11:09.261] <TB3> INFO: Expecting 655360 events.
[16:11:24.449] <TB3> INFO: 655360 events read in total (14660ms).
[16:11:24.472] <TB3> INFO: Expecting 655360 events.
[16:11:40.604] <TB3> INFO: 655360 events read in total (15605ms).
[16:11:40.630] <TB3> INFO: Expecting 655360 events.
[16:11:56.994] <TB3> INFO: 655360 events read in total (15837ms).
[16:11:57.025] <TB3> INFO: Expecting 655360 events.
[16:12:13.601] <TB3> INFO: 655360 events read in total (16048ms).
[16:12:13.637] <TB3> INFO: Expecting 655360 events.
[16:12:29.380] <TB3> INFO: 655360 events read in total (15215ms).
[16:12:29.418] <TB3> INFO: Expecting 655360 events.
[16:12:44.433] <TB3> INFO: 655360 events read in total (14487ms).
[16:12:44.479] <TB3> INFO: Expecting 655360 events.
[16:13:00.983] <TB3> INFO: 655360 events read in total (15976ms).
[16:13:01.028] <TB3> INFO: Expecting 655360 events.
[16:13:17.580] <TB3> INFO: 655360 events read in total (16024ms).
[16:13:17.630] <TB3> INFO: Expecting 655360 events.
[16:13:34.290] <TB3> INFO: 655360 events read in total (16132ms).
[16:13:34.355] <TB3> INFO: Expecting 655360 events.
[16:13:50.954] <TB3> INFO: 655360 events read in total (16071ms).
[16:13:51.012] <TB3> INFO: Expecting 655360 events.
[16:14:07.486] <TB3> INFO: 655360 events read in total (15946ms).
[16:14:07.554] <TB3> INFO: Expecting 655360 events.
[16:14:24.070] <TB3> INFO: 655360 events read in total (15989ms).
[16:14:24.136] <TB3> INFO: Test took 261443ms.
[16:14:24.220] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:14:24.528] <TB3> INFO: Expecting 655360 events.
[16:14:41.178] <TB3> INFO: 655360 events read in total (15934ms).
[16:14:41.188] <TB3> INFO: Expecting 655360 events.
[16:14:57.830] <TB3> INFO: 655360 events read in total (16115ms).
[16:14:57.845] <TB3> INFO: Expecting 655360 events.
[16:15:14.033] <TB3> INFO: 655360 events read in total (15661ms).
[16:15:14.051] <TB3> INFO: Expecting 655360 events.
[16:15:29.088] <TB3> INFO: 655360 events read in total (14510ms).
[16:15:29.108] <TB3> INFO: Expecting 655360 events.
[16:15:45.376] <TB3> INFO: 655360 events read in total (15741ms).
[16:15:45.400] <TB3> INFO: Expecting 655360 events.
[16:16:01.820] <TB3> INFO: 655360 events read in total (15893ms).
[16:16:01.848] <TB3> INFO: Expecting 655360 events.
[16:16:18.256] <TB3> INFO: 655360 events read in total (15881ms).
[16:16:18.289] <TB3> INFO: Expecting 655360 events.
[16:16:34.612] <TB3> INFO: 655360 events read in total (15796ms).
[16:16:34.648] <TB3> INFO: Expecting 655360 events.
[16:16:49.872] <TB3> INFO: 655360 events read in total (14696ms).
[16:16:49.925] <TB3> INFO: Expecting 655360 events.
[16:17:05.645] <TB3> INFO: 655360 events read in total (15193ms).
[16:17:05.687] <TB3> INFO: Expecting 655360 events.
[16:17:22.308] <TB3> INFO: 655360 events read in total (16093ms).
[16:17:22.355] <TB3> INFO: Expecting 655360 events.
[16:17:39.077] <TB3> INFO: 655360 events read in total (16195ms).
[16:17:39.126] <TB3> INFO: Expecting 655360 events.
[16:17:55.498] <TB3> INFO: 655360 events read in total (15844ms).
[16:17:55.551] <TB3> INFO: Expecting 655360 events.
[16:18:12.149] <TB3> INFO: 655360 events read in total (16070ms).
[16:18:12.213] <TB3> INFO: Expecting 655360 events.
[16:18:28.623] <TB3> INFO: 655360 events read in total (15882ms).
[16:18:28.684] <TB3> INFO: Expecting 655360 events.
[16:18:45.107] <TB3> INFO: 655360 events read in total (15896ms).
[16:18:45.173] <TB3> INFO: Test took 260953ms.
[16:18:45.374] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.381] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:18:45.389] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:18:45.397] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.404] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.412] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.421] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.429] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.438] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.446] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.455] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:18:45.462] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:18:45.471] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.479] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.488] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:18:45.496] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:18:45.505] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[16:18:45.514] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[16:18:45.522] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[16:18:45.531] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[16:18:45.539] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[16:18:45.547] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[16:18:45.556] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[16:18:45.564] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.572] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.581] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.589] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.597] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.605] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:45.652] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:18:45.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:18:45.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:18:45.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:18:45.654] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:18:45.654] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:18:45.654] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:18:45.654] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:18:45.654] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:18:45.655] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:18:45.655] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:18:45.655] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:18:45.655] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:18:45.655] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:18:45.655] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:18:45.655] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:18:45.963] <TB3> INFO: Expecting 41600 events.
[16:18:50.436] <TB3> INFO: 41600 events read in total (3756ms).
[16:18:50.437] <TB3> INFO: Test took 4778ms.
[16:18:50.994] <TB3> INFO: Expecting 41600 events.
[16:18:55.432] <TB3> INFO: 41600 events read in total (3721ms).
[16:18:55.433] <TB3> INFO: Test took 4758ms.
[16:18:55.983] <TB3> INFO: Expecting 41600 events.
[16:19:00.456] <TB3> INFO: 41600 events read in total (3756ms).
[16:19:00.456] <TB3> INFO: Test took 4799ms.
[16:19:00.686] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:00.819] <TB3> INFO: Expecting 2560 events.
[16:19:01.887] <TB3> INFO: 2560 events read in total (352ms).
[16:19:01.887] <TB3> INFO: Test took 1201ms.
[16:19:01.890] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:02.303] <TB3> INFO: Expecting 2560 events.
[16:19:03.374] <TB3> INFO: 2560 events read in total (355ms).
[16:19:03.374] <TB3> INFO: Test took 1484ms.
[16:19:03.380] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:03.789] <TB3> INFO: Expecting 2560 events.
[16:19:04.858] <TB3> INFO: 2560 events read in total (352ms).
[16:19:04.858] <TB3> INFO: Test took 1478ms.
[16:19:04.861] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:05.274] <TB3> INFO: Expecting 2560 events.
[16:19:06.342] <TB3> INFO: 2560 events read in total (351ms).
[16:19:06.342] <TB3> INFO: Test took 1481ms.
[16:19:06.344] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:06.758] <TB3> INFO: Expecting 2560 events.
[16:19:07.830] <TB3> INFO: 2560 events read in total (356ms).
[16:19:07.830] <TB3> INFO: Test took 1486ms.
[16:19:07.833] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:08.246] <TB3> INFO: Expecting 2560 events.
[16:19:09.316] <TB3> INFO: 2560 events read in total (354ms).
[16:19:09.317] <TB3> INFO: Test took 1484ms.
[16:19:09.319] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:09.732] <TB3> INFO: Expecting 2560 events.
[16:19:10.803] <TB3> INFO: 2560 events read in total (354ms).
[16:19:10.803] <TB3> INFO: Test took 1484ms.
[16:19:10.806] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:11.219] <TB3> INFO: Expecting 2560 events.
[16:19:12.289] <TB3> INFO: 2560 events read in total (353ms).
[16:19:12.289] <TB3> INFO: Test took 1483ms.
[16:19:12.292] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:12.705] <TB3> INFO: Expecting 2560 events.
[16:19:13.775] <TB3> INFO: 2560 events read in total (354ms).
[16:19:13.775] <TB3> INFO: Test took 1483ms.
[16:19:13.778] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:14.191] <TB3> INFO: Expecting 2560 events.
[16:19:15.262] <TB3> INFO: 2560 events read in total (355ms).
[16:19:15.262] <TB3> INFO: Test took 1485ms.
[16:19:15.265] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:15.678] <TB3> INFO: Expecting 2560 events.
[16:19:16.752] <TB3> INFO: 2560 events read in total (358ms).
[16:19:16.752] <TB3> INFO: Test took 1487ms.
[16:19:16.755] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:17.168] <TB3> INFO: Expecting 2560 events.
[16:19:18.240] <TB3> INFO: 2560 events read in total (356ms).
[16:19:18.240] <TB3> INFO: Test took 1486ms.
[16:19:18.243] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:18.656] <TB3> INFO: Expecting 2560 events.
[16:19:19.719] <TB3> INFO: 2560 events read in total (347ms).
[16:19:19.719] <TB3> INFO: Test took 1476ms.
[16:19:19.722] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:20.134] <TB3> INFO: Expecting 2560 events.
[16:19:21.196] <TB3> INFO: 2560 events read in total (346ms).
[16:19:21.196] <TB3> INFO: Test took 1474ms.
[16:19:21.198] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:21.612] <TB3> INFO: Expecting 2560 events.
[16:19:22.673] <TB3> INFO: 2560 events read in total (345ms).
[16:19:22.673] <TB3> INFO: Test took 1475ms.
[16:19:22.675] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:23.089] <TB3> INFO: Expecting 2560 events.
[16:19:24.150] <TB3> INFO: 2560 events read in total (345ms).
[16:19:24.151] <TB3> INFO: Test took 1476ms.
[16:19:24.152] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:24.566] <TB3> INFO: Expecting 2560 events.
[16:19:25.628] <TB3> INFO: 2560 events read in total (346ms).
[16:19:25.628] <TB3> INFO: Test took 1476ms.
[16:19:25.630] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:26.043] <TB3> INFO: Expecting 2560 events.
[16:19:27.105] <TB3> INFO: 2560 events read in total (345ms).
[16:19:27.105] <TB3> INFO: Test took 1475ms.
[16:19:27.107] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:27.520] <TB3> INFO: Expecting 2560 events.
[16:19:28.584] <TB3> INFO: 2560 events read in total (347ms).
[16:19:28.584] <TB3> INFO: Test took 1477ms.
[16:19:28.586] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:28.999] <TB3> INFO: Expecting 2560 events.
[16:19:30.062] <TB3> INFO: 2560 events read in total (347ms).
[16:19:30.063] <TB3> INFO: Test took 1477ms.
[16:19:30.065] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:30.478] <TB3> INFO: Expecting 2560 events.
[16:19:31.540] <TB3> INFO: 2560 events read in total (346ms).
[16:19:31.541] <TB3> INFO: Test took 1476ms.
[16:19:31.543] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:31.956] <TB3> INFO: Expecting 2560 events.
[16:19:33.017] <TB3> INFO: 2560 events read in total (345ms).
[16:19:33.017] <TB3> INFO: Test took 1474ms.
[16:19:33.019] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:33.433] <TB3> INFO: Expecting 2560 events.
[16:19:34.496] <TB3> INFO: 2560 events read in total (347ms).
[16:19:34.496] <TB3> INFO: Test took 1477ms.
[16:19:34.500] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:34.912] <TB3> INFO: Expecting 2560 events.
[16:19:35.975] <TB3> INFO: 2560 events read in total (347ms).
[16:19:35.975] <TB3> INFO: Test took 1476ms.
[16:19:35.978] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:36.390] <TB3> INFO: Expecting 2560 events.
[16:19:37.454] <TB3> INFO: 2560 events read in total (347ms).
[16:19:37.454] <TB3> INFO: Test took 1476ms.
[16:19:37.457] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:37.870] <TB3> INFO: Expecting 2560 events.
[16:19:38.934] <TB3> INFO: 2560 events read in total (348ms).
[16:19:38.935] <TB3> INFO: Test took 1479ms.
[16:19:38.936] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:39.350] <TB3> INFO: Expecting 2560 events.
[16:19:40.414] <TB3> INFO: 2560 events read in total (348ms).
[16:19:40.414] <TB3> INFO: Test took 1478ms.
[16:19:40.416] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:40.830] <TB3> INFO: Expecting 2560 events.
[16:19:41.892] <TB3> INFO: 2560 events read in total (346ms).
[16:19:41.892] <TB3> INFO: Test took 1476ms.
[16:19:41.894] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:42.308] <TB3> INFO: Expecting 2560 events.
[16:19:43.369] <TB3> INFO: 2560 events read in total (345ms).
[16:19:43.369] <TB3> INFO: Test took 1475ms.
[16:19:43.371] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:43.785] <TB3> INFO: Expecting 2560 events.
[16:19:44.854] <TB3> INFO: 2560 events read in total (353ms).
[16:19:44.854] <TB3> INFO: Test took 1483ms.
[16:19:44.859] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:45.270] <TB3> INFO: Expecting 2560 events.
[16:19:46.341] <TB3> INFO: 2560 events read in total (354ms).
[16:19:46.341] <TB3> INFO: Test took 1483ms.
[16:19:46.344] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:46.757] <TB3> INFO: Expecting 2560 events.
[16:19:47.829] <TB3> INFO: 2560 events read in total (355ms).
[16:19:47.829] <TB3> INFO: Test took 1485ms.
[16:19:48.456] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 638 seconds
[16:19:48.456] <TB3> INFO: PH scale (per ROC): 71 80 76 86 71 77 72 75 69 80 75 82 79 67 67 74
[16:19:48.456] <TB3> INFO: PH offset (per ROC): 176 176 174 175 174 190 193 176 173 176 162 174 172 172 178 174
[16:19:48.631] <TB3> INFO: ######################################################################
[16:19:48.631] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:19:48.631] <TB3> INFO: ######################################################################
[16:19:48.641] <TB3> INFO: scanning low vcal = 10
[16:19:48.950] <TB3> INFO: Expecting 41600 events.
[16:19:52.537] <TB3> INFO: 41600 events read in total (2871ms).
[16:19:52.537] <TB3> INFO: Test took 3896ms.
[16:19:52.539] <TB3> INFO: scanning low vcal = 20
[16:19:52.952] <TB3> INFO: Expecting 41600 events.
[16:19:56.544] <TB3> INFO: 41600 events read in total (2876ms).
[16:19:56.545] <TB3> INFO: Test took 4006ms.
[16:19:56.548] <TB3> INFO: scanning low vcal = 30
[16:19:56.959] <TB3> INFO: Expecting 41600 events.
[16:20:00.609] <TB3> INFO: 41600 events read in total (2933ms).
[16:20:00.609] <TB3> INFO: Test took 4061ms.
[16:20:00.611] <TB3> INFO: scanning low vcal = 40
[16:20:01.017] <TB3> INFO: Expecting 41600 events.
[16:20:05.212] <TB3> INFO: 41600 events read in total (3479ms).
[16:20:05.213] <TB3> INFO: Test took 4602ms.
[16:20:05.216] <TB3> INFO: scanning low vcal = 50
[16:20:05.564] <TB3> INFO: Expecting 41600 events.
[16:20:09.762] <TB3> INFO: 41600 events read in total (3482ms).
[16:20:09.763] <TB3> INFO: Test took 4547ms.
[16:20:09.766] <TB3> INFO: scanning low vcal = 60
[16:20:10.118] <TB3> INFO: Expecting 41600 events.
[16:20:14.306] <TB3> INFO: 41600 events read in total (3472ms).
[16:20:14.307] <TB3> INFO: Test took 4541ms.
[16:20:14.310] <TB3> INFO: scanning low vcal = 70
[16:20:14.653] <TB3> INFO: Expecting 41600 events.
[16:20:18.873] <TB3> INFO: 41600 events read in total (3504ms).
[16:20:18.873] <TB3> INFO: Test took 4563ms.
[16:20:18.876] <TB3> INFO: scanning low vcal = 80
[16:20:19.210] <TB3> INFO: Expecting 41600 events.
[16:20:23.412] <TB3> INFO: 41600 events read in total (3486ms).
[16:20:23.412] <TB3> INFO: Test took 4536ms.
[16:20:23.415] <TB3> INFO: scanning low vcal = 90
[16:20:23.769] <TB3> INFO: Expecting 41600 events.
[16:20:28.077] <TB3> INFO: 41600 events read in total (3592ms).
[16:20:28.077] <TB3> INFO: Test took 4662ms.
[16:20:28.080] <TB3> INFO: scanning low vcal = 100
[16:20:28.435] <TB3> INFO: Expecting 41600 events.
[16:20:32.637] <TB3> INFO: 41600 events read in total (3486ms).
[16:20:32.637] <TB3> INFO: Test took 4556ms.
[16:20:32.640] <TB3> INFO: scanning low vcal = 110
[16:20:32.991] <TB3> INFO: Expecting 41600 events.
[16:20:37.210] <TB3> INFO: 41600 events read in total (3503ms).
[16:20:37.210] <TB3> INFO: Test took 4570ms.
[16:20:37.213] <TB3> INFO: scanning low vcal = 120
[16:20:37.564] <TB3> INFO: Expecting 41600 events.
[16:20:41.760] <TB3> INFO: 41600 events read in total (3479ms).
[16:20:41.760] <TB3> INFO: Test took 4547ms.
[16:20:41.763] <TB3> INFO: scanning low vcal = 130
[16:20:42.109] <TB3> INFO: Expecting 41600 events.
[16:20:46.293] <TB3> INFO: 41600 events read in total (3468ms).
[16:20:46.293] <TB3> INFO: Test took 4530ms.
[16:20:46.296] <TB3> INFO: scanning low vcal = 140
[16:20:46.645] <TB3> INFO: Expecting 41600 events.
[16:20:50.714] <TB3> INFO: 41600 events read in total (3352ms).
[16:20:50.715] <TB3> INFO: Test took 4419ms.
[16:20:50.717] <TB3> INFO: scanning low vcal = 150
[16:20:51.071] <TB3> INFO: Expecting 41600 events.
[16:20:55.149] <TB3> INFO: 41600 events read in total (3361ms).
[16:20:55.150] <TB3> INFO: Test took 4433ms.
[16:20:55.152] <TB3> INFO: scanning low vcal = 160
[16:20:55.510] <TB3> INFO: Expecting 41600 events.
[16:20:59.566] <TB3> INFO: 41600 events read in total (3340ms).
[16:20:59.566] <TB3> INFO: Test took 4414ms.
[16:20:59.569] <TB3> INFO: scanning low vcal = 170
[16:20:59.924] <TB3> INFO: Expecting 41600 events.
[16:21:04.003] <TB3> INFO: 41600 events read in total (3363ms).
[16:21:04.003] <TB3> INFO: Test took 4434ms.
[16:21:04.007] <TB3> INFO: scanning low vcal = 180
[16:21:04.360] <TB3> INFO: Expecting 41600 events.
[16:21:08.414] <TB3> INFO: 41600 events read in total (3338ms).
[16:21:08.414] <TB3> INFO: Test took 4407ms.
[16:21:08.417] <TB3> INFO: scanning low vcal = 190
[16:21:08.774] <TB3> INFO: Expecting 41600 events.
[16:21:12.847] <TB3> INFO: 41600 events read in total (3357ms).
[16:21:12.848] <TB3> INFO: Test took 4431ms.
[16:21:12.851] <TB3> INFO: scanning low vcal = 200
[16:21:13.231] <TB3> INFO: Expecting 41600 events.
[16:21:17.420] <TB3> INFO: 41600 events read in total (3472ms).
[16:21:17.421] <TB3> INFO: Test took 4570ms.
[16:21:17.424] <TB3> INFO: scanning low vcal = 210
[16:21:17.778] <TB3> INFO: Expecting 41600 events.
[16:21:21.928] <TB3> INFO: 41600 events read in total (3434ms).
[16:21:21.928] <TB3> INFO: Test took 4504ms.
[16:21:21.931] <TB3> INFO: scanning low vcal = 220
[16:21:22.284] <TB3> INFO: Expecting 41600 events.
[16:21:26.479] <TB3> INFO: 41600 events read in total (3479ms).
[16:21:26.479] <TB3> INFO: Test took 4548ms.
[16:21:26.485] <TB3> INFO: scanning low vcal = 230
[16:21:26.832] <TB3> INFO: Expecting 41600 events.
[16:21:31.027] <TB3> INFO: 41600 events read in total (3479ms).
[16:21:31.027] <TB3> INFO: Test took 4542ms.
[16:21:31.030] <TB3> INFO: scanning low vcal = 240
[16:21:31.383] <TB3> INFO: Expecting 41600 events.
[16:21:35.579] <TB3> INFO: 41600 events read in total (3480ms).
[16:21:35.580] <TB3> INFO: Test took 4550ms.
[16:21:35.583] <TB3> INFO: scanning low vcal = 250
[16:21:35.934] <TB3> INFO: Expecting 41600 events.
[16:21:40.162] <TB3> INFO: 41600 events read in total (3512ms).
[16:21:40.163] <TB3> INFO: Test took 4580ms.
[16:21:40.167] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[16:21:40.516] <TB3> INFO: Expecting 41600 events.
[16:21:44.762] <TB3> INFO: 41600 events read in total (3529ms).
[16:21:44.763] <TB3> INFO: Test took 4596ms.
[16:21:44.766] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[16:21:45.100] <TB3> INFO: Expecting 41600 events.
[16:21:49.296] <TB3> INFO: 41600 events read in total (3479ms).
[16:21:49.297] <TB3> INFO: Test took 4531ms.
[16:21:49.300] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[16:21:49.641] <TB3> INFO: Expecting 41600 events.
[16:21:53.857] <TB3> INFO: 41600 events read in total (3499ms).
[16:21:53.858] <TB3> INFO: Test took 4558ms.
[16:21:53.861] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[16:21:54.204] <TB3> INFO: Expecting 41600 events.
[16:21:58.570] <TB3> INFO: 41600 events read in total (3649ms).
[16:21:58.570] <TB3> INFO: Test took 4709ms.
[16:21:58.573] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:21:58.908] <TB3> INFO: Expecting 41600 events.
[16:22:03.121] <TB3> INFO: 41600 events read in total (3496ms).
[16:22:03.122] <TB3> INFO: Test took 4549ms.
[16:22:03.590] <TB3> INFO: PixTestGainPedestal::measure() done
[16:22:38.193] <TB3> INFO: PixTestGainPedestal::fit() done
[16:22:38.194] <TB3> INFO: non-linearity mean: 0.965 0.963 0.950 0.956 0.957 0.966 0.958 0.962 0.950 0.961 0.950 0.954 0.958 0.953 0.961 0.961
[16:22:38.194] <TB3> INFO: non-linearity RMS: 0.006 0.005 0.007 0.007 0.007 0.006 0.007 0.005 0.007 0.005 0.007 0.006 0.006 0.006 0.008 0.007
[16:22:38.194] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:22:38.213] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:22:38.232] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:22:38.251] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:22:38.270] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:22:38.289] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:22:38.308] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:22:38.326] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:22:38.345] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:22:38.363] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:22:38.381] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:22:38.399] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:22:38.417] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:22:38.436] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:22:38.454] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:22:38.473] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:22:38.491] <TB3> INFO: PixTestGainPedestal::doTest() done, duration: 169 seconds
[16:22:38.497] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C15.dat
[16:22:38.498] <TB3> INFO: PixTestReadback::doTest() start.
[16:22:38.499] <TB3> INFO: PixTestReadback::RES sent once
[16:23:00.256] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C0.dat
[16:23:00.256] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C1.dat
[16:23:00.257] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C2.dat
[16:23:00.257] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C3.dat
[16:23:00.257] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C4.dat
[16:23:00.257] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C5.dat
[16:23:00.257] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C6.dat
[16:23:00.257] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C7.dat
[16:23:00.257] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C8.dat
[16:23:00.257] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C9.dat
[16:23:00.257] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C10.dat
[16:23:00.257] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C11.dat
[16:23:00.257] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C12.dat
[16:23:00.257] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C13.dat
[16:23:00.257] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C14.dat
[16:23:00.258] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C15.dat
[16:23:00.290] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:23:00.291] <TB3> INFO: PixTestReadback::RES sent once
[16:23:22.005] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C0.dat
[16:23:22.005] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C1.dat
[16:23:22.005] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C2.dat
[16:23:22.005] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C3.dat
[16:23:22.005] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C4.dat
[16:23:22.006] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C5.dat
[16:23:22.006] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C6.dat
[16:23:22.006] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C7.dat
[16:23:22.006] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C8.dat
[16:23:22.006] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C9.dat
[16:23:22.006] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C10.dat
[16:23:22.006] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C11.dat
[16:23:22.006] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C12.dat
[16:23:22.006] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C13.dat
[16:23:22.006] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C14.dat
[16:23:22.006] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C15.dat
[16:23:22.039] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:23:22.039] <TB3> INFO: PixTestReadback::RES sent once
[16:23:38.913] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:23:38.913] <TB3> INFO: Vbg will be calibrated using Vd calibration
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 146.4calibrated Vbg = 1.21347 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 155.2calibrated Vbg = 1.21838 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 149.3calibrated Vbg = 1.21964 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 255calibrated Vbg = 1.1775 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 151.9calibrated Vbg = 1.22852 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 159.4calibrated Vbg = 1.2363 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 152.5calibrated Vbg = 1.22539 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 255calibrated Vbg = 1.1775 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 145.5calibrated Vbg = 1.23056 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 151.9calibrated Vbg = 1.22671 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 156.4calibrated Vbg = 1.22435 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 255calibrated Vbg = 1.1775 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 154calibrated Vbg = 1.21557 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 149.2calibrated Vbg = 1.21901 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 152.4calibrated Vbg = 1.21498 :::*/*/*/*/
[16:23:38.913] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 255calibrated Vbg = 1.1775 :::*/*/*/*/
[16:23:38.916] <TB3> INFO: PixTestReadback::RES sent once
[16:28:19.275] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C0.dat
[16:28:19.275] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C1.dat
[16:28:19.275] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C2.dat
[16:28:19.275] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C3.dat
[16:28:19.275] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C4.dat
[16:28:19.275] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C5.dat
[16:28:19.275] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C6.dat
[16:28:19.275] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C7.dat
[16:28:19.275] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C8.dat
[16:28:19.275] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C9.dat
[16:28:19.275] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C10.dat
[16:28:19.275] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C11.dat
[16:28:19.275] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C12.dat
[16:28:19.276] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C13.dat
[16:28:19.276] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C14.dat
[16:28:19.276] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2068_FullQualification_2015-09-03_10h31m_1441269084//004_FulltestPxar_p17//readbackCal_C15.dat
[16:28:19.309] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:28:19.311] <TB3> INFO: PixTestReadback::doTest() done
[16:28:19.326] <TB3> INFO: enter test to run
[16:28:19.326] <TB3> INFO: test: exit no parameter change
[16:28:19.871] <TB3> QUIET: Connection to board 170 closed.
[16:28:19.951] <TB3> INFO: pXar: this is the end, my friend