[14:57:31.410] <TB0> INFO: *** Welcome to pxar ***
[14:57:31.410] <TB0> INFO: *** Today: 2015/08/20
[14:57:31.410] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C15.dat
[14:57:31.411] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:57:31.411] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//defaultMaskFile.dat
[14:57:31.411] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters_C15.dat
[14:57:31.477] <TB0> INFO: clk: 4
[14:57:31.477] <TB0> INFO: ctr: 4
[14:57:31.477] <TB0> INFO: sda: 19
[14:57:31.477] <TB0> INFO: tin: 9
[14:57:31.477] <TB0> INFO: level: 15
[14:57:31.477] <TB0> INFO: triggerdelay: 0
[14:57:31.477] <TB0> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[14:57:31.477] <TB0> INFO: Log level: INFO
[14:57:31.483] <TB0> INFO: Found DTB DTB_WWVASW
[14:57:31.492] <TB0> QUIET: Connection to board DTB_WWVASW opened.
[14:57:31.495] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 126
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWVASW
MAC address: 40D85511807E
Hostname: pixelDTB126
Comment:
------------------------------------------------------
[14:57:31.497] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[14:57:33.009] <TB0> INFO: DUT info:
[14:57:33.009] <TB0> INFO: The DUT currently contains the following objects:
[14:57:33.009] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[14:57:33.009] <TB0> INFO: TBM Core alpha (0): 7 registers set
[14:57:33.009] <TB0> INFO: TBM Core beta (1): 7 registers set
[14:57:33.009] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:57:33.009] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.009] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:33.410] <TB0> INFO: enter 'restricted' command line mode
[14:57:33.410] <TB0> INFO: enter test to run
[14:57:33.410] <TB0> INFO: test: pretest no parameter change
[14:57:33.410] <TB0> INFO: running: pretest
[14:57:33.414] <TB0> INFO: ######################################################################
[14:57:33.414] <TB0> INFO: PixTestPretest::doTest()
[14:57:33.414] <TB0> INFO: ######################################################################
[14:57:33.415] <TB0> INFO: ----------------------------------------------------------------------
[14:57:33.416] <TB0> INFO: PixTestPretest::programROC()
[14:57:33.416] <TB0> INFO: ----------------------------------------------------------------------
[14:57:51.432] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:57:51.432] <TB0> INFO: IA differences per ROC: 16.9 17.7 18.5 19.3 20.1 17.7 19.3 20.1 17.7 17.7 17.7 19.3 20.1 19.3 17.7 20.1
[14:57:51.498] <TB0> INFO: ----------------------------------------------------------------------
[14:57:51.498] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:57:51.499] <TB0> INFO: ----------------------------------------------------------------------
[14:58:11.065] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 375.4 mA = 23.4625 mA/ROC
[14:58:11.068] <TB0> INFO: ----------------------------------------------------------------------
[14:58:11.072] <TB0> INFO: PixTestPretest::findWorkingPixel()
[14:58:11.072] <TB0> INFO: ----------------------------------------------------------------------
[14:58:19.422] <TB0> INFO: Test took 8346ms.
[14:58:19.713] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[14:58:19.752] <TB0> INFO: ----------------------------------------------------------------------
[14:58:19.752] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[14:58:19.752] <TB0> INFO: ----------------------------------------------------------------------
[14:58:28.777] <TB0> INFO: Test took 9020ms.
[14:58:29.075] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[14:58:29.075] <TB0> INFO: CalDel: 126 124 134 134 126 138 133 142 138 138 130 146 123 105 123 168
[14:58:29.075] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[14:58:29.078] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C0.dat
[14:58:29.078] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C1.dat
[14:58:29.078] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C2.dat
[14:58:29.078] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C3.dat
[14:58:29.078] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C4.dat
[14:58:29.078] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C5.dat
[14:58:29.079] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C6.dat
[14:58:29.079] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C7.dat
[14:58:29.079] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C8.dat
[14:58:29.079] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C9.dat
[14:58:29.079] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C10.dat
[14:58:29.079] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C11.dat
[14:58:29.079] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C12.dat
[14:58:29.079] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C13.dat
[14:58:29.079] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C14.dat
[14:58:29.079] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters_C15.dat
[14:58:29.079] <TB0> INFO: PixTestPretest::doTest() done, duration: 55 seconds
[14:58:29.171] <TB0> INFO: enter test to run
[14:58:29.171] <TB0> INFO: test: fulltest no parameter change
[14:58:29.171] <TB0> INFO: running: fulltest
[14:58:29.171] <TB0> INFO: ######################################################################
[14:58:29.171] <TB0> INFO: PixTestFullTest::doTest()
[14:58:29.171] <TB0> INFO: ######################################################################
[14:58:29.172] <TB0> INFO: ######################################################################
[14:58:29.172] <TB0> INFO: PixTestAlive::doTest()
[14:58:29.172] <TB0> INFO: ######################################################################
[14:58:29.174] <TB0> INFO: ----------------------------------------------------------------------
[14:58:29.174] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:58:29.174] <TB0> INFO: ----------------------------------------------------------------------
[14:58:32.650] <TB0> INFO: Test took 3475ms.
[14:58:32.671] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:32.900] <TB0> INFO: PixTestAlive::aliveTest() done
[14:58:32.900] <TB0> INFO: number of dead pixels (per ROC): 2 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[14:58:32.902] <TB0> INFO: ----------------------------------------------------------------------
[14:58:32.902] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:58:32.902] <TB0> INFO: ----------------------------------------------------------------------
[14:58:35.806] <TB0> INFO: Test took 2902ms.
[14:58:35.809] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:35.810] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:58:36.037] <TB0> INFO: PixTestAlive::maskTest() done
[14:58:36.037] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:58:36.039] <TB0> INFO: ----------------------------------------------------------------------
[14:58:36.039] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:58:36.039] <TB0> INFO: ----------------------------------------------------------------------
[14:58:39.483] <TB0> INFO: Test took 3442ms.
[14:58:39.508] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:39.737] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[14:58:39.737] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:58:39.737] <TB0> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[14:58:39.748] <TB0> INFO: ######################################################################
[14:58:39.748] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[14:58:39.748] <TB0> INFO: ######################################################################
[14:58:39.751] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30) hits flags = 2 (plus default)
[14:58:39.762] <TB0> INFO: dacScan step from 0 .. 29
[14:59:01.153] <TB0> INFO: Test took 21391ms.
[14:59:01.191] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:01.191] <TB0> INFO: dacScan step from 30 .. 59
[14:59:26.413] <TB0> INFO: Test took 25222ms.
[14:59:26.571] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:26.598] <TB0> INFO: dacScan step from 60 .. 89
[14:59:57.415] <TB0> INFO: Test took 30817ms.
[14:59:57.690] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:57.771] <TB0> INFO: dacScan step from 90 .. 119
[15:00:28.405] <TB0> INFO: Test took 30634ms.
[15:00:28.654] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:28.731] <TB0> INFO: dacScan step from 120 .. 149
[15:00:55.252] <TB0> INFO: Test took 26521ms.
[15:00:55.426] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:17.601] <TB0> INFO: PixTestBBMap::doTest() done, duration: 157 seconds
[15:01:17.602] <TB0> INFO: number of dead bumps (per ROC): 1 0 0 0 0 0 2 1 0 1 0 1 0 0 3 1
[15:01:17.602] <TB0> INFO: separation cut (per ROC): 71 71 70 86 92 68 78 73 68 76 74 71 71 89 72 74
[15:01:17.673] <TB0> INFO: ######################################################################
[15:01:17.673] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50
[15:01:17.673] <TB0> INFO: ######################################################################
[15:01:17.673] <TB0> INFO: ----------------------------------------------------------------------
[15:01:17.674] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50
[15:01:17.674] <TB0> INFO: ----------------------------------------------------------------------
[15:01:17.674] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (4) hits flags = 16 (plus default)
[15:01:17.682] <TB0> INFO: dacScan step from 0 .. 3
[15:01:37.592] <TB0> INFO: Test took 19910ms.
[15:01:37.625] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:37.625] <TB0> INFO: dacScan step from 4 .. 7
[15:01:57.643] <TB0> INFO: Test took 20018ms.
[15:01:57.671] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:57.671] <TB0> INFO: dacScan step from 8 .. 11
[15:02:17.451] <TB0> INFO: Test took 19780ms.
[15:02:17.478] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:17.478] <TB0> INFO: dacScan step from 12 .. 15
[15:02:37.604] <TB0> INFO: Test took 20126ms.
[15:02:37.631] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:37.631] <TB0> INFO: dacScan step from 16 .. 19
[15:02:57.646] <TB0> INFO: Test took 20015ms.
[15:02:57.672] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:57.672] <TB0> INFO: dacScan step from 20 .. 23
[15:03:17.857] <TB0> INFO: Test took 20185ms.
[15:03:17.888] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:17.888] <TB0> INFO: dacScan step from 24 .. 27
[15:03:38.141] <TB0> INFO: Test took 20253ms.
[15:03:38.168] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:38.168] <TB0> INFO: dacScan step from 28 .. 31
[15:03:58.297] <TB0> INFO: Test took 20129ms.
[15:03:58.327] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:58.327] <TB0> INFO: dacScan step from 32 .. 35
[15:04:18.705] <TB0> INFO: Test took 20378ms.
[15:04:18.731] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:18.731] <TB0> INFO: dacScan step from 36 .. 39
[15:04:39.076] <TB0> INFO: Test took 20344ms.
[15:04:39.102] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:39.102] <TB0> INFO: dacScan step from 40 .. 43
[15:04:59.189] <TB0> INFO: Test took 20087ms.
[15:04:59.217] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:59.217] <TB0> INFO: dacScan step from 44 .. 47
[15:05:19.565] <TB0> INFO: Test took 20348ms.
[15:05:19.592] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:19.592] <TB0> INFO: dacScan step from 48 .. 51
[15:05:40.011] <TB0> INFO: Test took 20419ms.
[15:05:40.042] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:40.042] <TB0> INFO: dacScan step from 52 .. 55
[15:06:00.500] <TB0> INFO: Test took 20458ms.
[15:06:00.527] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:00.527] <TB0> INFO: dacScan step from 56 .. 59
[15:06:21.284] <TB0> INFO: Test took 20757ms.
[15:06:21.309] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:21.310] <TB0> INFO: dacScan step from 60 .. 63
[15:06:41.632] <TB0> INFO: Test took 20322ms.
[15:06:41.659] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:41.659] <TB0> INFO: dacScan step from 64 .. 67
[15:07:02.390] <TB0> INFO: Test took 20730ms.
[15:07:02.421] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:02.421] <TB0> INFO: dacScan step from 68 .. 71
[15:07:23.150] <TB0> INFO: Test took 20729ms.
[15:07:23.185] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:23.186] <TB0> INFO: dacScan step from 72 .. 75
[15:07:44.738] <TB0> INFO: Test took 21552ms.
[15:07:44.791] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:44.793] <TB0> INFO: dacScan step from 76 .. 79
[15:08:07.337] <TB0> INFO: Test took 22544ms.
[15:08:07.403] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:07.406] <TB0> INFO: dacScan step from 80 .. 83
[15:08:32.937] <TB0> INFO: Test took 25531ms.
[15:08:33.051] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:33.057] <TB0> INFO: dacScan step from 84 .. 87
[15:09:00.029] <TB0> INFO: Test took 26972ms.
[15:09:00.192] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:00.199] <TB0> INFO: dacScan step from 88 .. 91
[15:09:27.727] <TB0> INFO: Test took 27528ms.
[15:09:27.911] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:27.921] <TB0> INFO: dacScan step from 92 .. 95
[15:09:57.471] <TB0> INFO: Test took 29550ms.
[15:09:57.677] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:57.686] <TB0> INFO: dacScan step from 96 .. 99
[15:10:27.836] <TB0> INFO: Test took 30150ms.
[15:10:28.059] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:28.069] <TB0> INFO: dacScan step from 100 .. 103
[15:10:58.857] <TB0> INFO: Test took 30788ms.
[15:10:59.088] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:59.099] <TB0> INFO: dacScan step from 104 .. 107


