[16:59:33.148] <TB0> INFO: *** Welcome to pxar ***
[16:59:33.148] <TB0> INFO: *** Today: 2015/08/04
[16:59:33.148] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C15.dat
[16:59:33.149] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//tbmParameters_C0b.dat
[16:59:33.149] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//defaultMaskFile.dat
[16:59:33.149] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters_C15.dat
[16:59:33.209] <TB0> INFO: clk: 4
[16:59:33.209] <TB0> INFO: ctr: 4
[16:59:33.209] <TB0> INFO: sda: 19
[16:59:33.209] <TB0> INFO: tin: 9
[16:59:33.209] <TB0> INFO: level: 15
[16:59:33.209] <TB0> INFO: triggerdelay: 0
[16:59:33.209] <TB0> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[16:59:33.209] <TB0> INFO: Log level: INFO
[16:59:33.223] <TB0> INFO: Found DTB DTB_WWVASW
[16:59:33.232] <TB0> QUIET: Connection to board DTB_WWVASW opened.
[16:59:33.235] <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:
------------------------------------------------------
[16:59:33.238] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[16:59:34.748] <TB0> INFO: DUT info:
[16:59:34.749] <TB0> INFO: The DUT currently contains the following objects:
[16:59:34.749] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:59:34.749] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:59:34.749] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:59:34.749] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:59:34.749] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:34.749] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:59:35.150] <TB0> INFO: enter 'restricted' command line mode
[16:59:35.150] <TB0> INFO: enter test to run
[16:59:35.150] <TB0> INFO: test: pretest no parameter change
[16:59:35.150] <TB0> INFO: running: pretest
[16:59:35.154] <TB0> INFO: ######################################################################
[16:59:35.154] <TB0> INFO: PixTestPretest::doTest()
[16:59:35.154] <TB0> INFO: ######################################################################
[16:59:35.155] <TB0> INFO: ----------------------------------------------------------------------
[16:59:35.155] <TB0> INFO: PixTestPretest::programROC()
[16:59:35.155] <TB0> INFO: ----------------------------------------------------------------------
[16:59:53.171] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[16:59:53.171] <TB0> INFO: IA differences per ROC: 18.5 18.5 17.7 19.3 17.7 18.5 20.1 18.5 17.7 20.1 20.1 21.7 18.5 18.5 19.3 18.5
[16:59:53.236] <TB0> INFO: ----------------------------------------------------------------------
[16:59:53.236] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[16:59:53.236] <TB0> INFO: ----------------------------------------------------------------------
[16:59:57.995] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 391.5 mA = 24.4688 mA/ROC
[16:59:57.999] <TB0> INFO: ----------------------------------------------------------------------
[16:59:57.999] <TB0> INFO: PixTestPretest::findWorkingPixel()
[16:59:57.999] <TB0> INFO: ----------------------------------------------------------------------
[17:00:08.498] <TB0> INFO: Test took 10494ms.
[17:00:08.799] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[17:00:08.819] <TB0> INFO: ----------------------------------------------------------------------
[17:00:08.819] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[17:00:08.819] <TB0> INFO: ----------------------------------------------------------------------
[17:00:19.612] <TB0> INFO: Test took 10789ms.
[17:00:19.910] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[17:00:19.910] <TB0> INFO: CalDel: 110 155 125 125 143 143 122 154 125 138 136 153 160 140 143 147
[17:00:19.910] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[17:00:19.914] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C0.dat
[17:00:19.915] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C1.dat
[17:00:19.915] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C2.dat
[17:00:19.915] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C3.dat
[17:00:19.916] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C4.dat
[17:00:19.916] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C5.dat
[17:00:19.916] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C6.dat
[17:00:19.916] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C7.dat
[17:00:19.917] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C8.dat
[17:00:19.917] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C9.dat
[17:00:19.917] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C10.dat
[17:00:19.918] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C11.dat
[17:00:19.918] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C12.dat
[17:00:19.918] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C13.dat
[17:00:19.918] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C14.dat
[17:00:19.919] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters_C15.dat
[17:00:19.919] <TB0> INFO: PixTestPretest::doTest() done, duration: 44 seconds
[17:00:20.031] <TB0> INFO: enter test to run
[17:00:20.031] <TB0> INFO: test: fulltest no parameter change
[17:00:20.031] <TB0> INFO: running: fulltest
[17:00:20.032] <TB0> INFO: ######################################################################
[17:00:20.032] <TB0> INFO: PixTestFullTest::doTest()
[17:00:20.032] <TB0> INFO: ######################################################################
[17:00:20.033] <TB0> INFO: ######################################################################
[17:00:20.033] <TB0> INFO: PixTestAlive::doTest()
[17:00:20.033] <TB0> INFO: ######################################################################
[17:00:20.035] <TB0> INFO: ----------------------------------------------------------------------
[17:00:20.035] <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)
[17:00:20.035] <TB0> INFO: ----------------------------------------------------------------------
[17:00:24.090] <TB0> INFO: Test took 4055ms.
[17:00:24.110] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:24.319] <TB0> INFO: PixTestAlive::aliveTest() done
[17:00:24.319] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:00:24.321] <TB0> INFO: ----------------------------------------------------------------------
[17:00:24.321] <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)
[17:00:24.321] <TB0> INFO: ----------------------------------------------------------------------
[17:00:27.485] <TB0> INFO: Test took 3163ms.
[17:00:27.488] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:27.489] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:00:27.700] <TB0> INFO: PixTestAlive::maskTest() done
[17:00:27.700] <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
[17:00:27.702] <TB0> INFO: ----------------------------------------------------------------------
[17:00:27.702] <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)
[17:00:27.702] <TB0> INFO: ----------------------------------------------------------------------
[17:00:32.042] <TB0> INFO: Test took 4339ms.
[17:00:32.061] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:32.272] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[17:00:32.272] <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
[17:00:32.272] <TB0> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[17:00:32.285] <TB0> INFO: ######################################################################
[17:00:32.285] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[17:00:32.285] <TB0> INFO: ######################################################################
[17:00:32.287] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30) hits flags = 2 (plus default)
[17:00:32.298] <TB0> INFO: dacScan step from 0 .. 29
[17:00:58.277] <TB0> INFO: Test took 25979ms.
[17:00:58.308] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:58.308] <TB0> INFO: dacScan step from 30 .. 59
[17:01:26.285] <TB0> INFO: Test took 27977ms.
[17:01:26.347] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:26.355] <TB0> INFO: dacScan step from 60 .. 89
[17:02:04.386] <TB0> INFO: Test took 38031ms.
[17:02:04.659] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:02:04.729] <TB0> INFO: dacScan step from 90 .. 119
[17:02:44.503] <TB0> INFO: Test took 39774ms.
[17:02:44.805] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:02:44.894] <TB0> INFO: dacScan step from 120 .. 149
[17:03:16.277] <TB0> INFO: Test took 31383ms.
[17:03:16.478] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:03:39.896] <TB0> INFO: PixTestBBMap::doTest() done, duration: 187 seconds
[17:03:39.896] <TB0> INFO: number of dead bumps (per ROC): 0 0 0 0 1 0 0 0 0 0 1 0 0 0 0 0
[17:03:39.896] <TB0> INFO: separation cut (per ROC): 94 94 99 96 87 110 95 82 88 91 88 97 92 87 93 89
[17:03:39.971] <TB0> INFO: ######################################################################
[17:03:39.971] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50
[17:03:39.971] <TB0> INFO: ######################################################################
[17:03:39.971] <TB0> INFO: ----------------------------------------------------------------------
[17:03:39.971] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50
[17:03:39.971] <TB0> INFO: ----------------------------------------------------------------------
[17:03:39.971] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (4) hits flags = 16 (plus default)
[17:03:39.981] <TB0> INFO: dacScan step from 0 .. 3
[17:04:00.321] <TB0> INFO: Test took 20340ms.
[17:04:00.347] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:00.347] <TB0> INFO: dacScan step from 4 .. 7
[17:04:23.441] <TB0> INFO: Test took 23094ms.
[17:04:23.467] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:23.468] <TB0> INFO: dacScan step from 8 .. 11
[17:04:48.160] <TB0> INFO: Test took 24692ms.
[17:04:48.187] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:48.187] <TB0> INFO: dacScan step from 12 .. 15
[17:05:13.329] <TB0> INFO: Test took 25142ms.
[17:05:13.357] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:13.357] <TB0> INFO: dacScan step from 16 .. 19
[17:05:38.860] <TB0> INFO: Test took 25503ms.
[17:05:38.887] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:38.887] <TB0> INFO: dacScan step from 20 .. 23
[17:06:04.166] <TB0> INFO: Test took 25279ms.
[17:06:04.191] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:06:04.191] <TB0> INFO: dacScan step from 24 .. 27
[17:06:29.614] <TB0> INFO: Test took 25423ms.
[17:06:29.639] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:06:29.639] <TB0> INFO: dacScan step from 28 .. 31
[17:06:55.072] <TB0> INFO: Test took 25433ms.
[17:06:55.097] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:06:55.097] <TB0> INFO: dacScan step from 32 .. 35
[17:07:20.684] <TB0> INFO: Test took 25587ms.
[17:07:20.709] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:20.709] <TB0> INFO: dacScan step from 36 .. 39
[17:07:46.330] <TB0> INFO: Test took 25621ms.
[17:07:46.358] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:46.358] <TB0> INFO: dacScan step from 40 .. 43
[17:08:11.607] <TB0> INFO: Test took 25249ms.
[17:08:11.632] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:11.632] <TB0> INFO: dacScan step from 44 .. 47
[17:08:37.326] <TB0> INFO: Test took 25694ms.
[17:08:37.350] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:37.350] <TB0> INFO: dacScan step from 48 .. 51
[17:09:02.979] <TB0> INFO: Test took 25630ms.
[17:09:03.010] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:03.010] <TB0> INFO: dacScan step from 52 .. 55
[17:09:28.008] <TB0> INFO: Test took 24998ms.
[17:09:28.037] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:28.037] <TB0> INFO: dacScan step from 56 .. 59
[17:09:53.247] <TB0> INFO: Test took 25210ms.
[17:09:53.278] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:53.278] <TB0> INFO: dacScan step from 60 .. 63
[17:10:18.701] <TB0> INFO: Test took 25423ms.
[17:10:18.730] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:18.730] <TB0> INFO: dacScan step from 64 .. 67
[17:10:44.156] <TB0> INFO: Test took 25426ms.
[17:10:44.181] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:44.181] <TB0> INFO: dacScan step from 68 .. 71
[17:11:10.089] <TB0> INFO: Test took 25908ms.
[17:11:10.117] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:11:10.117] <TB0> INFO: dacScan step from 72 .. 75
[17:11:36.745] <TB0> INFO: Test took 26627ms.
[17:11:36.771] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:11:36.771] <TB0> INFO: dacScan step from 76 .. 79
[17:12:02.696] <TB0> INFO: Test took 25924ms.
[17:12:02.730] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:02.731] <TB0> INFO: dacScan step from 80 .. 83
[17:12:29.041] <TB0> INFO: Test took 26310ms.
[17:12:29.093] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:29.096] <TB0> INFO: dacScan step from 84 .. 87
[17:12:57.311] <TB0> INFO: Test took 28215ms.
[17:12:57.389] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:57.393] <TB0> INFO: dacScan step from 88 .. 91
[17:13:27.869] <TB0> INFO: Test took 30476ms.
[17:13:27.978] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:27.985] <TB0> INFO: dacScan step from 92 .. 95
[17:14:01.567] <TB0> INFO: Test took 33582ms.
[17:14:01.731] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:14:01.741] <TB0> INFO: dacScan step from 96 .. 99
[17:14:38.337] <TB0> INFO: Test took 36596ms.
[17:14:38.546] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:14:38.555] <TB0> INFO: dacScan step from 100 .. 103
[17:15:17.717] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (66) != TBM ID (0)
[17:15:17.717] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (1) != Token Chain Length (4)
[17:15:17.717] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (1) != TBM ID (67)
[17:15:17.717] <TB0> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[17:15:17.717] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:15:18.177] <TB0> INFO: Test took 39622ms.
[17:15:18.377] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:18.387] <TB0> INFO: dacScan step from 104 .. 107
[17:15:56.706] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[17:15:56.707] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:15:58.184] <TB0> INFO: Test took 39797ms.
[17:15:58.429] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:58.441] <TB0> INFO: dacScan step from 108 .. 111
[17:16:38.198] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[17:16:38.198] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:16:39.916] <TB0> INFO: Test took 41475ms.
[17:16:40.155] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:40.168] <TB0> INFO: dacScan step from 112 .. 115
[17:17:20.282] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[17:17:20.282] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (108) != TBM ID (109)
[17:17:20.282] <TB0> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[17:17:20.282] <TB0> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[17:17:20.282] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:17:22.253] <TB0> INFO: Test took 42085ms.
[17:17:22.500] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:22.514] <TB0> INFO: dacScan step from 116 .. 119
[17:18:02.969] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (13) != TBM ID (0)
[17:18:02.969] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[17:18:02.969] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (1) != TBM ID (14)
[17:18:04.893] <TB0> INFO: Test took 42379ms.
[17:18:05.119] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:05.129] <TB0> INFO: dacScan step from 120 .. 123
[17:18:45.616] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[17:18:45.616] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (209) != TBM ID (210)
[17:18:45.616] <TB0> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[17:18:45.617] <TB0> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[17:18:45.617] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:18:47.324] <TB0> INFO: Test took 42195ms.
[17:18:47.574] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:47.590] <TB0> INFO: dacScan step from 124 .. 127
[17:19:20.024] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[17:19:20.024] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:19:21.380] <TB0> INFO: Test took 33790ms.
[17:19:21.606] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:19:21.616] <TB0> INFO: dacScan step from 128 .. 131
[17:19:58.372] <TB0> INFO: Test took 36755ms.
[17:19:58.645] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:19:58.657] <TB0> INFO: dacScan step from 132 .. 135
[17:20:40.306] <TB0> INFO: Test took 41649ms.
[17:20:40.532] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:40.545] <TB0> INFO: dacScan step from 136 .. 139
[17:21:22.411] <TB0> INFO: Test took 41866ms.
[17:21:22.629] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:22.640] <TB0> INFO: dacScan step from 140 .. 143
[17:21:56.768] <TB0> INFO: Test took 34128ms.
[17:21:56.992] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:57.005] <TB0> INFO: dacScan step from 144 .. 147
[17:22:30.766] <TB0> INFO: Test took 33760ms.
[17:22:31.008] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:22:31.022] <TB0> INFO: dacScan step from 148 .. 149
[17:22:49.448] <TB0> INFO: Test took 18426ms.
[17:22:49.560] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:22:49.567] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:22:51.180] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:22:52.571] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:22:54.073] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:22:55.470] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:22:57.006] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:22:58.496] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:23:00.020] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:23:01.422] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:23:02.852] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:23:04.289] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:23:05.709] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:23:07.102] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:23:08.504] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:23:10.049] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:23:11.640] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:23:13.014] <TB0> INFO: PixTestScurves::scurves() done
[17:23:13.014] <TB0> INFO: Vcal mean: 100.98 101.38 108.38 102.45 90.22 109.66 95.80 85.24 100.63 92.59 95.45 92.51 96.60 88.54 92.62 95.51
[17:23:13.014] <TB0> INFO: Vcal RMS: 5.63 5.40 5.57 5.50 4.48 6.15 5.87 4.66 5.88 5.62 5.38 5.40 5.34 4.87 6.55 5.81
[17:23:13.014] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1173 seconds
[17:23:13.094] <TB0> INFO: ######################################################################
[17:23:13.094] <TB0> INFO: PixTestTrim::doTest()
[17:23:13.094] <TB0> INFO: ######################################################################
[17:23:13.095] <TB0> INFO: ----------------------------------------------------------------------
[17:23:13.095] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[17:23:13.095] <TB0> INFO: ----------------------------------------------------------------------
[17:23:13.183] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[17:23:13.183] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[17:23:13.193] <TB0> INFO: dacScan step from 0 .. 19
[17:23:30.127] <TB0> INFO: Test took 16934ms.
[17:23:30.149] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:30.149] <TB0> INFO: dacScan step from 20 .. 39
[17:23:47.088] <TB0> INFO: Test took 16939ms.
[17:23:47.108] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:47.108] <TB0> INFO: dacScan step from 40 .. 59
[17:24:05.233] <TB0> INFO: Test took 18125ms.
[17:24:05.261] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:05.261] <TB0> INFO: dacScan step from 60 .. 79
[17:24:23.627] <TB0> INFO: Test took 18366ms.
[17:24:23.651] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:23.651] <TB0> INFO: dacScan step from 80 .. 99
[17:24:40.648] <TB0> INFO: Test took 16997ms.
[17:24:40.688] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:40.692] <TB0> INFO: dacScan step from 100 .. 119
[17:25:01.695] <TB0> INFO: Test took 21003ms.
[17:25:01.898] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:25:01.938] <TB0> INFO: dacScan step from 120 .. 139
[17:25:18.895] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)
[17:25:18.895] <TB0> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[17:25:18.895] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[17:25:18.895] <TB0> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!
[17:25:18.895] <TB0> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[17:25:18.895] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:25:24.105] <TB0> INFO: Test took 22167ms.
[17:25:24.264] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:25:24.294] <TB0> INFO: dacScan step from 140 .. 159
[17:25:45.170] <TB0> INFO: Test took 20876ms.
[17:25:45.246] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:26:09.262] <TB0> INFO: ROC 0 VthrComp = 102
[17:26:09.262] <TB0> INFO: ROC 1 VthrComp = 99
[17:26:09.262] <TB0> INFO: ROC 2 VthrComp = 104
[17:26:09.262] <TB0> INFO: ROC 3 VthrComp = 102
[17:26:09.262] <TB0> INFO: ROC 4 VthrComp = 94
[17:26:09.262] <TB0> INFO: ROC 5 VthrComp = 105
[17:26:09.262] <TB0> INFO: ROC 6 VthrComp = 96
[17:26:09.262] <TB0> INFO: ROC 7 VthrComp = 87
[17:26:09.262] <TB0> INFO: ROC 8 VthrComp = 99
[17:26:09.262] <TB0> INFO: ROC 9 VthrComp = 95
[17:26:09.262] <TB0> INFO: ROC 10 VthrComp = 95
[17:26:09.262] <TB0> INFO: ROC 11 VthrComp = 96
[17:26:09.262] <TB0> INFO: ROC 12 VthrComp = 98
[17:26:09.263] <TB0> INFO: ROC 13 VthrComp = 88
[17:26:09.263] <TB0> INFO: ROC 14 VthrComp = 90
[17:26:09.263] <TB0> INFO: ROC 15 VthrComp = 92
[17:26:09.263] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[17:26:09.263] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[17:26:09.271] <TB0> INFO: dacScan step from 0 .. 19
[17:26:27.895] <TB0> INFO: Test took 18623ms.
[17:26:27.921] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:26:27.921] <TB0> INFO: dacScan step from 20 .. 39
[17:26:46.329] <TB0> INFO: Test took 18408ms.
[17:26:46.363] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:26:46.367] <TB0> INFO: dacScan step from 40 .. 59
[17:27:09.962] <TB0> INFO: Test took 23595ms.
[17:27:10.136] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:27:10.171] <TB0> INFO: dacScan step from 60 .. 79
[17:27:36.915] <TB0> INFO: Test took 26744ms.
[17:27:37.083] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:27:37.132] <TB0> INFO: dacScan step from 80 .. 99
[17:28:03.968] <TB0> INFO: Test took 26836ms.
[17:28:04.135] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:28:04.187] <TB0> INFO: dacScan step from 100 .. 119
[17:28:31.115] <TB0> INFO: Test took 26928ms.
[17:28:31.285] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:28:31.335] <TB0> INFO: dacScan step from 120 .. 139
[17:28:57.681] <TB0> INFO: Test took 26346ms.
[17:28:57.837] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:28:57.886] <TB0> INFO: dacScan step from 140 .. 159
[17:29:24.524] <TB0> INFO: Test took 26638ms.
[17:29:24.702] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:29:49.904] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 61.3768 for pixel 1/79 mean/min/max = 47.0616/32.6229/61.5004
[17:29:49.904] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 58.7497 for pixel 42/5 mean/min/max = 45.1544/31.4353/58.8736
[17:29:49.905] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 65.583 for pixel 0/75 mean/min/max = 49.8295/33.9707/65.6883
[17:29:49.905] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 59.2292 for pixel 25/69 mean/min/max = 45.9614/32.5965/59.3262
[17:29:49.905] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 55.7006 for pixel 0/16 mean/min/max = 44.9108/33.909/55.9126
[17:29:49.905] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 67.3248 for pixel 0/0 mean/min/max = 50.4758/33.4054/67.5463
[17:29:49.906] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 62.5173 for pixel 0/0 mean/min/max = 47.0473/31.573/62.5216
[17:29:49.906] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 56.7561 for pixel 19/5 mean/min/max = 44.975/33.1504/56.7996
[17:29:49.906] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 61.3712 for pixel 0/77 mean/min/max = 46.5918/31.6308/61.5528
[17:29:49.906] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 59.2634 for pixel 3/2 mean/min/max = 45.463/31.6342/59.2919
[17:29:49.907] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 58.7862 for pixel 5/61 mean/min/max = 45.5254/32.2068/58.8439
[17:29:49.907] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 58.9892 for pixel 7/8 mean/min/max = 45.6232/32.2541/58.9922
[17:29:49.907] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 59.5832 for pixel 4/73 mean/min/max = 45.7526/31.7836/59.7217
[17:29:49.907] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 58.7385 for pixel 4/2 mean/min/max = 46.6897/34.5673/58.8122
[17:29:49.908] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 64.4209 for pixel 0/74 mean/min/max = 48.4498/32.3572/64.5424
[17:29:49.908] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 63.7711 for pixel 0/26 mean/min/max = 48.7173/33.6266/63.8081
[17:29:49.908] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:31:57.510] <TB0> INFO: Test took 127602ms.
[17:31:58.819] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[17:31:58.827] <TB0> INFO: dacScan step from 0 .. 19
[17:32:27.606] <TB0> INFO: Test took 28779ms.
[17:32:27.654] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:32:27.656] <TB0> INFO: dacScan step from 20 .. 39
[17:33:01.901] <TB0> INFO: Test took 34245ms.
[17:33:02.138] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:02.171] <TB0> INFO: dacScan step from 40 .. 59
[17:33:45.868] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[17:33:45.869] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:33:47.306] <TB0> INFO: Test took 45135ms.
[17:33:47.587] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:47.637] <TB0> INFO: dacScan step from 60 .. 79
[17:34:30.201] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[17:34:30.201] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (237) != TBM ID (238)
[17:34:30.201] <TB0> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[17:34:30.201] <TB0> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[17:34:30.201] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:34:31.481] <TB0> INFO: Test took 43844ms.
[17:34:31.754] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:31.809] <TB0> INFO: dacScan step from 80 .. 99
[17:35:13.550] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[17:35:13.550] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:35:15.697] <TB0> INFO: Test took 43888ms.
[17:35:15.961] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:16.005] <TB0> INFO: dacScan step from 100 .. 119
[17:35:58.718] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[17:35:58.718] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:36:00.259] <TB0> INFO: Test took 44254ms.
[17:36:00.531] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:36:00.580] <TB0> INFO: dacScan step from 120 .. 139
[17:36:43.957] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[17:36:43.958] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:36:45.326] <TB0> INFO: Test took 44746ms.
[17:36:45.589] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:36:45.641] <TB0> INFO: dacScan step from 140 .. 159
[17:37:21.371] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[17:37:21.371] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (24) != TBM ID (25)
[17:37:21.371] <TB0> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[17:37:21.371] <TB0> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[17:37:21.371] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:37:22.721] <TB0> INFO: Test took 37080ms.
[17:37:22.999] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:37:23.053] <TB0> INFO: dacScan step from 160 .. 179
[17:38:05.081] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[17:38:05.081] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (22) != TBM ID (23)
[17:38:05.081] <TB0> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[17:38:05.081] <TB0> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[17:38:05.081] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:38:06.481] <TB0> INFO: Test took 43428ms.
[17:38:06.757] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:06.808] <TB0> INFO: dacScan step from 180 .. 199
[17:38:46.307] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[17:38:46.307] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (34) != TBM ID (35)
[17:38:46.307] <TB0> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[17:38:46.307] <TB0> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[17:38:46.307] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:38:48.191] <TB0> INFO: Test took 41383ms.
[17:38:48.463] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:39:13.704] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.007457 .. 255.000000
[17:39:13.780] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20) hits flags = 16 (plus default)
[17:39:13.789] <TB0> INFO: dacScan step from 0 .. 19
[17:39:28.652] <TB0> INFO: Test took 14863ms.
[17:39:28.671] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:39:28.671] <TB0> INFO: dacScan step from 20 .. 39
[17:39:44.508] <TB0> INFO: Test took 15837ms.
[17:39:44.585] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:39:44.600] <TB0> INFO: dacScan step from 40 .. 59
[17:40:06.734] <TB0> INFO: Test took 22134ms.
[17:40:06.963] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:40:07.037] <TB0> INFO: dacScan step from 60 .. 79
[17:40:29.824] <TB0> INFO: Test took 22787ms.
[17:40:29.966] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:40:30.015] <TB0> INFO: dacScan step from 80 .. 99
[17:40:53.315] <TB0> INFO: Test took 23300ms.
[17:40:53.463] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:40:53.512] <TB0> INFO: dacScan step from 100 .. 119
[17:41:16.359] <TB0> INFO: Test took 22847ms.
[17:41:16.498] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:16.547] <TB0> INFO: dacScan step from 120 .. 139
[17:41:37.892] <TB0> INFO: Test took 21345ms.
[17:41:38.027] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:38.074] <TB0> INFO: dacScan step from 140 .. 159
[17:42:00.973] <TB0> INFO: Test took 22899ms.
[17:42:01.108] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:42:01.156] <TB0> INFO: dacScan step from 160 .. 179
[17:42:23.809] <TB0> INFO: Test took 22653ms.
[17:42:23.947] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:42:23.995] <TB0> INFO: dacScan step from 180 .. 199
[17:42:47.794] <TB0> INFO: Test took 23799ms.
[17:42:47.944] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:42:47.992] <TB0> INFO: dacScan step from 200 .. 219
[17:43:11.073] <TB0> INFO: Test took 23081ms.
[17:43:11.223] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:43:11.289] <TB0> INFO: dacScan step from 220 .. 239
[17:43:34.251] <TB0> INFO: Test took 22962ms.
[17:43:34.387] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:43:34.435] <TB0> INFO: dacScan step from 240 .. 255
[17:43:53.210] <TB0> INFO: Test took 18774ms.
[17:43:53.327] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:44:25.043] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 16.295588 .. 45.630280
[17:44:25.124] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 6 .. 55 (20) hits flags = 16 (plus default)
[17:44:25.133] <TB0> INFO: dacScan step from 6 .. 25
[17:44:41.687] <TB0> INFO: Test took 16554ms.
[17:44:41.709] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:44:41.709] <TB0> INFO: dacScan step from 26 .. 45
[17:45:01.813] <TB0> INFO: Test took 20104ms.
[17:45:01.940] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:45:01.971] <TB0> INFO: dacScan step from 46 .. 55
[17:45:14.800] <TB0> INFO: Test took 12829ms.
[17:45:14.869] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:45:31.021] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 3.842241 .. 41.480502
[17:45:31.104] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 3 .. 51 (20) hits flags = 16 (plus default)
[17:45:31.113] <TB0> INFO: dacScan step from 3 .. 22
[17:45:47.126] <TB0> INFO: Test took 16013ms.
[17:45:47.144] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:45:47.144] <TB0> INFO: dacScan step from 23 .. 42
[17:46:06.202] <TB0> INFO: Test took 19058ms.
[17:46:06.302] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:46:06.327] <TB0> INFO: dacScan step from 43 .. 51
[17:46:17.894] <TB0> INFO: Test took 11567ms.
[17:46:17.973] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:46:33.007] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 0.072837 .. 41.480502
[17:46:33.082] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 0 .. 51 (20) hits flags = 16 (plus default)
[17:46:33.092] <TB0> INFO: dacScan step from 0 .. 19
[17:46:47.764] <TB0> INFO: Test took 14672ms.
[17:46:47.784] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:46:47.784] <TB0> INFO: dacScan step from 20 .. 39
[17:47:06.335] <TB0> INFO: Test took 18551ms.
[17:47:06.405] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:06.421] <TB0> INFO: dacScan step from 40 .. 51
[17:47:21.522] <TB0> INFO: Test took 15101ms.
[17:47:21.635] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:35.914] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[17:47:35.914] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20) hits flags = 16 (plus default)
[17:47:35.923] <TB0> INFO: dacScan step from 15 .. 34
[17:48:06.332] <TB0> INFO: Test took 30409ms.
[17:48:06.397] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:06.406] <TB0> INFO: dacScan step from 35 .. 54
[17:48:49.905] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (244) != TBM ID (0)
[17:48:49.905] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[17:48:49.905] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (1) != TBM ID (245)
[17:48:50.991] <TB0> INFO: Test took 44585ms.
[17:48:51.276] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:51.321] <TB0> INFO: dacScan step from 55 .. 55
[17:48:56.377] <TB0> INFO: Test took 5056ms.
[17:48:56.392] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:49:10.631] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C0.dat
[17:49:10.632] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C1.dat
[17:49:10.632] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C2.dat
[17:49:10.632] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C3.dat
[17:49:10.632] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C4.dat
[17:49:10.633] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C5.dat
[17:49:10.633] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C6.dat
[17:49:10.633] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C7.dat
[17:49:10.633] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C8.dat
[17:49:10.634] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C9.dat
[17:49:10.634] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C10.dat
[17:49:10.634] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C11.dat
[17:49:10.634] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C12.dat
[17:49:10.635] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C13.dat
[17:49:10.635] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C14.dat
[17:49:10.635] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C15.dat
[17:49:10.635] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C0.dat
[17:49:10.648] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C1.dat
[17:49:10.654] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C2.dat
[17:49:10.660] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C3.dat
[17:49:10.669] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C4.dat
[17:49:10.679] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C5.dat
[17:49:10.689] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C6.dat
[17:49:10.698] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C7.dat
[17:49:10.708] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C8.dat
[17:49:10.718] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C9.dat
[17:49:10.728] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C10.dat
[17:49:10.738] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C11.dat
[17:49:10.748] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C12.dat
[17:49:10.758] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C13.dat
[17:49:10.768] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C14.dat
[17:49:10.777] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//trimParameters35_C15.dat
[17:49:10.787] <TB0> INFO: PixTestTrim::trimTest() done
[17:49:10.787] <TB0> INFO: vtrim: 109 108 133 121 106 127 116 102 114 108 105 116 115 101 120 108
[17:49:10.787] <TB0> INFO: vthrcomp: 102 99 104 102 94 105 96 87 99 95 95 96 98 88 90 92
[17:49:10.787] <TB0> INFO: vcal mean: 35.08 35.11 35.12 35.06 35.12 35.20 35.08 35.05 35.05 35.08 35.05 35.02 35.03 35.10 35.08 35.09
[17:49:10.787] <TB0> INFO: vcal RMS: 1.06 1.26 1.11 1.05 0.99 1.13 1.16 1.04 1.08 1.04 1.08 1.14 1.02 1.02 1.22 1.05
[17:49:10.787] <TB0> INFO: bits mean: 9.29 10.40 8.76 9.87 10.02 8.60 9.75 10.18 10.00 10.23 9.90 9.97 9.80 9.43 9.10 8.60
[17:49:10.787] <TB0> INFO: bits RMS: 2.64 2.37 2.50 2.47 2.26 2.57 2.56 2.27 2.44 2.44 2.55 2.48 2.59 2.34 2.66 2.69
[17:49:10.794] <TB0> INFO: ----------------------------------------------------------------------
[17:49:10.794] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[17:49:10.794] <TB0> INFO: ----------------------------------------------------------------------
[17:49:10.795] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[17:49:10.806] <TB0> INFO: dacScan step from 0 .. 19
[17:49:39.915] <TB0> INFO: Test took 29109ms.
[17:49:39.951] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:49:39.951] <TB0> INFO: dacScan step from 20 .. 39
[17:50:09.190] <TB0> INFO: Test took 29238ms.
[17:50:09.226] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:50:09.226] <TB0> INFO: dacScan step from 40 .. 59
[17:50:38.572] <TB0> INFO: Test took 29346ms.
[17:50:38.607] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:50:38.607] <TB0> INFO: dacScan step from 60 .. 79
[17:51:04.789] <TB0> INFO: Test took 26182ms.
[17:51:04.827] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:51:04.827] <TB0> INFO: dacScan step from 80 .. 99
[17:51:30.460] <TB0> INFO: Test took 25633ms.
[17:51:30.496] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:51:30.497] <TB0> INFO: dacScan step from 100 .. 119
[17:52:03.001] <TB0> INFO: Test took 32504ms.
[17:52:03.095] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:52:03.105] <TB0> INFO: dacScan step from 120 .. 139
[17:52:41.700] <TB0> INFO: Test took 38595ms.
[17:52:41.979] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:52:42.037] <TB0> INFO: dacScan step from 140 .. 159
[17:53:20.085] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[17:53:20.085] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[17:53:21.655] <TB0> INFO: Test took 39618ms.
[17:53:21.924] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:53:21.993] <TB0> INFO: dacScan step from 160 .. 179
[17:54:03.845] <TB0> INFO: Test took 41852ms.
[17:54:04.124] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:54:04.171] <TB0> INFO: dacScan step from 180 .. 199
[17:54:48.306] <TB0> INFO: Test took 44135ms.
[17:54:48.585] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:55:13.822] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 183 (20) hits flags = 16 (plus default)
[17:55:13.833] <TB0> INFO: dacScan step from 0 .. 19
[17:55:40.570] <TB0> INFO: Test took 26737ms.
[17:55:40.605] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:55:40.605] <TB0> INFO: dacScan step from 20 .. 39
[17:56:10.322] <TB0> INFO: Test took 29717ms.
[17:56:10.357] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:56:10.357] <TB0> INFO: dacScan step from 40 .. 59
[17:56:39.565] <TB0> INFO: Test took 29208ms.
[17:56:39.600] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:56:39.600] <TB0> INFO: dacScan step from 60 .. 79
[17:57:08.862] <TB0> INFO: Test took 29262ms.
[17:57:08.900] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:57:08.900] <TB0> INFO: dacScan step from 80 .. 99
[17:57:38.407] <TB0> INFO: Test took 29507ms.
[17:57:38.455] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:57:38.456] <TB0> INFO: dacScan step from 100 .. 119
[17:58:15.770] <TB0> INFO: Test took 37314ms.
[17:58:15.984] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:58:16.005] <TB0> INFO: dacScan step from 120 .. 139
[17:58:54.135] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[17:58:54.135] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 31 readouts!
[17:58:55.893] <TB0> INFO: Test took 39888ms.
[17:58:56.157] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:58:56.196] <TB0> INFO: dacScan step from 140 .. 159
[17:59:39.665] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[17:59:41.339] <TB0> INFO: Test took 45143ms.
[17:59:41.674] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:59:41.725] <TB0> INFO: dacScan step from 160 .. 179
[18:00:22.980] <TB0> INFO: Test took 41255ms.
[18:00:23.236] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:00:23.280] <TB0> INFO: dacScan step from 180 .. 183
[18:00:34.671] <TB0> INFO: Test took 11391ms.
[18:00:34.727] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:00:59.648] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 179 (20) hits flags = 16 (plus default)
[18:00:59.657] <TB0> INFO: dacScan step from 0 .. 19
[18:01:28.051] <TB0> INFO: Test took 28394ms.
[18:01:28.086] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:01:28.086] <TB0> INFO: dacScan step from 20 .. 39
[18:01:56.776] <TB0> INFO: Test took 28690ms.
[18:01:56.808] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:01:56.808] <TB0> INFO: dacScan step from 40 .. 59
[18:02:24.518] <TB0> INFO: Test took 27710ms.
[18:02:24.556] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:02:24.556] <TB0> INFO: dacScan step from 60 .. 79
[18:02:51.617] <TB0> INFO: Test took 27061ms.
[18:02:51.651] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:02:51.651] <TB0> INFO: dacScan step from 80 .. 99
[18:03:21.052] <TB0> INFO: Test took 29401ms.
[18:03:21.095] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:03:21.097] <TB0> INFO: dacScan step from 100 .. 119
[18:03:54.740] <TB0> INFO: Test took 33643ms.
[18:03:54.944] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:03:54.966] <TB0> INFO: dacScan step from 120 .. 139
[18:04:32.897] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[18:04:32.897] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (236) != TBM ID (237)
[18:04:32.897] <TB0> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[18:04:32.897] <TB0> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[18:04:32.897] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[18:04:34.471] <TB0> INFO: Test took 39504ms.
[18:04:34.736] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:04:34.776] <TB0> INFO: dacScan step from 140 .. 159
[18:05:18.049] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[18:05:19.606] <TB0> INFO: Test took 44830ms.
[18:05:19.876] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:05:19.925] <TB0> INFO: dacScan step from 160 .. 179
[18:06:03.426] <TB0> INFO: Test took 43501ms.
[18:06:03.705] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:06:27.788] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 170 (20) hits flags = 16 (plus default)
[18:06:27.796] <TB0> INFO: dacScan step from 0 .. 19
[18:06:56.687] <TB0> INFO: Test took 28890ms.
[18:06:56.721] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:06:56.721] <TB0> INFO: dacScan step from 20 .. 39
[18:07:26.045] <TB0> INFO: Test took 29324ms.
[18:07:26.079] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:07:26.079] <TB0> INFO: dacScan step from 40 .. 59
[18:07:55.390] <TB0> INFO: Test took 29311ms.
[18:07:55.427] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:07:55.427] <TB0> INFO: dacScan step from 60 .. 79
[18:08:24.571] <TB0> INFO: Test took 29143ms.
[18:08:24.606] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:08:24.606] <TB0> INFO: dacScan step from 80 .. 99
[18:08:54.174] <TB0> INFO: Test took 29568ms.
[18:08:54.221] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:08:54.223] <TB0> INFO: dacScan step from 100 .. 119
[18:09:31.615] <TB0> INFO: Test took 37392ms.
[18:09:31.810] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:09:31.833] <TB0> INFO: dacScan step from 120 .. 139
[18:10:15.274] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (202) != TBM ID (0)
[18:10:15.274] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[18:10:15.274] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (1) != TBM ID (203)
[18:10:17.068] <TB0> INFO: Test took 45235ms.
[18:10:17.333] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:10:17.372] <TB0> INFO: dacScan step from 140 .. 159
[18:11:00.888] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (69) != TBM ID (0)
[18:11:00.888] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[18:11:00.888] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (1) != TBM ID (70)
[18:11:02.676] <TB0> INFO: Test took 45304ms.
[18:11:02.950] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:11:02.995] <TB0> INFO: dacScan step from 160 .. 170
[18:11:29.825] <TB0> INFO: Test took 26830ms.
[18:11:29.976] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:11:53.758] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 169 (20) hits flags = 16 (plus default)
[18:11:53.768] <TB0> INFO: dacScan step from 0 .. 19
[18:12:22.751] <TB0> INFO: Test took 28983ms.
[18:12:22.785] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:12:22.785] <TB0> INFO: dacScan step from 20 .. 39
[18:12:50.964] <TB0> INFO: Test took 28179ms.
[18:12:50.998] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:12:50.998] <TB0> INFO: dacScan step from 40 .. 59
[18:13:20.502] <TB0> INFO: Test took 29504ms.
[18:13:20.537] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:13:20.537] <TB0> INFO: dacScan step from 60 .. 79
[18:13:45.500] <TB0> INFO: Test took 24963ms.
[18:13:45.533] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:13:45.533] <TB0> INFO: dacScan step from 80 .. 99
[18:14:15.482] <TB0> INFO: Test took 29949ms.
[18:14:15.525] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:14:15.527] <TB0> INFO: dacScan step from 100 .. 119
[18:14:48.376] <TB0> INFO: Test took 32849ms.
[18:14:48.585] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:14:48.610] <TB0> INFO: dacScan step from 120 .. 139
[18:15:31.396] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (120) != TBM ID (0)
[18:15:31.396] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[18:15:31.396] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (1) != TBM ID (121)
[18:15:32.823] <TB0> INFO: Test took 44213ms.
[18:15:33.120] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:15:33.160] <TB0> INFO: dacScan step from 140 .. 159
[18:16:09.357] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[18:16:09.357] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[18:16:10.832] <TB0> INFO: Test took 37671ms.
[18:16:11.091] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:16:11.136] <TB0> INFO: dacScan step from 160 .. 169
[18:16:36.539] <TB0> INFO: Test took 25402ms.
[18:16:36.666] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:16:58.434] <TB0> INFO: PixTestTrim::trimBitTest() done
[18:16:58.436] <TB0> INFO: PixTestTrim::doTest() done, duration: 3225 seconds
[18:16:59.092] <TB0> INFO: ######################################################################
[18:16:59.092] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[18:16:59.092] <TB0> INFO: ######################################################################
[18:17:03.364] <TB0> INFO: Test took 4270ms.
[18:17:03.387] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:17:07.685] <TB0> INFO: Test took 4103ms.
[18:17:07.750] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:17:11.800] <TB0> INFO: Test took 4043ms.
[18:17:11.871] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:17:11.877] <TB0> INFO: The DUT currently contains the following objects:
[18:17:11.877] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:11.877] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:11.877] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:11.877] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:11.877] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:11.877] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.991] <TB0> INFO: Test took 1114ms.
[18:17:12.992] <TB0> INFO: The DUT currently contains the following objects:
[18:17:12.992] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:12.992] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:12.992] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:12.992] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:12.992] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:12.992] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.104] <TB0> INFO: Test took 1112ms.
[18:17:14.105] <TB0> INFO: The DUT currently contains the following objects:
[18:17:14.105] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:14.105] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:14.105] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:14.105] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:14.106] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:14.106] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.249] <TB0> INFO: Test took 1143ms.
[18:17:15.250] <TB0> INFO: The DUT currently contains the following objects:
[18:17:15.250] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:15.250] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:15.250] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:15.250] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:15.250] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:15.250] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.377] <TB0> INFO: Test took 1127ms.
[18:17:16.378] <TB0> INFO: The DUT currently contains the following objects:
[18:17:16.388] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:16.388] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:16.388] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:16.388] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:16.388] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.388] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.388] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.388] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.388] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.388] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.388] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.388] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.389] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.389] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.389] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.389] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.389] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.389] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.389] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:16.389] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.491] <TB0> INFO: Test took 1102ms.
[18:17:17.492] <TB0> INFO: The DUT currently contains the following objects:
[18:17:17.492] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:17.492] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:17.492] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:17.492] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:17.492] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.492] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.492] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.492] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.492] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.492] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.492] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.493] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.493] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.493] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.493] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.493] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.493] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.493] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.493] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:17.493] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.620] <TB0> INFO: Test took 1127ms.
[18:17:18.621] <TB0> INFO: The DUT currently contains the following objects:
[18:17:18.621] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:18.621] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:18.621] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:18.621] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:18.621] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:18.621] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.779] <TB0> INFO: Test took 1158ms.
[18:17:19.781] <TB0> INFO: The DUT currently contains the following objects:
[18:17:19.781] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:19.781] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:19.781] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:19.781] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:19.781] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.781] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.781] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.781] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.781] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.781] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.781] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.781] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.781] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.781] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.781] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.781] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.782] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.782] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.782] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:19.782] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.893] <TB0> INFO: Test took 1111ms.
[18:17:20.894] <TB0> INFO: The DUT currently contains the following objects:
[18:17:20.894] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:20.894] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:20.894] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:20.894] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:20.894] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:20.894] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.007] <TB0> INFO: Test took 1113ms.
[18:17:22.008] <TB0> INFO: The DUT currently contains the following objects:
[18:17:22.008] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:22.008] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:22.008] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:22.008] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:22.008] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.008] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.008] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.008] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.008] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.008] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.008] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.008] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.008] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.008] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.008] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.008] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.008] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.008] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.009] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:22.009] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.136] <TB0> INFO: Test took 1127ms.
[18:17:23.138] <TB0> INFO: The DUT currently contains the following objects:
[18:17:23.138] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:23.138] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:23.138] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:23.138] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:23.138] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:23.138] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.281] <TB0> INFO: Test took 1143ms.
[18:17:24.282] <TB0> INFO: The DUT currently contains the following objects:
[18:17:24.282] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:24.282] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:24.282] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:24.282] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:24.282] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:24.282] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.410] <TB0> INFO: Test took 1128ms.
[18:17:25.411] <TB0> INFO: The DUT currently contains the following objects:
[18:17:25.411] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:25.411] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:25.411] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:25.411] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:25.411] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.411] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.411] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.412] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.412] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.412] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.412] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.412] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.412] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.412] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.412] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.412] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.412] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.412] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.412] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:25.412] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.540] <TB0> INFO: Test took 1128ms.
[18:17:26.541] <TB0> INFO: The DUT currently contains the following objects:
[18:17:26.541] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:26.541] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:26.541] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:26.541] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:26.541] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.541] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.541] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.541] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.541] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.541] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.542] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.542] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.542] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.542] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.542] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.542] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.542] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.542] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.542] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:26.542] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.665] <TB0> INFO: Test took 1123ms.
[18:17:27.666] <TB0> INFO: The DUT currently contains the following objects:
[18:17:27.666] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:27.666] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:27.666] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:27.666] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:27.666] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.666] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.666] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.666] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.666] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.666] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.666] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.667] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.667] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.667] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.667] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.667] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.667] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.667] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.667] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:27.667] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.794] <TB0> INFO: Test took 1127ms.
[18:17:28.795] <TB0> INFO: The DUT currently contains the following objects:
[18:17:28.795] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[18:17:28.795] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:17:28.795] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:17:28.795] <TB0> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[18:17:28.795] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:28.795] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[18:17:29.955] <TB0> INFO: Test took 1159ms.
[18:17:29.958] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:22:46.091] <TB0> INFO: Test took 316134ms.
[18:22:47.741] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:01.362] <TB0> INFO: Test took 313621ms.
[18:28:03.037] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.044] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.051] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.058] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.065] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.072] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.078] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.089] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.100] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.110] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.121] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.128] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.135] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.146] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.155] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.162] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:28:03.185] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C0.dat
[18:28:03.186] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C1.dat
[18:28:03.186] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C2.dat
[18:28:03.186] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C3.dat
[18:28:03.186] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C4.dat
[18:28:03.186] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C5.dat
[18:28:03.186] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C6.dat
[18:28:03.186] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C7.dat
[18:28:03.186] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C8.dat
[18:28:03.187] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C9.dat
[18:28:03.187] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C10.dat
[18:28:03.187] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C11.dat
[18:28:03.187] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C12.dat
[18:28:03.187] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C13.dat
[18:28:03.187] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C14.dat
[18:28:03.187] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//dacParameters35_C15.dat
[18:28:07.471] <TB0> INFO: Test took 4282ms.
[18:28:11.924] <TB0> INFO: Test took 4136ms.
[18:28:16.281] <TB0> INFO: Test took 4035ms.
[18:28:16.549] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:17.523] <TB0> INFO: Test took 974ms.
[18:28:17.525] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:18.686] <TB0> INFO: Test took 1161ms.
[18:28:18.688] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:19.818] <TB0> INFO: Test took 1130ms.
[18:28:19.820] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:20.995] <TB0> INFO: Test took 1175ms.
[18:28:20.997] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:22.142] <TB0> INFO: Test took 1145ms.
[18:28:22.144] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:23.288] <TB0> INFO: Test took 1144ms.
[18:28:23.291] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:24.419] <TB0> INFO: Test took 1128ms.
[18:28:24.421] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:25.578] <TB0> INFO: Test took 1157ms.
[18:28:25.580] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:26.725] <TB0> INFO: Test took 1145ms.
[18:28:26.727] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:27.871] <TB0> INFO: Test took 1144ms.
[18:28:27.874] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:28.987] <TB0> INFO: Test took 1113ms.
[18:28:28.989] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:30.118] <TB0> INFO: Test took 1129ms.
[18:28:30.120] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:31.249] <TB0> INFO: Test took 1129ms.
[18:28:31.252] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:32.381] <TB0> INFO: Test took 1129ms.
[18:28:32.383] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:33.542] <TB0> INFO: Test took 1159ms.
[18:28:33.545] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:34.658] <TB0> INFO: Test took 1114ms.
[18:28:34.661] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:35.774] <TB0> INFO: Test took 1113ms.
[18:28:35.776] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:36.889] <TB0> INFO: Test took 1113ms.
[18:28:36.892] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:38.021] <TB0> INFO: Test took 1129ms.
[18:28:38.023] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:39.197] <TB0> INFO: Test took 1174ms.
[18:28:39.200] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:40.312] <TB0> INFO: Test took 1113ms.
[18:28:40.315] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:41.428] <TB0> INFO: Test took 1114ms.
[18:28:41.430] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:42.575] <TB0> INFO: Test took 1145ms.
[18:28:42.577] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:43.690] <TB0> INFO: Test took 1113ms.
[18:28:43.693] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:44.837] <TB0> INFO: Test took 1144ms.
[18:28:44.839] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:45.952] <TB0> INFO: Test took 1113ms.
[18:28:45.955] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:47.068] <TB0> INFO: Test took 1113ms.
[18:28:47.070] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:48.199] <TB0> INFO: Test took 1129ms.
[18:28:48.202] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:49.309] <TB0> INFO: Test took 1108ms.
[18:28:49.310] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:50.469] <TB0> INFO: Test took 1159ms.
[18:28:50.472] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:51.601] <TB0> INFO: Test took 1129ms.
[18:28:51.604] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:28:52.717] <TB0> INFO: Test took 1114ms.
[18:28:53.246] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 714 seconds
[18:28:53.246] <TB0> INFO: PH scale (per ROC): 74 69 64 74 68 70 67 80 75 68 67 79 79 79 70 70
[18:28:53.246] <TB0> INFO: PH offset (per ROC): 178 177 191 177 175 173 181 165 186 167 175 174 177 180 176 166
[18:28:53.418] <TB0> INFO: ######################################################################
[18:28:53.418] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[18:28:53.418] <TB0> INFO: ######################################################################
[18:28:53.427] <TB0> INFO: scanning low vcal = 10
[18:28:57.592] <TB0> INFO: Test took 4165ms.
[18:28:57.596] <TB0> INFO: scanning low vcal = 20
[18:29:01.640] <TB0> INFO: Test took 4045ms.
[18:29:01.645] <TB0> INFO: scanning low vcal = 30
[18:29:05.923] <TB0> INFO: Test took 4278ms.
[18:29:05.930] <TB0> INFO: scanning low vcal = 40
[18:29:11.083] <TB0> INFO: Test took 5153ms.
[18:29:11.145] <TB0> INFO: scanning low vcal = 50
[18:29:16.167] <TB0> INFO: Test took 5022ms.
[18:29:16.228] <TB0> INFO: scanning low vcal = 60
[18:29:21.223] <TB0> INFO: Test took 4995ms.
[18:29:21.287] <TB0> INFO: scanning low vcal = 70
[18:29:26.252] <TB0> INFO: Test took 4965ms.
[18:29:26.315] <TB0> INFO: scanning low vcal = 80
[18:29:31.113] <TB0> INFO: Test took 4798ms.
[18:29:31.170] <TB0> INFO: scanning low vcal = 90
[18:29:35.919] <TB0> INFO: Test took 4749ms.
[18:29:35.978] <TB0> INFO: scanning low vcal = 100
[18:29:41.096] <TB0> INFO: Test took 5118ms.
[18:29:41.156] <TB0> INFO: scanning low vcal = 110
[18:29:46.100] <TB0> INFO: Test took 4944ms.
[18:29:46.167] <TB0> INFO: scanning low vcal = 120
[18:29:51.232] <TB0> INFO: Test took 5065ms.
[18:29:51.331] <TB0> INFO: scanning low vcal = 130
[18:29:56.425] <TB0> INFO: Test took 5094ms.
[18:29:56.494] <TB0> INFO: scanning low vcal = 140
[18:30:01.698] <TB0> INFO: Test took 5204ms.
[18:30:01.755] <TB0> INFO: scanning low vcal = 150
[18:30:06.707] <TB0> INFO: Test took 4952ms.
[18:30:06.769] <TB0> INFO: scanning low vcal = 160
[18:30:11.779] <TB0> INFO: Test took 5011ms.
[18:30:11.839] <TB0> INFO: scanning low vcal = 170
[18:30:16.836] <TB0> INFO: Test took 4997ms.
[18:30:16.938] <TB0> INFO: scanning low vcal = 180
[18:30:21.990] <TB0> INFO: Test took 5052ms.
[18:30:22.049] <TB0> INFO: scanning low vcal = 190
[18:30:27.054] <TB0> INFO: Test took 5005ms.
[18:30:27.110] <TB0> INFO: scanning low vcal = 200
[18:30:31.769] <TB0> INFO: Test took 4659ms.
[18:30:31.824] <TB0> INFO: scanning low vcal = 210
[18:30:36.619] <TB0> INFO: Test took 4795ms.
[18:30:36.678] <TB0> INFO: scanning low vcal = 220
[18:30:41.316] <TB0> INFO: Test took 4638ms.
[18:30:41.418] <TB0> INFO: scanning low vcal = 230
[18:30:46.114] <TB0> INFO: Test took 4696ms.
[18:30:46.196] <TB0> INFO: scanning low vcal = 240
[18:30:51.309] <TB0> INFO: Test took 5113ms.
[18:30:51.368] <TB0> INFO: scanning low vcal = 250
[18:30:56.389] <TB0> INFO: Test took 5021ms.
[18:30:56.464] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[18:31:01.767] <TB0> INFO: Test took 5303ms.
[18:31:01.856] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[18:31:06.812] <TB0> INFO: Test took 4956ms.
[18:31:06.874] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[18:31:11.770] <TB0> INFO: Test took 4896ms.
[18:31:11.834] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[18:31:16.764] <TB0> INFO: Test took 4930ms.
[18:31:16.827] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[18:31:21.804] <TB0> INFO: Test took 4977ms.
[18:31:22.258] <TB0> INFO: PixTestGainPedestal::measure() done
[18:31:55.063] <TB0> INFO: PixTestGainPedestal::fit() done
[18:31:55.064] <TB0> INFO: non-linearity mean: 0.959 0.955 0.958 0.960 0.955 0.959 0.956 0.959 0.968 0.957 0.963 0.963 0.963 0.959 0.963 0.965
[18:31:55.064] <TB0> INFO: non-linearity RMS: 0.007 0.007 0.008 0.006 0.007 0.007 0.007 0.006 0.005 0.007 0.006 0.005 0.006 0.006 0.007 0.005
[18:31:55.064] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[18:31:55.084] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[18:31:55.103] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[18:31:55.122] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[18:31:55.143] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[18:31:55.162] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[18:31:55.183] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[18:31:55.207] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[18:31:55.227] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[18:31:55.246] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[18:31:55.266] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[18:31:55.285] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[18:31:55.304] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[18:31:55.324] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[18:31:55.343] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[18:31:55.363] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2054_FullQualification_2015-08-04_13h03m_1438686194//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[18:31:55.383] <TB0> INFO: PixTestGainPedestal::doTest() done, duration: 181 seconds
[18:31:55.392] <TB0> INFO: enter test to run
[18:31:55.393] <TB0> INFO: test: exit no parameter change
[18:31:55.808] <TB0> QUIET: Connection to board 126 closed.
[18:31:55.823] <TB0> INFO: pXar: this is the end, my friend