[09:45:25.530] <TB3> INFO: *** Welcome to pxar ***
[09:45:25.530] <TB3> INFO: *** Today: 2016/04/10
[09:45:25.635] <TB3> INFO: *** Version: 9751-dirty
[09:45:25.636] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C15.dat
[09:45:25.637] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:45:25.637] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//defaultMaskFile.dat
[09:45:25.637] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters_C15.dat
[09:45:25.732] <TB3> INFO: clk: 4
[09:45:25.732] <TB3> INFO: ctr: 4
[09:45:25.732] <TB3> INFO: sda: 19
[09:45:25.732] <TB3> INFO: tin: 9
[09:45:25.732] <TB3> INFO: level: 15
[09:45:25.732] <TB3> INFO: triggerdelay: 0
[09:45:25.732] <TB3> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[09:45:25.732] <TB3> INFO: Log level: INFO
[09:45:25.739] <TB3> INFO: Found DTB DTB_WZ4I6J
[09:45:25.748] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[09:45:25.750] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[09:45:25.751] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[09:45:27.293] <TB3> INFO: DUT info:
[09:45:27.293] <TB3> INFO: The DUT currently contains the following objects:
[09:45:27.293] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:45:27.293] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:45:27.293] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:45:27.293] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:45:27.293] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.293] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.293] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.294] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.294] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.294] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.294] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.294] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.294] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.294] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.294] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.294] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.294] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.294] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.294] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.294] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:27.695] <TB3> INFO: enter 'restricted' command line mode
[09:45:27.695] <TB3> INFO: enter test to run
[09:45:27.695] <TB3> INFO: test: pretest no parameter change
[09:45:27.695] <TB3> INFO: running: pretest
[09:45:27.702] <TB3> INFO: ----------------------------------------------------------------------
[09:45:27.702] <TB3> INFO: PixTestPretest::programROC()
[09:45:27.702] <TB3> INFO: ----------------------------------------------------------------------
[09:45:45.717] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:45:45.717] <TB3> INFO: IA differences per ROC: 19.3 17.7 18.5 18.5 17.7 20.1 19.3 18.5 19.3 20.1 19.3 20.1 19.3 18.5 19.3 19.3
[09:45:45.815] <TB3> INFO: enter test to run
[09:45:45.815] <TB3> INFO: test: pretest no parameter change
[09:45:45.815] <TB3> INFO: running: pretest
[09:45:45.817] <TB3> INFO: ----------------------------------------------------------------------
[09:45:45.817] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:45:45.817] <TB3> INFO: ----------------------------------------------------------------------
[09:46:07.091] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 388.3 mA = 24.2687 mA/ROC
[09:46:07.091] <TB3> INFO: i(loss) [mA/ROC]: 20.1 20.1 19.3 20.1 20.1 20.1 19.3 20.1 20.1 19.3 19.3 20.1 20.1 19.3 20.1 19.3
[09:46:07.119] <TB3> INFO: enter test to run
[09:46:07.119] <TB3> INFO: test: pretest no parameter change
[09:46:07.119] <TB3> INFO: running: pretest
[09:46:07.119] <TB3> INFO: ----------------------------------------------------------------------
[09:46:07.119] <TB3> INFO: PixTestPretest::findTiming()
[09:46:07.119] <TB3> INFO: ----------------------------------------------------------------------
[09:46:07.119] <TB3> INFO: PixTestCmd::init()

[09:47:36.645] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 3, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[09:47:36.646] <TB3> INFO: (success/tries = 100/100), width = 4
[09:47:36.647] <TB3> INFO: enter test to run
[09:47:36.647] <TB3> INFO: test: pretest no parameter change
[09:47:36.647] <TB3> INFO: running: pretest
[09:47:36.648] <TB3> INFO: ----------------------------------------------------------------------
[09:47:36.648] <TB3> INFO: PixTestPretest::findWorkingPixel()
[09:47:36.648] <TB3> INFO: ----------------------------------------------------------------------
[09:47:36.744] <TB3> INFO: Expecting 231680 events.


