Test Date: 2015-07-09 11:22
Analysis date: 2016-05-25 21:28
Logfile
LogfileView
[14:49:58.021] <TB1> INFO: *** Welcome to pxar ***
[14:49:58.021] <TB1> INFO: *** Today: 2015/07/09
[14:49:58.021] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C15.dat
[14:49:58.021] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:49:58.021] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//defaultMaskFile.dat
[14:49:58.021] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters_C15.dat
[14:49:58.091] <TB1> INFO: clk: 4
[14:49:58.091] <TB1> INFO: ctr: 4
[14:49:58.091] <TB1> INFO: sda: 19
[14:49:58.091] <TB1> INFO: tin: 9
[14:49:58.091] <TB1> INFO: level: 15
[14:49:58.091] <TB1> INFO: triggerdelay: 0
[14:49:58.091] <TB1> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[14:49:58.091] <TB1> INFO: Log level: INFO
[14:49:58.099] <TB1> INFO: Found DTB DTB_WXBYFL
[14:49:58.118] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[14:49:58.121] <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:
------------------------------------------------------
[14:49:58.124] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[14:49:59.681] <TB1> INFO: DUT info:
[14:49:59.681] <TB1> INFO: The DUT currently contains the following objects:
[14:49:59.681] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[14:49:59.681] <TB1> INFO: TBM Core alpha (0): 7 registers set
[14:49:59.681] <TB1> INFO: TBM Core beta (1): 7 registers set
[14:49:59.681] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:49:59.681] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.681] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.681] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.681] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.681] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.681] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.681] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.681] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.682] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.682] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.682] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.682] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.682] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.682] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.682] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:59.682] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.083] <TB1> INFO: enter 'restricted' command line mode
[14:50:00.083] <TB1> INFO: enter test to run
[14:50:00.083] <TB1> INFO: test: pretest no parameter change
[14:50:00.083] <TB1> INFO: running: pretest
[14:50:00.090] <TB1> INFO: ######################################################################
[14:50:00.090] <TB1> INFO: PixTestPretest::doTest()
[14:50:00.090] <TB1> INFO: ######################################################################
[14:50:00.092] <TB1> INFO: ----------------------------------------------------------------------
[14:50:00.092] <TB1> INFO: PixTestPretest::programROC()
[14:50:00.092] <TB1> INFO: ----------------------------------------------------------------------
[14:50:18.115] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:50:18.115] <TB1> INFO: IA differences per ROC: 19.3 17.7 17.7 16.9 17.7 17.7 18.5 16.9 17.7 18.5 20.1 16.9 16.1 18.5 16.1 19.3
[14:50:18.212] <TB1> INFO: ----------------------------------------------------------------------
[14:50:18.212] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:50:18.212] <TB1> INFO: ----------------------------------------------------------------------
[14:50:37.804] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 393.1 mA = 24.5688 mA/ROC
[14:50:37.807] <TB1> INFO: ----------------------------------------------------------------------
[14:50:37.808] <TB1> INFO: PixTestPretest::findWorkingPixel()
[14:50:37.808] <TB1> INFO: ----------------------------------------------------------------------
[14:50:46.137] <TB1> INFO: Test took 8324ms.
[14:50:46.434] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[14:50:46.485] <TB1> INFO: ----------------------------------------------------------------------
[14:50:46.485] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[14:50:46.485] <TB1> INFO: ----------------------------------------------------------------------
[14:50:54.818] <TB1> INFO: Test took 8323ms.
[14:50:55.151] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[14:50:55.151] <TB1> INFO: CalDel: 122 126 148 132 120 155 136 122 139 141 145 131 126 150 117 131
[14:50:55.151] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[14:50:55.156] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C0.dat
[14:50:55.156] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C1.dat
[14:50:55.157] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C2.dat
[14:50:55.157] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C3.dat
[14:50:55.157] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C4.dat
[14:50:55.157] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C5.dat
[14:50:55.158] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C6.dat
[14:50:55.158] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C7.dat
[14:50:55.158] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C8.dat
[14:50:55.158] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C9.dat
[14:50:55.159] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C10.dat
[14:50:55.159] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C11.dat
[14:50:55.159] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C12.dat
[14:50:55.160] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C13.dat
[14:50:55.160] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C14.dat
[14:50:55.160] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters_C15.dat
[14:50:55.160] <TB1> INFO: PixTestPretest::doTest() done, duration: 55 seconds
[14:50:55.265] <TB1> INFO: enter test to run
[14:50:55.265] <TB1> INFO: test: fulltest no parameter change
[14:50:55.265] <TB1> INFO: running: fulltest
[14:50:55.265] <TB1> INFO: ######################################################################
[14:50:55.265] <TB1> INFO: PixTestFullTest::doTest()
[14:50:55.265] <TB1> INFO: ######################################################################
[14:50:55.266] <TB1> INFO: ######################################################################
[14:50:55.266] <TB1> INFO: PixTestAlive::doTest()
[14:50:55.266] <TB1> INFO: ######################################################################
[14:50:55.268] <TB1> INFO: ----------------------------------------------------------------------
[14:50:55.268] <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)
[14:50:55.268] <TB1> INFO: ----------------------------------------------------------------------
[14:50:58.730] <TB1> INFO: Test took 3460ms.
[14:50:58.754] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:58.995] <TB1> INFO: PixTestAlive::aliveTest() done
[14:50:58.995] <TB1> INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:50:58.997] <TB1> INFO: ----------------------------------------------------------------------
[14:50:58.997] <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)
[14:50:58.997] <TB1> INFO: ----------------------------------------------------------------------
[14:51:01.729] <TB1> INFO: Test took 2729ms.
[14:51:01.732] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:01.733] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:51:01.975] <TB1> INFO: PixTestAlive::maskTest() done
[14:51:01.975] <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
[14:51:01.977] <TB1> INFO: ----------------------------------------------------------------------
[14:51:01.977] <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)
[14:51:01.977] <TB1> INFO: ----------------------------------------------------------------------
[14:51:05.416] <TB1> INFO: Test took 3437ms.
[14:51:05.436] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:05.680] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[14:51:05.680] <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
[14:51:05.681] <TB1> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[14:51:05.693] <TB1> INFO: ######################################################################
[14:51:05.693] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[14:51:05.693] <TB1> INFO: ######################################################################
[14:51:05.697] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30) hits flags = 2 (plus default)
[14:51:05.712] <TB1> INFO: dacScan step from 0 .. 29
[14:51:27.115] <TB1> INFO: Test took 21403ms.
[14:51:27.158] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:27.158] <TB1> INFO: dacScan step from 30 .. 59
[14:51:49.788] <TB1> INFO: Test took 22630ms.
[14:51:49.879] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:49.889] <TB1> INFO: dacScan step from 60 .. 89
[14:52:18.894] <TB1> INFO: Test took 29005ms.
[14:52:19.179] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:19.253] <TB1> INFO: dacScan step from 90 .. 119
[14:52:48.364] <TB1> INFO: Test took 29111ms.
[14:52:48.608] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:48.681] <TB1> INFO: dacScan step from 120 .. 149
[14:53:14.068] <TB1> INFO: Test took 25387ms.
[14:53:14.295] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:38.855] <TB1> INFO: PixTestBBMap::doTest() done, duration: 153 seconds
[14:53:38.855] <TB1> INFO: number of dead bumps (per ROC): 31 27 10 25 16 4 31 63 41 29 32 36 18 21 91 48
[14:53:38.855] <TB1> INFO: separation cut (per ROC): 88 87 97 74 86 92 82 82 71 73 80 80 81 85 82 84
[14:53:38.927] <TB1> INFO: ######################################################################
[14:53:38.927] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50
[14:53:38.927] <TB1> INFO: ######################################################################
[14:53:38.927] <TB1> INFO: ----------------------------------------------------------------------
[14:53:38.927] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50
[14:53:38.927] <TB1> INFO: ----------------------------------------------------------------------
[14:53:38.928] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (4) hits flags = 16 (plus default)
[14:53:38.936] <TB1> INFO: dacScan step from 0 .. 3
[14:53:56.369] <TB1> INFO: Test took 17433ms.
[14:53:56.398] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:56.398] <TB1> INFO: dacScan step from 4 .. 7
[14:54:15.339] <TB1> INFO: Test took 18941ms.
[14:54:15.371] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:15.371] <TB1> INFO: dacScan step from 8 .. 11
[14:54:34.402] <TB1> INFO: Test took 19031ms.
[14:54:34.433] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:34.433] <TB1> INFO: dacScan step from 12 .. 15
[14:54:53.299] <TB1> INFO: Test took 18866ms.
[14:54:53.325] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:53.325] <TB1> INFO: dacScan step from 16 .. 19
[14:55:12.311] <TB1> INFO: Test took 18986ms.
[14:55:12.341] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:12.341] <TB1> INFO: dacScan step from 20 .. 23
[14:55:31.324] <TB1> INFO: Test took 18983ms.
[14:55:31.351] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:31.351] <TB1> INFO: dacScan step from 24 .. 27
[14:55:50.316] <TB1> INFO: Test took 18965ms.
[14:55:50.343] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:50.343] <TB1> INFO: dacScan step from 28 .. 31
[14:56:09.318] <TB1> INFO: Test took 18975ms.
[14:56:09.345] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:56:09.346] <TB1> INFO: dacScan step from 32 .. 35
[14:56:28.274] <TB1> INFO: Test took 18928ms.
[14:56:28.303] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:56:28.303] <TB1> INFO: dacScan step from 36 .. 39
[14:56:47.287] <TB1> INFO: Test took 18983ms.
[14:56:47.315] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:56:47.315] <TB1> INFO: dacScan step from 40 .. 43
[14:57:06.290] <TB1> INFO: Test took 18974ms.
[14:57:06.317] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:57:06.317] <TB1> INFO: dacScan step from 44 .. 47
[14:57:25.110] <TB1> INFO: Test took 18793ms.
[14:57:25.139] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:57:25.139] <TB1> INFO: dacScan step from 48 .. 51
[14:57:44.074] <TB1> INFO: Test took 18934ms.
[14:57:44.107] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:57:44.107] <TB1> INFO: dacScan step from 52 .. 55
[14:58:03.097] <TB1> INFO: Test took 18990ms.
[14:58:03.125] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:03.125] <TB1> INFO: dacScan step from 56 .. 59
[14:58:22.084] <TB1> INFO: Test took 18959ms.
[14:58:22.113] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:22.113] <TB1> INFO: dacScan step from 60 .. 63
[14:58:41.096] <TB1> INFO: Test took 18983ms.
[14:58:41.126] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:41.126] <TB1> INFO: dacScan step from 64 .. 67
[14:59:00.141] <TB1> INFO: Test took 19015ms.
[14:59:00.171] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:00.171] <TB1> INFO: dacScan step from 68 .. 71
[14:59:19.082] <TB1> INFO: Test took 18911ms.
[14:59:19.113] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:19.113] <TB1> INFO: dacScan step from 72 .. 75
[14:59:38.039] <TB1> INFO: Test took 18926ms.
[14:59:38.071] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:38.072] <TB1> INFO: dacScan step from 76 .. 79
[14:59:57.085] <TB1> INFO: Test took 19013ms.
[14:59:57.118] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:57.118] <TB1> INFO: dacScan step from 80 .. 83
[15:00:16.979] <TB1> INFO: Test took 19860ms.
[15:00:17.042] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:17.044] <TB1> INFO: dacScan step from 84 .. 87
[15:00:38.322] <TB1> INFO: Test took 21278ms.
[15:00:38.401] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:38.404] <TB1> INFO: dacScan step from 88 .. 91
[15:01:00.921] <TB1> INFO: Test took 22517ms.
[15:01:01.031] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:01.036] <TB1> INFO: dacScan step from 92 .. 95
[15:01:25.934] <TB1> INFO: Test took 24898ms.
[15:01:26.084] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:26.091] <TB1> INFO: dacScan step from 96 .. 99
[15:01:52.829] <TB1> INFO: Test took 26738ms.
[15:01:53.047] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:53.055] <TB1> INFO: dacScan step from 100 .. 103
[15:02:21.377] <TB1> INFO: Test took 28321ms.
[15:02:21.618] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:21.628] <TB1> INFO: dacScan step from 104 .. 107
[15:02:50.218] <TB1> INFO: Test took 28590ms.
[15:02:50.495] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:50.504] <TB1> INFO: dacScan step from 108 .. 111
[15:03:19.046] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (8) != Token Chain Length (4)