[15:11:30.385] <TB0> INFO: Test took 31286ms.
[15:11:30.613] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:30.625] <TB0> INFO: dacScan step from 108 .. 111



[15:12:01.774] <TB0> INFO: Test took 31149ms.
[15:12:02.001] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:02.013] <TB0> INFO: dacScan step from 112 .. 115



[15:12:33.612] <TB0> INFO: Test took 31599ms.
[15:12:33.842] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:33.855] <TB0> INFO: dacScan step from 116 .. 119


[15:13:06.284] <TB0> INFO: Test took 32429ms.
[15:13:06.501] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:13:06.513] <TB0> INFO: dacScan step from 120 .. 123

[15:13:38.435] <TB0> INFO: Test took 31922ms.
[15:13:38.656] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:13:38.668] <TB0> INFO: dacScan step from 124 .. 127
[15:14:11.078] <TB0> INFO: Test took 32409ms.
[15:14:11.323] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:11.336] <TB0> INFO: dacScan step from 128 .. 131
[15:14:43.996] <TB0> INFO: Test took 32660ms.
[15:14:44.253] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:44.265] <TB0> INFO: dacScan step from 132 .. 135
[15:15:16.761] <TB0> INFO: Test took 32496ms.
[15:15:17.007] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:17.022] <TB0> INFO: dacScan step from 136 .. 139
[15:15:49.790] <TB0> INFO: Test took 32768ms.
[15:15:50.071] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:50.087] <TB0> INFO: dacScan step from 140 .. 143
[15:16:19.324] <TB0> INFO: Test took 29237ms.
[15:16:19.569] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:16:19.582] <TB0> INFO: dacScan step from 144 .. 147
[15:16:52.494] <TB0> INFO: Test took 32912ms.
[15:16:52.716] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:16:52.728] <TB0> INFO: dacScan step from 148 .. 149
[15:17:10.738] <TB0> INFO: Test took 18010ms.
[15:17:10.864] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:10.871] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:12.398] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:13.780] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:15.202] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:16.667] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:18.198] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:19.737] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:21.329] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:22.760] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:24.204] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:25.629] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:27.037] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:28.415] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:29.787] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:31.189] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:32.557] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:17:34.002] <TB0> INFO: PixTestScurves::scurves() done
[15:17:34.002] <TB0> INFO: Vcal mean: 78.18 86.17 79.82 94.55 91.05 89.92 94.06 85.56 83.23 97.50 97.77 88.19 85.70 98.73 98.13 87.13
[15:17:34.002] <TB0> INFO: Vcal RMS: 4.70 5.28 4.03 5.40 5.87 5.21 5.90 5.11 5.25 5.56 5.29 5.15 5.54 5.30 5.70 4.60
[15:17:34.002] <TB0> INFO: PixTestScurves::fullTest() done, duration: 976 seconds
[15:17:34.071] <TB0> INFO: ######################################################################
[15:17:34.071] <TB0> INFO: PixTestTrim::doTest()
[15:17:34.071] <TB0> INFO: ######################################################################
[15:17:34.073] <TB0> INFO: ----------------------------------------------------------------------
[15:17:34.073] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[15:17:34.073] <TB0> INFO: ----------------------------------------------------------------------
[15:17:34.151] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:17:34.151] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[15:17:34.159] <TB0> INFO: dacScan step from 0 .. 19
[15:17:50.145] <TB0> INFO: Test took 15986ms.
[15:17:50.172] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:50.172] <TB0> INFO: dacScan step from 20 .. 39
[15:18:06.118] <TB0> INFO: Test took 15946ms.
[15:18:06.140] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:06.140] <TB0> INFO: dacScan step from 40 .. 59
[15:18:21.137] <TB0> INFO: Test took 14997ms.
[15:18:21.159] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:21.159] <TB0> INFO: dacScan step from 60 .. 79
[15:18:37.500] <TB0> INFO: Test took 16341ms.
[15:18:37.522] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:37.522] <TB0> INFO: dacScan step from 80 .. 99
[15:18:54.392] <TB0> INFO: Test took 16870ms.
[15:18:54.461] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:54.470] <TB0> INFO: dacScan step from 100 .. 119
[15:19:14.252] <TB0> INFO: Test took 19782ms.
[15:19:14.438] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:14.484] <TB0> INFO: dacScan step from 120 .. 139
[15:19:33.408] <TB0> INFO: Test took 18924ms.
[15:19:33.543] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:33.565] <TB0> INFO: dacScan step from 140 .. 159
[15:19:49.538] <TB0> INFO: Test took 15973ms.
[15:19:49.586] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:10.385] <TB0> INFO: ROC 0 VthrComp = 78
[15:20:10.385] <TB0> INFO: ROC 1 VthrComp = 88
[15:20:10.385] <TB0> INFO: ROC 2 VthrComp = 83
[15:20:10.385] <TB0> INFO: ROC 3 VthrComp = 101
[15:20:10.386] <TB0> INFO: ROC 4 VthrComp = 92
[15:20:10.386] <TB0> INFO: ROC 5 VthrComp = 90
[15:20:10.386] <TB0> INFO: ROC 6 VthrComp = 95
[15:20:10.386] <TB0> INFO: ROC 7 VthrComp = 84
[15:20:10.386] <TB0> INFO: ROC 8 VthrComp = 84
[15:20:10.386] <TB0> INFO: ROC 9 VthrComp = 98
[15:20:10.386] <TB0> INFO: ROC 10 VthrComp = 96
[15:20:10.386] <TB0> INFO: ROC 11 VthrComp = 88
[15:20:10.386] <TB0> INFO: ROC 12 VthrComp = 89
[15:20:10.386] <TB0> INFO: ROC 13 VthrComp = 99
[15:20:10.386] <TB0> INFO: ROC 14 VthrComp = 94
[15:20:10.387] <TB0> INFO: ROC 15 VthrComp = 87
[15:20:10.387] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:20:10.387] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[15:20:10.395] <TB0> INFO: dacScan step from 0 .. 19
[15:20:25.698] <TB0> INFO: Test took 15303ms.
[15:20:25.722] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:25.722] <TB0> INFO: dacScan step from 20 .. 39
[15:20:41.237] <TB0> INFO: Test took 15515ms.
[15:20:41.278] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:41.282] <TB0> INFO: dacScan step from 40 .. 59
[15:21:00.922] <TB0> INFO: Test took 19640ms.
[15:21:01.078] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:01.119] <TB0> INFO: dacScan step from 60 .. 79
[15:21:21.627] <TB0> INFO: Test took 20508ms.
[15:21:21.788] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:21.844] <TB0> INFO: dacScan step from 80 .. 99
[15:21:41.472] <TB0> INFO: Test took 19628ms.
[15:21:41.688] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:41.751] <TB0> INFO: dacScan step from 100 .. 119
[15:22:02.440] <TB0> INFO: Test took 20688ms.
[15:22:02.603] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:22:02.658] <TB0> INFO: dacScan step from 120 .. 139
[15:22:23.246] <TB0> INFO: Test took 20587ms.
[15:22:23.408] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:22:23.464] <TB0> INFO: dacScan step from 140 .. 159
[15:22:42.168] <TB0> INFO: Test took 18704ms.
[15:22:42.326] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:07.026] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 58.1603 for pixel 17/15 mean/min/max = 46.7693/35.3098/58.2289
[15:23:07.027] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 59.0574 for pixel 18/66 mean/min/max = 46.074/32.9008/59.2472
[15:23:07.027] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 55.6453 for pixel 17/23 mean/min/max = 44.2565/32.8002/55.7128
[15:23:07.027] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 56.6395 for pixel 12/78 mean/min/max = 44.6677/32.6077/56.7278
[15:23:07.027] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 58.9052 for pixel 26/78 mean/min/max = 45.7776/32.5897/58.9655
[15:23:07.028] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 58.2044 for pixel 3/76 mean/min/max = 45.8908/33.2632/58.5185
[15:23:07.028] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 59.4911 for pixel 6/4 mean/min/max = 45.9981/32.3787/59.6175
[15:23:07.028] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 59.3427 for pixel 17/3 mean/min/max = 46.4741/33.3398/59.6085
[15:23:07.028] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 58.7047 for pixel 17/77 mean/min/max = 45.3457/31.7793/58.9121
[15:23:07.029] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 58.3589 for pixel 0/69 mean/min/max = 45.1943/31.8521/58.5364
[15:23:07.029] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 58.0836 for pixel 8/5 mean/min/max = 45.1987/32.2699/58.1274
[15:23:07.029] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 58.4773 for pixel 0/7 mean/min/max = 46.4886/34.4932/58.484
[15:23:07.029] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 58.6812 for pixel 11/73 mean/min/max = 46.2395/33.6741/58.8049
[15:23:07.029] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 57.5398 for pixel 3/68 mean/min/max = 44.5809/31.5899/57.5718
[15:23:07.030] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 61.7299 for pixel 13/61 mean/min/max = 47.3669/32.9601/61.7737
[15:23:07.030] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 56.671 for pixel 6/65 mean/min/max = 44.7779/32.7398/56.8161
[15:23:07.030] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:24:47.731] <TB0> INFO: Test took 100701ms.
[15:24:49.099] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[15:24:49.107] <TB0> INFO: dacScan step from 0 .. 19
[15:25:14.108] <TB0> INFO: Test took 25001ms.
[15:25:14.161] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:14.162] <TB0> INFO: dacScan step from 20 .. 39
[15:25:46.324] <TB0> INFO: Test took 32162ms.
[15:25:46.578] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:46.608] <TB0> INFO: dacScan step from 40 .. 59


