[08:29:11.233] <TB3> INFO: *** Welcome to pxar ***
[08:29:11.233] <TB3> INFO: *** Today: 2015/07/07
[08:29:11.233] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C15.dat
[08:29:11.235] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:29:11.235] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//defaultMaskFile.dat
[08:29:11.235] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters_C15.dat
[08:29:11.313] <TB3> INFO: clk: 4
[08:29:11.313] <TB3> INFO: ctr: 4
[08:29:11.313] <TB3> INFO: sda: 19
[08:29:11.313] <TB3> INFO: tin: 9
[08:29:11.313] <TB3> INFO: level: 15
[08:29:11.313] <TB3> INFO: triggerdelay: 0
[08:29:11.313] <TB3> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[08:29:11.313] <TB3> INFO: Log level: INFO
[08:29:11.320] <TB3> INFO: Found DTB DTB_WZ4I6J
[08:29:11.328] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[08:29:11.332] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[08:29:11.334] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[08:29:12.906] <TB3> INFO: DUT info:
[08:29:12.906] <TB3> INFO: The DUT currently contains the following objects:
[08:29:12.906] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[08:29:12.906] <TB3> INFO: TBM Core alpha (0): 7 registers set
[08:29:12.906] <TB3> INFO: TBM Core beta (1): 7 registers set
[08:29:12.906] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:29:12.906] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:12.906] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:13.307] <TB3> INFO: enter 'restricted' command line mode
[08:29:13.307] <TB3> INFO: enter test to run
[08:29:13.308] <TB3> INFO: test: pretest no parameter change
[08:29:13.308] <TB3> INFO: running: pretest
[08:29:13.314] <TB3> INFO: ######################################################################
[08:29:13.314] <TB3> INFO: PixTestPretest::doTest()
[08:29:13.315] <TB3> INFO: ######################################################################
[08:29:13.316] <TB3> INFO: ----------------------------------------------------------------------
[08:29:13.316] <TB3> INFO: PixTestPretest::programROC()
[08:29:13.316] <TB3> INFO: ----------------------------------------------------------------------
[08:29:31.334] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:29:31.334] <TB3> INFO: IA differences per ROC: 17.7 17.7 19.3 16.9 17.7 20.9 20.1 18.5 19.3 19.3 19.3 19.3 20.1 19.3 17.7 20.1
[08:29:31.401] <TB3> INFO: ----------------------------------------------------------------------
[08:29:31.401] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:29:31.401] <TB3> INFO: ----------------------------------------------------------------------
[08:29:36.279] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 397.1 mA = 24.8188 mA/ROC
[08:29:36.282] <TB3> INFO: ----------------------------------------------------------------------
[08:29:36.282] <TB3> INFO: PixTestPretest::findWorkingPixel()
[08:29:36.282] <TB3> INFO: ----------------------------------------------------------------------
[08:29:44.495] <TB3> INFO: Test took 8208ms.
[08:29:44.794] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:29:44.833] <TB3> INFO: ----------------------------------------------------------------------
[08:29:44.833] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[08:29:44.833] <TB3> INFO: ----------------------------------------------------------------------
[08:29:53.129] <TB3> INFO: Test took 8289ms.
[08:29:53.452] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[08:29:53.452] <TB3> INFO: CalDel: 132 139 151 133 156 139 130 128 142 148 129 120 129 154 120 148
[08:29:53.452] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:29:53.457] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C0.dat
[08:29:53.457] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C1.dat
[08:29:53.457] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C2.dat
[08:29:53.458] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C3.dat
[08:29:53.458] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C4.dat
[08:29:53.458] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C5.dat
[08:29:53.458] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C6.dat
[08:29:53.459] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C7.dat
[08:29:53.459] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C8.dat
[08:29:53.459] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C9.dat
[08:29:53.459] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C10.dat
[08:29:53.460] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C11.dat
[08:29:53.460] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C12.dat
[08:29:53.460] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C13.dat
[08:29:53.460] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C14.dat
[08:29:53.460] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters_C15.dat
[08:29:53.460] <TB3> INFO: PixTestPretest::doTest() done, duration: 40 seconds
[08:29:53.570] <TB3> INFO: enter test to run
[08:29:53.570] <TB3> INFO: test: fulltest no parameter change
[08:29:53.570] <TB3> INFO: running: fulltest
[08:29:53.570] <TB3> INFO: ######################################################################
[08:29:53.570] <TB3> INFO: PixTestFullTest::doTest()
[08:29:53.570] <TB3> INFO: ######################################################################
[08:29:53.571] <TB3> INFO: ######################################################################
[08:29:53.571] <TB3> INFO: PixTestAlive::doTest()
[08:29:53.571] <TB3> INFO: ######################################################################
[08:29:53.573] <TB3> INFO: ----------------------------------------------------------------------
[08:29:53.573] <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)
[08:29:53.573] <TB3> INFO: ----------------------------------------------------------------------
[08:29:57.032] <TB3> INFO: Test took 3457ms.
[08:29:57.056] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:57.277] <TB3> INFO: PixTestAlive::aliveTest() done
[08:29:57.277] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:29:57.278] <TB3> INFO: ----------------------------------------------------------------------
[08:29:57.278] <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)
[08:29:57.278] <TB3> INFO: ----------------------------------------------------------------------
[08:30:00.007] <TB3> INFO: Test took 2727ms.
[08:30:00.009] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:00.010] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:30:00.238] <TB3> INFO: PixTestAlive::maskTest() done
[08:30:00.238] <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
[08:30:00.240] <TB3> INFO: ----------------------------------------------------------------------
[08:30:00.240] <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)
[08:30:00.240] <TB3> INFO: ----------------------------------------------------------------------
[08:30:03.699] <TB3> INFO: Test took 3458ms.
[08:30:03.723] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:03.953] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[08:30:03.953] <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
[08:30:03.953] <TB3> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[08:30:03.965] <TB3> INFO: ######################################################################
[08:30:03.965] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:30:03.965] <TB3> INFO: ######################################################################
[08:30:03.967] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30) hits flags = 2 (plus default)
[08:30:03.979] <TB3> INFO: dacScan step from 0 .. 29
[08:30:25.312] <TB3> INFO: Test took 21333ms.
[08:30:25.349] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:25.349] <TB3> INFO: dacScan step from 30 .. 59
[08:30:47.793] <TB3> INFO: Test took 22444ms.
[08:30:47.879] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:47.889] <TB3> INFO: dacScan step from 60 .. 89
[08:31:16.659] <TB3> INFO: Test took 28770ms.
[08:31:16.914] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:16.993] <TB3> INFO: dacScan step from 90 .. 119
[08:31:45.820] <TB3> INFO: Test took 28827ms.
[08:31:46.084] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:46.171] <TB3> INFO: dacScan step from 120 .. 149
[08:32:11.637] <TB3> INFO: Test took 25465ms.
[08:32:11.971] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:36.003] <TB3> INFO: PixTestBBMap::doTest() done, duration: 152 seconds
[08:32:36.003] <TB3> INFO: number of dead bumps (per ROC): 9 0 4 0 0 0 0 9 0 0 0 2 1 0 1 6
[08:32:36.003] <TB3> INFO: separation cut (per ROC): 83 83 94 97 90 102 100 93 80 100 90 106 89 103 104 82
[08:32:36.101] <TB3> INFO: ######################################################################
[08:32:36.101] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50
[08:32:36.101] <TB3> INFO: ######################################################################
[08:32:36.101] <TB3> INFO: ----------------------------------------------------------------------
[08:32:36.101] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50
[08:32:36.101] <TB3> INFO: ----------------------------------------------------------------------
[08:32:36.101] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (3) hits flags = 16 (plus default)
[08:32:36.110] <TB3> INFO: dacScan step from 0 .. 2
[08:32:49.759] <TB3> INFO: Test took 13648ms.
[08:32:49.781] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:49.781] <TB3> INFO: dacScan step from 3 .. 5
[08:33:04.094] <TB3> INFO: Test took 14313ms.
[08:33:04.116] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:04.116] <TB3> INFO: dacScan step from 6 .. 8
[08:33:18.915] <TB3> INFO: Test took 14798ms.
[08:33:18.940] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:18.940] <TB3> INFO: dacScan step from 9 .. 11
[08:33:33.880] <TB3> INFO: Test took 14940ms.
[08:33:33.906] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:33.906] <TB3> INFO: dacScan step from 12 .. 14
[08:33:48.743] <TB3> INFO: Test took 14837ms.
[08:33:48.766] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:48.766] <TB3> INFO: dacScan step from 15 .. 17
[08:34:03.573] <TB3> INFO: Test took 14807ms.
[08:34:03.596] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:03.596] <TB3> INFO: dacScan step from 18 .. 20
[08:34:18.456] <TB3> INFO: Test took 14860ms.
[08:34:18.477] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:18.477] <TB3> INFO: dacScan step from 21 .. 23
[08:34:33.238] <TB3> INFO: Test took 14761ms.
[08:34:33.257] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:33.257] <TB3> INFO: dacScan step from 24 .. 26
[08:34:48.039] <TB3> INFO: Test took 14782ms.
[08:34:48.061] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:48.062] <TB3> INFO: dacScan step from 27 .. 29
[08:35:02.733] <TB3> INFO: Test took 14671ms.
[08:35:02.755] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:02.755] <TB3> INFO: dacScan step from 30 .. 32
[08:35:17.492] <TB3> INFO: Test took 14736ms.
[08:35:17.515] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:17.515] <TB3> INFO: dacScan step from 33 .. 35
[08:35:32.236] <TB3> INFO: Test took 14721ms.
[08:35:32.257] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:32.257] <TB3> INFO: dacScan step from 36 .. 38
[08:35:47.102] <TB3> INFO: Test took 14845ms.
[08:35:47.129] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:47.129] <TB3> INFO: dacScan step from 39 .. 41
[08:36:01.834] <TB3> INFO: Test took 14705ms.
[08:36:01.856] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:01.856] <TB3> INFO: dacScan step from 42 .. 44
[08:36:16.601] <TB3> INFO: Test took 14745ms.
[08:36:16.624] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:16.625] <TB3> INFO: dacScan step from 45 .. 47
[08:36:31.422] <TB3> INFO: Test took 14797ms.
[08:36:31.445] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:31.445] <TB3> INFO: dacScan step from 48 .. 50
[08:36:46.138] <TB3> INFO: Test took 14693ms.
[08:36:46.161] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:46.161] <TB3> INFO: dacScan step from 51 .. 53
[08:37:00.939] <TB3> INFO: Test took 14778ms.
[08:37:00.964] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:00.964] <TB3> INFO: dacScan step from 54 .. 56
[08:37:15.887] <TB3> INFO: Test took 14923ms.
[08:37:15.908] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:15.908] <TB3> INFO: dacScan step from 57 .. 59
[08:37:30.732] <TB3> INFO: Test took 14824ms.
[08:37:30.755] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:30.755] <TB3> INFO: dacScan step from 60 .. 62
[08:37:45.599] <TB3> INFO: Test took 14844ms.
[08:37:45.625] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:45.625] <TB3> INFO: dacScan step from 63 .. 65
[08:38:00.430] <TB3> INFO: Test took 14805ms.
[08:38:00.453] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:00.453] <TB3> INFO: dacScan step from 66 .. 68
[08:38:15.366] <TB3> INFO: Test took 14913ms.
[08:38:15.394] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:15.394] <TB3> INFO: dacScan step from 69 .. 71
[08:38:30.288] <TB3> INFO: Test took 14894ms.
[08:38:30.312] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:30.313] <TB3> INFO: dacScan step from 72 .. 74
[08:38:45.355] <TB3> INFO: Test took 15042ms.
[08:38:45.393] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:45.394] <TB3> INFO: dacScan step from 75 .. 77
[08:39:00.990] <TB3> INFO: Test took 15596ms.
[08:39:01.038] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:01.040] <TB3> INFO: dacScan step from 78 .. 80
[08:39:17.409] <TB3> INFO: Test took 16369ms.
[08:39:17.475] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:17.479] <TB3> INFO: dacScan step from 81 .. 83
[08:39:35.238] <TB3> INFO: Test took 17759ms.
[08:39:35.334] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:35.338] <TB3> INFO: dacScan step from 84 .. 86
[08:39:53.897] <TB3> INFO: Test took 18559ms.
[08:39:54.015] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:54.021] <TB3> INFO: dacScan step from 87 .. 89
[08:40:13.604] <TB3> INFO: Test took 19583ms.
[08:40:13.734] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:40:13.742] <TB3> INFO: dacScan step from 90 .. 92
[08:40:34.106] <TB3> INFO: Test took 20364ms.
[08:40:34.257] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:40:34.268] <TB3> INFO: dacScan step from 93 .. 95
[08:40:55.341] <TB3> INFO: Test took 21073ms.
[08:40:55.516] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:40:55.523] <TB3> INFO: dacScan step from 96 .. 98
[08:41:17.291] <TB3> INFO: Test took 21768ms.
[08:41:17.473] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:41:17.481] <TB3> INFO: dacScan step from 99 .. 101
[08:41:39.540] <TB3> INFO: Test took 22058ms.
[08:41:39.745] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:41:39.754] <TB3> INFO: dacScan step from 102 .. 104
[08:42:02.080] <TB3> INFO: Test took 22326ms.
[08:42:02.263] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:02.273] <TB3> INFO: dacScan step from 105 .. 107
[08:42:24.667] <TB3> INFO: Test took 22394ms.
[08:42:24.841] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:24.852] <TB3> INFO: dacScan step from 108 .. 110
[08:42:47.116] <TB3> INFO: Test took 22264ms.
[08:42:47.291] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:47.302] <TB3> INFO: dacScan step from 111 .. 113
[08:43:09.695] <TB3> INFO: Test took 22393ms.
[08:43:09.877] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:09.887] <TB3> INFO: dacScan step from 114 .. 116
[08:43:32.138] <TB3> INFO: Test took 22250ms.
[08:43:32.322] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:32.331] <TB3> INFO: dacScan step from 117 .. 119
[08:43:54.538] <TB3> INFO: Test took 22207ms.
[08:43:54.704] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:54.714] <TB3> INFO: dacScan step from 120 .. 122
[08:44:17.087] <TB3> INFO: Test took 22373ms.
[08:44:17.265] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:17.275] <TB3> INFO: dacScan step from 123 .. 125
[08:44:39.622] <TB3> INFO: Test took 22347ms.
[08:44:39.795] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:39.804] <TB3> INFO: dacScan step from 126 .. 128
[08:45:02.128] <TB3> INFO: Test took 22324ms.
[08:45:02.302] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:02.311] <TB3> INFO: dacScan step from 129 .. 131
[08:45:24.667] <TB3> INFO: Test took 22356ms.
[08:45:24.832] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:24.842] <TB3> INFO: dacScan step from 132 .. 134
[08:45:47.160] <TB3> INFO: Test took 22318ms.
[08:45:47.327] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:47.336] <TB3> INFO: dacScan step from 135 .. 137
[08:46:09.704] <TB3> INFO: Test took 22368ms.
[08:46:09.879] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:09.888] <TB3> INFO: dacScan step from 138 .. 140
[08:46:32.364] <TB3> INFO: Test took 22476ms.
[08:46:32.535] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:32.544] <TB3> INFO: dacScan step from 141 .. 143
[08:46:55.031] <TB3> INFO: Test took 22487ms.
[08:46:55.203] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:55.212] <TB3> INFO: dacScan step from 144 .. 146
[08:47:17.579] <TB3> INFO: Test took 22367ms.
[08:47:17.743] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:47:17.751] <TB3> INFO: dacScan step from 147 .. 149
[08:47:38.817] <TB3> INFO: Test took 21066ms.
[08:47:39.001] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:47:39.010] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:47:40.431] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:47:41.967] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:47:43.482] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:47:44.915] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:47:46.339] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:47:47.785] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:47:49.248] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:47:50.724] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:47:52.402] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:47:53.986] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:47:55.372] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:47:56.765] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:47:58.173] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:47:59.557] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:48:00.905] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:48:02.318] <TB3> INFO: PixTestScurves::scurves() done
[08:48:02.318] <TB3> INFO: Vcal mean: 82.76 79.04 92.55 93.73 89.69 98.53 93.72 86.20 79.67 96.70 82.19 93.66 82.05 84.84 95.01 82.68
[08:48:02.318] <TB3> INFO: Vcal RMS: 4.18 5.09 5.20 5.47 6.51 5.65 5.68 5.10 3.90 5.17 4.15 5.40 4.64 4.55 5.55 4.58
[08:48:02.318] <TB3> INFO: PixTestScurves::fullTest() done, duration: 926 seconds
[08:48:02.400] <TB3> INFO: ######################################################################
[08:48:02.400] <TB3> INFO: PixTestTrim::doTest()
[08:48:02.400] <TB3> INFO: ######################################################################
[08:48:02.401] <TB3> INFO: ----------------------------------------------------------------------
[08:48:02.401] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[08:48:02.401] <TB3> INFO: ----------------------------------------------------------------------
[08:48:02.491] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[08:48:02.491] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[08:48:02.501] <TB3> INFO: dacScan step from 0 .. 19
[08:48:17.565] <TB3> INFO: Test took 15064ms.
[08:48:17.592] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:48:17.592] <TB3> INFO: dacScan step from 20 .. 39
[08:48:32.649] <TB3> INFO: Test took 15057ms.
[08:48:32.674] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:48:32.674] <TB3> INFO: dacScan step from 40 .. 59
[08:48:47.779] <TB3> INFO: Test took 15105ms.
[08:48:47.806] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:48:47.806] <TB3> INFO: dacScan step from 60 .. 79
[08:49:02.907] <TB3> INFO: Test took 15101ms.
[08:49:02.935] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:49:02.935] <TB3> INFO: dacScan step from 80 .. 99
[08:49:18.427] <TB3> INFO: Test took 15492ms.
[08:49:18.480] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:49:18.490] <TB3> INFO: dacScan step from 100 .. 119
[08:49:37.016] <TB3> INFO: Test took 18526ms.
[08:49:37.170] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:49:37.205] <TB3> INFO: dacScan step from 120 .. 139
[08:49:56.623] <TB3> INFO: Test took 19418ms.
[08:49:56.801] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:49:56.839] <TB3> INFO: dacScan step from 140 .. 159
[08:50:12.610] <TB3> INFO: Test took 15771ms.
[08:50:12.713] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:50:37.555] <TB3> INFO: ROC 0 VthrComp = 91
[08:50:37.555] <TB3> INFO: ROC 1 VthrComp = 85
[08:50:37.555] <TB3> INFO: ROC 2 VthrComp = 97
[08:50:37.555] <TB3> INFO: ROC 3 VthrComp = 98
[08:50:37.555] <TB3> INFO: ROC 4 VthrComp = 92
[08:50:37.555] <TB3> INFO: ROC 5 VthrComp = 102
[08:50:37.555] <TB3> INFO: ROC 6 VthrComp = 99
[08:50:37.556] <TB3> INFO: ROC 7 VthrComp = 94
[08:50:37.556] <TB3> INFO: ROC 8 VthrComp = 86
[08:50:37.556] <TB3> INFO: ROC 9 VthrComp = 103
[08:50:37.556] <TB3> INFO: ROC 10 VthrComp = 91
[08:50:37.556] <TB3> INFO: ROC 11 VthrComp = 103
[08:50:37.556] <TB3> INFO: ROC 12 VthrComp = 88
[08:50:37.556] <TB3> INFO: ROC 13 VthrComp = 96
[08:50:37.556] <TB3> INFO: ROC 14 VthrComp = 102
[08:50:37.556] <TB3> INFO: ROC 15 VthrComp = 90
[08:50:37.556] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[08:50:37.556] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[08:50:37.564] <TB3> INFO: dacScan step from 0 .. 19
[08:50:52.618] <TB3> INFO: Test took 15054ms.
[08:50:52.648] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:50:52.648] <TB3> INFO: dacScan step from 20 .. 39
[08:51:07.649] <TB3> INFO: Test took 15001ms.
[08:51:07.682] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:51:07.684] <TB3> INFO: dacScan step from 40 .. 59
[08:51:25.294] <TB3> INFO: Test took 17609ms.
[08:51:25.449] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:51:25.489] <TB3> INFO: dacScan step from 60 .. 79
[08:51:45.717] <TB3> INFO: Test took 20228ms.
[08:51:45.881] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:51:45.936] <TB3> INFO: dacScan step from 80 .. 99
[08:52:06.026] <TB3> INFO: Test took 20090ms.
[08:52:06.236] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:52:06.297] <TB3> INFO: dacScan step from 100 .. 119
[08:52:26.433] <TB3> INFO: Test took 20136ms.
[08:52:26.626] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:52:26.685] <TB3> INFO: dacScan step from 120 .. 139
[08:52:46.808] <TB3> INFO: Test took 20123ms.
[08:52:46.965] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:52:47.017] <TB3> INFO: dacScan step from 140 .. 159
[08:53:05.619] <TB3> INFO: Test took 18602ms.
[08:53:05.800] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:53:32.032] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 56.4591 for pixel 0/4 mean/min/max = 44.5377/32.5787/56.4967
[08:53:32.032] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 58.9154 for pixel 5/75 mean/min/max = 45.5242/32.0097/59.0387
[08:53:32.032] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 57.0096 for pixel 14/2 mean/min/max = 44.6658/31.8391/57.4925
[08:53:32.033] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 59.7589 for pixel 21/1 mean/min/max = 45.4276/31.0444/59.8107
[08:53:32.033] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 60.6919 for pixel 2/46 mean/min/max = 46.3283/31.868/60.7886
[08:53:32.033] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 58.8988 for pixel 31/77 mean/min/max = 45.4633/31.9848/58.9417
[08:53:32.033] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 58.492 for pixel 9/0 mean/min/max = 45.039/31.5739/58.504
[08:53:32.034] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 57.8659 for pixel 0/3 mean/min/max = 44.8338/31.763/57.9046
[08:53:32.034] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 55.8497 for pixel 2/72 mean/min/max = 44.3925/32.6702/56.1148
[08:53:32.034] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 58.4573 for pixel 12/65 mean/min/max = 45.3173/32.1298/58.5048
[08:53:32.034] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 55.9525 for pixel 7/59 mean/min/max = 44.4546/32.8478/56.0613
[08:53:32.035] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 57.4841 for pixel 22/41 mean/min/max = 45.1156/32.7315/57.4997
[08:53:32.035] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 58.3231 for pixel 0/79 mean/min/max = 45.6898/33.0564/58.3232
[08:53:32.035] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 54.8663 for pixel 51/42 mean/min/max = 43.4242/31.7027/55.1457
[08:53:32.035] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 57.6785 for pixel 9/29 mean/min/max = 45.2996/32.6961/57.903
[08:53:32.035] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 57.2458 for pixel 15/79 mean/min/max = 44.8129/32.3484/57.2774
[08:53:32.036] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:55:05.093] <TB3> INFO: Test took 93057ms.
[08:55:06.610] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[08:55:06.619] <TB3> INFO: dacScan step from 0 .. 19
[08:55:29.344] <TB3> INFO: Test took 22725ms.
[08:55:29.388] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:55:29.389] <TB3> INFO: dacScan step from 20 .. 39
[08:55:57.694] <TB3> INFO: Test took 28305ms.
[08:55:57.928] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:55:57.957] <TB3> INFO: dacScan step from 40 .. 59
[08:56:29.783] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (157) != TBM ID (0)
[08:56:29.784] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)
[08:56:29.784] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (1) != TBM ID (158)
[08:56:29.784] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!
[08:56:31.023] <TB3> INFO: Test took 33065ms.
[08:56:31.304] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:56:31.359] <TB3> INFO: dacScan step from 60 .. 79
[08:57:02.960] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (8) != Token Chain Length (4)
[08:57:02.960] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (195) != TBM ID (196)
[08:57:02.960] <TB3> WARNING: ROC 0: Readback start marker after 15 readouts!
[08:57:02.960] <TB3> WARNING: ROC 1: Readback start marker after 15 readouts!
[08:57:02.960] <TB3> WARNING: ROC 2: Readback start marker after 15 readouts!
[08:57:02.960] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!
[08:57:04.223] <TB3> INFO: Test took 32864ms.
[08:57:04.506] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:57:04.563] <TB3> INFO: dacScan step from 80 .. 99
[08:57:33.275] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (195) != TBM ID (0)
[08:57:33.275] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)
[08:57:33.275] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (1) != TBM ID (196)
[08:57:33.275] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!
[08:57:34.305] <TB3> INFO: Test took 29742ms.
[08:57:34.568] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:57:34.624] <TB3> INFO: dacScan step from 100 .. 119
[08:58:07.606] <TB3> INFO: Test took 32982ms.
[08:58:07.977] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:58:08.035] <TB3> INFO: dacScan step from 120 .. 139
[08:58:39.522] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)
[08:58:39.522] <TB3> WARNING: ROC 2: Readback start marker after 15 readouts!
[08:58:39.522] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!
[08:58:40.763] <TB3> INFO: Test took 32728ms.
[08:58:41.054] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:58:41.107] <TB3> INFO: dacScan step from 140 .. 159
[08:59:12.736] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)
[08:59:12.737] <TB3> WARNING: ROC 2: Readback start marker after 15 readouts!
[08:59:12.737] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!
[08:59:13.975] <TB3> INFO: Test took 32868ms.
[08:59:14.252] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:59:14.305] <TB3> INFO: dacScan step from 160 .. 179
[08:59:45.907] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (195) != TBM ID (0)
[08:59:45.907] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)
[08:59:45.907] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (1) != TBM ID (196)
[08:59:45.907] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!
[08:59:47.159] <TB3> INFO: Test took 32854ms.
[08:59:47.454] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:59:47.507] <TB3> INFO: dacScan step from 180 .. 199
[09:00:19.579] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)
[09:00:19.579] <TB3> WARNING: ROC 2: Readback start marker after 15 readouts!
[09:00:19.579] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!
[09:00:20.673] <TB3> INFO: Test took 33166ms.
[09:00:21.123] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:46.854] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.125008 .. 48.610246
[09:00:46.934] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 58 (20) hits flags = 16 (plus default)
[09:00:46.942] <TB3> INFO: dacScan step from 0 .. 19
[09:01:00.553] <TB3> INFO: Test took 13611ms.
[09:01:00.579] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:01:00.579] <TB3> INFO: dacScan step from 20 .. 39
[09:01:15.409] <TB3> INFO: Test took 14830ms.
[09:01:15.492] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:01:15.508] <TB3> INFO: dacScan step from 40 .. 58
[09:01:32.235] <TB3> INFO: Test took 16727ms.
[09:01:32.391] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:01:51.096] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 16.323948 .. 43.113416
[09:01:51.194] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 6 .. 53 (20) hits flags = 16 (plus default)
[09:01:51.204] <TB3> INFO: dacScan step from 6 .. 25
[09:02:04.785] <TB3> INFO: Test took 13581ms.
[09:02:04.807] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:02:04.807] <TB3> INFO: dacScan step from 26 .. 45
[09:02:20.693] <TB3> INFO: Test took 15885ms.
[09:02:20.809] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:02:20.837] <TB3> INFO: dacScan step from 46 .. 53
[09:02:29.580] <TB3> INFO: Test took 8743ms.
[09:02:29.643] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:02:45.582] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 22.185322 .. 40.652218
[09:02:45.669] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 12 .. 50 (20) hits flags = 16 (plus default)
[09:02:45.677] <TB3> INFO: dacScan step from 12 .. 31
[09:02:59.204] <TB3> INFO: Test took 13527ms.
[09:02:59.232] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:02:59.234] <TB3> INFO: dacScan step from 32 .. 50
[09:03:15.409] <TB3> INFO: Test took 16175ms.
[09:03:15.595] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:30.908] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 24.500001 .. 39.495435
[09:03:30.987] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 14 .. 49 (20) hits flags = 16 (plus default)
[09:03:30.995] <TB3> INFO: dacScan step from 14 .. 33
[09:03:44.683] <TB3> INFO: Test took 13688ms.
[09:03:44.718] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:44.722] <TB3> INFO: dacScan step from 34 .. 49
[09:03:59.165] <TB3> INFO: Test took 14443ms.
[09:03:59.301] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:04:14.129] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:04:14.129] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20) hits flags = 16 (plus default)
[09:04:14.137] <TB3> INFO: dacScan step from 15 .. 34
[09:04:36.038] <TB3> INFO: Test took 21901ms.
[09:04:36.127] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:04:36.136] <TB3> INFO: dacScan step from 35 .. 54
[09:05:05.976] <TB3> INFO: Test took 29840ms.
[09:05:06.331] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:05:06.391] <TB3> INFO: dacScan step from 55 .. 55
[09:05:10.761] <TB3> INFO: Test took 4370ms.
[09:05:10.781] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:05:25.999] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:05:26.000] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:05:26.000] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:05:26.000] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:05:26.000] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:05:26.000] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:05:26.000] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:05:26.000] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:05:26.000] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:05:26.001] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:05:26.001] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:05:26.001] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:05:26.001] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:05:26.001] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:05:26.001] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:05:26.002] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:05:26.002] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:05:26.011] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:05:26.018] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:05:26.024] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:05:26.031] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:05:26.038] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:05:26.044] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:05:26.050] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:05:26.057] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:05:26.064] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:05:26.071] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:05:26.078] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:05:26.084] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:05:26.091] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:05:26.098] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:05:26.105] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:05:26.111] <TB3> INFO: PixTestTrim::trimTest() done
[09:05:26.111] <TB3> INFO: vtrim: 109 115 99 118 105 118 112 104 101 104 109 115 98 87 111 126
[09:05:26.111] <TB3> INFO: vthrcomp: 91 85 97 98 92 102 99 94 86 103 91 103 88 96 102 90
[09:05:26.111] <TB3> INFO: vcal mean: 35.04 35.05 34.85 35.01 35.00 35.02 35.04 35.01 34.98 35.02 35.03 35.01 35.05 34.98 35.00 35.02
[09:05:26.111] <TB3> INFO: vcal RMS: 0.97 1.01 1.17 1.03 1.04 1.01 1.02 0.96 0.94 1.00 0.92 1.05 0.97 0.97 1.06 0.97
[09:05:26.111] <TB3> INFO: bits mean: 9.89 10.08 9.94 10.01 9.18 9.87 9.86 9.58 10.07 9.79 9.86 10.24 9.06 9.86 9.73 9.82
[09:05:26.111] <TB3> INFO: bits RMS: 2.47 2.45 2.48 2.56 2.74 2.44 2.58 2.73 2.41 2.51 2.40 2.31 2.68 2.66 2.51 2.60
[09:05:26.116] <TB3> INFO: ----------------------------------------------------------------------
[09:05:26.116] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 255 240 150 100
[09:05:26.116] <TB3> INFO: ----------------------------------------------------------------------
[09:05:26.119] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[09:05:26.128] <TB3> INFO: dacScan step from 0 .. 19
[09:05:47.696] <TB3> INFO: Test took 21568ms.
[09:05:47.741] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:05:47.741] <TB3> INFO: dacScan step from 20 .. 39
[09:06:10.663] <TB3> INFO: Test took 22922ms.
[09:06:10.721] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:10.725] <TB3> INFO: dacScan step from 40 .. 59
[09:06:40.282] <TB3> INFO: Test took 29557ms.
[09:06:40.651] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:40.702] <TB3> INFO: dacScan step from 60 .. 79
[09:07:11.509] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (73) != TBM ID (0)
[09:07:11.509] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)
[09:07:11.509] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (1) != TBM ID (74)
[09:07:11.509] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!
[09:07:12.785] <TB3> INFO: Test took 32083ms.
[09:07:13.083] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:13.139] <TB3> INFO: dacScan step from 80 .. 99
[09:07:43.839] <TB3> INFO: Test took 30700ms.
[09:07:44.289] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:44.396] <TB3> INFO: dacScan step from 100 .. 119
[09:08:17.238] <TB3> INFO: Test took 32842ms.
[09:08:17.514] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:08:17.574] <TB3> INFO: dacScan step from 120 .. 139
[09:08:49.921] <TB3> INFO: Test took 32347ms.
[09:08:50.243] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:08:50.307] <TB3> INFO: dacScan step from 140 .. 159
[09:09:21.554] <TB3> INFO: Test took 31246ms.
[09:09:21.831] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:21.888] <TB3> INFO: dacScan step from 160 .. 179
[09:09:55.047] <TB3> INFO: Test took 33158ms.
[09:09:55.332] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:55.386] <TB3> INFO: dacScan step from 180 .. 199
[09:10:26.458] <TB3> INFO: Test took 31072ms.
[09:10:26.720] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:51.443] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 86 (20) hits flags = 16 (plus default)
[09:10:51.451] <TB3> INFO: dacScan step from 0 .. 19
[09:11:14.057] <TB3> INFO: Test took 22606ms.
[09:11:14.093] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:14.093] <TB3> INFO: dacScan step from 20 .. 39
[09:11:36.416] <TB3> INFO: Test took 22323ms.
[09:11:36.508] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:36.517] <TB3> INFO: dacScan step from 40 .. 59
[09:12:08.328] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)
[09:12:08.328] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!
[09:12:08.596] <TB3> INFO: Test took 32078ms.
[09:12:08.883] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:08.932] <TB3> INFO: dacScan step from 60 .. 79
[09:12:39.687] <TB3> INFO: Test took 30755ms.
[09:12:39.983] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:40.041] <TB3> INFO: dacScan step from 80 .. 86
[09:12:53.252] <TB3> INFO: Test took 13211ms.
[09:12:53.352] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:09.922] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 72 (20) hits flags = 16 (plus default)
[09:13:09.933] <TB3> INFO: dacScan step from 0 .. 19
[09:13:31.080] <TB3> INFO: Test took 21147ms.
[09:13:31.115] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:31.115] <TB3> INFO: dacScan step from 20 .. 39
[09:13:56.435] <TB3> INFO: Test took 25320ms.
[09:13:56.576] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:56.592] <TB3> INFO: dacScan step from 40 .. 59
[09:14:25.903] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)
[09:14:25.903] <TB3> WARNING: ROC 2: Readback start marker after 15 readouts!
[09:14:25.903] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!
[09:14:26.678] <TB3> INFO: Test took 30086ms.
[09:14:26.954] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:27.007] <TB3> INFO: dacScan step from 60 .. 72
[09:14:47.863] <TB3> INFO: Test took 20855ms.
[09:14:48.097] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:03.657] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 65 (20) hits flags = 16 (plus default)
[09:15:03.665] <TB3> INFO: dacScan step from 0 .. 19
[09:15:26.231] <TB3> INFO: Test took 22566ms.
[09:15:26.273] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:26.273] <TB3> INFO: dacScan step from 20 .. 39
[09:15:52.398] <TB3> INFO: Test took 26125ms.
[09:15:52.571] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:52.591] <TB3> INFO: dacScan step from 40 .. 59
[09:16:23.635] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)
[09:16:23.635] <TB3> WARNING: ROC 2: Readback start marker after 15 readouts!
[09:16:23.635] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!
[09:16:24.644] <TB3> INFO: Test took 32053ms.
[09:16:24.923] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:24.977] <TB3> INFO: dacScan step from 60 .. 65
[09:16:36.212] <TB3> INFO: Test took 11235ms.
[09:16:36.304] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:51.479] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 63 (20) hits flags = 16 (plus default)
[09:16:51.488] <TB3> INFO: dacScan step from 0 .. 19
[09:17:14.149] <TB3> INFO: Test took 22661ms.
[09:17:14.188] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:14.189] <TB3> INFO: dacScan step from 20 .. 39
[09:17:41.869] <TB3> INFO: Test took 27680ms.
[09:17:42.096] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:42.123] <TB3> INFO: dacScan step from 40 .. 59
[09:18:13.757] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)
[09:18:13.757] <TB3> WARNING: ROC 2: Readback start marker after 15 readouts!
[09:18:13.757] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!
[09:18:14.985] <TB3> INFO: Test took 32862ms.
[09:18:15.262] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:15.319] <TB3> INFO: dacScan step from 60 .. 63
[09:18:23.576] <TB3> INFO: Test took 8256ms.
[09:18:23.631] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:38.740] <TB3> INFO: PixTestTrim::trimBitTest() done
[09:18:38.742] <TB3> INFO: PixTestTrim::doTest() done, duration: 1836 seconds
[09:18:39.484] <TB3> INFO: ######################################################################
[09:18:39.484] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[09:18:39.484] <TB3> INFO: ######################################################################
[09:18:42.921] <TB3> INFO: Test took 3435ms.
[09:18:42.943] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:46.595] <TB3> INFO: Test took 3454ms.
[09:18:46.668] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:50.102] <TB3> INFO: Test took 3422ms.
[09:18:50.166] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:50.175] <TB3> INFO: The DUT currently contains the following objects:
[09:18:50.175] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:18:50.175] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:18:50.175] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:18:50.175] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:18:50.175] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:50.175] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.285] <TB3> INFO: Test took 1110ms.
[09:18:51.286] <TB3> INFO: The DUT currently contains the following objects:
[09:18:51.286] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:18:51.286] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:18:51.286] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:18:51.286] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:18:51.286] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:51.287] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.395] <TB3> INFO: Test took 1108ms.
[09:18:52.397] <TB3> INFO: The DUT currently contains the following objects:
[09:18:52.397] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:18:52.397] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:18:52.397] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:18:52.397] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:18:52.397] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.397] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.397] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.397] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.397] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.397] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.397] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.397] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.397] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.397] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.397] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.397] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.397] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.397] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.398] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:52.398] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.505] <TB3> INFO: Test took 1107ms.
[09:18:53.506] <TB3> INFO: The DUT currently contains the following objects:
[09:18:53.506] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:18:53.506] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:18:53.506] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:18:53.506] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:18:53.506] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.506] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.506] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.506] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.506] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.506] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.506] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.506] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.507] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.507] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.507] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.507] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.507] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.507] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.507] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:53.507] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.615] <TB3> INFO: Test took 1108ms.
[09:18:54.617] <TB3> INFO: The DUT currently contains the following objects:
[09:18:54.617] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:18:54.617] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:18:54.617] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:18:54.617] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:18:54.617] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.617] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.618] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.618] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.618] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.618] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.618] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.618] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.618] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.618] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.618] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.618] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.618] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.618] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.618] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:54.618] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.725] <TB3> INFO: Test took 1107ms.
[09:18:55.726] <TB3> INFO: The DUT currently contains the following objects:
[09:18:55.726] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:18:55.726] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:18:55.726] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:18:55.726] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:18:55.726] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.726] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.726] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.726] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.727] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.727] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.727] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.727] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.727] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.727] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.727] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.727] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.727] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.727] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.727] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:55.727] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.835] <TB3> INFO: Test took 1108ms.
[09:18:56.836] <TB3> INFO: The DUT currently contains the following objects:
[09:18:56.836] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:18:56.836] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:18:56.836] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:18:56.836] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:18:56.836] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:56.836] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.944] <TB3> INFO: Test took 1108ms.
[09:18:57.945] <TB3> INFO: The DUT currently contains the following objects:
[09:18:57.945] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:18:57.945] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:18:57.945] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:18:57.945] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:18:57.945] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.945] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.946] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.946] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.946] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.946] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.946] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.946] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.946] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.946] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.946] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.946] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.946] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.946] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.946] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:57.946] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.053] <TB3> INFO: Test took 1107ms.
[09:18:59.054] <TB3> INFO: The DUT currently contains the following objects:
[09:18:59.054] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:18:59.054] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:18:59.054] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:18:59.055] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:18:59.055] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:18:59.055] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.164] <TB3> INFO: Test took 1109ms.
[09:19:00.165] <TB3> INFO: The DUT currently contains the following objects:
[09:19:00.165] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:19:00.165] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:19:00.165] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:19:00.165] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:19:00.165] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.165] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.165] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.165] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.165] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.165] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.165] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.166] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.166] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.166] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.166] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.166] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.166] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.166] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.166] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:00.166] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.273] <TB3> INFO: Test took 1107ms.
[09:19:01.274] <TB3> INFO: The DUT currently contains the following objects:
[09:19:01.274] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:19:01.274] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:19:01.274] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:19:01.274] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:19:01.274] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.274] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.274] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.274] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.275] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.275] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.275] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.275] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.275] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.275] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.275] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.275] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.275] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.275] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.275] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:01.275] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.383] <TB3> INFO: Test took 1108ms.
[09:19:02.385] <TB3> INFO: The DUT currently contains the following objects:
[09:19:02.385] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:19:02.385] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:19:02.385] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:19:02.385] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:19:02.385] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.385] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.385] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.385] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.385] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.385] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.385] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.385] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.385] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.385] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.385] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.385] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.386] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.386] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.386] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:02.386] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.493] <TB3> INFO: Test took 1107ms.
[09:19:03.494] <TB3> INFO: The DUT currently contains the following objects:
[09:19:03.494] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:19:03.494] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:19:03.494] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:19:03.494] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:19:03.494] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.494] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.494] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.494] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.494] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.494] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.494] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.494] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.494] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.494] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.494] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.495] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.495] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.495] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.495] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:03.495] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.598] <TB3> INFO: Test took 1103ms.
[09:19:04.599] <TB3> INFO: The DUT currently contains the following objects:
[09:19:04.599] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:19:04.599] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:19:04.599] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:19:04.599] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:19:04.599] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.599] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.599] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.599] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.599] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.599] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.599] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.599] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.599] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.600] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.600] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.600] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.600] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.600] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.600] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:04.600] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.709] <TB3> INFO: Test took 1109ms.
[09:19:05.710] <TB3> INFO: The DUT currently contains the following objects:
[09:19:05.710] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:19:05.710] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:19:05.710] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:19:05.710] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:19:05.710] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.710] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.710] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.710] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.710] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.710] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.710] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.711] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.711] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.711] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.711] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.711] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.711] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.711] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.711] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:05.711] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.818] <TB3> INFO: Test took 1107ms.
[09:19:06.820] <TB3> INFO: The DUT currently contains the following objects:
[09:19:06.820] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:19:06.820] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:19:06.820] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:19:06.820] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:19:06.820] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.820] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.820] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.820] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.820] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.820] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.820] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.820] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.820] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.820] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.820] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.821] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.821] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.821] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.821] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:06.821] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:19:07.928] <TB3> INFO: Test took 1107ms.
[09:19:07.932] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:22:45.575] <TB3> INFO: Test took 217643ms.
[09:22:47.124] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:25.413] <TB3> INFO: Test took 218289ms.
[09:26:27.251] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.259] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.268] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.276] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.285] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.294] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.303] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.312] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.321] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.329] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.338] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.347] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.355] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.364] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[09:26:27.372] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[09:26:27.381] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.390] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.398] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:27.446] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:26:27.446] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:26:27.446] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:26:27.446] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:26:27.446] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:26:27.446] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:26:27.446] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:26:27.447] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:26:27.447] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:26:27.447] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:26:27.447] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:26:27.447] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:26:27.447] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:26:27.447] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:26:27.447] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:26:27.448] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:26:30.913] <TB3> INFO: Test took 3462ms.
[09:26:34.670] <TB3> INFO: Test took 3450ms.
[09:26:38.376] <TB3> INFO: Test took 3431ms.
[09:26:38.666] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:39.585] <TB3> INFO: Test took 919ms.
[09:26:39.589] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:40.698] <TB3> INFO: Test took 1109ms.
[09:26:40.702] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:41.823] <TB3> INFO: Test took 1121ms.
[09:26:41.826] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:42.929] <TB3> INFO: Test took 1103ms.
[09:26:42.931] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:44.041] <TB3> INFO: Test took 1110ms.
[09:26:44.045] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:45.152] <TB3> INFO: Test took 1107ms.
[09:26:45.155] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:46.265] <TB3> INFO: Test took 1110ms.
[09:26:46.269] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:47.377] <TB3> INFO: Test took 1108ms.
[09:26:47.380] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:48.487] <TB3> INFO: Test took 1107ms.
[09:26:48.491] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:49.599] <TB3> INFO: Test took 1108ms.
[09:26:49.603] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:50.711] <TB3> INFO: Test took 1108ms.
[09:26:50.714] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:51.822] <TB3> INFO: Test took 1108ms.
[09:26:51.825] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:52.934] <TB3> INFO: Test took 1109ms.
[09:26:52.938] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:54.048] <TB3> INFO: Test took 1110ms.
[09:26:54.051] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:55.174] <TB3> INFO: Test took 1123ms.
[09:26:55.178] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:56.287] <TB3> INFO: Test took 1110ms.
[09:26:56.290] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:57.398] <TB3> INFO: Test took 1108ms.
[09:26:57.401] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:58.509] <TB3> INFO: Test took 1108ms.
[09:26:58.512] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:59.620] <TB3> INFO: Test took 1108ms.
[09:26:59.624] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:00.731] <TB3> INFO: Test took 1108ms.
[09:27:00.735] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:01.843] <TB3> INFO: Test took 1108ms.
[09:27:01.846] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:02.953] <TB3> INFO: Test took 1107ms.
[09:27:02.956] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:04.065] <TB3> INFO: Test took 1109ms.
[09:27:04.069] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:05.178] <TB3> INFO: Test took 1110ms.
[09:27:05.181] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:06.289] <TB3> INFO: Test took 1108ms.
[09:27:06.292] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:07.402] <TB3> INFO: Test took 1110ms.
[09:27:07.405] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:08.513] <TB3> INFO: Test took 1108ms.
[09:27:08.516] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:09.623] <TB3> INFO: Test took 1107ms.
[09:27:09.627] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:10.736] <TB3> INFO: Test took 1109ms.
[09:27:10.739] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:11.847] <TB3> INFO: Test took 1109ms.
[09:27:11.851] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:12.960] <TB3> INFO: Test took 1110ms.
[09:27:12.964] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:14.070] <TB3> INFO: Test took 1107ms.
[09:27:14.616] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 515 seconds
[09:27:14.616] <TB3> INFO: PH scale (per ROC): 104 90 80 88 84 93 83 84 94 81 94 90 80 95 92 98
[09:27:14.616] <TB3> INFO: PH offset (per ROC): 162 148 142 155 153 156 154 154 129 162 161 157 149 150 167 146
[09:27:14.808] <TB3> INFO: ######################################################################
[09:27:14.808] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[09:27:14.808] <TB3> INFO: ######################################################################
[09:27:14.819] <TB3> INFO: scanning low vcal = 50
[09:27:19.209] <TB3> INFO: Test took 4390ms.
[09:27:19.274] <TB3> INFO: scanning low vcal = 100
[09:27:23.629] <TB3> INFO: Test took 4355ms.
[09:27:23.695] <TB3> INFO: scanning low vcal = 150
[09:27:28.035] <TB3> INFO: Test took 4340ms.
[09:27:28.104] <TB3> INFO: scanning low vcal = 200
[09:27:32.426] <TB3> INFO: Test took 4322ms.
[09:27:32.488] <TB3> INFO: scanning low vcal = 250
[09:27:36.855] <TB3> INFO: Test took 4367ms.
[09:27:36.916] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[09:27:41.260] <TB3> INFO: Test took 4344ms.
[09:27:41.319] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[09:27:45.677] <TB3> INFO: Test took 4358ms.
[09:27:45.738] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[09:27:50.007] <TB3> INFO: Test took 4269ms.
[09:27:50.088] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[09:27:54.287] <TB3> INFO: Test took 4199ms.
[09:27:54.341] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[09:27:58.556] <TB3> INFO: Test took 4215ms.
[09:27:58.949] <TB3> INFO: PixTestGainPedestal::measure() done
[09:28:23.952] <TB3> INFO: PixTestGainPedestal::fit() done
[09:28:23.952] <TB3> INFO: non-linearity mean: 0.959 0.952 0.957 0.953 0.954 0.955 0.956 0.950 0.960 0.958 0.953 0.955 0.959 0.947 0.953 0.953
[09:28:23.952] <TB3> INFO: non-linearity RMS: 0.005 0.005 0.005 0.005 0.005 0.006 0.005 0.007 0.005 0.005 0.005 0.005 0.004 0.006 0.005 0.006
[09:28:23.952] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[09:28:23.972] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[09:28:23.991] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[09:28:24.009] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[09:28:24.040] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[09:28:24.072] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[09:28:24.105] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[09:28:24.137] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[09:28:24.171] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[09:28:24.202] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[09:28:24.232] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[09:28:24.261] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[09:28:24.294] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[09:28:24.327] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[09:28:24.345] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[09:28:24.375] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2029_FullQualification_2015-07-07_10h14m_1436256858//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[09:28:24.398] <TB3> INFO: PixTestGainPedestal::doTest() done, duration: 69 seconds
[09:28:24.404] <TB3> INFO: enter test to run
[09:28:24.404] <TB3> INFO: test: exit no parameter change
[09:28:24.905] <TB3> QUIET: Connection to board 170 closed.
[09:28:24.984] <TB3> INFO: pXar: this is the end, my friend