[15:03:19.046] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (126) != TBM ID (127)

[15:03:19.046] <TB1> WARNING: ROC 0: Readback start marker after 15 readouts!

[15:03:19.046] <TB1> WARNING: ROC 1: Readback start marker after 15 readouts!

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

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

[15:03:20.007] <TB1> INFO: Test took 29503ms.
[15:03:20.248] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:20.259] <TB1> INFO: dacScan step from 112 .. 115
[15:03:48.486] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (232) != TBM ID (0)

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

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

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

[15:03:49.699] <TB1> INFO: Test took 29440ms.
[15:03:49.929] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:49.939] <TB1> INFO: dacScan step from 116 .. 119
[15:04:18.057] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (8) != Token Chain Length (4)

[15:04:18.057] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (116) != TBM ID (117)

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

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

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

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

[15:04:19.319] <TB1> INFO: Test took 29380ms.
[15:04:19.547] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:19.557] <TB1> INFO: dacScan step from 120 .. 123
[15:04:47.737] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

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

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

[15:04:49.030] <TB1> INFO: Test took 29473ms.
[15:04:49.286] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:49.297] <TB1> INFO: dacScan step from 124 .. 127
[15:05:17.275] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

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

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

[15:05:18.527] <TB1> INFO: Test took 29229ms.
[15:05:18.752] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:18.763] <TB1> INFO: dacScan step from 128 .. 131
[15:05:46.967] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (195) != TBM ID (0)

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

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

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