[15:26:23.092] <TB0> INFO: Test took 36484ms.
[15:26:23.390] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:23.442] <TB0> INFO: dacScan step from 60 .. 79


[15:26:59.985] <TB0> INFO: Test took 36543ms.
[15:27:00.257] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:00.312] <TB0> INFO: dacScan step from 80 .. 99

[15:27:36.998] <TB0> INFO: Test took 36686ms.
[15:27:37.297] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:37.367] <TB0> INFO: dacScan step from 100 .. 119


[15:28:12.637] <TB0> INFO: Test took 35270ms.
[15:28:12.914] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:12.971] <TB0> INFO: dacScan step from 120 .. 139


[15:28:44.964] <TB0> INFO: Test took 31993ms.
[15:28:45.240] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:45.303] <TB0> INFO: dacScan step from 140 .. 159





[15:29:20.020] <TB0> INFO: Test took 34717ms.
[15:29:20.307] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:20.363] <TB0> INFO: dacScan step from 160 .. 179
[15:29:54.537] <TB0> INFO: Test took 34174ms.
[15:29:54.812] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:54.867] <TB0> INFO: dacScan step from 180 .. 199

[15:30:30.938] <TB0> INFO: Test took 36071ms.
[15:30:31.204] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:57.547] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.029052 .. 255.000000
[15:30:57.656] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20) hits flags = 16 (plus default)
[15:30:57.665] <TB0> INFO: dacScan step from 0 .. 19
[15:31:11.344] <TB0> INFO: Test took 13679ms.
[15:31:11.369] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:11.369] <TB0> INFO: dacScan step from 20 .. 39
[15:31:25.505] <TB0> INFO: Test took 14136ms.
[15:31:25.581] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:25.597] <TB0> INFO: dacScan step from 40 .. 59
[15:31:43.238] <TB0> INFO: Test took 17641ms.
[15:31:43.398] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:43.453] <TB0> INFO: dacScan step from 60 .. 79
[15:32:01.569] <TB0> INFO: Test took 18116ms.
[15:32:01.727] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:01.786] <TB0> INFO: dacScan step from 80 .. 99
[15:32:20.444] <TB0> INFO: Test took 18658ms.
[15:32:20.580] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:20.635] <TB0> INFO: dacScan step from 100 .. 119
[15:32:39.650] <TB0> INFO: Test took 19015ms.
[15:32:39.836] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:39.895] <TB0> INFO: dacScan step from 120 .. 139
[15:32:57.110] <TB0> INFO: Test took 17215ms.
[15:32:57.247] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:57.305] <TB0> INFO: dacScan step from 140 .. 159
[15:33:15.336] <TB0> INFO: Test took 18031ms.
[15:33:15.487] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:15.544] <TB0> INFO: dacScan step from 160 .. 179
[15:33:32.153] <TB0> INFO: Test took 16609ms.
[15:33:32.293] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:32.347] <TB0> INFO: dacScan step from 180 .. 199
[15:33:50.920] <TB0> INFO: Test took 18573ms.
[15:33:51.059] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:51.115] <TB0> INFO: dacScan step from 200 .. 219
[15:34:08.736] <TB0> INFO: Test took 17621ms.
[15:34:08.875] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:08.928] <TB0> INFO: dacScan step from 220 .. 239
[15:34:26.823] <TB0> INFO: Test took 17894ms.
[15:34:26.959] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:27.014] <TB0> INFO: dacScan step from 240 .. 255
[15:34:41.495] <TB0> INFO: Test took 14480ms.
[15:34:41.673] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:12.750] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 15.882448 .. 44.379869
[15:35:12.831] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 5 .. 54 (20) hits flags = 16 (plus default)
[15:35:12.838] <TB0> INFO: dacScan step from 5 .. 24
[15:35:26.335] <TB0> INFO: Test took 13496ms.
[15:35:26.360] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:26.360] <TB0> INFO: dacScan step from 25 .. 44
[15:35:41.655] <TB0> INFO: Test took 15295ms.
[15:35:41.766] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:41.794] <TB0> INFO: dacScan step from 45 .. 54
[15:35:52.262] <TB0> INFO: Test took 10467ms.
[15:35:52.331] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:08.840] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 1.173920 .. 39.707960
[15:36:08.918] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 1 .. 49 (20) hits flags = 16 (plus default)
[15:36:08.926] <TB0> INFO: dacScan step from 1 .. 20
[15:36:23.739] <TB0> INFO: Test took 14813ms.
[15:36:23.757] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:23.757] <TB0> INFO: dacScan step from 21 .. 40
[15:36:38.894] <TB0> INFO: Test took 15137ms.
[15:36:38.984] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:39.004] <TB0> INFO: dacScan step from 41 .. 49
[15:36:48.480] <TB0> INFO: Test took 9476ms.
[15:36:48.555] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:03.482] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 1.173920 .. 39.707960
[15:37:03.557] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 1 .. 49 (20) hits flags = 16 (plus default)
[15:37:03.565] <TB0> INFO: dacScan step from 1 .. 20
[15:37:16.616] <TB0> INFO: Test took 13051ms.
[15:37:16.639] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:16.639] <TB0> INFO: dacScan step from 21 .. 40
[15:37:32.002] <TB0> INFO: Test took 15363ms.
[15:37:32.099] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:32.125] <TB0> INFO: dacScan step from 41 .. 49
[15:37:42.077] <TB0> INFO: Test took 9952ms.
[15:37:42.153] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:57.725] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:37:57.725] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20) hits flags = 16 (plus default)
[15:37:57.733] <TB0> INFO: dacScan step from 15 .. 34
[15:38:19.950] <TB0> INFO: Test took 22216ms.
[15:38:20.029] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:20.038] <TB0> INFO: dacScan step from 35 .. 54



