[13:09:57.551] <TB3> INFO: *** Welcome to pxar ***
[13:09:57.551] <TB3> INFO: *** Today: 2015/08/20
[13:09:57.551] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C15.dat
[13:09:57.553] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//tbmParameters_C0b.dat
[13:09:57.553] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//defaultMaskFile.dat
[13:09:57.553] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters_C15.dat
[13:09:57.632] <TB3> INFO: clk: 4
[13:09:57.632] <TB3> INFO: ctr: 4
[13:09:57.632] <TB3> INFO: sda: 19
[13:09:57.632] <TB3> INFO: tin: 9
[13:09:57.632] <TB3> INFO: level: 15
[13:09:57.632] <TB3> INFO: triggerdelay: 0
[13:09:57.632] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[13:09:57.632] <TB3> INFO: Log level: INFO
[13:09:57.638] <TB3> INFO: Found DTB DTB_WZ4I6J
[13:09:57.649] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[13:09:57.652] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[13:09:57.655] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[13:09:59.184] <TB3> INFO: DUT info:
[13:09:59.184] <TB3> INFO: The DUT currently contains the following objects:
[13:09:59.184] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[13:09:59.184] <TB3> INFO: TBM Core alpha (0): 7 registers set
[13:09:59.185] <TB3> INFO: TBM Core beta (1): 7 registers set
[13:09:59.185] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:09:59.185] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.185] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:59.586] <TB3> INFO: enter 'restricted' command line mode
[13:09:59.586] <TB3> INFO: enter test to run
[13:09:59.587] <TB3> INFO: test: pretest no parameter change
[13:09:59.587] <TB3> INFO: running: pretest
[13:09:59.595] <TB3> INFO: ######################################################################
[13:09:59.595] <TB3> INFO: PixTestPretest::doTest()
[13:09:59.595] <TB3> INFO: ######################################################################
[13:09:59.597] <TB3> INFO: ----------------------------------------------------------------------
[13:09:59.597] <TB3> INFO: PixTestPretest::programROC()
[13:09:59.597] <TB3> INFO: ----------------------------------------------------------------------
[13:10:17.615] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[13:10:17.615] <TB3> INFO: IA differences per ROC: 16.9 20.9 20.1 17.7 19.3 18.5 19.3 20.9 17.7 20.1 18.5 19.3 18.5 16.9 19.3 18.5
[13:10:17.688] <TB3> INFO: ----------------------------------------------------------------------
[13:10:17.688] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[13:10:17.688] <TB3> INFO: ----------------------------------------------------------------------
[13:10:37.263] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 389.1 mA = 24.3188 mA/ROC
[13:10:37.266] <TB3> INFO: ----------------------------------------------------------------------
[13:10:37.266] <TB3> INFO: PixTestPretest::findWorkingPixel()
[13:10:37.266] <TB3> INFO: ----------------------------------------------------------------------
[13:10:45.519] <TB3> INFO: Test took 8248ms.
[13:10:45.799] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[13:10:45.840] <TB3> INFO: ----------------------------------------------------------------------
[13:10:45.840] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[13:10:45.840] <TB3> INFO: ----------------------------------------------------------------------
[13:10:54.116] <TB3> INFO: Test took 8271ms.
[13:10:54.430] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[13:10:54.430] <TB3> INFO: CalDel: 129 124 136 135 121 138 140 147 163 126 139 127 152 143 169 140
[13:10:54.430] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[13:10:54.434] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C0.dat
[13:10:54.434] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C1.dat
[13:10:54.434] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C2.dat
[13:10:54.435] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C3.dat
[13:10:54.435] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C4.dat
[13:10:54.435] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C5.dat
[13:10:54.435] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C6.dat
[13:10:54.435] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C7.dat
[13:10:54.436] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C8.dat
[13:10:54.436] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C9.dat
[13:10:54.436] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C10.dat
[13:10:54.436] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C11.dat
[13:10:54.437] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C12.dat
[13:10:54.437] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C13.dat
[13:10:54.437] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C14.dat
[13:10:54.437] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters_C15.dat
[13:10:54.438] <TB3> INFO: PixTestPretest::doTest() done, duration: 54 seconds
[13:10:54.530] <TB3> INFO: enter test to run
[13:10:54.530] <TB3> INFO: test: fulltest no parameter change
[13:10:54.530] <TB3> INFO: running: fulltest
[13:10:54.530] <TB3> INFO: ######################################################################
[13:10:54.530] <TB3> INFO: PixTestFullTest::doTest()
[13:10:54.530] <TB3> INFO: ######################################################################
[13:10:54.531] <TB3> INFO: ######################################################################
[13:10:54.531] <TB3> INFO: PixTestAlive::doTest()
[13:10:54.531] <TB3> INFO: ######################################################################
[13:10:54.533] <TB3> INFO: ----------------------------------------------------------------------
[13:10:54.533] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:10:54.533] <TB3> INFO: ----------------------------------------------------------------------
[13:10:57.979] <TB3> INFO: Test took 3445ms.
[13:10:57.999] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:10:58.228] <TB3> INFO: PixTestAlive::aliveTest() done
[13:10:58.228] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 2 0 0
[13:10:58.230] <TB3> INFO: ----------------------------------------------------------------------
[13:10:58.230] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:10:58.230] <TB3> INFO: ----------------------------------------------------------------------
[13:11:00.957] <TB3> INFO: Test took 2725ms.
[13:11:00.961] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:00.961] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:11:01.185] <TB3> INFO: PixTestAlive::maskTest() done
[13:11:01.185] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:11:01.187] <TB3> INFO: ----------------------------------------------------------------------
[13:11:01.187] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:11:01.187] <TB3> INFO: ----------------------------------------------------------------------
[13:11:04.661] <TB3> INFO: Test took 3472ms.
[13:11:04.687] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:04.917] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[13:11:04.917] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:11:04.917] <TB3> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[13:11:04.928] <TB3> INFO: ######################################################################
[13:11:04.928] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[13:11:04.928] <TB3> INFO: ######################################################################
[13:11:04.932] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30) hits flags = 2 (plus default)
[13:11:04.943] <TB3> INFO: dacScan step from 0 .. 29
[13:11:26.265] <TB3> INFO: Test took 21321ms.
[13:11:26.294] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:26.294] <TB3> INFO: dacScan step from 30 .. 59
[13:11:49.419] <TB3> INFO: Test took 23125ms.
[13:11:49.521] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:49.533] <TB3> INFO: dacScan step from 60 .. 89
[13:12:18.045] <TB3> INFO: Test took 28512ms.
[13:12:18.301] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:12:18.366] <TB3> INFO: dacScan step from 90 .. 119
[13:12:47.251] <TB3> INFO: Test took 28885ms.
[13:12:47.573] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:12:47.658] <TB3> INFO: dacScan step from 120 .. 149
[13:13:12.490] <TB3> INFO: Test took 24832ms.
[13:13:12.776] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:35.152] <TB3> INFO: PixTestBBMap::doTest() done, duration: 150 seconds
[13:13:35.152] <TB3> INFO: number of dead bumps (per ROC): 0 1 0 0 0 0 2 0 0 1 0 0 0 4 2 1
[13:13:35.152] <TB3> INFO: separation cut (per ROC): 103 101 89 71 91 94 85 96 77 104 105 100 71 85 86 76
[13:13:35.223] <TB3> INFO: ######################################################################
[13:13:35.223] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50
[13:13:35.223] <TB3> INFO: ######################################################################
[13:13:35.223] <TB3> INFO: ----------------------------------------------------------------------
[13:13:35.223] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50
[13:13:35.223] <TB3> INFO: ----------------------------------------------------------------------
[13:13:35.223] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (4) hits flags = 16 (plus default)
[13:13:35.231] <TB3> INFO: dacScan step from 0 .. 3
[13:13:54.039] <TB3> INFO: Test took 18808ms.
[13:13:54.067] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:54.067] <TB3> INFO: dacScan step from 4 .. 7
[13:14:12.859] <TB3> INFO: Test took 18792ms.
[13:14:12.887] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:12.887] <TB3> INFO: dacScan step from 8 .. 11
[13:14:31.724] <TB3> INFO: Test took 18837ms.
[13:14:31.750] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:31.750] <TB3> INFO: dacScan step from 12 .. 15
[13:14:50.554] <TB3> INFO: Test took 18804ms.
[13:14:50.579] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:50.579] <TB3> INFO: dacScan step from 16 .. 19
[13:15:09.521] <TB3> INFO: Test took 18942ms.
[13:15:09.548] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:09.548] <TB3> INFO: dacScan step from 20 .. 23
[13:15:28.488] <TB3> INFO: Test took 18939ms.
[13:15:28.518] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:28.518] <TB3> INFO: dacScan step from 24 .. 27
[13:15:47.306] <TB3> INFO: Test took 18788ms.
[13:15:47.332] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:47.332] <TB3> INFO: dacScan step from 28 .. 31
[13:16:06.255] <TB3> INFO: Test took 18923ms.
[13:16:06.280] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:06.280] <TB3> INFO: dacScan step from 32 .. 35
[13:16:25.223] <TB3> INFO: Test took 18943ms.
[13:16:25.252] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:25.252] <TB3> INFO: dacScan step from 36 .. 39
[13:16:44.105] <TB3> INFO: Test took 18853ms.
[13:16:44.131] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:44.131] <TB3> INFO: dacScan step from 40 .. 43
[13:17:03.036] <TB3> INFO: Test took 18905ms.
[13:17:03.063] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:03.063] <TB3> INFO: dacScan step from 44 .. 47
[13:17:22.074] <TB3> INFO: Test took 19011ms.
[13:17:22.100] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:22.100] <TB3> INFO: dacScan step from 48 .. 51
[13:17:40.968] <TB3> INFO: Test took 18868ms.
[13:17:40.998] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:40.998] <TB3> INFO: dacScan step from 52 .. 55
[13:17:59.878] <TB3> INFO: Test took 18880ms.
[13:17:59.910] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:59.910] <TB3> INFO: dacScan step from 56 .. 59
[13:18:17.586] <TB3> INFO: Test took 17676ms.
[13:18:17.616] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:17.616] <TB3> INFO: dacScan step from 60 .. 63
[13:18:35.360] <TB3> INFO: Test took 17744ms.
[13:18:35.393] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:35.393] <TB3> INFO: dacScan step from 64 .. 67
[13:18:53.383] <TB3> INFO: Test took 17990ms.
[13:18:53.423] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:53.423] <TB3> INFO: dacScan step from 68 .. 71
[13:19:11.809] <TB3> INFO: Test took 18386ms.
[13:19:11.861] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:11.862] <TB3> INFO: dacScan step from 72 .. 75
[13:19:30.460] <TB3> INFO: Test took 18598ms.
[13:19:30.529] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:30.531] <TB3> INFO: dacScan step from 76 .. 79
[13:19:51.592] <TB3> INFO: Test took 21061ms.
[13:19:51.693] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:51.697] <TB3> INFO: dacScan step from 80 .. 83
[13:20:14.777] <TB3> INFO: Test took 23080ms.
[13:20:14.914] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:14.920] <TB3> INFO: dacScan step from 84 .. 87
[13:20:40.141] <TB3> INFO: Test took 25220ms.
[13:20:40.314] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:40.327] <TB3> INFO: dacScan step from 88 .. 91
[13:21:06.986] <TB3> INFO: Test took 26659ms.
[13:21:07.168] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:07.180] <TB3> INFO: dacScan step from 92 .. 95
[13:21:35.081] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[13:21:35.081] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[13:21:35.136] <TB3> INFO: Test took 27956ms.
[13:21:35.356] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:35.367] <TB3> INFO: dacScan step from 96 .. 99
[13:22:03.347] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[13:22:03.347] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (50) != TBM ID (51)
[13:22:03.347] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[13:22:03.347] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[13:22:03.347] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[13:22:04.055] <TB3> INFO: Test took 28688ms.
[13:22:04.293] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:04.302] <TB3> INFO: dacScan step from 100 .. 103
[13:22:32.184] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[13:22:32.184] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[13:22:33.226] <TB3> INFO: Test took 28924ms.
[13:22:33.462] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:33.472] <TB3> INFO: dacScan step from 104 .. 107
[13:23:01.466] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[13:23:01.466] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[13:23:02.658] <TB3> INFO: Test took 29186ms.
[13:23:02.896] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:02.905] <TB3> INFO: dacScan step from 108 .. 111
[13:23:30.652] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[13:23:30.652] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (68) != TBM ID (69)
[13:23:30.653] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[13:23:30.653] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[13:23:30.653] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[13:23:31.882] <TB3> INFO: Test took 28977ms.
[13:23:32.134] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:32.144] <TB3> INFO: dacScan step from 112 .. 115
[13:23:59.921] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[13:24:01.187] <TB3> INFO: Test took 29043ms.
[13:24:01.411] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:01.421] <TB3> INFO: dacScan step from 116 .. 119
[13:24:29.325] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[13:24:30.582] <TB3> INFO: Test took 29161ms.
[13:24:30.826] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:30.837] <TB3> INFO: dacScan step from 120 .. 123
[13:24:58.652] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[13:24:58.653] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (195) != TBM ID (196)
[13:24:58.653] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[13:24:58.653] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[13:24:58.653] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[13:24:59.933] <TB3> INFO: Test took 29095ms.
[13:25:00.165] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:00.176] <TB3> INFO: dacScan step from 124 .. 127
[13:25:29.244] <TB3> INFO: Test took 29068ms.
[13:25:29.458] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:29.469] <TB3> INFO: dacScan step from 128 .. 131
[13:25:58.445] <TB3> INFO: Test took 28976ms.
[13:25:58.668] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:58.679] <TB3> INFO: dacScan step from 132 .. 135
[13:26:27.822] <TB3> INFO: Test took 29143ms.
[13:26:28.041] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:28.051] <TB3> INFO: dacScan step from 136 .. 139
[13:26:57.125] <TB3> INFO: Test took 29074ms.
[13:26:57.349] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:57.360] <TB3> INFO: dacScan step from 140 .. 143
[13:27:26.424] <TB3> INFO: Test took 29064ms.
[13:27:26.643] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:26.653] <TB3> INFO: dacScan step from 144 .. 147
[13:27:55.628] <TB3> INFO: Test took 28975ms.
[13:27:55.841] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:55.851] <TB3> INFO: dacScan step from 148 .. 149
[13:28:11.593] <TB3> INFO: Test took 15742ms.
[13:28:11.700] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:28:11.706] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:13.168] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:14.543] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:16.017] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:17.554] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:19.055] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:20.486] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:21.937] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:23.400] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:24.918] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:26.377] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:27.775] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:29.127] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:30.519] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:31.888] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:33.243] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:28:34.653] <TB3> INFO: PixTestScurves::scurves() done
[13:28:34.653] <TB3> INFO: Vcal mean: 102.18 89.15 82.51 67.54 81.12 93.52 82.94 85.65 78.77 92.91 103.20 90.96 79.71 82.90 90.18 84.47
[13:28:34.653] <TB3> INFO: Vcal RMS: 5.84 5.18 4.45 4.86 4.19 4.68 4.23 5.03 3.99 5.92 5.90 5.82 4.39 5.48 6.64 4.82
[13:28:34.653] <TB3> INFO: PixTestScurves::fullTest() done, duration: 899 seconds
[13:28:34.730] <TB3> INFO: ######################################################################
[13:28:34.730] <TB3> INFO: PixTestTrim::doTest()
[13:28:34.730] <TB3> INFO: ######################################################################
[13:28:34.731] <TB3> INFO: ----------------------------------------------------------------------
[13:28:34.731] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[13:28:34.731] <TB3> INFO: ----------------------------------------------------------------------
[13:28:34.815] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[13:28:34.815] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[13:28:34.823] <TB3> INFO: dacScan step from 0 .. 19
[13:28:49.926] <TB3> INFO: Test took 15103ms.
[13:28:49.952] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:28:49.952] <TB3> INFO: dacScan step from 20 .. 39
[13:29:05.001] <TB3> INFO: Test took 15049ms.
[13:29:05.022] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:05.022] <TB3> INFO: dacScan step from 40 .. 59
[13:29:19.522] <TB3> INFO: Test took 14500ms.
[13:29:19.542] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:19.542] <TB3> INFO: dacScan step from 60 .. 79
[13:29:33.974] <TB3> INFO: Test took 14432ms.
[13:29:33.997] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:33.997] <TB3> INFO: dacScan step from 80 .. 99
[13:29:49.597] <TB3> INFO: Test took 15600ms.
[13:29:49.668] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:49.677] <TB3> INFO: dacScan step from 100 .. 119
[13:30:08.732] <TB3> INFO: Test took 19055ms.
[13:30:08.884] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:08.910] <TB3> INFO: dacScan step from 120 .. 139
[13:30:27.896] <TB3> INFO: Test took 18986ms.
[13:30:28.092] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:28.125] <TB3> INFO: dacScan step from 140 .. 159
[13:30:44.674] <TB3> INFO: Test took 16548ms.
[13:30:44.750] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:31:05.709] <TB3> INFO: ROC 0 VthrComp = 103
[13:31:05.709] <TB3> INFO: ROC 1 VthrComp = 98
[13:31:05.709] <TB3> INFO: ROC 2 VthrComp = 90
[13:31:05.709] <TB3> INFO: ROC 3 VthrComp = 75
[13:31:05.709] <TB3> INFO: ROC 4 VthrComp = 88
[13:31:05.709] <TB3> INFO: ROC 5 VthrComp = 100
[13:31:05.709] <TB3> INFO: ROC 6 VthrComp = 90
[13:31:05.710] <TB3> INFO: ROC 7 VthrComp = 92
[13:31:05.710] <TB3> INFO: ROC 8 VthrComp = 84
[13:31:05.710] <TB3> INFO: ROC 9 VthrComp = 99
[13:31:05.710] <TB3> INFO: ROC 10 VthrComp = 105
[13:31:05.710] <TB3> INFO: ROC 11 VthrComp = 95
[13:31:05.710] <TB3> INFO: ROC 12 VthrComp = 83
[13:31:05.710] <TB3> INFO: ROC 13 VthrComp = 86
[13:31:05.710] <TB3> INFO: ROC 14 VthrComp = 91
[13:31:05.710] <TB3> INFO: ROC 15 VthrComp = 87
[13:31:05.710] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:31:05.710] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[13:31:05.718] <TB3> INFO: dacScan step from 0 .. 19
[13:31:20.765] <TB3> INFO: Test took 15047ms.
[13:31:20.790] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:31:20.790] <TB3> INFO: dacScan step from 20 .. 39
[13:31:35.962] <TB3> INFO: Test took 15172ms.
[13:31:35.996] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:31:35.999] <TB3> INFO: dacScan step from 40 .. 59
[13:31:54.248] <TB3> INFO: Test took 18249ms.
[13:31:54.395] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:31:54.430] <TB3> INFO: dacScan step from 60 .. 79
[13:32:13.234] <TB3> INFO: Test took 18804ms.
[13:32:13.398] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:13.441] <TB3> INFO: dacScan step from 80 .. 99
[13:32:33.518] <TB3> INFO: Test took 20077ms.
[13:32:33.676] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:33.725] <TB3> INFO: dacScan step from 100 .. 119
[13:32:53.755] <TB3> INFO: Test took 20030ms.
[13:32:53.923] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:53.969] <TB3> INFO: dacScan step from 120 .. 139
[13:33:14.033] <TB3> INFO: Test took 20064ms.
[13:33:14.190] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:14.237] <TB3> INFO: dacScan step from 140 .. 159
[13:33:34.399] <TB3> INFO: Test took 20162ms.
[13:33:34.565] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:58.856] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 61.3002 for pixel 13/18 mean/min/max = 46.5891/31.7593/61.419
[13:33:58.857] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 56.8052 for pixel 6/32 mean/min/max = 43.9543/30.8856/57.0229
[13:33:58.857] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 56.5833 for pixel 51/0 mean/min/max = 44.4992/32.3293/56.6691
[13:33:58.857] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 56.9281 for pixel 0/72 mean/min/max = 45.7873/34.4808/57.0938
[13:33:58.858] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 56.8589 for pixel 6/23 mean/min/max = 45.1329/33.3338/56.932
[13:33:58.858] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 56.3414 for pixel 48/1 mean/min/max = 44.1001/31.8493/56.3509
[13:33:58.858] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 56.2478 for pixel 14/15 mean/min/max = 44.5641/32.8538/56.2744
[13:33:58.859] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 57.8123 for pixel 0/52 mean/min/max = 45.2031/32.5683/57.838
[13:33:58.859] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 56.711 for pixel 2/57 mean/min/max = 44.4986/32.2844/56.7127
[13:33:58.859] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 59.7001 for pixel 0/44 mean/min/max = 45.791/31.8124/59.7695
[13:33:58.859] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 61.3548 for pixel 4/79 mean/min/max = 47.1878/32.9514/61.4241
[13:33:58.860] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 60.1408 for pixel 0/4 mean/min/max = 45.9021/31.3084/60.4958
[13:33:58.860] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 57.58 for pixel 0/13 mean/min/max = 44.913/32.1892/57.6368
[13:33:58.860] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 58.9511 for pixel 0/71 mean/min/max = 45.3414/31.5303/59.1525
[13:33:58.861] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 61.3675 for pixel 16/5 mean/min/max = 46.5577/31.7148/61.4006
[13:33:58.861] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 58.7116 for pixel 10/79 mean/min/max = 45.7444/32.6701/58.8187
[13:33:58.861] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:35:31.353] <TB3> INFO: Test took 92492ms.
[13:35:32.815] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[13:35:32.823] <TB3> INFO: dacScan step from 0 .. 19
[13:35:55.585] <TB3> INFO: Test took 22762ms.
[13:35:55.632] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:55.633] <TB3> INFO: dacScan step from 20 .. 39
[13:36:23.961] <TB3> INFO: Test took 28328ms.
[13:36:24.178] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:24.205] <TB3> INFO: dacScan step from 40 .. 59
[13:36:55.536] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[13:36:55.536] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 31 readouts!
[13:36:56.711] <TB3> INFO: Test took 32505ms.
[13:36:57.034] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:57.079] <TB3> INFO: dacScan step from 60 .. 79
[13:37:28.671] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[13:37:28.671] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (210) != TBM ID (211)
[13:37:28.671] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[13:37:28.671] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[13:37:28.671] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[13:37:29.940] <TB3> INFO: Test took 32861ms.
[13:37:30.248] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:30.294] <TB3> INFO: dacScan step from 80 .. 99
[13:38:01.840] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[13:38:01.841] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[13:38:03.094] <TB3> INFO: Test took 32800ms.
[13:38:03.359] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:03.399] <TB3> INFO: dacScan step from 100 .. 119
[13:38:34.981] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[13:38:36.243] <TB3> INFO: Test took 32844ms.
[13:38:36.503] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:36.542] <TB3> INFO: dacScan step from 120 .. 139
[13:39:08.139] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[13:39:08.139] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[13:39:09.414] <TB3> INFO: Test took 32872ms.
[13:39:09.690] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:09.736] <TB3> INFO: dacScan step from 140 .. 159
[13:39:41.376] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[13:39:42.649] <TB3> INFO: Test took 32913ms.
[13:39:42.912] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:42.958] <TB3> INFO: dacScan step from 160 .. 179
[13:40:14.755] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[13:40:14.756] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[13:40:16.030] <TB3> INFO: Test took 33072ms.
[13:40:16.306] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:16.362] <TB3> INFO: dacScan step from 180 .. 199
[13:40:49.072] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[13:40:49.072] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (221) != TBM ID (222)
[13:40:49.072] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[13:40:49.072] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[13:40:49.072] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[13:40:50.349] <TB3> INFO: Test took 33987ms.
[13:40:50.646] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:15.034] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.177671 .. 255.000000
[13:41:15.130] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20) hits flags = 16 (plus default)
[13:41:15.139] <TB3> INFO: dacScan step from 0 .. 19
[13:41:28.580] <TB3> INFO: Test took 13440ms.
[13:41:28.600] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:28.600] <TB3> INFO: dacScan step from 20 .. 39
[13:41:43.427] <TB3> INFO: Test took 14827ms.
[13:41:43.503] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:43.521] <TB3> INFO: dacScan step from 40 .. 59
[13:42:01.142] <TB3> INFO: Test took 17621ms.
[13:42:01.290] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:01.327] <TB3> INFO: dacScan step from 60 .. 79
[13:42:18.994] <TB3> INFO: Test took 17667ms.
[13:42:19.136] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:19.182] <TB3> INFO: dacScan step from 80 .. 99
[13:42:35.761] <TB3> INFO: Test took 16578ms.
[13:42:35.899] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:35.947] <TB3> INFO: dacScan step from 100 .. 119
[13:42:52.833] <TB3> INFO: Test took 16886ms.
[13:42:53.058] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:53.103] <TB3> INFO: dacScan step from 120 .. 139
[13:43:10.366] <TB3> INFO: Test took 17263ms.
[13:43:10.506] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:10.548] <TB3> INFO: dacScan step from 140 .. 159
[13:43:28.233] <TB3> INFO: Test took 17685ms.
[13:43:28.389] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:28.428] <TB3> INFO: dacScan step from 160 .. 179
[13:43:46.085] <TB3> INFO: Test took 17657ms.
[13:43:46.233] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:46.272] <TB3> INFO: dacScan step from 180 .. 199
[13:44:04.635] <TB3> INFO: Test took 18363ms.
[13:44:04.788] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:04.833] <TB3> INFO: dacScan step from 200 .. 219
[13:44:22.377] <TB3> INFO: Test took 17544ms.
[13:44:22.525] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:22.564] <TB3> INFO: dacScan step from 220 .. 239
[13:44:40.306] <TB3> INFO: Test took 17742ms.
[13:44:40.446] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:40.487] <TB3> INFO: dacScan step from 240 .. 255
[13:44:55.114] <TB3> INFO: Test took 14627ms.
[13:44:55.233] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:28.304] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 14.946893 .. 76.017242
[13:45:28.395] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 4 .. 86 (20) hits flags = 16 (plus default)
[13:45:28.404] <TB3> INFO: dacScan step from 4 .. 23
[13:45:41.935] <TB3> INFO: Test took 13531ms.
[13:45:41.959] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:41.959] <TB3> INFO: dacScan step from 24 .. 43
[13:45:57.473] <TB3> INFO: Test took 15513ms.
[13:45:57.585] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:57.609] <TB3> INFO: dacScan step from 44 .. 63
[13:46:15.111] <TB3> INFO: Test took 17502ms.
[13:46:15.258] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:15.301] <TB3> INFO: dacScan step from 64 .. 83
[13:46:32.859] <TB3> INFO: Test took 17558ms.
[13:46:33.010] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:33.055] <TB3> INFO: dacScan step from 84 .. 86
[13:46:38.147] <TB3> INFO: Test took 5092ms.
[13:46:38.173] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:58.096] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 0.131154 .. 54.867729
[13:46:58.171] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 0 .. 64 (20) hits flags = 16 (plus default)
[13:46:58.178] <TB3> INFO: dacScan step from 0 .. 19
[13:47:11.104] <TB3> INFO: Test took 12925ms.
[13:47:11.122] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:11.122] <TB3> INFO: dacScan step from 20 .. 39
[13:47:25.778] <TB3> INFO: Test took 14656ms.
[13:47:25.850] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:25.869] <TB3> INFO: dacScan step from 40 .. 59
[13:47:43.443] <TB3> INFO: Test took 17574ms.
[13:47:43.590] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:43.637] <TB3> INFO: dacScan step from 60 .. 64
[13:47:50.214] <TB3> INFO: Test took 6577ms.
[13:47:50.253] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:08.303] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 0.378567 .. 54.867729
[13:48:08.378] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 0 .. 64 (20) hits flags = 16 (plus default)
[13:48:08.386] <TB3> INFO: dacScan step from 0 .. 19
[13:48:21.368] <TB3> INFO: Test took 12981ms.
[13:48:21.387] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:21.387] <TB3> INFO: dacScan step from 20 .. 39
[13:48:35.242] <TB3> INFO: Test took 13855ms.
[13:48:35.315] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:35.339] <TB3> INFO: dacScan step from 40 .. 59
[13:48:51.971] <TB3> INFO: Test took 16632ms.
[13:48:52.185] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:52.285] <TB3> INFO: dacScan step from 60 .. 64
[13:48:58.673] <TB3> INFO: Test took 6388ms.
[13:48:58.711] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:16.316] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:49:16.316] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20) hits flags = 16 (plus default)
[13:49:16.326] <TB3> INFO: dacScan step from 15 .. 34
[13:49:38.085] <TB3> INFO: Test took 21759ms.
[13:49:38.175] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:38.192] <TB3> INFO: dacScan step from 35 .. 54
[13:50:08.407] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (193) != TBM ID (0)
[13:50:08.407] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[13:50:08.407] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (1) != TBM ID (194)
[13:50:08.998] <TB3> INFO: Test took 30806ms.
[13:50:09.281] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:09.341] <TB3> INFO: dacScan step from 55 .. 55
[13:50:13.549] <TB3> INFO: Test took 4208ms.
[13:50:13.570] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:27.976] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:50:27.976] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:50:27.976] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:50:27.976] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:50:27.976] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:50:27.976] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:50:27.976] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:50:27.976] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:50:27.977] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:50:27.977] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:50:27.977] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:50:27.977] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:50:27.977] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:50:27.977] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:50:27.977] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:50:27.978] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:50:27.978] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C0.dat
[13:50:27.988] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C1.dat
[13:50:27.994] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C2.dat
[13:50:28.000] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C3.dat
[13:50:28.007] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C4.dat
[13:50:28.013] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C5.dat
[13:50:28.019] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C6.dat
[13:50:28.025] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C7.dat
[13:50:28.032] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C8.dat
[13:50:28.038] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C9.dat
[13:50:28.044] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C10.dat
[13:50:28.051] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C11.dat
[13:50:28.059] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C12.dat
[13:50:28.066] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C13.dat
[13:50:28.072] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C14.dat
[13:50:28.079] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//trimParameters35_C15.dat
[13:50:28.085] <TB3> INFO: PixTestTrim::trimTest() done
[13:50:28.085] <TB3> INFO: vtrim: 123 109 100 98 111 102 103 120 116 109 106 104 118 100 117 112
[13:50:28.085] <TB3> INFO: vthrcomp: 103 98 90 75 88 100 90 92 84 99 105 95 83 86 91 87
[13:50:28.085] <TB3> INFO: vcal mean: 35.03 35.04 35.04 35.05 35.08 35.05 35.07 35.03 35.01 35.01 35.07 35.05 35.02 35.00 35.01 35.11
[13:50:28.085] <TB3> INFO: vcal RMS: 1.07 0.96 0.97 0.95 0.96 1.03 0.97 0.95 1.01 0.98 1.01 1.25 1.06 1.21 1.23 1.07
[13:50:28.085] <TB3> INFO: bits mean: 9.75 10.36 9.66 9.16 9.81 10.22 9.96 10.00 10.47 9.36 8.60 9.49 10.22 9.68 10.00 9.72
[13:50:28.085] <TB3> INFO: bits RMS: 2.49 2.48 2.61 2.45 2.38 2.44 2.41 2.40 2.28 2.75 2.86 2.75 2.37 2.70 2.43 2.51
[13:50:28.093] <TB3> INFO: ----------------------------------------------------------------------
[13:50:28.093] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[13:50:28.093] <TB3> INFO: ----------------------------------------------------------------------
[13:50:28.096] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[13:50:28.106] <TB3> INFO: dacScan step from 0 .. 19
[13:50:50.233] <TB3> INFO: Test took 22127ms.
[13:50:50.266] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:50.266] <TB3> INFO: dacScan step from 20 .. 39
[13:51:12.866] <TB3> INFO: Test took 22600ms.
[13:51:12.899] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:12.899] <TB3> INFO: dacScan step from 40 .. 59
[13:51:35.255] <TB3> INFO: Test took 22355ms.
[13:51:35.288] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:35.288] <TB3> INFO: dacScan step from 60 .. 79
[13:51:57.757] <TB3> INFO: Test took 22469ms.
[13:51:57.796] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:57.796] <TB3> INFO: dacScan step from 80 .. 99
[13:52:21.131] <TB3> INFO: Test took 23334ms.
[13:52:21.211] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:21.214] <TB3> INFO: dacScan step from 100 .. 119
[13:52:49.361] <TB3> INFO: Test took 28147ms.
[13:52:49.568] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:49.593] <TB3> INFO: dacScan step from 120 .. 139
[13:53:21.067] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (51) != TBM ID (0)
[13:53:21.068] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[13:53:21.068] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (1) != TBM ID (52)
[13:53:22.012] <TB3> INFO: Test took 32419ms.
[13:53:22.300] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:22.340] <TB3> INFO: dacScan step from 140 .. 159
[13:53:54.154] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[13:53:54.154] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[13:53:55.452] <TB3> INFO: Test took 33112ms.
[13:53:55.740] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:55.785] <TB3> INFO: dacScan step from 160 .. 179
[13:54:28.666] <TB3> INFO: Test took 32881ms.
[13:54:28.974] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:29.020] <TB3> INFO: dacScan step from 180 .. 199
[13:55:02.814] <TB3> INFO: Test took 33794ms.
[13:55:03.084] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:29.414] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 177 (20) hits flags = 16 (plus default)
[13:55:29.422] <TB3> INFO: dacScan step from 0 .. 19
[13:55:51.981] <TB3> INFO: Test took 22559ms.
[13:55:52.016] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:52.016] <TB3> INFO: dacScan step from 20 .. 39
[13:56:13.159] <TB3> INFO: Test took 21143ms.
[13:56:13.192] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:13.192] <TB3> INFO: dacScan step from 40 .. 59
[13:56:34.358] <TB3> INFO: Test took 21166ms.
[13:56:34.391] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:34.391] <TB3> INFO: dacScan step from 60 .. 79
[13:56:56.596] <TB3> INFO: Test took 22205ms.
[13:56:56.640] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:56.640] <TB3> INFO: dacScan step from 80 .. 99
[13:57:21.196] <TB3> INFO: Test took 24556ms.
[13:57:21.315] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:21.327] <TB3> INFO: dacScan step from 100 .. 119
[13:57:52.577] <TB3> INFO: Test took 31250ms.
[13:57:52.838] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:52.870] <TB3> INFO: dacScan step from 120 .. 139
[13:58:24.698] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[13:58:24.698] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[13:58:25.944] <TB3> INFO: Test took 33074ms.
[13:58:26.210] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:26.253] <TB3> INFO: dacScan step from 140 .. 159
[13:58:58.975] <TB3> INFO: Test took 32722ms.
[13:58:59.331] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:59.378] <TB3> INFO: dacScan step from 160 .. 177
[13:59:29.508] <TB3> INFO: Test took 30130ms.
[13:59:29.763] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:53.841] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 162 (20) hits flags = 16 (plus default)
[13:59:53.850] <TB3> INFO: dacScan step from 0 .. 19
[14:00:16.470] <TB3> INFO: Test took 22620ms.
[14:00:16.506] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:16.506] <TB3> INFO: dacScan step from 20 .. 39
[14:00:38.514] <TB3> INFO: Test took 22008ms.
[14:00:38.548] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:38.548] <TB3> INFO: dacScan step from 40 .. 59
[14:00:59.966] <TB3> INFO: Test took 21418ms.
[14:00:59.999] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:59.999] <TB3> INFO: dacScan step from 60 .. 79
[14:01:22.650] <TB3> INFO: Test took 22651ms.
[14:01:22.692] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:22.692] <TB3> INFO: dacScan step from 80 .. 99
[14:01:47.453] <TB3> INFO: Test took 24761ms.
[14:01:47.573] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:47.585] <TB3> INFO: dacScan step from 100 .. 119
[14:02:18.800] <TB3> INFO: Test took 31215ms.
[14:02:19.065] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:19.103] <TB3> INFO: dacScan step from 120 .. 139
[14:02:50.857] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[14:02:50.857] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[14:02:52.129] <TB3> INFO: Test took 33026ms.
[14:02:52.406] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:52.450] <TB3> INFO: dacScan step from 140 .. 159
[14:03:24.150] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[14:03:24.150] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (67) != TBM ID (68)
[14:03:24.150] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[14:03:24.150] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[14:03:24.150] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[14:03:25.402] <TB3> INFO: Test took 32952ms.
[14:03:25.687] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:25.733] <TB3> INFO: dacScan step from 160 .. 162
[14:03:33.056] <TB3> INFO: Test took 7323ms.
[14:03:33.103] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:56.755] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 163 (20) hits flags = 16 (plus default)
[14:03:56.763] <TB3> INFO: dacScan step from 0 .. 19
[14:04:19.262] <TB3> INFO: Test took 22499ms.
[14:04:19.296] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:19.296] <TB3> INFO: dacScan step from 20 .. 39
[14:04:40.548] <TB3> INFO: Test took 21252ms.
[14:04:40.592] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:40.593] <TB3> INFO: dacScan step from 40 .. 59
[14:05:01.681] <TB3> INFO: Test took 21088ms.
[14:05:01.714] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:01.714] <TB3> INFO: dacScan step from 60 .. 79
[14:05:24.523] <TB3> INFO: Test took 22809ms.
[14:05:24.562] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:24.562] <TB3> INFO: dacScan step from 80 .. 99
[14:05:49.428] <TB3> INFO: Test took 24866ms.
[14:05:49.550] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:49.563] <TB3> INFO: dacScan step from 100 .. 119
[14:06:20.870] <TB3> INFO: Test took 31307ms.
[14:06:21.134] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:21.168] <TB3> INFO: dacScan step from 120 .. 139
[14:06:52.959] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[14:06:52.959] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (179) != TBM ID (180)
[14:06:52.964] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[14:06:52.965] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[14:06:52.965] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[14:06:54.171] <TB3> INFO: Test took 33003ms.
[14:06:54.483] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:54.531] <TB3> INFO: dacScan step from 140 .. 159
[14:07:26.299] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[14:07:26.299] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (195) != TBM ID (196)
[14:07:26.299] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[14:07:26.299] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[14:07:26.299] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[14:07:27.556] <TB3> INFO: Test took 33025ms.
[14:07:27.821] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:07:27.861] <TB3> INFO: dacScan step from 160 .. 163
[14:07:36.718] <TB3> INFO: Test took 8857ms.
[14:07:36.775] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:07:58.855] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 163 (20) hits flags = 16 (plus default)
[14:07:58.863] <TB3> INFO: dacScan step from 0 .. 19
[14:08:20.666] <TB3> INFO: Test took 21803ms.
[14:08:20.700] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:20.700] <TB3> INFO: dacScan step from 20 .. 39
[14:08:42.653] <TB3> INFO: Test took 21953ms.
[14:08:42.687] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:42.687] <TB3> INFO: dacScan step from 40 .. 59
[14:09:05.031] <TB3> INFO: Test took 22344ms.
[14:09:05.065] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:05.065] <TB3> INFO: dacScan step from 60 .. 79
[14:09:26.340] <TB3> INFO: Test took 21275ms.
[14:09:26.388] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:26.388] <TB3> INFO: dacScan step from 80 .. 99
[14:09:51.299] <TB3> INFO: Test took 24911ms.
[14:09:51.424] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:51.438] <TB3> INFO: dacScan step from 100 .. 119
[14:10:22.764] <TB3> INFO: Test took 31326ms.
[14:10:23.021] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:23.061] <TB3> INFO: dacScan step from 120 .. 139
[14:10:54.657] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[14:10:54.657] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (143) != TBM ID (144)
[14:10:54.657] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 31 readouts!
[14:10:54.657] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 31 readouts!
[14:10:54.657] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 31 readouts!
[14:10:55.911] <TB3> INFO: Test took 32850ms.
[14:10:56.212] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:56.257] <TB3> INFO: dacScan step from 140 .. 159
[14:11:28.904] <TB3> INFO: Test took 32647ms.
[14:11:29.193] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:29.235] <TB3> INFO: dacScan step from 160 .. 163
[14:11:38.033] <TB3> INFO: Test took 8798ms.
[14:11:38.092] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:01.477] <TB3> INFO: PixTestTrim::trimBitTest() done
[14:12:01.478] <TB3> INFO: PixTestTrim::doTest() done, duration: 2606 seconds
[14:12:02.220] <TB3> INFO: ######################################################################
[14:12:02.220] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[14:12:02.220] <TB3> INFO: ######################################################################
[14:12:05.492] <TB3> INFO: Test took 3270ms.
[14:12:05.510] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:08.966] <TB3> INFO: Test took 3259ms.
[14:12:09.027] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:12.326] <TB3> INFO: Test took 3289ms.
[14:12:12.393] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:12.401] <TB3> INFO: The DUT currently contains the following objects:
[14:12:12.401] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:12.401] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:12.401] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:12.401] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:12.401] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:12.401] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.503] <TB3> INFO: Test took 1102ms.
[14:12:13.504] <TB3> INFO: The DUT currently contains the following objects:
[14:12:13.504] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:13.504] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:13.504] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:13.504] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:13.504] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:13.504] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.605] <TB3> INFO: Test took 1101ms.
[14:12:14.606] <TB3> INFO: The DUT currently contains the following objects:
[14:12:14.606] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:14.606] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:14.606] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:14.606] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:14.606] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:14.606] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.707] <TB3> INFO: Test took 1101ms.
[14:12:15.708] <TB3> INFO: The DUT currently contains the following objects:
[14:12:15.708] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:15.708] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:15.708] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:15.708] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:15.708] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:15.708] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.809] <TB3> INFO: Test took 1101ms.
[14:12:16.810] <TB3> INFO: The DUT currently contains the following objects:
[14:12:16.821] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:16.821] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:16.821] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:16.821] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:16.821] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:16.821] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.911] <TB3> INFO: Test took 1090ms.
[14:12:17.912] <TB3> INFO: The DUT currently contains the following objects:
[14:12:17.912] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:17.912] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:17.912] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:17.912] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:17.912] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:17.912] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.013] <TB3> INFO: Test took 1101ms.
[14:12:19.014] <TB3> INFO: The DUT currently contains the following objects:
[14:12:19.014] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:19.014] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:19.014] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:19.014] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:19.014] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:19.014] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.115] <TB3> INFO: Test took 1101ms.
[14:12:20.116] <TB3> INFO: The DUT currently contains the following objects:
[14:12:20.116] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:20.116] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:20.116] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:20.116] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:20.116] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:20.116] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: Test took 1101ms.
[14:12:21.217] <TB3> INFO: The DUT currently contains the following objects:
[14:12:21.217] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:21.217] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:21.217] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:21.217] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:21.217] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:21.217] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.319] <TB3> INFO: Test took 1102ms.
[14:12:22.320] <TB3> INFO: The DUT currently contains the following objects:
[14:12:22.320] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:22.320] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:22.320] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:22.320] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:22.320] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:22.320] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.427] <TB3> INFO: Test took 1107ms.
[14:12:23.428] <TB3> INFO: The DUT currently contains the following objects:
[14:12:23.428] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:23.428] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:23.428] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:23.428] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:23.429] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:23.429] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.536] <TB3> INFO: Test took 1107ms.
[14:12:24.537] <TB3> INFO: The DUT currently contains the following objects:
[14:12:24.537] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:24.537] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:24.537] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:24.537] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:24.537] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.537] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.537] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.537] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.537] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.537] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.537] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.537] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.538] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.538] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.538] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.538] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.538] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.538] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.538] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:24.538] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.645] <TB3> INFO: Test took 1107ms.
[14:12:25.646] <TB3> INFO: The DUT currently contains the following objects:
[14:12:25.647] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:25.647] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:25.647] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:25.647] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:25.647] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:25.647] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.754] <TB3> INFO: Test took 1107ms.
[14:12:26.755] <TB3> INFO: The DUT currently contains the following objects:
[14:12:26.755] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:26.755] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:26.755] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:26.755] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:26.755] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.755] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.755] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.755] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.755] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.755] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.755] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.755] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.755] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.756] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.756] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.756] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.756] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.756] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.756] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:26.756] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.864] <TB3> INFO: Test took 1108ms.
[14:12:27.865] <TB3> INFO: The DUT currently contains the following objects:
[14:12:27.866] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:27.866] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:27.866] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:27.866] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:27.866] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:27.866] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.974] <TB3> INFO: Test took 1108ms.
[14:12:28.975] <TB3> INFO: The DUT currently contains the following objects:
[14:12:28.975] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:12:28.975] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:12:28.975] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:12:28.975] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:12:28.975] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.975] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.975] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.975] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.976] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.976] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.976] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.976] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.976] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.976] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.976] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.976] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.976] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.976] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.976] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:28.976] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:12:30.083] <TB3> INFO: Test took 1107ms.
[14:12:30.088] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:16:13.147] <TB3> INFO: Test took 223059ms.
[14:16:14.659] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:19:51.690] <TB3> INFO: Test took 217031ms.
[14:19:53.600] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.609] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.618] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.628] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.636] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.645] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.653] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.662] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.671] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[14:19:53.680] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[14:19:53.689] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[14:19:53.698] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.706] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.715] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.722] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.728] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.735] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.742] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.748] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:19:53.789] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C0.dat
[14:19:53.789] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C1.dat
[14:19:53.789] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C2.dat
[14:19:53.789] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C3.dat
[14:19:53.789] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C4.dat
[14:19:53.790] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C5.dat
[14:19:53.790] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C6.dat
[14:19:53.790] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C7.dat
[14:19:53.790] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C8.dat
[14:19:53.790] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C9.dat
[14:19:53.790] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C10.dat
[14:19:53.790] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C11.dat
[14:19:53.805] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C12.dat
[14:19:53.805] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C13.dat
[14:19:53.805] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C14.dat
[14:19:53.805] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//dacParameters35_C15.dat
[14:19:57.250] <TB3> INFO: Test took 3442ms.
[14:20:00.984] <TB3> INFO: Test took 3462ms.
[14:20:04.703] <TB3> INFO: Test took 3439ms.
[14:20:05.009] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:05.929] <TB3> INFO: Test took 920ms.
[14:20:05.933] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:07.039] <TB3> INFO: Test took 1106ms.
[14:20:07.043] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:08.151] <TB3> INFO: Test took 1108ms.
[14:20:08.155] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:09.264] <TB3> INFO: Test took 1109ms.
[14:20:09.267] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:10.376] <TB3> INFO: Test took 1109ms.
[14:20:10.378] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:11.487] <TB3> INFO: Test took 1109ms.
[14:20:11.491] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:12.599] <TB3> INFO: Test took 1108ms.
[14:20:12.602] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:13.710] <TB3> INFO: Test took 1108ms.
[14:20:13.713] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:14.819] <TB3> INFO: Test took 1106ms.
[14:20:14.823] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:15.930] <TB3> INFO: Test took 1107ms.
[14:20:15.934] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:17.041] <TB3> INFO: Test took 1108ms.
[14:20:17.045] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:18.154] <TB3> INFO: Test took 1109ms.
[14:20:18.157] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:19.264] <TB3> INFO: Test took 1107ms.
[14:20:19.267] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:20.375] <TB3> INFO: Test took 1108ms.
[14:20:20.379] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:21.485] <TB3> INFO: Test took 1106ms.
[14:20:21.488] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:22.596] <TB3> INFO: Test took 1108ms.
[14:20:22.600] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:23.706] <TB3> INFO: Test took 1107ms.
[14:20:23.710] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:24.816] <TB3> INFO: Test took 1106ms.
[14:20:24.820] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:25.929] <TB3> INFO: Test took 1109ms.
[14:20:25.933] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:27.041] <TB3> INFO: Test took 1109ms.
[14:20:27.045] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:28.154] <TB3> INFO: Test took 1109ms.
[14:20:28.158] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:29.266] <TB3> INFO: Test took 1108ms.
[14:20:29.269] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:30.376] <TB3> INFO: Test took 1107ms.
[14:20:30.379] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:31.488] <TB3> INFO: Test took 1109ms.
[14:20:31.491] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:32.598] <TB3> INFO: Test took 1107ms.
[14:20:32.601] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:33.708] <TB3> INFO: Test took 1107ms.
[14:20:33.711] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:34.817] <TB3> INFO: Test took 1106ms.
[14:20:34.820] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:35.926] <TB3> INFO: Test took 1107ms.
[14:20:35.930] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:37.034] <TB3> INFO: Test took 1105ms.
[14:20:37.038] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:38.145] <TB3> INFO: Test took 1107ms.
[14:20:38.149] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:39.256] <TB3> INFO: Test took 1107ms.
[14:20:39.260] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:40.367] <TB3> INFO: Test took 1107ms.
[14:20:40.915] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 518 seconds
[14:20:40.915] <TB3> INFO: PH scale (per ROC): 81 91 89 93 77 80 82 80 80 76 79 83 87 85 76 81
[14:20:40.915] <TB3> INFO: PH offset (per ROC): 157 177 143 130 160 151 162 163 149 160 151 160 144 153 170 164
[14:20:41.097] <TB3> INFO: ######################################################################
[14:20:41.097] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[14:20:41.097] <TB3> INFO: ######################################################################
[14:20:41.107] <TB3> INFO: scanning low vcal = 10
[14:20:44.940] <TB3> INFO: Test took 3833ms.
[14:20:44.945] <TB3> INFO: scanning low vcal = 20
[14:20:48.796] <TB3> INFO: Test took 3851ms.
[14:20:48.801] <TB3> INFO: scanning low vcal = 30
[14:20:52.687] <TB3> INFO: Test took 3886ms.
[14:20:52.698] <TB3> INFO: scanning low vcal = 40
[14:20:57.017] <TB3> INFO: Test took 4319ms.
[14:20:57.072] <TB3> INFO: scanning low vcal = 50
[14:21:01.284] <TB3> INFO: Test took 4212ms.
[14:21:01.341] <TB3> INFO: scanning low vcal = 60
[14:21:05.646] <TB3> INFO: Test took 4305ms.
[14:21:05.704] <TB3> INFO: scanning low vcal = 70
[14:21:10.056] <TB3> INFO: Test took 4352ms.
[14:21:10.114] <TB3> INFO: scanning low vcal = 80
[14:21:14.482] <TB3> INFO: Test took 4368ms.
[14:21:14.548] <TB3> INFO: scanning low vcal = 90
[14:21:18.892] <TB3> INFO: Test took 4344ms.
[14:21:18.969] <TB3> INFO: scanning low vcal = 100
[14:21:23.305] <TB3> INFO: Test took 4336ms.
[14:21:23.369] <TB3> INFO: scanning low vcal = 110
[14:21:27.708] <TB3> INFO: Test took 4339ms.
[14:21:27.768] <TB3> INFO: scanning low vcal = 120
[14:21:32.145] <TB3> INFO: Test took 4377ms.
[14:21:32.230] <TB3> INFO: scanning low vcal = 130
[14:21:36.597] <TB3> INFO: Test took 4367ms.
[14:21:36.658] <TB3> INFO: scanning low vcal = 140
[14:21:40.966] <TB3> INFO: Test took 4308ms.
[14:21:41.031] <TB3> INFO: scanning low vcal = 150
[14:21:45.369] <TB3> INFO: Test took 4338ms.
[14:21:45.433] <TB3> INFO: scanning low vcal = 160
[14:21:49.751] <TB3> INFO: Test took 4318ms.
[14:21:49.812] <TB3> INFO: scanning low vcal = 170
[14:21:54.192] <TB3> INFO: Test took 4380ms.
[14:21:54.256] <TB3> INFO: scanning low vcal = 180
[14:21:58.582] <TB3> INFO: Test took 4326ms.
[14:21:58.647] <TB3> INFO: scanning low vcal = 190
[14:22:03.099] <TB3> INFO: Test took 4452ms.
[14:22:03.166] <TB3> INFO: scanning low vcal = 200
[14:22:07.476] <TB3> INFO: Test took 4310ms.
[14:22:07.547] <TB3> INFO: scanning low vcal = 210
[14:22:11.881] <TB3> INFO: Test took 4334ms.
[14:22:11.945] <TB3> INFO: scanning low vcal = 220
[14:22:16.208] <TB3> INFO: Test took 4263ms.
[14:22:16.284] <TB3> INFO: scanning low vcal = 230
[14:22:20.633] <TB3> INFO: Test took 4349ms.
[14:22:20.723] <TB3> INFO: scanning low vcal = 240
[14:22:25.057] <TB3> INFO: Test took 4334ms.
[14:22:25.133] <TB3> INFO: scanning low vcal = 250
[14:22:29.514] <TB3> INFO: Test took 4381ms.
[14:22:29.581] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[14:22:33.945] <TB3> INFO: Test took 4364ms.
[14:22:34.010] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[14:22:38.357] <TB3> INFO: Test took 4347ms.
[14:22:38.443] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[14:22:42.772] <TB3> INFO: Test took 4329ms.
[14:22:42.838] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[14:22:47.195] <TB3> INFO: Test took 4357ms.
[14:22:47.258] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[14:22:51.654] <TB3> INFO: Test took 4396ms.
[14:22:52.321] <TB3> INFO: PixTestGainPedestal::measure() done
[14:23:26.921] <TB3> INFO: PixTestGainPedestal::fit() done
[14:23:26.921] <TB3> INFO: non-linearity mean: 0.947 0.948 0.955 0.954 0.949 0.955 0.957 0.959 0.955 0.952 0.962 0.955 0.955 0.953 0.954 0.951
[14:23:26.921] <TB3> INFO: non-linearity RMS: 0.006 0.006 0.005 0.006 0.007 0.005 0.007 0.004 0.007 0.007 0.006 0.006 0.006 0.006 0.005 0.006
[14:23:26.921] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[14:23:26.940] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[14:23:26.959] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[14:23:26.978] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[14:23:26.997] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[14:23:27.016] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[14:23:27.035] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[14:23:27.054] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[14:23:27.073] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[14:23:27.092] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[14:23:27.111] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[14:23:27.130] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[14:23:27.149] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[14:23:27.168] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[14:23:27.187] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[14:23:27.206] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[14:23:27.225] <TB3> INFO: PixTestGainPedestal::doTest() done, duration: 166 seconds
[14:23:27.231] <TB3> INFO: enter test to run
[14:23:27.231] <TB3> INFO: test: exit no parameter change
[14:23:27.720] <TB3> QUIET: Connection to board 170 closed.
[14:23:27.799] <TB3> INFO: pXar: this is the end, my friend