[09:47:46.935] <TB3> INFO: 231680 events read in total (9635ms).
[09:47:46.939] <TB3> INFO: Test took 10284ms.
[09:47:47.159] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:47:47.222] <TB3> INFO: enter test to run
[09:47:47.222] <TB3> INFO: test: pretest no parameter change
[09:47:47.222] <TB3> INFO: running: pretest
[09:47:47.223] <TB3> INFO: ----------------------------------------------------------------------
[09:47:47.223] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[09:47:47.223] <TB3> INFO: ----------------------------------------------------------------------
[09:47:47.314] <TB3> INFO: Expecting 231680 events.
[09:47:57.519] <TB3> INFO: 231680 events read in total (9649ms).
[09:47:57.522] <TB3> INFO: Test took 10296ms.
[09:47:57.756] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[09:47:57.756] <TB3> INFO: CalDel: 144 131 132 138 171 144 154 134 137 144 132 121 150 159 134 159
[09:47:57.756] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 52 51 51 51 51 51 51 51 51
[09:47:57.792] <TB3> INFO: enter test to run
[09:47:57.792] <TB3> INFO: test: pretest no parameter change
[09:47:57.792] <TB3> INFO: running: pretest
[09:47:57.792] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C0.dat
[09:47:57.792] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C1.dat
[09:47:57.792] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C2.dat
[09:47:57.792] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C3.dat
[09:47:57.792] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C4.dat
[09:47:57.792] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C5.dat
[09:47:57.792] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C6.dat
[09:47:57.793] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C7.dat
[09:47:57.793] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C8.dat
[09:47:57.793] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C9.dat
[09:47:57.793] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C10.dat
[09:47:57.793] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C11.dat
[09:47:57.793] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C12.dat
[09:47:57.793] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C13.dat
[09:47:57.794] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C14.dat
[09:47:57.794] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters_C15.dat
[09:47:57.794] <TB3> INFO: enter test to run
[09:47:57.794] <TB3> INFO: test: fulltest no parameter change
[09:47:57.794] <TB3> INFO: running: fulltest
[09:47:57.794] <TB3> INFO: ######################################################################
[09:47:57.794] <TB3> INFO: PixTestFullTest::doTest()
[09:47:57.794] <TB3> INFO: ######################################################################
[09:47:57.795] <TB3> INFO: ######################################################################
[09:47:57.795] <TB3> INFO: PixTestAlive::doTest()
[09:47:57.795] <TB3> INFO: ######################################################################
[09:47:57.796] <TB3> INFO: ----------------------------------------------------------------------
[09:47:57.796] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:47:57.796] <TB3> INFO: ----------------------------------------------------------------------
[09:47:58.061] <TB3> INFO: Expecting 41600 events.
[09:48:01.821] <TB3> INFO: 41600 events read in total (3204ms).
[09:48:01.821] <TB3> INFO: Test took 4024ms.
[09:48:02.052] <TB3> INFO: PixTestAlive::aliveTest() done
[09:48:02.052] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 1 0 0 0
[09:48:02.054] <TB3> INFO: ----------------------------------------------------------------------
[09:48:02.054] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:48:02.054] <TB3> INFO: ----------------------------------------------------------------------
[09:48:02.353] <TB3> INFO: Expecting 41600 events.
[09:48:05.315] <TB3> INFO: 41600 events read in total (2406ms).
[09:48:05.315] <TB3> INFO: Test took 3259ms.
[09:48:05.316] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:48:05.553] <TB3> INFO: PixTestAlive::maskTest() done
[09:48:05.553] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:48:05.554] <TB3> INFO: ----------------------------------------------------------------------
[09:48:05.554] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:48:05.554] <TB3> INFO: ----------------------------------------------------------------------
[09:48:05.823] <TB3> INFO: Expecting 41600 events.
[09:48:09.619] <TB3> INFO: 41600 events read in total (3239ms).
[09:48:09.619] <TB3> INFO: Test took 4063ms.
[09:48:09.856] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[09:48:09.856] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:48:09.856] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[09:48:09.856] <TB3> INFO: Decoding statistics:
[09:48:09.856] <TB3> INFO: General information:
[09:48:09.856] <TB3> INFO: 16bit words read: 0
[09:48:09.856] <TB3> INFO: valid events total: 0
[09:48:09.856] <TB3> INFO: empty events: 0
[09:48:09.856] <TB3> INFO: valid events with pixels: 0
[09:48:09.856] <TB3> INFO: valid pixel hits: 0
[09:48:09.856] <TB3> INFO: Event errors: 0
[09:48:09.856] <TB3> INFO: start marker: 0
[09:48:09.856] <TB3> INFO: stop marker: 0
[09:48:09.856] <TB3> INFO: overflow: 0
[09:48:09.856] <TB3> INFO: invalid 5bit words: 0
[09:48:09.856] <TB3> INFO: invalid XOR eye diagram: 0
[09:48:09.856] <TB3> INFO: frame (failed synchr.): 0
[09:48:09.856] <TB3> INFO: idle data (no TBM trl): 0
[09:48:09.856] <TB3> INFO: no data (only TBM hdr): 0
[09:48:09.856] <TB3> INFO: TBM errors: 0
[09:48:09.856] <TB3> INFO: flawed TBM headers: 0
[09:48:09.856] <TB3> INFO: flawed TBM trailers: 0
[09:48:09.856] <TB3> INFO: event ID mismatches: 0
[09:48:09.856] <TB3> INFO: ROC errors: 0
[09:48:09.856] <TB3> INFO: missing ROC header(s): 0
[09:48:09.856] <TB3> INFO: misplaced readback start: 0
[09:48:09.856] <TB3> INFO: Pixel decoding errors: 0
[09:48:09.856] <TB3> INFO: pixel data incomplete: 0
[09:48:09.856] <TB3> INFO: pixel address: 0
[09:48:09.856] <TB3> INFO: pulse height fill bit: 0
[09:48:09.856] <TB3> INFO: buffer corruption: 0
[09:48:09.863] <TB3> INFO: ######################################################################
[09:48:09.863] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:48:09.863] <TB3> INFO: ######################################################################
[09:48:09.865] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:48:09.876] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:48:09.876] <TB3> INFO: run 1 of 1
[09:48:10.148] <TB3> INFO: Expecting 3120000 events.
[09:48:51.279] <TB3> INFO: 862990 events read in total (40574ms).
[09:49:31.849] <TB3> INFO: 1715805 events read in total (81144ms).
[09:50:12.766] <TB3> INFO: 2578180 events read in total (122062ms).
[09:50:38.832] <TB3> INFO: 3120000 events read in total (148127ms).
[09:50:38.881] <TB3> INFO: Test took 149005ms.
[09:51:03.636] <TB3> INFO: PixTestBBMap::doTest() done, duration: 173 seconds
[09:51:03.636] <TB3> INFO: number of dead bumps (per ROC): 4 2 0 1 1 0 1 0 6 3 2 3 9 10 10 20
[09:51:03.636] <TB3> INFO: separation cut (per ROC): 91 93 101 99 79 85 89 100 95 67 93 98 80 79 103 66
[09:51:03.636] <TB3> INFO: Decoding statistics:
[09:51:03.636] <TB3> INFO: General information:
[09:51:03.636] <TB3> INFO: 16bit words read: 0
[09:51:03.636] <TB3> INFO: valid events total: 0
[09:51:03.636] <TB3> INFO: empty events: 0
[09:51:03.636] <TB3> INFO: valid events with pixels: 0
[09:51:03.636] <TB3> INFO: valid pixel hits: 0
[09:51:03.636] <TB3> INFO: Event errors: 0
[09:51:03.636] <TB3> INFO: start marker: 0
[09:51:03.636] <TB3> INFO: stop marker: 0
[09:51:03.636] <TB3> INFO: overflow: 0
[09:51:03.636] <TB3> INFO: invalid 5bit words: 0
[09:51:03.636] <TB3> INFO: invalid XOR eye diagram: 0
[09:51:03.636] <TB3> INFO: frame (failed synchr.): 0
[09:51:03.636] <TB3> INFO: idle data (no TBM trl): 0
[09:51:03.636] <TB3> INFO: no data (only TBM hdr): 0
[09:51:03.636] <TB3> INFO: TBM errors: 0
[09:51:03.636] <TB3> INFO: flawed TBM headers: 0
[09:51:03.636] <TB3> INFO: flawed TBM trailers: 0
[09:51:03.636] <TB3> INFO: event ID mismatches: 0
[09:51:03.636] <TB3> INFO: ROC errors: 0
[09:51:03.636] <TB3> INFO: missing ROC header(s): 0
[09:51:03.636] <TB3> INFO: misplaced readback start: 0
[09:51:03.636] <TB3> INFO: Pixel decoding errors: 0
[09:51:03.636] <TB3> INFO: pixel data incomplete: 0
[09:51:03.636] <TB3> INFO: pixel address: 0
[09:51:03.636] <TB3> INFO: pulse height fill bit: 0
[09:51:03.636] <TB3> INFO: buffer corruption: 0
[09:51:03.718] <TB3> INFO: ######################################################################
[09:51:03.718] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:51:03.718] <TB3> INFO: ######################################################################
[09:51:03.718] <TB3> INFO: ----------------------------------------------------------------------
[09:51:03.718] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:51:03.718] <TB3> INFO: ----------------------------------------------------------------------
[09:51:03.718] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:51:03.726] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:51:03.726] <TB3> INFO: run 1 of 1
[09:51:04.015] <TB3> INFO: Expecting 26208000 events.
[09:51:37.448] <TB3> INFO: 904000 events read in total (32875ms).
[09:52:10.508] <TB3> INFO: 1793300 events read in total (65935ms).
[09:52:43.503] <TB3> INFO: 2681100 events read in total (98930ms).
[09:53:16.874] <TB3> INFO: 3569650 events read in total (132301ms).
[09:53:49.832] <TB3> INFO: 4452700 events read in total (165259ms).
[09:54:22.722] <TB3> INFO: 5335850 events read in total (198149ms).
[09:54:55.976] <TB3> INFO: 6221350 events read in total (231403ms).
[09:55:29.173] <TB3> INFO: 7102150 events read in total (264600ms).
[09:56:01.926] <TB3> INFO: 7980900 events read in total (297353ms).
[09:56:35.022] <TB3> INFO: 8862300 events read in total (330449ms).
[09:57:08.058] <TB3> INFO: 9744400 events read in total (363485ms).
[09:57:41.073] <TB3> INFO: 10624850 events read in total (396500ms).
[09:58:13.788] <TB3> INFO: 11500700 events read in total (429215ms).
[09:58:46.991] <TB3> INFO: 12377600 events read in total (462418ms).
[09:59:19.606] <TB3> INFO: 13251550 events read in total (495033ms).
[09:59:52.517] <TB3> INFO: 14120300 events read in total (527944ms).
[10:00:25.318] <TB3> INFO: 14984700 events read in total (560745ms).
[10:00:58.106] <TB3> INFO: 15851950 events read in total (593533ms).
[10:01:30.833] <TB3> INFO: 16718350 events read in total (626260ms).
[10:02:03.572] <TB3> INFO: 17581550 events read in total (658999ms).
[10:02:36.359] <TB3> INFO: 18446750 events read in total (691786ms).
[10:03:08.839] <TB3> INFO: 19311200 events read in total (724266ms).
[10:03:41.541] <TB3> INFO: 20173250 events read in total (756968ms).
[10:04:13.874] <TB3> INFO: 21034000 events read in total (789301ms).
[10:04:46.399] <TB3> INFO: 21897400 events read in total (821827ms).
[10:05:18.958] <TB3> INFO: 22759700 events read in total (854385ms).
[10:05:51.451] <TB3> INFO: 23620750 events read in total (886878ms).
[10:06:24.208] <TB3> INFO: 24485450 events read in total (919635ms).
[10:06:56.722] <TB3> INFO: 25350350 events read in total (952149ms).
[10:07:28.433] <TB3> INFO: 26208000 events read in total (983860ms).
[10:07:28.466] <TB3> INFO: Test took 984739ms.
[10:07:28.703] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:30.151] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:31.767] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:33.318] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:34.870] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:36.617] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:38.353] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:40.045] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:41.684] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:43.189] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:44.714] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:46.160] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:47.599] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:49.085] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:50.540] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:51.955] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:07:53.476] <TB3> INFO: PixTestScurves::scurves() done
[10:07:53.476] <TB3> INFO: Vcal mean: 90.04 89.92 95.37 100.23 84.34 80.26 92.97 105.80 103.15 75.41 91.01 92.81 79.50 85.02 100.41 78.14
[10:07:53.476] <TB3> INFO: Vcal RMS: 5.79 5.69 5.81 6.02 4.84 4.07 5.49 5.98 6.05 4.39 5.57 5.02 6.16 5.36 5.84 4.49
[10:07:53.476] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1009 seconds
[10:07:53.476] <TB3> INFO: Decoding statistics:
[10:07:53.476] <TB3> INFO: General information:
[10:07:53.477] <TB3> INFO: 16bit words read: 0
[10:07:53.477] <TB3> INFO: valid events total: 0
[10:07:53.477] <TB3> INFO: empty events: 0
[10:07:53.477] <TB3> INFO: valid events with pixels: 0
[10:07:53.477] <TB3> INFO: valid pixel hits: 0
[10:07:53.477] <TB3> INFO: Event errors: 0
[10:07:53.477] <TB3> INFO: start marker: 0
[10:07:53.477] <TB3> INFO: stop marker: 0
[10:07:53.477] <TB3> INFO: overflow: 0
[10:07:53.477] <TB3> INFO: invalid 5bit words: 0
[10:07:53.477] <TB3> INFO: invalid XOR eye diagram: 0
[10:07:53.477] <TB3> INFO: frame (failed synchr.): 0
[10:07:53.477] <TB3> INFO: idle data (no TBM trl): 0
[10:07:53.477] <TB3> INFO: no data (only TBM hdr): 0
[10:07:53.477] <TB3> INFO: TBM errors: 0
[10:07:53.477] <TB3> INFO: flawed TBM headers: 0
[10:07:53.477] <TB3> INFO: flawed TBM trailers: 0
[10:07:53.477] <TB3> INFO: event ID mismatches: 0
[10:07:53.477] <TB3> INFO: ROC errors: 0
[10:07:53.477] <TB3> INFO: missing ROC header(s): 0
[10:07:53.477] <TB3> INFO: misplaced readback start: 0
[10:07:53.477] <TB3> INFO: Pixel decoding errors: 0
[10:07:53.477] <TB3> INFO: pixel data incomplete: 0
[10:07:53.477] <TB3> INFO: pixel address: 0
[10:07:53.477] <TB3> INFO: pulse height fill bit: 0
[10:07:53.477] <TB3> INFO: buffer corruption: 0
[10:07:53.555] <TB3> INFO: ######################################################################
[10:07:53.555] <TB3> INFO: PixTestTrim::doTest()
[10:07:53.555] <TB3> INFO: ######################################################################
[10:07:53.556] <TB3> INFO: ----------------------------------------------------------------------
[10:07:53.556] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:07:53.556] <TB3> INFO: ----------------------------------------------------------------------
[10:07:53.634] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:07:53.634] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:07:53.642] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:07:53.642] <TB3> INFO: run 1 of 1
[10:07:53.901] <TB3> INFO: Expecting 6281600 events.
[10:08:33.873] <TB3> INFO: 1074680 events read in total (39416ms).
[10:09:13.181] <TB3> INFO: 2143010 events read in total (78724ms).
[10:09:52.230] <TB3> INFO: 3204770 events read in total (117773ms).
[10:10:31.252] <TB3> INFO: 4270110 events read in total (156795ms).
[10:11:10.452] <TB3> INFO: 5339150 events read in total (195995ms).
[10:11:45.125] <TB3> INFO: 6281600 events read in total (230668ms).
[10:11:45.161] <TB3> INFO: Test took 231519ms.
[10:12:04.747] <TB3> INFO: ROC 0 VthrComp = 92
[10:12:04.747] <TB3> INFO: ROC 1 VthrComp = 94
[10:12:04.748] <TB3> INFO: ROC 2 VthrComp = 95
[10:12:04.748] <TB3> INFO: ROC 3 VthrComp = 101
[10:12:04.748] <TB3> INFO: ROC 4 VthrComp = 87
[10:12:04.748] <TB3> INFO: ROC 5 VthrComp = 87
[10:12:04.748] <TB3> INFO: ROC 6 VthrComp = 91
[10:12:04.748] <TB3> INFO: ROC 7 VthrComp = 100
[10:12:04.748] <TB3> INFO: ROC 8 VthrComp = 100
[10:12:04.748] <TB3> INFO: ROC 9 VthrComp = 78
[10:12:04.748] <TB3> INFO: ROC 10 VthrComp = 94
[10:12:04.748] <TB3> INFO: ROC 11 VthrComp = 99
[10:12:04.749] <TB3> INFO: ROC 12 VthrComp = 80
[10:12:04.749] <TB3> INFO: ROC 13 VthrComp = 86
[10:12:04.749] <TB3> INFO: ROC 14 VthrComp = 101
[10:12:04.749] <TB3> INFO: ROC 15 VthrComp = 82
[10:12:04.749] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:12:04.749] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:12:04.757] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:12:04.757] <TB3> INFO: run 1 of 1
[10:12:05.019] <TB3> INFO: Expecting 6281600 events.
[10:12:40.327] <TB3> INFO: 756490 events read in total (34752ms).
[10:13:14.831] <TB3> INFO: 1511210 events read in total (69256ms).
[10:13:49.589] <TB3> INFO: 2266490 events read in total (104014ms).
[10:14:24.861] <TB3> INFO: 3022380 events read in total (139286ms).
[10:14:59.465] <TB3> INFO: 3772830 events read in total (173890ms).
[10:15:34.314] <TB3> INFO: 4519350 events read in total (208739ms).
[10:16:08.893] <TB3> INFO: 5264470 events read in total (243318ms).
[10:16:44.212] <TB3> INFO: 6009550 events read in total (278637ms).
[10:16:57.078] <TB3> INFO: 6281600 events read in total (291503ms).
[10:16:57.117] <TB3> INFO: Test took 292360ms.
[10:17:22.230] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 60.3589 for pixel 32/79 mean/min/max = 46.2965/32.1904/60.4026
[10:17:22.230] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 59.7046 for pixel 0/9 mean/min/max = 45.71/31.576/59.844
[10:17:22.231] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 60.2724 for pixel 0/69 mean/min/max = 46.0284/31.6578/60.399
[10:17:22.231] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 59.8576 for pixel 27/5 mean/min/max = 45.7751/31.685/59.8651
[10:17:22.231] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 58.0552 for pixel 51/0 mean/min/max = 44.9481/31.8306/58.0656
[10:17:22.231] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 55.4649 for pixel 17/71 mean/min/max = 44.0674/32.1466/55.9882
[10:17:22.232] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 58.662 for pixel 0/12 mean/min/max = 45.9337/32.7263/59.1412
[10:17:22.232] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 62.6727 for pixel 42/6 mean/min/max = 47.3546/31.7287/62.9805
[10:17:22.232] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 63.864 for pixel 0/52 mean/min/max = 48.0543/32.1624/63.9463
[10:17:22.232] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 59.9507 for pixel 23/74 mean/min/max = 47.6131/35.2635/59.9628
[10:17:22.233] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 58.8731 for pixel 2/66 mean/min/max = 45.2578/31.5743/58.9413
[10:17:22.233] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 57.2625 for pixel 11/0 mean/min/max = 44.7388/32.0088/57.4688
[10:17:22.233] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 64.3088 for pixel 0/68 mean/min/max = 47.6339/30.8647/64.4031
[10:17:22.234] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 58.7812 for pixel 30/79 mean/min/max = 45.0664/31.3378/58.795
[10:17:22.234] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 61.6467 for pixel 9/0 mean/min/max = 46.8645/31.8275/61.9015
[10:17:22.234] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 57.8637 for pixel 0/7 mean/min/max = 45.2113/32.3803/58.0423
[10:17:22.234] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:17:22.326] <TB3> INFO: Expecting 514560 events.
[10:17:35.479] <TB3> INFO: 514560 events read in total (12597ms).
[10:17:35.485] <TB3> INFO: Expecting 514560 events.
[10:17:48.338] <TB3> INFO: 514560 events read in total (12436ms).
[10:17:48.345] <TB3> INFO: Expecting 514560 events.
[10:18:01.209] <TB3> INFO: 514560 events read in total (12441ms).
[10:18:01.217] <TB3> INFO: Expecting 514560 events.
[10:18:14.084] <TB3> INFO: 514560 events read in total (12449ms).
[10:18:14.094] <TB3> INFO: Expecting 514560 events.
[10:18:26.939] <TB3> INFO: 514560 events read in total (12432ms).
[10:18:26.951] <TB3> INFO: Expecting 514560 events.
[10:18:39.819] <TB3> INFO: 514560 events read in total (12453ms).
[10:18:39.833] <TB3> INFO: Expecting 514560 events.
[10:18:52.575] <TB3> INFO: 514560 events read in total (12330ms).
[10:18:52.590] <TB3> INFO: Expecting 514560 events.
[10:19:05.465] <TB3> INFO: 514560 events read in total (12464ms).
[10:19:05.482] <TB3> INFO: Expecting 514560 events.
[10:19:18.381] <TB3> INFO: 514560 events read in total (12485ms).
[10:19:18.402] <TB3> INFO: Expecting 514560 events.
[10:19:31.292] <TB3> INFO: 514560 events read in total (12485ms).
[10:19:31.316] <TB3> INFO: Expecting 514560 events.
[10:19:44.276] <TB3> INFO: 514560 events read in total (12558ms).
[10:19:44.299] <TB3> INFO: Expecting 514560 events.
[10:19:57.299] <TB3> INFO: 514560 events read in total (12598ms).
[10:19:57.326] <TB3> INFO: Expecting 514560 events.
[10:20:10.194] <TB3> INFO: 514560 events read in total (12466ms).
[10:20:10.222] <TB3> INFO: Expecting 514560 events.
[10:20:22.864] <TB3> INFO: 514560 events read in total (12241ms).
[10:20:22.893] <TB3> INFO: Expecting 514560 events.
[10:20:35.642] <TB3> INFO: 514560 events read in total (12347ms).
[10:20:35.674] <TB3> INFO: Expecting 514560 events.
[10:20:48.411] <TB3> INFO: 514560 events read in total (12335ms).
[10:20:48.446] <TB3> INFO: Test took 206212ms.
[10:20:49.398] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:20:49.406] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:20:49.406] <TB3> INFO: run 1 of 1
[10:20:49.691] <TB3> INFO: Expecting 6281600 events.
[10:21:24.869] <TB3> INFO: 742690 events read in total (34622ms).
[10:21:59.862] <TB3> INFO: 1484100 events read in total (69615ms).
[10:22:34.838] <TB3> INFO: 2226100 events read in total (104591ms).
[10:23:09.533] <TB3> INFO: 2968050 events read in total (139286ms).
[10:23:44.030] <TB3> INFO: 3704690 events read in total (173783ms).
[10:24:18.697] <TB3> INFO: 4436790 events read in total (208450ms).
[10:24:53.325] <TB3> INFO: 5167060 events read in total (243078ms).
[10:25:28.224] <TB3> INFO: 5897090 events read in total (277977ms).
[10:25:46.976] <TB3> INFO: 6281600 events read in total (296729ms).
[10:25:47.023] <TB3> INFO: Test took 297616ms.
[10:26:12.501] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.064438 .. 255.000000
[10:26:12.584] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:26:12.592] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:26:12.592] <TB3> INFO: run 1 of 1
[10:26:12.858] <TB3> INFO: Expecting 10649600 events.
[10:26:47.524] <TB3> INFO: 712110 events read in total (34110ms).
[10:27:21.865] <TB3> INFO: 1424040 events read in total (68451ms).
[10:27:56.071] <TB3> INFO: 2136130 events read in total (102657ms).
[10:28:30.644] <TB3> INFO: 2848300 events read in total (137230ms).
[10:29:05.247] <TB3> INFO: 3560450 events read in total (171833ms).
[10:29:39.327] <TB3> INFO: 4273100 events read in total (205913ms).
[10:30:13.593] <TB3> INFO: 4985490 events read in total (240179ms).
[10:30:47.750] <TB3> INFO: 5697380 events read in total (274336ms).
[10:31:22.036] <TB3> INFO: 6409250 events read in total (308622ms).
[10:31:56.164] <TB3> INFO: 7120440 events read in total (342750ms).
[10:32:30.516] <TB3> INFO: 7831770 events read in total (377102ms).
[10:33:04.633] <TB3> INFO: 8542320 events read in total (411219ms).
[10:33:38.953] <TB3> INFO: 9253160 events read in total (445539ms).
[10:34:12.466] <TB3> INFO: 9964050 events read in total (479052ms).
[10:34:45.610] <TB3> INFO: 10649600 events read in total (512196ms).
[10:34:45.710] <TB3> INFO: Test took 513118ms.
[10:35:15.477] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 14.824566 .. 75.331072
[10:35:15.569] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 4 .. 85 (-1/-1) hits flags = 528 (plus default)
[10:35:15.578] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:35:15.578] <TB3> INFO: run 1 of 1
[10:35:15.862] <TB3> INFO: Expecting 3411200 events.
[10:35:52.244] <TB3> INFO: 817270 events read in total (35826ms).
[10:36:27.961] <TB3> INFO: 1634510 events read in total (71543ms).
[10:37:04.090] <TB3> INFO: 2450760 events read in total (107672ms).
[10:37:40.018] <TB3> INFO: 3266020 events read in total (143600ms).
[10:37:46.778] <TB3> INFO: 3411200 events read in total (150360ms).
[10:37:46.801] <TB3> INFO: Test took 151223ms.
[10:38:05.410] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 19.813095 .. 58.019647
[10:38:05.485] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 68 (-1/-1) hits flags = 528 (plus default)
[10:38:05.493] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:38:05.493] <TB3> INFO: run 1 of 1
[10:38:05.753] <TB3> INFO: Expecting 2496000 events.
[10:38:42.702] <TB3> INFO: 847230 events read in total (36392ms).
[10:39:19.044] <TB3> INFO: 1693970 events read in total (72734ms).
[10:39:53.760] <TB3> INFO: 2496000 events read in total (107450ms).
[10:39:53.776] <TB3> INFO: Test took 108283ms.
[10:40:10.019] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 3.500000 .. 44.471080
[10:40:10.100] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 3 .. 54 (-1/-1) hits flags = 528 (plus default)
[10:40:10.109] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:40:10.109] <TB3> INFO: run 1 of 1
[10:40:10.381] <TB3> INFO: Expecting 2163200 events.
[10:40:48.776] <TB3> INFO: 958280 events read in total (37839ms).
[10:41:27.302] <TB3> INFO: 1916060 events read in total (76365ms).
[10:41:37.639] <TB3> INFO: 2163200 events read in total (86703ms).
[10:41:37.662] <TB3> INFO: Test took 87553ms.
[10:41:50.786] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:41:50.786] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:41:50.795] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:41:50.795] <TB3> INFO: run 1 of 1
[10:41:51.087] <TB3> INFO: Expecting 1705600 events.
[10:42:28.582] <TB3> INFO: 878680 events read in total (36939ms).
[10:43:03.839] <TB3> INFO: 1705600 events read in total (72196ms).
[10:43:03.851] <TB3> INFO: Test took 73056ms.
[10:43:18.721] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:43:18.721] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:43:18.721] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:43:18.721] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:43:18.721] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:43:18.722] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:43:18.722] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:43:18.722] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:43:18.722] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:43:18.722] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:43:18.722] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:43:18.723] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:43:18.723] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:43:18.723] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:43:18.723] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:43:18.723] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:43:18.723] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C0.dat
[10:43:18.732] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C1.dat
[10:43:18.741] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C2.dat
[10:43:18.749] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C3.dat
[10:43:18.758] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C4.dat
[10:43:18.766] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C5.dat
[10:43:18.774] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C6.dat
[10:43:18.782] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C7.dat
[10:43:18.790] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C8.dat
[10:43:18.799] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C9.dat
[10:43:18.806] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C10.dat
[10:43:18.814] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C11.dat
[10:43:18.822] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C12.dat
[10:43:18.830] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C13.dat
[10:43:18.838] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C14.dat
[10:43:18.846] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//trimParameters35_C15.dat
[10:43:18.855] <TB3> INFO: PixTestTrim::trimTest() done
[10:43:18.855] <TB3> INFO: vtrim: 106 101 102 99 91 89 86 91 116 97 99 98 108 74 107 82
[10:43:18.855] <TB3> INFO: vthrcomp: 92 94 95 101 87 87 91 100 100 78 94 99 80 86 101 82
[10:43:18.855] <TB3> INFO: vcal mean: 34.96 34.98 34.98 34.98 34.96 34.98 34.98 35.00 34.99 35.04 34.97 34.99 35.01 34.98 34.99 34.95
[10:43:18.855] <TB3> INFO: vcal RMS: 0.89 0.85 0.86 1.01 0.98 0.83 0.87 1.04 0.95 0.85 0.87 0.78 1.04 0.90 0.89 0.80
[10:43:18.855] <TB3> INFO: bits mean: 9.14 9.15 9.19 9.56 9.67 9.61 9.05 8.97 8.92 8.64 9.88 9.40 8.87 9.10 8.98 8.94
[10:43:18.855] <TB3> INFO: bits RMS: 2.73 2.92 2.84 2.70 2.70 2.78 2.78 2.83 2.72 2.49 2.56 2.77 2.96 3.02 2.76 2.93
[10:43:18.861] <TB3> INFO: ----------------------------------------------------------------------
[10:43:18.861] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[10:43:18.862] <TB3> INFO: ----------------------------------------------------------------------
[10:43:18.864] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[10:43:18.874] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:43:18.874] <TB3> INFO: run 1 of 1
[10:43:19.166] <TB3> INFO: Expecting 4160000 events.
[10:44:02.159] <TB3> INFO: 931955 events read in total (42437ms).
[10:44:43.555] <TB3> INFO: 1855835 events read in total (83833ms).
[10:45:25.367] <TB3> INFO: 2769955 events read in total (125645ms).
[10:46:06.382] <TB3> INFO: 3680795 events read in total (166660ms).
[10:46:28.382] <TB3> INFO: 4160000 events read in total (188660ms).
[10:46:28.456] <TB3> INFO: Test took 189582ms.
[10:46:58.141] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 186 (-1/-1) hits flags = 528 (plus default)
[10:46:58.149] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:46:58.149] <TB3> INFO: run 1 of 1
[10:46:58.408] <TB3> INFO: Expecting 3889600 events.
[10:47:40.622] <TB3> INFO: 922765 events read in total (41658ms).
[10:48:22.582] <TB3> INFO: 1836910 events read in total (83618ms).
[10:49:03.187] <TB3> INFO: 2741105 events read in total (124223ms).
[10:49:44.532] <TB3> INFO: 3642720 events read in total (165568ms).
[10:49:56.081] <TB3> INFO: 3889600 events read in total (177117ms).
[10:49:56.118] <TB3> INFO: Test took 177969ms.
[10:50:24.294] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 171 (-1/-1) hits flags = 528 (plus default)
[10:50:24.302] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:50:24.302] <TB3> INFO: run 1 of 1
[10:50:24.561] <TB3> INFO: Expecting 3577600 events.
[10:51:07.979] <TB3> INFO: 953965 events read in total (42861ms).
[10:51:49.452] <TB3> INFO: 1896880 events read in total (84334ms).
[10:52:30.761] <TB3> INFO: 2828290 events read in total (125643ms).
[10:53:04.072] <TB3> INFO: 3577600 events read in total (158954ms).
[10:53:04.111] <TB3> INFO: Test took 159809ms.
[10:53:33.847] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[10:53:33.856] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:53:33.856] <TB3> INFO: run 1 of 1
[10:53:34.125] <TB3> INFO: Expecting 3598400 events.
[10:54:16.866] <TB3> INFO: 950795 events read in total (42184ms).
[10:54:58.516] <TB3> INFO: 1890905 events read in total (83834ms).
[10:55:40.028] <TB3> INFO: 2819550 events read in total (125346ms).
[10:56:15.057] <TB3> INFO: 3598400 events read in total (160375ms).
[10:56:15.096] <TB3> INFO: Test took 161241ms.
[10:56:43.107] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[10:56:43.116] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:56:43.116] <TB3> INFO: run 1 of 1
[10:56:43.397] <TB3> INFO: Expecting 3598400 events.
[10:57:25.674] <TB3> INFO: 950305 events read in total (41721ms).
[10:58:06.931] <TB3> INFO: 1889810 events read in total (82979ms).
[10:58:48.294] <TB3> INFO: 2817880 events read in total (124342ms).
[10:59:23.146] <TB3> INFO: 3598400 events read in total (159193ms).
[10:59:23.191] <TB3> INFO: Test took 160075ms.
[10:59:52.238] <TB3> INFO: PixTestTrim::trimBitTest() done
[10:59:52.240] <TB3> INFO: PixTestTrim::doTest() done, duration: 3118 seconds
[10:59:52.240] <TB3> INFO: Decoding statistics:
[10:59:52.240] <TB3> INFO: General information:
[10:59:52.240] <TB3> INFO: 16bit words read: 0
[10:59:52.240] <TB3> INFO: valid events total: 0
[10:59:52.240] <TB3> INFO: empty events: 0
[10:59:52.240] <TB3> INFO: valid events with pixels: 0
[10:59:52.240] <TB3> INFO: valid pixel hits: 0
[10:59:52.240] <TB3> INFO: Event errors: 0
[10:59:52.240] <TB3> INFO: start marker: 0
[10:59:52.240] <TB3> INFO: stop marker: 0
[10:59:52.240] <TB3> INFO: overflow: 0
[10:59:52.240] <TB3> INFO: invalid 5bit words: 0
[10:59:52.240] <TB3> INFO: invalid XOR eye diagram: 0
[10:59:52.240] <TB3> INFO: frame (failed synchr.): 0
[10:59:52.240] <TB3> INFO: idle data (no TBM trl): 0
[10:59:52.240] <TB3> INFO: no data (only TBM hdr): 0
[10:59:52.240] <TB3> INFO: TBM errors: 0
[10:59:52.240] <TB3> INFO: flawed TBM headers: 0
[10:59:52.240] <TB3> INFO: flawed TBM trailers: 0
[10:59:52.240] <TB3> INFO: event ID mismatches: 0
[10:59:52.240] <TB3> INFO: ROC errors: 0
[10:59:52.240] <TB3> INFO: missing ROC header(s): 0
[10:59:52.240] <TB3> INFO: misplaced readback start: 0
[10:59:52.240] <TB3> INFO: Pixel decoding errors: 0
[10:59:52.240] <TB3> INFO: pixel data incomplete: 0
[10:59:52.240] <TB3> INFO: pixel address: 0
[10:59:52.240] <TB3> INFO: pulse height fill bit: 0
[10:59:52.240] <TB3> INFO: buffer corruption: 0
[10:59:53.021] <TB3> INFO: ######################################################################
[10:59:53.021] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[10:59:53.021] <TB3> INFO: ######################################################################
[10:59:53.296] <TB3> INFO: Expecting 41600 events.
[10:59:57.122] <TB3> INFO: 41600 events read in total (3270ms).
[10:59:57.122] <TB3> INFO: Test took 4100ms.
[10:59:57.609] <TB3> INFO: Expecting 41600 events.
[11:00:01.372] <TB3> INFO: 41600 events read in total (3207ms).
[11:00:01.372] <TB3> INFO: Test took 4050ms.
[11:00:01.677] <TB3> INFO: Expecting 41600 events.
[11:00:05.536] <TB3> INFO: 41600 events read in total (3303ms).
[11:00:05.537] <TB3> INFO: Test took 4143ms.
[11:00:05.794] <TB3> INFO: Expecting 2560 events.
[11:00:06.653] <TB3> INFO: 2560 events read in total (303ms).
[11:00:06.653] <TB3> INFO: Test took 1101ms.
[11:00:06.958] <TB3> INFO: Expecting 2560 events.
[11:00:07.812] <TB3> INFO: 2560 events read in total (297ms).
[11:00:07.812] <TB3> INFO: Test took 1158ms.
[11:00:08.117] <TB3> INFO: Expecting 2560 events.
[11:00:08.974] <TB3> INFO: 2560 events read in total (300ms).
[11:00:08.975] <TB3> INFO: Test took 1162ms.
[11:00:09.280] <TB3> INFO: Expecting 2560 events.
[11:00:10.139] <TB3> INFO: 2560 events read in total (302ms).
[11:00:10.139] <TB3> INFO: Test took 1164ms.
[11:00:10.445] <TB3> INFO: Expecting 2560 events.
[11:00:11.298] <TB3> INFO: 2560 events read in total (297ms).
[11:00:11.299] <TB3> INFO: Test took 1160ms.
[11:00:11.605] <TB3> INFO: Expecting 2560 events.
[11:00:12.460] <TB3> INFO: 2560 events read in total (299ms).
[11:00:12.460] <TB3> INFO: Test took 1160ms.
[11:00:12.765] <TB3> INFO: Expecting 2560 events.
[11:00:13.627] <TB3> INFO: 2560 events read in total (306ms).
[11:00:13.627] <TB3> INFO: Test took 1166ms.
[11:00:13.932] <TB3> INFO: Expecting 2560 events.
[11:00:14.785] <TB3> INFO: 2560 events read in total (297ms).
[11:00:14.785] <TB3> INFO: Test took 1158ms.
[11:00:15.091] <TB3> INFO: Expecting 2560 events.
[11:00:15.946] <TB3> INFO: 2560 events read in total (299ms).
[11:00:15.946] <TB3> INFO: Test took 1160ms.
[11:00:16.252] <TB3> INFO: Expecting 2560 events.
[11:00:17.107] <TB3> INFO: 2560 events read in total (299ms).
[11:00:17.107] <TB3> INFO: Test took 1161ms.
[11:00:17.412] <TB3> INFO: Expecting 2560 events.
[11:00:18.265] <TB3> INFO: 2560 events read in total (297ms).
[11:00:18.265] <TB3> INFO: Test took 1158ms.
[11:00:18.571] <TB3> INFO: Expecting 2560 events.
[11:00:19.425] <TB3> INFO: 2560 events read in total (298ms).
[11:00:19.425] <TB3> INFO: Test took 1159ms.
[11:00:19.730] <TB3> INFO: Expecting 2560 events.
[11:00:20.587] <TB3> INFO: 2560 events read in total (301ms).
[11:00:20.587] <TB3> INFO: Test took 1161ms.
[11:00:20.893] <TB3> INFO: Expecting 2560 events.
[11:00:21.747] <TB3> INFO: 2560 events read in total (298ms).
[11:00:21.748] <TB3> INFO: Test took 1160ms.
[11:00:22.053] <TB3> INFO: Expecting 2560 events.
[11:00:22.907] <TB3> INFO: 2560 events read in total (298ms).
[11:00:22.907] <TB3> INFO: Test took 1159ms.
[11:00:23.213] <TB3> INFO: Expecting 2560 events.
[11:00:24.069] <TB3> INFO: 2560 events read in total (300ms).
[11:00:24.069] <TB3> INFO: Test took 1161ms.
[11:00:24.073] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:24.375] <TB3> INFO: Expecting 655360 events.
[11:00:41.314] <TB3> INFO: 655360 events read in total (16382ms).
[11:00:41.322] <TB3> INFO: Expecting 655360 events.
[11:00:57.983] <TB3> INFO: 655360 events read in total (16259ms).
[11:00:57.995] <TB3> INFO: Expecting 655360 events.
[11:01:14.894] <TB3> INFO: 655360 events read in total (16497ms).
[11:01:14.909] <TB3> INFO: Expecting 655360 events.
[11:01:31.743] <TB3> INFO: 655360 events read in total (16432ms).
[11:01:31.763] <TB3> INFO: Expecting 655360 events.
[11:01:48.615] <TB3> INFO: 655360 events read in total (16450ms).
[11:01:48.642] <TB3> INFO: Expecting 655360 events.
[11:02:05.385] <TB3> INFO: 655360 events read in total (16341ms).
[11:02:05.412] <TB3> INFO: Expecting 655360 events.
[11:02:22.044] <TB3> INFO: 655360 events read in total (16230ms).
[11:02:22.075] <TB3> INFO: Expecting 655360 events.
[11:02:38.867] <TB3> INFO: 655360 events read in total (16391ms).
[11:02:38.900] <TB3> INFO: Expecting 655360 events.
[11:02:55.733] <TB3> INFO: 655360 events read in total (16432ms).
[11:02:55.776] <TB3> INFO: Expecting 655360 events.
[11:03:12.637] <TB3> INFO: 655360 events read in total (16459ms).
[11:03:12.678] <TB3> INFO: Expecting 655360 events.
[11:03:29.479] <TB3> INFO: 655360 events read in total (16399ms).
[11:03:29.529] <TB3> INFO: Expecting 655360 events.
[11:03:46.336] <TB3> INFO: 655360 events read in total (16405ms).
[11:03:46.382] <TB3> INFO: Expecting 655360 events.
[11:04:03.130] <TB3> INFO: 655360 events read in total (16347ms).
[11:04:03.183] <TB3> INFO: Expecting 655360 events.
[11:04:19.906] <TB3> INFO: 655360 events read in total (16321ms).
[11:04:19.960] <TB3> INFO: Expecting 655360 events.
[11:04:36.834] <TB3> INFO: 655360 events read in total (16473ms).
[11:04:36.908] <TB3> INFO: Expecting 655360 events.
[11:04:53.650] <TB3> INFO: 655360 events read in total (16340ms).
[11:04:53.714] <TB3> INFO: Test took 269641ms.
[11:04:53.796] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:54.028] <TB3> INFO: Expecting 655360 events.
[11:05:10.782] <TB3> INFO: 655360 events read in total (16198ms).
[11:05:10.791] <TB3> INFO: Expecting 655360 events.
[11:05:27.476] <TB3> INFO: 655360 events read in total (16283ms).
[11:05:27.488] <TB3> INFO: Expecting 655360 events.
[11:05:44.014] <TB3> INFO: 655360 events read in total (16125ms).
[11:05:44.033] <TB3> INFO: Expecting 655360 events.
[11:06:00.787] <TB3> INFO: 655360 events read in total (16353ms).
[11:06:00.812] <TB3> INFO: Expecting 655360 events.
[11:06:17.612] <TB3> INFO: 655360 events read in total (16398ms).
[11:06:17.639] <TB3> INFO: Expecting 655360 events.
[11:06:34.249] <TB3> INFO: 655360 events read in total (16208ms).
[11:06:34.280] <TB3> INFO: Expecting 655360 events.
[11:06:50.937] <TB3> INFO: 655360 events read in total (16256ms).
[11:06:50.979] <TB3> INFO: Expecting 655360 events.
[11:07:07.883] <TB3> INFO: 655360 events read in total (16502ms).
[11:07:07.922] <TB3> INFO: Expecting 655360 events.
[11:07:24.765] <TB3> INFO: 655360 events read in total (16442ms).
[11:07:24.808] <TB3> INFO: Expecting 655360 events.
[11:07:41.565] <TB3> INFO: 655360 events read in total (16355ms).
[11:07:41.609] <TB3> INFO: Expecting 655360 events.
[11:07:58.411] <TB3> INFO: 655360 events read in total (16400ms).
[11:07:58.471] <TB3> INFO: Expecting 655360 events.
[11:08:15.327] <TB3> INFO: 655360 events read in total (16454ms).
[11:08:15.376] <TB3> INFO: Expecting 655360 events.
[11:08:32.264] <TB3> INFO: 655360 events read in total (16486ms).
[11:08:32.328] <TB3> INFO: Expecting 655360 events.
[11:08:49.099] <TB3> INFO: 655360 events read in total (16370ms).
[11:08:49.157] <TB3> INFO: Expecting 655360 events.
[11:09:06.046] <TB3> INFO: 655360 events read in total (16488ms).
[11:09:06.108] <TB3> INFO: Expecting 655360 events.
[11:09:23.073] <TB3> INFO: 655360 events read in total (16563ms).
[11:09:23.142] <TB3> INFO: Test took 269346ms.
[11:09:23.337] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.344] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.350] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.357] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.364] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.370] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[11:09:23.377] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[11:09:23.383] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[11:09:23.390] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.396] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.403] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.410] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[11:09:23.416] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[11:09:23.423] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[11:09:23.429] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.436] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[11:09:23.443] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.449] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.456] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.462] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.469] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[11:09:23.477] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[11:09:23.484] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[11:09:23.491] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[11:09:23.498] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[11:09:23.507] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.514] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.522] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:23.561] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:09:23.561] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:09:23.561] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:09:23.561] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:09:23.561] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:09:23.561] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:09:23.561] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:09:23.562] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:09:23.562] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:09:23.562] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:09:23.562] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:09:23.562] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:09:23.563] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:09:23.563] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:09:23.563] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:09:23.563] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:09:23.826] <TB3> INFO: Expecting 41600 events.
[11:09:27.302] <TB3> INFO: 41600 events read in total (2920ms).
[11:09:27.303] <TB3> INFO: Test took 3737ms.
[11:09:27.757] <TB3> INFO: Expecting 41600 events.
[11:09:31.199] <TB3> INFO: 41600 events read in total (2886ms).
[11:09:31.199] <TB3> INFO: Test took 3717ms.
[11:09:31.638] <TB3> INFO: Expecting 41600 events.
[11:09:35.157] <TB3> INFO: 41600 events read in total (2963ms).
[11:09:35.158] <TB3> INFO: Test took 3783ms.
[11:09:35.342] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:35.431] <TB3> INFO: Expecting 2560 events.
[11:09:36.283] <TB3> INFO: 2560 events read in total (296ms).
[11:09:36.284] <TB3> INFO: Test took 942ms.
[11:09:36.286] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:36.590] <TB3> INFO: Expecting 2560 events.
[11:09:37.443] <TB3> INFO: 2560 events read in total (297ms).
[11:09:37.443] <TB3> INFO: Test took 1157ms.
[11:09:37.445] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:37.749] <TB3> INFO: Expecting 2560 events.
[11:09:38.606] <TB3> INFO: 2560 events read in total (301ms).
[11:09:38.607] <TB3> INFO: Test took 1162ms.
[11:09:38.609] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:38.912] <TB3> INFO: Expecting 2560 events.
[11:09:39.768] <TB3> INFO: 2560 events read in total (299ms).
[11:09:39.768] <TB3> INFO: Test took 1159ms.
[11:09:39.777] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:40.074] <TB3> INFO: Expecting 2560 events.
[11:09:40.930] <TB3> INFO: 2560 events read in total (299ms).
[11:09:40.930] <TB3> INFO: Test took 1153ms.
[11:09:40.932] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:41.235] <TB3> INFO: Expecting 2560 events.
[11:09:42.091] <TB3> INFO: 2560 events read in total (300ms).
[11:09:42.091] <TB3> INFO: Test took 1159ms.
[11:09:42.094] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:42.397] <TB3> INFO: Expecting 2560 events.
[11:09:43.255] <TB3> INFO: 2560 events read in total (302ms).
[11:09:43.255] <TB3> INFO: Test took 1162ms.
[11:09:43.258] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:43.562] <TB3> INFO: Expecting 2560 events.
[11:09:44.418] <TB3> INFO: 2560 events read in total (300ms).
[11:09:44.418] <TB3> INFO: Test took 1160ms.
[11:09:44.420] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:44.724] <TB3> INFO: Expecting 2560 events.
[11:09:45.578] <TB3> INFO: 2560 events read in total (299ms).
[11:09:45.579] <TB3> INFO: Test took 1159ms.
[11:09:45.581] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:45.885] <TB3> INFO: Expecting 2560 events.
[11:09:46.744] <TB3> INFO: 2560 events read in total (303ms).
[11:09:46.744] <TB3> INFO: Test took 1164ms.
[11:09:46.747] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:47.050] <TB3> INFO: Expecting 2560 events.
[11:09:47.909] <TB3> INFO: 2560 events read in total (302ms).
[11:09:47.910] <TB3> INFO: Test took 1163ms.
[11:09:47.913] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:48.215] <TB3> INFO: Expecting 2560 events.
[11:09:49.069] <TB3> INFO: 2560 events read in total (298ms).
[11:09:49.069] <TB3> INFO: Test took 1158ms.
[11:09:49.072] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:49.374] <TB3> INFO: Expecting 2560 events.
[11:09:50.229] <TB3> INFO: 2560 events read in total (299ms).
[11:09:50.230] <TB3> INFO: Test took 1158ms.
[11:09:50.232] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:50.535] <TB3> INFO: Expecting 2560 events.
[11:09:51.389] <TB3> INFO: 2560 events read in total (298ms).
[11:09:51.389] <TB3> INFO: Test took 1157ms.
[11:09:51.390] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:51.694] <TB3> INFO: Expecting 2560 events.
[11:09:52.548] <TB3> INFO: 2560 events read in total (298ms).
[11:09:52.548] <TB3> INFO: Test took 1158ms.
[11:09:52.550] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:52.853] <TB3> INFO: Expecting 2560 events.
[11:09:53.706] <TB3> INFO: 2560 events read in total (297ms).
[11:09:53.706] <TB3> INFO: Test took 1157ms.
[11:09:53.708] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:54.011] <TB3> INFO: Expecting 2560 events.
[11:09:54.864] <TB3> INFO: 2560 events read in total (297ms).
[11:09:54.865] <TB3> INFO: Test took 1157ms.
[11:09:54.866] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:55.170] <TB3> INFO: Expecting 2560 events.
[11:09:56.023] <TB3> INFO: 2560 events read in total (297ms).
[11:09:56.023] <TB3> INFO: Test took 1157ms.
[11:09:56.025] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:56.328] <TB3> INFO: Expecting 2560 events.
[11:09:57.181] <TB3> INFO: 2560 events read in total (297ms).
[11:09:57.182] <TB3> INFO: Test took 1158ms.
[11:09:57.183] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:57.487] <TB3> INFO: Expecting 2560 events.
[11:09:58.339] <TB3> INFO: 2560 events read in total (296ms).
[11:09:58.340] <TB3> INFO: Test took 1157ms.
[11:09:58.341] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:58.645] <TB3> INFO: Expecting 2560 events.
[11:09:59.499] <TB3> INFO: 2560 events read in total (298ms).
[11:09:59.499] <TB3> INFO: Test took 1158ms.
[11:09:59.501] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:09:59.804] <TB3> INFO: Expecting 2560 events.
[11:10:00.658] <TB3> INFO: 2560 events read in total (298ms).
[11:10:00.658] <TB3> INFO: Test took 1158ms.
[11:10:00.660] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:00.964] <TB3> INFO: Expecting 2560 events.
[11:10:01.819] <TB3> INFO: 2560 events read in total (299ms).
[11:10:01.819] <TB3> INFO: Test took 1159ms.
[11:10:01.821] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:02.124] <TB3> INFO: Expecting 2560 events.
[11:10:02.978] <TB3> INFO: 2560 events read in total (298ms).
[11:10:02.978] <TB3> INFO: Test took 1157ms.
[11:10:02.980] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:03.284] <TB3> INFO: Expecting 2560 events.
[11:10:04.137] <TB3> INFO: 2560 events read in total (297ms).
[11:10:04.137] <TB3> INFO: Test took 1157ms.
[11:10:04.138] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:04.442] <TB3> INFO: Expecting 2560 events.
[11:10:05.295] <TB3> INFO: 2560 events read in total (297ms).
[11:10:05.296] <TB3> INFO: Test took 1158ms.
[11:10:05.297] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:05.601] <TB3> INFO: Expecting 2560 events.
[11:10:06.454] <TB3> INFO: 2560 events read in total (297ms).
[11:10:06.454] <TB3> INFO: Test took 1157ms.
[11:10:06.456] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:06.760] <TB3> INFO: Expecting 2560 events.
[11:10:07.614] <TB3> INFO: 2560 events read in total (298ms).
[11:10:07.614] <TB3> INFO: Test took 1158ms.
[11:10:07.615] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:07.919] <TB3> INFO: Expecting 2560 events.
[11:10:08.774] <TB3> INFO: 2560 events read in total (298ms).
[11:10:08.775] <TB3> INFO: Test took 1160ms.
[11:10:08.776] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:09.080] <TB3> INFO: Expecting 2560 events.
[11:10:09.934] <TB3> INFO: 2560 events read in total (298ms).
[11:10:09.934] <TB3> INFO: Test took 1158ms.
[11:10:09.936] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:10.239] <TB3> INFO: Expecting 2560 events.
[11:10:11.093] <TB3> INFO: 2560 events read in total (297ms).
[11:10:11.093] <TB3> INFO: Test took 1157ms.
[11:10:11.095] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:11.399] <TB3> INFO: Expecting 2560 events.
[11:10:12.253] <TB3> INFO: 2560 events read in total (298ms).
[11:10:12.253] <TB3> INFO: Test took 1158ms.
[11:10:12.709] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 619 seconds
[11:10:12.709] <TB3> INFO: PH scale (per ROC): 83 89 82 83 80 79 81 78 77 92 86 80 80 81 80 74
[11:10:12.709] <TB3> INFO: PH offset (per ROC): 160 158 152 162 158 162 176 176 176 142 154 163 160 162 149 162
[11:10:12.714] <TB3> INFO: Decoding statistics:
[11:10:12.714] <TB3> INFO: General information:
[11:10:12.714] <TB3> INFO: 16bit words read: 91920
[11:10:12.714] <TB3> INFO: valid events total: 10240
[11:10:12.714] <TB3> INFO: empty events: 7740
[11:10:12.714] <TB3> INFO: valid events with pixels: 2500
[11:10:12.714] <TB3> INFO: valid pixel hits: 2500
[11:10:12.714] <TB3> INFO: Event errors: 0
[11:10:12.714] <TB3> INFO: start marker: 0
[11:10:12.714] <TB3> INFO: stop marker: 0
[11:10:12.714] <TB3> INFO: overflow: 0
[11:10:12.714] <TB3> INFO: invalid 5bit words: 0
[11:10:12.714] <TB3> INFO: invalid XOR eye diagram: 0
[11:10:12.714] <TB3> INFO: frame (failed synchr.): 0
[11:10:12.714] <TB3> INFO: idle data (no TBM trl): 0
[11:10:12.714] <TB3> INFO: no data (only TBM hdr): 0
[11:10:12.714] <TB3> INFO: TBM errors: 0
[11:10:12.714] <TB3> INFO: flawed TBM headers: 0
[11:10:12.714] <TB3> INFO: flawed TBM trailers: 0
[11:10:12.714] <TB3> INFO: event ID mismatches: 0
[11:10:12.714] <TB3> INFO: ROC errors: 0
[11:10:12.714] <TB3> INFO: missing ROC header(s): 0
[11:10:12.714] <TB3> INFO: misplaced readback start: 0
[11:10:12.714] <TB3> INFO: Pixel decoding errors: 0
[11:10:12.714] <TB3> INFO: pixel data incomplete: 0
[11:10:12.714] <TB3> INFO: pixel address: 0
[11:10:12.714] <TB3> INFO: pulse height fill bit: 0
[11:10:12.714] <TB3> INFO: buffer corruption: 0
[11:10:12.944] <TB3> INFO: ######################################################################
[11:10:12.944] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:10:12.944] <TB3> INFO: ######################################################################
[11:10:12.954] <TB3> INFO: scanning low vcal = 10
[11:10:13.229] <TB3> INFO: Expecting 41600 events.
[11:10:16.764] <TB3> INFO: 41600 events read in total (2979ms).
[11:10:16.765] <TB3> INFO: Test took 3811ms.
[11:10:16.766] <TB3> INFO: scanning low vcal = 20
[11:10:17.070] <TB3> INFO: Expecting 41600 events.
[11:10:20.613] <TB3> INFO: 41600 events read in total (2987ms).
[11:10:20.614] <TB3> INFO: Test took 3848ms.
[11:10:20.615] <TB3> INFO: scanning low vcal = 30
[11:10:20.919] <TB3> INFO: Expecting 41600 events.
[11:10:24.500] <TB3> INFO: 41600 events read in total (3025ms).
[11:10:24.501] <TB3> INFO: Test took 3886ms.
[11:10:24.517] <TB3> INFO: scanning low vcal = 40
[11:10:24.811] <TB3> INFO: Expecting 41600 events.
[11:10:28.962] <TB3> INFO: 41600 events read in total (3595ms).
[11:10:28.963] <TB3> INFO: Test took 4446ms.
[11:10:28.965] <TB3> INFO: scanning low vcal = 50
[11:10:29.229] <TB3> INFO: Expecting 41600 events.
[11:10:33.446] <TB3> INFO: 41600 events read in total (3661ms).
[11:10:33.446] <TB3> INFO: Test took 4481ms.
[11:10:33.449] <TB3> INFO: scanning low vcal = 60
[11:10:33.712] <TB3> INFO: Expecting 41600 events.
[11:10:37.927] <TB3> INFO: 41600 events read in total (3658ms).
[11:10:37.928] <TB3> INFO: Test took 4479ms.
[11:10:37.930] <TB3> INFO: scanning low vcal = 70
[11:10:38.204] <TB3> INFO: Expecting 41600 events.
[11:10:42.451] <TB3> INFO: 41600 events read in total (3691ms).
[11:10:42.452] <TB3> INFO: Test took 4521ms.
[11:10:42.454] <TB3> INFO: scanning low vcal = 80
[11:10:42.715] <TB3> INFO: Expecting 41600 events.
[11:10:46.891] <TB3> INFO: 41600 events read in total (3620ms).
[11:10:46.891] <TB3> INFO: Test took 4437ms.
[11:10:46.894] <TB3> INFO: scanning low vcal = 90
[11:10:47.156] <TB3> INFO: Expecting 41600 events.
[11:10:51.319] <TB3> INFO: 41600 events read in total (3607ms).
[11:10:51.319] <TB3> INFO: Test took 4425ms.
[11:10:51.322] <TB3> INFO: scanning low vcal = 100
[11:10:51.595] <TB3> INFO: Expecting 41600 events.
[11:10:55.855] <TB3> INFO: 41600 events read in total (3704ms).
[11:10:55.856] <TB3> INFO: Test took 4534ms.
[11:10:55.859] <TB3> INFO: scanning low vcal = 110
[11:10:56.147] <TB3> INFO: Expecting 41600 events.
[11:11:00.324] <TB3> INFO: 41600 events read in total (3621ms).
[11:11:00.324] <TB3> INFO: Test took 4465ms.
[11:11:00.327] <TB3> INFO: scanning low vcal = 120
[11:11:00.595] <TB3> INFO: Expecting 41600 events.
[11:11:04.770] <TB3> INFO: 41600 events read in total (3619ms).
[11:11:04.770] <TB3> INFO: Test took 4443ms.
[11:11:04.773] <TB3> INFO: scanning low vcal = 130
[11:11:05.043] <TB3> INFO: Expecting 41600 events.
[11:11:09.224] <TB3> INFO: 41600 events read in total (3625ms).
[11:11:09.225] <TB3> INFO: Test took 4452ms.
[11:11:09.227] <TB3> INFO: scanning low vcal = 140
[11:11:09.516] <TB3> INFO: Expecting 41600 events.
[11:11:13.705] <TB3> INFO: 41600 events read in total (3632ms).
[11:11:13.706] <TB3> INFO: Test took 4479ms.
[11:11:13.709] <TB3> INFO: scanning low vcal = 150
[11:11:13.988] <TB3> INFO: Expecting 41600 events.
[11:11:18.211] <TB3> INFO: 41600 events read in total (3667ms).
[11:11:18.212] <TB3> INFO: Test took 4503ms.
[11:11:18.214] <TB3> INFO: scanning low vcal = 160
[11:11:18.496] <TB3> INFO: Expecting 41600 events.
[11:11:22.734] <TB3> INFO: 41600 events read in total (3681ms).
[11:11:22.735] <TB3> INFO: Test took 4520ms.
[11:11:22.737] <TB3> INFO: scanning low vcal = 170
[11:11:23.005] <TB3> INFO: Expecting 41600 events.
[11:11:27.225] <TB3> INFO: 41600 events read in total (3664ms).
[11:11:27.225] <TB3> INFO: Test took 4488ms.
[11:11:27.229] <TB3> INFO: scanning low vcal = 180
[11:11:27.504] <TB3> INFO: Expecting 41600 events.
[11:11:31.758] <TB3> INFO: 41600 events read in total (3698ms).
[11:11:31.759] <TB3> INFO: Test took 4530ms.
[11:11:31.762] <TB3> INFO: scanning low vcal = 190
[11:11:32.052] <TB3> INFO: Expecting 41600 events.
[11:11:36.299] <TB3> INFO: 41600 events read in total (3691ms).
[11:11:36.300] <TB3> INFO: Test took 4538ms.
[11:11:36.302] <TB3> INFO: scanning low vcal = 200
[11:11:36.561] <TB3> INFO: Expecting 41600 events.
[11:11:40.817] <TB3> INFO: 41600 events read in total (3700ms).
[11:11:40.817] <TB3> INFO: Test took 4515ms.
[11:11:40.819] <TB3> INFO: scanning low vcal = 210
[11:11:41.096] <TB3> INFO: Expecting 41600 events.
[11:11:45.334] <TB3> INFO: 41600 events read in total (3681ms).
[11:11:45.335] <TB3> INFO: Test took 4515ms.
[11:11:45.337] <TB3> INFO: scanning low vcal = 220
[11:11:45.611] <TB3> INFO: Expecting 41600 events.
[11:11:49.820] <TB3> INFO: 41600 events read in total (3653ms).
[11:11:49.820] <TB3> INFO: Test took 4483ms.
[11:11:49.823] <TB3> INFO: scanning low vcal = 230
[11:11:50.084] <TB3> INFO: Expecting 41600 events.
[11:11:54.310] <TB3> INFO: 41600 events read in total (3670ms).
[11:11:54.311] <TB3> INFO: Test took 4488ms.
[11:11:54.313] <TB3> INFO: scanning low vcal = 240
[11:11:54.579] <TB3> INFO: Expecting 41600 events.
[11:11:58.796] <TB3> INFO: 41600 events read in total (3661ms).
[11:11:58.796] <TB3> INFO: Test took 4482ms.
[11:11:58.800] <TB3> INFO: scanning low vcal = 250
[11:11:59.102] <TB3> INFO: Expecting 41600 events.
[11:12:03.284] <TB3> INFO: 41600 events read in total (3625ms).
[11:12:03.284] <TB3> INFO: Test took 4484ms.
[11:12:03.288] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[11:12:03.549] <TB3> INFO: Expecting 41600 events.
[11:12:07.721] <TB3> INFO: 41600 events read in total (3615ms).
[11:12:07.722] <TB3> INFO: Test took 4434ms.
[11:12:07.724] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[11:12:08.001] <TB3> INFO: Expecting 41600 events.
[11:12:12.170] <TB3> INFO: 41600 events read in total (3613ms).
[11:12:12.171] <TB3> INFO: Test took 4447ms.
[11:12:12.173] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[11:12:12.439] <TB3> INFO: Expecting 41600 events.
[11:12:16.614] <TB3> INFO: 41600 events read in total (3619ms).
[11:12:16.614] <TB3> INFO: Test took 4441ms.
[11:12:16.617] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[11:12:16.885] <TB3> INFO: Expecting 41600 events.
[11:12:21.056] <TB3> INFO: 41600 events read in total (3615ms).
[11:12:21.056] <TB3> INFO: Test took 4439ms.
[11:12:21.059] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:12:21.342] <TB3> INFO: Expecting 41600 events.
[11:12:25.600] <TB3> INFO: 41600 events read in total (3702ms).
[11:12:25.601] <TB3> INFO: Test took 4542ms.
[11:12:26.088] <TB3> INFO: PixTestGainPedestal::measure() done
[11:13:01.933] <TB3> INFO: PixTestGainPedestal::fit() done
[11:13:01.933] <TB3> INFO: non-linearity mean: 0.954 0.957 0.956 0.960 0.960 0.949 0.955 0.967 0.953 0.960 0.952 0.956 0.961 0.964 0.957 0.952
[11:13:01.933] <TB3> INFO: non-linearity RMS: 0.005 0.005 0.005 0.006 0.006 0.007 0.006 0.004 0.006 0.005 0.007 0.005 0.004 0.005 0.005 0.005
[11:13:01.933] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:13:01.952] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:13:01.970] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:13:01.989] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:13:02.007] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:13:02.032] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:13:02.052] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:13:02.070] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:13:02.088] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:13:02.106] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:13:02.125] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:13:02.143] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:13:02.162] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:13:02.180] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:13:02.199] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:13:02.217] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:13:02.236] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 169 seconds
[11:13:02.236] <TB3> INFO: Decoding statistics:
[11:13:02.236] <TB3> INFO: General information:
[11:13:02.236] <TB3> INFO: 16bit words read: 2662400
[11:13:02.236] <TB3> INFO: valid events total: 166400
[11:13:02.236] <TB3> INFO: empty events: 0
[11:13:02.236] <TB3> INFO: valid events with pixels: 166400
[11:13:02.236] <TB3> INFO: valid pixel hits: 663975
[11:13:02.236] <TB3> INFO: Event errors: 0
[11:13:02.236] <TB3> INFO: start marker: 0
[11:13:02.236] <TB3> INFO: stop marker: 0
[11:13:02.236] <TB3> INFO: overflow: 0
[11:13:02.236] <TB3> INFO: invalid 5bit words: 0
[11:13:02.236] <TB3> INFO: invalid XOR eye diagram: 0
[11:13:02.236] <TB3> INFO: frame (failed synchr.): 0
[11:13:02.236] <TB3> INFO: idle data (no TBM trl): 0
[11:13:02.236] <TB3> INFO: no data (only TBM hdr): 0
[11:13:02.236] <TB3> INFO: TBM errors: 0
[11:13:02.236] <TB3> INFO: flawed TBM headers: 0
[11:13:02.236] <TB3> INFO: flawed TBM trailers: 0
[11:13:02.236] <TB3> INFO: event ID mismatches: 0
[11:13:02.236] <TB3> INFO: ROC errors: 0
[11:13:02.236] <TB3> INFO: missing ROC header(s): 0
[11:13:02.236] <TB3> INFO: misplaced readback start: 0
[11:13:02.236] <TB3> INFO: Pixel decoding errors: 0
[11:13:02.236] <TB3> INFO: pixel data incomplete: 0
[11:13:02.236] <TB3> INFO: pixel address: 0
[11:13:02.236] <TB3> INFO: pulse height fill bit: 0
[11:13:02.236] <TB3> INFO: buffer corruption: 0
[11:13:02.243] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C15.dat
[11:13:02.244] <TB3> INFO: ######################################################################
[11:13:02.244] <TB3> INFO: PixTestReadback::doTest()
[11:13:02.244] <TB3> INFO: ######################################################################
[11:13:02.244] <TB3> INFO: ----------------------------------------------------------------------
[11:13:02.244] <TB3> INFO: PixTestReadback::CalibrateVd()
[11:13:02.244] <TB3> INFO: ----------------------------------------------------------------------
[11:13:11.757] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C0.dat
[11:13:11.758] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C1.dat
[11:13:11.758] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C2.dat
[11:13:11.758] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C3.dat
[11:13:11.758] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C4.dat
[11:13:11.758] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C5.dat
[11:13:11.758] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C6.dat
[11:13:11.758] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C7.dat
[11:13:11.758] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C8.dat
[11:13:11.758] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C9.dat
[11:13:11.758] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C10.dat
[11:13:11.758] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C11.dat
[11:13:11.759] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C12.dat
[11:13:11.759] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C13.dat
[11:13:11.759] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C14.dat
[11:13:11.759] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C15.dat
[11:13:11.782] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:13:11.782] <TB3> INFO: ----------------------------------------------------------------------
[11:13:11.782] <TB3> INFO: PixTestReadback::CalibrateVa()
[11:13:11.782] <TB3> INFO: ----------------------------------------------------------------------
[11:13:21.301] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C0.dat
[11:13:21.302] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C1.dat
[11:13:21.302] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C2.dat
[11:13:21.302] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C3.dat
[11:13:21.302] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C4.dat
[11:13:21.302] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C5.dat
[11:13:21.302] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C6.dat
[11:13:21.302] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C7.dat
[11:13:21.303] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C8.dat
[11:13:21.303] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C9.dat
[11:13:21.303] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C10.dat
[11:13:21.303] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C11.dat
[11:13:21.303] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C12.dat
[11:13:21.303] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C13.dat
[11:13:21.303] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C14.dat
[11:13:21.303] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C15.dat
[11:13:21.335] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:13:21.335] <TB3> INFO: ----------------------------------------------------------------------
[11:13:21.335] <TB3> INFO: PixTestReadback::readbackVbg()
[11:13:21.335] <TB3> INFO: ----------------------------------------------------------------------
[11:13:28.657] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:13:28.657] <TB3> INFO: ----------------------------------------------------------------------
[11:13:28.657] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[11:13:28.657] <TB3> INFO: ----------------------------------------------------------------------
[11:13:28.657] <TB3> INFO: Vbg will be calibrated using Vd calibration
[11:13:28.657] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151.7calibrated Vbg = 1.19243 :::*/*/*/*/
[11:13:28.657] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 157calibrated Vbg = 1.18822 :::*/*/*/*/
[11:13:28.657] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 154.3calibrated Vbg = 1.18978 :::*/*/*/*/
[11:13:28.657] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 149calibrated Vbg = 1.19385 :::*/*/*/*/
[11:13:28.657] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.8calibrated Vbg = 1.20538 :::*/*/*/*/
[11:13:28.657] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.3calibrated Vbg = 1.21062 :::*/*/*/*/
[11:13:28.657] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 161.8calibrated Vbg = 1.20166 :::*/*/*/*/
[11:13:28.658] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 163.5calibrated Vbg = 1.2051 :::*/*/*/*/
[11:13:28.658] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 161.7calibrated Vbg = 1.20804 :::*/*/*/*/
[11:13:28.658] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 153.1calibrated Vbg = 1.2075 :::*/*/*/*/
[11:13:28.658] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 171calibrated Vbg = 1.20235 :::*/*/*/*/
[11:13:28.658] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 143calibrated Vbg = 1.20351 :::*/*/*/*/
[11:13:28.658] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 160.9calibrated Vbg = 1.19393 :::*/*/*/*/
[11:13:28.658] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 149.6calibrated Vbg = 1.19926 :::*/*/*/*/
[11:13:28.658] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155.1calibrated Vbg = 1.20079 :::*/*/*/*/
[11:13:28.658] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 162.2calibrated Vbg = 1.19825 :::*/*/*/*/
[11:13:28.661] <TB3> INFO: ----------------------------------------------------------------------
[11:13:28.661] <TB3> INFO: PixTestReadback::CalibrateIa()
[11:13:28.661] <TB3> INFO: ----------------------------------------------------------------------
[11:16:04.068] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C0.dat
[11:16:04.068] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C1.dat
[11:16:04.068] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C2.dat
[11:16:04.068] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C3.dat
[11:16:04.068] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C4.dat
[11:16:04.069] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C5.dat
[11:16:04.069] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C6.dat
[11:16:04.069] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C7.dat
[11:16:04.069] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C8.dat
[11:16:04.069] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C9.dat
[11:16:04.069] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C10.dat
[11:16:04.069] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C11.dat
[11:16:04.069] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C12.dat
[11:16:04.069] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C13.dat
[11:16:04.069] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C14.dat
[11:16:04.070] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//000_FulltestPxar_m20//readbackCal_C15.dat
[11:16:04.100] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:16:04.101] <TB3> INFO: PixTestReadback::doTest() done
[11:16:04.101] <TB3> INFO: Decoding statistics:
[11:16:04.101] <TB3> INFO: General information:
[11:16:04.101] <TB3> INFO: 16bit words read: 1024
[11:16:04.101] <TB3> INFO: valid events total: 128
[11:16:04.101] <TB3> INFO: empty events: 128
[11:16:04.101] <TB3> INFO: valid events with pixels: 0
[11:16:04.101] <TB3> INFO: valid pixel hits: 0
[11:16:04.101] <TB3> INFO: Event errors: 0
[11:16:04.101] <TB3> INFO: start marker: 0
[11:16:04.102] <TB3> INFO: stop marker: 0
[11:16:04.102] <TB3> INFO: overflow: 0
[11:16:04.102] <TB3> INFO: invalid 5bit words: 0
[11:16:04.102] <TB3> INFO: invalid XOR eye diagram: 0
[11:16:04.102] <TB3> INFO: frame (failed synchr.): 0
[11:16:04.102] <TB3> INFO: idle data (no TBM trl): 0
[11:16:04.102] <TB3> INFO: no data (only TBM hdr): 0
[11:16:04.102] <TB3> INFO: TBM errors: 0
[11:16:04.102] <TB3> INFO: flawed TBM headers: 0
[11:16:04.102] <TB3> INFO: flawed TBM trailers: 0
[11:16:04.102] <TB3> INFO: event ID mismatches: 0
[11:16:04.102] <TB3> INFO: ROC errors: 0
[11:16:04.102] <TB3> INFO: missing ROC header(s): 0
[11:16:04.102] <TB3> INFO: misplaced readback start: 0
[11:16:04.102] <TB3> INFO: Pixel decoding errors: 0
[11:16:04.102] <TB3> INFO: pixel data incomplete: 0
[11:16:04.102] <TB3> INFO: pixel address: 0
[11:16:04.102] <TB3> INFO: pulse height fill bit: 0
[11:16:04.102] <TB3> INFO: buffer corruption: 0
[11:16:04.122] <TB3> INFO: Decoding statistics:
[11:16:04.122] <TB3> INFO: General information:
[11:16:04.122] <TB3> INFO: 16bit words read: 2755344
[11:16:04.122] <TB3> INFO: valid events total: 176768
[11:16:04.122] <TB3> INFO: empty events: 7868
[11:16:04.122] <TB3> INFO: valid events with pixels: 168900
[11:16:04.122] <TB3> INFO: valid pixel hits: 666475
[11:16:04.122] <TB3> INFO: Event errors: 0
[11:16:04.122] <TB3> INFO: start marker: 0
[11:16:04.122] <TB3> INFO: stop marker: 0
[11:16:04.122] <TB3> INFO: overflow: 0
[11:16:04.122] <TB3> INFO: invalid 5bit words: 0
[11:16:04.122] <TB3> INFO: invalid XOR eye diagram: 0
[11:16:04.122] <TB3> INFO: frame (failed synchr.): 0
[11:16:04.122] <TB3> INFO: idle data (no TBM trl): 0
[11:16:04.122] <TB3> INFO: no data (only TBM hdr): 0
[11:16:04.122] <TB3> INFO: TBM errors: 0
[11:16:04.122] <TB3> INFO: flawed TBM headers: 0
[11:16:04.122] <TB3> INFO: flawed TBM trailers: 0
[11:16:04.122] <TB3> INFO: event ID mismatches: 0
[11:16:04.122] <TB3> INFO: ROC errors: 0
[11:16:04.122] <TB3> INFO: missing ROC header(s): 0
[11:16:04.122] <TB3> INFO: misplaced readback start: 0
[11:16:04.122] <TB3> INFO: Pixel decoding errors: 0
[11:16:04.122] <TB3> INFO: pixel data incomplete: 0
[11:16:04.122] <TB3> INFO: pixel address: 0
[11:16:04.122] <TB3> INFO: pulse height fill bit: 0
[11:16:04.122] <TB3> INFO: buffer corruption: 0
[11:16:04.122] <TB3> INFO: enter test to run
[11:16:04.122] <TB3> INFO: test: exit no parameter change
[11:16:04.280] <TB3> QUIET: Connection to board 170 closed.
[11:16:04.359] <TB3> INFO: pXar: this is the end, my friend