[15:38:54.665] <TB0> INFO: Test took 34627ms.
[15:38:54.952] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:55.004] <TB0> INFO: dacScan step from 55 .. 55
[15:38:59.416] <TB0> INFO: Test took 4412ms.
[15:38:59.435] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:13.889] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:39:13.889] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:39:13.889] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:39:13.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:39:13.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:39:13.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:39:13.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:39:13.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:39:13.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:39:13.891] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:39:13.891] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:39:13.891] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:39:13.891] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:39:13.891] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:39:13.892] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:39:13.892] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:39:13.892] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C0.dat
[15:39:13.899] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C1.dat
[15:39:13.905] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C2.dat
[15:39:13.911] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C3.dat
[15:39:13.917] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C4.dat
[15:39:13.923] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C5.dat
[15:39:13.929] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C6.dat
[15:39:13.935] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C7.dat
[15:39:13.941] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C8.dat
[15:39:13.947] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C9.dat
[15:39:13.953] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C10.dat
[15:39:13.959] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C11.dat
[15:39:13.965] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C12.dat
[15:39:13.971] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C13.dat
[15:39:13.977] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C14.dat
[15:39:13.983] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//trimParameters35_C15.dat
[15:39:13.989] <TB0> INFO: PixTestTrim::trimTest() done
[15:39:13.989] <TB0> INFO: vtrim: 98 113 97 108 101 99 107 110 103 95 113 90 109 102 123 99
[15:39:13.989] <TB0> INFO: vthrcomp: 78 88 83 101 92 90 95 84 84 98 96 88 89 99 94 87
[15:39:13.989] <TB0> INFO: vcal mean: 35.09 35.03 35.11 35.07 35.11 35.08 35.07 35.04 35.03 35.13 35.05 35.11 35.11 35.16 35.16 35.08
[15:39:13.989] <TB0> INFO: vcal RMS: 1.30 1.09 1.15 1.18 1.14 1.17 1.11 1.20 1.11 1.11 1.13 1.05 1.01 1.12 1.16 1.08
[15:39:13.989] <TB0> INFO: bits mean: 9.49 9.96 10.58 9.97 9.95 9.70 9.71 9.96 9.99 10.07 10.43 8.79 9.40 10.17 9.83 10.07
[15:39:13.989] <TB0> INFO: bits RMS: 2.17 2.38 2.16 2.47 2.41 2.46 2.55 2.31 2.56 2.47 2.29 2.64 2.46 2.53 2.32 2.39
[15:39:13.996] <TB0> INFO: ----------------------------------------------------------------------
[15:39:13.996] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[15:39:13.996] <TB0> INFO: ----------------------------------------------------------------------
[15:39:13.999] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[15:39:14.008] <TB0> INFO: dacScan step from 0 .. 19
[15:39:37.772] <TB0> INFO: Test took 23764ms.
[15:39:37.809] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:37.809] <TB0> INFO: dacScan step from 20 .. 39
[15:40:01.777] <TB0> INFO: Test took 23968ms.
[15:40:01.819] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:01.819] <TB0> INFO: dacScan step from 40 .. 59
[15:40:26.434] <TB0> INFO: Test took 24614ms.
[15:40:26.468] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:26.468] <TB0> INFO: dacScan step from 60 .. 79
[15:40:51.736] <TB0> INFO: Test took 25268ms.
[15:40:51.770] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:51.770] <TB0> INFO: dacScan step from 80 .. 99
[15:41:16.155] <TB0> INFO: Test took 24385ms.
[15:41:16.201] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:16.201] <TB0> INFO: dacScan step from 100 .. 119
[15:41:42.104] <TB0> INFO: Test took 25903ms.
[15:41:42.265] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:42.284] <TB0> INFO: dacScan step from 120 .. 139
[15:42:15.117] <TB0> INFO: Test took 32833ms.
[15:42:15.449] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:15.499] <TB0> INFO: dacScan step from 140 .. 159





