[10:17:47.360] <TB2> INFO: *** Welcome to pxar ***
[10:17:47.360] <TB2> INFO: *** Today: 2016/07/18
[10:17:47.473] <TB2> INFO: *** Version: 9751-dirty
[10:17:47.473] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C15.dat
[10:17:47.474] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//tbmParameters_C0b.dat
[10:17:47.474] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//defaultMaskFile.dat
[10:17:47.474] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters_C15.dat
[10:17:47.554] <TB2> INFO: clk: 4
[10:17:47.554] <TB2> INFO: ctr: 4
[10:17:47.554] <TB2> INFO: sda: 19
[10:17:47.554] <TB2> INFO: tin: 9
[10:17:47.554] <TB2> INFO: level: 15
[10:17:47.554] <TB2> INFO: triggerdelay: 0
[10:17:47.554] <TB2> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[10:17:47.554] <TB2> INFO: Log level: INFO
[10:17:47.561] <TB2> INFO: Found DTB DTB_WXC55Z
[10:17:47.570] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[10:17:47.572] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 156
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXC55Z
MAC address: 40D85511809C
Hostname: pixelDTB156
Comment:
------------------------------------------------------
[10:17:47.575] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[10:17:49.112] <TB2> INFO: DUT info:
[10:17:49.112] <TB2> INFO: The DUT currently contains the following objects:
[10:17:49.112] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[10:17:49.112] <TB2> INFO: TBM Core alpha (0): 7 registers set
[10:17:49.112] <TB2> INFO: TBM Core beta (1): 7 registers set
[10:17:49.112] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:17:49.112] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.112] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.112] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.112] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.112] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.112] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.112] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.112] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.112] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.112] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.112] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.112] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.112] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.112] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.112] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.113] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:49.513] <TB2> INFO: enter 'restricted' command line mode
[10:17:49.513] <TB2> INFO: enter test to run
[10:17:49.513] <TB2> INFO: test: pretest no parameter change
[10:17:49.513] <TB2> INFO: running: pretest
[10:17:49.521] <TB2> INFO: ----------------------------------------------------------------------
[10:17:49.521] <TB2> INFO: PixTestPretest::programROC()
[10:17:49.521] <TB2> INFO: ----------------------------------------------------------------------
[10:18:07.535] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:18:07.535] <TB2> INFO: IA differences per ROC: 18.5 20.1 18.5 20.1 20.1 20.1 19.3 20.1 19.3 18.5 19.3 19.3 20.1 20.1 20.9 20.1
[10:18:07.642] <TB2> INFO: enter test to run
[10:18:07.642] <TB2> INFO: test: pretest no parameter change
[10:18:07.642] <TB2> INFO: running: pretest
[10:18:07.644] <TB2> INFO: ----------------------------------------------------------------------
[10:18:07.644] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:18:07.644] <TB2> INFO: ----------------------------------------------------------------------
[10:18:13.536] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 377.8 mA = 23.6125 mA/ROC
[10:18:13.537] <TB2> INFO: i(loss) [mA/ROC]: 19.3 18.5 18.5 19.3 19.3 18.5 18.5 18.5 18.5 19.3 18.5 18.5 19.3 18.5 18.5 18.5
[10:18:13.568] <TB2> INFO: enter test to run
[10:18:13.568] <TB2> INFO: test: pretest no parameter change
[10:18:13.568] <TB2> INFO: running: pretest
[10:18:13.569] <TB2> INFO: ----------------------------------------------------------------------
[10:18:13.569] <TB2> INFO: PixTestPretest::findTiming()
[10:18:13.569] <TB2> INFO: ----------------------------------------------------------------------
[10:18:13.569] <TB2> INFO: PixTestCmd::init()

[10:19:39.846] <TB2> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[10:19:39.847] <TB2> INFO: (success/tries = 100/100), width = 4
[10:19:39.847] <TB2> INFO: enter test to run
[10:19:39.847] <TB2> INFO: test: pretest no parameter change
[10:19:39.847] <TB2> INFO: running: pretest
[10:19:39.848] <TB2> INFO: ----------------------------------------------------------------------
[10:19:39.848] <TB2> INFO: PixTestPretest::findWorkingPixel()
[10:19:39.848] <TB2> INFO: ----------------------------------------------------------------------
[10:19:39.939] <TB2> INFO: Expecting 231680 events.