[15:05:48.199] <TB1> INFO: Test took 29436ms.
[15:05:48.426] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:48.436] <TB1> INFO: dacScan step from 132 .. 135
[15:06:17.795] <TB1> INFO: Test took 29359ms.
[15:06:18.025] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:18.035] <TB1> INFO: dacScan step from 136 .. 139
[15:06:47.244] <TB1> INFO: Test took 29209ms.
[15:06:47.462] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:47.472] <TB1> INFO: dacScan step from 140 .. 143
[15:07:16.888] <TB1> INFO: Test took 29416ms.
[15:07:17.116] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:17.126] <TB1> INFO: dacScan step from 144 .. 147
[15:07:45.908] <TB1> INFO: Test took 28781ms.
[15:07:46.129] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:46.139] <TB1> INFO: dacScan step from 148 .. 149
[15:08:00.745] <TB1> INFO: Test took 14605ms.
[15:08:00.859] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:00.865] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:02.385] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:03.922] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:05.408] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:06.928] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:08.283] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:09.663] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:11.031] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:12.401] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:13.788] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:15.184] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:16.563] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:17.943] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:19.316] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:20.671] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:22.029] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:08:23.455] <TB1> INFO: PixTestScurves::scurves() done
[15:08:23.455] <TB1> INFO: Vcal mean: 97.36 91.47 88.49 86.85 103.36 95.26 97.87 101.09 93.30 87.18 97.63 96.19 106.39 105.15 101.24 95.17
[15:08:23.455] <TB1> INFO: Vcal RMS: 5.81 5.40 5.07 5.36 4.89 5.71 5.01 5.64 5.92 5.49 7.05 6.21 4.92 5.40 4.97 5.30
[15:08:23.455] <TB1> INFO: PixTestScurves::fullTest() done, duration: 884 seconds
[15:08:23.531] <TB1> INFO: ######################################################################
[15:08:23.531] <TB1> INFO: PixTestTrim::doTest()
[15:08:23.531] <TB1> INFO: ######################################################################
[15:08:23.532] <TB1> INFO: ----------------------------------------------------------------------
[15:08:23.532] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[15:08:23.532] <TB1> INFO: ----------------------------------------------------------------------
[15:08:23.626] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:08:23.626] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[15:08:23.634] <TB1> INFO: dacScan step from 0 .. 19
[15:08:38.135] <TB1> INFO: Test took 14501ms.
[15:08:38.157] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:38.157] <TB1> INFO: dacScan step from 20 .. 39
[15:08:52.893] <TB1> INFO: Test took 14736ms.
[15:08:52.922] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:52.922] <TB1> INFO: dacScan step from 40 .. 59
[15:09:07.948] <TB1> INFO: Test took 15026ms.
[15:09:07.969] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:07.969] <TB1> INFO: dacScan step from 60 .. 79
[15:09:23.072] <TB1> INFO: Test took 15103ms.
[15:09:23.099] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:23.099] <TB1> INFO: dacScan step from 80 .. 99
[15:09:38.509] <TB1> INFO: Test took 15410ms.
[15:09:38.547] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:38.549] <TB1> INFO: dacScan step from 100 .. 119
[15:09:56.817] <TB1> INFO: Test took 18268ms.
[15:09:56.951] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:56.976] <TB1> INFO: dacScan step from 120 .. 139
[15:10:16.402] <TB1> INFO: Test took 19426ms.
[15:10:16.569] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:16.615] <TB1> INFO: dacScan step from 140 .. 159
[15:10:32.431] <TB1> INFO: Test took 15816ms.
[15:10:32.515] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:55.520] <TB1> INFO: ROC 0 VthrComp = 103
[15:10:55.520] <TB1> INFO: ROC 1 VthrComp = 96
[15:10:55.521] <TB1> INFO: ROC 2 VthrComp = 94
[15:10:55.521] <TB1> INFO: ROC 3 VthrComp = 88
[15:10:55.521] <TB1> INFO: ROC 4 VthrComp = 105
[15:10:55.521] <TB1> INFO: ROC 5 VthrComp = 95
[15:10:55.521] <TB1> INFO: ROC 6 VthrComp = 99
[15:10:55.521] <TB1> INFO: ROC 7 VthrComp = 100
[15:10:55.521] <TB1> INFO: ROC 8 VthrComp = 94
[15:10:55.521] <TB1> INFO: ROC 9 VthrComp = 88
[15:10:55.522] <TB1> INFO: ROC 10 VthrComp = 97
[15:10:55.522] <TB1> INFO: ROC 11 VthrComp = 99
[15:10:55.522] <TB1> INFO: ROC 12 VthrComp = 103
[15:10:55.522] <TB1> INFO: ROC 13 VthrComp = 103
[15:10:55.522] <TB1> INFO: ROC 14 VthrComp = 102
[15:10:55.522] <TB1> INFO: ROC 15 VthrComp = 101
[15:10:55.522] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:10:55.522] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[15:10:55.531] <TB1> INFO: dacScan step from 0 .. 19
[15:11:09.948] <TB1> INFO: Test took 14417ms.
[15:11:09.969] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:09.969] <TB1> INFO: dacScan step from 20 .. 39
[15:11:24.771] <TB1> INFO: Test took 14801ms.
[15:11:24.808] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:24.811] <TB1> INFO: dacScan step from 40 .. 59
[15:11:43.761] <TB1> INFO: Test took 18950ms.
[15:11:43.917] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:43.951] <TB1> INFO: dacScan step from 60 .. 79
[15:12:04.182] <TB1> INFO: Test took 20231ms.
[15:12:04.361] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:04.410] <TB1> INFO: dacScan step from 80 .. 99
[15:12:24.707] <TB1> INFO: Test took 20297ms.
[15:12:24.872] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:24.918] <TB1> INFO: dacScan step from 100 .. 119
[15:12:45.158] <TB1> INFO: Test took 20240ms.
[15:12:45.327] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:45.377] <TB1> INFO: dacScan step from 120 .. 139
[15:13:05.526] <TB1> INFO: Test took 20149ms.
[15:13:05.690] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:13:05.738] <TB1> INFO: dacScan step from 140 .. 159
[15:13:24.709] <TB1> INFO: Test took 18971ms.
[15:13:24.868] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:13:50.973] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 58.6165 for pixel 6/3 mean/min/max = 45.3839/32.0478/58.7199
[15:13:50.974] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 59.3114 for pixel 9/13 mean/min/max = 45.752/32.1247/59.3794
[15:13:50.974] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 56.585 for pixel 24/1 mean/min/max = 44.8795/33.0575/56.7014
[15:13:50.974] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 59.2279 for pixel 0/58 mean/min/max = 46.5175/33.7511/59.2839
[15:13:50.974] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 59.0621 for pixel 11/9 mean/min/max = 46.5044/33.9074/59.1013
[15:13:50.974] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 59.2805 for pixel 0/26 mean/min/max = 46.2263/32.9172/59.5354
[15:13:50.975] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 57.8892 for pixel 5/5 mean/min/max = 45.0799/31.8305/58.3292
[15:13:50.975] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 57.9242 for pixel 33/74 mean/min/max = 44.8068/31.6833/57.9303
[15:13:50.975] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 60.3002 for pixel 6/75 mean/min/max = 46.5981/32.6571/60.5391
[15:13:50.976] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 60.6764 for pixel 12/3 mean/min/max = 47.3181/33.906/60.7302
[15:13:50.976] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 62.7649 for pixel 4/76 mean/min/max = 46.9821/31.0507/62.9136
[15:13:50.976] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 60.7793 for pixel 1/74 mean/min/max = 45.9803/30.998/60.9626
[15:13:50.977] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 57.5768 for pixel 25/3 mean/min/max = 45.2091/32.7316/57.6866
[15:13:50.977] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 59.5649 for pixel 23/9 mean/min/max = 46.367/33.1689/59.5651
[15:13:50.977] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 57.581 for pixel 10/2 mean/min/max = 45.2629/32.8538/57.6719
[15:13:50.977] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.6887 for pixel 10/72 mean/min/max = 44.8915/32.0159/57.7671
[15:13:50.978] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:15:24.321] <TB1> INFO: Test took 93343ms.
[15:15:25.890] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[15:15:25.902] <TB1> INFO: dacScan step from 0 .. 19
[15:15:48.899] <TB1> INFO: Test took 22997ms.
[15:15:48.951] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:48.953] <TB1> INFO: dacScan step from 20 .. 39
[15:16:17.620] <TB1> INFO: Test took 28667ms.
[15:16:17.850] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:16:17.883] <TB1> INFO: dacScan step from 40 .. 59
[15:16:47.764] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

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

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

