[08:53:07.698] <TB1> INFO: *** Welcome to pxar ***
[08:53:07.698] <TB1> INFO: *** Today: 2015/08/28
[08:53:07.698] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C15.dat
[08:53:07.699] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:53:07.699] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//defaultMaskFile.dat
[08:53:07.699] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters_C15.dat
[08:53:07.785] <TB1> INFO: clk: 4
[08:53:07.785] <TB1> INFO: ctr: 4
[08:53:07.785] <TB1> INFO: sda: 19
[08:53:07.785] <TB1> INFO: tin: 9
[08:53:07.785] <TB1> INFO: level: 15
[08:53:07.785] <TB1> INFO: triggerdelay: 0
[08:53:07.785] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:53:07.785] <TB1> INFO: Log level: INFO
[08:53:07.793] <TB1> INFO: Found DTB DTB_WXBYFL
[08:53:07.805] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[08:53:07.808] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[08:53:07.811] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[08:53:09.346] <TB1> INFO: DUT info:
[08:53:09.346] <TB1> INFO: The DUT currently contains the following objects:
[08:53:09.346] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:53:09.346] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:53:09.346] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:53:09.346] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:53:09.346] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.346] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:09.748] <TB1> INFO: enter 'restricted' command line mode
[08:53:09.748] <TB1> INFO: enter test to run
[08:53:09.748] <TB1> INFO: test: pretest no parameter change
[08:53:09.748] <TB1> INFO: running: pretest
[08:53:09.754] <TB1> INFO: ######################################################################
[08:53:09.755] <TB1> INFO: PixTestPretest::doTest()
[08:53:09.755] <TB1> INFO: ######################################################################
[08:53:09.756] <TB1> INFO: ----------------------------------------------------------------------
[08:53:09.756] <TB1> INFO: PixTestPretest::programROC()
[08:53:09.756] <TB1> INFO: ----------------------------------------------------------------------
[08:53:27.779] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:53:27.779] <TB1> INFO: IA differences per ROC: 19.3 14.5 18.5 19.3 17.7 18.5 18.5 18.5 20.9 16.9 20.1 19.3 17.7 16.1 17.7 19.3
[08:53:27.875] <TB1> INFO: ----------------------------------------------------------------------
[08:53:27.876] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:53:27.876] <TB1> INFO: ----------------------------------------------------------------------
[08:53:47.475] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 388.3 mA = 24.2687 mA/ROC
[08:53:47.477] <TB1> INFO: ----------------------------------------------------------------------
[08:53:47.477] <TB1> INFO: PixTestPretest::findTiming()
[08:53:47.477] <TB1> INFO: ----------------------------------------------------------------------
[08:53:47.477] <TB1> INFO: PixTestCmd::init()

