Test Date: 2015-07-10 12:10
Analysis date: 2016-05-25 21:56
Logfile
LogfileView
[15:47:11.750] <TB1> INFO: *** Welcome to pxar ***
[15:47:11.750] <TB1> INFO: *** Today: 2015/07/10
[15:47:11.750] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C15.dat
[15:47:11.751] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:47:11.751] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//defaultMaskFile.dat
[15:47:11.751] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters_C15.dat
[15:47:11.821] <TB1> INFO: clk: 4
[15:47:11.821] <TB1> INFO: ctr: 4
[15:47:11.821] <TB1> INFO: sda: 19
[15:47:11.821] <TB1> INFO: tin: 9
[15:47:11.821] <TB1> INFO: level: 15
[15:47:11.821] <TB1> INFO: triggerdelay: 0
[15:47:11.821] <TB1> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[15:47:11.821] <TB1> INFO: Log level: INFO
[15:47:11.829] <TB1> INFO: Found DTB DTB_WXBYFL
[15:47:11.841] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[15:47:11.844] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[15:47:11.847] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[15:47:13.418] <TB1> INFO: DUT info:
[15:47:13.418] <TB1> INFO: The DUT currently contains the following objects:
[15:47:13.418] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:47:13.418] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:47:13.418] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:47:13.418] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:47:13.418] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.418] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:47:13.819] <TB1> INFO: enter 'restricted' command line mode
[15:47:13.820] <TB1> INFO: enter test to run
[15:47:13.820] <TB1> INFO: test: pretest no parameter change
[15:47:13.820] <TB1> INFO: running: pretest
[15:47:13.825] <TB1> INFO: ######################################################################
[15:47:13.825] <TB1> INFO: PixTestPretest::doTest()
[15:47:13.825] <TB1> INFO: ######################################################################
[15:47:13.826] <TB1> INFO: ----------------------------------------------------------------------
[15:47:13.826] <TB1> INFO: PixTestPretest::programROC()
[15:47:13.826] <TB1> INFO: ----------------------------------------------------------------------
[15:47:31.848] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:47:31.848] <TB1> INFO: IA differences per ROC: 17.7 17.7 17.7 19.3 15.3 16.1 16.1 17.7 16.9 18.5 16.9 16.1 18.5 18.5 17.7 16.1
[15:47:31.946] <TB1> INFO: ----------------------------------------------------------------------
[15:47:31.946] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:47:31.946] <TB1> INFO: ----------------------------------------------------------------------
[15:47:51.537] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 392.3 mA = 24.5187 mA/ROC
[15:47:51.540] <TB1> INFO: ----------------------------------------------------------------------
[15:47:51.540] <TB1> INFO: PixTestPretest::findWorkingPixel()
[15:47:51.540] <TB1> INFO: ----------------------------------------------------------------------
[15:48:00.042] <TB1> INFO: Test took 8497ms.
[15:48:00.333] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:48:00.382] <TB1> INFO: ----------------------------------------------------------------------
[15:48:00.382] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[15:48:00.382] <TB1> INFO: ----------------------------------------------------------------------
[15:48:08.917] <TB1> INFO: Test took 8527ms.
[15:48:09.241] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[15:48:09.241] <TB1> INFO: CalDel: 154 139 154 175 127 148 153 130 125 155 125 144 138 137 129 139
[15:48:09.241] <TB1> INFO: VthrComp: 55 51 51 52 51 51 51 51 51 51 51 51 51 51 51 51
[15:48:09.245] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C0.dat
[15:48:09.245] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C1.dat
[15:48:09.245] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C2.dat
[15:48:09.245] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C3.dat
[15:48:09.246] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C4.dat
[15:48:09.246] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C5.dat
[15:48:09.246] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C6.dat
[15:48:09.246] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C7.dat
[15:48:09.246] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C8.dat
[15:48:09.247] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C9.dat
[15:48:09.247] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C10.dat
[15:48:09.247] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C11.dat
[15:48:09.247] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C12.dat
[15:48:09.247] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C13.dat
[15:48:09.248] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C14.dat
[15:48:09.248] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters_C15.dat
[15:48:09.248] <TB1> INFO: PixTestPretest::doTest() done, duration: 55 seconds
[15:48:09.343] <TB1> INFO: enter test to run
[15:48:09.343] <TB1> INFO: test: fulltest no parameter change
[15:48:09.343] <TB1> INFO: running: fulltest
[15:48:09.343] <TB1> INFO: ######################################################################
[15:48:09.343] <TB1> INFO: PixTestFullTest::doTest()
[15:48:09.343] <TB1> INFO: ######################################################################
[15:48:09.344] <TB1> INFO: ######################################################################
[15:48:09.344] <TB1> INFO: PixTestAlive::doTest()
[15:48:09.345] <TB1> INFO: ######################################################################
[15:48:09.347] <TB1> INFO: ----------------------------------------------------------------------
[15:48:09.347] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:48:09.347] <TB1> INFO: ----------------------------------------------------------------------
[15:48:12.822] <TB1> INFO: Test took 3473ms.
[15:48:12.844] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:13.083] <TB1> INFO: PixTestAlive::aliveTest() done
[15:48:13.084] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:48:13.086] <TB1> INFO: ----------------------------------------------------------------------
[15:48:13.086] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:48:13.086] <TB1> INFO: ----------------------------------------------------------------------
[15:48:15.879] <TB1> INFO: Test took 2791ms.
[15:48:15.881] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:15.881] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:48:16.121] <TB1> INFO: PixTestAlive::maskTest() done
[15:48:16.121] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:48:16.123] <TB1> INFO: ----------------------------------------------------------------------
[15:48:16.123] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:48:16.123] <TB1> INFO: ----------------------------------------------------------------------
[15:48:19.643] <TB1> INFO: Test took 3517ms.
[15:48:19.664] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:19.905] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[15:48:19.905] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:48:19.906] <TB1> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[15:48:19.918] <TB1> INFO: ######################################################################
[15:48:19.918] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:48:19.918] <TB1> INFO: ######################################################################
[15:48:19.923] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30) hits flags = 2 (plus default)
[15:48:19.938] <TB1> INFO: dacScan step from 0 .. 29
[15:48:41.641] <TB1> INFO: Test took 21703ms.
[15:48:41.673] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:41.673] <TB1> INFO: dacScan step from 30 .. 59
[15:49:03.817] <TB1> INFO: Test took 22144ms.
[15:49:03.883] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:03.891] <TB1> INFO: dacScan step from 60 .. 89
[15:49:32.796] <TB1> INFO: Test took 28905ms.
[15:49:33.052] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:33.118] <TB1> INFO: dacScan step from 90 .. 119
[15:50:02.795] <TB1> INFO: Test took 29677ms.
[15:50:03.074] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:03.152] <TB1> INFO: dacScan step from 120 .. 149
[15:50:28.653] <TB1> INFO: Test took 25501ms.
[15:50:28.871] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:56.038] <TB1> INFO: PixTestBBMap::doTest() done, duration: 156 seconds
[15:50:56.038] <TB1> INFO: number of dead bumps (per ROC): 2 0 0 0 0 0 0 0 3 6 1 3 3 2 10 21
[15:50:56.038] <TB1> INFO: separation cut (per ROC): 92 102 87 99 90 96 88 108 90 94 97 91 79 96 91 88
[15:50:56.104] <TB1> INFO: ######################################################################
[15:50:56.105] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50
[15:50:56.105] <TB1> INFO: ######################################################################
[15:50:56.105] <TB1> INFO: ----------------------------------------------------------------------
[15:50:56.105] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50
[15:50:56.105] <TB1> INFO: ----------------------------------------------------------------------
[15:50:56.105] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (4) hits flags = 16 (plus default)
[15:50:56.112] <TB1> INFO: dacScan step from 0 .. 3
[15:51:15.415] <TB1> INFO: Test took 19302ms.
[15:51:15.449] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:15.449] <TB1> INFO: dacScan step from 4 .. 7
[15:51:34.809] <TB1> INFO: Test took 19360ms.
[15:51:34.843] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:34.843] <TB1> INFO: dacScan step from 8 .. 11
[15:51:54.161] <TB1> INFO: Test took 19317ms.
[15:51:54.192] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:54.192] <TB1> INFO: dacScan step from 12 .. 15
[15:52:13.889] <TB1> INFO: Test took 19697ms.
[15:52:13.913] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:13.915] <TB1> INFO: dacScan step from 16 .. 19
[15:52:31.725] <TB1> INFO: Test took 17810ms.
[15:52:31.754] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:31.754] <TB1> INFO: dacScan step from 20 .. 23
[15:52:49.854] <TB1> INFO: Test took 18099ms.
[15:52:49.889] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:49.889] <TB1> INFO: dacScan step from 24 .. 27
[15:53:09.466] <TB1> INFO: Test took 19577ms.
[15:53:09.500] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:09.500] <TB1> INFO: dacScan step from 28 .. 31
[15:53:29.049] <TB1> INFO: Test took 19549ms.
[15:53:29.079] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:29.079] <TB1> INFO: dacScan step from 32 .. 35
[15:53:48.558] <TB1> INFO: Test took 19479ms.
[15:53:48.584] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:48.585] <TB1> INFO: dacScan step from 36 .. 39
[15:54:08.093] <TB1> INFO: Test took 19508ms.
[15:54:08.123] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:08.123] <TB1> INFO: dacScan step from 40 .. 43
[15:54:27.351] <TB1> INFO: Test took 19228ms.
[15:54:27.379] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:27.379] <TB1> INFO: dacScan step from 44 .. 47
[15:54:46.789] <TB1> INFO: Test took 19410ms.
[15:54:46.822] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:46.822] <TB1> INFO: dacScan step from 48 .. 51
[15:55:06.405] <TB1> INFO: Test took 19583ms.
[15:55:06.440] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:06.440] <TB1> INFO: dacScan step from 52 .. 55
[15:55:25.753] <TB1> INFO: Test took 19313ms.
[15:55:25.786] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:25.787] <TB1> INFO: dacScan step from 56 .. 59
[15:55:45.352] <TB1> INFO: Test took 19565ms.
[15:55:45.387] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:45.387] <TB1> INFO: dacScan step from 60 .. 63
[15:56:04.861] <TB1> INFO: Test took 19473ms.
[15:56:04.893] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:04.893] <TB1> INFO: dacScan step from 64 .. 67
[15:56:24.214] <TB1> INFO: Test took 19321ms.
[15:56:24.243] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:24.243] <TB1> INFO: dacScan step from 68 .. 71
[15:56:43.626] <TB1> INFO: Test took 19383ms.
[15:56:43.657] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:43.658] <TB1> INFO: dacScan step from 72 .. 75
[15:57:03.090] <TB1> INFO: Test took 19432ms.
[15:57:03.126] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:03.126] <TB1> INFO: dacScan step from 76 .. 79
[15:57:22.409] <TB1> INFO: Test took 19283ms.
[15:57:22.438] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:22.438] <TB1> INFO: dacScan step from 80 .. 83
[15:57:41.934] <TB1> INFO: Test took 19496ms.
[15:57:41.974] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:41.974] <TB1> INFO: dacScan step from 84 .. 87
[15:58:02.037] <TB1> INFO: Test took 20063ms.
[15:58:02.081] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:02.082] <TB1> INFO: dacScan step from 88 .. 91
[15:58:22.175] <TB1> INFO: Test took 20093ms.
[15:58:22.236] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:22.238] <TB1> INFO: dacScan step from 92 .. 95
[15:58:43.838] <TB1> INFO: Test took 21600ms.
[15:58:43.949] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:43.953] <TB1> INFO: dacScan step from 96 .. 99
[15:59:07.591] <TB1> INFO: Test took 23637ms.
[15:59:07.723] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:07.729] <TB1> INFO: dacScan step from 100 .. 103
[15:59:33.122] <TB1> INFO: Test took 25393ms.
[15:59:33.360] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:33.370] <TB1> INFO: dacScan step from 104 .. 107
[16:00:00.496] <TB1> INFO: Test took 27126ms.
[16:00:00.711] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:00.722] <TB1> INFO: dacScan step from 108 .. 111
[16:00:29.650] <TB1> INFO: Test took 28928ms.
[16:00:29.886] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:29.895] <TB1> INFO: dacScan step from 112 .. 115
[16:00:58.753] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[16:00:58.753] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:00:59.761] <TB1> INFO: Test took 29866ms.
[16:01:00.072] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:01:00.085] <TB1> INFO: dacScan step from 116 .. 119
[16:01:30.217] <TB1> INFO: Test took 30132ms.
[16:01:30.440] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:01:30.450] <TB1> INFO: dacScan step from 120 .. 123
[16:01:59.232] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (32) != TBM ID (0)