[15:16:48.815] <TB1> INFO: Test took 30932ms.
[15:16:49.091] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:16:49.139] <TB1> INFO: dacScan step from 60 .. 79
[15:17:21.010] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (8) != Token Chain Length (4)

[15:17:21.010] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (204) != TBM ID (205)

[15:17:21.010] <TB1> WARNING: ROC 0: Readback start marker after 15 readouts!

[15:17:21.010] <TB1> WARNING: ROC 1: Readback start marker after 15 readouts!

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

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

[15:17:22.270] <TB1> INFO: Test took 33131ms.
[15:17:22.573] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:22.624] <TB1> INFO: dacScan step from 80 .. 99
[15:17:54.853] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

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

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

[15:17:56.199] <TB1> INFO: Test took 33574ms.
[15:17:56.531] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:56.586] <TB1> INFO: dacScan step from 100 .. 119
[15:18:28.652] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (212) != TBM ID (0)

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

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

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

[15:18:29.912] <TB1> INFO: Test took 33325ms.
[15:18:30.192] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:30.242] <TB1> INFO: dacScan step from 120 .. 139
[15:19:01.953] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (211) != TBM ID (0)

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

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

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

[15:19:03.246] <TB1> INFO: Test took 33004ms.
[15:19:03.531] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:03.586] <TB1> INFO: dacScan step from 140 .. 159
[15:19:35.543] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

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

[15:19:36.828] <TB1> INFO: Test took 33242ms.
[15:19:37.120] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:37.174] <TB1> INFO: dacScan step from 160 .. 179
[15:20:09.462] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (8) != Token Chain Length (4)

[15:20:09.462] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (217) != TBM ID (218)