[08:55:30.609] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[08:55:30.609] <TB1> INFO: (success/tries = 100/100), width = 5
[08:55:30.611] <TB1> INFO: ----------------------------------------------------------------------
[08:55:30.611] <TB1> INFO: PixTestPretest::findWorkingPixel()
[08:55:30.611] <TB1> INFO: ----------------------------------------------------------------------
[08:55:30.750] <TB1> INFO: Expecting 231680 events.
[08:55:39.333] <TB1> INFO: 231680 events read in total (7866ms).
[08:55:39.337] <TB1> INFO: Test took 8723ms.
[08:55:39.667] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:55:39.716] <TB1> INFO: ----------------------------------------------------------------------
[08:55:39.716] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[08:55:39.716] <TB1> INFO: ----------------------------------------------------------------------
[08:55:39.856] <TB1> INFO: Expecting 231680 events.
[08:55:49.137] <TB1> INFO: 231680 events read in total (8564ms).
[08:55:49.141] <TB1> INFO: Test took 9419ms.
[08:55:49.487] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[08:55:49.487] <TB1> INFO: CalDel: 163 150 153 159 156 133 159 138 135 138 137 138 144 119 133 141
[08:55:49.487] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:55:49.491] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C0.dat
[08:55:49.491] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C1.dat
[08:55:49.491] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C2.dat
[08:55:49.492] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C3.dat
[08:55:49.492] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C4.dat
[08:55:49.492] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C5.dat
[08:55:49.493] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C6.dat
[08:55:49.493] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C7.dat
[08:55:49.493] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C8.dat
[08:55:49.494] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C9.dat
[08:55:49.494] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C10.dat
[08:55:49.494] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C11.dat
[08:55:49.495] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C12.dat
[08:55:49.495] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C13.dat
[08:55:49.495] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C14.dat
[08:55:49.495] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters_C15.dat
[08:55:49.495] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//tbmParameters_C0a.dat
[08:55:49.496] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:55:49.496] <TB1> INFO: PixTestPretest::doTest() done, duration: 159 seconds
[08:55:49.563] <TB1> INFO: enter test to run
[08:55:49.563] <TB1> INFO: test: fulltest no parameter change
[08:55:49.563] <TB1> INFO: running: fulltest
[08:55:49.563] <TB1> INFO: ######################################################################
[08:55:49.563] <TB1> INFO: PixTestFullTest::doTest()
[08:55:49.563] <TB1> INFO: ######################################################################
[08:55:49.565] <TB1> INFO: ######################################################################
[08:55:49.565] <TB1> INFO: PixTestAlive::doTest()
[08:55:49.565] <TB1> INFO: ######################################################################
[08:55:49.567] <TB1> INFO: ----------------------------------------------------------------------
[08:55:49.567] <TB1> 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:55:49.567] <TB1> INFO: ----------------------------------------------------------------------
[08:55:49.883] <TB1> INFO: Expecting 41600 events.
[08:55:54.315] <TB1> INFO: 41600 events read in total (3715ms).
[08:55:54.315] <TB1> INFO: Test took 4746ms.
[08:55:54.321] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:55:54.626] <TB1> INFO: PixTestAlive::aliveTest() done
[08:55:54.626] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:55:54.628] <TB1> INFO: ----------------------------------------------------------------------
[08:55:54.628] <TB1> 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:55:54.628] <TB1> INFO: ----------------------------------------------------------------------
[08:55:54.942] <TB1> INFO: Expecting 41600 events.
[08:55:58.109] <TB1> INFO: 41600 events read in total (2450ms).
[08:55:58.109] <TB1> INFO: Test took 3479ms.
[08:55:58.109] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:55:58.110] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:55:58.438] <TB1> INFO: PixTestAlive::maskTest() done
[08:55:58.438] <TB1> 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:55:58.440] <TB1> INFO: ----------------------------------------------------------------------
[08:55:58.440] <TB1> 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:55:58.440] <TB1> INFO: ----------------------------------------------------------------------
[08:55:58.777] <TB1> INFO: Expecting 41600 events.
[08:56:03.225] <TB1> INFO: 41600 events read in total (3731ms).
[08:56:03.226] <TB1> INFO: Test took 4783ms.
[08:56:03.233] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:56:03.529] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[08:56:03.529] <TB1> 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:56:03.529] <TB1> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[08:56:03.539] <TB1> INFO: ######################################################################
[08:56:03.539] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:56:03.539] <TB1> INFO: ######################################################################
[08:56:03.544] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 2 (plus default)
[08:56:03.554] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:56:03.554] <TB1> INFO: run 1 of 1
[08:56:03.858] <TB1> INFO: Expecting 3120000 events.
[08:56:38.828] <TB1> INFO: 835600 events read in total (34252ms).
[08:57:12.970] <TB1> INFO: 1657160 events read in total (68394ms).
[08:57:47.237] <TB1> INFO: 2487735 events read in total (102661ms).
[08:58:13.323] <TB1> INFO: 3120000 events read in total (128747ms).
[08:58:13.372] <TB1> INFO: Test took 129818ms.
[08:58:13.481] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:58:37.290] <TB1> INFO: PixTestBBMap::doTest() done, duration: 153 seconds
[08:58:37.290] <TB1> INFO: number of dead bumps (per ROC): 5 7 1 1 1 0 0 1 0 0 0 0 0 2 4 8
[08:58:37.290] <TB1> INFO: separation cut (per ROC): 89 74 91 93 78 77 89 86 94 85 79 83 75 70 69 90
[08:58:37.369] <TB1> INFO: ######################################################################
[08:58:37.369] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:58:37.369] <TB1> INFO: ######################################################################
[08:58:37.369] <TB1> INFO: ----------------------------------------------------------------------
[08:58:37.370] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:58:37.370] <TB1> INFO: ----------------------------------------------------------------------
[08:58:37.370] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (-1/-1) hits flags = 16 (plus default)
[08:58:37.378] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[08:58:37.378] <TB1> INFO: run 1 of 1
[08:58:37.694] <TB1> INFO: Expecting 31200000 events.
[08:59:00.093] <TB1> INFO: 929750 events read in total (21682ms).
[08:59:23.854] <TB1> INFO: 1849900 events read in total (45443ms).
[08:59:47.588] <TB1> INFO: 2764250 events read in total (69177ms).
[09:00:11.321] <TB1> INFO: 3680600 events read in total (92910ms).
[09:00:35.172] <TB1> INFO: 4593750 events read in total (116761ms).
[09:00:58.835] <TB1> INFO: 5508600 events read in total (140424ms).
[09:01:22.620] <TB1> INFO: 6419750 events read in total (164209ms).
[09:01:46.514] <TB1> INFO: 7334900 events read in total (188103ms).
[09:02:10.330] <TB1> INFO: 8245800 events read in total (211919ms).
[09:02:34.214] <TB1> INFO: 9156250 events read in total (235803ms).
[09:02:58.069] <TB1> INFO: 10067400 events read in total (259658ms).
[09:03:21.902] <TB1> INFO: 10980900 events read in total (283491ms).
[09:03:45.713] <TB1> INFO: 11890300 events read in total (307302ms).
[09:04:09.429] <TB1> INFO: 12800800 events read in total (331018ms).
[09:04:33.158] <TB1> INFO: 13709350 events read in total (354747ms).
[09:04:56.994] <TB1> INFO: 14619100 events read in total (378583ms).
[09:05:20.867] <TB1> INFO: 15524900 events read in total (402456ms).
[09:05:44.786] <TB1> INFO: 16426150 events read in total (426375ms).
[09:06:08.733] <TB1> INFO: 17326500 events read in total (450322ms).
[09:06:32.515] <TB1> INFO: 18228200 events read in total (474104ms).
[09:06:56.205] <TB1> INFO: 19127400 events read in total (497794ms).
[09:07:19.943] <TB1> INFO: 20024950 events read in total (521532ms).
[09:07:43.693] <TB1> INFO: 20922850 events read in total (545282ms).
[09:08:07.429] <TB1> INFO: 21822250 events read in total (569018ms).
[09:08:31.308] <TB1> INFO: 22719850 events read in total (592897ms).
[09:08:55.006] <TB1> INFO: 23616200 events read in total (616595ms).
[09:09:18.758] <TB1> INFO: 24512650 events read in total (640347ms).
[09:09:42.558] <TB1> INFO: 25410500 events read in total (664147ms).
[09:10:06.330] <TB1> INFO: 26308300 events read in total (687919ms).
[09:10:30.074] <TB1> INFO: 27206450 events read in total (711663ms).
[09:10:53.751] <TB1> INFO: 28103500 events read in total (735340ms).
[09:11:17.581] <TB1> INFO: 29006400 events read in total (759170ms).
[09:11:41.382] <TB1> INFO: 29907200 events read in total (782971ms).
[09:12:05.243] <TB1> INFO: 30814550 events read in total (806832ms).
[09:12:15.495] <TB1> INFO: 31200000 events read in total (817084ms).
[09:12:15.528] <TB1> INFO: Test took 818150ms.
[09:12:15.621] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:15.729] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:17.194] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:18.695] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:20.132] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:21.690] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:23.180] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:24.623] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:26.046] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:27.549] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:28.991] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:30.473] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:31.947] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:33.349] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:34.754] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:36.257] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:37.790] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:12:39.330] <TB1> INFO: PixTestScurves::scurves() done
[09:12:39.330] <TB1> INFO: Vcal mean: 84.23 75.34 91.92 93.80 82.57 87.73 95.82 100.28 92.70 81.10 87.82 93.49 81.60 70.59 71.45 95.95
[09:12:39.330] <TB1> INFO: Vcal RMS: 4.80 4.85 6.38 6.71 4.47 6.43 7.08 5.30 5.72 4.87 5.62 5.64 4.48 4.73 4.44 5.74
[09:12:39.330] <TB1> INFO: PixTestScurves::fullTest() done, duration: 841 seconds
[09:12:39.404] <TB1> INFO: ######################################################################
[09:12:39.404] <TB1> INFO: PixTestTrim::doTest()
[09:12:39.404] <TB1> INFO: ######################################################################
[09:12:39.406] <TB1> INFO: ----------------------------------------------------------------------
[09:12:39.406] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[09:12:39.406] <TB1> INFO: ----------------------------------------------------------------------
[09:12:39.492] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:12:39.492] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 20 dacrange: 0 .. 159 (-1/-1) hits flags = 16 (plus default)
[09:12:39.500] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[09:12:39.500] <TB1> INFO: run 1 of 1
[09:12:39.816] <TB1> INFO: Expecting 13312000 events.
[09:13:05.944] <TB1> INFO: 1063020 events read in total (25407ms).
[09:13:33.101] <TB1> INFO: 2120460 events read in total (52564ms).
[09:13:59.154] <TB1> INFO: 3173940 events read in total (78617ms).
[09:14:25.528] <TB1> INFO: 4225860 events read in total (104991ms).
[09:14:52.821] <TB1> INFO: 5273540 events read in total (132284ms).
[09:15:20.138] <TB1> INFO: 6319520 events read in total (159601ms).
[09:15:47.487] <TB1> INFO: 7369780 events read in total (186950ms).
[09:16:15.042] <TB1> INFO: 8423820 events read in total (214505ms).
[09:16:42.507] <TB1> INFO: 9477300 events read in total (241970ms).
[09:17:09.988] <TB1> INFO: 10532860 events read in total (269451ms).
[09:17:37.442] <TB1> INFO: 11589140 events read in total (296905ms).
[09:18:04.975] <TB1> INFO: 12644000 events read in total (324438ms).
[09:18:22.426] <TB1> INFO: 13312000 events read in total (341889ms).
[09:18:22.463] <TB1> INFO: Test took 342963ms.
[09:18:22.523] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:44.854] <TB1> INFO: ROC 0 VthrComp = 94
[09:18:44.854] <TB1> INFO: ROC 1 VthrComp = 77
[09:18:44.854] <TB1> INFO: ROC 2 VthrComp = 96
[09:18:44.854] <TB1> INFO: ROC 3 VthrComp = 97
[09:18:44.854] <TB1> INFO: ROC 4 VthrComp = 85
[09:18:44.855] <TB1> INFO: ROC 5 VthrComp = 86
[09:18:44.855] <TB1> INFO: ROC 6 VthrComp = 96
[09:18:44.855] <TB1> INFO: ROC 7 VthrComp = 99
[09:18:44.855] <TB1> INFO: ROC 8 VthrComp = 99
[09:18:44.855] <TB1> INFO: ROC 9 VthrComp = 85
[09:18:44.855] <TB1> INFO: ROC 10 VthrComp = 95
[09:18:44.856] <TB1> INFO: ROC 11 VthrComp = 98
[09:18:44.856] <TB1> INFO: ROC 12 VthrComp = 87
[09:18:44.856] <TB1> INFO: ROC 13 VthrComp = 78
[09:18:44.856] <TB1> INFO: ROC 14 VthrComp = 76
[09:18:44.856] <TB1> INFO: ROC 15 VthrComp = 100
[09:18:44.856] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[09:18:44.856] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 20 dacrange: 0 .. 159 (-1/-1) hits flags = 16 (plus default)
[09:18:44.864] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[09:18:44.865] <TB1> INFO: run 1 of 1
[09:18:45.190] <TB1> INFO: Expecting 13312000 events.
[09:19:10.431] <TB1> INFO: 778600 events read in total (24518ms).
[09:19:35.353] <TB1> INFO: 1553940 events read in total (49440ms).
[09:20:00.195] <TB1> INFO: 2329060 events read in total (74282ms).
[09:20:25.043] <TB1> INFO: 3104840 events read in total (99130ms).
[09:20:47.282] <TB1> INFO: 3879580 events read in total (121369ms).
[09:21:12.050] <TB1> INFO: 4654960 events read in total (146137ms).
[09:21:36.989] <TB1> INFO: 5429960 events read in total (171076ms).
[09:22:01.920] <TB1> INFO: 6205500 events read in total (196007ms).
[09:22:26.782] <TB1> INFO: 6977720 events read in total (220869ms).
[09:22:51.505] <TB1> INFO: 7746760 events read in total (245592ms).
[09:23:16.289] <TB1> INFO: 8514720 events read in total (270376ms).
[09:23:41.233] <TB1> INFO: 9281620 events read in total (295320ms).
[09:24:05.918] <TB1> INFO: 10047180 events read in total (320005ms).
[09:24:28.306] <TB1> INFO: 10812620 events read in total (342393ms).
[09:24:51.000] <TB1> INFO: 11578180 events read in total (365087ms).
[09:25:13.710] <TB1> INFO: 12344020 events read in total (387797ms).
[09:25:36.501] <TB1> INFO: 13110060 events read in total (410588ms).
[09:25:43.018] <TB1> INFO: 13312000 events read in total (417105ms).
[09:25:43.084] <TB1> INFO: Test took 418219ms.
[09:25:43.232] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:09.490] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 56.3357 for pixel 21/2 mean/min/max = 44.1602/31.5883/56.7321
[09:26:09.491] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 60.7769 for pixel 14/0 mean/min/max = 47.5398/34.1259/60.9538
[09:26:09.491] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 61.7222 for pixel 4/8 mean/min/max = 46.4742/31.0946/61.8539
[09:26:09.491] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 62.4096 for pixel 21/1 mean/min/max = 46.7616/31.0875/62.4357
[09:26:09.492] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 56.5808 for pixel 3/20 mean/min/max = 44.448/32.2808/56.6153
[09:26:09.492] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 64.7818 for pixel 1/3 mean/min/max = 47.8116/30.7067/64.9164
[09:26:09.492] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 60.9493 for pixel 3/27 mean/min/max = 46.131/31.0906/61.1714
[09:26:09.493] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 60.6703 for pixel 7/3 mean/min/max = 46.505/32.311/60.6991
[09:26:09.493] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 59.8262 for pixel 0/76 mean/min/max = 45.7217/31.3831/60.0602
[09:26:09.493] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 56.0423 for pixel 5/78 mean/min/max = 44.2619/31.9967/56.527
[09:26:09.494] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 57.9075 for pixel 21/0 mean/min/max = 44.4813/30.979/57.9836
[09:26:09.494] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 57.8836 for pixel 6/4 mean/min/max = 44.8976/31.6587/58.1366
[09:26:09.494] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 57.1602 for pixel 17/61 mean/min/max = 44.7975/32.3563/57.2386
[09:26:09.495] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 56.6152 for pixel 0/67 mean/min/max = 45.4798/34.2652/56.6945
[09:26:09.495] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 56.9181 for pixel 0/74 mean/min/max = 46.0596/34.9985/57.1207
[09:26:09.495] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 59.5111 for pixel 12/1 mean/min/max = 45.5407/31.5006/59.5808
[09:26:09.496] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:09.629] <TB1> INFO: Expecting 1029120 events.
[09:26:33.985] <TB1> INFO: 1029120 events read in total (23635ms).
[09:26:33.992] <TB1> INFO: Expecting 1029120 events.
[09:26:58.038] <TB1> INFO: 1029120 events read in total (23518ms).
[09:26:58.049] <TB1> INFO: Expecting 1029120 events.
[09:27:21.976] <TB1> INFO: 1029120 events read in total (23391ms).
[09:27:21.985] <TB1> INFO: Expecting 1029120 events.
[09:27:46.009] <TB1> INFO: 1029120 events read in total (23492ms).
[09:27:46.022] <TB1> INFO: Expecting 1029120 events.
[09:28:09.831] <TB1> INFO: 1029120 events read in total (23281ms).
[09:28:09.845] <TB1> INFO: Expecting 1029120 events.
[09:28:33.708] <TB1> INFO: 1029120 events read in total (23330ms).
[09:28:33.727] <TB1> INFO: Expecting 1029120 events.
[09:28:55.265] <TB1> INFO: 1029120 events read in total (21009ms).
[09:28:55.290] <TB1> INFO: Expecting 1029120 events.
[09:29:19.222] <TB1> INFO: 1029120 events read in total (23404ms).
[09:29:19.242] <TB1> INFO: Expecting 1029120 events.
[09:29:43.104] <TB1> INFO: 1029120 events read in total (23333ms).
[09:29:43.129] <TB1> INFO: Expecting 1029120 events.
[09:30:06.974] <TB1> INFO: 1029120 events read in total (23316ms).
[09:30:06.995] <TB1> INFO: Expecting 1029120 events.
[09:30:31.224] <TB1> INFO: 1029120 events read in total (23695ms).
[09:30:31.253] <TB1> INFO: Expecting 1029120 events.
[09:30:55.128] <TB1> INFO: 1029120 events read in total (23346ms).
[09:30:55.161] <TB1> INFO: Expecting 1029120 events.
[09:31:19.165] <TB1> INFO: 1029120 events read in total (23476ms).
[09:31:19.202] <TB1> INFO: Expecting 1029120 events.
[09:31:43.115] <TB1> INFO: 1029120 events read in total (23385ms).
[09:31:43.145] <TB1> INFO: Expecting 1029120 events.
[09:32:06.947] <TB1> INFO: 1029120 events read in total (23274ms).
[09:32:06.983] <TB1> INFO: Expecting 1029120 events.
[09:32:30.929] <TB1> INFO: 1029120 events read in total (23418ms).
[09:32:30.969] <TB1> INFO: Test took 381474ms.
[09:32:32.045] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 16 (plus default)
[09:32:32.053] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:32:32.053] <TB1> INFO: run 1 of 1
[09:32:32.359] <TB1> INFO: Expecting 8320000 events.
[09:33:00.092] <TB1> INFO: 723160 events read in total (27017ms).
[09:33:27.427] <TB1> INFO: 1445510 events read in total (54352ms).
[09:33:54.472] <TB1> INFO: 2167220 events read in total (81397ms).
[09:34:21.652] <TB1> INFO: 2888950 events read in total (108577ms).
[09:34:48.745] <TB1> INFO: 3610600 events read in total (135670ms).
[09:35:15.930] <TB1> INFO: 4330700 events read in total (162855ms).
[09:35:42.934] <TB1> INFO: 5045830 events read in total (189859ms).
[09:36:09.658] <TB1> INFO: 5759230 events read in total (216583ms).
[09:36:36.444] <TB1> INFO: 6471410 events read in total (243369ms).
[09:37:03.336] <TB1> INFO: 7182240 events read in total (270261ms).
[09:37:30.189] <TB1> INFO: 7893190 events read in total (297114ms).
[09:37:46.414] <TB1> INFO: 8320000 events read in total (313339ms).
[09:37:46.487] <TB1> INFO: Test took 314434ms.
[09:37:46.699] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:12.099] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.054396 .. 255.000000
[09:38:12.174] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 20 dacrange: 0 .. 255 (-1/-1) hits flags = 16 (plus default)
[09:38:12.182] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[09:38:12.182] <TB1> INFO: run 1 of 1
[09:38:12.486] <TB1> INFO: Expecting 21299200 events.
[09:38:37.674] <TB1> INFO: 711140 events read in total (24463ms).
[09:39:01.927] <TB1> INFO: 1423540 events read in total (48716ms).
[09:39:26.574] <TB1> INFO: 2136180 events read in total (73363ms).
[09:39:50.872] <TB1> INFO: 2848600 events read in total (97661ms).
[09:40:15.170] <TB1> INFO: 3560760 events read in total (121959ms).
[09:40:39.404] <TB1> INFO: 4273280 events read in total (146193ms).
[09:41:03.686] <TB1> INFO: 4985620 events read in total (170475ms).
[09:41:28.100] <TB1> INFO: 5698340 events read in total (194889ms).
[09:41:51.708] <TB1> INFO: 6410940 events read in total (218497ms).
[09:42:13.782] <TB1> INFO: 7123380 events read in total (240571ms).
[09:42:38.216] <TB1> INFO: 7835760 events read in total (265005ms).
[09:43:02.788] <TB1> INFO: 8548180 events read in total (289577ms).
[09:43:27.244] <TB1> INFO: 9260800 events read in total (314033ms).
[09:43:51.533] <TB1> INFO: 9973360 events read in total (338322ms).
[09:44:16.055] <TB1> INFO: 10686000 events read in total (362844ms).
[09:44:40.403] <TB1> INFO: 11398100 events read in total (387192ms).
[09:45:04.283] <TB1> INFO: 12110300 events read in total (411073ms).
[09:45:27.885] <TB1> INFO: 12822440 events read in total (434674ms).
[09:45:52.298] <TB1> INFO: 13534220 events read in total (459087ms).
[09:46:16.614] <TB1> INFO: 14245700 events read in total (483403ms).
[09:46:41.002] <TB1> INFO: 14956960 events read in total (507791ms).
[09:47:05.458] <TB1> INFO: 15668560 events read in total (532247ms).
[09:47:29.781] <TB1> INFO: 16378980 events read in total (556570ms).
[09:47:52.030] <TB1> INFO: 17089380 events read in total (578819ms).
[09:48:16.416] <TB1> INFO: 17800200 events read in total (603205ms).
[09:48:40.849] <TB1> INFO: 18510660 events read in total (627638ms).
[09:49:05.353] <TB1> INFO: 19221280 events read in total (652142ms).
[09:49:29.524] <TB1> INFO: 19931520 events read in total (676313ms).
[09:49:53.475] <TB1> INFO: 20641840 events read in total (700264ms).
[09:50:14.628] <TB1> INFO: 21299200 events read in total (721417ms).
[09:50:14.734] <TB1> INFO: Test took 722553ms.
[09:50:15.005] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:43.107] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 14.255755 .. 45.640250
[09:50:43.198] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 20 dacrange: 4 .. 55 (-1/-1) hits flags = 16 (plus default)
[09:50:43.207] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[09:50:43.207] <TB1> INFO: run 1 of 1
[09:50:43.536] <TB1> INFO: Expecting 4326400 events.
[09:51:10.941] <TB1> INFO: 943260 events read in total (26673ms).
[09:51:37.541] <TB1> INFO: 1886820 events read in total (53273ms).
[09:52:02.669] <TB1> INFO: 2828940 events read in total (78402ms).
[09:52:29.316] <TB1> INFO: 3768560 events read in total (105048ms).
[09:52:45.133] <TB1> INFO: 4326400 events read in total (120865ms).
[09:52:45.151] <TB1> INFO: Test took 121945ms.
[09:52:45.187] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:52:59.215] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 0.500000 .. 41.941356
[09:52:59.296] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 20 dacrange: 0 .. 51 (-1/-1) hits flags = 16 (plus default)
[09:52:59.304] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[09:52:59.304] <TB1> INFO: run 1 of 1
[09:52:59.618] <TB1> INFO: Expecting 4326400 events.
[09:53:25.065] <TB1> INFO: 1000740 events read in total (24729ms).
[09:53:52.274] <TB1> INFO: 2001640 events read in total (51938ms).
[09:54:17.570] <TB1> INFO: 3001000 events read in total (77234ms).
[09:54:42.589] <TB1> INFO: 3998720 events read in total (102253ms).
[09:54:51.154] <TB1> INFO: 4326400 events read in total (110818ms).
[09:54:51.168] <TB1> INFO: Test took 111864ms.
[09:54:51.199] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:04.141] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 0.500000 .. 41.941356
[09:55:04.219] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 20 dacrange: 0 .. 51 (-1/-1) hits flags = 16 (plus default)
[09:55:04.227] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[09:55:04.227] <TB1> INFO: run 1 of 1
[09:55:04.535] <TB1> INFO: Expecting 4326400 events.
[09:55:30.105] <TB1> INFO: 1000800 events read in total (24853ms).
[09:55:57.102] <TB1> INFO: 2001940 events read in total (51850ms).
[09:56:24.385] <TB1> INFO: 3002160 events read in total (79133ms).
[09:56:51.531] <TB1> INFO: 4002160 events read in total (106279ms).
[09:57:00.596] <TB1> INFO: 4326400 events read in total (115344ms).
[09:57:00.608] <TB1> INFO: Test took 116381ms.
[09:57:00.636] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:13.838] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:57:13.838] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 16 (plus default)
[09:57:13.846] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:57:13.846] <TB1> INFO: run 1 of 1
[09:57:14.163] <TB1> INFO: Expecting 1705600 events.
[09:57:44.364] <TB1> INFO: 878410 events read in total (29484ms).
[09:58:12.004] <TB1> INFO: 1705600 events read in total (57124ms).
[09:58:12.025] <TB1> INFO: Test took 58178ms.
[09:58:12.058] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:25.697] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:58:25.697] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:58:25.698] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:58:25.698] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:58:25.698] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:58:25.698] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:58:25.698] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:58:25.698] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:58:25.698] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:58:25.698] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:58:25.699] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:58:25.699] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:58:25.699] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:58:25.699] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:58:25.699] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:58:25.699] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:58:25.699] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:58:25.709] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:58:25.719] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:58:25.729] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:58:25.738] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:58:25.748] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:58:25.758] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:58:25.767] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:58:25.777] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:58:25.787] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:58:25.796] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:58:25.806] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:58:25.816] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:58:25.826] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:58:25.835] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:58:25.845] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:58:25.855] <TB1> INFO: PixTestTrim::trimTest() done
[09:58:25.855] <TB1> INFO: vtrim: 97 101 109 112 93 113 99 110 109 80 99 86 91 81 83 97
[09:58:25.855] <TB1> INFO: vthrcomp: 94 77 96 97 85 86 96 99 99 85 95 98 87 78 76 100
[09:58:25.855] <TB1> INFO: vcal mean: 34.89 34.94 34.89 34.91 34.82 34.92 34.93 34.89 34.97 34.93 34.91 34.87 34.92 34.99 34.92 34.92
[09:58:25.855] <TB1> INFO: vcal RMS: 0.72 0.82 0.88 0.84 0.78 0.93 0.83 1.05 0.79 0.78 0.79 0.84 0.75 0.70 0.70 0.96
[09:58:25.855] <TB1> INFO: bits mean: 9.57 9.09 9.26 9.34 9.62 9.35 8.90 9.15 8.72 9.07 9.54 9.31 9.40 8.27 8.13 9.15
[09:58:25.855] <TB1> INFO: bits RMS: 2.80 2.49 2.92 2.78 2.63 2.78 3.02 2.76 3.08 3.00 2.91 2.89 2.71 2.80 2.69 2.90
[09:58:25.864] <TB1> INFO: ----------------------------------------------------------------------
[09:58:25.864] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[09:58:25.864] <TB1> INFO: ----------------------------------------------------------------------
[09:58:25.868] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 16 (plus default)
[09:58:25.878] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:58:25.878] <TB1> INFO: run 1 of 1
[09:58:26.183] <TB1> INFO: Expecting 8320000 events.
[09:58:55.495] <TB1> INFO: 909610 events read in total (28595ms).
[09:59:24.364] <TB1> INFO: 1813200 events read in total (57464ms).
[09:59:53.344] <TB1> INFO: 2713550 events read in total (86444ms).
[10:00:22.321] <TB1> INFO: 3612360 events read in total (115421ms).
[10:00:51.279] <TB1> INFO: 4506800 events read in total (144379ms).
[10:01:20.057] <TB1> INFO: 5396250 events read in total (173157ms).
[10:01:48.732] <TB1> INFO: 6285370 events read in total (201832ms).
[10:02:17.513] <TB1> INFO: 7174120 events read in total (230613ms).
[10:02:44.962] <TB1> INFO: 8065900 events read in total (258062ms).
[10:02:52.830] <TB1> INFO: 8320000 events read in total (265930ms).
[10:02:52.880] <TB1> INFO: Test took 267002ms.
[10:02:52.991] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:19.713] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 164 (-1/-1) hits flags = 16 (plus default)
[10:03:19.721] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:03:19.721] <TB1> INFO: run 1 of 1
[10:03:20.025] <TB1> INFO: Expecting 6864000 events.
[10:03:50.207] <TB1> INFO: 944750 events read in total (29465ms).
[10:04:19.435] <TB1> INFO: 1882260 events read in total (58693ms).
[10:04:48.822] <TB1> INFO: 2816830 events read in total (88080ms).
[10:05:18.054] <TB1> INFO: 3745710 events read in total (117312ms).
[10:05:47.365] <TB1> INFO: 4669010 events read in total (146623ms).
[10:06:16.557] <TB1> INFO: 5591620 events read in total (175815ms).
[10:06:43.355] <TB1> INFO: 6515990 events read in total (202613ms).
[10:06:54.035] <TB1> INFO: 6864000 events read in total (213293ms).
[10:06:54.070] <TB1> INFO: Test took 214349ms.
[10:06:54.157] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:07:18.858] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 151 (-1/-1) hits flags = 16 (plus default)
[10:07:18.866] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:07:18.866] <TB1> INFO: run 1 of 1
[10:07:19.176] <TB1> INFO: Expecting 6323200 events.
[10:07:47.268] <TB1> INFO: 979740 events read in total (27374ms).
[10:08:17.232] <TB1> INFO: 1950350 events read in total (57338ms).
[10:08:47.093] <TB1> INFO: 2917820 events read in total (87199ms).
[10:09:16.779] <TB1> INFO: 3875240 events read in total (116885ms).
[10:09:46.480] <TB1> INFO: 4829280 events read in total (146586ms).
[10:10:16.170] <TB1> INFO: 5784560 events read in total (176276ms).
[10:10:31.619] <TB1> INFO: 6323200 events read in total (191725ms).
[10:10:31.650] <TB1> INFO: Test took 192783ms.
[10:10:31.723] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:10:53.634] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 152 (-1/-1) hits flags = 16 (plus default)
[10:10:53.642] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:10:53.642] <TB1> INFO: run 1 of 1
[10:10:53.949] <TB1> INFO: Expecting 6364800 events.
[10:11:24.484] <TB1> INFO: 975940 events read in total (29813ms).
[10:11:51.927] <TB1> INFO: 1943040 events read in total (57256ms).
[10:12:21.579] <TB1> INFO: 2906880 events read in total (86909ms).
[10:12:51.342] <TB1> INFO: 3861460 events read in total (116671ms).
[10:13:21.032] <TB1> INFO: 4812490 events read in total (146361ms).
[10:13:50.847] <TB1> INFO: 5764260 events read in total (176176ms).
[10:14:09.566] <TB1> INFO: 6364800 events read in total (194895ms).
[10:14:09.593] <TB1> INFO: Test took 195951ms.
[10:14:09.657] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:32.123] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 152 (-1/-1) hits flags = 16 (plus default)
[10:14:32.132] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:14:32.132] <TB1> INFO: run 1 of 1
[10:14:32.454] <TB1> INFO: Expecting 6364800 events.
[10:15:03.059] <TB1> INFO: 975180 events read in total (29886ms).
[10:15:32.886] <TB1> INFO: 1941460 events read in total (59713ms).
[10:16:00.132] <TB1> INFO: 2904810 events read in total (86959ms).
[10:16:29.783] <TB1> INFO: 3858350 events read in total (116610ms).
[10:16:59.484] <TB1> INFO: 4808450 events read in total (146311ms).
[10:17:29.155] <TB1> INFO: 5759420 events read in total (175982ms).
[10:17:48.028] <TB1> INFO: 6364800 events read in total (194855ms).
[10:17:48.063] <TB1> INFO: Test took 195931ms.
[10:17:48.129] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:11.045] <TB1> INFO: PixTestTrim::trimBitTest() done
[10:18:11.047] <TB1> INFO: PixTestTrim::doTest() done, duration: 3931 seconds
[10:18:11.785] <TB1> INFO: ######################################################################
[10:18:11.785] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[10:18:11.785] <TB1> INFO: ######################################################################
[10:18:12.098] <TB1> INFO: Expecting 41600 events.
[10:18:16.517] <TB1> INFO: 41600 events read in total (3702ms).
[10:18:16.517] <TB1> INFO: Test took 4730ms.
[10:18:16.523] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:17.107] <TB1> INFO: Expecting 41600 events.
[10:18:21.582] <TB1> INFO: 41600 events read in total (3759ms).
[10:18:21.582] <TB1> INFO: Test took 4797ms.
[10:18:21.588] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:21.937] <TB1> INFO: Expecting 41600 events.
[10:18:26.421] <TB1> INFO: 41600 events read in total (3767ms).
[10:18:26.422] <TB1> INFO: Test took 4810ms.
[10:18:26.428] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:26.437] <TB1> INFO: The DUT currently contains the following objects:
[10:18:26.437] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:26.437] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:26.437] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:26.437] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:26.437] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.437] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:26.773] <TB1> INFO: Expecting 2560 events.
[10:18:27.843] <TB1> INFO: 2560 events read in total (353ms).
[10:18:27.844] <TB1> INFO: Test took 1407ms.
[10:18:27.844] <TB1> INFO: The DUT currently contains the following objects:
[10:18:27.844] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:27.844] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:27.844] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:27.844] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:27.844] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.844] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.845] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.845] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.845] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.845] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.845] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.845] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.845] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.845] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.845] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.845] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.845] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.845] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.845] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:27.845] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:28.260] <TB1> INFO: Expecting 2560 events.
[10:18:29.327] <TB1> INFO: 2560 events read in total (350ms).
[10:18:29.327] <TB1> INFO: Test took 1482ms.
[10:18:29.328] <TB1> INFO: The DUT currently contains the following objects:
[10:18:29.328] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:29.328] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:29.328] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:29.328] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:29.328] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.328] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.328] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.328] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.328] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.328] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.328] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.328] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.328] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.328] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.328] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.328] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.328] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.328] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.328] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.329] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:29.743] <TB1> INFO: Expecting 2560 events.
[10:18:30.813] <TB1> INFO: 2560 events read in total (353ms).
[10:18:30.813] <TB1> INFO: Test took 1484ms.
[10:18:30.814] <TB1> INFO: The DUT currently contains the following objects:
[10:18:30.814] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:30.814] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:30.815] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:30.815] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:30.815] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:30.815] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:31.229] <TB1> INFO: Expecting 2560 events.
[10:18:32.300] <TB1> INFO: 2560 events read in total (354ms).
[10:18:32.300] <TB1> INFO: Test took 1485ms.
[10:18:32.300] <TB1> INFO: The DUT currently contains the following objects:
[10:18:32.300] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:32.300] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:32.300] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:32.300] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:32.300] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.300] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.300] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.300] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.300] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.300] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.301] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.301] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.301] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.301] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.301] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.301] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.301] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.301] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.301] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.301] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:32.716] <TB1> INFO: Expecting 2560 events.
[10:18:33.788] <TB1> INFO: 2560 events read in total (354ms).
[10:18:33.788] <TB1> INFO: Test took 1487ms.
[10:18:33.789] <TB1> INFO: The DUT currently contains the following objects:
[10:18:33.789] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:33.789] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:33.789] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:33.789] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:33.789] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:33.789] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:34.204] <TB1> INFO: Expecting 2560 events.
[10:18:35.274] <TB1> INFO: 2560 events read in total (353ms).
[10:18:35.274] <TB1> INFO: Test took 1485ms.
[10:18:35.275] <TB1> INFO: The DUT currently contains the following objects:
[10:18:35.275] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:35.275] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:35.275] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:35.275] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:35.275] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.275] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.275] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.275] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.275] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.275] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.275] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.275] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.275] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.275] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.275] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.276] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.276] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.276] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.276] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.276] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:35.690] <TB1> INFO: Expecting 2560 events.
[10:18:36.761] <TB1> INFO: 2560 events read in total (354ms).
[10:18:36.762] <TB1> INFO: Test took 1486ms.
[10:18:36.762] <TB1> INFO: The DUT currently contains the following objects:
[10:18:36.762] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:36.762] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:36.762] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:36.762] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:36.762] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.762] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.762] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.762] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.762] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.762] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.762] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.762] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.763] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.763] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.763] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.763] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.763] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.763] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.763] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:36.763] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:37.177] <TB1> INFO: Expecting 2560 events.
[10:18:38.247] <TB1> INFO: 2560 events read in total (353ms).
[10:18:38.247] <TB1> INFO: Test took 1484ms.
[10:18:38.248] <TB1> INFO: The DUT currently contains the following objects:
[10:18:38.248] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:38.248] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:38.248] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:38.248] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:38.248] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.248] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:38.663] <TB1> INFO: Expecting 2560 events.
[10:18:39.733] <TB1> INFO: 2560 events read in total (353ms).
[10:18:39.734] <TB1> INFO: Test took 1486ms.
[10:18:39.734] <TB1> INFO: The DUT currently contains the following objects:
[10:18:39.734] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:39.734] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:39.734] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:39.734] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:39.734] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.734] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.734] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.734] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.734] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.734] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.734] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.734] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.734] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.735] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.735] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.735] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.735] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.735] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.735] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:39.735] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:40.149] <TB1> INFO: Expecting 2560 events.
[10:18:41.219] <TB1> INFO: 2560 events read in total (353ms).
[10:18:41.220] <TB1> INFO: Test took 1485ms.
[10:18:41.220] <TB1> INFO: The DUT currently contains the following objects:
[10:18:41.220] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:41.220] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:41.220] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:41.221] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:41.221] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.221] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:41.635] <TB1> INFO: Expecting 2560 events.
[10:18:42.706] <TB1> INFO: 2560 events read in total (354ms).
[10:18:42.706] <TB1> INFO: Test took 1485ms.
[10:18:42.707] <TB1> INFO: The DUT currently contains the following objects:
[10:18:42.707] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:42.707] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:42.707] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:42.707] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:42.707] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:42.707] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:43.122] <TB1> INFO: Expecting 2560 events.
[10:18:44.190] <TB1> INFO: 2560 events read in total (351ms).
[10:18:44.190] <TB1> INFO: Test took 1483ms.
[10:18:44.191] <TB1> INFO: The DUT currently contains the following objects:
[10:18:44.191] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:44.191] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:44.191] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:44.191] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:44.191] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.191] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.191] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.191] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.191] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.191] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.191] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.191] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.191] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.191] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.192] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.192] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.192] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.192] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.192] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.192] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:44.606] <TB1> INFO: Expecting 2560 events.
[10:18:45.677] <TB1> INFO: 2560 events read in total (354ms).
[10:18:45.678] <TB1> INFO: Test took 1486ms.
[10:18:45.678] <TB1> INFO: The DUT currently contains the following objects:
[10:18:45.678] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:45.678] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:45.678] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:45.678] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:45.679] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:45.679] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:46.094] <TB1> INFO: Expecting 2560 events.
[10:18:47.163] <TB1> INFO: 2560 events read in total (353ms).
[10:18:47.164] <TB1> INFO: Test took 1485ms.
[10:18:47.164] <TB1> INFO: The DUT currently contains the following objects:
[10:18:47.164] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:47.164] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:47.164] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:47.164] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:47.164] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.165] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:47.580] <TB1> INFO: Expecting 2560 events.
[10:18:48.647] <TB1> INFO: 2560 events read in total (350ms).
[10:18:48.647] <TB1> INFO: Test took 1482ms.
[10:18:48.648] <TB1> INFO: The DUT currently contains the following objects:
[10:18:48.648] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:48.648] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:48.648] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:48.648] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[10:18:48.648] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.648] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.648] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.648] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.648] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.648] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.648] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.648] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.648] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.648] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.648] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.649] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.649] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.649] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.649] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:48.649] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[10:18:49.063] <TB1> INFO: Expecting 2560 events.
[10:18:50.133] <TB1> INFO: 2560 events read in total (353ms).
[10:18:50.134] <TB1> INFO: Test took 1485ms.
[10:18:50.139] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:18:50.552] <TB1> INFO: Expecting 655360 events.
[10:19:07.041] <TB1> INFO: 655360 events read in total (15772ms).
[10:19:07.050] <TB1> INFO: Expecting 655360 events.
[10:19:23.469] <TB1> INFO: 655360 events read in total (15890ms).
[10:19:23.484] <TB1> INFO: Expecting 655360 events.
[10:19:39.975] <TB1> INFO: 655360 events read in total (15963ms).
[10:19:39.991] <TB1> INFO: Expecting 655360 events.
[10:19:55.037] <TB1> INFO: 655360 events read in total (14518ms).
[10:19:55.057] <TB1> INFO: Expecting 655360 events.
[10:20:10.133] <TB1> INFO: 655360 events read in total (14548ms).
[10:20:10.157] <TB1> INFO: Expecting 655360 events.
[10:20:26.653] <TB1> INFO: 655360 events read in total (15969ms).
[10:20:26.681] <TB1> INFO: Expecting 655360 events.
[10:20:43.251] <TB1> INFO: 655360 events read in total (16042ms).
[10:20:43.283] <TB1> INFO: Expecting 655360 events.
[10:20:59.795] <TB1> INFO: 655360 events read in total (15983ms).
[10:20:59.830] <TB1> INFO: Expecting 655360 events.
[10:21:16.225] <TB1> INFO: 655360 events read in total (15867ms).
[10:21:16.262] <TB1> INFO: Expecting 655360 events.
[10:21:32.778] <TB1> INFO: 655360 events read in total (15988ms).
[10:21:32.821] <TB1> INFO: Expecting 655360 events.
[10:21:49.295] <TB1> INFO: 655360 events read in total (15946ms).
[10:21:49.341] <TB1> INFO: Expecting 655360 events.
[10:22:05.816] <TB1> INFO: 655360 events read in total (15947ms).
[10:22:05.863] <TB1> INFO: Expecting 655360 events.
[10:22:22.371] <TB1> INFO: 655360 events read in total (15980ms).
[10:22:22.429] <TB1> INFO: Expecting 655360 events.
[10:22:38.991] <TB1> INFO: 655360 events read in total (16034ms).
[10:22:39.050] <TB1> INFO: Expecting 655360 events.
[10:22:55.571] <TB1> INFO: 655360 events read in total (15993ms).
[10:22:55.634] <TB1> INFO: Expecting 655360 events.
[10:23:12.112] <TB1> INFO: 655360 events read in total (15949ms).
[10:23:12.178] <TB1> INFO: Test took 262039ms.
[10:23:12.267] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:23:12.574] <TB1> INFO: Expecting 655360 events.
[10:23:29.179] <TB1> INFO: 655360 events read in total (15888ms).
[10:23:29.188] <TB1> INFO: Expecting 655360 events.
[10:23:45.523] <TB1> INFO: 655360 events read in total (15807ms).
[10:23:45.535] <TB1> INFO: Expecting 655360 events.
[10:24:01.867] <TB1> INFO: 655360 events read in total (15803ms).
[10:24:01.882] <TB1> INFO: Expecting 655360 events.
[10:24:16.975] <TB1> INFO: 655360 events read in total (14564ms).
[10:24:16.996] <TB1> INFO: Expecting 655360 events.
[10:24:32.375] <TB1> INFO: 655360 events read in total (14851ms).
[10:24:32.400] <TB1> INFO: Expecting 655360 events.
[10:24:47.825] <TB1> INFO: 655360 events read in total (14897ms).
[10:24:47.864] <TB1> INFO: Expecting 655360 events.
[10:25:03.276] <TB1> INFO: 655360 events read in total (14884ms).
[10:25:03.306] <TB1> INFO: Expecting 655360 events.
[10:25:18.287] <TB1> INFO: 655360 events read in total (14453ms).
[10:25:18.320] <TB1> INFO: Expecting 655360 events.
[10:25:33.378] <TB1> INFO: 655360 events read in total (14530ms).
[10:25:33.416] <TB1> INFO: Expecting 655360 events.
[10:25:49.598] <TB1> INFO: 655360 events read in total (15653ms).
[10:25:49.641] <TB1> INFO: Expecting 655360 events.
[10:26:06.029] <TB1> INFO: 655360 events read in total (15860ms).
[10:26:06.073] <TB1> INFO: Expecting 655360 events.
[10:26:22.425] <TB1> INFO: 655360 events read in total (15824ms).
[10:26:22.473] <TB1> INFO: Expecting 655360 events.
[10:26:38.740] <TB1> INFO: 655360 events read in total (15738ms).
[10:26:38.798] <TB1> INFO: Expecting 655360 events.
[10:26:55.262] <TB1> INFO: 655360 events read in total (15935ms).
[10:26:55.319] <TB1> INFO: Expecting 655360 events.
[10:27:11.748] <TB1> INFO: 655360 events read in total (15901ms).
[10:27:11.807] <TB1> INFO: Expecting 655360 events.
[10:27:28.206] <TB1> INFO: 655360 events read in total (15871ms).
[10:27:28.271] <TB1> INFO: Test took 256005ms.
[10:27:28.463] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.470] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.477] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.484] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.491] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.498] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.505] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.511] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.518] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.525] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.532] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.539] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.546] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.552] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.559] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.566] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:27:28.624] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:27:28.624] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:27:28.625] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:27:28.625] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:27:28.625] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:27:28.625] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:27:28.626] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:27:28.626] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:27:28.626] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:27:28.626] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:27:28.626] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:27:28.627] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:27:28.627] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:27:28.627] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:27:28.627] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:27:28.628] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:27:28.952] <TB1> INFO: Expecting 41600 events.
[10:27:33.430] <TB1> INFO: 41600 events read in total (3761ms).
[10:27:33.430] <TB1> INFO: Test took 4797ms.
[10:27:33.973] <TB1> INFO: Expecting 41600 events.
[10:27:38.461] <TB1> INFO: 41600 events read in total (3772ms).
[10:27:38.462] <TB1> INFO: Test took 4794ms.
[10:27:39.027] <TB1> INFO: Expecting 41600 events.
[10:27:43.494] <TB1> INFO: 41600 events read in total (3749ms).
[10:27:43.494] <TB1> INFO: Test took 4807ms.
[10:27:43.732] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:27:43.865] <TB1> INFO: Expecting 2560 events.
[10:27:44.937] <TB1> INFO: 2560 events read in total (355ms).
[10:27:44.938] <TB1> INFO: Test took 1206ms.
[10:27:44.940] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:27:45.354] <TB1> INFO: Expecting 2560 events.
[10:27:46.428] <TB1> INFO: 2560 events read in total (357ms).
[10:27:46.428] <TB1> INFO: Test took 1488ms.
[10:27:46.431] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:27:46.844] <TB1> INFO: Expecting 2560 events.
[10:27:47.914] <TB1> INFO: 2560 events read in total (353ms).
[10:27:47.914] <TB1> INFO: Test took 1483ms.
[10:27:47.917] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:27:48.331] <TB1> INFO: Expecting 2560 events.
[10:27:49.403] <TB1> INFO: 2560 events read in total (355ms).
[10:27:49.403] <TB1> INFO: Test took 1486ms.
[10:27:49.406] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:27:49.820] <TB1> INFO: Expecting 2560 events.
[10:27:50.891] <TB1> INFO: 2560 events read in total (354ms).
[10:27:50.891] <TB1> INFO: Test took 1485ms.
[10:27:50.894] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:27:51.308] <TB1> INFO: Expecting 2560 events.
[10:27:52.377] <TB1> INFO: 2560 events read in total (352ms).
[10:27:52.378] <TB1> INFO: Test took 1484ms.
[10:27:52.380] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:27:52.795] <TB1> INFO: Expecting 2560 events.
[10:27:53.867] <TB1> INFO: 2560 events read in total (355ms).
[10:27:53.867] <TB1> INFO: Test took 1487ms.
[10:27:53.870] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:27:54.284] <TB1> INFO: Expecting 2560 events.
[10:27:55.355] <TB1> INFO: 2560 events read in total (354ms).
[10:27:55.355] <TB1> INFO: Test took 1485ms.
[10:27:55.358] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:27:55.772] <TB1> INFO: Expecting 2560 events.
[10:27:56.843] <TB1> INFO: 2560 events read in total (354ms).
[10:27:56.844] <TB1> INFO: Test took 1486ms.
[10:27:56.847] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:27:57.260] <TB1> INFO: Expecting 2560 events.
[10:27:58.331] <TB1> INFO: 2560 events read in total (354ms).
[10:27:58.332] <TB1> INFO: Test took 1485ms.
[10:27:58.335] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:27:58.748] <TB1> INFO: Expecting 2560 events.
[10:27:59.819] <TB1> INFO: 2560 events read in total (354ms).
[10:27:59.820] <TB1> INFO: Test took 1485ms.
[10:27:59.823] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:00.236] <TB1> INFO: Expecting 2560 events.
[10:28:01.307] <TB1> INFO: 2560 events read in total (354ms).
[10:28:01.308] <TB1> INFO: Test took 1485ms.
[10:28:01.311] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:01.724] <TB1> INFO: Expecting 2560 events.
[10:28:02.791] <TB1> INFO: 2560 events read in total (350ms).
[10:28:02.791] <TB1> INFO: Test took 1480ms.
[10:28:02.794] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:03.207] <TB1> INFO: Expecting 2560 events.
[10:28:04.277] <TB1> INFO: 2560 events read in total (352ms).
[10:28:04.277] <TB1> INFO: Test took 1483ms.
[10:28:04.280] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:04.695] <TB1> INFO: Expecting 2560 events.
[10:28:05.767] <TB1> INFO: 2560 events read in total (355ms).
[10:28:05.767] <TB1> INFO: Test took 1487ms.
[10:28:05.770] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:06.184] <TB1> INFO: Expecting 2560 events.
[10:28:07.248] <TB1> INFO: 2560 events read in total (347ms).
[10:28:07.249] <TB1> INFO: Test took 1479ms.
[10:28:07.252] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:07.665] <TB1> INFO: Expecting 2560 events.
[10:28:08.736] <TB1> INFO: 2560 events read in total (354ms).
[10:28:08.737] <TB1> INFO: Test took 1485ms.
[10:28:08.740] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:09.153] <TB1> INFO: Expecting 2560 events.
[10:28:10.224] <TB1> INFO: 2560 events read in total (353ms).
[10:28:10.224] <TB1> INFO: Test took 1484ms.
[10:28:10.227] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:10.641] <TB1> INFO: Expecting 2560 events.
[10:28:11.712] <TB1> INFO: 2560 events read in total (354ms).
[10:28:11.713] <TB1> INFO: Test took 1486ms.
[10:28:11.716] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:12.130] <TB1> INFO: Expecting 2560 events.
[10:28:13.201] <TB1> INFO: 2560 events read in total (354ms).
[10:28:13.201] <TB1> INFO: Test took 1486ms.
[10:28:13.205] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:13.618] <TB1> INFO: Expecting 2560 events.
[10:28:14.690] <TB1> INFO: 2560 events read in total (355ms).
[10:28:14.690] <TB1> INFO: Test took 1486ms.
[10:28:14.693] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:15.107] <TB1> INFO: Expecting 2560 events.
[10:28:16.178] <TB1> INFO: 2560 events read in total (354ms).
[10:28:16.178] <TB1> INFO: Test took 1485ms.
[10:28:16.181] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:16.594] <TB1> INFO: Expecting 2560 events.
[10:28:17.658] <TB1> INFO: 2560 events read in total (347ms).
[10:28:17.658] <TB1> INFO: Test took 1477ms.
[10:28:17.660] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:18.075] <TB1> INFO: Expecting 2560 events.
[10:28:19.139] <TB1> INFO: 2560 events read in total (348ms).
[10:28:19.139] <TB1> INFO: Test took 1479ms.
[10:28:19.141] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:19.556] <TB1> INFO: Expecting 2560 events.
[10:28:20.620] <TB1> INFO: 2560 events read in total (347ms).
[10:28:20.620] <TB1> INFO: Test took 1479ms.
[10:28:20.623] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:21.037] <TB1> INFO: Expecting 2560 events.
[10:28:22.102] <TB1> INFO: 2560 events read in total (348ms).
[10:28:22.103] <TB1> INFO: Test took 1480ms.
[10:28:22.105] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:22.519] <TB1> INFO: Expecting 2560 events.
[10:28:23.583] <TB1> INFO: 2560 events read in total (347ms).
[10:28:23.583] <TB1> INFO: Test took 1478ms.
[10:28:23.586] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:24.000] <TB1> INFO: Expecting 2560 events.
[10:28:25.063] <TB1> INFO: 2560 events read in total (347ms).
[10:28:25.064] <TB1> INFO: Test took 1479ms.
[10:28:25.066] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:25.481] <TB1> INFO: Expecting 2560 events.
[10:28:26.545] <TB1> INFO: 2560 events read in total (348ms).
[10:28:26.545] <TB1> INFO: Test took 1479ms.
[10:28:26.548] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:26.962] <TB1> INFO: Expecting 2560 events.
[10:28:28.026] <TB1> INFO: 2560 events read in total (347ms).
[10:28:28.027] <TB1> INFO: Test took 1479ms.
[10:28:28.029] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:28.443] <TB1> INFO: Expecting 2560 events.
[10:28:29.508] <TB1> INFO: 2560 events read in total (348ms).
[10:28:29.508] <TB1> INFO: Test took 1479ms.
[10:28:29.510] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:29.925] <TB1> INFO: Expecting 2560 events.
[10:28:30.988] <TB1> INFO: 2560 events read in total (347ms).
[10:28:30.988] <TB1> INFO: Test took 1478ms.
[10:28:31.640] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 619 seconds
[10:28:31.640] <TB1> INFO: PH scale (per ROC): 82 94 80 76 82 73 73 73 71 93 79 83 84 97 90 83
[10:28:31.640] <TB1> INFO: PH offset (per ROC): 141 147 175 151 160 175 151 174 145 147 144 155 150 123 140 153
[10:28:31.815] <TB1> INFO: ######################################################################
[10:28:31.816] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[10:28:31.816] <TB1> INFO: ######################################################################
[10:28:31.826] <TB1> INFO: scanning low vcal = 10
[10:28:32.134] <TB1> INFO: Expecting 41600 events.
[10:28:35.677] <TB1> INFO: 41600 events read in total (2827ms).
[10:28:35.677] <TB1> INFO: Test took 3851ms.
[10:28:35.680] <TB1> INFO: scanning low vcal = 20
[10:28:36.094] <TB1> INFO: Expecting 41600 events.
[10:28:39.638] <TB1> INFO: 41600 events read in total (2827ms).
[10:28:39.639] <TB1> INFO: Test took 3959ms.
[10:28:39.641] <TB1> INFO: scanning low vcal = 30
[10:28:40.055] <TB1> INFO: Expecting 41600 events.
[10:28:43.610] <TB1> INFO: 41600 events read in total (2838ms).
[10:28:43.611] <TB1> INFO: Test took 3970ms.
[10:28:43.613] <TB1> INFO: scanning low vcal = 40
[10:28:44.022] <TB1> INFO: Expecting 41600 events.
[10:28:48.089] <TB1> INFO: 41600 events read in total (3350ms).
[10:28:48.089] <TB1> INFO: Test took 4476ms.
[10:28:48.093] <TB1> INFO: scanning low vcal = 50
[10:28:48.442] <TB1> INFO: Expecting 41600 events.
[10:28:52.731] <TB1> INFO: 41600 events read in total (3572ms).
[10:28:52.732] <TB1> INFO: Test took 4639ms.
[10:28:52.736] <TB1> INFO: scanning low vcal = 60
[10:28:53.057] <TB1> INFO: Expecting 41600 events.
[10:28:57.268] <TB1> INFO: 41600 events read in total (3494ms).
[10:28:57.269] <TB1> INFO: Test took 4533ms.
[10:28:57.273] <TB1> INFO: scanning low vcal = 70
[10:28:57.622] <TB1> INFO: Expecting 41600 events.
[10:29:01.826] <TB1> INFO: 41600 events read in total (3487ms).
[10:29:01.827] <TB1> INFO: Test took 4554ms.
[10:29:01.830] <TB1> INFO: scanning low vcal = 80
[10:29:02.178] <TB1> INFO: Expecting 41600 events.
[10:29:06.387] <TB1> INFO: 41600 events read in total (3492ms).
[10:29:06.388] <TB1> INFO: Test took 4558ms.
[10:29:06.391] <TB1> INFO: scanning low vcal = 90
[10:29:06.742] <TB1> INFO: Expecting 41600 events.
[10:29:11.059] <TB1> INFO: 41600 events read in total (3600ms).
[10:29:11.060] <TB1> INFO: Test took 4669ms.
[10:29:11.064] <TB1> INFO: scanning low vcal = 100
[10:29:11.414] <TB1> INFO: Expecting 41600 events.
[10:29:15.631] <TB1> INFO: 41600 events read in total (3500ms).
[10:29:15.632] <TB1> INFO: Test took 4568ms.
[10:29:15.648] <TB1> INFO: scanning low vcal = 110
[10:29:15.974] <TB1> INFO: Expecting 41600 events.
[10:29:20.148] <TB1> INFO: 41600 events read in total (3457ms).
[10:29:20.149] <TB1> INFO: Test took 4501ms.
[10:29:20.152] <TB1> INFO: scanning low vcal = 120
[10:29:20.500] <TB1> INFO: Expecting 41600 events.
[10:29:24.718] <TB1> INFO: 41600 events read in total (3501ms).
[10:29:24.718] <TB1> INFO: Test took 4566ms.
[10:29:24.722] <TB1> INFO: scanning low vcal = 130
[10:29:25.073] <TB1> INFO: Expecting 41600 events.
[10:29:29.281] <TB1> INFO: 41600 events read in total (3491ms).
[10:29:29.281] <TB1> INFO: Test took 4559ms.
[10:29:29.285] <TB1> INFO: scanning low vcal = 140
[10:29:29.637] <TB1> INFO: Expecting 41600 events.
[10:29:33.834] <TB1> INFO: 41600 events read in total (3480ms).
[10:29:33.835] <TB1> INFO: Test took 4550ms.
[10:29:33.838] <TB1> INFO: scanning low vcal = 150
[10:29:34.189] <TB1> INFO: Expecting 41600 events.
[10:29:38.415] <TB1> INFO: 41600 events read in total (3509ms).
[10:29:38.416] <TB1> INFO: Test took 4578ms.
[10:29:38.419] <TB1> INFO: scanning low vcal = 160
[10:29:38.755] <TB1> INFO: Expecting 41600 events.
[10:29:42.966] <TB1> INFO: 41600 events read in total (3494ms).
[10:29:42.967] <TB1> INFO: Test took 4548ms.
[10:29:42.970] <TB1> INFO: scanning low vcal = 170
[10:29:43.312] <TB1> INFO: Expecting 41600 events.
[10:29:47.471] <TB1> INFO: 41600 events read in total (3442ms).
[10:29:47.472] <TB1> INFO: Test took 4502ms.
[10:29:47.476] <TB1> INFO: scanning low vcal = 180
[10:29:47.827] <TB1> INFO: Expecting 41600 events.
[10:29:52.025] <TB1> INFO: 41600 events read in total (3481ms).
[10:29:52.025] <TB1> INFO: Test took 4549ms.
[10:29:52.028] <TB1> INFO: scanning low vcal = 190
[10:29:52.371] <TB1> INFO: Expecting 41600 events.
[10:29:56.587] <TB1> INFO: 41600 events read in total (3499ms).
[10:29:56.587] <TB1> INFO: Test took 4559ms.
[10:29:56.590] <TB1> INFO: scanning low vcal = 200
[10:29:56.945] <TB1> INFO: Expecting 41600 events.
[10:30:01.158] <TB1> INFO: 41600 events read in total (3496ms).
[10:30:01.159] <TB1> INFO: Test took 4568ms.
[10:30:01.168] <TB1> INFO: scanning low vcal = 210
[10:30:01.515] <TB1> INFO: Expecting 41600 events.
[10:30:05.718] <TB1> INFO: 41600 events read in total (3486ms).
[10:30:05.718] <TB1> INFO: Test took 4550ms.
[10:30:05.722] <TB1> INFO: scanning low vcal = 220
[10:30:06.075] <TB1> INFO: Expecting 41600 events.
[10:30:10.294] <TB1> INFO: 41600 events read in total (3502ms).
[10:30:10.295] <TB1> INFO: Test took 4573ms.
[10:30:10.298] <TB1> INFO: scanning low vcal = 230
[10:30:10.648] <TB1> INFO: Expecting 41600 events.
[10:30:14.869] <TB1> INFO: 41600 events read in total (3504ms).
[10:30:14.870] <TB1> INFO: Test took 4572ms.
[10:30:14.873] <TB1> INFO: scanning low vcal = 240
[10:30:15.228] <TB1> INFO: Expecting 41600 events.
[10:30:19.437] <TB1> INFO: 41600 events read in total (3492ms).
[10:30:19.437] <TB1> INFO: Test took 4564ms.
[10:30:19.441] <TB1> INFO: scanning low vcal = 250
[10:30:19.788] <TB1> INFO: Expecting 41600 events.
[10:30:23.991] <TB1> INFO: 41600 events read in total (3486ms).
[10:30:23.991] <TB1> INFO: Test took 4550ms.
[10:30:23.997] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[10:30:24.351] <TB1> INFO: Expecting 41600 events.
[10:30:28.542] <TB1> INFO: 41600 events read in total (3474ms).
[10:30:28.543] <TB1> INFO: Test took 4546ms.
[10:30:28.546] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[10:30:28.898] <TB1> INFO: Expecting 41600 events.
[10:30:33.099] <TB1> INFO: 41600 events read in total (3484ms).
[10:30:33.099] <TB1> INFO: Test took 4553ms.
[10:30:33.103] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[10:30:33.452] <TB1> INFO: Expecting 41600 events.
[10:30:37.623] <TB1> INFO: 41600 events read in total (3454ms).
[10:30:37.623] <TB1> INFO: Test took 4520ms.
[10:30:37.627] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[10:30:37.980] <TB1> INFO: Expecting 41600 events.
[10:30:42.266] <TB1> INFO: 41600 events read in total (3569ms).
[10:30:42.266] <TB1> INFO: Test took 4639ms.
[10:30:42.270] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[10:30:42.614] <TB1> INFO: Expecting 41600 events.
[10:30:46.791] <TB1> INFO: 41600 events read in total (3460ms).
[10:30:46.792] <TB1> INFO: Test took 4522ms.
[10:30:47.250] <TB1> INFO: PixTestGainPedestal::measure() done
[10:31:22.190] <TB1> INFO: PixTestGainPedestal::fit() done
[10:31:22.190] <TB1> INFO: non-linearity mean: 0.954 0.957 0.953 0.951 0.954 0.955 0.948 0.954 0.952 0.956 0.953 0.957 0.955 0.960 0.954 0.956
[10:31:22.190] <TB1> INFO: non-linearity RMS: 0.006 0.007 0.006 0.006 0.005 0.007 0.007 0.007 0.006 0.005 0.005 0.005 0.006 0.005 0.006 0.005
[10:31:22.191] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[10:31:22.213] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[10:31:22.234] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[10:31:22.255] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[10:31:22.276] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[10:31:22.296] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[10:31:22.317] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[10:31:22.337] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[10:31:22.357] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[10:31:22.377] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[10:31:22.397] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[10:31:22.418] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[10:31:22.444] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[10:31:22.470] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[10:31:22.490] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[10:31:22.513] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[10:31:22.538] <TB1> INFO: PixTestGainPedestal::doTest() done, duration: 170 seconds
[10:31:22.544] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C15.dat
[10:31:22.545] <TB1> INFO: PixTestReadback::doTest() start.
[10:31:22.547] <TB1> INFO: PixTestReadback::RES sent once
[10:31:33.918] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C0.dat
[10:31:33.918] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C1.dat
[10:31:33.918] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C2.dat
[10:31:33.918] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C3.dat
[10:31:33.918] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C4.dat
[10:31:33.918] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C5.dat
[10:31:33.918] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C6.dat
[10:31:33.918] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C7.dat
[10:31:33.918] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C8.dat
[10:31:33.918] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C9.dat
[10:31:33.919] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C10.dat
[10:31:33.919] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C11.dat
[10:31:33.919] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C12.dat
[10:31:33.919] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C13.dat
[10:31:33.919] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C14.dat
[10:31:33.919] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C15.dat
[10:31:33.966] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[10:31:33.967] <TB1> INFO: PixTestReadback::RES sent once
[10:31:45.283] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C0.dat
[10:31:45.284] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C1.dat
[10:31:45.284] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C2.dat
[10:31:45.284] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C3.dat
[10:31:45.284] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C4.dat
[10:31:45.285] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C5.dat
[10:31:45.285] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C6.dat
[10:31:45.285] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C7.dat
[10:31:45.285] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C8.dat
[10:31:45.285] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C9.dat
[10:31:45.285] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C10.dat
[10:31:45.285] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C11.dat
[10:31:45.286] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C12.dat
[10:31:45.286] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C13.dat
[10:31:45.286] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C14.dat
[10:31:45.286] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C15.dat
[10:31:45.332] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[10:31:45.333] <TB1> INFO: PixTestReadback::RES sent once
[10:31:54.017] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[10:31:54.018] <TB1> INFO: Vbg will be calibrated using Vd calibration
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 149.9calibrated Vbg = 1.18971 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 161.1calibrated Vbg = 1.19292 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 162.6calibrated Vbg = 1.19689 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 150.8calibrated Vbg = 1.2028 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 161.7calibrated Vbg = 1.20406 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 162.4calibrated Vbg = 1.20221 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 160.9calibrated Vbg = 1.20186 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 163.4calibrated Vbg = 1.20001 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156.3calibrated Vbg = 1.20906 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 150.9calibrated Vbg = 1.21163 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.5calibrated Vbg = 1.20519 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 160.2calibrated Vbg = 1.20489 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 150calibrated Vbg = 1.19138 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 149.7calibrated Vbg = 1.18535 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 150.3calibrated Vbg = 1.18542 :::*/*/*/*/
[10:31:54.018] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 157.4calibrated Vbg = 1.19421 :::*/*/*/*/
[10:31:54.022] <TB1> INFO: PixTestReadback::RES sent once
[10:34:49.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C0.dat
[10:34:49.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C1.dat
[10:34:49.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C2.dat
[10:34:49.582] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C3.dat
[10:34:49.582] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C4.dat
[10:34:49.582] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C5.dat
[10:34:49.582] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C6.dat
[10:34:49.582] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C7.dat
[10:34:49.582] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C8.dat
[10:34:49.582] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C9.dat
[10:34:49.582] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C10.dat
[10:34:49.582] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C11.dat
[10:34:49.582] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C12.dat
[10:34:49.583] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C13.dat
[10:34:49.583] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C14.dat
[10:34:49.583] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//000_FulltestPxar_m20//readbackCal_C15.dat
[10:34:49.628] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[10:34:49.629] <TB1> INFO: PixTestReadback::doTest() done
[10:34:49.643] <TB1> INFO: enter test to run
[10:34:49.643] <TB1> INFO: test: exit no parameter change
[10:34:50.243] <TB1> QUIET: Connection to board 153 closed.
[10:34:50.323] <TB1> INFO: pXar: this is the end, my friend