[16:01:59.232] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[16:01:59.232] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (1) != TBM ID (33)

[16:01:59.232] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:02:00.517] <TB1> INFO: Test took 30067ms.
[16:02:00.745] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:02:00.756] <TB1> INFO: dacScan step from 124 .. 127
[16:02:29.691] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (204) != TBM ID (0)

[16:02:29.691] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[16:02:29.691] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (1) != TBM ID (205)

[16:02:29.691] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:02:31.006] <TB1> INFO: Test took 30250ms.
[16:02:31.225] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:02:31.235] <TB1> INFO: dacScan step from 128 .. 131
[16:02:59.821] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (234) != TBM ID (0)

[16:02:59.821] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[16:02:59.821] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (1) != TBM ID (235)

[16:02:59.821] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:03:01.096] <TB1> INFO: Test took 29861ms.
[16:03:01.319] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:01.330] <TB1> INFO: dacScan step from 132 .. 135
[16:03:30.138] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (205) != TBM ID (0)

[16:03:30.138] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[16:03:30.138] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (1) != TBM ID (206)

[16:03:30.138] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:03:31.542] <TB1> INFO: Test took 30212ms.
[16:03:31.865] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:31.878] <TB1> INFO: dacScan step from 136 .. 139
[16:04:00.669] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[16:04:00.669] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:04:02.010] <TB1> INFO: Test took 30132ms.
[16:04:02.244] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:02.255] <TB1> INFO: dacScan step from 140 .. 143
[16:04:30.938] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (8) != Token Chain Length (4)