[15:20:09.462] <TB1> WARNING: ROC 0: Readback start marker after 15 readouts!

[15:20:09.462] <TB1> WARNING: ROC 1: Readback start marker after 15 readouts!

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

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

[15:20:10.742] <TB1> INFO: Test took 33568ms.
[15:20:11.032] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:11.083] <TB1> INFO: dacScan step from 180 .. 199
[15:20:41.474] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

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

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

[15:20:42.558] <TB1> INFO: Test took 31474ms.
[15:20:42.837] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:10.220] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.121263 .. 255.000000
[15:21:10.299] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20) hits flags = 16 (plus default)
[15:21:10.307] <TB1> INFO: dacScan step from 0 .. 19
[15:21:23.278] <TB1> INFO: Test took 12971ms.
[15:21:23.300] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:23.300] <TB1> INFO: dacScan step from 20 .. 39
[15:21:38.160] <TB1> INFO: Test took 14861ms.
[15:21:38.254] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:38.274] <TB1> INFO: dacScan step from 40 .. 59
[15:21:56.011] <TB1> INFO: Test took 17736ms.
[15:21:56.162] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:56.212] <TB1> INFO: dacScan step from 60 .. 79
[15:22:13.877] <TB1> INFO: Test took 17665ms.
[15:22:14.025] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:22:14.070] <TB1> INFO: dacScan step from 80 .. 99
[15:22:31.778] <TB1> INFO: Test took 17708ms.
[15:22:31.931] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:22:31.983] <TB1> INFO: dacScan step from 100 .. 119
[15:22:49.804] <TB1> INFO: Test took 17821ms.
[15:22:49.957] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:22:50.005] <TB1> INFO: dacScan step from 120 .. 139
[15:23:07.771] <TB1> INFO: Test took 17766ms.
[15:23:07.943] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:08.002] <TB1> INFO: dacScan step from 140 .. 159
[15:23:25.756] <TB1> INFO: Test took 17753ms.
[15:23:25.912] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:25.961] <TB1> INFO: dacScan step from 160 .. 179
[15:23:43.727] <TB1> INFO: Test took 17766ms.
[15:23:43.871] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:43.922] <TB1> INFO: dacScan step from 180 .. 199
[15:24:02.674] <TB1> INFO: Test took 18752ms.
[15:24:02.835] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:02.887] <TB1> INFO: dacScan step from 200 .. 219
[15:24:20.592] <TB1> INFO: Test took 17704ms.
[15:24:20.735] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:20.786] <TB1> INFO: dacScan step from 220 .. 239
[15:24:37.375] <TB1> INFO: Test took 16589ms.
[15:24:37.527] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:37.580] <TB1> INFO: dacScan step from 240 .. 255
[15:24:51.487] <TB1> INFO: Test took 13907ms.
[15:24:51.673] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:26.705] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 14.426458 .. 47.040404
[15:25:26.784] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 4 .. 57 (20) hits flags = 16 (plus default)
[15:25:26.794] <TB1> INFO: dacScan step from 4 .. 23
[15:25:39.787] <TB1> INFO: Test took 12993ms.
[15:25:39.810] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:39.810] <TB1> INFO: dacScan step from 24 .. 43
[15:25:54.477] <TB1> INFO: Test took 14667ms.
[15:25:54.592] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:54.624] <TB1> INFO: dacScan step from 44 .. 57
[15:26:07.996] <TB1> INFO: Test took 13372ms.
[15:26:08.133] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:24.378] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 7.365856 .. 43.867323
[15:26:24.465] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 7 .. 53 (20) hits flags = 16 (plus default)
[15:26:24.473] <TB1> INFO: dacScan step from 7 .. 26
[15:26:37.449] <TB1> INFO: Test took 12976ms.
[15:26:37.469] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:37.469] <TB1> INFO: dacScan step from 27 .. 46
[15:26:53.721] <TB1> INFO: Test took 16252ms.
[15:26:53.880] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:53.917] <TB1> INFO: dacScan step from 47 .. 53
[15:27:01.996] <TB1> INFO: Test took 8079ms.
[15:27:02.053] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:18.656] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 7.365856 .. 43.867323
[15:27:18.739] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 7 .. 53 (20) hits flags = 16 (plus default)
[15:27:18.747] <TB1> INFO: dacScan step from 7 .. 26
[15:27:31.792] <TB1> INFO: Test took 13045ms.
[15:27:31.812] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:31.812] <TB1> INFO: dacScan step from 27 .. 46
[15:27:47.854] <TB1> INFO: Test took 16041ms.
[15:27:47.980] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:48.009] <TB1> INFO: dacScan step from 47 .. 53
[15:27:56.114] <TB1> INFO: Test took 8105ms.
[15:27:56.173] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:12.659] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:28:12.659] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20) hits flags = 16 (plus default)
[15:28:12.667] <TB1> INFO: dacScan step from 15 .. 34
[15:28:35.928] <TB1> INFO: Test took 23261ms.
[15:28:35.999] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:36.011] <TB1> INFO: dacScan step from 35 .. 54
[15:29:07.336] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

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

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

