[15:13:15.971] <TB1> INFO: *** Welcome to pxar ***
[15:13:15.971] <TB1> INFO: *** Today: 2015/08/28
[15:13:15.971] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C15.dat
[15:13:15.972] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:13:15.973] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//defaultMaskFile.dat
[15:13:15.973] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters_C15.dat
[15:13:16.041] <TB1> INFO: clk: 4
[15:13:16.041] <TB1> INFO: ctr: 4
[15:13:16.041] <TB1> INFO: sda: 19
[15:13:16.041] <TB1> INFO: tin: 9
[15:13:16.042] <TB1> INFO: level: 15
[15:13:16.042] <TB1> INFO: triggerdelay: 0
[15:13:16.042] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[15:13:16.042] <TB1> INFO: Log level: INFO
[15:13:16.049] <TB1> INFO: Found DTB DTB_WXBYFL
[15:13:16.062] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[15:13:16.065] <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:
------------------------------------------------------
[15:13:16.068] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[15:13:17.605] <TB1> INFO: DUT info:
[15:13:17.605] <TB1> INFO: The DUT currently contains the following objects:
[15:13:17.605] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:13:17.605] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:13:17.605] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:13:17.605] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:13:17.605] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:17.605] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:13:18.007] <TB1> INFO: enter 'restricted' command line mode
[15:13:18.007] <TB1> INFO: enter test to run
[15:13:18.007] <TB1> INFO: test: pretest no parameter change
[15:13:18.007] <TB1> INFO: running: pretest
[15:13:18.014] <TB1> INFO: ######################################################################
[15:13:18.014] <TB1> INFO: PixTestPretest::doTest()
[15:13:18.014] <TB1> INFO: ######################################################################
[15:13:18.016] <TB1> INFO: ----------------------------------------------------------------------
[15:13:18.016] <TB1> INFO: PixTestPretest::programROC()
[15:13:18.016] <TB1> INFO: ----------------------------------------------------------------------
[15:13:36.038] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:13:36.038] <TB1> INFO: IA differences per ROC: 18.5 14.5 17.7 19.3 16.9 17.7 17.7 17.7 20.1 16.9 19.3 19.3 17.7 16.9 17.7 19.3
[15:13:36.140] <TB1> INFO: ----------------------------------------------------------------------
[15:13:36.140] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:13:36.140] <TB1> INFO: ----------------------------------------------------------------------
[15:13:55.735] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 386.7 mA = 24.1687 mA/ROC
[15:13:55.737] <TB1> INFO: ----------------------------------------------------------------------
[15:13:55.737] <TB1> INFO: PixTestPretest::findTiming()
[15:13:55.737] <TB1> INFO: ----------------------------------------------------------------------
[15:13:55.737] <TB1> INFO: PixTestCmd::init()