[15:42:50.205] <TB0> INFO: Test took 34706ms.
[15:42:50.474] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:50.530] <TB0> INFO: dacScan step from 160 .. 179
[15:43:24.391] <TB0> INFO: Test took 33861ms.
[15:43:24.691] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:24.757] <TB0> INFO: dacScan step from 180 .. 199
[15:43:59.532] <TB0> INFO: Test took 34776ms.
[15:43:59.810] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:26.172] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 164 (20) hits flags = 16 (plus default)
[15:44:26.181] <TB0> INFO: dacScan step from 0 .. 19
[15:44:50.208] <TB0> INFO: Test took 24025ms.
[15:44:50.245] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:50.245] <TB0> INFO: dacScan step from 20 .. 39
[15:45:14.684] <TB0> INFO: Test took 24440ms.
[15:45:14.722] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:14.722] <TB0> INFO: dacScan step from 40 .. 59
[15:45:39.696] <TB0> INFO: Test took 24974ms.
[15:45:39.736] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:39.736] <TB0> INFO: dacScan step from 60 .. 79
[15:46:02.997] <TB0> INFO: Test took 23261ms.
[15:46:03.034] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:03.034] <TB0> INFO: dacScan step from 80 .. 99
[15:46:27.425] <TB0> INFO: Test took 24391ms.
[15:46:27.498] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:27.504] <TB0> INFO: dacScan step from 100 .. 119
[15:46:58.730] <TB0> INFO: Test took 31226ms.
[15:46:58.965] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:58.996] <TB0> INFO: dacScan step from 120 .. 139