[16:04:30.938] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (195) != TBM ID (196)

[16:04:30.938] <TB1> WARNING: ROC 0: Readback start marker after 15 readouts!

[16:04:30.938] <TB1> WARNING: ROC 1: Readback start marker after 15 readouts!

[16:04:30.938] <TB1> WARNING: ROC 2: Readback start marker after 15 readouts!

[16:04:30.938] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:04:32.316] <TB1> INFO: Test took 30061ms.
[16:04:32.550] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:32.562] <TB1> INFO: dacScan step from 144 .. 147
[16:05:02.386] <TB1> INFO: Test took 29824ms.
[16:05:02.706] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:02.721] <TB1> INFO: dacScan step from 148 .. 149
[16:05:19.096] <TB1> INFO: Test took 16375ms.
[16:05:19.218] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:19.224] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:20.663] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:22.031] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:23.406] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:24.700] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:26.080] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:27.432] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:28.801] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:30.142] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:31.496] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:32.837] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:34.181] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:35.547] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:36.892] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:38.250] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:39.619] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:05:41.003] <TB1> INFO: PixTestScurves::scurves() done
[16:05:41.003] <TB1> INFO: Vcal mean: 110.32 98.54 96.40 118.26 94.36 104.85 99.77 107.66 99.56 107.92 105.44 97.62 104.08 99.73 95.30 108.64
[16:05:41.003] <TB1> INFO: Vcal RMS: 5.46 5.44 5.15 5.73 6.01 5.85 5.85 6.01 5.66 5.19 5.53 4.94 5.87 6.23 5.41 5.40
[16:05:41.003] <TB1> INFO: PixTestScurves::fullTest() done, duration: 884 seconds
[16:05:41.079] <TB1> INFO: ######################################################################
[16:05:41.079] <TB1> INFO: PixTestTrim::doTest()
[16:05:41.079] <TB1> INFO: ######################################################################
[16:05:41.081] <TB1> INFO: ----------------------------------------------------------------------
[16:05:41.081] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[16:05:41.081] <TB1> INFO: ----------------------------------------------------------------------
[16:05:41.183] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[16:05:41.183] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[16:05:41.194] <TB1> INFO: dacScan step from 0 .. 19
[16:05:56.404] <TB1> INFO: Test took 15210ms.
[16:05:56.426] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:56.426] <TB1> INFO: dacScan step from 20 .. 39
[16:06:11.306] <TB1> INFO: Test took 14880ms.
[16:06:11.330] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:11.330] <TB1> INFO: dacScan step from 40 .. 59
[16:06:25.910] <TB1> INFO: Test took 14579ms.
[16:06:25.931] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:25.931] <TB1> INFO: dacScan step from 60 .. 79
[16:06:41.177] <TB1> INFO: Test took 15246ms.
[16:06:41.211] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:41.211] <TB1> INFO: dacScan step from 80 .. 99
[16:06:56.707] <TB1> INFO: Test took 15496ms.
[16:06:56.736] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:56.737] <TB1> INFO: dacScan step from 100 .. 119
[16:07:14.572] <TB1> INFO: Test took 17834ms.
[16:07:14.701] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:14.726] <TB1> INFO: dacScan step from 120 .. 139
[16:07:34.514] <TB1> INFO: Test took 19788ms.
[16:07:34.745] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:34.778] <TB1> INFO: dacScan step from 140 .. 159
[16:07:51.503] <TB1> INFO: Test took 16724ms.
[16:07:51.596] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:13.859] <TB1> INFO: ROC 0 VthrComp = 105
[16:08:13.859] <TB1> INFO: ROC 1 VthrComp = 98
[16:08:13.860] <TB1> INFO: ROC 2 VthrComp = 93
[16:08:13.860] <TB1> INFO: ROC 3 VthrComp = 108
[16:08:13.860] <TB1> INFO: ROC 4 VthrComp = 91
[16:08:13.860] <TB1> INFO: ROC 5 VthrComp = 99
[16:08:13.860] <TB1> INFO: ROC 6 VthrComp = 94
[16:08:13.860] <TB1> INFO: ROC 7 VthrComp = 104
[16:08:13.861] <TB1> INFO: ROC 8 VthrComp = 100
[16:08:13.861] <TB1> INFO: ROC 9 VthrComp = 104
[16:08:13.861] <TB1> INFO: ROC 10 VthrComp = 103
[16:08:13.861] <TB1> INFO: ROC 11 VthrComp = 97
[16:08:13.861] <TB1> INFO: ROC 12 VthrComp = 97
[16:08:13.861] <TB1> INFO: ROC 13 VthrComp = 100
[16:08:13.861] <TB1> INFO: ROC 14 VthrComp = 97
[16:08:13.862] <TB1> INFO: ROC 15 VthrComp = 105
[16:08:13.862] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[16:08:13.862] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[16:08:13.871] <TB1> INFO: dacScan step from 0 .. 19
[16:08:29.013] <TB1> INFO: Test took 15142ms.
[16:08:29.039] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:29.039] <TB1> INFO: dacScan step from 20 .. 39
[16:08:44.560] <TB1> INFO: Test took 15521ms.
[16:08:44.593] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:44.596] <TB1> INFO: dacScan step from 40 .. 59
[16:09:03.284] <TB1> INFO: Test took 18688ms.
[16:09:03.490] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:03.530] <TB1> INFO: dacScan step from 60 .. 79
[16:09:22.621] <TB1> INFO: Test took 19090ms.
[16:09:22.778] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:22.832] <TB1> INFO: dacScan step from 80 .. 99
[16:09:41.806] <TB1> INFO: Test took 18974ms.
[16:09:41.960] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:42.014] <TB1> INFO: dacScan step from 100 .. 119
[16:10:02.816] <TB1> INFO: Test took 20802ms.
[16:10:02.979] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:03.033] <TB1> INFO: dacScan step from 120 .. 139
[16:10:23.718] <TB1> INFO: Test took 20685ms.
[16:10:23.938] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:23.999] <TB1> INFO: dacScan step from 140 .. 159
[16:10:44.668] <TB1> INFO: Test took 20668ms.
[16:10:44.902] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:11:11.240] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 65.4532 for pixel 21/0 mean/min/max = 49.8649/34.2078/65.522
[16:11:11.241] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 58.6044 for pixel 5/0 mean/min/max = 45.5009/32.1516/58.8502
[16:11:11.241] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 60.1163 for pixel 23/1 mean/min/max = 46.7082/33.1343/60.2821
[16:11:11.241] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 66.9973 for pixel 20/16 mean/min/max = 51.3666/35.5498/67.1834
[16:11:11.241] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 61.3203 for pixel 3/2 mean/min/max = 47.0831/32.841/61.3252
[16:11:11.242] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 63.583 for pixel 15/6 mean/min/max = 48.0121/32.4017/63.6226
[16:11:11.242] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 62.6843 for pixel 0/1 mean/min/max = 47.6644/32.4702/62.8587
[16:11:11.242] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 63.8518 for pixel 0/68 mean/min/max = 48.7439/33.2694/64.2185
[16:11:11.242] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 58.8515 for pixel 9/2 mean/min/max = 45.2593/31.572/58.9465
[16:11:11.243] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 62.583 for pixel 5/77 mean/min/max = 48.5967/34.5976/62.5957
[16:11:11.243] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 61.2233 for pixel 11/75 mean/min/max = 46.72/32.1876/61.2524
[16:11:11.243] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 58.548 for pixel 0/11 mean/min/max = 45.5726/32.4633/58.6819
[16:11:11.244] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 66.102 for pixel 20/44 mean/min/max = 50.4387/34.7493/66.1282
[16:11:11.244] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 60.0296 for pixel 5/78 mean/min/max = 45.7997/31.5132/60.0862
[16:11:11.244] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 56.6852 for pixel 0/73 mean/min/max = 44.7013/32.1915/57.2112
[16:11:11.244] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 62.6908 for pixel 25/74 mean/min/max = 48.1096/33.4173/62.8019
[16:11:11.244] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:12:45.784] <TB1> INFO: Test took 94540ms.
[16:12:47.127] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[16:12:47.135] <TB1> INFO: dacScan step from 0 .. 19
[16:13:10.181] <TB1> INFO: Test took 23046ms.
[16:13:10.226] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:10.228] <TB1> INFO: dacScan step from 20 .. 39
[16:13:39.121] <TB1> INFO: Test took 28893ms.
[16:13:39.337] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:39.367] <TB1> INFO: dacScan step from 40 .. 59
[16:14:12.054] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (8) != Token Chain Length (4)