[15:29:07.998] <TB1> INFO: Test took 31987ms.
[15:29:08.289] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:08.335] <TB1> INFO: dacScan step from 55 .. 55
[15:29:12.707] <TB1> INFO: Test took 4373ms.
[15:29:12.729] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:27.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:29:27.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:29:27.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:29:27.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:29:27.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:29:27.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:29:27.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:29:27.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:29:27.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:29:27.240] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:29:27.240] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:29:27.240] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:29:27.240] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:29:27.240] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:29:27.240] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:29:27.240] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:29:27.240] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C0.dat
[15:29:27.246] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C1.dat
[15:29:27.252] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C2.dat
[15:29:27.258] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C3.dat
[15:29:27.264] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C4.dat
[15:29:27.270] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C5.dat
[15:29:27.276] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C6.dat
[15:29:27.282] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C7.dat
[15:29:27.288] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C8.dat
[15:29:27.294] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C9.dat
[15:29:27.300] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C10.dat
[15:29:27.306] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C11.dat
[15:29:27.311] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C12.dat
[15:29:27.317] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C13.dat
[15:29:27.323] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C14.dat
[15:29:27.329] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//trimParameters35_C15.dat
[15:29:27.335] <TB1> INFO: PixTestTrim::trimTest() done
[15:29:27.335] <TB1> INFO: vtrim: 122 116 106 100 122 106 107 124 112 108 130 116 103 113 102 116
[15:29:27.335] <TB1> INFO: vthrcomp: 103 96 94 88 105 95 99 100 94 88 97 99 103 103 102 101
[15:29:27.335] <TB1> INFO: vcal mean: 35.08 35.12 35.06 35.11 35.06 35.05 35.03 35.09 35.10 35.13 35.09 35.14 35.12 35.10 35.12 35.04
[15:29:27.335] <TB1> INFO: vcal RMS: 1.15 1.02 0.99 1.04 1.02 1.12 1.06 1.17 1.09 1.05 1.12 1.10 1.24 1.06 1.03 1.03
[15:29:27.335] <TB1> INFO: bits mean: 9.99 9.93 10.00 9.19 9.50 9.16 10.05 10.34 9.97 9.19 9.84 10.17 10.35 9.48 10.13 10.22
[15:29:27.335] <TB1> INFO: bits RMS: 2.53 2.55 2.41 2.62 2.43 2.74 2.55 2.42 2.36 2.52 2.61 2.53 2.29 2.53 2.34 2.43
[15:29:27.340] <TB1> INFO: ----------------------------------------------------------------------
[15:29:27.340] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 240 150 100
[15:29:27.340] <TB1> INFO: ----------------------------------------------------------------------
[15:29:27.344] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[15:29:27.352] <TB1> INFO: dacScan step from 0 .. 19
[15:29:48.680] <TB1> INFO: Test took 21328ms.
[15:29:48.719] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:48.719] <TB1> INFO: dacScan step from 20 .. 39
[15:30:11.744] <TB1> INFO: Test took 23025ms.
[15:30:11.815] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:11.820] <TB1> INFO: dacScan step from 40 .. 59
[15:30:42.155] <TB1> INFO: Test took 30335ms.
[15:30:42.408] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:42.443] <TB1> INFO: dacScan step from 60 .. 79
[15:31:13.208] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

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

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

[15:31:14.485] <TB1> INFO: Test took 32042ms.
[15:31:14.765] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:14.812] <TB1> INFO: dacScan step from 80 .. 99
[15:31:47.584] <TB1> INFO: Test took 32771ms.
[15:31:47.987] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:48.040] <TB1> INFO: dacScan step from 100 .. 119
[15:32:21.153] <TB1> INFO: Test took 33113ms.
[15:32:21.519] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:21.558] <TB1> INFO: dacScan step from 120 .. 139
[15:32:54.719] <TB1> INFO: Test took 33161ms.
[15:32:54.993] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:55.038] <TB1> INFO: dacScan step from 140 .. 159
[15:33:25.408] <TB1> INFO: Test took 30370ms.
[15:33:25.681] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:25.722] <TB1> INFO: dacScan step from 160 .. 179
[15:33:58.435] <TB1> INFO: Test took 32713ms.
[15:33:58.707] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:58.753] <TB1> INFO: dacScan step from 180 .. 199
[15:34:30.801] <TB1> INFO: Test took 32048ms.
[15:34:31.079] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:55.956] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 84 (20) hits flags = 16 (plus default)
[15:34:55.964] <TB1> INFO: dacScan step from 0 .. 19
[15:35:18.702] <TB1> INFO: Test took 22738ms.
[15:35:18.742] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:18.742] <TB1> INFO: dacScan step from 20 .. 39
[15:35:42.568] <TB1> INFO: Test took 23826ms.
[15:35:42.662] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:42.671] <TB1> INFO: dacScan step from 40 .. 59
[15:36:14.122] <TB1> INFO: Test took 31451ms.
[15:36:14.400] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:14.442] <TB1> INFO: dacScan step from 60 .. 79
[15:36:47.831] <TB1> INFO: Test took 33389ms.
[15:36:48.188] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:48.236] <TB1> INFO: dacScan step from 80 .. 84
[15:36:57.864] <TB1> INFO: Test took 9628ms.
[15:36:57.945] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:15.718] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 77 (20) hits flags = 16 (plus default)
[15:37:15.726] <TB1> INFO: dacScan step from 0 .. 19
[15:37:38.549] <TB1> INFO: Test took 22823ms.
[15:37:38.592] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:38.592] <TB1> INFO: dacScan step from 20 .. 39
[15:38:03.841] <TB1> INFO: Test took 25249ms.
[15:38:03.989] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:04.006] <TB1> INFO: dacScan step from 40 .. 59
[15:38:35.098] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (38) != TBM ID (0)

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

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

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

[15:38:35.892] <TB1> INFO: Test took 31886ms.
[15:38:36.171] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:36.215] <TB1> INFO: dacScan step from 60 .. 77
[15:39:04.186] <TB1> INFO: Test took 27971ms.
[15:39:04.459] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:22.662] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 73 (20) hits flags = 16 (plus default)
[15:39:22.671] <TB1> INFO: dacScan step from 0 .. 19
[15:39:45.368] <TB1> INFO: Test took 22697ms.
[15:39:45.414] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:45.414] <TB1> INFO: dacScan step from 20 .. 39
[15:40:09.718] <TB1> INFO: Test took 24304ms.
[15:40:09.880] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:09.900] <TB1> INFO: dacScan step from 40 .. 59
[15:40:41.433] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (8) != Token Chain Length (4)

[15:40:41.433] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (189) != TBM ID (190)

[15:40:41.433] <TB1> WARNING: ROC 0: Readback start marker after 15 readouts!

[15:40:41.433] <TB1> WARNING: ROC 1: Readback start marker after 15 readouts!

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

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