[15:47:34.120] <TB0> INFO: Test took 35124ms.
[15:47:34.386] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:34.443] <TB0> INFO: dacScan step from 140 .. 159



[15:48:05.152] <TB0> INFO: Test took 30709ms.
[15:48:05.428] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:05.492] <TB0> INFO: dacScan step from 160 .. 164
[15:48:15.247] <TB0> INFO: Test took 9755ms.
[15:48:15.314] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:37.971] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 152 (20) hits flags = 16 (plus default)
[15:48:37.979] <TB0> INFO: dacScan step from 0 .. 19
[15:49:00.559] <TB0> INFO: Test took 22580ms.
[15:49:00.597] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:00.597] <TB0> INFO: dacScan step from 20 .. 39
[15:49:23.149] <TB0> INFO: Test took 22552ms.
[15:49:23.185] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:23.185] <TB0> INFO: dacScan step from 40 .. 59
[15:49:44.791] <TB0> INFO: Test took 21606ms.
[15:49:44.825] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:44.825] <TB0> INFO: dacScan step from 60 .. 79
[15:50:06.882] <TB0> INFO: Test took 22057ms.
[15:50:06.918] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:06.918] <TB0> INFO: dacScan step from 80 .. 99
[15:50:32.066] <TB0> INFO: Test took 25148ms.
[15:50:32.144] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:32.148] <TB0> INFO: dacScan step from 100 .. 119
[15:51:05.322] <TB0> INFO: Test took 33173ms.
[15:51:05.566] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:05.598] <TB0> INFO: dacScan step from 120 .. 139





[15:51:41.713] <TB0> INFO: Test took 36115ms.
[15:51:41.977] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:42.033] <TB0> INFO: dacScan step from 140 .. 152
[15:52:02.835] <TB0> INFO: Test took 20801ms.
[15:52:03.033] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:26.155] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 152 (20) hits flags = 16 (plus default)
[15:52:26.164] <TB0> INFO: dacScan step from 0 .. 19
[15:52:50.336] <TB0> INFO: Test took 24172ms.
[15:52:50.369] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:50.369] <TB0> INFO: dacScan step from 20 .. 39
[15:53:14.883] <TB0> INFO: Test took 24514ms.
[15:53:14.916] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:14.917] <TB0> INFO: dacScan step from 40 .. 59
[15:53:38.529] <TB0> INFO: Test took 23612ms.
[15:53:38.570] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:38.570] <TB0> INFO: dacScan step from 60 .. 79
[15:54:03.394] <TB0> INFO: Test took 24824ms.
[15:54:03.432] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:03.432] <TB0> INFO: dacScan step from 80 .. 99
[15:54:29.705] <TB0> INFO: Test took 26273ms.
[15:54:29.783] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:29.790] <TB0> INFO: dacScan step from 100 .. 119
[15:55:01.923] <TB0> INFO: Test took 32133ms.
[15:55:02.154] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:02.185] <TB0> INFO: dacScan step from 120 .. 139

[15:55:37.073] <TB0> INFO: Test took 34888ms.
[15:55:37.354] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:37.414] <TB0> INFO: dacScan step from 140 .. 152
[15:55:57.997] <TB0> INFO: Test took 20583ms.
[15:55:58.168] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:19.783] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 153 (20) hits flags = 16 (plus default)
[15:56:19.791] <TB0> INFO: dacScan step from 0 .. 19
[15:56:43.729] <TB0> INFO: Test took 23938ms.
[15:56:43.766] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:43.766] <TB0> INFO: dacScan step from 20 .. 39
[15:57:07.816] <TB0> INFO: Test took 24051ms.
[15:57:07.854] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:07.854] <TB0> INFO: dacScan step from 40 .. 59
[15:57:30.929] <TB0> INFO: Test took 23075ms.
[15:57:30.962] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:30.962] <TB0> INFO: dacScan step from 60 .. 79
[15:57:55.335] <TB0> INFO: Test took 24373ms.
[15:57:55.370] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:55.370] <TB0> INFO: dacScan step from 80 .. 99
[15:58:20.177] <TB0> INFO: Test took 24807ms.
[15:58:20.256] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:20.263] <TB0> INFO: dacScan step from 100 .. 119
[15:58:51.298] <TB0> INFO: Test took 31035ms.
[15:58:51.536] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:51.567] <TB0> INFO: dacScan step from 120 .. 139