[16:14:12.054] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (198) != TBM ID (199)

[16:14:12.054] <TB1> WARNING: ROC 0: Readback start marker after 15 readouts!

[16:14:12.054] <TB1> WARNING: ROC 1: Readback start marker after 15 readouts!

[16:14:12.054] <TB1> WARNING: ROC 2: Readback start marker after 15 readouts!

[16:14:12.054] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:14:13.280] <TB1> INFO: Test took 33914ms.
[16:14:13.549] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:14:13.597] <TB1> INFO: dacScan step from 60 .. 79
[16:14:47.618] <TB1> INFO: Test took 34021ms.
[16:14:47.881] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:14:47.935] <TB1> INFO: dacScan step from 80 .. 99
[16:15:20.413] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[16:15:20.413] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:15:21.611] <TB1> INFO: Test took 33676ms.
[16:15:21.888] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:15:21.940] <TB1> INFO: dacScan step from 100 .. 119
[16:15:52.039] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (232) != TBM ID (0)

[16:15:52.039] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[16:15:52.039] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (1) != TBM ID (233)

[16:15:52.040] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:15:53.347] <TB1> INFO: Test took 31407ms.
[16:15:53.732] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:15:53.787] <TB1> INFO: dacScan step from 120 .. 139
[16:16:26.611] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

[16:16:26.612] <TB1> WARNING: ROC 2: Readback start marker after 15 readouts!