[10:19:50.277] <TB2> INFO: 231680 events read in total (9781ms).
[10:19:50.282] <TB2> INFO: Test took 10431ms.
[10:19:50.504] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:19:50.572] <TB2> INFO: enter test to run
[10:19:50.572] <TB2> INFO: test: pretest no parameter change
[10:19:50.572] <TB2> INFO: running: pretest
[10:19:50.573] <TB2> INFO: ----------------------------------------------------------------------
[10:19:50.573] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[10:19:50.573] <TB2> INFO: ----------------------------------------------------------------------
[10:19:50.666] <TB2> INFO: Expecting 231680 events.
[10:20:00.953] <TB2> INFO: 231680 events read in total (9731ms).
[10:20:00.958] <TB2> INFO: Test took 10381ms.
[10:20:01.202] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[10:20:01.202] <TB2> INFO: CalDel: 148 154 154 177 143 145 172 174 143 142 140 143 164 189 172 175
[10:20:01.202] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:20:01.234] <TB2> INFO: enter test to run
[10:20:01.235] <TB2> INFO: test: pretest no parameter change
[10:20:01.235] <TB2> INFO: running: pretest
[10:20:01.235] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C0.dat
[10:20:01.235] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C1.dat
[10:20:01.235] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C2.dat
[10:20:01.235] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C3.dat
[10:20:01.235] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C4.dat
[10:20:01.235] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C5.dat
[10:20:01.235] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C6.dat
[10:20:01.235] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C7.dat
[10:20:01.235] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C8.dat
[10:20:01.236] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C9.dat
[10:20:01.236] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C10.dat
[10:20:01.236] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C11.dat
[10:20:01.236] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C12.dat
[10:20:01.236] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C13.dat
[10:20:01.236] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C14.dat
[10:20:01.236] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C15.dat
[10:20:01.236] <TB2> INFO: enter test to run
[10:20:01.236] <TB2> INFO: test: fulltest no parameter change
[10:20:01.236] <TB2> INFO: running: fulltest
[10:20:01.236] <TB2> INFO: ######################################################################
[10:20:01.236] <TB2> INFO: PixTestFullTest::doTest()
[10:20:01.236] <TB2> INFO: ######################################################################
[10:20:01.237] <TB2> INFO: ######################################################################
[10:20:01.238] <TB2> INFO: PixTestAlive::doTest()
[10:20:01.238] <TB2> INFO: ######################################################################
[10:20:01.239] <TB2> INFO: ----------------------------------------------------------------------
[10:20:01.239] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:20:01.239] <TB2> INFO: ----------------------------------------------------------------------
[10:20:01.506] <TB2> INFO: Expecting 41600 events.
[10:20:05.345] <TB2> INFO: 41600 events read in total (3283ms).
[10:20:05.346] <TB2> INFO: Test took 4106ms.
[10:20:05.575] <TB2> INFO: PixTestAlive::aliveTest() done
[10:20:05.575] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 2 0 0 0 0 0 0 0 0
[10:20:05.578] <TB2> INFO: ----------------------------------------------------------------------
[10:20:05.578] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:20:05.578] <TB2> INFO: ----------------------------------------------------------------------
[10:20:05.858] <TB2> INFO: Expecting 41600 events.
[10:20:08.845] <TB2> INFO: 41600 events read in total (2430ms).
[10:20:08.845] <TB2> INFO: Test took 3266ms.
[10:20:08.846] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:20:09.089] <TB2> INFO: PixTestAlive::maskTest() done
[10:20:09.089] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:20:09.091] <TB2> INFO: ----------------------------------------------------------------------
[10:20:09.091] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:20:09.091] <TB2> INFO: ----------------------------------------------------------------------
[10:20:09.365] <TB2> INFO: Expecting 41600 events.
[10:20:13.178] <TB2> INFO: 41600 events read in total (3257ms).
[10:20:13.178] <TB2> INFO: Test took 4084ms.
[10:20:13.402] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[10:20:13.402] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:20:13.402] <TB2> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[10:20:13.402] <TB2> INFO: Decoding statistics:
[10:20:13.402] <TB2> INFO: General information:
[10:20:13.402] <TB2> INFO: 16bit words read: 0
[10:20:13.402] <TB2> INFO: valid events total: 0
[10:20:13.402] <TB2> INFO: empty events: 0
[10:20:13.402] <TB2> INFO: valid events with pixels: 0
[10:20:13.402] <TB2> INFO: valid pixel hits: 0
[10:20:13.402] <TB2> INFO: Event errors: 0
[10:20:13.402] <TB2> INFO: start marker: 0
[10:20:13.402] <TB2> INFO: stop marker: 0
[10:20:13.402] <TB2> INFO: overflow: 0
[10:20:13.402] <TB2> INFO: invalid 5bit words: 0
[10:20:13.402] <TB2> INFO: invalid XOR eye diagram: 0
[10:20:13.402] <TB2> INFO: frame (failed synchr.): 0
[10:20:13.402] <TB2> INFO: idle data (no TBM trl): 0
[10:20:13.402] <TB2> INFO: no data (only TBM hdr): 0
[10:20:13.402] <TB2> INFO: TBM errors: 0
[10:20:13.402] <TB2> INFO: flawed TBM headers: 0
[10:20:13.402] <TB2> INFO: flawed TBM trailers: 0
[10:20:13.402] <TB2> INFO: event ID mismatches: 0
[10:20:13.402] <TB2> INFO: ROC errors: 0
[10:20:13.402] <TB2> INFO: missing ROC header(s): 0
[10:20:13.402] <TB2> INFO: misplaced readback start: 0
[10:20:13.402] <TB2> INFO: Pixel decoding errors: 0
[10:20:13.402] <TB2> INFO: pixel data incomplete: 0
[10:20:13.402] <TB2> INFO: pixel address: 0
[10:20:13.402] <TB2> INFO: pulse height fill bit: 0
[10:20:13.402] <TB2> INFO: buffer corruption: 0
[10:20:13.409] <TB2> INFO: ######################################################################
[10:20:13.409] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:20:13.409] <TB2> INFO: ######################################################################
[10:20:13.412] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:20:13.425] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:20:13.425] <TB2> INFO: run 1 of 1
[10:20:13.690] <TB2> INFO: Expecting 3120000 events.
[10:20:55.523] <TB2> INFO: 874820 events read in total (41276ms).
[10:21:36.471] <TB2> INFO: 1738260 events read in total (82225ms).
[10:22:18.255] <TB2> INFO: 2617655 events read in total (124009ms).
[10:22:42.364] <TB2> INFO: 3120000 events read in total (148117ms).
[10:22:42.428] <TB2> INFO: Test took 149003ms.
[10:23:05.359] <TB2> INFO: PixTestBBMap::doTest() done, duration: 171 seconds
[10:23:05.359] <TB2> INFO: number of dead bumps (per ROC): 7 7 2 4 0 1 2 5 40 9 0 0 0 0 0 0
[10:23:05.359] <TB2> INFO: separation cut (per ROC): 94 96 93 112 99 101 69 66 61 75 87 83 69 79 75 70
[10:23:05.360] <TB2> INFO: Decoding statistics:
[10:23:05.360] <TB2> INFO: General information:
[10:23:05.360] <TB2> INFO: 16bit words read: 0
[10:23:05.360] <TB2> INFO: valid events total: 0
[10:23:05.360] <TB2> INFO: empty events: 0
[10:23:05.360] <TB2> INFO: valid events with pixels: 0
[10:23:05.360] <TB2> INFO: valid pixel hits: 0
[10:23:05.360] <TB2> INFO: Event errors: 0
[10:23:05.360] <TB2> INFO: start marker: 0
[10:23:05.360] <TB2> INFO: stop marker: 0
[10:23:05.360] <TB2> INFO: overflow: 0
[10:23:05.360] <TB2> INFO: invalid 5bit words: 0
[10:23:05.360] <TB2> INFO: invalid XOR eye diagram: 0
[10:23:05.360] <TB2> INFO: frame (failed synchr.): 0
[10:23:05.360] <TB2> INFO: idle data (no TBM trl): 0
[10:23:05.360] <TB2> INFO: no data (only TBM hdr): 0
[10:23:05.360] <TB2> INFO: TBM errors: 0
[10:23:05.360] <TB2> INFO: flawed TBM headers: 0
[10:23:05.360] <TB2> INFO: flawed TBM trailers: 0
[10:23:05.360] <TB2> INFO: event ID mismatches: 0
[10:23:05.360] <TB2> INFO: ROC errors: 0
[10:23:05.360] <TB2> INFO: missing ROC header(s): 0
[10:23:05.360] <TB2> INFO: misplaced readback start: 0
[10:23:05.360] <TB2> INFO: Pixel decoding errors: 0
[10:23:05.360] <TB2> INFO: pixel data incomplete: 0
[10:23:05.360] <TB2> INFO: pixel address: 0
[10:23:05.360] <TB2> INFO: pulse height fill bit: 0
[10:23:05.360] <TB2> INFO: buffer corruption: 0
[10:23:05.445] <TB2> INFO: ######################################################################
[10:23:05.445] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:23:05.445] <TB2> INFO: ######################################################################
[10:23:05.445] <TB2> INFO: ----------------------------------------------------------------------
[10:23:05.445] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:23:05.445] <TB2> INFO: ----------------------------------------------------------------------
[10:23:05.445] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:23:05.455] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[10:23:05.455] <TB2> INFO: run 1 of 1
[10:23:05.747] <TB2> INFO: Expecting 26208000 events.
[10:23:40.713] <TB2> INFO: 902000 events read in total (34410ms).
[10:24:14.251] <TB2> INFO: 1792650 events read in total (67948ms).
[10:24:47.581] <TB2> INFO: 2681250 events read in total (101278ms).
[10:25:21.211] <TB2> INFO: 3570600 events read in total (134908ms).
[10:25:54.953] <TB2> INFO: 4457350 events read in total (168650ms).
[10:26:28.597] <TB2> INFO: 5344450 events read in total (202294ms).
[10:27:02.455] <TB2> INFO: 6232650 events read in total (236152ms).
[10:27:35.752] <TB2> INFO: 7119750 events read in total (269449ms).
[10:28:09.506] <TB2> INFO: 8006400 events read in total (303203ms).
[10:28:43.068] <TB2> INFO: 8893250 events read in total (336765ms).
[10:29:17.076] <TB2> INFO: 9779500 events read in total (370773ms).
[10:29:50.922] <TB2> INFO: 10665250 events read in total (404619ms).
[10:30:24.374] <TB2> INFO: 11549700 events read in total (438071ms).
[10:30:57.804] <TB2> INFO: 12433150 events read in total (471501ms).
[10:31:31.527] <TB2> INFO: 13312450 events read in total (505224ms).
[10:32:04.996] <TB2> INFO: 14186050 events read in total (538693ms).
[10:32:38.177] <TB2> INFO: 15057000 events read in total (571874ms).
[10:33:11.494] <TB2> INFO: 15929900 events read in total (605191ms).
[10:33:44.852] <TB2> INFO: 16799050 events read in total (638549ms).
[10:34:17.925] <TB2> INFO: 17668650 events read in total (671622ms).
[10:34:51.365] <TB2> INFO: 18536900 events read in total (705062ms).
[10:35:24.505] <TB2> INFO: 19403000 events read in total (738202ms).
[10:35:57.719] <TB2> INFO: 20270150 events read in total (771416ms).
[10:36:30.846] <TB2> INFO: 21133850 events read in total (804543ms).
[10:37:03.918] <TB2> INFO: 21999300 events read in total (837615ms).
[10:37:36.922] <TB2> INFO: 22866600 events read in total (870619ms).
[10:38:10.213] <TB2> INFO: 23735600 events read in total (903910ms).
[10:38:43.633] <TB2> INFO: 24604400 events read in total (937330ms).
[10:39:17.323] <TB2> INFO: 25472750 events read in total (971020ms).
[10:39:45.543] <TB2> INFO: 26208000 events read in total (999240ms).
[10:39:45.572] <TB2> INFO: Test took 1000116ms.
[10:39:45.825] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:39:47.268] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:39:48.717] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:39:50.125] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:39:51.498] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:39:52.865] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:39:54.242] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:39:55.640] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:39:57.066] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:39:58.460] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:39:59.854] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:40:01.262] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:40:02.663] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:40:04.072] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:40:05.446] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:40:06.829] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:40:08.269] <TB2> INFO: PixTestScurves::scurves() done
[10:40:08.269] <TB2> INFO: Vcal mean: 88.13 85.76 82.59 101.03 98.07 94.04 87.74 79.33 85.22 84.46 91.02 86.41 87.05 99.60 93.50 80.52
[10:40:08.269] <TB2> INFO: Vcal RMS: 5.53 4.77 4.38 5.27 5.57 5.31 5.82 4.97 4.73 4.64 4.55 5.15 5.74 5.67 6.02 3.93
[10:40:08.269] <TB2> INFO: PixTestScurves::fullTest() done, duration: 1022 seconds
[10:40:08.269] <TB2> INFO: Decoding statistics:
[10:40:08.270] <TB2> INFO: General information:
[10:40:08.270] <TB2> INFO: 16bit words read: 0
[10:40:08.270] <TB2> INFO: valid events total: 0
[10:40:08.270] <TB2> INFO: empty events: 0
[10:40:08.270] <TB2> INFO: valid events with pixels: 0
[10:40:08.270] <TB2> INFO: valid pixel hits: 0
[10:40:08.270] <TB2> INFO: Event errors: 0
[10:40:08.270] <TB2> INFO: start marker: 0
[10:40:08.270] <TB2> INFO: stop marker: 0
[10:40:08.270] <TB2> INFO: overflow: 0
[10:40:08.270] <TB2> INFO: invalid 5bit words: 0
[10:40:08.270] <TB2> INFO: invalid XOR eye diagram: 0
[10:40:08.270] <TB2> INFO: frame (failed synchr.): 0
[10:40:08.270] <TB2> INFO: idle data (no TBM trl): 0
[10:40:08.270] <TB2> INFO: no data (only TBM hdr): 0
[10:40:08.270] <TB2> INFO: TBM errors: 0
[10:40:08.270] <TB2> INFO: flawed TBM headers: 0
[10:40:08.270] <TB2> INFO: flawed TBM trailers: 0
[10:40:08.270] <TB2> INFO: event ID mismatches: 0
[10:40:08.270] <TB2> INFO: ROC errors: 0
[10:40:08.270] <TB2> INFO: missing ROC header(s): 0
[10:40:08.270] <TB2> INFO: misplaced readback start: 0
[10:40:08.270] <TB2> INFO: Pixel decoding errors: 0
[10:40:08.270] <TB2> INFO: pixel data incomplete: 0
[10:40:08.270] <TB2> INFO: pixel address: 0
[10:40:08.270] <TB2> INFO: pulse height fill bit: 0
[10:40:08.270] <TB2> INFO: buffer corruption: 0
[10:40:08.339] <TB2> INFO: ######################################################################
[10:40:08.339] <TB2> INFO: PixTestTrim::doTest()
[10:40:08.339] <TB2> INFO: ######################################################################
[10:40:08.341] <TB2> INFO: ----------------------------------------------------------------------
[10:40:08.341] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:40:08.341] <TB2> INFO: ----------------------------------------------------------------------
[10:40:08.419] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:40:08.419] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:40:08.428] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:40:08.428] <TB2> INFO: run 1 of 1
[10:40:08.686] <TB2> INFO: Expecting 6281600 events.
[10:40:48.638] <TB2> INFO: 1094960 events read in total (39393ms).
[10:41:28.319] <TB2> INFO: 2182790 events read in total (79074ms).
[10:42:07.593] <TB2> INFO: 3262540 events read in total (118348ms).
[10:42:47.000] <TB2> INFO: 4349490 events read in total (157755ms).
[10:43:26.484] <TB2> INFO: 5440280 events read in total (197239ms).
[10:43:57.030] <TB2> INFO: 6281600 events read in total (227785ms).
[10:43:57.058] <TB2> INFO: Test took 228630ms.
[10:44:16.791] <TB2> INFO: ROC 0 VthrComp = 89
[10:44:16.791] <TB2> INFO: ROC 1 VthrComp = 88
[10:44:16.791] <TB2> INFO: ROC 2 VthrComp = 84
[10:44:16.792] <TB2> INFO: ROC 3 VthrComp = 99
[10:44:16.792] <TB2> INFO: ROC 4 VthrComp = 95
[10:44:16.792] <TB2> INFO: ROC 5 VthrComp = 98
[10:44:16.792] <TB2> INFO: ROC 6 VthrComp = 83
[10:44:16.792] <TB2> INFO: ROC 7 VthrComp = 78
[10:44:16.792] <TB2> INFO: ROC 8 VthrComp = 88
[10:44:16.792] <TB2> INFO: ROC 9 VthrComp = 87
[10:44:16.792] <TB2> INFO: ROC 10 VthrComp = 93
[10:44:16.792] <TB2> INFO: ROC 11 VthrComp = 87
[10:44:16.792] <TB2> INFO: ROC 12 VthrComp = 84
[10:44:16.793] <TB2> INFO: ROC 13 VthrComp = 92
[10:44:16.793] <TB2> INFO: ROC 14 VthrComp = 90
[10:44:16.793] <TB2> INFO: ROC 15 VthrComp = 83
[10:44:16.794] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:44:16.794] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:44:16.802] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:44:16.803] <TB2> INFO: run 1 of 1
[10:44:17.076] <TB2> INFO: Expecting 6281600 events.
[10:44:53.534] <TB2> INFO: 757730 events read in total (35902ms).
[10:45:29.396] <TB2> INFO: 1514120 events read in total (71764ms).
[10:46:04.585] <TB2> INFO: 2271650 events read in total (106953ms).
[10:46:40.501] <TB2> INFO: 3029470 events read in total (142869ms).
[10:47:16.270] <TB2> INFO: 3781520 events read in total (178638ms).
[10:47:51.706] <TB2> INFO: 4529350 events read in total (214074ms).
[10:48:27.216] <TB2> INFO: 5274380 events read in total (249584ms).
[10:49:02.094] <TB2> INFO: 6019790 events read in total (284462ms).
[10:49:14.383] <TB2> INFO: 6281600 events read in total (296751ms).
[10:49:14.424] <TB2> INFO: Test took 297621ms.
[10:49:38.654] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 59.9694 for pixel 8/17 mean/min/max = 46.8832/33.7838/59.9826
[10:49:38.654] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 56.986 for pixel 5/79 mean/min/max = 45.7044/34.3583/57.0505
[10:49:38.654] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 57.1749 for pixel 29/45 mean/min/max = 45.3441/33.2221/57.4662
[10:49:38.654] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 58.4114 for pixel 18/12 mean/min/max = 45.5604/32.5869/58.5338
[10:49:38.655] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 59.8167 for pixel 5/12 mean/min/max = 46.3851/32.9492/59.8211
[10:49:38.655] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 57.5549 for pixel 0/4 mean/min/max = 44.6724/31.7358/57.609
[10:49:38.655] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 63.3623 for pixel 22/1 mean/min/max = 47.7512/32.0148/63.4875
[10:49:38.655] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 62.2894 for pixel 18/7 mean/min/max = 48.6623/34.8797/62.4449
[10:49:38.656] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 57.4204 for pixel 0/45 mean/min/max = 45.9208/34.1916/57.65
[10:49:38.656] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 57.3035 for pixel 11/4 mean/min/max = 44.7186/32/57.4371
[10:49:38.656] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 56.8832 for pixel 20/18 mean/min/max = 45.3223/33.7566/56.8879
[10:49:38.657] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 57.9381 for pixel 13/27 mean/min/max = 44.9785/31.9067/58.0502
[10:49:38.657] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 64.1296 for pixel 11/6 mean/min/max = 48.0888/31.9998/64.1777
[10:49:38.657] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 65.0373 for pixel 5/0 mean/min/max = 49.1402/33.1971/65.0834
[10:49:38.657] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 64.3829 for pixel 17/2 mean/min/max = 48.5871/32.7166/64.4576
[10:49:38.658] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 55.473 for pixel 30/79 mean/min/max = 44.5186/33.1513/55.8858
[10:49:38.658] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:49:38.746] <TB2> INFO: Expecting 514560 events.
[10:49:51.830] <TB2> INFO: 514560 events read in total (12528ms).
[10:49:51.836] <TB2> INFO: Expecting 514560 events.
[10:50:04.677] <TB2> INFO: 514560 events read in total (12426ms).
[10:50:04.683] <TB2> INFO: Expecting 514560 events.
[10:50:17.489] <TB2> INFO: 514560 events read in total (12384ms).
[10:50:17.498] <TB2> INFO: Expecting 514560 events.
[10:50:30.327] <TB2> INFO: 514560 events read in total (12411ms).
[10:50:30.337] <TB2> INFO: Expecting 514560 events.
[10:50:43.183] <TB2> INFO: 514560 events read in total (12434ms).
[10:50:43.197] <TB2> INFO: Expecting 514560 events.
[10:50:56.119] <TB2> INFO: 514560 events read in total (12507ms).
[10:50:56.140] <TB2> INFO: Expecting 514560 events.
[10:51:08.922] <TB2> INFO: 514560 events read in total (12380ms).
[10:51:08.939] <TB2> INFO: Expecting 514560 events.
[10:51:21.638] <TB2> INFO: 514560 events read in total (12289ms).
[10:51:21.656] <TB2> INFO: Expecting 514560 events.
[10:51:34.496] <TB2> INFO: 514560 events read in total (12426ms).
[10:51:34.515] <TB2> INFO: Expecting 514560 events.
[10:51:47.348] <TB2> INFO: 514560 events read in total (12425ms).
[10:51:47.373] <TB2> INFO: Expecting 514560 events.
[10:52:00.206] <TB2> INFO: 514560 events read in total (12429ms).
[10:52:00.229] <TB2> INFO: Expecting 514560 events.
[10:52:12.991] <TB2> INFO: 514560 events read in total (12360ms).
[10:52:13.014] <TB2> INFO: Expecting 514560 events.
[10:52:25.829] <TB2> INFO: 514560 events read in total (12409ms).
[10:52:25.859] <TB2> INFO: Expecting 514560 events.
[10:52:38.636] <TB2> INFO: 514560 events read in total (12376ms).
[10:52:38.665] <TB2> INFO: Expecting 514560 events.
[10:52:51.489] <TB2> INFO: 514560 events read in total (12423ms).
[10:52:51.520] <TB2> INFO: Expecting 514560 events.
[10:53:04.216] <TB2> INFO: 514560 events read in total (12295ms).
[10:53:04.251] <TB2> INFO: Test took 205593ms.
[10:53:05.257] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:53:05.266] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:53:05.266] <TB2> INFO: run 1 of 1
[10:53:05.530] <TB2> INFO: Expecting 6281600 events.
[10:53:40.934] <TB2> INFO: 741100 events read in total (34848ms).
[10:54:16.194] <TB2> INFO: 1481590 events read in total (70108ms).
[10:54:51.382] <TB2> INFO: 2223190 events read in total (105296ms).
[10:55:26.742] <TB2> INFO: 2965100 events read in total (140656ms).
[10:56:01.791] <TB2> INFO: 3700900 events read in total (175705ms).
[10:56:36.773] <TB2> INFO: 4432180 events read in total (210687ms).
[10:57:11.505] <TB2> INFO: 5160080 events read in total (245419ms).
[10:57:46.321] <TB2> INFO: 5887940 events read in total (280235ms).
[10:58:05.449] <TB2> INFO: 6281600 events read in total (299363ms).
[10:58:05.497] <TB2> INFO: Test took 300231ms.
[10:58:28.632] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.022865 .. 255.000000
[10:58:28.707] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:58:28.715] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:58:28.715] <TB2> INFO: run 1 of 1
[10:58:28.976] <TB2> INFO: Expecting 10649600 events.
[10:59:04.378] <TB2> INFO: 712880 events read in total (34846ms).
[10:59:39.317] <TB2> INFO: 1425900 events read in total (69785ms).
[11:00:14.390] <TB2> INFO: 2139260 events read in total (104858ms).
[11:00:49.280] <TB2> INFO: 2852810 events read in total (139748ms).
[11:01:24.196] <TB2> INFO: 3565840 events read in total (174664ms).
[11:01:59.065] <TB2> INFO: 4279070 events read in total (209533ms).
[11:02:33.961] <TB2> INFO: 4992440 events read in total (244429ms).
[11:03:09.254] <TB2> INFO: 5705390 events read in total (279722ms).
[11:03:43.944] <TB2> INFO: 6418360 events read in total (314412ms).
[11:04:18.648] <TB2> INFO: 7130180 events read in total (349116ms).
[11:04:53.392] <TB2> INFO: 7841640 events read in total (383860ms).
[11:05:28.216] <TB2> INFO: 8552160 events read in total (418684ms).
[11:06:02.695] <TB2> INFO: 9262990 events read in total (453163ms).
[11:06:37.994] <TB2> INFO: 9973990 events read in total (488462ms).
[11:07:10.990] <TB2> INFO: 10649600 events read in total (521458ms).
[11:07:11.086] <TB2> INFO: Test took 522371ms.
[11:07:40.953] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 15.615632 .. 49.539879
[11:07:41.030] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 59 (-1/-1) hits flags = 528 (plus default)
[11:07:41.038] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[11:07:41.038] <TB2> INFO: run 1 of 1
[11:07:41.310] <TB2> INFO: Expecting 2288000 events.
[11:08:19.361] <TB2> INFO: 910650 events read in total (37495ms).
[11:08:57.001] <TB2> INFO: 1821010 events read in total (75136ms).
[11:09:16.724] <TB2> INFO: 2288000 events read in total (94859ms).
[11:09:16.751] <TB2> INFO: Test took 95714ms.
[11:09:30.646] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 1.461449 .. 49.539879
[11:09:30.723] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 1 .. 59 (-1/-1) hits flags = 528 (plus default)
[11:09:30.731] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[11:09:30.731] <TB2> INFO: run 1 of 1
[11:09:30.993] <TB2> INFO: Expecting 2454400 events.
[11:10:09.659] <TB2> INFO: 933250 events read in total (38107ms).
[11:10:48.019] <TB2> INFO: 1865570 events read in total (76467ms).
[11:11:11.939] <TB2> INFO: 2454400 events read in total (100387ms).
[11:11:11.964] <TB2> INFO: Test took 101234ms.
[11:11:27.087] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 0.365431 .. 49.539879
[11:11:27.171] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 0 .. 59 (-1/-1) hits flags = 528 (plus default)
[11:11:27.180] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[11:11:27.180] <TB2> INFO: run 1 of 1
[11:11:27.442] <TB2> INFO: Expecting 2496000 events.
[11:12:06.478] <TB2> INFO: 937810 events read in total (38480ms).
[11:12:44.828] <TB2> INFO: 1875210 events read in total (76830ms).
[11:13:09.842] <TB2> INFO: 2496000 events read in total (101844ms).
[11:13:09.859] <TB2> INFO: Test took 102679ms.
[11:13:24.959] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:13:24.959] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:13:24.967] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[11:13:24.967] <TB2> INFO: run 1 of 1
[11:13:25.227] <TB2> INFO: Expecting 1705600 events.
[11:14:03.448] <TB2> INFO: 878990 events read in total (37665ms).
[11:14:39.098] <TB2> INFO: 1705600 events read in total (73315ms).
[11:14:39.110] <TB2> INFO: Test took 74143ms.
[11:14:53.867] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C0.dat
[11:14:53.867] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C1.dat
[11:14:53.867] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C2.dat
[11:14:53.867] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C3.dat
[11:14:53.867] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C4.dat
[11:14:53.867] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C5.dat
[11:14:53.867] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C6.dat
[11:14:53.867] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C7.dat
[11:14:53.868] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C8.dat
[11:14:53.868] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C9.dat
[11:14:53.868] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C10.dat
[11:14:53.868] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C11.dat
[11:14:53.868] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C12.dat
[11:14:53.868] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C13.dat
[11:14:53.868] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C14.dat
[11:14:53.868] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C15.dat
[11:14:53.868] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C0.dat
[11:14:53.875] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C1.dat
[11:14:53.883] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C2.dat
[11:14:53.891] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C3.dat
[11:14:53.897] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C4.dat
[11:14:53.903] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C5.dat
[11:14:53.911] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C6.dat
[11:14:53.920] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C7.dat
[11:14:53.928] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C8.dat
[11:14:53.937] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C9.dat
[11:14:53.945] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C10.dat
[11:14:53.953] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C11.dat
[11:14:53.962] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C12.dat
[11:14:53.971] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C13.dat
[11:14:53.979] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C14.dat
[11:14:53.987] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C15.dat
[11:14:53.994] <TB2> INFO: PixTestTrim::trimTest() done
[11:14:53.994] <TB2> INFO: vtrim: 105 88 88 101 103 108 105 94 82 94 98 88 113 113 119 81
[11:14:53.994] <TB2> INFO: vthrcomp: 89 88 84 99 95 98 83 78 88 87 93 87 84 92 90 83
[11:14:53.994] <TB2> INFO: vcal mean: 34.94 35.01 35.03 34.94 34.95 35.00 34.97 34.99 34.99 34.97 34.94 34.98 35.00 34.96 35.00 34.94
[11:14:53.994] <TB2> INFO: vcal RMS: 0.82 0.79 0.85 0.87 1.09 2.24 0.92 1.17 0.82 0.85 0.82 0.87 1.13 1.21 0.96 0.81
[11:14:53.994] <TB2> INFO: bits mean: 9.09 8.70 9.62 9.79 9.51 9.83 9.26 8.89 8.83 9.98 9.62 9.76 9.63 9.36 9.40 9.69
[11:14:53.994] <TB2> INFO: bits RMS: 2.56 2.69 2.55 2.50 2.54 2.68 2.60 2.39 2.72 2.60 2.44 2.67 2.51 2.40 2.48 2.53
[11:14:54.001] <TB2> INFO: ----------------------------------------------------------------------
[11:14:54.001] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:14:54.001] <TB2> INFO: ----------------------------------------------------------------------
[11:14:54.004] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:14:54.012] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:14:54.012] <TB2> INFO: run 1 of 1
[11:14:54.296] <TB2> INFO: Expecting 4160000 events.
[11:15:36.935] <TB2> INFO: 935215 events read in total (42083ms).
[11:16:19.769] <TB2> INFO: 1865575 events read in total (84917ms).
[11:17:02.380] <TB2> INFO: 2786410 events read in total (127529ms).
[11:17:43.857] <TB2> INFO: 3703025 events read in total (169005ms).
[11:18:04.753] <TB2> INFO: 4160000 events read in total (189901ms).
[11:18:04.788] <TB2> INFO: Test took 190776ms.
[11:18:33.684] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 201 (-1/-1) hits flags = 528 (plus default)
[11:18:33.693] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:18:33.693] <TB2> INFO: run 1 of 1
[11:18:33.971] <TB2> INFO: Expecting 4201600 events.
[11:19:16.846] <TB2> INFO: 900815 events read in total (42318ms).
[11:19:58.090] <TB2> INFO: 1797965 events read in total (83562ms).
[11:20:38.927] <TB2> INFO: 2686900 events read in total (124399ms).
[11:21:20.262] <TB2> INFO: 3570700 events read in total (165734ms).
[11:21:49.500] <TB2> INFO: 4201600 events read in total (194972ms).
[11:21:49.547] <TB2> INFO: Test took 195854ms.
[11:22:19.872] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 153 (-1/-1) hits flags = 528 (plus default)
[11:22:19.882] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:22:19.882] <TB2> INFO: run 1 of 1
[11:22:20.159] <TB2> INFO: Expecting 3203200 events.
[11:23:04.330] <TB2> INFO: 1011225 events read in total (43615ms).
[11:23:47.795] <TB2> INFO: 2008840 events read in total (87080ms).
[11:24:31.089] <TB2> INFO: 2994480 events read in total (130374ms).
[11:24:40.376] <TB2> INFO: 3203200 events read in total (139661ms).
[11:24:40.407] <TB2> INFO: Test took 140525ms.
[11:25:04.480] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[11:25:04.489] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:25:04.489] <TB2> INFO: run 1 of 1
[11:25:04.748] <TB2> INFO: Expecting 5324800 events.
[11:25:45.290] <TB2> INFO: 837795 events read in total (39986ms).
[11:26:26.507] <TB2> INFO: 1673005 events read in total (81203ms).
[11:27:06.409] <TB2> INFO: 2506565 events read in total (121105ms).
[11:27:46.411] <TB2> INFO: 3334380 events read in total (161107ms).
[11:28:26.120] <TB2> INFO: 4160035 events read in total (200816ms).
[11:29:05.894] <TB2> INFO: 4985805 events read in total (240590ms).
[11:29:22.584] <TB2> INFO: 5324800 events read in total (257280ms).
[11:29:22.676] <TB2> INFO: Test took 258187ms.
[11:29:57.623] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 187 (-1/-1) hits flags = 528 (plus default)
[11:29:57.631] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:29:57.631] <TB2> INFO: run 1 of 1
[11:29:57.911] <TB2> INFO: Expecting 3910400 events.
[11:30:40.436] <TB2> INFO: 923690 events read in total (41966ms).
[11:31:22.490] <TB2> INFO: 1842225 events read in total (84021ms).
[11:32:03.795] <TB2> INFO: 2750180 events read in total (125326ms).
[11:32:45.223] <TB2> INFO: 3654720 events read in total (166753ms).
[11:32:57.195] <TB2> INFO: 3910400 events read in total (178726ms).
[11:32:57.241] <TB2> INFO: Test took 179610ms.
[11:33:25.333] <TB2> INFO: PixTestTrim::trimBitTest() done
[11:33:25.334] <TB2> INFO: PixTestTrim::doTest() done, duration: 3196 seconds
[11:33:25.334] <TB2> INFO: Decoding statistics:
[11:33:25.334] <TB2> INFO: General information:
[11:33:25.334] <TB2> INFO: 16bit words read: 0
[11:33:25.334] <TB2> INFO: valid events total: 0
[11:33:25.334] <TB2> INFO: empty events: 0
[11:33:25.334] <TB2> INFO: valid events with pixels: 0
[11:33:25.334] <TB2> INFO: valid pixel hits: 0
[11:33:25.334] <TB2> INFO: Event errors: 0
[11:33:25.334] <TB2> INFO: start marker: 0
[11:33:25.334] <TB2> INFO: stop marker: 0
[11:33:25.334] <TB2> INFO: overflow: 0
[11:33:25.334] <TB2> INFO: invalid 5bit words: 0
[11:33:25.334] <TB2> INFO: invalid XOR eye diagram: 0
[11:33:25.334] <TB2> INFO: frame (failed synchr.): 0
[11:33:25.334] <TB2> INFO: idle data (no TBM trl): 0
[11:33:25.334] <TB2> INFO: no data (only TBM hdr): 0
[11:33:25.334] <TB2> INFO: TBM errors: 0
[11:33:25.334] <TB2> INFO: flawed TBM headers: 0
[11:33:25.334] <TB2> INFO: flawed TBM trailers: 0
[11:33:25.334] <TB2> INFO: event ID mismatches: 0
[11:33:25.334] <TB2> INFO: ROC errors: 0
[11:33:25.334] <TB2> INFO: missing ROC header(s): 0
[11:33:25.334] <TB2> INFO: misplaced readback start: 0
[11:33:25.334] <TB2> INFO: Pixel decoding errors: 0
[11:33:25.334] <TB2> INFO: pixel data incomplete: 0
[11:33:25.334] <TB2> INFO: pixel address: 0
[11:33:25.334] <TB2> INFO: pulse height fill bit: 0
[11:33:25.334] <TB2> INFO: buffer corruption: 0
[11:33:26.019] <TB2> INFO: ######################################################################
[11:33:26.019] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:33:26.019] <TB2> INFO: ######################################################################
[11:33:26.281] <TB2> INFO: Expecting 41600 events.
[11:33:30.130] <TB2> INFO: 41600 events read in total (3292ms).
[11:33:30.131] <TB2> INFO: Test took 4111ms.
[11:33:30.612] <TB2> INFO: Expecting 41600 events.
[11:33:34.454] <TB2> INFO: 41600 events read in total (3286ms).
[11:33:34.455] <TB2> INFO: Test took 4118ms.
[11:33:34.751] <TB2> INFO: Expecting 41600 events.
[11:33:38.612] <TB2> INFO: 41600 events read in total (3304ms).
[11:33:38.612] <TB2> INFO: Test took 4131ms.
[11:33:38.867] <TB2> INFO: Expecting 2560 events.
[11:33:39.720] <TB2> INFO: 2560 events read in total (296ms).
[11:33:39.721] <TB2> INFO: Test took 1095ms.
[11:33:40.026] <TB2> INFO: Expecting 2560 events.
[11:33:40.879] <TB2> INFO: 2560 events read in total (297ms).
[11:33:40.879] <TB2> INFO: Test took 1158ms.
[11:33:41.185] <TB2> INFO: Expecting 2560 events.
[11:33:42.043] <TB2> INFO: 2560 events read in total (302ms).
[11:33:42.044] <TB2> INFO: Test took 1165ms.
[11:33:42.349] <TB2> INFO: Expecting 2560 events.
[11:33:43.206] <TB2> INFO: 2560 events read in total (301ms).
[11:33:43.206] <TB2> INFO: Test took 1162ms.
[11:33:43.512] <TB2> INFO: Expecting 2560 events.
[11:33:44.374] <TB2> INFO: 2560 events read in total (306ms).
[11:33:44.374] <TB2> INFO: Test took 1168ms.
[11:33:44.679] <TB2> INFO: Expecting 2560 events.
[11:33:45.535] <TB2> INFO: 2560 events read in total (299ms).
[11:33:45.535] <TB2> INFO: Test took 1160ms.
[11:33:45.841] <TB2> INFO: Expecting 2560 events.
[11:33:46.694] <TB2> INFO: 2560 events read in total (297ms).
[11:33:46.695] <TB2> INFO: Test took 1159ms.
[11:33:47.000] <TB2> INFO: Expecting 2560 events.
[11:33:47.854] <TB2> INFO: 2560 events read in total (298ms).
[11:33:47.854] <TB2> INFO: Test took 1159ms.
[11:33:48.160] <TB2> INFO: Expecting 2560 events.
[11:33:49.014] <TB2> INFO: 2560 events read in total (298ms).
[11:33:49.014] <TB2> INFO: Test took 1159ms.
[11:33:49.319] <TB2> INFO: Expecting 2560 events.
[11:33:50.176] <TB2> INFO: 2560 events read in total (301ms).
[11:33:50.176] <TB2> INFO: Test took 1162ms.
[11:33:50.481] <TB2> INFO: Expecting 2560 events.
[11:33:51.334] <TB2> INFO: 2560 events read in total (296ms).
[11:33:51.335] <TB2> INFO: Test took 1158ms.
[11:33:51.640] <TB2> INFO: Expecting 2560 events.
[11:33:52.494] <TB2> INFO: 2560 events read in total (298ms).
[11:33:52.494] <TB2> INFO: Test took 1159ms.
[11:33:52.799] <TB2> INFO: Expecting 2560 events.
[11:33:53.652] <TB2> INFO: 2560 events read in total (297ms).
[11:33:53.652] <TB2> INFO: Test took 1157ms.
[11:33:53.957] <TB2> INFO: Expecting 2560 events.
[11:33:54.816] <TB2> INFO: 2560 events read in total (302ms).
[11:33:54.817] <TB2> INFO: Test took 1165ms.
[11:33:55.122] <TB2> INFO: Expecting 2560 events.
[11:33:55.980] <TB2> INFO: 2560 events read in total (302ms).
[11:33:55.981] <TB2> INFO: Test took 1164ms.
[11:33:56.285] <TB2> INFO: Expecting 2560 events.
[11:33:57.138] <TB2> INFO: 2560 events read in total (297ms).
[11:33:57.138] <TB2> INFO: Test took 1157ms.
[11:33:57.141] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:33:57.445] <TB2> INFO: Expecting 655360 events.
[11:34:14.337] <TB2> INFO: 655360 events read in total (16336ms).
[11:34:14.347] <TB2> INFO: Expecting 655360 events.
[11:34:31.042] <TB2> INFO: 655360 events read in total (16293ms).
[11:34:31.055] <TB2> INFO: Expecting 655360 events.
[11:34:47.815] <TB2> INFO: 655360 events read in total (16358ms).
[11:34:47.833] <TB2> INFO: Expecting 655360 events.
[11:35:04.699] <TB2> INFO: 655360 events read in total (16464ms).
[11:35:04.728] <TB2> INFO: Expecting 655360 events.
[11:35:21.618] <TB2> INFO: 655360 events read in total (16488ms).
[11:35:21.641] <TB2> INFO: Expecting 655360 events.
[11:35:38.392] <TB2> INFO: 655360 events read in total (16349ms).
[11:35:38.419] <TB2> INFO: Expecting 655360 events.
[11:35:55.139] <TB2> INFO: 655360 events read in total (16318ms).
[11:35:55.170] <TB2> INFO: Expecting 655360 events.
[11:36:11.910] <TB2> INFO: 655360 events read in total (16338ms).
[11:36:11.943] <TB2> INFO: Expecting 655360 events.
[11:36:28.736] <TB2> INFO: 655360 events read in total (16391ms).
[11:36:28.776] <TB2> INFO: Expecting 655360 events.
[11:36:45.591] <TB2> INFO: 655360 events read in total (16413ms).
[11:36:45.636] <TB2> INFO: Expecting 655360 events.
[11:37:02.261] <TB2> INFO: 655360 events read in total (16223ms).
[11:37:02.307] <TB2> INFO: Expecting 655360 events.
[11:37:19.066] <TB2> INFO: 655360 events read in total (16357ms).
[11:37:19.115] <TB2> INFO: Expecting 655360 events.
[11:37:35.928] <TB2> INFO: 655360 events read in total (16411ms).
[11:37:35.984] <TB2> INFO: Expecting 655360 events.
[11:37:52.792] <TB2> INFO: 655360 events read in total (16406ms).
[11:37:52.847] <TB2> INFO: Expecting 655360 events.
[11:38:09.595] <TB2> INFO: 655360 events read in total (16346ms).
[11:38:09.658] <TB2> INFO: Expecting 655360 events.
[11:38:26.473] <TB2> INFO: 655360 events read in total (16413ms).
[11:38:26.534] <TB2> INFO: Test took 269393ms.
[11:38:26.614] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:26.845] <TB2> INFO: Expecting 655360 events.
[11:38:43.942] <TB2> INFO: 655360 events read in total (16541ms).
[11:38:43.952] <TB2> INFO: Expecting 655360 events.
[11:39:00.401] <TB2> INFO: 655360 events read in total (16047ms).
[11:39:00.414] <TB2> INFO: Expecting 655360 events.
[11:39:16.872] <TB2> INFO: 655360 events read in total (16057ms).
[11:39:16.888] <TB2> INFO: Expecting 655360 events.
[11:39:33.706] <TB2> INFO: 655360 events read in total (16416ms).
[11:39:33.725] <TB2> INFO: Expecting 655360 events.
[11:39:50.521] <TB2> INFO: 655360 events read in total (16394ms).
[11:39:50.543] <TB2> INFO: Expecting 655360 events.
[11:40:07.289] <TB2> INFO: 655360 events read in total (16344ms).
[11:40:07.316] <TB2> INFO: Expecting 655360 events.
[11:40:24.246] <TB2> INFO: 655360 events read in total (16528ms).
[11:40:24.276] <TB2> INFO: Expecting 655360 events.
[11:40:41.148] <TB2> INFO: 655360 events read in total (16471ms).
[11:40:41.184] <TB2> INFO: Expecting 655360 events.
[11:40:57.873] <TB2> INFO: 655360 events read in total (16288ms).
[11:40:57.915] <TB2> INFO: Expecting 655360 events.
[11:41:14.387] <TB2> INFO: 655360 events read in total (16070ms).
[11:41:14.433] <TB2> INFO: Expecting 655360 events.
[11:41:31.089] <TB2> INFO: 655360 events read in total (16255ms).
[11:41:31.137] <TB2> INFO: Expecting 655360 events.
[11:41:47.544] <TB2> INFO: 655360 events read in total (16003ms).
[11:41:47.599] <TB2> INFO: Expecting 655360 events.
[11:42:04.104] <TB2> INFO: 655360 events read in total (16103ms).
[11:42:04.159] <TB2> INFO: Expecting 655360 events.
[11:42:21.164] <TB2> INFO: 655360 events read in total (16603ms).
[11:42:21.223] <TB2> INFO: Expecting 655360 events.
[11:42:38.133] <TB2> INFO: 655360 events read in total (16509ms).
[11:42:38.187] <TB2> INFO: Expecting 655360 events.
[11:42:55.058] <TB2> INFO: 655360 events read in total (16469ms).
[11:42:55.130] <TB2> INFO: Test took 268516ms.
[11:42:55.325] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.332] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.340] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.347] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.354] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.360] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.367] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.374] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.381] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.388] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.396] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[11:42:55.404] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[11:42:55.412] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[11:42:55.419] <TB2> INFO: safety margin for low PH: adding 4, margin is now 24
[11:42:55.425] <TB2> INFO: safety margin for low PH: adding 5, margin is now 25
[11:42:55.432] <TB2> INFO: safety margin for low PH: adding 6, margin is now 26
[11:42:55.441] <TB2> INFO: safety margin for low PH: adding 7, margin is now 27
[11:42:55.449] <TB2> INFO: safety margin for low PH: adding 8, margin is now 28
[11:42:55.458] <TB2> INFO: safety margin for low PH: adding 9, margin is now 29
[11:42:55.465] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.471] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.478] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.486] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.494] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.502] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:42:55.569] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C0.dat
[11:42:55.569] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C1.dat
[11:42:55.569] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C2.dat
[11:42:55.570] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C3.dat
[11:42:55.570] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C4.dat
[11:42:55.570] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C5.dat
[11:42:55.570] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C6.dat
[11:42:55.570] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C7.dat
[11:42:55.570] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C8.dat
[11:42:55.571] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C9.dat
[11:42:55.571] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C10.dat
[11:42:55.571] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C11.dat
[11:42:55.571] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C12.dat
[11:42:55.571] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C13.dat
[11:42:55.571] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C14.dat
[11:42:55.571] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C15.dat
[11:42:55.850] <TB2> INFO: Expecting 41600 events.
[11:42:59.325] <TB2> INFO: 41600 events read in total (2919ms).
[11:42:59.326] <TB2> INFO: Test took 3752ms.
[11:42:59.763] <TB2> INFO: Expecting 41600 events.
[11:43:03.275] <TB2> INFO: 41600 events read in total (2956ms).
[11:43:03.275] <TB2> INFO: Test took 3770ms.
[11:43:03.756] <TB2> INFO: Expecting 41600 events.
[11:43:07.270] <TB2> INFO: 41600 events read in total (2958ms).
[11:43:07.281] <TB2> INFO: Test took 3825ms.
[11:43:07.473] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:07.562] <TB2> INFO: Expecting 2560 events.
[11:43:08.419] <TB2> INFO: 2560 events read in total (301ms).
[11:43:08.420] <TB2> INFO: Test took 947ms.
[11:43:08.422] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:08.726] <TB2> INFO: Expecting 2560 events.
[11:43:09.583] <TB2> INFO: 2560 events read in total (301ms).
[11:43:09.583] <TB2> INFO: Test took 1161ms.
[11:43:09.586] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:09.890] <TB2> INFO: Expecting 2560 events.
[11:43:10.748] <TB2> INFO: 2560 events read in total (302ms).
[11:43:10.749] <TB2> INFO: Test took 1163ms.
[11:43:10.757] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:11.055] <TB2> INFO: Expecting 2560 events.
[11:43:11.913] <TB2> INFO: 2560 events read in total (302ms).
[11:43:11.913] <TB2> INFO: Test took 1156ms.
[11:43:11.916] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:12.219] <TB2> INFO: Expecting 2560 events.
[11:43:13.080] <TB2> INFO: 2560 events read in total (305ms).
[11:43:13.081] <TB2> INFO: Test took 1165ms.
[11:43:13.083] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:13.387] <TB2> INFO: Expecting 2560 events.
[11:43:14.243] <TB2> INFO: 2560 events read in total (300ms).
[11:43:14.243] <TB2> INFO: Test took 1160ms.
[11:43:14.245] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:14.550] <TB2> INFO: Expecting 2560 events.
[11:43:15.406] <TB2> INFO: 2560 events read in total (300ms).
[11:43:15.406] <TB2> INFO: Test took 1161ms.
[11:43:15.409] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:15.712] <TB2> INFO: Expecting 2560 events.
[11:43:16.570] <TB2> INFO: 2560 events read in total (302ms).
[11:43:16.570] <TB2> INFO: Test took 1161ms.
[11:43:16.572] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:16.877] <TB2> INFO: Expecting 2560 events.
[11:43:17.736] <TB2> INFO: 2560 events read in total (303ms).
[11:43:17.737] <TB2> INFO: Test took 1165ms.
[11:43:17.739] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:18.043] <TB2> INFO: Expecting 2560 events.
[11:43:18.902] <TB2> INFO: 2560 events read in total (303ms).
[11:43:18.903] <TB2> INFO: Test took 1164ms.
[11:43:18.905] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:19.208] <TB2> INFO: Expecting 2560 events.
[11:43:20.062] <TB2> INFO: 2560 events read in total (298ms).
[11:43:20.062] <TB2> INFO: Test took 1158ms.
[11:43:20.065] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:20.367] <TB2> INFO: Expecting 2560 events.
[11:43:21.225] <TB2> INFO: 2560 events read in total (301ms).
[11:43:21.225] <TB2> INFO: Test took 1160ms.
[11:43:21.228] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:21.531] <TB2> INFO: Expecting 2560 events.
[11:43:22.384] <TB2> INFO: 2560 events read in total (297ms).
[11:43:22.384] <TB2> INFO: Test took 1157ms.
[11:43:22.386] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:22.691] <TB2> INFO: Expecting 2560 events.
[11:43:23.549] <TB2> INFO: 2560 events read in total (302ms).
[11:43:23.549] <TB2> INFO: Test took 1163ms.
[11:43:23.552] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:23.855] <TB2> INFO: Expecting 2560 events.
[11:43:24.714] <TB2> INFO: 2560 events read in total (302ms).
[11:43:24.714] <TB2> INFO: Test took 1162ms.
[11:43:24.716] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:25.022] <TB2> INFO: Expecting 2560 events.
[11:43:25.881] <TB2> INFO: 2560 events read in total (303ms).
[11:43:25.881] <TB2> INFO: Test took 1165ms.
[11:43:25.884] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:26.187] <TB2> INFO: Expecting 2560 events.
[11:43:27.045] <TB2> INFO: 2560 events read in total (301ms).
[11:43:27.045] <TB2> INFO: Test took 1161ms.
[11:43:27.048] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:27.351] <TB2> INFO: Expecting 2560 events.
[11:43:28.205] <TB2> INFO: 2560 events read in total (298ms).
[11:43:28.206] <TB2> INFO: Test took 1158ms.
[11:43:28.208] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:28.512] <TB2> INFO: Expecting 2560 events.
[11:43:29.368] <TB2> INFO: 2560 events read in total (300ms).
[11:43:29.368] <TB2> INFO: Test took 1160ms.
[11:43:29.371] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:29.674] <TB2> INFO: Expecting 2560 events.
[11:43:30.532] <TB2> INFO: 2560 events read in total (302ms).
[11:43:30.532] <TB2> INFO: Test took 1161ms.
[11:43:30.535] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:30.837] <TB2> INFO: Expecting 2560 events.
[11:43:31.693] <TB2> INFO: 2560 events read in total (299ms).
[11:43:31.694] <TB2> INFO: Test took 1159ms.
[11:43:31.696] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:32.001] <TB2> INFO: Expecting 2560 events.
[11:43:32.854] <TB2> INFO: 2560 events read in total (297ms).
[11:43:32.855] <TB2> INFO: Test took 1159ms.
[11:43:32.858] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:33.160] <TB2> INFO: Expecting 2560 events.
[11:43:34.016] <TB2> INFO: 2560 events read in total (299ms).
[11:43:34.017] <TB2> INFO: Test took 1160ms.
[11:43:34.019] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:34.322] <TB2> INFO: Expecting 2560 events.
[11:43:35.181] <TB2> INFO: 2560 events read in total (302ms).
[11:43:35.181] <TB2> INFO: Test took 1162ms.
[11:43:35.184] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:35.487] <TB2> INFO: Expecting 2560 events.
[11:43:36.347] <TB2> INFO: 2560 events read in total (304ms).
[11:43:36.348] <TB2> INFO: Test took 1164ms.
[11:43:36.350] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:36.654] <TB2> INFO: Expecting 2560 events.
[11:43:37.514] <TB2> INFO: 2560 events read in total (302ms).
[11:43:37.514] <TB2> INFO: Test took 1164ms.
[11:43:37.517] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:37.820] <TB2> INFO: Expecting 2560 events.
[11:43:38.672] <TB2> INFO: 2560 events read in total (296ms).
[11:43:38.672] <TB2> INFO: Test took 1155ms.
[11:43:38.674] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:38.978] <TB2> INFO: Expecting 2560 events.
[11:43:39.834] <TB2> INFO: 2560 events read in total (299ms).
[11:43:39.835] <TB2> INFO: Test took 1161ms.
[11:43:39.838] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:40.141] <TB2> INFO: Expecting 2560 events.
[11:43:40.994] <TB2> INFO: 2560 events read in total (297ms).
[11:43:40.994] <TB2> INFO: Test took 1157ms.
[11:43:40.997] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:41.300] <TB2> INFO: Expecting 2560 events.
[11:43:42.154] <TB2> INFO: 2560 events read in total (298ms).
[11:43:42.154] <TB2> INFO: Test took 1157ms.
[11:43:42.156] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:42.460] <TB2> INFO: Expecting 2560 events.
[11:43:43.316] <TB2> INFO: 2560 events read in total (299ms).
[11:43:43.316] <TB2> INFO: Test took 1160ms.
[11:43:43.318] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:43:43.622] <TB2> INFO: Expecting 2560 events.
[11:43:44.476] <TB2> INFO: 2560 events read in total (298ms).
[11:43:44.476] <TB2> INFO: Test took 1158ms.
[11:43:44.958] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 618 seconds
[11:43:44.958] <TB2> INFO: PH scale (per ROC): 68 76 79 73 70 75 72 75 74 78 81 79 68 67 66 76
[11:43:44.958] <TB2> INFO: PH offset (per ROC): 163 184 173 177 179 169 186 167 179 176 173 170 170 175 187 167
[11:43:44.965] <TB2> INFO: Decoding statistics:
[11:43:44.965] <TB2> INFO: General information:
[11:43:44.965] <TB2> INFO: 16bit words read: 91932
[11:43:44.965] <TB2> INFO: valid events total: 10240
[11:43:44.965] <TB2> INFO: empty events: 7737
[11:43:44.965] <TB2> INFO: valid events with pixels: 2503
[11:43:44.965] <TB2> INFO: valid pixel hits: 2503
[11:43:44.965] <TB2> INFO: Event errors: 0
[11:43:44.966] <TB2> INFO: start marker: 0
[11:43:44.966] <TB2> INFO: stop marker: 0
[11:43:44.966] <TB2> INFO: overflow: 0
[11:43:44.966] <TB2> INFO: invalid 5bit words: 0
[11:43:44.966] <TB2> INFO: invalid XOR eye diagram: 0
[11:43:44.966] <TB2> INFO: frame (failed synchr.): 0
[11:43:44.966] <TB2> INFO: idle data (no TBM trl): 0
[11:43:44.966] <TB2> INFO: no data (only TBM hdr): 0
[11:43:44.966] <TB2> INFO: TBM errors: 0
[11:43:44.966] <TB2> INFO: flawed TBM headers: 0
[11:43:44.966] <TB2> INFO: flawed TBM trailers: 0
[11:43:44.966] <TB2> INFO: event ID mismatches: 0
[11:43:44.966] <TB2> INFO: ROC errors: 0
[11:43:44.966] <TB2> INFO: missing ROC header(s): 0
[11:43:44.966] <TB2> INFO: misplaced readback start: 0
[11:43:44.966] <TB2> INFO: Pixel decoding errors: 0
[11:43:44.966] <TB2> INFO: pixel data incomplete: 0
[11:43:44.966] <TB2> INFO: pixel address: 0
[11:43:44.966] <TB2> INFO: pulse height fill bit: 0
[11:43:44.966] <TB2> INFO: buffer corruption: 0
[11:43:45.138] <TB2> INFO: ######################################################################
[11:43:45.138] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:43:45.138] <TB2> INFO: ######################################################################
[11:43:45.148] <TB2> INFO: scanning low vcal = 10
[11:43:45.420] <TB2> INFO: Expecting 41600 events.
[11:43:48.974] <TB2> INFO: 41600 events read in total (2998ms).
[11:43:48.974] <TB2> INFO: Test took 3826ms.
[11:43:48.976] <TB2> INFO: scanning low vcal = 20
[11:43:49.281] <TB2> INFO: Expecting 41600 events.
[11:43:52.850] <TB2> INFO: 41600 events read in total (3012ms).
[11:43:52.850] <TB2> INFO: Test took 3874ms.
[11:43:52.852] <TB2> INFO: scanning low vcal = 30
[11:43:53.153] <TB2> INFO: Expecting 41600 events.
[11:43:56.727] <TB2> INFO: 41600 events read in total (3017ms).
[11:43:56.728] <TB2> INFO: Test took 3876ms.
[11:43:56.729] <TB2> INFO: scanning low vcal = 40
[11:43:57.023] <TB2> INFO: Expecting 41600 events.
[11:44:01.183] <TB2> INFO: 41600 events read in total (3603ms).
[11:44:01.184] <TB2> INFO: Test took 4455ms.
[11:44:01.186] <TB2> INFO: scanning low vcal = 50
[11:44:01.463] <TB2> INFO: Expecting 41600 events.
[11:44:05.671] <TB2> INFO: 41600 events read in total (3652ms).
[11:44:05.672] <TB2> INFO: Test took 4485ms.
[11:44:05.674] <TB2> INFO: scanning low vcal = 60
[11:44:05.953] <TB2> INFO: Expecting 41600 events.
[11:44:10.181] <TB2> INFO: 41600 events read in total (3672ms).
[11:44:10.182] <TB2> INFO: Test took 4508ms.
[11:44:10.184] <TB2> INFO: scanning low vcal = 70
[11:44:10.444] <TB2> INFO: Expecting 41600 events.
[11:44:14.692] <TB2> INFO: 41600 events read in total (3692ms).
[11:44:14.692] <TB2> INFO: Test took 4508ms.
[11:44:14.695] <TB2> INFO: scanning low vcal = 80
[11:44:14.953] <TB2> INFO: Expecting 41600 events.
[11:44:19.154] <TB2> INFO: 41600 events read in total (3645ms).
[11:44:19.154] <TB2> INFO: Test took 4459ms.
[11:44:19.157] <TB2> INFO: scanning low vcal = 90
[11:44:19.441] <TB2> INFO: Expecting 41600 events.
[11:44:23.680] <TB2> INFO: 41600 events read in total (3682ms).
[11:44:23.681] <TB2> INFO: Test took 4524ms.
[11:44:23.684] <TB2> INFO: scanning low vcal = 100
[11:44:23.970] <TB2> INFO: Expecting 41600 events.
[11:44:28.178] <TB2> INFO: 41600 events read in total (3652ms).
[11:44:28.179] <TB2> INFO: Test took 4495ms.
[11:44:28.182] <TB2> INFO: scanning low vcal = 110
[11:44:28.443] <TB2> INFO: Expecting 41600 events.
[11:44:32.713] <TB2> INFO: 41600 events read in total (3714ms).
[11:44:32.714] <TB2> INFO: Test took 4532ms.
[11:44:32.716] <TB2> INFO: scanning low vcal = 120
[11:44:33.000] <TB2> INFO: Expecting 41600 events.
[11:44:37.256] <TB2> INFO: 41600 events read in total (3699ms).
[11:44:37.257] <TB2> INFO: Test took 4541ms.
[11:44:37.259] <TB2> INFO: scanning low vcal = 130
[11:44:37.570] <TB2> INFO: Expecting 41600 events.
[11:44:41.837] <TB2> INFO: 41600 events read in total (3710ms).
[11:44:41.838] <TB2> INFO: Test took 4578ms.
[11:44:41.840] <TB2> INFO: scanning low vcal = 140
[11:44:42.120] <TB2> INFO: Expecting 41600 events.
[11:44:46.380] <TB2> INFO: 41600 events read in total (3704ms).
[11:44:46.381] <TB2> INFO: Test took 4541ms.
[11:44:46.383] <TB2> INFO: scanning low vcal = 150
[11:44:46.638] <TB2> INFO: Expecting 41600 events.
[11:44:50.907] <TB2> INFO: 41600 events read in total (3712ms).
[11:44:50.907] <TB2> INFO: Test took 4524ms.
[11:44:50.910] <TB2> INFO: scanning low vcal = 160
[11:44:51.194] <TB2> INFO: Expecting 41600 events.
[11:44:55.440] <TB2> INFO: 41600 events read in total (3690ms).
[11:44:55.441] <TB2> INFO: Test took 4531ms.
[11:44:55.444] <TB2> INFO: scanning low vcal = 170
[11:44:55.751] <TB2> INFO: Expecting 41600 events.
[11:45:00.013] <TB2> INFO: 41600 events read in total (3706ms).
[11:45:00.014] <TB2> INFO: Test took 4570ms.
[11:45:00.017] <TB2> INFO: scanning low vcal = 180
[11:45:00.296] <TB2> INFO: Expecting 41600 events.
[11:45:04.482] <TB2> INFO: 41600 events read in total (3630ms).
[11:45:04.482] <TB2> INFO: Test took 4465ms.
[11:45:04.485] <TB2> INFO: scanning low vcal = 190
[11:45:04.740] <TB2> INFO: Expecting 41600 events.
[11:45:08.893] <TB2> INFO: 41600 events read in total (3597ms).
[11:45:08.893] <TB2> INFO: Test took 4408ms.
[11:45:08.896] <TB2> INFO: scanning low vcal = 200
[11:45:09.148] <TB2> INFO: Expecting 41600 events.
[11:45:13.302] <TB2> INFO: 41600 events read in total (3598ms).
[11:45:13.302] <TB2> INFO: Test took 4406ms.
[11:45:13.305] <TB2> INFO: scanning low vcal = 210
[11:45:13.559] <TB2> INFO: Expecting 41600 events.
[11:45:17.710] <TB2> INFO: 41600 events read in total (3595ms).
[11:45:17.711] <TB2> INFO: Test took 4406ms.
[11:45:17.714] <TB2> INFO: scanning low vcal = 220
[11:45:17.966] <TB2> INFO: Expecting 41600 events.
[11:45:22.118] <TB2> INFO: 41600 events read in total (3596ms).
[11:45:22.119] <TB2> INFO: Test took 4405ms.
[11:45:22.121] <TB2> INFO: scanning low vcal = 230
[11:45:22.374] <TB2> INFO: Expecting 41600 events.
[11:45:26.526] <TB2> INFO: 41600 events read in total (3596ms).
[11:45:26.527] <TB2> INFO: Test took 4406ms.
[11:45:26.529] <TB2> INFO: scanning low vcal = 240
[11:45:26.782] <TB2> INFO: Expecting 41600 events.
[11:45:30.935] <TB2> INFO: 41600 events read in total (3597ms).
[11:45:30.936] <TB2> INFO: Test took 4407ms.
[11:45:30.938] <TB2> INFO: scanning low vcal = 250
[11:45:31.192] <TB2> INFO: Expecting 41600 events.
[11:45:35.347] <TB2> INFO: 41600 events read in total (3598ms).
[11:45:35.347] <TB2> INFO: Test took 4409ms.
[11:45:35.351] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[11:45:35.605] <TB2> INFO: Expecting 41600 events.
[11:45:39.757] <TB2> INFO: 41600 events read in total (3596ms).
[11:45:39.758] <TB2> INFO: Test took 4407ms.
[11:45:39.760] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[11:45:40.012] <TB2> INFO: Expecting 41600 events.
[11:45:44.163] <TB2> INFO: 41600 events read in total (3595ms).
[11:45:44.164] <TB2> INFO: Test took 4404ms.
[11:45:44.167] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[11:45:44.420] <TB2> INFO: Expecting 41600 events.
[11:45:48.571] <TB2> INFO: 41600 events read in total (3595ms).
[11:45:48.572] <TB2> INFO: Test took 4405ms.
[11:45:48.574] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[11:45:48.828] <TB2> INFO: Expecting 41600 events.
[11:45:52.984] <TB2> INFO: 41600 events read in total (3599ms).
[11:45:52.985] <TB2> INFO: Test took 4410ms.
[11:45:52.987] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:45:53.242] <TB2> INFO: Expecting 41600 events.
[11:45:57.397] <TB2> INFO: 41600 events read in total (3598ms).
[11:45:57.397] <TB2> INFO: Test took 4410ms.
[11:45:57.819] <TB2> INFO: PixTestGainPedestal::measure() done
[11:46:28.420] <TB2> INFO: PixTestGainPedestal::fit() done
[11:46:28.420] <TB2> INFO: non-linearity mean: 0.952 0.959 0.956 0.953 0.953 0.955 0.961 0.957 0.958 0.963 0.960 0.955 0.958 0.960 0.952 0.951
[11:46:28.420] <TB2> INFO: non-linearity RMS: 0.007 0.006 0.006 0.009 0.007 0.007 0.007 0.007 0.006 0.004 0.006 0.007 0.006 0.007 0.008 0.007
[11:46:28.420] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[11:46:28.438] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[11:46:28.455] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[11:46:28.472] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[11:46:28.490] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[11:46:28.507] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[11:46:28.524] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[11:46:28.541] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[11:46:28.559] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[11:46:28.576] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[11:46:28.593] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[11:46:28.610] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[11:46:28.627] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[11:46:28.645] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[11:46:28.662] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[11:46:28.679] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[11:46:28.696] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 163 seconds
[11:46:28.696] <TB2> INFO: Decoding statistics:
[11:46:28.696] <TB2> INFO: General information:
[11:46:28.696] <TB2> INFO: 16bit words read: 2662400
[11:46:28.696] <TB2> INFO: valid events total: 166400
[11:46:28.696] <TB2> INFO: empty events: 0
[11:46:28.696] <TB2> INFO: valid events with pixels: 166400
[11:46:28.696] <TB2> INFO: valid pixel hits: 665536
[11:46:28.696] <TB2> INFO: Event errors: 0
[11:46:28.696] <TB2> INFO: start marker: 0
[11:46:28.696] <TB2> INFO: stop marker: 0
[11:46:28.696] <TB2> INFO: overflow: 0
[11:46:28.696] <TB2> INFO: invalid 5bit words: 0
[11:46:28.696] <TB2> INFO: invalid XOR eye diagram: 0
[11:46:28.696] <TB2> INFO: frame (failed synchr.): 0
[11:46:28.696] <TB2> INFO: idle data (no TBM trl): 0
[11:46:28.696] <TB2> INFO: no data (only TBM hdr): 0
[11:46:28.696] <TB2> INFO: TBM errors: 0
[11:46:28.696] <TB2> INFO: flawed TBM headers: 0
[11:46:28.696] <TB2> INFO: flawed TBM trailers: 0
[11:46:28.696] <TB2> INFO: event ID mismatches: 0
[11:46:28.696] <TB2> INFO: ROC errors: 0
[11:46:28.696] <TB2> INFO: missing ROC header(s): 0
[11:46:28.696] <TB2> INFO: misplaced readback start: 0
[11:46:28.696] <TB2> INFO: Pixel decoding errors: 0
[11:46:28.696] <TB2> INFO: pixel data incomplete: 0
[11:46:28.696] <TB2> INFO: pixel address: 0
[11:46:28.696] <TB2> INFO: pulse height fill bit: 0
[11:46:28.696] <TB2> INFO: buffer corruption: 0
[11:46:28.704] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C15.dat
[11:46:28.705] <TB2> INFO: ######################################################################
[11:46:28.705] <TB2> INFO: PixTestReadback::doTest()
[11:46:28.705] <TB2> INFO: ######################################################################
[11:46:28.705] <TB2> INFO: ----------------------------------------------------------------------
[11:46:28.705] <TB2> INFO: PixTestReadback::CalibrateVd()
[11:46:28.705] <TB2> INFO: ----------------------------------------------------------------------
[11:46:38.156] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C0.dat
[11:46:38.156] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C1.dat
[11:46:38.156] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C2.dat
[11:46:38.156] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C3.dat
[11:46:38.156] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C4.dat
[11:46:38.157] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C5.dat
[11:46:38.157] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C6.dat
[11:46:38.157] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C7.dat
[11:46:38.157] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C8.dat
[11:46:38.157] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C9.dat
[11:46:38.157] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C10.dat
[11:46:38.157] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C11.dat
[11:46:38.157] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C12.dat
[11:46:38.157] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C13.dat
[11:46:38.157] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C14.dat
[11:46:38.157] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C15.dat
[11:46:38.187] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:46:38.187] <TB2> INFO: ----------------------------------------------------------------------
[11:46:38.187] <TB2> INFO: PixTestReadback::CalibrateVa()
[11:46:38.187] <TB2> INFO: ----------------------------------------------------------------------
[11:46:47.751] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C0.dat
[11:46:47.751] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C1.dat
[11:46:47.751] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C2.dat
[11:46:47.751] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C3.dat
[11:46:47.752] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C4.dat
[11:46:47.752] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C5.dat
[11:46:47.752] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C6.dat
[11:46:47.752] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C7.dat
[11:46:47.752] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C8.dat
[11:46:47.752] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C9.dat
[11:46:47.752] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C10.dat
[11:46:47.752] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C11.dat
[11:46:47.752] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C12.dat
[11:46:47.752] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C13.dat
[11:46:47.752] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C14.dat
[11:46:47.752] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C15.dat
[11:46:47.783] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:46:47.783] <TB2> INFO: ----------------------------------------------------------------------
[11:46:47.783] <TB2> INFO: PixTestReadback::readbackVbg()
[11:46:47.783] <TB2> INFO: ----------------------------------------------------------------------
[11:46:55.045] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:46:55.045] <TB2> INFO: ----------------------------------------------------------------------
[11:46:55.045] <TB2> INFO: PixTestReadback::getCalibratedVbg()
[11:46:55.045] <TB2> INFO: ----------------------------------------------------------------------
[11:46:55.045] <TB2> INFO: Vbg will be calibrated using Vd calibration
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151.9calibrated Vbg = 1.21972 :::*/*/*/*/
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 140calibrated Vbg = 1.2242 :::*/*/*/*/
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157.2calibrated Vbg = 1.22401 :::*/*/*/*/
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 157.9calibrated Vbg = 1.2263 :::*/*/*/*/
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 152.6calibrated Vbg = 1.23279 :::*/*/*/*/
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 154.9calibrated Vbg = 1.2309 :::*/*/*/*/
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 157.1calibrated Vbg = 1.23466 :::*/*/*/*/
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 159.9calibrated Vbg = 1.23021 :::*/*/*/*/
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 151.7calibrated Vbg = 1.22744 :::*/*/*/*/
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 153.6calibrated Vbg = 1.23039 :::*/*/*/*/
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 158.8calibrated Vbg = 1.23112 :::*/*/*/*/
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 151.3calibrated Vbg = 1.22923 :::*/*/*/*/
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 152calibrated Vbg = 1.22685 :::*/*/*/*/
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 155.9calibrated Vbg = 1.22149 :::*/*/*/*/
[11:46:55.045] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 146.2calibrated Vbg = 1.22482 :::*/*/*/*/
[11:46:55.046] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 144.9calibrated Vbg = 1.22577 :::*/*/*/*/
[11:46:55.047] <TB2> INFO: ----------------------------------------------------------------------
[11:46:55.047] <TB2> INFO: PixTestReadback::CalibrateIa()
[11:46:55.047] <TB2> INFO: ----------------------------------------------------------------------
[11:49:30.238] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C0.dat
[11:49:30.238] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C1.dat
[11:49:30.238] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C2.dat
[11:49:30.239] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C3.dat
[11:49:30.239] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C4.dat
[11:49:30.239] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C5.dat
[11:49:30.239] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C6.dat
[11:49:30.239] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C7.dat
[11:49:30.239] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C8.dat
[11:49:30.239] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C9.dat
[11:49:30.239] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C10.dat
[11:49:30.239] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C11.dat
[11:49:30.239] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C12.dat
[11:49:30.239] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C13.dat
[11:49:30.240] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C14.dat
[11:49:30.240] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2273_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C15.dat
[11:49:30.270] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:49:30.271] <TB2> INFO: PixTestReadback::doTest() done
[11:49:30.271] <TB2> INFO: Decoding statistics:
[11:49:30.271] <TB2> INFO: General information:
[11:49:30.271] <TB2> INFO: 16bit words read: 1024
[11:49:30.271] <TB2> INFO: valid events total: 128
[11:49:30.271] <TB2> INFO: empty events: 128
[11:49:30.271] <TB2> INFO: valid events with pixels: 0
[11:49:30.271] <TB2> INFO: valid pixel hits: 0
[11:49:30.271] <TB2> INFO: Event errors: 0
[11:49:30.271] <TB2> INFO: start marker: 0
[11:49:30.271] <TB2> INFO: stop marker: 0
[11:49:30.271] <TB2> INFO: overflow: 0
[11:49:30.271] <TB2> INFO: invalid 5bit words: 0
[11:49:30.271] <TB2> INFO: invalid XOR eye diagram: 0
[11:49:30.271] <TB2> INFO: frame (failed synchr.): 0
[11:49:30.271] <TB2> INFO: idle data (no TBM trl): 0
[11:49:30.271] <TB2> INFO: no data (only TBM hdr): 0
[11:49:30.271] <TB2> INFO: TBM errors: 0
[11:49:30.271] <TB2> INFO: flawed TBM headers: 0
[11:49:30.271] <TB2> INFO: flawed TBM trailers: 0
[11:49:30.271] <TB2> INFO: event ID mismatches: 0
[11:49:30.271] <TB2> INFO: ROC errors: 0
[11:49:30.271] <TB2> INFO: missing ROC header(s): 0
[11:49:30.271] <TB2> INFO: misplaced readback start: 0
[11:49:30.271] <TB2> INFO: Pixel decoding errors: 0
[11:49:30.271] <TB2> INFO: pixel data incomplete: 0
[11:49:30.271] <TB2> INFO: pixel address: 0
[11:49:30.271] <TB2> INFO: pulse height fill bit: 0
[11:49:30.271] <TB2> INFO: buffer corruption: 0
[11:49:30.289] <TB2> INFO: Decoding statistics:
[11:49:30.289] <TB2> INFO: General information:
[11:49:30.289] <TB2> INFO: 16bit words read: 2755356
[11:49:30.289] <TB2> INFO: valid events total: 176768
[11:49:30.289] <TB2> INFO: empty events: 7865
[11:49:30.289] <TB2> INFO: valid events with pixels: 168903
[11:49:30.289] <TB2> INFO: valid pixel hits: 668039
[11:49:30.289] <TB2> INFO: Event errors: 0
[11:49:30.289] <TB2> INFO: start marker: 0
[11:49:30.289] <TB2> INFO: stop marker: 0
[11:49:30.289] <TB2> INFO: overflow: 0
[11:49:30.289] <TB2> INFO: invalid 5bit words: 0
[11:49:30.289] <TB2> INFO: invalid XOR eye diagram: 0
[11:49:30.289] <TB2> INFO: frame (failed synchr.): 0
[11:49:30.289] <TB2> INFO: idle data (no TBM trl): 0
[11:49:30.289] <TB2> INFO: no data (only TBM hdr): 0
[11:49:30.289] <TB2> INFO: TBM errors: 0
[11:49:30.289] <TB2> INFO: flawed TBM headers: 0
[11:49:30.289] <TB2> INFO: flawed TBM trailers: 0
[11:49:30.289] <TB2> INFO: event ID mismatches: 0
[11:49:30.289] <TB2> INFO: ROC errors: 0
[11:49:30.289] <TB2> INFO: missing ROC header(s): 0
[11:49:30.289] <TB2> INFO: misplaced readback start: 0
[11:49:30.289] <TB2> INFO: Pixel decoding errors: 0
[11:49:30.289] <TB2> INFO: pixel data incomplete: 0
[11:49:30.289] <TB2> INFO: pixel address: 0
[11:49:30.289] <TB2> INFO: pulse height fill bit: 0
[11:49:30.289] <TB2> INFO: buffer corruption: 0
[11:49:30.289] <TB2> INFO: enter test to run
[11:49:30.289] <TB2> INFO: test: exit no parameter change
[11:49:30.414] <TB2> QUIET: Connection to board 156 closed.
[11:49:30.494] <TB2> INFO: pXar: this is the end, my friend