[15:59:26.201] <TB0> INFO: Test took 34634ms.
[15:59:26.493] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:26.550] <TB0> INFO: dacScan step from 140 .. 153
[15:59:48.708] <TB0> INFO: Test took 22158ms.
[15:59:48.895] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:09.865] <TB0> INFO: PixTestTrim::trimBitTest() done
[16:00:09.867] <TB0> INFO: PixTestTrim::doTest() done, duration: 2555 seconds
[16:00:10.585] <TB0> INFO: ######################################################################
[16:00:10.585] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:00:10.585] <TB0> INFO: ######################################################################
[16:00:14.079] <TB0> INFO: Test took 3492ms.
[16:00:14.100] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:17.717] <TB0> INFO: Test took 3420ms.
[16:00:17.782] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:21.278] <TB0> INFO: Test took 3485ms.
[16:00:21.339] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:21.345] <TB0> INFO: The DUT currently contains the following objects:
[16:00:21.345] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:21.345] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:21.345] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:21.345] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:21.345] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:21.345] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.452] <TB0> INFO: Test took 1107ms.
[16:00:22.453] <TB0> INFO: The DUT currently contains the following objects:
[16:00:22.453] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:22.453] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:22.453] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:22.453] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:22.453] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.453] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.453] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.453] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.453] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.453] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.453] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.453] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.453] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.453] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.453] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.453] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.454] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.454] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.454] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:22.454] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.560] <TB0> INFO: Test took 1106ms.
[16:00:23.561] <TB0> INFO: The DUT currently contains the following objects:
[16:00:23.561] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:23.561] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:23.561] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:23.561] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:23.561] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.561] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.561] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.561] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.561] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.561] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.561] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.561] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.561] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.561] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.561] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.561] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.561] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.562] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.562] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:23.562] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.668] <TB0> INFO: Test took 1106ms.
[16:00:24.670] <TB0> INFO: The DUT currently contains the following objects:
[16:00:24.670] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:24.670] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:24.670] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:24.670] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:24.670] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:24.670] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.778] <TB0> INFO: Test took 1108ms.
[16:00:25.780] <TB0> INFO: The DUT currently contains the following objects:
[16:00:25.780] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:25.780] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:25.780] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:25.780] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:25.780] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.780] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.780] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.780] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.780] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.780] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.780] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.780] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.780] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.780] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.780] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.780] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.780] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.780] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.780] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:25.781] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.885] <TB0> INFO: Test took 1104ms.
[16:00:26.887] <TB0> INFO: The DUT currently contains the following objects:
[16:00:26.887] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:26.887] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:26.887] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:26.887] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:26.887] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:26.887] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.994] <TB0> INFO: Test took 1107ms.
[16:00:27.996] <TB0> INFO: The DUT currently contains the following objects:
[16:00:27.996] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:27.996] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:27.996] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:27.996] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:27.996] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.996] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.996] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.997] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.997] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.997] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.997] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.997] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.997] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.997] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.997] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.998] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.998] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.998] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.998] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:27.998] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.105] <TB0> INFO: Test took 1107ms.
[16:00:29.106] <TB0> INFO: The DUT currently contains the following objects:
[16:00:29.106] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:29.106] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:29.106] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:29.106] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:29.106] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:29.106] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.213] <TB0> INFO: Test took 1107ms.
[16:00:30.214] <TB0> INFO: The DUT currently contains the following objects:
[16:00:30.214] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:30.214] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:30.214] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:30.214] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:30.214] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:30.214] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.334] <TB0> INFO: Test took 1120ms.
[16:00:31.336] <TB0> INFO: The DUT currently contains the following objects:
[16:00:31.336] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:31.336] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:31.336] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:31.336] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:31.336] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.336] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.336] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.336] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.336] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.336] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.336] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.336] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.336] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.336] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.337] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.337] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.337] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.337] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.337] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:31.337] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.444] <TB0> INFO: Test took 1107ms.
[16:00:32.445] <TB0> INFO: The DUT currently contains the following objects:
[16:00:32.445] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:32.445] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:32.445] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:32.446] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:32.446] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:32.446] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.554] <TB0> INFO: Test took 1108ms.
[16:00:33.555] <TB0> INFO: The DUT currently contains the following objects:
[16:00:33.555] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:33.555] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:33.555] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:33.555] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:33.555] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:33.556] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.677] <TB0> INFO: Test took 1121ms.
[16:00:34.678] <TB0> INFO: The DUT currently contains the following objects:
[16:00:34.679] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:34.679] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:34.679] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:34.679] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:34.679] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.679] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.679] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.679] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.679] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.679] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.679] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.679] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.679] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.679] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.679] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.679] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.679] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.679] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.680] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:34.680] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.801] <TB0> INFO: Test took 1121ms.
[16:00:35.803] <TB0> INFO: The DUT currently contains the following objects:
[16:00:35.803] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:35.803] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:35.803] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:35.803] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:35.803] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.803] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.803] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.803] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.803] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.803] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.803] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.803] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.803] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.803] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.803] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.803] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.803] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.804] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.804] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:35.804] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.938] <TB0> INFO: Test took 1134ms.
[16:00:36.939] <TB0> INFO: The DUT currently contains the following objects:
[16:00:36.939] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:36.939] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:36.939] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:36.939] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:36.939] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.939] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.939] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.940] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.940] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.940] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.940] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.940] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.940] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.940] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.940] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.940] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.940] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.940] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.940] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:36.940] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.061] <TB0> INFO: Test took 1121ms.
[16:00:38.062] <TB0> INFO: The DUT currently contains the following objects:
[16:00:38.062] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:00:38.062] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:00:38.062] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:00:38.062] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:00:38.062] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.062] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.062] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.062] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.062] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.062] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.062] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.063] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.063] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.063] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.063] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.063] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.063] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.063] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.063] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:38.063] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:00:39.182] <TB0> INFO: Test took 1119ms.
[16:00:39.185] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:04:55.869] <TB0> INFO: Test took 256684ms.
[16:04:57.481] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:08:42.626] <TB0> INFO: Test took 225148ms.
[16:08:44.463] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.470] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.476] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.483] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.489] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.496] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.506] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.516] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.524] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.539] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.546] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.552] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.559] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.565] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.572] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.578] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:08:44.770] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:08:44.771] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:08:44.776] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:08:44.776] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:08:44.780] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:08:44.780] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:08:44.780] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:08:44.780] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:08:44.783] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:08:44.783] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:08:44.789] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:08:44.789] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:08:44.789] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:08:44.789] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:08:44.792] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:08:44.792] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:08:48.097] <TB0> INFO: Test took 3302ms.
[16:08:51.643] <TB0> INFO: Test took 3283ms.
[16:08:55.173] <TB0> INFO: Test took 3264ms.
[16:08:55.442] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:08:56.356] <TB0> INFO: Test took 914ms.
[16:08:56.359] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:08:57.459] <TB0> INFO: Test took 1100ms.
[16:08:57.460] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:08:58.560] <TB0> INFO: Test took 1100ms.
[16:08:58.562] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:08:59.662] <TB0> INFO: Test took 1100ms.
[16:08:59.664] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:00.765] <TB0> INFO: Test took 1101ms.
[16:09:00.766] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:01.867] <TB0> INFO: Test took 1101ms.
[16:09:01.869] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:02.969] <TB0> INFO: Test took 1100ms.
[16:09:02.971] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:04.072] <TB0> INFO: Test took 1101ms.
[16:09:04.074] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:05.190] <TB0> INFO: Test took 1116ms.
[16:09:05.192] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:06.308] <TB0> INFO: Test took 1116ms.
[16:09:06.310] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:07.413] <TB0> INFO: Test took 1103ms.
[16:09:07.415] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:08.515] <TB0> INFO: Test took 1101ms.
[16:09:08.517] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:09.618] <TB0> INFO: Test took 1101ms.
[16:09:09.620] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:10.720] <TB0> INFO: Test took 1100ms.
[16:09:10.723] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:11.823] <TB0> INFO: Test took 1101ms.
[16:09:11.825] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:12.926] <TB0> INFO: Test took 1101ms.
[16:09:12.928] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:14.029] <TB0> INFO: Test took 1101ms.
[16:09:14.031] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:15.131] <TB0> INFO: Test took 1100ms.
[16:09:15.134] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:16.234] <TB0> INFO: Test took 1100ms.
[16:09:16.237] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:17.337] <TB0> INFO: Test took 1100ms.
[16:09:17.339] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:18.440] <TB0> INFO: Test took 1101ms.
[16:09:18.442] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:19.542] <TB0> INFO: Test took 1100ms.
[16:09:19.545] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:20.645] <TB0> INFO: Test took 1100ms.
[16:09:20.647] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:21.747] <TB0> INFO: Test took 1100ms.
[16:09:21.750] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:22.850] <TB0> INFO: Test took 1100ms.
[16:09:22.853] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:23.953] <TB0> INFO: Test took 1101ms.
[16:09:23.955] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:25.055] <TB0> INFO: Test took 1100ms.
[16:09:25.057] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:26.158] <TB0> INFO: Test took 1101ms.
[16:09:26.160] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:27.261] <TB0> INFO: Test took 1101ms.
[16:09:27.263] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:28.362] <TB0> INFO: Test took 1099ms.
[16:09:28.364] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:29.464] <TB0> INFO: Test took 1100ms.
[16:09:29.466] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:30.565] <TB0> INFO: Test took 1099ms.
[16:09:31.096] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 560 seconds
[16:09:31.096] <TB0> INFO: PH scale (per ROC): 78 76 82 74 82 80 78 69 70 74 79 76 74 77 70 74
[16:09:31.096] <TB0> INFO: PH offset (per ROC): 178 189 167 175 179 187 162 181 190 182 185 172 163 185 184 159
[16:09:31.411] <TB0> INFO: ######################################################################
[16:09:31.411] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:09:31.411] <TB0> INFO: ######################################################################
[16:09:32.226] <TB0> INFO: scanning low vcal = 10
[16:09:35.970] <TB0> INFO: Test took 3744ms.
[16:09:35.973] <TB0> INFO: scanning low vcal = 20
[16:09:39.749] <TB0> INFO: Test took 3776ms.
[16:09:39.752] <TB0> INFO: scanning low vcal = 30
[16:09:43.544] <TB0> INFO: Test took 3792ms.
[16:09:43.551] <TB0> INFO: scanning low vcal = 40
[16:09:47.725] <TB0> INFO: Test took 4174ms.
[16:09:47.778] <TB0> INFO: scanning low vcal = 50
[16:09:51.982] <TB0> INFO: Test took 4204ms.
[16:09:52.035] <TB0> INFO: scanning low vcal = 60
[16:09:56.224] <TB0> INFO: Test took 4189ms.
[16:09:56.277] <TB0> INFO: scanning low vcal = 70
[16:10:00.462] <TB0> INFO: Test took 4185ms.
[16:10:00.515] <TB0> INFO: scanning low vcal = 80
[16:10:04.719] <TB0> INFO: Test took 4204ms.
[16:10:04.772] <TB0> INFO: scanning low vcal = 90
[16:10:08.960] <TB0> INFO: Test took 4188ms.
[16:10:09.014] <TB0> INFO: scanning low vcal = 100
[16:10:13.203] <TB0> INFO: Test took 4189ms.
[16:10:13.256] <TB0> INFO: scanning low vcal = 110
[16:10:17.445] <TB0> INFO: Test took 4189ms.
[16:10:17.543] <TB0> INFO: scanning low vcal = 120
[16:10:21.746] <TB0> INFO: Test took 4203ms.
[16:10:21.800] <TB0> INFO: scanning low vcal = 130
[16:10:25.991] <TB0> INFO: Test took 4191ms.
[16:10:26.044] <TB0> INFO: scanning low vcal = 140
[16:10:30.231] <TB0> INFO: Test took 4187ms.
[16:10:30.284] <TB0> INFO: scanning low vcal = 150
[16:10:34.470] <TB0> INFO: Test took 4186ms.
[16:10:34.523] <TB0> INFO: scanning low vcal = 160
[16:10:38.713] <TB0> INFO: Test took 4190ms.
[16:10:38.767] <TB0> INFO: scanning low vcal = 170
[16:10:42.979] <TB0> INFO: Test took 4212ms.
[16:10:43.034] <TB0> INFO: scanning low vcal = 180
[16:10:47.224] <TB0> INFO: Test took 4190ms.
[16:10:47.277] <TB0> INFO: scanning low vcal = 190
[16:10:51.580] <TB0> INFO: Test took 4303ms.
[16:10:51.633] <TB0> INFO: scanning low vcal = 200
[16:10:55.805] <TB0> INFO: Test took 4172ms.
[16:10:55.857] <TB0> INFO: scanning low vcal = 210
[16:11:00.045] <TB0> INFO: Test took 4188ms.
[16:11:00.098] <TB0> INFO: scanning low vcal = 220
[16:11:04.268] <TB0> INFO: Test took 4170ms.
[16:11:04.321] <TB0> INFO: scanning low vcal = 230
[16:11:08.509] <TB0> INFO: Test took 4188ms.
[16:11:08.562] <TB0> INFO: scanning low vcal = 240
[16:11:12.737] <TB0> INFO: Test took 4175ms.
[16:11:12.789] <TB0> INFO: scanning low vcal = 250
[16:11:16.963] <TB0> INFO: Test took 4174ms.
[16:11:17.016] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[16:11:21.189] <TB0> INFO: Test took 4173ms.
[16:11:21.242] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[16:11:25.415] <TB0> INFO: Test took 4173ms.
[16:11:25.468] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[16:11:29.669] <TB0> INFO: Test took 4201ms.
[16:11:29.721] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[16:11:33.893] <TB0> INFO: Test took 4172ms.
[16:11:33.946] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:11:38.118] <TB0> INFO: Test took 4172ms.
[16:11:38.567] <TB0> INFO: PixTestGainPedestal::measure() done
[16:12:09.163] <TB0> INFO: PixTestGainPedestal::fit() done
[16:12:09.163] <TB0> INFO: non-linearity mean: 0.958 0.956 0.953 0.955 0.954 0.958 0.958 0.956 0.956 0.949 0.957 0.959 0.958 0.951 0.953 0.960
[16:12:09.163] <TB0> INFO: non-linearity RMS: 0.006 0.007 0.006 0.006 0.008 0.008 0.006 0.007 0.006 0.008 0.008 0.006 0.006 0.007 0.007 0.006
[16:12:09.163] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:12:09.182] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:12:09.202] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:12:09.221] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:12:09.240] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:12:09.259] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:12:09.278] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:12:09.298] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:12:09.317] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:12:09.336] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:12:09.355] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:12:09.374] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:12:09.394] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:12:09.413] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:12:09.432] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:12:09.451] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2026_FullQualification_2015-08-20_12h24m_1440066255//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:12:09.470] <TB0> INFO: PixTestGainPedestal::doTest() done, duration: 158 seconds
[16:12:09.489] <TB0> INFO: enter test to run
[16:12:09.494] <TB0> INFO: test: exit no parameter change
[16:12:10.647] <TB0> QUIET: Connection to board 126 closed.
[16:12:10.736] <TB0> INFO: pXar: this is the end, my friend