[16:16:26.612] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:16:27.988] <TB1> INFO: Test took 34201ms.
[16:16:28.329] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:16:28.378] <TB1> INFO: dacScan step from 140 .. 159
[16:17:01.014] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[16:17:01.014] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:17:02.366] <TB1> INFO: Test took 33988ms.
[16:17:02.796] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:17:02.851] <TB1> INFO: dacScan step from 160 .. 179
[16:17:36.702] <TB1> INFO: Test took 33851ms.
[16:17:37.128] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:17:37.182] <TB1> INFO: dacScan step from 180 .. 199
[16:18:10.594] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[16:18:10.594] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:18:11.880] <TB1> INFO: Test took 34698ms.
[16:18:12.146] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:18:42.420] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.031251 .. 255.000000
[16:18:42.500] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20) hits flags = 16 (plus default)
[16:18:42.508] <TB1> INFO: dacScan step from 0 .. 19
[16:18:56.158] <TB1> INFO: Test took 13650ms.
[16:18:56.190] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:18:56.191] <TB1> INFO: dacScan step from 20 .. 39
[16:19:11.379] <TB1> INFO: Test took 15188ms.
[16:19:11.463] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:11.484] <TB1> INFO: dacScan step from 40 .. 59
[16:19:29.553] <TB1> INFO: Test took 18069ms.
[16:19:29.703] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:29.748] <TB1> INFO: dacScan step from 60 .. 79
[16:19:48.030] <TB1> INFO: Test took 18282ms.
[16:19:48.245] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:48.304] <TB1> INFO: dacScan step from 80 .. 99
[16:20:06.285] <TB1> INFO: Test took 17981ms.
[16:20:06.428] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:20:06.477] <TB1> INFO: dacScan step from 100 .. 119
[16:20:24.474] <TB1> INFO: Test took 17997ms.
[16:20:24.612] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:20:24.660] <TB1> INFO: dacScan step from 120 .. 139
[16:20:42.674] <TB1> INFO: Test took 18014ms.
[16:20:42.811] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:20:42.860] <TB1> INFO: dacScan step from 140 .. 159
[16:20:59.866] <TB1> INFO: Test took 17006ms.
[16:21:00.034] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:00.083] <TB1> INFO: dacScan step from 160 .. 179
[16:21:17.911] <TB1> INFO: Test took 17828ms.
[16:21:18.051] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:18.107] <TB1> INFO: dacScan step from 180 .. 199
[16:21:36.919] <TB1> INFO: Test took 18812ms.
[16:21:37.070] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:37.119] <TB1> INFO: dacScan step from 200 .. 219
[16:21:55.044] <TB1> INFO: Test took 17925ms.
[16:21:55.246] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:55.305] <TB1> INFO: dacScan step from 220 .. 239
[16:22:13.291] <TB1> INFO: Test took 17986ms.
[16:22:13.435] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:13.485] <TB1> INFO: dacScan step from 240 .. 255
[16:22:28.436] <TB1> INFO: Test took 14951ms.
[16:22:28.545] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:59.693] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 13.286703 .. 47.982597
[16:22:59.802] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 3 .. 57 (20) hits flags = 16 (plus default)
[16:22:59.812] <TB1> INFO: dacScan step from 3 .. 22
[16:23:13.615] <TB1> INFO: Test took 13803ms.
[16:23:13.643] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:13.643] <TB1> INFO: dacScan step from 23 .. 42
[16:23:28.747] <TB1> INFO: Test took 15104ms.
[16:23:28.844] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:28.867] <TB1> INFO: dacScan step from 43 .. 57
[16:23:42.261] <TB1> INFO: Test took 13394ms.
[16:23:42.364] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:58.830] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 20.226975 .. 41.982960
[16:23:58.913] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 10 .. 51 (20) hits flags = 16 (plus default)
[16:23:58.924] <TB1> INFO: dacScan step from 10 .. 29
[16:24:12.832] <TB1> INFO: Test took 13908ms.
[16:24:12.858] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:12.858] <TB1> INFO: dacScan step from 30 .. 49
[16:24:30.197] <TB1> INFO: Test took 17338ms.
[16:24:30.330] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:30.375] <TB1> INFO: dacScan step from 50 .. 51
[16:24:34.771] <TB1> INFO: Test took 4395ms.
[16:24:34.799] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:50.583] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 5.061229 .. 40.905505
[16:24:50.663] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 5 .. 50 (20) hits flags = 16 (plus default)
[16:24:50.671] <TB1> INFO: dacScan step from 5 .. 24
[16:25:03.766] <TB1> INFO: Test took 13095ms.
[16:25:03.785] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:03.786] <TB1> INFO: dacScan step from 25 .. 44
[16:25:18.743] <TB1> INFO: Test took 14957ms.
[16:25:18.880] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:18.917] <TB1> INFO: dacScan step from 45 .. 50
[16:25:26.413] <TB1> INFO: Test took 7496ms.
[16:25:26.459] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:43.252] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[16:25:43.252] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20) hits flags = 16 (plus default)
[16:25:43.262] <TB1> INFO: dacScan step from 15 .. 34
[16:26:06.731] <TB1> INFO: Test took 23468ms.
[16:26:06.800] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:06.812] <TB1> INFO: dacScan step from 35 .. 54
[16:26:39.194] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

[16:26:39.194] <TB1> WARNING: ROC 2: Readback start marker after 15 readouts!