[15:40:42.350] <TB1> INFO: Test took 32450ms.
[15:40:42.745] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:42.793] <TB1> INFO: dacScan step from 60 .. 73
[15:41:04.814] <TB1> INFO: Test took 22021ms.
[15:41:05.123] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:21.570] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 69 (20) hits flags = 16 (plus default)
[15:41:21.579] <TB1> INFO: dacScan step from 0 .. 19
[15:41:43.454] <TB1> INFO: Test took 21875ms.
[15:41:43.497] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:43.498] <TB1> INFO: dacScan step from 20 .. 39
[15:42:11.387] <TB1> INFO: Test took 27889ms.
[15:42:11.593] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:11.619] <TB1> INFO: dacScan step from 40 .. 59
[15:42:41.720] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

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

[15:42:42.966] <TB1> INFO: Test took 31347ms.
[15:42:43.283] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:43.328] <TB1> INFO: dacScan step from 60 .. 69
[15:43:01.305] <TB1> INFO: Test took 17977ms.
[15:43:01.437] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:17.176] <TB1> INFO: PixTestTrim::trimBitTest() done
[15:43:17.178] <TB1> INFO: PixTestTrim::doTest() done, duration: 2093 seconds
[15:43:17.872] <TB1> INFO: ######################################################################
[15:43:17.872] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[15:43:17.872] <TB1> INFO: ######################################################################
[15:43:21.342] <TB1> INFO: Test took 3468ms.
[15:43:21.365] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:25.050] <TB1> INFO: Test took 3486ms.
[15:43:25.117] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:28.450] <TB1> INFO: Test took 3317ms.
[15:43:28.514] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:28.521] <TB1> INFO: The DUT currently contains the following objects:
[15:43:28.521] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:28.521] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:28.521] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:28.521] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:28.521] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.521] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.521] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.521] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.521] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.521] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.521] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.522] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.522] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.522] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.522] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.522] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.522] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.522] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.522] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:28.522] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.625] <TB1> INFO: Test took 1103ms.
[15:43:29.626] <TB1> INFO: The DUT currently contains the following objects:
[15:43:29.626] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:29.626] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:29.626] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:29.626] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:29.626] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:29.626] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.732] <TB1> INFO: Test took 1106ms.
[15:43:30.733] <TB1> INFO: The DUT currently contains the following objects:
[15:43:30.733] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:30.733] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:30.733] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:30.733] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:30.733] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:30.733] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.837] <TB1> INFO: Test took 1104ms.
[15:43:31.838] <TB1> INFO: The DUT currently contains the following objects:
[15:43:31.843] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:31.843] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:31.843] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:31.843] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:31.843] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.843] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.843] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.843] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.843] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.843] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.843] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.844] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.844] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.844] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.844] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.844] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.844] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.844] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.844] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:31.844] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.942] <TB1> INFO: Test took 1098ms.
[15:43:32.943] <TB1> INFO: The DUT currently contains the following objects:
[15:43:32.943] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:32.943] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:32.943] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:32.943] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:32.943] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:32.943] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.047] <TB1> INFO: Test took 1104ms.
[15:43:34.048] <TB1> INFO: The DUT currently contains the following objects:
[15:43:34.048] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:34.048] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:34.048] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:34.048] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:34.048] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:34.048] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.153] <TB1> INFO: Test took 1105ms.
[15:43:35.154] <TB1> INFO: The DUT currently contains the following objects:
[15:43:35.154] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:35.154] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:35.154] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:35.154] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:35.154] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:35.154] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.259] <TB1> INFO: Test took 1105ms.
[15:43:36.260] <TB1> INFO: The DUT currently contains the following objects:
[15:43:36.260] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:36.260] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:36.260] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:36.260] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:36.260] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:36.260] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.364] <TB1> INFO: Test took 1104ms.
[15:43:37.365] <TB1> INFO: The DUT currently contains the following objects:
[15:43:37.365] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:37.365] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:37.365] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:37.365] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:37.365] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:37.365] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.470] <TB1> INFO: Test took 1105ms.
[15:43:38.471] <TB1> INFO: The DUT currently contains the following objects:
[15:43:38.471] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:38.471] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:38.471] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:38.471] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:38.471] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:38.471] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.577] <TB1> INFO: Test took 1106ms.
[15:43:39.578] <TB1> INFO: The DUT currently contains the following objects:
[15:43:39.578] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:39.578] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:39.578] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:39.578] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:39.578] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:39.578] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.682] <TB1> INFO: Test took 1104ms.
[15:43:40.683] <TB1> INFO: The DUT currently contains the following objects:
[15:43:40.683] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:40.683] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:40.683] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:40.683] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:40.683] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:40.683] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: Test took 1105ms.
[15:43:41.788] <TB1> INFO: The DUT currently contains the following objects:
[15:43:41.788] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:41.788] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:41.788] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:41.788] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:41.788] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:41.788] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.908] <TB1> INFO: Test took 1120ms.
[15:43:42.909] <TB1> INFO: The DUT currently contains the following objects:
[15:43:42.909] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:42.909] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:42.909] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:42.909] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:42.909] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:42.909] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: Test took 1104ms.
[15:43:44.013] <TB1> INFO: The DUT currently contains the following objects:
[15:43:44.013] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:44.013] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:44.013] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:44.013] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:44.013] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:44.013] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.123] <TB1> INFO: Test took 1109ms.
[15:43:45.124] <TB1> INFO: The DUT currently contains the following objects:
[15:43:45.124] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:45.124] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:43:45.124] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:43:45.125] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[15:43:45.125] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:45.125] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[15:43:46.235] <TB1> INFO: Test took 1110ms.
[15:43:46.240] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:47:33.043] <TB1> INFO: Test took 226803ms.
[15:47:34.578] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:17.903] <TB1> INFO: Test took 223325ms.
[15:51:19.736] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.744] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.753] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.761] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[15:51:19.768] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.777] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.784] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.791] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.800] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.808] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.814] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[15:51:19.821] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[15:51:19.827] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[15:51:19.834] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[15:51:19.841] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[15:51:19.847] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[15:51:19.855] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.863] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.871] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.879] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[15:51:19.886] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.893] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.902] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.910] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:51:19.967] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:51:19.967] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:51:19.967] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:51:19.967] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:51:19.967] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:51:19.967] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:51:19.967] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:51:19.967] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:51:19.968] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:51:19.968] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:51:19.968] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:51:19.968] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:51:19.968] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:51:19.968] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:51:19.968] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:51:19.968] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:51:23.285] <TB1> INFO: Test took 3313ms.
[15:51:26.874] <TB1> INFO: Test took 3302ms.
[15:51:30.432] <TB1> INFO: Test took 3280ms.
[15:51:30.711] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:31.626] <TB1> INFO: Test took 915ms.
[15:51:31.629] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:32.732] <TB1> INFO: Test took 1103ms.
[15:51:32.735] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:33.838] <TB1> INFO: Test took 1103ms.
[15:51:33.841] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:34.944] <TB1> INFO: Test took 1103ms.
[15:51:34.947] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:36.050] <TB1> INFO: Test took 1103ms.
[15:51:36.053] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:37.155] <TB1> INFO: Test took 1102ms.
[15:51:37.158] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:38.261] <TB1> INFO: Test took 1103ms.
[15:51:38.264] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:39.370] <TB1> INFO: Test took 1106ms.
[15:51:39.373] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:40.477] <TB1> INFO: Test took 1105ms.
[15:51:40.480] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:41.585] <TB1> INFO: Test took 1105ms.
[15:51:41.590] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:42.695] <TB1> INFO: Test took 1105ms.
[15:51:42.698] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:43.803] <TB1> INFO: Test took 1105ms.
[15:51:43.806] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:44.910] <TB1> INFO: Test took 1104ms.
[15:51:44.913] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:46.017] <TB1> INFO: Test took 1104ms.
[15:51:46.020] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:47.125] <TB1> INFO: Test took 1105ms.
[15:51:47.128] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:48.231] <TB1> INFO: Test took 1103ms.
[15:51:48.234] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:49.344] <TB1> INFO: Test took 1110ms.
[15:51:49.348] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:50.459] <TB1> INFO: Test took 1111ms.
[15:51:50.463] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:51.573] <TB1> INFO: Test took 1110ms.
[15:51:51.577] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:52.691] <TB1> INFO: Test took 1114ms.
[15:51:52.695] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:53.805] <TB1> INFO: Test took 1110ms.
[15:51:53.809] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:54.918] <TB1> INFO: Test took 1109ms.
[15:51:54.922] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:56.032] <TB1> INFO: Test took 1111ms.
[15:51:56.036] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:57.149] <TB1> INFO: Test took 1113ms.
[15:51:57.153] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:58.262] <TB1> INFO: Test took 1109ms.
[15:51:58.267] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:59.379] <TB1> INFO: Test took 1113ms.
[15:51:59.384] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:52:00.494] <TB1> INFO: Test took 1111ms.
[15:52:00.499] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:52:01.612] <TB1> INFO: Test took 1113ms.
[15:52:01.616] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:52:02.727] <TB1> INFO: Test took 1111ms.
[15:52:02.731] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:52:03.843] <TB1> INFO: Test took 1112ms.
[15:52:03.847] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:52:04.956] <TB1> INFO: Test took 1109ms.
[15:52:04.960] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:52:06.068] <TB1> INFO: Test took 1109ms.
[15:52:06.656] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 528 seconds
[15:52:06.656] <TB1> INFO: PH scale (per ROC): 80 74 80 77 70 78 73 74 80 68 79 80 73 68 71 72
[15:52:06.656] <TB1> INFO: PH offset (per ROC): 176 163 161 188 173 186 184 173 165 164 179 192 192 169 167 185
[15:52:06.848] <TB1> INFO: ######################################################################
[15:52:06.848] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[15:52:06.848] <TB1> INFO: ######################################################################
[15:52:06.858] <TB1> INFO: scanning low vcal = 50
[15:52:11.122] <TB1> INFO: Test took 4264ms.
[15:52:11.179] <TB1> INFO: scanning low vcal = 100
[15:52:15.420] <TB1> INFO: Test took 4241ms.
[15:52:15.514] <TB1> INFO: scanning low vcal = 150
[15:52:19.727] <TB1> INFO: Test took 4213ms.
[15:52:19.782] <TB1> INFO: scanning low vcal = 200
[15:52:23.997] <TB1> INFO: Test took 4215ms.
[15:52:24.077] <TB1> INFO: scanning low vcal = 250
[15:52:28.285] <TB1> INFO: Test took 4208ms.
[15:52:28.343] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[15:52:32.551] <TB1> INFO: Test took 4208ms.
[15:52:32.605] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[15:52:36.977] <TB1> INFO: Test took 4372ms.
[15:52:37.067] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[15:52:41.452] <TB1> INFO: Test took 4385ms.
[15:52:41.512] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[15:52:45.898] <TB1> INFO: Test took 4386ms.
[15:52:45.971] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[15:52:50.347] <TB1> INFO: Test took 4376ms.
[15:52:50.843] <TB1> INFO: PixTestGainPedestal::measure() done
[15:53:14.755] <TB1> INFO: PixTestGainPedestal::fit() done
[15:53:14.755] <TB1> INFO: non-linearity mean: 0.958 0.957 0.960 0.960 0.962 0.955 0.952 0.964 0.963 0.948 0.954 0.961 0.958 0.957 0.960 0.948
[15:53:14.755] <TB1> INFO: non-linearity RMS: 0.006 0.005 0.005 0.007 0.006 0.008 0.008 0.006 0.005 0.008 0.006 0.005 0.006 0.006 0.006 0.007
[15:53:14.755] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[15:53:14.773] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[15:53:14.791] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[15:53:14.808] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[15:53:14.836] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[15:53:14.855] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[15:53:14.872] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[15:53:14.890] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[15:53:14.908] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[15:53:14.925] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[15:53:14.943] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[15:53:14.961] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[15:53:14.978] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[15:53:14.996] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[15:53:15.013] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[15:53:15.031] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[15:53:15.048] <TB1> INFO: PixTestGainPedestal::doTest() done, duration: 68 seconds
[15:53:15.054] <TB1> INFO: enter test to run
[15:53:15.054] <TB1> INFO: test: exit no parameter change
[15:53:15.480] <TB1> QUIET: Connection to board 153 closed.
[15:53:15.559] <TB1> INFO: pXar: this is the end, my friend
MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master