[15:16:33.578] <TB1> INFO: TBM phases: 160MHz: 4, 400MHz: 2, TBM delays: ROC(0/1):2, header/trailer: 1, token: 0
[15:16:33.578] <TB1> INFO: (success/tries = 100/100), width = 3
[15:16:33.580] <TB1> INFO: ----------------------------------------------------------------------
[15:16:33.580] <TB1> INFO: PixTestPretest::findWorkingPixel()
[15:16:33.580] <TB1> INFO: ----------------------------------------------------------------------
[15:16:33.718] <TB1> INFO: Expecting 231680 events.
[15:16:42.454] <TB1> INFO: 231680 events read in total (8019ms).
[15:16:42.458] <TB1> INFO: Test took 8875ms.
[15:16:42.781] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:16:42.835] <TB1> INFO: ----------------------------------------------------------------------
[15:16:42.835] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[15:16:42.835] <TB1> INFO: ----------------------------------------------------------------------
[15:16:42.975] <TB1> INFO: Expecting 231680 events.
[15:16:52.283] <TB1> INFO: 231680 events read in total (8591ms).
[15:16:52.286] <TB1> INFO: Test took 9445ms.
[15:16:52.634] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[15:16:52.634] <TB1> INFO: CalDel: 153 143 143 152 144 127 152 130 129 130 129 128 137 114 124 131
[15:16:52.634] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[15:16:52.637] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C0.dat
[15:16:52.638] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C1.dat
[15:16:52.638] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C2.dat
[15:16:52.638] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C3.dat
[15:16:52.638] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C4.dat
[15:16:52.639] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C5.dat
[15:16:52.639] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C6.dat
[15:16:52.639] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C7.dat
[15:16:52.639] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C8.dat
[15:16:52.640] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C9.dat
[15:16:52.640] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C10.dat
[15:16:52.640] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C11.dat
[15:16:52.641] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C12.dat
[15:16:52.641] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C13.dat
[15:16:52.641] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C14.dat
[15:16:52.641] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters_C15.dat
[15:16:52.642] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//tbmParameters_C0a.dat
[15:16:52.642] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:16:52.642] <TB1> INFO: PixTestPretest::doTest() done, duration: 214 seconds
[15:16:52.715] <TB1> INFO: enter test to run
[15:16:52.715] <TB1> INFO: test: fulltest no parameter change
[15:16:52.715] <TB1> INFO: running: fulltest
[15:16:52.715] <TB1> INFO: ######################################################################
[15:16:52.715] <TB1> INFO: PixTestFullTest::doTest()
[15:16:52.715] <TB1> INFO: ######################################################################
[15:16:52.716] <TB1> INFO: ######################################################################
[15:16:52.716] <TB1> INFO: PixTestAlive::doTest()
[15:16:52.716] <TB1> INFO: ######################################################################
[15:16:52.718] <TB1> INFO: ----------------------------------------------------------------------
[15:16:52.718] <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)
[15:16:52.718] <TB1> INFO: ----------------------------------------------------------------------
[15:16:53.043] <TB1> INFO: Expecting 41600 events.
[15:16:57.494] <TB1> INFO: 41600 events read in total (3734ms).
[15:16:57.494] <TB1> INFO: Test took 4773ms.
[15:16:57.500] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:16:57.803] <TB1> INFO: PixTestAlive::aliveTest() done
[15:16:57.803] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:16:57.805] <TB1> INFO: ----------------------------------------------------------------------
[15:16:57.805] <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)
[15:16:57.805] <TB1> INFO: ----------------------------------------------------------------------
[15:16:58.123] <TB1> INFO: Expecting 41600 events.
[15:17:01.296] <TB1> INFO: 41600 events read in total (2456ms).
[15:17:01.297] <TB1> INFO: Test took 3490ms.
[15:17:01.297] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:01.297] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:17:01.639] <TB1> INFO: PixTestAlive::maskTest() done
[15:17:01.639] <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
[15:17:01.641] <TB1> INFO: ----------------------------------------------------------------------
[15:17:01.641] <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)
[15:17:01.641] <TB1> INFO: ----------------------------------------------------------------------
[15:17:01.980] <TB1> INFO: Expecting 41600 events.
[15:17:06.453] <TB1> INFO: 41600 events read in total (3756ms).
[15:17:06.453] <TB1> INFO: Test took 4810ms.
[15:17:06.460] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:06.763] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[15:17:06.763] <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
[15:17:06.763] <TB1> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:17:06.778] <TB1> INFO: ######################################################################
[15:17:06.778] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:17:06.778] <TB1> INFO: ######################################################################
[15:17:06.782] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 2 (plus default)
[15:17:06.799] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:17:06.799] <TB1> INFO: run 1 of 1
[15:17:07.139] <TB1> INFO: Expecting 3120000 events.
[15:17:42.924] <TB1> INFO: 851620 events read in total (35068ms).
[15:18:17.717] <TB1> INFO: 1689250 events read in total (69861ms).
[15:18:50.183] <TB1> INFO: 2535375 events read in total (102328ms).
[15:19:12.387] <TB1> INFO: 3120000 events read in total (124531ms).
[15:19:12.431] <TB1> INFO: Test took 125632ms.
[15:19:12.526] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:37.046] <TB1> INFO: PixTestBBMap::doTest() done, duration: 150 seconds
[15:19:37.046] <TB1> INFO: number of dead bumps (per ROC): 5 7 1 1 2 0 0 1 0 0 0 0 0 3 4 7
[15:19:37.046] <TB1> INFO: separation cut (per ROC): 90 85 96 95 83 96 98 88 97 89 86 99 82 70 70 96
[15:19:37.116] <TB1> INFO: ######################################################################
[15:19:37.116] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:19:37.116] <TB1> INFO: ######################################################################
[15:19:37.116] <TB1> INFO: ----------------------------------------------------------------------
[15:19:37.116] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:19:37.116] <TB1> INFO: ----------------------------------------------------------------------
[15:19:37.116] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (-1/-1) hits flags = 16 (plus default)
[15:19:37.124] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[15:19:37.124] <TB1> INFO: run 1 of 1
[15:19:37.436] <TB1> INFO: Expecting 31200000 events.
[15:20:02.794] <TB1> INFO: 976250 events read in total (24640ms).
[15:20:27.219] <TB1> INFO: 1940450 events read in total (49065ms).
[15:20:51.631] <TB1> INFO: 2899100 events read in total (73477ms).
[15:21:16.044] <TB1> INFO: 3859500 events read in total (97890ms).
[15:21:40.623] <TB1> INFO: 4819950 events read in total (122469ms).
[15:22:04.979] <TB1> INFO: 5775400 events read in total (146825ms).
[15:22:29.430] <TB1> INFO: 6734400 events read in total (171276ms).
[15:22:53.945] <TB1> INFO: 7690600 events read in total (195791ms).
[15:23:18.581] <TB1> INFO: 8645700 events read in total (220427ms).
[15:23:43.105] <TB1> INFO: 9601050 events read in total (244951ms).
[15:24:07.257] <TB1> INFO: 10556100 events read in total (269103ms).
[15:24:29.907] <TB1> INFO: 11511300 events read in total (291753ms).
[15:24:52.183] <TB1> INFO: 12462250 events read in total (314029ms).
[15:25:14.349] <TB1> INFO: 13415700 events read in total (336195ms).
[15:25:36.858] <TB1> INFO: 14368050 events read in total (358704ms).
[15:26:01.163] <TB1> INFO: 15315400 events read in total (383009ms).
[15:26:25.384] <TB1> INFO: 16258250 events read in total (407230ms).
[15:26:49.862] <TB1> INFO: 17199500 events read in total (431708ms).
[15:27:14.233] <TB1> INFO: 18141900 events read in total (456079ms).
[15:27:38.575] <TB1> INFO: 19081250 events read in total (480421ms).
[15:28:02.951] <TB1> INFO: 20019750 events read in total (504797ms).
[15:28:27.101] <TB1> INFO: 20957800 events read in total (528947ms).
[15:28:51.185] <TB1> INFO: 21896950 events read in total (553031ms).
[15:29:15.166] <TB1> INFO: 22835450 events read in total (577012ms).
[15:29:39.352] <TB1> INFO: 23771100 events read in total (601198ms).
[15:30:03.595] <TB1> INFO: 24708750 events read in total (625441ms).
[15:30:27.707] <TB1> INFO: 25644850 events read in total (649553ms).
[15:30:51.880] <TB1> INFO: 26583350 events read in total (673726ms).
[15:31:16.064] <TB1> INFO: 27520750 events read in total (697910ms).
[15:31:40.422] <TB1> INFO: 28459650 events read in total (722268ms).
[15:32:04.763] <TB1> INFO: 29399200 events read in total (746609ms).
[15:32:29.004] <TB1> INFO: 30340150 events read in total (770850ms).
[15:32:50.607] <TB1> INFO: 31200000 events read in total (792453ms).
[15:32:50.631] <TB1> INFO: Test took 793507ms.
[15:32:50.710] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:50.806] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:32:52.230] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:32:53.680] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:32:55.169] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:32:56.627] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:32:58.057] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:32:59.528] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:00.961] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:02.419] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:03.893] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:05.370] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:06.885] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:08.348] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:09.759] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:11.190] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:12.617] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:14.020] <TB1> INFO: PixTestScurves::scurves() done
[15:33:14.020] <TB1> INFO: Vcal mean: 90.12 84.65 98.80 97.60 88.40 101.91 109.33 105.38 99.50 91.26 94.47 108.31 88.31 81.22 81.36 106.94
[15:33:14.020] <TB1> INFO: Vcal RMS: 5.12 5.23 6.28 6.95 4.81 6.70 7.05 5.05 5.88 5.83 5.75 4.92 5.11 4.67 4.01 5.33
[15:33:14.020] <TB1> INFO: PixTestScurves::fullTest() done, duration: 816 seconds
[15:33:14.098] <TB1> INFO: ######################################################################
[15:33:14.098] <TB1> INFO: PixTestTrim::doTest()
[15:33:14.098] <TB1> INFO: ######################################################################
[15:33:14.100] <TB1> INFO: ----------------------------------------------------------------------
[15:33:14.100] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[15:33:14.100] <TB1> INFO: ----------------------------------------------------------------------
[15:33:14.185] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:33:14.185] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 20 dacrange: 0 .. 159 (-1/-1) hits flags = 16 (plus default)
[15:33:14.194] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[15:33:14.194] <TB1> INFO: run 1 of 1
[15:33:14.518] <TB1> INFO: Expecting 13312000 events.
[15:33:41.872] <TB1> INFO: 1082360 events read in total (26637ms).
[15:34:07.070] <TB1> INFO: 2159620 events read in total (51835ms).
[15:34:34.777] <TB1> INFO: 3234160 events read in total (79542ms).
[15:35:02.445] <TB1> INFO: 4306240 events read in total (107210ms).
[15:35:30.038] <TB1> INFO: 5373980 events read in total (134803ms).
[15:35:57.757] <TB1> INFO: 6439440 events read in total (162522ms).
[15:36:25.412] <TB1> INFO: 7509980 events read in total (190177ms).
[15:36:53.039] <TB1> INFO: 8584240 events read in total (217804ms).
[15:37:20.581] <TB1> INFO: 9658640 events read in total (245346ms).
[15:37:48.183] <TB1> INFO: 10734700 events read in total (272948ms).
[15:38:15.894] <TB1> INFO: 11810800 events read in total (300659ms).
[15:38:43.679] <TB1> INFO: 12886960 events read in total (328444ms).
[15:38:54.153] <TB1> INFO: 13312000 events read in total (338918ms).
[15:38:54.186] <TB1> INFO: Test took 339992ms.
[15:38:54.238] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:13.933] <TB1> INFO: ROC 0 VthrComp = 95
[15:39:13.933] <TB1> INFO: ROC 1 VthrComp = 82
[15:39:13.933] <TB1> INFO: ROC 2 VthrComp = 98
[15:39:13.934] <TB1> INFO: ROC 3 VthrComp = 95
[15:39:13.934] <TB1> INFO: ROC 4 VthrComp = 89
[15:39:13.934] <TB1> INFO: ROC 5 VthrComp = 96
[15:39:13.934] <TB1> INFO: ROC 6 VthrComp = 105
[15:39:13.935] <TB1> INFO: ROC 7 VthrComp = 100
[15:39:13.935] <TB1> INFO: ROC 8 VthrComp = 101
[15:39:13.935] <TB1> INFO: ROC 9 VthrComp = 91
[15:39:13.935] <TB1> INFO: ROC 10 VthrComp = 97
[15:39:13.936] <TB1> INFO: ROC 11 VthrComp = 108
[15:39:13.936] <TB1> INFO: ROC 12 VthrComp = 90
[15:39:13.936] <TB1> INFO: ROC 13 VthrComp = 86
[15:39:13.936] <TB1> INFO: ROC 14 VthrComp = 84
[15:39:13.937] <TB1> INFO: ROC 15 VthrComp = 106
[15:39:13.937] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:39:13.937] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 20 dacrange: 0 .. 159 (-1/-1) hits flags = 16 (plus default)
[15:39:13.945] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[15:39:13.945] <TB1> INFO: run 1 of 1
[15:39:14.302] <TB1> INFO: Expecting 13312000 events.
[15:39:39.993] <TB1> INFO: 779740 events read in total (24974ms).
[15:40:04.925] <TB1> INFO: 1556720 events read in total (49906ms).
[15:40:27.961] <TB1> INFO: 2333200 events read in total (72942ms).
[15:40:51.946] <TB1> INFO: 3110060 events read in total (96927ms).
[15:41:16.951] <TB1> INFO: 3886320 events read in total (121932ms).
[15:41:42.099] <TB1> INFO: 4663640 events read in total (147080ms).
[15:42:07.062] <TB1> INFO: 5439860 events read in total (172043ms).
[15:42:32.083] <TB1> INFO: 6216840 events read in total (197064ms).
[15:42:57.208] <TB1> INFO: 6990600 events read in total (222189ms).
[15:43:22.049] <TB1> INFO: 7761240 events read in total (247030ms).
[15:43:46.825] <TB1> INFO: 8529940 events read in total (271806ms).
[15:44:11.818] <TB1> INFO: 9297620 events read in total (296799ms).
[15:44:36.599] <TB1> INFO: 10063780 events read in total (321580ms).
[15:45:01.281] <TB1> INFO: 10830120 events read in total (346262ms).
[15:45:25.916] <TB1> INFO: 11595720 events read in total (370897ms).
[15:45:50.672] <TB1> INFO: 12361840 events read in total (395653ms).
[15:46:15.397] <TB1> INFO: 13128600 events read in total (420378ms).
[15:46:21.497] <TB1> INFO: 13312000 events read in total (426478ms).
[15:46:21.538] <TB1> INFO: Test took 427594ms.
[15:46:21.686] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:45.538] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 56.9523 for pixel 23/79 mean/min/max = 44.7938/32.6118/56.9757
[15:46:45.538] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 60.2583 for pixel 14/0 mean/min/max = 46.7543/33.0665/60.4421
[15:46:45.539] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 61.9147 for pixel 21/71 mean/min/max = 46.7623/31.4449/62.0798
[15:46:45.539] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 62.8675 for pixel 20/10 mean/min/max = 47.1014/31.1584/63.0444
[15:46:45.539] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 56.5707 for pixel 18/19 mean/min/max = 45.088/33.5322/56.6437
[15:46:45.539] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 67.3396 for pixel 0/64 mean/min/max = 49.588/31.7266/67.4494
[15:46:45.540] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 63.5696 for pixel 22/47 mean/min/max = 48.1704/32.7587/63.5821
[15:46:45.540] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 60.1688 for pixel 3/16 mean/min/max = 46.2974/32.357/60.2377
[15:46:45.540] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 59.8163 for pixel 19/21 mean/min/max = 45.7347/31.6333/59.836
[15:46:45.540] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 56.9424 for pixel 17/50 mean/min/max = 45.3517/33.3631/57.3403
[15:46:45.541] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 58.0208 for pixel 22/6 mean/min/max = 45.0091/31.8965/58.1217
[15:46:45.541] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 59.5196 for pixel 19/66 mean/min/max = 47.2182/34.8176/59.6188
[15:46:45.541] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 57.8487 for pixel 29/2 mean/min/max = 45.5473/33.2192/57.8755
[15:46:45.541] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 55.8562 for pixel 5/13 mean/min/max = 43.753/31.5745/55.9315
[15:46:45.542] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 55.445 for pixel 24/77 mean/min/max = 43.971/32.36/55.5821
[15:46:45.542] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 61.5621 for pixel 12/1 mean/min/max = 47.9772/34.1455/61.8089
[15:46:45.542] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:45.675] <TB1> INFO: Expecting 1029120 events.
[15:47:09.468] <TB1> INFO: 1029120 events read in total (23076ms).
[15:47:09.474] <TB1> INFO: Expecting 1029120 events.
[15:47:32.992] <TB1> INFO: 1029120 events read in total (22970ms).
[15:47:33.000] <TB1> INFO: Expecting 1029120 events.
[15:47:56.772] <TB1> INFO: 1029120 events read in total (23227ms).
[15:47:56.781] <TB1> INFO: Expecting 1029120 events.
[15:48:20.187] <TB1> INFO: 1029120 events read in total (22869ms).
[15:48:20.197] <TB1> INFO: Expecting 1029120 events.
[15:48:42.059] <TB1> INFO: 1029120 events read in total (21321ms).
[15:48:42.072] <TB1> INFO: Expecting 1029120 events.
[15:49:05.192] <TB1> INFO: 1029120 events read in total (22576ms).
[15:49:05.206] <TB1> INFO: Expecting 1029120 events.
[15:49:29.123] <TB1> INFO: 1029120 events read in total (23370ms).
[15:49:29.140] <TB1> INFO: Expecting 1029120 events.
[15:49:52.596] <TB1> INFO: 1029120 events read in total (22923ms).
[15:49:52.614] <TB1> INFO: Expecting 1029120 events.
[15:50:16.581] <TB1> INFO: 1029120 events read in total (23433ms).
[15:50:16.606] <TB1> INFO: Expecting 1029120 events.
[15:50:40.215] <TB1> INFO: 1029120 events read in total (23080ms).
[15:50:40.240] <TB1> INFO: Expecting 1029120 events.
[15:51:04.212] <TB1> INFO: 1029120 events read in total (23443ms).
[15:51:04.235] <TB1> INFO: Expecting 1029120 events.
[15:51:27.936] <TB1> INFO: 1029120 events read in total (23170ms).
[15:51:27.963] <TB1> INFO: Expecting 1029120 events.
[15:51:51.635] <TB1> INFO: 1029120 events read in total (23143ms).
[15:51:51.663] <TB1> INFO: Expecting 1029120 events.
[15:52:15.333] <TB1> INFO: 1029120 events read in total (23141ms).
[15:52:15.362] <TB1> INFO: Expecting 1029120 events.
[15:52:39.020] <TB1> INFO: 1029120 events read in total (23128ms).
[15:52:39.054] <TB1> INFO: Expecting 1029120 events.
[15:53:02.807] <TB1> INFO: 1029120 events read in total (23225ms).
[15:53:02.841] <TB1> INFO: Test took 377299ms.
[15:53:03.872] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 16 (plus default)
[15:53:03.880] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:53:03.880] <TB1> INFO: run 1 of 1
[15:53:04.191] <TB1> INFO: Expecting 8320000 events.
[15:53:31.759] <TB1> INFO: 723120 events read in total (26851ms).
[15:53:58.599] <TB1> INFO: 1445520 events read in total (53691ms).
[15:54:23.620] <TB1> INFO: 2167670 events read in total (78712ms).
[15:54:48.332] <TB1> INFO: 2890400 events read in total (103424ms).
[15:55:13.426] <TB1> INFO: 3612870 events read in total (128518ms).
[15:55:38.889] <TB1> INFO: 4334270 events read in total (153981ms).
[15:56:05.597] <TB1> INFO: 5050340 events read in total (180689ms).
[15:56:30.160] <TB1> INFO: 5764240 events read in total (205252ms).
[15:56:56.868] <TB1> INFO: 6476580 events read in total (231960ms).
[15:57:23.604] <TB1> INFO: 7187380 events read in total (258696ms).
[15:57:50.275] <TB1> INFO: 7898380 events read in total (285367ms).
[15:58:06.241] <TB1> INFO: 8320000 events read in total (301333ms).
[15:58:06.301] <TB1> INFO: Test took 302421ms.
[15:58:06.502] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:32.531] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.052679 .. 255.000000
[15:58:32.606] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 20 dacrange: 0 .. 255 (-1/-1) hits flags = 16 (plus default)
[15:58:32.614] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[15:58:32.614] <TB1> INFO: run 1 of 1
[15:58:32.920] <TB1> INFO: Expecting 21299200 events.
[15:58:57.776] <TB1> INFO: 711600 events read in total (24140ms).
[15:59:21.831] <TB1> INFO: 1424520 events read in total (48195ms).
[15:59:45.894] <TB1> INFO: 2137020 events read in total (72258ms).
[16:00:10.115] <TB1> INFO: 2849560 events read in total (96479ms).
[16:00:34.285] <TB1> INFO: 3561940 events read in total (120649ms).
[16:00:58.501] <TB1> INFO: 4274360 events read in total (144865ms).
[16:01:22.609] <TB1> INFO: 4987120 events read in total (168973ms).
[16:01:44.226] <TB1> INFO: 5700500 events read in total (190590ms).
[16:02:08.572] <TB1> INFO: 6413080 events read in total (214936ms).
[16:02:32.778] <TB1> INFO: 7125960 events read in total (239142ms).
[16:02:57.068] <TB1> INFO: 7838640 events read in total (263432ms).
[16:03:21.052] <TB1> INFO: 8551880 events read in total (287416ms).
[16:03:45.181] <TB1> INFO: 9264620 events read in total (311545ms).
[16:04:09.391] <TB1> INFO: 9977020 events read in total (335755ms).
[16:04:33.613] <TB1> INFO: 10689880 events read in total (359977ms).
[16:04:57.921] <TB1> INFO: 11402900 events read in total (384285ms).
[16:05:22.396] <TB1> INFO: 12115640 events read in total (408760ms).
[16:05:46.765] <TB1> INFO: 12828200 events read in total (433129ms).
[16:06:11.098] <TB1> INFO: 13540220 events read in total (457462ms).
[16:06:35.330] <TB1> INFO: 14251980 events read in total (481694ms).
[16:06:59.541] <TB1> INFO: 14963600 events read in total (505905ms).
[16:07:23.627] <TB1> INFO: 15675320 events read in total (529991ms).
[16:07:47.740] <TB1> INFO: 16386320 events read in total (554104ms).
[16:08:11.836] <TB1> INFO: 17097020 events read in total (578200ms).
[16:08:35.821] <TB1> INFO: 17808020 events read in total (602185ms).
[16:09:00.020] <TB1> INFO: 18518340 events read in total (626384ms).
[16:09:24.178] <TB1> INFO: 19228920 events read in total (650542ms).
[16:09:48.367] <TB1> INFO: 19939620 events read in total (674731ms).
[16:10:12.630] <TB1> INFO: 20649900 events read in total (698994ms).
[16:10:34.905] <TB1> INFO: 21299200 events read in total (721269ms).
[16:10:35.000] <TB1> INFO: Test took 722387ms.
[16:10:35.279] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:11:03.591] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 13.880400 .. 46.342078
[16:11:03.672] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 20 dacrange: 3 .. 56 (-1/-1) hits flags = 16 (plus default)
[16:11:03.681] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[16:11:03.681] <TB1> INFO: run 1 of 1
[16:11:03.994] <TB1> INFO: Expecting 4492800 events.
[16:11:31.440] <TB1> INFO: 941000 events read in total (26721ms).
[16:11:57.983] <TB1> INFO: 1882400 events read in total (53264ms).
[16:12:24.514] <TB1> INFO: 2823120 events read in total (79795ms).
[16:12:50.916] <TB1> INFO: 3762040 events read in total (106197ms).
[16:13:11.711] <TB1> INFO: 4492800 events read in total (126992ms).
[16:13:11.722] <TB1> INFO: Test took 128041ms.
[16:13:11.755] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:25.118] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 0.172362 .. 43.690697
[16:13:25.194] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 20 dacrange: 0 .. 53 (-1/-1) hits flags = 16 (plus default)
[16:13:25.202] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[16:13:25.202] <TB1> INFO: run 1 of 1
[16:13:25.507] <TB1> INFO: Expecting 4492800 events.
[16:13:53.137] <TB1> INFO: 981240 events read in total (26913ms).
[16:14:19.966] <TB1> INFO: 1963140 events read in total (53742ms).
[16:14:46.864] <TB1> INFO: 2944540 events read in total (80640ms).
[16:15:12.082] <TB1> INFO: 3924640 events read in total (105858ms).
[16:15:26.379] <TB1> INFO: 4492800 events read in total (120155ms).
[16:15:26.392] <TB1> INFO: Test took 121191ms.
[16:15:26.423] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:15:39.002] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 0.232808 .. 43.342696
[16:15:39.078] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 20 dacrange: 0 .. 53 (-1/-1) hits flags = 16 (plus default)
[16:15:39.086] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[16:15:39.086] <TB1> INFO: run 1 of 1
[16:15:39.392] <TB1> INFO: Expecting 4492800 events.
[16:16:07.024] <TB1> INFO: 981900 events read in total (26915ms).
[16:16:33.634] <TB1> INFO: 1964640 events read in total (53525ms).
[16:17:00.380] <TB1> INFO: 2946980 events read in total (80271ms).
[16:17:27.241] <TB1> INFO: 3928660 events read in total (107132ms).
[16:17:42.925] <TB1> INFO: 4492800 events read in total (122816ms).
[16:17:42.942] <TB1> INFO: Test took 123857ms.
[16:17:42.977] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:17:56.466] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[16:17:56.466] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 16 (plus default)
[16:17:56.475] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:17:56.475] <TB1> INFO: run 1 of 1
[16:17:56.788] <TB1> INFO: Expecting 1705600 events.
[16:18:26.542] <TB1> INFO: 878200 events read in total (29037ms).
[16:18:52.084] <TB1> INFO: 1705600 events read in total (54579ms).
[16:18:52.095] <TB1> INFO: Test took 55620ms.
[16:18:52.125] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:05.788] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:19:05.788] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:19:05.788] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:19:05.788] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:19:05.788] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:19:05.788] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:19:05.788] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:19:05.789] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:19:05.789] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:19:05.789] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:19:05.789] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:19:05.789] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:19:05.789] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:19:05.789] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:19:05.789] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:19:05.789] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:19:05.790] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C0.dat
[16:19:05.796] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C1.dat
[16:19:05.802] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C2.dat
[16:19:05.809] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C3.dat
[16:19:05.815] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C4.dat
[16:19:05.821] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C5.dat
[16:19:05.828] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C6.dat
[16:19:05.834] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C7.dat
[16:19:05.841] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C8.dat
[16:19:05.847] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C9.dat
[16:19:05.853] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C10.dat
[16:19:05.860] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C11.dat
[16:19:05.866] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C12.dat
[16:19:05.873] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C13.dat
[16:19:05.879] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C14.dat
[16:19:05.885] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//trimParameters35_C15.dat
[16:19:05.892] <TB1> INFO: PixTestTrim::trimTest() done
[16:19:05.892] <TB1> INFO: vtrim: 90 96 105 112 90 116 122 99 114 86 100 97 89 85 81 111
[16:19:05.892] <TB1> INFO: vthrcomp: 95 82 98 95 89 96 105 100 101 91 97 108 90 86 84 106
[16:19:05.892] <TB1> INFO: vcal mean: 34.94 34.94 34.92 34.90 35.00 34.86 34.88 34.87 34.92 34.92 34.89 34.92 34.92 34.86 34.87 34.92
[16:19:05.892] <TB1> INFO: vcal RMS: 0.72 0.84 0.85 1.01 0.75 1.04 1.02 0.85 0.81 0.77 0.79 0.82 0.84 0.81 0.74 0.80
[16:19:05.892] <TB1> INFO: bits mean: 9.07 9.33 9.10 9.59 9.08 8.40 9.06 9.06 9.45 8.95 9.60 8.30 9.07 9.87 9.53 8.45
[16:19:05.892] <TB1> INFO: bits RMS: 2.87 2.59 2.93 2.66 2.65 2.94 2.56 2.84 2.74 2.78 2.74 2.68 2.71 2.69 2.72 2.63
[16:19:05.901] <TB1> INFO: ----------------------------------------------------------------------
[16:19:05.901] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[16:19:05.901] <TB1> INFO: ----------------------------------------------------------------------
[16:19:05.905] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 16 (plus default)
[16:19:05.913] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:19:05.913] <TB1> INFO: run 1 of 1
[16:19:06.226] <TB1> INFO: Expecting 8320000 events.
[16:19:34.182] <TB1> INFO: 953070 events read in total (27239ms).
[16:20:03.852] <TB1> INFO: 1898850 events read in total (56909ms).
[16:20:33.371] <TB1> INFO: 2841090 events read in total (86428ms).
[16:21:03.044] <TB1> INFO: 3780060 events read in total (116101ms).
[16:21:32.523] <TB1> INFO: 4712740 events read in total (145580ms).
[16:22:01.761] <TB1> INFO: 5641200 events read in total (174818ms).
[16:22:31.173] <TB1> INFO: 6568750 events read in total (204230ms).
[16:22:59.445] <TB1> INFO: 7496670 events read in total (232502ms).
[16:23:25.546] <TB1> INFO: 8320000 events read in total (258603ms).
[16:23:25.584] <TB1> INFO: Test took 259671ms.
[16:23:25.689] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:50.871] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 181 (-1/-1) hits flags = 16 (plus default)
[16:23:50.881] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:23:50.881] <TB1> INFO: run 1 of 1
[16:23:51.196] <TB1> INFO: Expecting 7571200 events.
[16:24:19.454] <TB1> INFO: 955370 events read in total (27541ms).
[16:24:46.802] <TB1> INFO: 1904810 events read in total (54889ms).
[16:25:14.606] <TB1> INFO: 2849390 events read in total (82693ms).
[16:25:42.855] <TB1> INFO: 3789760 events read in total (110942ms).
[16:26:10.420] <TB1> INFO: 4720800 events read in total (138507ms).
[16:26:39.869] <TB1> INFO: 5650710 events read in total (167956ms).
[16:27:09.296] <TB1> INFO: 6580260 events read in total (197383ms).
[16:27:37.129] <TB1> INFO: 7514970 events read in total (225216ms).
[16:27:39.311] <TB1> INFO: 7571200 events read in total (227398ms).
[16:27:39.345] <TB1> INFO: Test took 228464ms.
[16:27:39.436] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:04.511] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 167 (-1/-1) hits flags = 16 (plus default)
[16:28:04.519] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:28:04.519] <TB1> INFO: run 1 of 1
[16:28:04.826] <TB1> INFO: Expecting 6988800 events.
[16:28:34.807] <TB1> INFO: 991560 events read in total (29264ms).
[16:29:04.958] <TB1> INFO: 1973970 events read in total (59415ms).
[16:29:35.046] <TB1> INFO: 2952870 events read in total (89503ms).
[16:30:05.011] <TB1> INFO: 3922070 events read in total (119468ms).
[16:30:34.961] <TB1> INFO: 4884550 events read in total (149418ms).
[16:31:04.851] <TB1> INFO: 5845990 events read in total (179308ms).
[16:31:34.813] <TB1> INFO: 6810120 events read in total (209270ms).
[16:31:40.769] <TB1> INFO: 6988800 events read in total (215226ms).
[16:31:40.805] <TB1> INFO: Test took 216286ms.
[16:31:40.883] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:07.154] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 168 (-1/-1) hits flags = 16 (plus default)
[16:32:07.162] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:32:07.162] <TB1> INFO: run 1 of 1
[16:32:07.476] <TB1> INFO: Expecting 7030400 events.
[16:32:36.368] <TB1> INFO: 988300 events read in total (28175ms).
[16:33:06.799] <TB1> INFO: 1967990 events read in total (58606ms).
[16:33:37.135] <TB1> INFO: 2943560 events read in total (88942ms).
[16:34:07.454] <TB1> INFO: 3909740 events read in total (119261ms).
[16:34:35.655] <TB1> INFO: 4869530 events read in total (147462ms).
[16:35:05.874] <TB1> INFO: 5828250 events read in total (177681ms).
[16:35:36.138] <TB1> INFO: 6789000 events read in total (207945ms).
[16:35:43.961] <TB1> INFO: 7030400 events read in total (215768ms).
[16:35:43.992] <TB1> INFO: Test took 216830ms.
[16:35:44.065] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:07.257] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 168 (-1/-1) hits flags = 16 (plus default)
[16:36:07.265] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:36:07.265] <TB1> INFO: run 1 of 1
[16:36:07.570] <TB1> INFO: Expecting 7030400 events.
[16:36:36.138] <TB1> INFO: 987550 events read in total (27851ms).
[16:37:04.943] <TB1> INFO: 1966550 events read in total (56656ms).
[16:37:35.075] <TB1> INFO: 2941310 events read in total (86789ms).
[16:38:05.183] <TB1> INFO: 3906630 events read in total (116896ms).
[16:38:35.192] <TB1> INFO: 4865340 events read in total (146905ms).
[16:39:04.900] <TB1> INFO: 5823450 events read in total (176614ms).
[16:39:34.152] <TB1> INFO: 6783740 events read in total (205865ms).
[16:39:42.083] <TB1> INFO: 7030400 events read in total (213796ms).
[16:39:42.111] <TB1> INFO: Test took 214846ms.
[16:39:42.186] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:07.593] <TB1> INFO: PixTestTrim::trimBitTest() done
[16:40:07.595] <TB1> INFO: PixTestTrim::doTest() done, duration: 4013 seconds
[16:40:08.298] <TB1> INFO: ######################################################################
[16:40:08.298] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:40:08.298] <TB1> INFO: ######################################################################
[16:40:08.613] <TB1> INFO: Expecting 41600 events.
[16:40:12.891] <TB1> INFO: 41600 events read in total (3561ms).
[16:40:12.891] <TB1> INFO: Test took 4591ms.
[16:40:12.898] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:13.483] <TB1> INFO: Expecting 41600 events.
[16:40:17.846] <TB1> INFO: 41600 events read in total (3646ms).
[16:40:17.847] <TB1> INFO: Test took 4684ms.
[16:40:17.855] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:18.258] <TB1> INFO: Expecting 41600 events.
[16:40:22.648] <TB1> INFO: 41600 events read in total (3673ms).
[16:40:22.648] <TB1> INFO: Test took 4775ms.
[16:40:22.656] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:22.667] <TB1> INFO: The DUT currently contains the following objects:
[16:40:22.667] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:22.667] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:22.667] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:22.667] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:22.667] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.668] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:22.989] <TB1> INFO: Expecting 2560 events.
[16:40:24.055] <TB1> INFO: 2560 events read in total (349ms).
[16:40:24.055] <TB1> INFO: Test took 1387ms.
[16:40:24.055] <TB1> INFO: The DUT currently contains the following objects:
[16:40:24.055] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:24.055] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:24.055] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:24.055] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:24.055] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.055] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.055] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.055] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.055] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.055] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.055] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.055] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.055] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.055] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.055] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.055] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.056] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.056] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.056] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.056] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:24.470] <TB1> INFO: Expecting 2560 events.
[16:40:25.534] <TB1> INFO: 2560 events read in total (347ms).
[16:40:25.534] <TB1> INFO: Test took 1478ms.
[16:40:25.534] <TB1> INFO: The DUT currently contains the following objects:
[16:40:25.534] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:25.534] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:25.534] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:25.534] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:25.534] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.535] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:25.950] <TB1> INFO: Expecting 2560 events.
[16:40:27.013] <TB1> INFO: 2560 events read in total (346ms).
[16:40:27.013] <TB1> INFO: Test took 1478ms.
[16:40:27.014] <TB1> INFO: The DUT currently contains the following objects:
[16:40:27.014] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:27.014] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:27.014] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:27.014] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:27.014] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.014] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:27.429] <TB1> INFO: Expecting 2560 events.
[16:40:28.495] <TB1> INFO: 2560 events read in total (349ms).
[16:40:28.495] <TB1> INFO: Test took 1481ms.
[16:40:28.495] <TB1> INFO: The DUT currently contains the following objects:
[16:40:28.495] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:28.495] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:28.495] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:28.495] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:28.495] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.495] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:28.910] <TB1> INFO: Expecting 2560 events.
[16:40:29.975] <TB1> INFO: 2560 events read in total (348ms).
[16:40:29.975] <TB1> INFO: Test took 1479ms.
[16:40:29.975] <TB1> INFO: The DUT currently contains the following objects:
[16:40:29.975] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:29.975] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:29.975] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:29.975] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:29.975] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:29.976] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:30.391] <TB1> INFO: Expecting 2560 events.
[16:40:31.454] <TB1> INFO: 2560 events read in total (346ms).
[16:40:31.454] <TB1> INFO: Test took 1478ms.
[16:40:31.454] <TB1> INFO: The DUT currently contains the following objects:
[16:40:31.454] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:31.454] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:31.454] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:31.454] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:31.455] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.455] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:31.870] <TB1> INFO: Expecting 2560 events.
[16:40:32.936] <TB1> INFO: 2560 events read in total (349ms).
[16:40:32.936] <TB1> INFO: Test took 1481ms.
[16:40:32.936] <TB1> INFO: The DUT currently contains the following objects:
[16:40:32.936] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:32.936] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:32.936] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:32.936] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:32.936] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.936] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.936] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.936] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.936] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.936] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.936] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.936] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.936] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.936] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.936] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.936] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.936] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.936] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.936] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:32.937] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:33.351] <TB1> INFO: Expecting 2560 events.
[16:40:34.417] <TB1> INFO: 2560 events read in total (349ms).
[16:40:34.417] <TB1> INFO: Test took 1480ms.
[16:40:34.417] <TB1> INFO: The DUT currently contains the following objects:
[16:40:34.417] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:34.417] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:34.417] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:34.417] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:34.417] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.417] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.418] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.418] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.418] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.418] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.418] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.418] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.418] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.418] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.418] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.418] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.418] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.418] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.418] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.418] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:34.833] <TB1> INFO: Expecting 2560 events.
[16:40:35.903] <TB1> INFO: 2560 events read in total (353ms).
[16:40:35.903] <TB1> INFO: Test took 1485ms.
[16:40:35.904] <TB1> INFO: The DUT currently contains the following objects:
[16:40:35.904] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:35.904] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:35.904] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:35.904] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:35.904] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.904] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.905] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.905] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.905] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.905] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.905] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.905] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.905] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.905] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.905] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.905] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.905] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.905] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.905] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:35.905] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:36.319] <TB1> INFO: Expecting 2560 events.
[16:40:37.389] <TB1> INFO: 2560 events read in total (353ms).
[16:40:37.389] <TB1> INFO: Test took 1484ms.
[16:40:37.390] <TB1> INFO: The DUT currently contains the following objects:
[16:40:37.390] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:37.390] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:37.390] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:37.390] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:37.390] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.390] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.390] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.390] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.390] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.391] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.391] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.391] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.391] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.391] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.391] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.391] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.391] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.391] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.391] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.391] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:37.805] <TB1> INFO: Expecting 2560 events.
[16:40:38.874] <TB1> INFO: 2560 events read in total (352ms).
[16:40:38.875] <TB1> INFO: Test took 1484ms.
[16:40:38.876] <TB1> INFO: The DUT currently contains the following objects:
[16:40:38.876] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:38.876] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:38.876] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:38.876] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:38.876] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.876] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.876] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.876] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.876] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.876] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.876] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.876] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.876] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.876] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.876] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.876] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.876] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.876] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.877] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:38.877] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:39.291] <TB1> INFO: Expecting 2560 events.
[16:40:40.362] <TB1> INFO: 2560 events read in total (354ms).
[16:40:40.362] <TB1> INFO: Test took 1485ms.
[16:40:40.363] <TB1> INFO: The DUT currently contains the following objects:
[16:40:40.363] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:40.363] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:40.363] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:40.363] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:40.363] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.363] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.363] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.363] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.363] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.363] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.364] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.364] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.364] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.364] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.364] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.364] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.364] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.364] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.364] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.364] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:40.778] <TB1> INFO: Expecting 2560 events.
[16:40:41.848] <TB1> INFO: 2560 events read in total (353ms).
[16:40:41.849] <TB1> INFO: Test took 1485ms.
[16:40:41.849] <TB1> INFO: The DUT currently contains the following objects:
[16:40:41.849] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:41.849] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:41.849] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:41.849] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:41.849] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.849] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.849] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.850] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.850] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.850] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.850] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.850] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.850] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.850] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.850] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.850] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.850] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.850] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.850] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:41.850] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:42.264] <TB1> INFO: Expecting 2560 events.
[16:40:43.328] <TB1> INFO: 2560 events read in total (347ms).
[16:40:43.328] <TB1> INFO: Test took 1478ms.
[16:40:43.328] <TB1> INFO: The DUT currently contains the following objects:
[16:40:43.328] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:43.328] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:43.328] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:43.328] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:43.328] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.328] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.329] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.329] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.329] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.329] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.329] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.329] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.329] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.329] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.329] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.329] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.329] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.329] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.329] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.329] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:43.744] <TB1> INFO: Expecting 2560 events.
[16:40:44.807] <TB1> INFO: 2560 events read in total (346ms).
[16:40:44.807] <TB1> INFO: Test took 1478ms.
[16:40:44.808] <TB1> INFO: The DUT currently contains the following objects:
[16:40:44.808] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:40:44.808] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:40:44.808] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:40:44.808] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:40:44.808] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:44.808] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:40:45.223] <TB1> INFO: Expecting 2560 events.
[16:40:46.287] <TB1> INFO: 2560 events read in total (347ms).
[16:40:46.287] <TB1> INFO: Test took 1479ms.
[16:40:46.292] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:40:46.705] <TB1> INFO: Expecting 655360 events.
[16:41:01.935] <TB1> INFO: 655360 events read in total (14513ms).
[16:41:01.943] <TB1> INFO: Expecting 655360 events.
[16:41:18.430] <TB1> INFO: 655360 events read in total (15958ms).
[16:41:18.442] <TB1> INFO: Expecting 655360 events.
[16:41:34.899] <TB1> INFO: 655360 events read in total (15929ms).
[16:41:34.915] <TB1> INFO: Expecting 655360 events.
[16:41:51.380] <TB1> INFO: 655360 events read in total (15937ms).
[16:41:51.402] <TB1> INFO: Expecting 655360 events.
[16:42:08.022] <TB1> INFO: 655360 events read in total (16092ms).
[16:42:08.047] <TB1> INFO: Expecting 655360 events.
[16:42:24.557] <TB1> INFO: 655360 events read in total (15982ms).
[16:42:24.585] <TB1> INFO: Expecting 655360 events.
[16:42:41.039] <TB1> INFO: 655360 events read in total (15925ms).
[16:42:41.068] <TB1> INFO: Expecting 655360 events.
[16:42:57.624] <TB1> INFO: 655360 events read in total (16028ms).
[16:42:57.657] <TB1> INFO: Expecting 655360 events.
[16:43:14.169] <TB1> INFO: 655360 events read in total (15984ms).
[16:43:14.207] <TB1> INFO: Expecting 655360 events.
[16:43:30.715] <TB1> INFO: 655360 events read in total (15980ms).
[16:43:30.754] <TB1> INFO: Expecting 655360 events.
[16:43:47.234] <TB1> INFO: 655360 events read in total (15952ms).
[16:43:47.279] <TB1> INFO: Expecting 655360 events.
[16:44:03.561] <TB1> INFO: 655360 events read in total (15754ms).
[16:44:03.607] <TB1> INFO: Expecting 655360 events.
[16:44:18.680] <TB1> INFO: 655360 events read in total (14545ms).
[16:44:18.729] <TB1> INFO: Expecting 655360 events.
[16:44:35.356] <TB1> INFO: 655360 events read in total (16099ms).
[16:44:35.412] <TB1> INFO: Expecting 655360 events.
[16:44:51.862] <TB1> INFO: 655360 events read in total (15921ms).
[16:44:51.919] <TB1> INFO: Expecting 655360 events.
[16:45:08.546] <TB1> INFO: 655360 events read in total (16098ms).
[16:45:08.606] <TB1> INFO: Test took 262314ms.
[16:45:08.692] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:45:09.000] <TB1> INFO: Expecting 655360 events.
[16:45:24.621] <TB1> INFO: 655360 events read in total (14904ms).
[16:45:24.631] <TB1> INFO: Expecting 655360 events.
[16:45:39.471] <TB1> INFO: 655360 events read in total (14312ms).
[16:45:39.483] <TB1> INFO: Expecting 655360 events.
[16:45:55.951] <TB1> INFO: 655360 events read in total (15940ms).
[16:45:55.969] <TB1> INFO: Expecting 655360 events.
[16:46:12.487] <TB1> INFO: 655360 events read in total (15990ms).
[16:46:12.508] <TB1> INFO: Expecting 655360 events.
[16:46:28.959] <TB1> INFO: 655360 events read in total (15923ms).
[16:46:28.982] <TB1> INFO: Expecting 655360 events.
[16:46:45.416] <TB1> INFO: 655360 events read in total (15906ms).
[16:46:45.448] <TB1> INFO: Expecting 655360 events.
[16:47:01.644] <TB1> INFO: 655360 events read in total (15667ms).
[16:47:01.675] <TB1> INFO: Expecting 655360 events.
[16:47:18.061] <TB1> INFO: 655360 events read in total (15858ms).
[16:47:18.097] <TB1> INFO: Expecting 655360 events.
[16:47:34.652] <TB1> INFO: 655360 events read in total (16027ms).
[16:47:34.689] <TB1> INFO: Expecting 655360 events.
[16:47:49.942] <TB1> INFO: 655360 events read in total (14724ms).
[16:47:49.985] <TB1> INFO: Expecting 655360 events.
[16:48:05.120] <TB1> INFO: 655360 events read in total (14607ms).
[16:48:05.162] <TB1> INFO: Expecting 655360 events.
[16:48:21.478] <TB1> INFO: 655360 events read in total (15788ms).
[16:48:21.524] <TB1> INFO: Expecting 655360 events.
[16:48:38.149] <TB1> INFO: 655360 events read in total (16097ms).
[16:48:38.199] <TB1> INFO: Expecting 655360 events.
[16:48:54.540] <TB1> INFO: 655360 events read in total (15813ms).
[16:48:54.593] <TB1> INFO: Expecting 655360 events.
[16:49:11.124] <TB1> INFO: 655360 events read in total (16002ms).
[16:49:11.185] <TB1> INFO: Expecting 655360 events.
[16:49:27.616] <TB1> INFO: 655360 events read in total (15902ms).
[16:49:27.681] <TB1> INFO: Test took 258989ms.
[16:49:27.887] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:27.895] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:27.902] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:27.909] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:27.917] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:27.924] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:27.931] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:27.939] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:27.946] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:27.954] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:27.961] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:27.968] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:27.975] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[16:49:27.982] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[16:49:27.989] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[16:49:27.997] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[16:49:28.004] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[16:49:28.011] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[16:49:28.018] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[16:49:28.025] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[16:49:28.031] <TB1> INFO: safety margin for low PH: adding 9, margin is now 29
[16:49:28.038] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:28.045] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:28.052] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:28.058] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:28.114] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:49:28.114] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:49:28.114] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:49:28.114] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:49:28.114] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:49:28.114] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:49:28.114] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:49:28.115] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:49:28.115] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:49:28.115] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:49:28.115] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:49:28.115] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:49:28.115] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:49:28.115] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:49:28.115] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:49:28.115] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:49:28.430] <TB1> INFO: Expecting 41600 events.
[16:49:32.738] <TB1> INFO: 41600 events read in total (3591ms).
[16:49:32.738] <TB1> INFO: Test took 4618ms.
[16:49:33.304] <TB1> INFO: Expecting 41600 events.
[16:49:37.639] <TB1> INFO: 41600 events read in total (3618ms).
[16:49:37.640] <TB1> INFO: Test took 4653ms.
[16:49:38.189] <TB1> INFO: Expecting 41600 events.
[16:49:42.502] <TB1> INFO: 41600 events read in total (3597ms).
[16:49:42.502] <TB1> INFO: Test took 4619ms.
[16:49:42.750] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:42.883] <TB1> INFO: Expecting 2560 events.
[16:49:43.948] <TB1> INFO: 2560 events read in total (349ms).
[16:49:43.948] <TB1> INFO: Test took 1198ms.
[16:49:43.950] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:44.364] <TB1> INFO: Expecting 2560 events.
[16:49:45.428] <TB1> INFO: 2560 events read in total (347ms).
[16:49:45.429] <TB1> INFO: Test took 1479ms.
[16:49:45.431] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:45.845] <TB1> INFO: Expecting 2560 events.
[16:49:46.910] <TB1> INFO: 2560 events read in total (348ms).
[16:49:46.910] <TB1> INFO: Test took 1479ms.
[16:49:46.912] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:47.327] <TB1> INFO: Expecting 2560 events.
[16:49:48.390] <TB1> INFO: 2560 events read in total (347ms).
[16:49:48.390] <TB1> INFO: Test took 1478ms.
[16:49:48.393] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:48.807] <TB1> INFO: Expecting 2560 events.
[16:49:49.871] <TB1> INFO: 2560 events read in total (347ms).
[16:49:49.872] <TB1> INFO: Test took 1479ms.
[16:49:49.874] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:50.289] <TB1> INFO: Expecting 2560 events.
[16:49:51.352] <TB1> INFO: 2560 events read in total (346ms).
[16:49:51.353] <TB1> INFO: Test took 1479ms.
[16:49:51.355] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:51.769] <TB1> INFO: Expecting 2560 events.
[16:49:52.833] <TB1> INFO: 2560 events read in total (347ms).
[16:49:52.834] <TB1> INFO: Test took 1479ms.
[16:49:52.836] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:53.250] <TB1> INFO: Expecting 2560 events.
[16:49:54.317] <TB1> INFO: 2560 events read in total (350ms).
[16:49:54.317] <TB1> INFO: Test took 1481ms.
[16:49:54.320] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:54.734] <TB1> INFO: Expecting 2560 events.
[16:49:55.800] <TB1> INFO: 2560 events read in total (349ms).
[16:49:55.800] <TB1> INFO: Test took 1480ms.
[16:49:55.803] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:56.217] <TB1> INFO: Expecting 2560 events.
[16:49:57.281] <TB1> INFO: 2560 events read in total (347ms).
[16:49:57.281] <TB1> INFO: Test took 1478ms.
[16:49:57.284] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:57.698] <TB1> INFO: Expecting 2560 events.
[16:49:58.764] <TB1> INFO: 2560 events read in total (349ms).
[16:49:58.764] <TB1> INFO: Test took 1480ms.
[16:49:58.768] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:59.181] <TB1> INFO: Expecting 2560 events.
[16:50:00.252] <TB1> INFO: 2560 events read in total (354ms).
[16:50:00.252] <TB1> INFO: Test took 1484ms.
[16:50:00.255] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:00.669] <TB1> INFO: Expecting 2560 events.
[16:50:01.742] <TB1> INFO: 2560 events read in total (356ms).
[16:50:01.742] <TB1> INFO: Test took 1487ms.
[16:50:01.745] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:02.159] <TB1> INFO: Expecting 2560 events.
[16:50:03.243] <TB1> INFO: 2560 events read in total (367ms).
[16:50:03.244] <TB1> INFO: Test took 1499ms.
[16:50:03.246] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:03.660] <TB1> INFO: Expecting 2560 events.
[16:50:04.731] <TB1> INFO: 2560 events read in total (353ms).
[16:50:04.731] <TB1> INFO: Test took 1485ms.
[16:50:04.733] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:05.148] <TB1> INFO: Expecting 2560 events.
[16:50:06.219] <TB1> INFO: 2560 events read in total (354ms).
[16:50:06.219] <TB1> INFO: Test took 1486ms.
[16:50:06.222] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:06.636] <TB1> INFO: Expecting 2560 events.
[16:50:07.707] <TB1> INFO: 2560 events read in total (354ms).
[16:50:07.707] <TB1> INFO: Test took 1485ms.
[16:50:07.711] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:08.125] <TB1> INFO: Expecting 2560 events.
[16:50:09.196] <TB1> INFO: 2560 events read in total (354ms).
[16:50:09.197] <TB1> INFO: Test took 1487ms.
[16:50:09.200] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:09.613] <TB1> INFO: Expecting 2560 events.
[16:50:10.686] <TB1> INFO: 2560 events read in total (356ms).
[16:50:10.686] <TB1> INFO: Test took 1486ms.
[16:50:10.689] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:11.103] <TB1> INFO: Expecting 2560 events.
[16:50:12.175] <TB1> INFO: 2560 events read in total (355ms).
[16:50:12.175] <TB1> INFO: Test took 1486ms.
[16:50:12.178] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:12.592] <TB1> INFO: Expecting 2560 events.
[16:50:13.663] <TB1> INFO: 2560 events read in total (354ms).
[16:50:13.664] <TB1> INFO: Test took 1486ms.
[16:50:13.667] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:14.081] <TB1> INFO: Expecting 2560 events.
[16:50:15.153] <TB1> INFO: 2560 events read in total (356ms).
[16:50:15.153] <TB1> INFO: Test took 1486ms.
[16:50:15.157] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:15.570] <TB1> INFO: Expecting 2560 events.
[16:50:16.643] <TB1> INFO: 2560 events read in total (355ms).
[16:50:16.644] <TB1> INFO: Test took 1487ms.
[16:50:16.647] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:17.061] <TB1> INFO: Expecting 2560 events.
[16:50:18.132] <TB1> INFO: 2560 events read in total (354ms).
[16:50:18.133] <TB1> INFO: Test took 1486ms.
[16:50:18.136] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:18.550] <TB1> INFO: Expecting 2560 events.
[16:50:19.622] <TB1> INFO: 2560 events read in total (355ms).
[16:50:19.622] <TB1> INFO: Test took 1486ms.
[16:50:19.625] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:20.040] <TB1> INFO: Expecting 2560 events.
[16:50:21.111] <TB1> INFO: 2560 events read in total (354ms).
[16:50:21.112] <TB1> INFO: Test took 1487ms.
[16:50:21.115] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:21.528] <TB1> INFO: Expecting 2560 events.
[16:50:22.600] <TB1> INFO: 2560 events read in total (355ms).
[16:50:22.600] <TB1> INFO: Test took 1485ms.
[16:50:22.603] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:23.017] <TB1> INFO: Expecting 2560 events.
[16:50:24.090] <TB1> INFO: 2560 events read in total (356ms).
[16:50:24.091] <TB1> INFO: Test took 1488ms.
[16:50:24.094] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:24.507] <TB1> INFO: Expecting 2560 events.
[16:50:25.579] <TB1> INFO: 2560 events read in total (355ms).
[16:50:25.579] <TB1> INFO: Test took 1485ms.
[16:50:25.583] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:25.996] <TB1> INFO: Expecting 2560 events.
[16:50:27.066] <TB1> INFO: 2560 events read in total (353ms).
[16:50:27.067] <TB1> INFO: Test took 1485ms.
[16:50:27.071] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:27.483] <TB1> INFO: Expecting 2560 events.
[16:50:28.555] <TB1> INFO: 2560 events read in total (355ms).
[16:50:28.556] <TB1> INFO: Test took 1485ms.
[16:50:28.559] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:28.973] <TB1> INFO: Expecting 2560 events.
[16:50:30.040] <TB1> INFO: 2560 events read in total (350ms).
[16:50:30.040] <TB1> INFO: Test took 1481ms.
[16:50:30.710] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 622 seconds
[16:50:30.710] <TB1> INFO: PH scale (per ROC): 74 80 72 65 70 65 61 65 63 80 68 76 75 83 79 74
[16:50:30.710] <TB1> INFO: PH offset (per ROC): 161 172 193 175 179 192 173 191 165 170 167 176 174 144 160 175
[16:50:30.912] <TB1> INFO: ######################################################################
[16:50:30.912] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:50:30.912] <TB1> INFO: ######################################################################
[16:50:30.923] <TB1> INFO: scanning low vcal = 10
[16:50:31.235] <TB1> INFO: Expecting 41600 events.
[16:50:34.859] <TB1> INFO: 41600 events read in total (2907ms).
[16:50:34.860] <TB1> INFO: Test took 3937ms.
[16:50:34.862] <TB1> INFO: scanning low vcal = 20
[16:50:35.277] <TB1> INFO: Expecting 41600 events.
[16:50:38.901] <TB1> INFO: 41600 events read in total (2907ms).
[16:50:38.901] <TB1> INFO: Test took 4039ms.
[16:50:38.904] <TB1> INFO: scanning low vcal = 30
[16:50:39.317] <TB1> INFO: Expecting 41600 events.
[16:50:42.964] <TB1> INFO: 41600 events read in total (2930ms).
[16:50:42.964] <TB1> INFO: Test took 4060ms.
[16:50:42.967] <TB1> INFO: scanning low vcal = 40
[16:50:43.375] <TB1> INFO: Expecting 41600 events.
[16:50:47.619] <TB1> INFO: 41600 events read in total (3527ms).
[16:50:47.619] <TB1> INFO: Test took 4652ms.
[16:50:47.623] <TB1> INFO: scanning low vcal = 50
[16:50:47.968] <TB1> INFO: Expecting 41600 events.
[16:50:52.182] <TB1> INFO: 41600 events read in total (3497ms).
[16:50:52.183] <TB1> INFO: Test took 4560ms.
[16:50:52.187] <TB1> INFO: scanning low vcal = 60
[16:50:52.531] <TB1> INFO: Expecting 41600 events.
[16:50:56.768] <TB1> INFO: 41600 events read in total (3519ms).
[16:50:56.768] <TB1> INFO: Test took 4581ms.
[16:50:56.772] <TB1> INFO: scanning low vcal = 70
[16:50:57.118] <TB1> INFO: Expecting 41600 events.
[16:51:01.338] <TB1> INFO: 41600 events read in total (3503ms).
[16:51:01.338] <TB1> INFO: Test took 4566ms.
[16:51:01.341] <TB1> INFO: scanning low vcal = 80
[16:51:01.692] <TB1> INFO: Expecting 41600 events.
[16:51:05.943] <TB1> INFO: 41600 events read in total (3533ms).
[16:51:05.944] <TB1> INFO: Test took 4603ms.
[16:51:05.947] <TB1> INFO: scanning low vcal = 90
[16:51:06.300] <TB1> INFO: Expecting 41600 events.
[16:51:10.639] <TB1> INFO: 41600 events read in total (3623ms).
[16:51:10.640] <TB1> INFO: Test took 4693ms.
[16:51:10.644] <TB1> INFO: scanning low vcal = 100
[16:51:10.986] <TB1> INFO: Expecting 41600 events.
[16:51:15.151] <TB1> INFO: 41600 events read in total (3448ms).
[16:51:15.152] <TB1> INFO: Test took 4508ms.
[16:51:15.155] <TB1> INFO: scanning low vcal = 110
[16:51:15.494] <TB1> INFO: Expecting 41600 events.
[16:51:19.675] <TB1> INFO: 41600 events read in total (3465ms).
[16:51:19.675] <TB1> INFO: Test took 4520ms.
[16:51:19.679] <TB1> INFO: scanning low vcal = 120
[16:51:20.027] <TB1> INFO: Expecting 41600 events.
[16:51:24.259] <TB1> INFO: 41600 events read in total (3515ms).
[16:51:24.260] <TB1> INFO: Test took 4581ms.
[16:51:24.265] <TB1> INFO: scanning low vcal = 130
[16:51:24.614] <TB1> INFO: Expecting 41600 events.
[16:51:28.837] <TB1> INFO: 41600 events read in total (3506ms).
[16:51:28.837] <TB1> INFO: Test took 4572ms.
[16:51:28.841] <TB1> INFO: scanning low vcal = 140
[16:51:29.188] <TB1> INFO: Expecting 41600 events.
[16:51:33.389] <TB1> INFO: 41600 events read in total (3484ms).
[16:51:33.390] <TB1> INFO: Test took 4549ms.
[16:51:33.393] <TB1> INFO: scanning low vcal = 150
[16:51:33.744] <TB1> INFO: Expecting 41600 events.
[16:51:37.945] <TB1> INFO: 41600 events read in total (3484ms).
[16:51:37.946] <TB1> INFO: Test took 4553ms.
[16:51:37.949] <TB1> INFO: scanning low vcal = 160
[16:51:38.302] <TB1> INFO: Expecting 41600 events.
[16:51:42.535] <TB1> INFO: 41600 events read in total (3516ms).
[16:51:42.535] <TB1> INFO: Test took 4586ms.
[16:51:42.539] <TB1> INFO: scanning low vcal = 170
[16:51:42.881] <TB1> INFO: Expecting 41600 events.
[16:51:47.082] <TB1> INFO: 41600 events read in total (3484ms).
[16:51:47.083] <TB1> INFO: Test took 4544ms.
[16:51:47.088] <TB1> INFO: scanning low vcal = 180
[16:51:47.433] <TB1> INFO: Expecting 41600 events.
[16:51:51.662] <TB1> INFO: 41600 events read in total (3511ms).
[16:51:51.663] <TB1> INFO: Test took 4575ms.
[16:51:51.666] <TB1> INFO: scanning low vcal = 190
[16:51:52.014] <TB1> INFO: Expecting 41600 events.
[16:51:56.240] <TB1> INFO: 41600 events read in total (3509ms).
[16:51:56.241] <TB1> INFO: Test took 4575ms.
[16:51:56.245] <TB1> INFO: scanning low vcal = 200
[16:51:56.593] <TB1> INFO: Expecting 41600 events.
[16:52:00.778] <TB1> INFO: 41600 events read in total (3468ms).
[16:52:00.778] <TB1> INFO: Test took 4533ms.
[16:52:00.788] <TB1> INFO: scanning low vcal = 210
[16:52:01.130] <TB1> INFO: Expecting 41600 events.
[16:52:05.283] <TB1> INFO: 41600 events read in total (3436ms).
[16:52:05.284] <TB1> INFO: Test took 4496ms.
[16:52:05.287] <TB1> INFO: scanning low vcal = 220
[16:52:05.635] <TB1> INFO: Expecting 41600 events.
[16:52:09.817] <TB1> INFO: 41600 events read in total (3465ms).
[16:52:09.818] <TB1> INFO: Test took 4531ms.
[16:52:09.821] <TB1> INFO: scanning low vcal = 230
[16:52:10.164] <TB1> INFO: Expecting 41600 events.
[16:52:14.346] <TB1> INFO: 41600 events read in total (3466ms).
[16:52:14.347] <TB1> INFO: Test took 4526ms.
[16:52:14.352] <TB1> INFO: scanning low vcal = 240
[16:52:14.697] <TB1> INFO: Expecting 41600 events.
[16:52:18.929] <TB1> INFO: 41600 events read in total (3515ms).
[16:52:18.929] <TB1> INFO: Test took 4577ms.
[16:52:18.933] <TB1> INFO: scanning low vcal = 250
[16:52:19.281] <TB1> INFO: Expecting 41600 events.
[16:52:23.535] <TB1> INFO: 41600 events read in total (3537ms).
[16:52:23.536] <TB1> INFO: Test took 4603ms.
[16:52:23.542] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[16:52:23.880] <TB1> INFO: Expecting 41600 events.
[16:52:28.118] <TB1> INFO: 41600 events read in total (3521ms).
[16:52:28.119] <TB1> INFO: Test took 4577ms.
[16:52:28.122] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[16:52:28.471] <TB1> INFO: Expecting 41600 events.
[16:52:32.695] <TB1> INFO: 41600 events read in total (3508ms).
[16:52:32.695] <TB1> INFO: Test took 4573ms.
[16:52:32.700] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[16:52:33.045] <TB1> INFO: Expecting 41600 events.
[16:52:37.295] <TB1> INFO: 41600 events read in total (3533ms).
[16:52:37.296] <TB1> INFO: Test took 4596ms.
[16:52:37.299] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[16:52:37.650] <TB1> INFO: Expecting 41600 events.
[16:52:42.017] <TB1> INFO: 41600 events read in total (3650ms).
[16:52:42.018] <TB1> INFO: Test took 4719ms.
[16:52:42.022] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:52:42.357] <TB1> INFO: Expecting 41600 events.
[16:52:46.579] <TB1> INFO: 41600 events read in total (3505ms).
[16:52:46.580] <TB1> INFO: Test took 4558ms.
[16:52:47.122] <TB1> INFO: PixTestGainPedestal::measure() done
[16:53:21.578] <TB1> INFO: PixTestGainPedestal::fit() done
[16:53:21.578] <TB1> INFO: non-linearity mean: 0.957 0.961 0.953 0.951 0.948 0.960 0.947 0.956 0.953 0.959 0.956 0.964 0.959 0.963 0.957 0.956
[16:53:21.578] <TB1> INFO: non-linearity RMS: 0.006 0.007 0.008 0.008 0.007 0.007 0.008 0.009 0.007 0.005 0.006 0.005 0.006 0.005 0.007 0.006
[16:53:21.578] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:53:21.601] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:53:21.621] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:53:21.645] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:53:21.668] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:53:21.690] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:53:21.711] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:53:21.733] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:53:21.752] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:53:21.771] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:53:21.790] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:53:21.809] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:53:21.828] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:53:21.847] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:53:21.866] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:53:21.886] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:53:21.906] <TB1> INFO: PixTestGainPedestal::doTest() done, duration: 170 seconds
[16:53:21.913] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C15.dat
[16:53:21.923] <TB1> INFO: PixTestReadback::doTest() start.
[16:53:21.925] <TB1> INFO: PixTestReadback::RES sent once
[16:53:33.245] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C0.dat
[16:53:33.245] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C1.dat
[16:53:33.245] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C2.dat
[16:53:33.245] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C3.dat
[16:53:33.246] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C4.dat
[16:53:33.246] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C5.dat
[16:53:33.246] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C6.dat
[16:53:33.246] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C7.dat
[16:53:33.246] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C8.dat
[16:53:33.246] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C9.dat
[16:53:33.246] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C10.dat
[16:53:33.246] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C11.dat
[16:53:33.246] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C12.dat
[16:53:33.246] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C13.dat
[16:53:33.246] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C14.dat
[16:53:33.247] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C15.dat
[16:53:33.293] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:53:33.293] <TB1> INFO: PixTestReadback::RES sent once
[16:53:44.568] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C0.dat
[16:53:44.568] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C1.dat
[16:53:44.568] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C2.dat
[16:53:44.568] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C3.dat
[16:53:44.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C4.dat
[16:53:44.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C5.dat
[16:53:44.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C6.dat
[16:53:44.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C7.dat
[16:53:44.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C8.dat
[16:53:44.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C9.dat
[16:53:44.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C10.dat
[16:53:44.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C11.dat
[16:53:44.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C12.dat
[16:53:44.570] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C13.dat
[16:53:44.570] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C14.dat
[16:53:44.570] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C15.dat
[16:53:44.616] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:53:44.617] <TB1> INFO: PixTestReadback::RES sent once
[16:53:53.293] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:53:53.294] <TB1> INFO: Vbg will be calibrated using Vd calibration
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151.6calibrated Vbg = 1.20802 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 160.9calibrated Vbg = 1.20809 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 163.3calibrated Vbg = 1.21506 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 152.6calibrated Vbg = 1.21925 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 161.1calibrated Vbg = 1.21855 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 162.3calibrated Vbg = 1.22017 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 161.2calibrated Vbg = 1.22248 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 164calibrated Vbg = 1.21932 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156.4calibrated Vbg = 1.22531 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 151.3calibrated Vbg = 1.22408 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.1calibrated Vbg = 1.21733 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 159.1calibrated Vbg = 1.20975 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 149.4calibrated Vbg = 1.20739 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 149.9calibrated Vbg = 1.21013 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 152calibrated Vbg = 1.2102 :::*/*/*/*/
[16:53:53.294] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 157.9calibrated Vbg = 1.21215 :::*/*/*/*/
[16:53:53.296] <TB1> INFO: PixTestReadback::RES sent once
[16:56:48.827] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C0.dat
[16:56:48.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C1.dat
[16:56:48.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C2.dat
[16:56:48.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C3.dat
[16:56:48.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C4.dat
[16:56:48.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C5.dat
[16:56:48.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C6.dat
[16:56:48.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C7.dat
[16:56:48.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C8.dat
[16:56:48.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C9.dat
[16:56:48.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C10.dat
[16:56:48.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C11.dat
[16:56:48.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C12.dat
[16:56:48.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C13.dat
[16:56:48.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C14.dat
[16:56:48.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2089_FullQualification_2015-08-28_10h45m_1440751527//004_FulltestPxar_p17//readbackCal_C15.dat
[16:56:48.874] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:56:48.875] <TB1> INFO: PixTestReadback::doTest() done
[16:56:48.890] <TB1> INFO: enter test to run
[16:56:48.890] <TB1> INFO: test: exit no parameter change
[16:56:49.440] <TB1> QUIET: Connection to board 153 closed.
[16:56:49.520] <TB1> INFO: pXar: this is the end, my friend