[16:26:39.194] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:26:39.881] <TB1> INFO: Test took 33069ms.
[16:26:40.164] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:40.210] <TB1> INFO: dacScan step from 55 .. 55
[16:26:44.591] <TB1> INFO: Test took 4381ms.
[16:26:44.605] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:58.270] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:26:58.270] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:26:58.270] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:26:58.270] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:26:58.270] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:26:58.270] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:26:58.270] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:26:58.271] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:26:58.271] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:26:58.271] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:26:58.271] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:26:58.271] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:26:58.271] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:26:58.271] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:26:58.271] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:26:58.271] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:26:58.272] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C0.dat
[16:26:58.279] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C1.dat
[16:26:58.285] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C2.dat
[16:26:58.291] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C3.dat
[16:26:58.296] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C4.dat
[16:26:58.302] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C5.dat
[16:26:58.308] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C6.dat
[16:26:58.314] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C7.dat
[16:26:58.320] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C8.dat
[16:26:58.325] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C9.dat
[16:26:58.331] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C10.dat
[16:26:58.337] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C11.dat
[16:26:58.343] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C12.dat
[16:26:58.349] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C13.dat
[16:26:58.355] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C14.dat
[16:26:58.360] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//trimParameters35_C15.dat
[16:26:58.366] <TB1> INFO: PixTestTrim::trimTest() done
[16:26:58.366] <TB1> INFO: vtrim: 109 113 105 134 107 117 108 113 99 116 125 106 149 115 102 129
[16:26:58.366] <TB1> INFO: vthrcomp: 105 98 93 108 91 99 94 104 100 104 103 97 97 100 97 105
[16:26:58.366] <TB1> INFO: vcal mean: 34.70 35.09 35.11 35.15 35.12 35.04 35.20 35.12 35.04 35.16 35.10 35.07 35.12 35.10 35.07 35.12
[16:26:58.366] <TB1> INFO: vcal RMS: 1.62 1.02 1.08 1.15 1.08 1.16 1.27 1.10 1.06 1.13 1.09 1.03 1.09 1.12 1.02 1.06
[16:26:58.366] <TB1> INFO: bits mean: 7.95 9.94 9.37 8.72 9.49 9.49 9.50 8.64 9.89 9.08 9.93 9.63 8.63 9.87 9.94 9.44
[16:26:58.366] <TB1> INFO: bits RMS: 2.82 2.48 2.56 2.27 2.53 2.53 2.55 2.69 2.60 2.35 2.41 2.55 2.29 2.58 2.51 2.39
[16:26:58.371] <TB1> INFO: ----------------------------------------------------------------------
[16:26:58.371] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 240 150 100
[16:26:58.371] <TB1> INFO: ----------------------------------------------------------------------
[16:26:58.376] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[16:26:58.383] <TB1> INFO: dacScan step from 0 .. 19
[16:27:21.472] <TB1> INFO: Test took 23089ms.
[16:27:21.520] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:21.520] <TB1> INFO: dacScan step from 20 .. 39
[16:27:44.794] <TB1> INFO: Test took 23274ms.
[16:27:44.862] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:44.865] <TB1> INFO: dacScan step from 40 .. 59
[16:28:12.847] <TB1> INFO: Test took 27981ms.
[16:28:13.092] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:13.125] <TB1> INFO: dacScan step from 60 .. 79
[16:28:45.425] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[16:28:45.425] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:28:46.520] <TB1> INFO: Test took 33395ms.
[16:28:46.794] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:46.842] <TB1> INFO: dacScan step from 80 .. 99
[16:29:19.156] <TB1> INFO: Test took 32314ms.
[16:29:19.451] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:19.511] <TB1> INFO: dacScan step from 100 .. 119
[16:29:53.771] <TB1> INFO: Test took 34260ms.
[16:29:54.027] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:54.075] <TB1> INFO: dacScan step from 120 .. 139
[16:30:25.860] <TB1> INFO: Test took 31785ms.
[16:30:26.247] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:26.303] <TB1> INFO: dacScan step from 140 .. 159
[16:31:00.211] <TB1> INFO: Test took 33908ms.
[16:31:00.491] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:00.546] <TB1> INFO: dacScan step from 160 .. 179
[16:31:33.266] <TB1> INFO: Test took 32720ms.
[16:31:33.524] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:33.564] <TB1> INFO: dacScan step from 180 .. 199
[16:32:08.405] <TB1> INFO: Test took 34841ms.
[16:32:08.693] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:35.674] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 83 (20) hits flags = 16 (plus default)
[16:32:35.682] <TB1> INFO: dacScan step from 0 .. 19
[16:32:58.836] <TB1> INFO: Test took 23154ms.
[16:32:58.873] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:58.873] <TB1> INFO: dacScan step from 20 .. 39
[16:33:23.089] <TB1> INFO: Test took 24216ms.
[16:33:23.172] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:23.180] <TB1> INFO: dacScan step from 40 .. 59
[16:33:55.848] <TB1> INFO: Test took 32667ms.
[16:33:56.113] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:56.151] <TB1> INFO: dacScan step from 60 .. 79
[16:34:28.803] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[16:34:28.803] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:34:30.149] <TB1> INFO: Test took 33998ms.
[16:34:30.417] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:30.475] <TB1> INFO: dacScan step from 80 .. 83
[16:34:39.452] <TB1> INFO: Test took 8977ms.
[16:34:39.529] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:56.722] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 78 (20) hits flags = 16 (plus default)
[16:34:56.730] <TB1> INFO: dacScan step from 0 .. 19
[16:35:19.774] <TB1> INFO: Test took 23044ms.
[16:35:19.823] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:19.823] <TB1> INFO: dacScan step from 20 .. 39
[16:35:45.231] <TB1> INFO: Test took 25408ms.
[16:35:45.398] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:45.417] <TB1> INFO: dacScan step from 40 .. 59
[16:36:18.469] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (144) != TBM ID (0)

[16:36:18.469] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[16:36:18.469] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (1) != TBM ID (145)

[16:36:18.469] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:36:19.114] <TB1> INFO: Test took 33697ms.
[16:36:19.388] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:19.444] <TB1> INFO: dacScan step from 60 .. 78
[16:36:49.008] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

[16:36:49.008] <TB1> WARNING: ROC 2: Readback start marker after 15 readouts!

[16:36:49.008] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[16:36:49.260] <TB1> INFO: Test took 29816ms.
[16:36:49.646] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:06.271] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 72 (20) hits flags = 16 (plus default)
[16:37:06.278] <TB1> INFO: dacScan step from 0 .. 19
[16:37:29.339] <TB1> INFO: Test took 23060ms.
[16:37:29.382] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:29.382] <TB1> INFO: dacScan step from 20 .. 39
[16:37:55.773] <TB1> INFO: Test took 26391ms.
[16:37:55.922] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:55.941] <TB1> INFO: dacScan step from 40 .. 59
[16:38:29.383] <TB1> INFO: Test took 33442ms.
[16:38:29.657] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:29.700] <TB1> INFO: dacScan step from 60 .. 72
[16:38:50.764] <TB1> INFO: Test took 21064ms.
[16:38:50.945] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:06.207] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 71 (20) hits flags = 16 (plus default)
[16:39:06.215] <TB1> INFO: dacScan step from 0 .. 19
[16:39:29.115] <TB1> INFO: Test took 22900ms.
[16:39:29.153] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:29.154] <TB1> INFO: dacScan step from 20 .. 39
[16:39:56.501] <TB1> INFO: Test took 27347ms.
[16:39:56.760] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:56.810] <TB1> INFO: dacScan step from 40 .. 59
[16:40:30.747] <TB1> INFO: Test took 33937ms.
[16:40:31.022] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:31.106] <TB1> INFO: dacScan step from 60 .. 71
[16:40:52.378] <TB1> INFO: Test took 21272ms.
[16:40:52.540] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:10.223] <TB1> INFO: PixTestTrim::trimBitTest() done
[16:41:10.224] <TB1> INFO: PixTestTrim::doTest() done, duration: 2129 seconds
[16:41:10.922] <TB1> INFO: ######################################################################
[16:41:10.922] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:41:10.922] <TB1> INFO: ######################################################################
[16:41:14.461] <TB1> INFO: Test took 3537ms.
[16:41:14.485] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:18.171] <TB1> INFO: Test took 3487ms.
[16:41:18.236] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:21.599] <TB1> INFO: Test took 3350ms.
[16:41:21.677] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:21.685] <TB1> INFO: The DUT currently contains the following objects:
[16:41:21.685] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:21.685] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:21.685] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:21.685] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:21.685] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:21.685] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.790] <TB1> INFO: Test took 1105ms.
[16:41:22.791] <TB1> INFO: The DUT currently contains the following objects:
[16:41:22.791] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:22.791] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:22.791] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:22.791] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:22.791] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:22.791] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.896] <TB1> INFO: Test took 1105ms.
[16:41:23.897] <TB1> INFO: The DUT currently contains the following objects:
[16:41:23.897] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:23.897] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:23.897] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:23.897] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:23.897] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.897] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.897] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.897] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.898] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.898] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.898] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.898] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.898] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.898] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.898] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.898] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.898] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.898] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.898] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:23.898] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.003] <TB1> INFO: Test took 1105ms.
[16:41:25.004] <TB1> INFO: The DUT currently contains the following objects:
[16:41:25.004] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:25.004] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:25.004] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:25.004] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:25.004] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:25.004] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.109] <TB1> INFO: Test took 1105ms.
[16:41:26.110] <TB1> INFO: The DUT currently contains the following objects:
[16:41:26.110] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:26.110] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:26.110] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:26.110] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:26.110] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.110] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.110] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.110] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.111] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.111] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.111] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.111] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.111] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.111] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.111] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.111] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.111] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.111] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.111] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:26.111] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: Test took 1104ms.
[16:41:27.215] <TB1> INFO: The DUT currently contains the following objects:
[16:41:27.215] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:27.215] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:27.215] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:27.215] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:27.215] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:27.215] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.320] <TB1> INFO: Test took 1105ms.
[16:41:28.321] <TB1> INFO: The DUT currently contains the following objects:
[16:41:28.321] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:28.321] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:28.321] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:28.321] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:28.321] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:28.321] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.425] <TB1> INFO: Test took 1104ms.
[16:41:29.425] <TB1> INFO: The DUT currently contains the following objects:
[16:41:29.425] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:29.425] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:29.425] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:29.425] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:29.425] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.425] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.425] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.425] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.425] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.425] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.425] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.425] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.425] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.425] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.426] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.426] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.426] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.426] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.426] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:29.426] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.529] <TB1> INFO: Test took 1103ms.
[16:41:30.530] <TB1> INFO: The DUT currently contains the following objects:
[16:41:30.530] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:30.530] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:30.530] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:30.530] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:30.530] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:30.530] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.634] <TB1> INFO: Test took 1104ms.
[16:41:31.634] <TB1> INFO: The DUT currently contains the following objects:
[16:41:31.635] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:31.635] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:31.635] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:31.635] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:31.635] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:31.635] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.738] <TB1> INFO: Test took 1103ms.
[16:41:32.739] <TB1> INFO: The DUT currently contains the following objects:
[16:41:32.739] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:32.739] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:32.739] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:32.739] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:32.739] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:32.739] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: Test took 1104ms.
[16:41:33.843] <TB1> INFO: The DUT currently contains the following objects:
[16:41:33.843] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:33.843] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:33.843] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:33.843] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:33.843] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:33.843] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.949] <TB1> INFO: Test took 1106ms.
[16:41:34.950] <TB1> INFO: The DUT currently contains the following objects:
[16:41:34.950] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:34.950] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:34.950] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:34.950] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:34.950] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.950] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.950] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.950] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.950] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.950] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.950] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.950] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.950] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.950] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.950] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.950] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.950] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.950] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.950] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:34.951] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.056] <TB1> INFO: Test took 1105ms.
[16:41:36.057] <TB1> INFO: The DUT currently contains the following objects:
[16:41:36.057] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:36.057] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:36.057] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:36.057] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:36.057] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.057] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.057] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.058] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.058] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.058] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.058] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.058] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.058] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.058] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.058] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.058] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.058] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.058] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.058] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:36.058] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.163] <TB1> INFO: Test took 1105ms.
[16:41:37.164] <TB1> INFO: The DUT currently contains the following objects:
[16:41:37.164] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:37.164] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:37.164] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:37.164] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:37.164] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.164] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.164] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.164] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.164] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.164] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.164] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.164] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.164] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.165] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.165] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.165] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.165] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.165] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.165] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:37.165] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.269] <TB1> INFO: Test took 1104ms.
[16:41:38.270] <TB1> INFO: The DUT currently contains the following objects:
[16:41:38.270] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:41:38.270] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:41:38.270] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:41:38.270] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[16:41:38.270] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.270] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.270] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.271] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.271] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.271] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.271] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.271] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.271] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.271] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.271] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.271] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.271] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.271] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.271] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:38.271] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[16:41:39.376] <TB1> INFO: Test took 1105ms.
[16:41:39.381] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:45:28.827] <TB1> INFO: Test took 229446ms.
[16:45:30.491] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:17.755] <TB1> INFO: Test took 227264ms.
[16:49:19.659] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.666] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.673] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.680] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.687] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.694] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.701] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.708] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.714] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[16:49:19.721] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[16:49:19.729] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[16:49:19.736] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[16:49:19.743] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[16:49:19.750] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[16:49:19.757] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[16:49:19.764] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.771] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[16:49:19.778] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[16:49:19.785] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[16:49:19.792] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[16:49:19.800] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[16:49:19.807] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[16:49:19.814] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.821] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.828] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[16:49:19.835] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[16:49:19.843] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[16:49:19.850] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[16:49:19.857] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[16:49:19.863] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.870] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.877] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.885] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.892] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:49:19.948] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:49:19.948] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:49:19.949] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:49:19.949] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:49:19.949] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:49:19.949] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:49:19.949] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:49:19.949] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:49:19.950] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:49:19.950] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:49:19.950] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:49:19.950] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:49:19.950] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:49:19.951] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:49:19.951] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:49:19.951] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:49:23.469] <TB1> INFO: Test took 3514ms.
[16:49:27.257] <TB1> INFO: Test took 3502ms.
[16:49:31.107] <TB1> INFO: Test took 3536ms.
[16:49:31.400] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:32.323] <TB1> INFO: Test took 923ms.
[16:49:32.328] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:33.441] <TB1> INFO: Test took 1113ms.
[16:49:33.446] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:34.557] <TB1> INFO: Test took 1111ms.
[16:49:34.561] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:35.671] <TB1> INFO: Test took 1110ms.
[16:49:35.675] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:36.784] <TB1> INFO: Test took 1109ms.
[16:49:36.788] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:37.898] <TB1> INFO: Test took 1110ms.
[16:49:37.901] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:39.015] <TB1> INFO: Test took 1114ms.
[16:49:39.020] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:40.128] <TB1> INFO: Test took 1109ms.
[16:49:40.132] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:41.242] <TB1> INFO: Test took 1110ms.
[16:49:41.246] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:42.355] <TB1> INFO: Test took 1109ms.
[16:49:42.359] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:43.468] <TB1> INFO: Test took 1109ms.
[16:49:43.472] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:44.581] <TB1> INFO: Test took 1109ms.
[16:49:44.585] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:45.694] <TB1> INFO: Test took 1109ms.
[16:49:45.698] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:46.802] <TB1> INFO: Test took 1104ms.
[16:49:46.805] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:47.918] <TB1> INFO: Test took 1113ms.
[16:49:47.922] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:49.036] <TB1> INFO: Test took 1114ms.
[16:49:49.040] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:50.153] <TB1> INFO: Test took 1114ms.
[16:49:50.157] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:51.269] <TB1> INFO: Test took 1112ms.
[16:49:51.273] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:52.384] <TB1> INFO: Test took 1111ms.
[16:49:52.389] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:53.499] <TB1> INFO: Test took 1110ms.
[16:49:53.504] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:54.616] <TB1> INFO: Test took 1112ms.
[16:49:54.621] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:55.731] <TB1> INFO: Test took 1111ms.
[16:49:55.735] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:56.845] <TB1> INFO: Test took 1110ms.
[16:49:56.850] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:57.960] <TB1> INFO: Test took 1110ms.
[16:49:57.964] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:49:59.076] <TB1> INFO: Test took 1112ms.
[16:49:59.080] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:00.193] <TB1> INFO: Test took 1113ms.
[16:50:00.198] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:01.303] <TB1> INFO: Test took 1105ms.
[16:50:01.306] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:02.425] <TB1> INFO: Test took 1119ms.
[16:50:02.428] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:03.537] <TB1> INFO: Test took 1109ms.
[16:50:03.541] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:04.654] <TB1> INFO: Test took 1113ms.
[16:50:04.658] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:05.771] <TB1> INFO: Test took 1113ms.
[16:50:05.776] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:50:06.885] <TB1> INFO: Test took 1110ms.
[16:50:07.468] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 536 seconds
[16:50:07.469] <TB1> INFO: PH scale (per ROC): 65 72 79 65 70 69 62 67 77 61 78 69 64 71 75 69
[16:50:07.469] <TB1> INFO: PH offset (per ROC): 195 178 159 178 174 174 181 176 176 173 176 177 176 170 173 187
[16:50:07.645] <TB1> INFO: ######################################################################
[16:50:07.645] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:50:07.645] <TB1> INFO: ######################################################################
[16:50:07.655] <TB1> INFO: scanning low vcal = 50
[16:50:12.079] <TB1> INFO: Test took 4424ms.
[16:50:12.144] <TB1> INFO: scanning low vcal = 100
[16:50:16.590] <TB1> INFO: Test took 4446ms.
[16:50:16.670] <TB1> INFO: scanning low vcal = 150
[16:50:21.091] <TB1> INFO: Test took 4421ms.
[16:50:21.155] <TB1> INFO: scanning low vcal = 200
[16:50:25.564] <TB1> INFO: Test took 4409ms.
[16:50:25.624] <TB1> INFO: scanning low vcal = 250
[16:50:30.077] <TB1> INFO: Test took 4453ms.
[16:50:30.184] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[16:50:34.613] <TB1> INFO: Test took 4429ms.
[16:50:34.675] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[16:50:39.080] <TB1> INFO: Test took 4405ms.
[16:50:39.144] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[16:50:43.551] <TB1> INFO: Test took 4407ms.
[16:50:43.618] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[16:50:48.033] <TB1> INFO: Test took 4415ms.
[16:50:48.094] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:50:52.496] <TB1> INFO: Test took 4402ms.
[16:50:52.941] <TB1> INFO: PixTestGainPedestal::measure() done
[16:51:22.436] <TB1> INFO: PixTestGainPedestal::fit() done
[16:51:22.436] <TB1> INFO: non-linearity mean: 0.963 0.947 0.960 0.960 0.953 0.960 0.958 0.964 0.967 0.953 0.958 0.956 0.960 0.958 0.955 0.954
[16:51:22.436] <TB1> INFO: non-linearity RMS: 0.006 0.008 0.006 0.008 0.006 0.006 0.009 0.005 0.004 0.007 0.005 0.007 0.006 0.006 0.006 0.008
[16:51:22.436] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:51:22.455] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:51:22.474] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:51:22.493] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:51:22.512] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:51:22.531] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:51:22.554] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:51:22.574] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:51:22.592] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:51:22.620] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:51:22.643] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:51:22.662] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:51:22.697] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:51:22.731] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:51:22.755] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:51:22.774] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2035_FullQualification_2015-07-10_12h10m_1436523044//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:51:22.793] <TB1> INFO: PixTestGainPedestal::doTest() done, duration: 75 seconds
[16:51:22.799] <TB1> INFO: enter test to run
[16:51:22.800] <TB1> INFO: test: exit no parameter change
[16:51:23.304] <TB1> QUIET: Connection to board 153 closed.
[16:51:23.384] <TB1> INFO: pXar: this is the end, my friend
MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master