[10:47:47.754] <TB3> INFO: *** Welcome to pxar ***
[10:47:47.754] <TB3> INFO: *** Today: 2016/05/04
[10:47:47.874] <TB3> INFO: *** Version: 9751-dirty
[10:47:47.874] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C15.dat
[10:47:47.875] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//tbmParameters_C0b.dat
[10:47:47.875] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//defaultMaskFile.dat
[10:47:47.875] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters_C15.dat
[10:47:47.945] <TB3> INFO: clk: 4
[10:47:47.945] <TB3> INFO: ctr: 4
[10:47:47.945] <TB3> INFO: sda: 19
[10:47:47.945] <TB3> INFO: tin: 9
[10:47:47.945] <TB3> INFO: level: 15
[10:47:47.945] <TB3> INFO: triggerdelay: 0
[10:47:47.946] <TB3> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[10:47:47.946] <TB3> INFO: Log level: INFO
[10:47:47.952] <TB3> INFO: Found DTB DTB_WZ4I6J
[10:47:47.960] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[10:47:47.962] <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:
------------------------------------------------------
[10:47:47.964] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[10:47:49.503] <TB3> INFO: DUT info:
[10:47:49.503] <TB3> INFO: The DUT currently contains the following objects:
[10:47:49.503] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[10:47:49.503] <TB3> INFO: TBM Core alpha (0): 7 registers set
[10:47:49.503] <TB3> INFO: TBM Core beta (1): 7 registers set
[10:47:49.503] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:47:49.503] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.503] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.503] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.503] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.503] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.503] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.503] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.503] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.503] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.503] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.503] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.503] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.504] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.504] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.504] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.504] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:47:49.904] <TB3> INFO: enter 'restricted' command line mode
[10:47:49.904] <TB3> INFO: enter test to run
[10:47:49.905] <TB3> INFO: test: pretest no parameter change
[10:47:49.905] <TB3> INFO: running: pretest
[10:47:49.909] <TB3> INFO: ----------------------------------------------------------------------
[10:47:49.909] <TB3> INFO: PixTestPretest::programROC()
[10:47:49.909] <TB3> INFO: ----------------------------------------------------------------------
[10:48:07.923] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:48:07.923] <TB3> INFO: IA differences per ROC: 18.5 22.5 19.3 18.5 20.9 19.3 18.5 21.7 18.5 19.3 18.5 17.7 19.3 22.5 20.9 19.3
[10:48:08.020] <TB3> INFO: enter test to run
[10:48:08.020] <TB3> INFO: test: pretest no parameter change
[10:48:08.020] <TB3> INFO: running: pretest
[10:48:08.021] <TB3> INFO: ----------------------------------------------------------------------
[10:48:08.021] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:48:08.021] <TB3> INFO: ----------------------------------------------------------------------
[10:48:16.627] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 379.4 mA = 23.7125 mA/ROC
[10:48:16.627] <TB3> INFO: i(loss) [mA/ROC]: 18.5 18.5 18.5 19.3 18.5 19.3 19.3 18.5 18.5 18.5 18.5 18.5 18.5 17.6 18.5 18.5
[10:48:16.648] <TB3> INFO: enter test to run
[10:48:16.648] <TB3> INFO: test: pretest no parameter change
[10:48:16.648] <TB3> INFO: running: pretest
[10:48:16.648] <TB3> INFO: ----------------------------------------------------------------------
[10:48:16.648] <TB3> INFO: PixTestPretest::findTiming()
[10:48:16.648] <TB3> INFO: ----------------------------------------------------------------------
[10:48:16.648] <TB3> INFO: PixTestCmd::init()

[10:49:45.926] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[10:49:45.926] <TB3> INFO: (success/tries = 100/100), width = 5
[10:49:45.927] <TB3> INFO: enter test to run
[10:49:45.927] <TB3> INFO: test: pretest no parameter change
[10:49:45.927] <TB3> INFO: running: pretest
[10:49:45.928] <TB3> INFO: ----------------------------------------------------------------------
[10:49:45.928] <TB3> INFO: PixTestPretest::findWorkingPixel()
[10:49:45.928] <TB3> INFO: ----------------------------------------------------------------------
[10:49:46.019] <TB3> INFO: Expecting 231680 events.


[10:49:56.091] <TB3> INFO: 231680 events read in total (9516ms).
[10:49:56.095] <TB3> INFO: Test took 10165ms.
[10:49:56.321] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:49:56.395] <TB3> INFO: enter test to run
[10:49:56.395] <TB3> INFO: test: pretest no parameter change
[10:49:56.395] <TB3> INFO: running: pretest
[10:49:56.396] <TB3> INFO: ----------------------------------------------------------------------
[10:49:56.396] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[10:49:56.396] <TB3> INFO: ----------------------------------------------------------------------
[10:49:56.488] <TB3> INFO: Expecting 231680 events.
[10:50:06.580] <TB3> INFO: 231680 events read in total (9535ms).
[10:50:06.584] <TB3> INFO: Test took 10184ms.
[10:50:06.832] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[10:50:06.832] <TB3> INFO: CalDel: 129 147 138 154 144 133 142 125 141 124 157 139 141 127 144 134
[10:50:06.832] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:50:06.869] <TB3> INFO: enter test to run
[10:50:06.869] <TB3> INFO: test: pretest no parameter change
[10:50:06.869] <TB3> INFO: running: pretest
[10:50:06.869] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C0.dat
[10:50:06.869] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C1.dat
[10:50:06.869] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C2.dat
[10:50:06.870] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C3.dat
[10:50:06.870] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C4.dat
[10:50:06.870] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C5.dat
[10:50:06.870] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C6.dat
[10:50:06.870] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C7.dat
[10:50:06.870] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C8.dat
[10:50:06.870] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C9.dat
[10:50:06.870] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C10.dat
[10:50:06.871] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C11.dat
[10:50:06.871] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C12.dat
[10:50:06.871] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C13.dat
[10:50:06.871] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C14.dat
[10:50:06.871] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters_C15.dat
[10:50:06.871] <TB3> INFO: enter test to run
[10:50:06.871] <TB3> INFO: test: fulltest no parameter change
[10:50:06.871] <TB3> INFO: running: fulltest
[10:50:06.871] <TB3> INFO: ######################################################################
[10:50:06.871] <TB3> INFO: PixTestFullTest::doTest()
[10:50:06.871] <TB3> INFO: ######################################################################
[10:50:06.872] <TB3> INFO: ######################################################################
[10:50:06.872] <TB3> INFO: PixTestAlive::doTest()
[10:50:06.872] <TB3> INFO: ######################################################################
[10:50:06.874] <TB3> INFO: ----------------------------------------------------------------------
[10:50:06.874] <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)
[10:50:06.874] <TB3> INFO: ----------------------------------------------------------------------
[10:50:07.139] <TB3> INFO: Expecting 41600 events.
[10:50:10.953] <TB3> INFO: 41600 events read in total (3258ms).
[10:50:10.953] <TB3> INFO: Test took 4078ms.
[10:50:11.186] <TB3> INFO: PixTestAlive::aliveTest() done
[10:50:11.186] <TB3> INFO: number of dead pixels (per ROC): 0 4 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:50:11.187] <TB3> INFO: ----------------------------------------------------------------------
[10:50:11.187] <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)
[10:50:11.187] <TB3> INFO: ----------------------------------------------------------------------
[10:50:11.480] <TB3> INFO: Expecting 41600 events.
[10:50:14.465] <TB3> INFO: 41600 events read in total (2429ms).
[10:50:14.465] <TB3> INFO: Test took 3276ms.
[10:50:14.466] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:50:14.717] <TB3> INFO: PixTestAlive::maskTest() done
[10:50:14.717] <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
[10:50:14.719] <TB3> INFO: ----------------------------------------------------------------------
[10:50:14.719] <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)
[10:50:14.719] <TB3> INFO: ----------------------------------------------------------------------
[10:50:14.993] <TB3> INFO: Expecting 41600 events.
[10:50:18.806] <TB3> INFO: 41600 events read in total (3257ms).
[10:50:18.807] <TB3> INFO: Test took 4086ms.
[10:50:19.031] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[10:50:19.031] <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
[10:50:19.031] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[10:50:19.031] <TB3> INFO: Decoding statistics:
[10:50:19.031] <TB3> INFO: General information:
[10:50:19.031] <TB3> INFO: 16bit words read: 0
[10:50:19.031] <TB3> INFO: valid events total: 0
[10:50:19.031] <TB3> INFO: empty events: 0
[10:50:19.031] <TB3> INFO: valid events with pixels: 0
[10:50:19.031] <TB3> INFO: valid pixel hits: 0
[10:50:19.031] <TB3> INFO: Event errors: 0
[10:50:19.031] <TB3> INFO: start marker: 0
[10:50:19.031] <TB3> INFO: stop marker: 0
[10:50:19.031] <TB3> INFO: overflow: 0
[10:50:19.031] <TB3> INFO: invalid 5bit words: 0
[10:50:19.031] <TB3> INFO: invalid XOR eye diagram: 0
[10:50:19.031] <TB3> INFO: frame (failed synchr.): 0
[10:50:19.031] <TB3> INFO: idle data (no TBM trl): 0
[10:50:19.031] <TB3> INFO: no data (only TBM hdr): 0
[10:50:19.032] <TB3> INFO: TBM errors: 0
[10:50:19.032] <TB3> INFO: flawed TBM headers: 0
[10:50:19.032] <TB3> INFO: flawed TBM trailers: 0
[10:50:19.032] <TB3> INFO: event ID mismatches: 0
[10:50:19.032] <TB3> INFO: ROC errors: 0
[10:50:19.032] <TB3> INFO: missing ROC header(s): 0
[10:50:19.032] <TB3> INFO: misplaced readback start: 0
[10:50:19.032] <TB3> INFO: Pixel decoding errors: 0
[10:50:19.032] <TB3> INFO: pixel data incomplete: 0
[10:50:19.032] <TB3> INFO: pixel address: 0
[10:50:19.032] <TB3> INFO: pulse height fill bit: 0
[10:50:19.032] <TB3> INFO: buffer corruption: 0
[10:50:19.041] <TB3> INFO: ######################################################################
[10:50:19.041] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:50:19.041] <TB3> INFO: ######################################################################
[10:50:19.043] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:50:19.055] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:50:19.055] <TB3> INFO: run 1 of 1
[10:50:19.318] <TB3> INFO: Expecting 3120000 events.
[10:51:00.629] <TB3> INFO: 858575 events read in total (40754ms).
[10:51:41.462] <TB3> INFO: 1708070 events read in total (81587ms).
[10:52:22.180] <TB3> INFO: 2570640 events read in total (122306ms).
[10:52:49.242] <TB3> INFO: 3120000 events read in total (149367ms).
[10:52:49.307] <TB3> INFO: Test took 150252ms.
[10:53:12.508] <TB3> INFO: PixTestBBMap::doTest() done, duration: 173 seconds
[10:53:12.508] <TB3> INFO: number of dead bumps (per ROC): 3 1 0 0 0 0 0 0 0 0 0 0 0 0 0 2
[10:53:12.508] <TB3> INFO: separation cut (per ROC): 73 91 75 71 93 98 80 93 87 92 76 66 91 93 86 85
[10:53:12.508] <TB3> INFO: Decoding statistics:
[10:53:12.508] <TB3> INFO: General information:
[10:53:12.508] <TB3> INFO: 16bit words read: 0
[10:53:12.508] <TB3> INFO: valid events total: 0
[10:53:12.508] <TB3> INFO: empty events: 0
[10:53:12.508] <TB3> INFO: valid events with pixels: 0
[10:53:12.508] <TB3> INFO: valid pixel hits: 0
[10:53:12.508] <TB3> INFO: Event errors: 0
[10:53:12.508] <TB3> INFO: start marker: 0
[10:53:12.508] <TB3> INFO: stop marker: 0
[10:53:12.508] <TB3> INFO: overflow: 0
[10:53:12.508] <TB3> INFO: invalid 5bit words: 0
[10:53:12.508] <TB3> INFO: invalid XOR eye diagram: 0
[10:53:12.508] <TB3> INFO: frame (failed synchr.): 0
[10:53:12.508] <TB3> INFO: idle data (no TBM trl): 0
[10:53:12.508] <TB3> INFO: no data (only TBM hdr): 0
[10:53:12.508] <TB3> INFO: TBM errors: 0
[10:53:12.508] <TB3> INFO: flawed TBM headers: 0
[10:53:12.508] <TB3> INFO: flawed TBM trailers: 0
[10:53:12.508] <TB3> INFO: event ID mismatches: 0
[10:53:12.508] <TB3> INFO: ROC errors: 0
[10:53:12.508] <TB3> INFO: missing ROC header(s): 0
[10:53:12.508] <TB3> INFO: misplaced readback start: 0
[10:53:12.508] <TB3> INFO: Pixel decoding errors: 0
[10:53:12.508] <TB3> INFO: pixel data incomplete: 0
[10:53:12.508] <TB3> INFO: pixel address: 0
[10:53:12.508] <TB3> INFO: pulse height fill bit: 0
[10:53:12.508] <TB3> INFO: buffer corruption: 0
[10:53:12.583] <TB3> INFO: ######################################################################
[10:53:12.583] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:53:12.583] <TB3> INFO: ######################################################################
[10:53:12.583] <TB3> INFO: ----------------------------------------------------------------------
[10:53:12.583] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:53:12.583] <TB3> INFO: ----------------------------------------------------------------------
[10:53:12.583] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:53:12.591] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:53:12.591] <TB3> INFO: run 1 of 1
[10:53:12.855] <TB3> INFO: Expecting 26208000 events.
[10:53:46.609] <TB3> INFO: 888200 events read in total (33198ms).
[10:54:19.509] <TB3> INFO: 1762300 events read in total (66098ms).
[10:54:52.608] <TB3> INFO: 2633350 events read in total (99197ms).
[10:55:25.522] <TB3> INFO: 3504550 events read in total (132111ms).
[10:55:58.152] <TB3> INFO: 4375650 events read in total (164741ms).
[10:56:30.945] <TB3> INFO: 5244700 events read in total (197534ms).
[10:57:03.857] <TB3> INFO: 6116150 events read in total (230446ms).
[10:57:37.131] <TB3> INFO: 6984850 events read in total (263720ms).
[10:58:10.116] <TB3> INFO: 7852450 events read in total (296706ms).
[10:58:42.913] <TB3> INFO: 8721650 events read in total (329502ms).
[10:59:15.888] <TB3> INFO: 9591800 events read in total (362477ms).
[10:59:48.813] <TB3> INFO: 10459300 events read in total (395402ms).
[11:00:21.904] <TB3> INFO: 11326650 events read in total (428493ms).
[11:00:54.936] <TB3> INFO: 12196100 events read in total (461525ms).
[11:01:27.981] <TB3> INFO: 13061450 events read in total (494570ms).
[11:02:00.743] <TB3> INFO: 13919050 events read in total (527332ms).
[11:02:33.889] <TB3> INFO: 14777850 events read in total (560478ms).
[11:03:06.667] <TB3> INFO: 15635150 events read in total (593256ms).
[11:03:39.675] <TB3> INFO: 16489650 events read in total (626264ms).
[11:04:12.682] <TB3> INFO: 17343700 events read in total (659271ms).
[11:04:45.541] <TB3> INFO: 18199550 events read in total (692130ms).
[11:05:18.796] <TB3> INFO: 19054800 events read in total (725385ms).
[11:05:51.463] <TB3> INFO: 19908150 events read in total (758052ms).
[11:06:24.547] <TB3> INFO: 20763550 events read in total (791136ms).
[11:06:57.464] <TB3> INFO: 21616400 events read in total (824053ms).
[11:07:30.690] <TB3> INFO: 22470050 events read in total (857279ms).
[11:08:03.217] <TB3> INFO: 23326450 events read in total (889806ms).
[11:08:35.974] <TB3> INFO: 24180500 events read in total (922563ms).
[11:09:08.944] <TB3> INFO: 25035700 events read in total (955533ms).
[11:09:43.745] <TB3> INFO: 25899050 events read in total (990334ms).
[11:09:55.393] <TB3> INFO: 26208000 events read in total (1001982ms).
[11:09:55.425] <TB3> INFO: Test took 1002834ms.
[11:09:55.708] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:09:57.212] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:09:58.713] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:10:00.079] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:10:01.493] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:10:02.859] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:10:04.234] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:10:05.634] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:10:07.030] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:10:08.579] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:10:10.030] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:10:11.513] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:10:13.261] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:10:14.730] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:10:16.321] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:10:17.919] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:10:19.395] <TB3> INFO: PixTestScurves::scurves() done
[11:10:19.395] <TB3> INFO: Vcal mean: 80.25 95.36 84.79 70.76 89.34 98.25 84.07 86.54 99.37 90.52 81.93 72.05 93.10 90.45 89.37 77.15
[11:10:19.395] <TB3> INFO: Vcal RMS: 4.18 6.47 4.70 4.90 5.41 5.54 4.98 5.02 6.14 5.94 5.19 4.51 6.15 5.27 5.37 4.22
[11:10:19.395] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1026 seconds
[11:10:19.395] <TB3> INFO: Decoding statistics:
[11:10:19.395] <TB3> INFO: General information:
[11:10:19.395] <TB3> INFO: 16bit words read: 0
[11:10:19.395] <TB3> INFO: valid events total: 0
[11:10:19.395] <TB3> INFO: empty events: 0
[11:10:19.395] <TB3> INFO: valid events with pixels: 0
[11:10:19.395] <TB3> INFO: valid pixel hits: 0
[11:10:19.395] <TB3> INFO: Event errors: 0
[11:10:19.395] <TB3> INFO: start marker: 0
[11:10:19.395] <TB3> INFO: stop marker: 0
[11:10:19.395] <TB3> INFO: overflow: 0
[11:10:19.395] <TB3> INFO: invalid 5bit words: 0
[11:10:19.395] <TB3> INFO: invalid XOR eye diagram: 0
[11:10:19.395] <TB3> INFO: frame (failed synchr.): 0
[11:10:19.395] <TB3> INFO: idle data (no TBM trl): 0
[11:10:19.395] <TB3> INFO: no data (only TBM hdr): 0
[11:10:19.395] <TB3> INFO: TBM errors: 0
[11:10:19.395] <TB3> INFO: flawed TBM headers: 0
[11:10:19.395] <TB3> INFO: flawed TBM trailers: 0
[11:10:19.395] <TB3> INFO: event ID mismatches: 0
[11:10:19.395] <TB3> INFO: ROC errors: 0
[11:10:19.395] <TB3> INFO: missing ROC header(s): 0
[11:10:19.395] <TB3> INFO: misplaced readback start: 0
[11:10:19.395] <TB3> INFO: Pixel decoding errors: 0
[11:10:19.395] <TB3> INFO: pixel data incomplete: 0
[11:10:19.395] <TB3> INFO: pixel address: 0
[11:10:19.395] <TB3> INFO: pulse height fill bit: 0
[11:10:19.395] <TB3> INFO: buffer corruption: 0
[11:10:19.465] <TB3> INFO: ######################################################################
[11:10:19.465] <TB3> INFO: PixTestTrim::doTest()
[11:10:19.465] <TB3> INFO: ######################################################################
[11:10:19.466] <TB3> INFO: ----------------------------------------------------------------------
[11:10:19.466] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[11:10:19.466] <TB3> INFO: ----------------------------------------------------------------------
[11:10:19.544] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[11:10:19.544] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:10:19.552] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:10:19.552] <TB3> INFO: run 1 of 1
[11:10:19.812] <TB3> INFO: Expecting 6281600 events.
[11:10:59.851] <TB3> INFO: 1065590 events read in total (39483ms).
[11:11:38.831] <TB3> INFO: 2126650 events read in total (78463ms).
[11:12:17.617] <TB3> INFO: 3180620 events read in total (117249ms).
[11:12:56.442] <TB3> INFO: 4242750 events read in total (156074ms).
[11:13:35.731] <TB3> INFO: 5311680 events read in total (195363ms).
[11:14:11.278] <TB3> INFO: 6281600 events read in total (230910ms).
[11:14:11.311] <TB3> INFO: Test took 231759ms.
[11:14:29.622] <TB3> INFO: ROC 0 VthrComp = 81
[11:14:29.622] <TB3> INFO: ROC 1 VthrComp = 97
[11:14:29.623] <TB3> INFO: ROC 2 VthrComp = 88
[11:14:29.623] <TB3> INFO: ROC 3 VthrComp = 75
[11:14:29.623] <TB3> INFO: ROC 4 VthrComp = 92
[11:14:29.623] <TB3> INFO: ROC 5 VthrComp = 100
[11:14:29.623] <TB3> INFO: ROC 6 VthrComp = 87
[11:14:29.623] <TB3> INFO: ROC 7 VthrComp = 92
[11:14:29.623] <TB3> INFO: ROC 8 VthrComp = 93
[11:14:29.624] <TB3> INFO: ROC 9 VthrComp = 90
[11:14:29.624] <TB3> INFO: ROC 10 VthrComp = 78
[11:14:29.624] <TB3> INFO: ROC 11 VthrComp = 76
[11:14:29.624] <TB3> INFO: ROC 12 VthrComp = 89
[11:14:29.624] <TB3> INFO: ROC 13 VthrComp = 90
[11:14:29.624] <TB3> INFO: ROC 14 VthrComp = 94
[11:14:29.624] <TB3> INFO: ROC 15 VthrComp = 82
[11:14:29.624] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[11:14:29.624] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:14:29.632] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:14:29.632] <TB3> INFO: run 1 of 1
[11:14:29.892] <TB3> INFO: Expecting 6281600 events.
[11:15:05.472] <TB3> INFO: 755920 events read in total (35022ms).
[11:15:40.543] <TB3> INFO: 1509660 events read in total (70093ms).
[11:16:15.463] <TB3> INFO: 2263730 events read in total (105013ms).
[11:16:50.132] <TB3> INFO: 3018910 events read in total (139683ms).
[11:17:25.250] <TB3> INFO: 3768520 events read in total (174800ms).
[11:18:00.268] <TB3> INFO: 4514700 events read in total (209818ms).
[11:18:35.291] <TB3> INFO: 5259120 events read in total (244841ms).
[11:19:11.801] <TB3> INFO: 6003620 events read in total (281351ms).
[11:19:24.895] <TB3> INFO: 6281600 events read in total (294445ms).
[11:19:24.937] <TB3> INFO: Test took 295305ms.
[11:19:48.359] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 56.9986 for pixel 23/2 mean/min/max = 44.8073/32.3329/57.2818
[11:19:48.359] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 60.9926 for pixel 6/75 mean/min/max = 46.3753/31.5685/61.1821
[11:19:48.360] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 56.862 for pixel 24/17 mean/min/max = 45.0008/32.9326/57.0691
[11:19:48.360] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 58.0366 for pixel 9/51 mean/min/max = 46.1057/34.1366/58.0748
[11:19:48.360] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 58.4085 for pixel 2/0 mean/min/max = 45.7433/32.9785/58.5082
[11:19:48.360] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 59.0001 for pixel 6/1 mean/min/max = 45.0249/31.0477/59.0022
[11:19:48.361] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 58.2356 for pixel 15/79 mean/min/max = 44.9575/31.355/58.5599
[11:19:48.361] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 57.3824 for pixel 0/61 mean/min/max = 45.2106/32.9721/57.4492
[11:19:48.361] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 62.536 for pixel 19/79 mean/min/max = 47.1414/31.7323/62.5504
[11:19:48.361] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 62.1825 for pixel 25/78 mean/min/max = 47.5796/32.8826/62.2765
[11:19:48.361] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 63.8924 for pixel 29/78 mean/min/max = 49.4156/34.6345/64.1968
[11:19:48.362] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 57.1138 for pixel 15/73 mean/min/max = 46.3294/35.5091/57.1496
[11:19:48.362] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 62.9596 for pixel 2/1 mean/min/max = 47.4612/31.8946/63.0278
[11:19:48.362] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 58.5796 for pixel 51/63 mean/min/max = 45.6978/32.7258/58.6697
[11:19:48.362] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 57.4059 for pixel 51/57 mean/min/max = 44.4624/31.5114/57.4134
[11:19:48.363] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 56.7133 for pixel 29/79 mean/min/max = 44.5235/32.2529/56.7941
[11:19:48.363] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:19:48.451] <TB3> INFO: Expecting 514560 events.
[11:20:01.404] <TB3> INFO: 514560 events read in total (12393ms).
[11:20:01.409] <TB3> INFO: Expecting 514560 events.
[11:20:14.214] <TB3> INFO: 514560 events read in total (12389ms).
[11:20:14.222] <TB3> INFO: Expecting 514560 events.
[11:20:27.063] <TB3> INFO: 514560 events read in total (12425ms).
[11:20:27.072] <TB3> INFO: Expecting 514560 events.
[11:20:39.824] <TB3> INFO: 514560 events read in total (12338ms).
[11:20:39.834] <TB3> INFO: Expecting 514560 events.
[11:20:52.774] <TB3> INFO: 514560 events read in total (12518ms).
[11:20:52.786] <TB3> INFO: Expecting 514560 events.
[11:21:05.710] <TB3> INFO: 514560 events read in total (12509ms).
[11:21:05.726] <TB3> INFO: Expecting 514560 events.
[11:21:18.426] <TB3> INFO: 514560 events read in total (12294ms).
[11:21:18.442] <TB3> INFO: Expecting 514560 events.
[11:21:31.164] <TB3> INFO: 514560 events read in total (12309ms).
[11:21:31.184] <TB3> INFO: Expecting 514560 events.
[11:21:43.899] <TB3> INFO: 514560 events read in total (12313ms).
[11:21:43.918] <TB3> INFO: Expecting 514560 events.
[11:21:56.613] <TB3> INFO: 514560 events read in total (12286ms).
[11:21:56.633] <TB3> INFO: Expecting 514560 events.
[11:22:09.502] <TB3> INFO: 514560 events read in total (12457ms).
[11:22:09.525] <TB3> INFO: Expecting 514560 events.
[11:22:22.283] <TB3> INFO: 514560 events read in total (12354ms).
[11:22:22.306] <TB3> INFO: Expecting 514560 events.
[11:22:35.046] <TB3> INFO: 514560 events read in total (12334ms).
[11:22:35.071] <TB3> INFO: Expecting 514560 events.
[11:22:47.975] <TB3> INFO: 514560 events read in total (12500ms).
[11:22:48.003] <TB3> INFO: Expecting 514560 events.
[11:23:00.665] <TB3> INFO: 514560 events read in total (12258ms).
[11:23:00.698] <TB3> INFO: Expecting 514560 events.
[11:23:13.465] <TB3> INFO: 514560 events read in total (12365ms).
[11:23:13.497] <TB3> INFO: Test took 205134ms.
[11:23:14.450] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[11:23:14.462] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:23:14.462] <TB3> INFO: run 1 of 1
[11:23:14.740] <TB3> INFO: Expecting 6281600 events.
[11:23:49.879] <TB3> INFO: 741310 events read in total (34583ms).
[11:24:24.652] <TB3> INFO: 1480870 events read in total (69356ms).
[11:24:59.611] <TB3> INFO: 2220660 events read in total (104315ms).
[11:25:34.105] <TB3> INFO: 2961370 events read in total (138809ms).
[11:26:08.931] <TB3> INFO: 3696340 events read in total (173635ms).
[11:26:43.457] <TB3> INFO: 4426990 events read in total (208161ms).
[11:27:17.778] <TB3> INFO: 5155920 events read in total (242482ms).
[11:27:53.215] <TB3> INFO: 5884110 events read in total (277919ms).
[11:28:12.236] <TB3> INFO: 6281600 events read in total (296940ms).
[11:28:12.280] <TB3> INFO: Test took 297818ms.
[11:28:35.332] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.010291 .. 255.000000
[11:28:35.407] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[11:28:35.415] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:28:35.415] <TB3> INFO: run 1 of 1
[11:28:35.675] <TB3> INFO: Expecting 10649600 events.
[11:29:10.777] <TB3> INFO: 712320 events read in total (34544ms).
[11:29:45.077] <TB3> INFO: 1424440 events read in total (68844ms).
[11:30:19.452] <TB3> INFO: 2136640 events read in total (103219ms).
[11:30:53.687] <TB3> INFO: 2849620 events read in total (137454ms).
[11:31:27.620] <TB3> INFO: 3561840 events read in total (171387ms).
[11:32:01.840] <TB3> INFO: 4274290 events read in total (205607ms).
[11:32:36.328] <TB3> INFO: 4986590 events read in total (240095ms).
[11:33:10.820] <TB3> INFO: 5699230 events read in total (274587ms).
[11:33:45.234] <TB3> INFO: 6411380 events read in total (309001ms).
[11:34:19.596] <TB3> INFO: 7122680 events read in total (343363ms).
[11:34:54.081] <TB3> INFO: 7834130 events read in total (377848ms).
[11:35:28.306] <TB3> INFO: 8544750 events read in total (412073ms).
[11:36:02.662] <TB3> INFO: 9255530 events read in total (446429ms).
[11:36:37.090] <TB3> INFO: 9966240 events read in total (480857ms).
[11:37:11.009] <TB3> INFO: 10649600 events read in total (514776ms).
[11:37:11.131] <TB3> INFO: Test took 515717ms.
[11:37:41.634] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 14.684925 .. 67.456165
[11:37:41.709] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 4 .. 77 (-1/-1) hits flags = 528 (plus default)
[11:37:41.717] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:37:41.717] <TB3> INFO: run 1 of 1
[11:37:41.977] <TB3> INFO: Expecting 3078400 events.
[11:38:18.816] <TB3> INFO: 837740 events read in total (36283ms).
[11:38:55.219] <TB3> INFO: 1675850 events read in total (72686ms).
[11:39:32.044] <TB3> INFO: 2512590 events read in total (109513ms).
[11:39:56.949] <TB3> INFO: 3078400 events read in total (134416ms).
[11:39:56.974] <TB3> INFO: Test took 135256ms.
[11:40:13.798] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 1.051912 .. 67.456165
[11:40:13.876] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 1 .. 77 (-1/-1) hits flags = 528 (plus default)
[11:40:13.884] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:40:13.884] <TB3> INFO: run 1 of 1
[11:40:14.151] <TB3> INFO: Expecting 3203200 events.
[11:40:51.472] <TB3> INFO: 851600 events read in total (36765ms).
[11:41:28.110] <TB3> INFO: 1703550 events read in total (73405ms).
[11:42:04.610] <TB3> INFO: 2554540 events read in total (109903ms).
[11:42:32.224] <TB3> INFO: 3203200 events read in total (137517ms).
[11:42:32.257] <TB3> INFO: Test took 138373ms.
[11:42:48.438] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 1.051912 .. 67.456165
[11:42:48.514] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 1 .. 77 (-1/-1) hits flags = 528 (plus default)
[11:42:48.522] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:42:48.522] <TB3> INFO: run 1 of 1
[11:42:48.784] <TB3> INFO: Expecting 3203200 events.
[11:43:25.966] <TB3> INFO: 851830 events read in total (36626ms).
[11:44:02.501] <TB3> INFO: 1703660 events read in total (73161ms).
[11:44:39.100] <TB3> INFO: 2555270 events read in total (109761ms).
[11:45:07.196] <TB3> INFO: 3203200 events read in total (137856ms).
[11:45:07.217] <TB3> INFO: Test took 138695ms.
[11:45:24.661] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:45:24.661] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:45:24.669] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:45:24.669] <TB3> INFO: run 1 of 1
[11:45:24.928] <TB3> INFO: Expecting 1705600 events.
[11:46:03.143] <TB3> INFO: 878460 events read in total (37659ms).
[11:46:38.571] <TB3> INFO: 1705600 events read in total (73087ms).
[11:46:38.595] <TB3> INFO: Test took 73927ms.
[11:46:52.191] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:46:52.191] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:46:52.191] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:46:52.191] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:46:52.191] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:46:52.191] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:46:52.192] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:46:52.192] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:46:52.192] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:46:52.192] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:46:52.192] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:46:52.192] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:46:52.192] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:46:52.192] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:46:52.192] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:46:52.193] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:46:52.193] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C0.dat
[11:46:52.199] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C1.dat
[11:46:52.205] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C2.dat
[11:46:52.212] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C3.dat
[11:46:52.218] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C4.dat
[11:46:52.224] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C5.dat
[11:46:52.230] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C6.dat
[11:46:52.236] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C7.dat
[11:46:52.242] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C8.dat
[11:46:52.249] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C9.dat
[11:46:52.256] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C10.dat
[11:46:52.262] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C11.dat
[11:46:52.269] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C12.dat
[11:46:52.275] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C13.dat
[11:46:52.281] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C14.dat
[11:46:52.287] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//trimParameters35_C15.dat
[11:46:52.293] <TB3> INFO: PixTestTrim::trimTest() done
[11:46:52.293] <TB3> INFO: vtrim: 91 101 81 85 100 101 85 95 95 99 102 90 108 89 91 97
[11:46:52.293] <TB3> INFO: vthrcomp: 81 97 88 75 92 100 87 92 93 90 78 76 89 90 94 82
[11:46:52.293] <TB3> INFO: vcal mean: 34.98 34.94 34.98 35.01 35.00 34.98 34.96 35.01 34.97 35.01 35.08 35.05 34.98 34.99 34.98 34.96
[11:46:52.293] <TB3> INFO: vcal RMS: 0.87 1.32 0.85 0.83 0.89 1.05 0.89 0.84 1.10 0.91 0.96 0.81 0.99 0.84 0.89 0.85
[11:46:52.293] <TB3> INFO: bits mean: 9.86 9.56 9.72 9.20 9.52 10.17 9.59 9.26 9.27 8.99 8.30 8.31 9.15 9.02 9.44 9.84
[11:46:52.293] <TB3> INFO: bits RMS: 2.55 2.68 2.50 2.51 2.60 2.53 2.80 2.70 2.73 2.70 2.53 2.61 2.75 2.79 2.87 2.55
[11:46:52.300] <TB3> INFO: ----------------------------------------------------------------------
[11:46:52.300] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:46:52.300] <TB3> INFO: ----------------------------------------------------------------------
[11:46:52.303] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:46:52.310] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:46:52.311] <TB3> INFO: run 1 of 1
[11:46:52.595] <TB3> INFO: Expecting 4160000 events.
[11:47:34.588] <TB3> INFO: 920295 events read in total (41437ms).
[11:48:15.732] <TB3> INFO: 1834660 events read in total (82581ms).
[11:48:56.957] <TB3> INFO: 2741435 events read in total (123807ms).
[11:49:37.985] <TB3> INFO: 3645405 events read in total (164834ms).
[11:50:01.640] <TB3> INFO: 4160000 events read in total (188489ms).
[11:50:01.681] <TB3> INFO: Test took 189370ms.
[11:50:33.156] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 171 (-1/-1) hits flags = 528 (plus default)
[11:50:33.165] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:50:33.165] <TB3> INFO: run 1 of 1
[11:50:33.457] <TB3> INFO: Expecting 3577600 events.
[11:51:15.899] <TB3> INFO: 939885 events read in total (41885ms).
[11:51:58.086] <TB3> INFO: 1872835 events read in total (84072ms).
[11:52:39.590] <TB3> INFO: 2795790 events read in total (125576ms).
[11:53:14.957] <TB3> INFO: 3577600 events read in total (160943ms).
[11:53:14.994] <TB3> INFO: Test took 161829ms.
[11:53:44.313] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 156 (-1/-1) hits flags = 528 (plus default)
[11:53:44.322] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:53:44.322] <TB3> INFO: run 1 of 1
[11:53:44.612] <TB3> INFO: Expecting 3265600 events.
[11:54:27.774] <TB3> INFO: 977405 events read in total (42606ms).
[11:55:10.702] <TB3> INFO: 1944525 events read in total (85534ms).
[11:55:53.068] <TB3> INFO: 2902350 events read in total (127900ms).
[11:56:09.633] <TB3> INFO: 3265600 events read in total (144465ms).
[11:56:09.669] <TB3> INFO: Test took 145348ms.
[11:56:33.428] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 156 (-1/-1) hits flags = 528 (plus default)
[11:56:33.440] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:56:33.440] <TB3> INFO: run 1 of 1
[11:56:33.721] <TB3> INFO: Expecting 3265600 events.
[11:57:17.042] <TB3> INFO: 976385 events read in total (42763ms).
[11:57:59.423] <TB3> INFO: 1942445 events read in total (85144ms).
[11:58:41.500] <TB3> INFO: 2898975 events read in total (127221ms).
[11:58:57.768] <TB3> INFO: 3265600 events read in total (143489ms).
[11:58:57.799] <TB3> INFO: Test took 144359ms.
[11:59:23.599] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 157 (-1/-1) hits flags = 528 (plus default)
[11:59:23.607] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:59:23.607] <TB3> INFO: run 1 of 1
[11:59:23.870] <TB3> INFO: Expecting 3286400 events.
[12:00:07.189] <TB3> INFO: 972770 events read in total (42763ms).
[12:00:49.440] <TB3> INFO: 1935165 events read in total (85014ms).
[12:01:31.594] <TB3> INFO: 2888040 events read in total (127169ms).
[12:01:49.515] <TB3> INFO: 3286400 events read in total (145089ms).
[12:01:49.549] <TB3> INFO: Test took 145941ms.
[12:02:16.010] <TB3> INFO: PixTestTrim::trimBitTest() done
[12:02:16.011] <TB3> INFO: PixTestTrim::doTest() done, duration: 3116 seconds
[12:02:16.012] <TB3> INFO: Decoding statistics:
[12:02:16.012] <TB3> INFO: General information:
[12:02:16.012] <TB3> INFO: 16bit words read: 0
[12:02:16.012] <TB3> INFO: valid events total: 0
[12:02:16.012] <TB3> INFO: empty events: 0
[12:02:16.012] <TB3> INFO: valid events with pixels: 0
[12:02:16.012] <TB3> INFO: valid pixel hits: 0
[12:02:16.012] <TB3> INFO: Event errors: 0
[12:02:16.012] <TB3> INFO: start marker: 0
[12:02:16.012] <TB3> INFO: stop marker: 0
[12:02:16.012] <TB3> INFO: overflow: 0
[12:02:16.012] <TB3> INFO: invalid 5bit words: 0
[12:02:16.012] <TB3> INFO: invalid XOR eye diagram: 0
[12:02:16.012] <TB3> INFO: frame (failed synchr.): 0
[12:02:16.012] <TB3> INFO: idle data (no TBM trl): 0
[12:02:16.012] <TB3> INFO: no data (only TBM hdr): 0
[12:02:16.012] <TB3> INFO: TBM errors: 0
[12:02:16.012] <TB3> INFO: flawed TBM headers: 0
[12:02:16.012] <TB3> INFO: flawed TBM trailers: 0
[12:02:16.012] <TB3> INFO: event ID mismatches: 0
[12:02:16.012] <TB3> INFO: ROC errors: 0
[12:02:16.012] <TB3> INFO: missing ROC header(s): 0
[12:02:16.012] <TB3> INFO: misplaced readback start: 0
[12:02:16.012] <TB3> INFO: Pixel decoding errors: 0
[12:02:16.012] <TB3> INFO: pixel data incomplete: 0
[12:02:16.012] <TB3> INFO: pixel address: 0
[12:02:16.012] <TB3> INFO: pulse height fill bit: 0
[12:02:16.012] <TB3> INFO: buffer corruption: 0
[12:02:16.731] <TB3> INFO: ######################################################################
[12:02:16.731] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[12:02:16.731] <TB3> INFO: ######################################################################
[12:02:17.009] <TB3> INFO: Expecting 41600 events.
[12:02:20.783] <TB3> INFO: 41600 events read in total (3218ms).
[12:02:20.784] <TB3> INFO: Test took 4052ms.
[12:02:21.269] <TB3> INFO: Expecting 41600 events.
[12:02:25.105] <TB3> INFO: 41600 events read in total (3280ms).
[12:02:25.105] <TB3> INFO: Test took 4117ms.
[12:02:25.391] <TB3> INFO: Expecting 41600 events.
[12:02:29.225] <TB3> INFO: 41600 events read in total (3278ms).
[12:02:29.226] <TB3> INFO: Test took 4099ms.
[12:02:29.481] <TB3> INFO: Expecting 2560 events.
[12:02:30.335] <TB3> INFO: 2560 events read in total (297ms).
[12:02:30.335] <TB3> INFO: Test took 1095ms.
[12:02:30.640] <TB3> INFO: Expecting 2560 events.
[12:02:31.495] <TB3> INFO: 2560 events read in total (299ms).
[12:02:31.495] <TB3> INFO: Test took 1160ms.
[12:02:31.800] <TB3> INFO: Expecting 2560 events.
[12:02:32.653] <TB3> INFO: 2560 events read in total (297ms).
[12:02:32.653] <TB3> INFO: Test took 1157ms.
[12:02:32.959] <TB3> INFO: Expecting 2560 events.
[12:02:33.812] <TB3> INFO: 2560 events read in total (297ms).
[12:02:33.812] <TB3> INFO: Test took 1158ms.
[12:02:34.118] <TB3> INFO: Expecting 2560 events.
[12:02:34.973] <TB3> INFO: 2560 events read in total (299ms).
[12:02:34.973] <TB3> INFO: Test took 1160ms.
[12:02:35.278] <TB3> INFO: Expecting 2560 events.
[12:02:36.133] <TB3> INFO: 2560 events read in total (299ms).
[12:02:36.133] <TB3> INFO: Test took 1159ms.
[12:02:36.438] <TB3> INFO: Expecting 2560 events.
[12:02:37.292] <TB3> INFO: 2560 events read in total (298ms).
[12:02:37.292] <TB3> INFO: Test took 1158ms.
[12:02:37.597] <TB3> INFO: Expecting 2560 events.
[12:02:38.452] <TB3> INFO: 2560 events read in total (299ms).
[12:02:38.452] <TB3> INFO: Test took 1159ms.
[12:02:38.758] <TB3> INFO: Expecting 2560 events.
[12:02:39.611] <TB3> INFO: 2560 events read in total (297ms).
[12:02:39.613] <TB3> INFO: Test took 1160ms.
[12:02:39.917] <TB3> INFO: Expecting 2560 events.
[12:02:40.770] <TB3> INFO: 2560 events read in total (297ms).
[12:02:40.771] <TB3> INFO: Test took 1158ms.
[12:02:41.076] <TB3> INFO: Expecting 2560 events.
[12:02:41.930] <TB3> INFO: 2560 events read in total (298ms).
[12:02:41.930] <TB3> INFO: Test took 1159ms.
[12:02:42.235] <TB3> INFO: Expecting 2560 events.
[12:02:43.088] <TB3> INFO: 2560 events read in total (297ms).
[12:02:43.088] <TB3> INFO: Test took 1157ms.
[12:02:43.393] <TB3> INFO: Expecting 2560 events.
[12:02:44.249] <TB3> INFO: 2560 events read in total (300ms).
[12:02:44.249] <TB3> INFO: Test took 1160ms.
[12:02:44.554] <TB3> INFO: Expecting 2560 events.
[12:02:45.407] <TB3> INFO: 2560 events read in total (296ms).
[12:02:45.408] <TB3> INFO: Test took 1158ms.
[12:02:45.713] <TB3> INFO: Expecting 2560 events.
[12:02:46.567] <TB3> INFO: 2560 events read in total (298ms).
[12:02:46.567] <TB3> INFO: Test took 1159ms.
[12:02:46.872] <TB3> INFO: Expecting 2560 events.
[12:02:47.726] <TB3> INFO: 2560 events read in total (298ms).
[12:02:47.726] <TB3> INFO: Test took 1158ms.
[12:02:47.729] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:02:48.032] <TB3> INFO: Expecting 655360 events.
[12:03:04.871] <TB3> INFO: 655360 events read in total (16283ms).
[12:03:04.880] <TB3> INFO: Expecting 655360 events.
[12:03:21.670] <TB3> INFO: 655360 events read in total (16389ms).
[12:03:21.682] <TB3> INFO: Expecting 655360 events.
[12:03:38.113] <TB3> INFO: 655360 events read in total (16030ms).
[12:03:38.131] <TB3> INFO: Expecting 655360 events.
[12:03:54.643] <TB3> INFO: 655360 events read in total (16111ms).
[12:03:54.665] <TB3> INFO: Expecting 655360 events.
[12:04:11.467] <TB3> INFO: 655360 events read in total (16401ms).
[12:04:11.491] <TB3> INFO: Expecting 655360 events.
[12:04:28.341] <TB3> INFO: 655360 events read in total (16449ms).
[12:04:28.368] <TB3> INFO: Expecting 655360 events.
[12:04:45.020] <TB3> INFO: 655360 events read in total (16246ms).
[12:04:45.050] <TB3> INFO: Expecting 655360 events.
[12:05:01.773] <TB3> INFO: 655360 events read in total (16321ms).
[12:05:01.807] <TB3> INFO: Expecting 655360 events.
[12:05:18.588] <TB3> INFO: 655360 events read in total (16379ms).
[12:05:18.624] <TB3> INFO: Expecting 655360 events.
[12:05:35.397] <TB3> INFO: 655360 events read in total (16372ms).
[12:05:35.437] <TB3> INFO: Expecting 655360 events.
[12:05:52.194] <TB3> INFO: 655360 events read in total (16355ms).
[12:05:52.243] <TB3> INFO: Expecting 655360 events.
[12:06:09.060] <TB3> INFO: 655360 events read in total (16415ms).
[12:06:09.106] <TB3> INFO: Expecting 655360 events.
[12:06:25.951] <TB3> INFO: 655360 events read in total (16443ms).
[12:06:26.004] <TB3> INFO: Expecting 655360 events.
[12:06:42.696] <TB3> INFO: 655360 events read in total (16291ms).
[12:06:42.751] <TB3> INFO: Expecting 655360 events.
[12:06:59.438] <TB3> INFO: 655360 events read in total (16285ms).
[12:06:59.499] <TB3> INFO: Expecting 655360 events.
[12:07:16.284] <TB3> INFO: 655360 events read in total (16383ms).
[12:07:16.354] <TB3> INFO: Test took 268625ms.
[12:07:16.444] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:07:16.671] <TB3> INFO: Expecting 655360 events.
[12:07:33.602] <TB3> INFO: 655360 events read in total (16375ms).
[12:07:33.612] <TB3> INFO: Expecting 655360 events.
[12:07:50.359] <TB3> INFO: 655360 events read in total (16345ms).
[12:07:50.372] <TB3> INFO: Expecting 655360 events.
[12:08:06.894] <TB3> INFO: 655360 events read in total (16121ms).
[12:08:06.909] <TB3> INFO: Expecting 655360 events.
[12:08:23.548] <TB3> INFO: 655360 events read in total (16237ms).
[12:08:23.568] <TB3> INFO: Expecting 655360 events.
[12:08:40.258] <TB3> INFO: 655360 events read in total (16289ms).
[12:08:40.284] <TB3> INFO: Expecting 655360 events.
[12:08:56.948] <TB3> INFO: 655360 events read in total (16263ms).
[12:08:56.976] <TB3> INFO: Expecting 655360 events.
[12:09:13.427] <TB3> INFO: 655360 events read in total (16049ms).
[12:09:13.457] <TB3> INFO: Expecting 655360 events.
[12:09:30.348] <TB3> INFO: 655360 events read in total (16489ms).
[12:09:30.384] <TB3> INFO: Expecting 655360 events.
[12:09:47.068] <TB3> INFO: 655360 events read in total (16282ms).
[12:09:47.106] <TB3> INFO: Expecting 655360 events.
[12:10:03.801] <TB3> INFO: 655360 events read in total (16293ms).
[12:10:03.845] <TB3> INFO: Expecting 655360 events.
[12:10:20.724] <TB3> INFO: 655360 events read in total (16477ms).
[12:10:20.769] <TB3> INFO: Expecting 655360 events.
[12:10:37.604] <TB3> INFO: 655360 events read in total (16434ms).
[12:10:37.653] <TB3> INFO: Expecting 655360 events.
[12:10:54.112] <TB3> INFO: 655360 events read in total (16057ms).
[12:10:54.171] <TB3> INFO: Expecting 655360 events.
[12:11:10.874] <TB3> INFO: 655360 events read in total (16301ms).
[12:11:10.927] <TB3> INFO: Expecting 655360 events.
[12:11:27.721] <TB3> INFO: 655360 events read in total (16392ms).
[12:11:27.779] <TB3> INFO: Expecting 655360 events.
[12:11:44.556] <TB3> INFO: 655360 events read in total (16375ms).
[12:11:44.614] <TB3> INFO: Test took 268170ms.
[12:11:44.832] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.839] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[12:11:44.846] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[12:11:44.853] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.860] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.867] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.874] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.883] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[12:11:44.891] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.898] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.905] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.912] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.920] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.927] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.934] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.941] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.950] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.957] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.965] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:11:44.996] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C0.dat
[12:11:44.996] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C1.dat
[12:11:44.996] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C2.dat
[12:11:44.996] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C3.dat
[12:11:44.996] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C4.dat
[12:11:44.996] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C5.dat
[12:11:45.019] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C6.dat
[12:11:45.019] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C7.dat
[12:11:45.019] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C8.dat
[12:11:45.019] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C9.dat
[12:11:45.019] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C10.dat
[12:11:45.019] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C11.dat
[12:11:45.019] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C12.dat
[12:11:45.019] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C13.dat
[12:11:45.019] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C14.dat
[12:11:45.019] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//dacParameters35_C15.dat
[12:11:45.302] <TB3> INFO: Expecting 41600 events.
[12:11:48.813] <TB3> INFO: 41600 events read in total (2954ms).
[12:11:48.813] <TB3> INFO: Test took 3791ms.
[12:11:49.252] <TB3> INFO: Expecting 41600 events.
[12:11:52.760] <TB3> INFO: 41600 events read in total (2952ms).
[12:11:52.761] <TB3> INFO: Test took 3772ms.
[12:11:53.214] <TB3> INFO: Expecting 41600 events.
[12:11:56.685] <TB3> INFO: 41600 events read in total (2914ms).
[12:11:56.686] <TB3> INFO: Test took 3743ms.
[12:11:56.869] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:11:56.957] <TB3> INFO: Expecting 2560 events.
[12:11:57.811] <TB3> INFO: 2560 events read in total (298ms).
[12:11:57.811] <TB3> INFO: Test took 942ms.
[12:11:57.813] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:11:58.116] <TB3> INFO: Expecting 2560 events.
[12:11:58.970] <TB3> INFO: 2560 events read in total (297ms).
[12:11:58.971] <TB3> INFO: Test took 1158ms.
[12:11:58.973] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:11:59.280] <TB3> INFO: Expecting 2560 events.
[12:12:00.133] <TB3> INFO: 2560 events read in total (297ms).
[12:12:00.134] <TB3> INFO: Test took 1161ms.
[12:12:00.136] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:00.440] <TB3> INFO: Expecting 2560 events.
[12:12:01.299] <TB3> INFO: 2560 events read in total (303ms).
[12:12:01.300] <TB3> INFO: Test took 1164ms.
[12:12:01.301] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:01.605] <TB3> INFO: Expecting 2560 events.
[12:12:02.460] <TB3> INFO: 2560 events read in total (298ms).
[12:12:02.460] <TB3> INFO: Test took 1159ms.
[12:12:02.461] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:02.766] <TB3> INFO: Expecting 2560 events.
[12:12:03.621] <TB3> INFO: 2560 events read in total (299ms).
[12:12:03.622] <TB3> INFO: Test took 1161ms.
[12:12:03.624] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:03.927] <TB3> INFO: Expecting 2560 events.
[12:12:04.784] <TB3> INFO: 2560 events read in total (301ms).
[12:12:04.784] <TB3> INFO: Test took 1160ms.
[12:12:04.787] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:05.090] <TB3> INFO: Expecting 2560 events.
[12:12:05.950] <TB3> INFO: 2560 events read in total (304ms).
[12:12:05.950] <TB3> INFO: Test took 1163ms.
[12:12:05.953] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:06.256] <TB3> INFO: Expecting 2560 events.
[12:12:07.110] <TB3> INFO: 2560 events read in total (298ms).
[12:12:07.110] <TB3> INFO: Test took 1157ms.
[12:12:07.112] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:07.416] <TB3> INFO: Expecting 2560 events.
[12:12:08.270] <TB3> INFO: 2560 events read in total (298ms).
[12:12:08.270] <TB3> INFO: Test took 1158ms.
[12:12:08.273] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:08.576] <TB3> INFO: Expecting 2560 events.
[12:12:09.434] <TB3> INFO: 2560 events read in total (301ms).
[12:12:09.435] <TB3> INFO: Test took 1162ms.
[12:12:09.438] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:09.741] <TB3> INFO: Expecting 2560 events.
[12:12:10.599] <TB3> INFO: 2560 events read in total (302ms).
[12:12:10.599] <TB3> INFO: Test took 1162ms.
[12:12:10.601] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:10.906] <TB3> INFO: Expecting 2560 events.
[12:12:11.760] <TB3> INFO: 2560 events read in total (298ms).
[12:12:11.760] <TB3> INFO: Test took 1159ms.
[12:12:11.763] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:12.065] <TB3> INFO: Expecting 2560 events.
[12:12:12.925] <TB3> INFO: 2560 events read in total (303ms).
[12:12:12.925] <TB3> INFO: Test took 1162ms.
[12:12:12.927] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:13.231] <TB3> INFO: Expecting 2560 events.
[12:12:14.088] <TB3> INFO: 2560 events read in total (298ms).
[12:12:14.088] <TB3> INFO: Test took 1161ms.
[12:12:14.091] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:14.393] <TB3> INFO: Expecting 2560 events.
[12:12:15.246] <TB3> INFO: 2560 events read in total (296ms).
[12:12:15.247] <TB3> INFO: Test took 1156ms.
[12:12:15.249] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:15.553] <TB3> INFO: Expecting 2560 events.
[12:12:16.408] <TB3> INFO: 2560 events read in total (299ms).
[12:12:16.409] <TB3> INFO: Test took 1161ms.
[12:12:16.410] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:16.714] <TB3> INFO: Expecting 2560 events.
[12:12:17.568] <TB3> INFO: 2560 events read in total (298ms).
[12:12:17.568] <TB3> INFO: Test took 1158ms.
[12:12:17.570] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:17.874] <TB3> INFO: Expecting 2560 events.
[12:12:18.728] <TB3> INFO: 2560 events read in total (298ms).
[12:12:18.728] <TB3> INFO: Test took 1158ms.
[12:12:18.729] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:19.033] <TB3> INFO: Expecting 2560 events.
[12:12:19.886] <TB3> INFO: 2560 events read in total (297ms).
[12:12:19.886] <TB3> INFO: Test took 1157ms.
[12:12:19.888] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:20.192] <TB3> INFO: Expecting 2560 events.
[12:12:21.045] <TB3> INFO: 2560 events read in total (297ms).
[12:12:21.046] <TB3> INFO: Test took 1158ms.
[12:12:21.048] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:21.351] <TB3> INFO: Expecting 2560 events.
[12:12:22.204] <TB3> INFO: 2560 events read in total (297ms).
[12:12:22.205] <TB3> INFO: Test took 1158ms.
[12:12:22.207] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:22.510] <TB3> INFO: Expecting 2560 events.
[12:12:23.364] <TB3> INFO: 2560 events read in total (298ms).
[12:12:23.364] <TB3> INFO: Test took 1157ms.
[12:12:23.366] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:23.670] <TB3> INFO: Expecting 2560 events.
[12:12:24.523] <TB3> INFO: 2560 events read in total (297ms).
[12:12:24.523] <TB3> INFO: Test took 1157ms.
[12:12:24.525] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:24.829] <TB3> INFO: Expecting 2560 events.
[12:12:25.681] <TB3> INFO: 2560 events read in total (296ms).
[12:12:25.681] <TB3> INFO: Test took 1156ms.
[12:12:25.683] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:25.987] <TB3> INFO: Expecting 2560 events.
[12:12:26.843] <TB3> INFO: 2560 events read in total (300ms).
[12:12:26.843] <TB3> INFO: Test took 1160ms.
[12:12:26.845] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:27.148] <TB3> INFO: Expecting 2560 events.
[12:12:28.001] <TB3> INFO: 2560 events read in total (297ms).
[12:12:28.001] <TB3> INFO: Test took 1156ms.
[12:12:28.003] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:28.307] <TB3> INFO: Expecting 2560 events.
[12:12:29.160] <TB3> INFO: 2560 events read in total (297ms).
[12:12:29.161] <TB3> INFO: Test took 1158ms.
[12:12:29.163] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:29.466] <TB3> INFO: Expecting 2560 events.
[12:12:30.319] <TB3> INFO: 2560 events read in total (297ms).
[12:12:30.320] <TB3> INFO: Test took 1158ms.
[12:12:30.321] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:30.625] <TB3> INFO: Expecting 2560 events.
[12:12:31.479] <TB3> INFO: 2560 events read in total (298ms).
[12:12:31.479] <TB3> INFO: Test took 1158ms.
[12:12:31.481] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:31.785] <TB3> INFO: Expecting 2560 events.
[12:12:32.638] <TB3> INFO: 2560 events read in total (297ms).
[12:12:32.638] <TB3> INFO: Test took 1157ms.
[12:12:32.640] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:12:32.944] <TB3> INFO: Expecting 2560 events.
[12:12:33.797] <TB3> INFO: 2560 events read in total (297ms).
[12:12:33.797] <TB3> INFO: Test took 1157ms.
[12:12:34.248] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 617 seconds
[12:12:34.248] <TB3> INFO: PH scale (per ROC): 80 81 84 79 80 79 84 80 78 81 75 78 74 80 77 88
[12:12:34.248] <TB3> INFO: PH offset (per ROC): 149 172 162 149 159 166 152 170 173 175 162 147 158 159 169 159
[12:12:34.252] <TB3> INFO: Decoding statistics:
[12:12:34.252] <TB3> INFO: General information:
[12:12:34.252] <TB3> INFO: 16bit words read: 91920
[12:12:34.252] <TB3> INFO: valid events total: 10240
[12:12:34.252] <TB3> INFO: empty events: 7740
[12:12:34.252] <TB3> INFO: valid events with pixels: 2500
[12:12:34.252] <TB3> INFO: valid pixel hits: 2500
[12:12:34.252] <TB3> INFO: Event errors: 0
[12:12:34.252] <TB3> INFO: start marker: 0
[12:12:34.252] <TB3> INFO: stop marker: 0
[12:12:34.252] <TB3> INFO: overflow: 0
[12:12:34.252] <TB3> INFO: invalid 5bit words: 0
[12:12:34.252] <TB3> INFO: invalid XOR eye diagram: 0
[12:12:34.252] <TB3> INFO: frame (failed synchr.): 0
[12:12:34.252] <TB3> INFO: idle data (no TBM trl): 0
[12:12:34.252] <TB3> INFO: no data (only TBM hdr): 0
[12:12:34.252] <TB3> INFO: TBM errors: 0
[12:12:34.252] <TB3> INFO: flawed TBM headers: 0
[12:12:34.252] <TB3> INFO: flawed TBM trailers: 0
[12:12:34.252] <TB3> INFO: event ID mismatches: 0
[12:12:34.252] <TB3> INFO: ROC errors: 0
[12:12:34.252] <TB3> INFO: missing ROC header(s): 0
[12:12:34.252] <TB3> INFO: misplaced readback start: 0
[12:12:34.252] <TB3> INFO: Pixel decoding errors: 0
[12:12:34.252] <TB3> INFO: pixel data incomplete: 0
[12:12:34.252] <TB3> INFO: pixel address: 0
[12:12:34.252] <TB3> INFO: pulse height fill bit: 0
[12:12:34.252] <TB3> INFO: buffer corruption: 0
[12:12:34.431] <TB3> INFO: ######################################################################
[12:12:34.431] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[12:12:34.431] <TB3> INFO: ######################################################################
[12:12:34.441] <TB3> INFO: scanning low vcal = 10
[12:12:34.704] <TB3> INFO: Expecting 41600 events.
[12:12:38.227] <TB3> INFO: 41600 events read in total (2967ms).
[12:12:38.228] <TB3> INFO: Test took 3787ms.
[12:12:38.229] <TB3> INFO: scanning low vcal = 20
[12:12:38.533] <TB3> INFO: Expecting 41600 events.
[12:12:42.072] <TB3> INFO: 41600 events read in total (2983ms).
[12:12:42.073] <TB3> INFO: Test took 3844ms.
[12:12:42.074] <TB3> INFO: scanning low vcal = 30
[12:12:42.378] <TB3> INFO: Expecting 41600 events.
[12:12:45.936] <TB3> INFO: 41600 events read in total (3002ms).
[12:12:45.937] <TB3> INFO: Test took 3863ms.
[12:12:45.939] <TB3> INFO: scanning low vcal = 40
[12:12:46.285] <TB3> INFO: Expecting 41600 events.
[12:12:50.411] <TB3> INFO: 41600 events read in total (3570ms).
[12:12:50.412] <TB3> INFO: Test took 4473ms.
[12:12:50.415] <TB3> INFO: scanning low vcal = 50
[12:12:50.691] <TB3> INFO: Expecting 41600 events.
[12:12:54.881] <TB3> INFO: 41600 events read in total (3633ms).
[12:12:54.882] <TB3> INFO: Test took 4467ms.
[12:12:54.885] <TB3> INFO: scanning low vcal = 60
[12:12:55.144] <TB3> INFO: Expecting 41600 events.
[12:12:59.349] <TB3> INFO: 41600 events read in total (3649ms).
[12:12:59.350] <TB3> INFO: Test took 4465ms.
[12:12:59.352] <TB3> INFO: scanning low vcal = 70
[12:12:59.612] <TB3> INFO: Expecting 41600 events.
[12:13:03.850] <TB3> INFO: 41600 events read in total (3682ms).
[12:13:03.850] <TB3> INFO: Test took 4498ms.
[12:13:03.853] <TB3> INFO: scanning low vcal = 80
[12:13:04.113] <TB3> INFO: Expecting 41600 events.
[12:13:08.313] <TB3> INFO: 41600 events read in total (3644ms).
[12:13:08.314] <TB3> INFO: Test took 4461ms.
[12:13:08.317] <TB3> INFO: scanning low vcal = 90
[12:13:08.586] <TB3> INFO: Expecting 41600 events.
[12:13:12.812] <TB3> INFO: 41600 events read in total (3670ms).
[12:13:12.813] <TB3> INFO: Test took 4496ms.
[12:13:12.816] <TB3> INFO: scanning low vcal = 100
[12:13:13.080] <TB3> INFO: Expecting 41600 events.
[12:13:17.303] <TB3> INFO: 41600 events read in total (3667ms).
[12:13:17.304] <TB3> INFO: Test took 4488ms.
[12:13:17.306] <TB3> INFO: scanning low vcal = 110
[12:13:17.573] <TB3> INFO: Expecting 41600 events.
[12:13:21.811] <TB3> INFO: 41600 events read in total (3682ms).
[12:13:21.811] <TB3> INFO: Test took 4505ms.
[12:13:21.814] <TB3> INFO: scanning low vcal = 120
[12:13:22.093] <TB3> INFO: Expecting 41600 events.
[12:13:26.312] <TB3> INFO: 41600 events read in total (3662ms).
[12:13:26.312] <TB3> INFO: Test took 4498ms.
[12:13:26.315] <TB3> INFO: scanning low vcal = 130
[12:13:26.593] <TB3> INFO: Expecting 41600 events.
[12:13:30.830] <TB3> INFO: 41600 events read in total (3681ms).
[12:13:30.831] <TB3> INFO: Test took 4516ms.
[12:13:30.833] <TB3> INFO: scanning low vcal = 140
[12:13:31.096] <TB3> INFO: Expecting 41600 events.
[12:13:35.317] <TB3> INFO: 41600 events read in total (3665ms).
[12:13:35.318] <TB3> INFO: Test took 4485ms.
[12:13:35.320] <TB3> INFO: scanning low vcal = 150
[12:13:35.579] <TB3> INFO: Expecting 41600 events.
[12:13:39.771] <TB3> INFO: 41600 events read in total (3636ms).
[12:13:39.771] <TB3> INFO: Test took 4451ms.
[12:13:39.774] <TB3> INFO: scanning low vcal = 160
[12:13:40.034] <TB3> INFO: Expecting 41600 events.
[12:13:44.239] <TB3> INFO: 41600 events read in total (3649ms).
[12:13:44.240] <TB3> INFO: Test took 4466ms.
[12:13:44.242] <TB3> INFO: scanning low vcal = 170
[12:13:44.518] <TB3> INFO: Expecting 41600 events.
[12:13:48.731] <TB3> INFO: 41600 events read in total (3657ms).
[12:13:48.732] <TB3> INFO: Test took 4490ms.
[12:13:48.735] <TB3> INFO: scanning low vcal = 180
[12:13:48.999] <TB3> INFO: Expecting 41600 events.
[12:13:53.219] <TB3> INFO: 41600 events read in total (3664ms).
[12:13:53.220] <TB3> INFO: Test took 4485ms.
[12:13:53.222] <TB3> INFO: scanning low vcal = 190
[12:13:53.496] <TB3> INFO: Expecting 41600 events.
[12:13:57.728] <TB3> INFO: 41600 events read in total (3677ms).
[12:13:57.729] <TB3> INFO: Test took 4507ms.
[12:13:57.731] <TB3> INFO: scanning low vcal = 200
[12:13:58.000] <TB3> INFO: Expecting 41600 events.
[12:14:02.202] <TB3> INFO: 41600 events read in total (3646ms).
[12:14:02.202] <TB3> INFO: Test took 4471ms.
[12:14:02.205] <TB3> INFO: scanning low vcal = 210
[12:14:02.471] <TB3> INFO: Expecting 41600 events.
[12:14:06.655] <TB3> INFO: 41600 events read in total (3627ms).
[12:14:06.656] <TB3> INFO: Test took 4451ms.
[12:14:06.658] <TB3> INFO: scanning low vcal = 220
[12:14:06.950] <TB3> INFO: Expecting 41600 events.
[12:14:11.149] <TB3> INFO: 41600 events read in total (3643ms).
[12:14:11.149] <TB3> INFO: Test took 4490ms.
[12:14:11.152] <TB3> INFO: scanning low vcal = 230
[12:14:11.414] <TB3> INFO: Expecting 41600 events.
[12:14:15.586] <TB3> INFO: 41600 events read in total (3615ms).
[12:14:15.586] <TB3> INFO: Test took 4434ms.
[12:14:15.589] <TB3> INFO: scanning low vcal = 240
[12:14:15.853] <TB3> INFO: Expecting 41600 events.
[12:14:20.030] <TB3> INFO: 41600 events read in total (3621ms).
[12:14:20.031] <TB3> INFO: Test took 4442ms.
[12:14:20.033] <TB3> INFO: scanning low vcal = 250
[12:14:20.294] <TB3> INFO: Expecting 41600 events.
[12:14:24.470] <TB3> INFO: 41600 events read in total (3620ms).
[12:14:24.470] <TB3> INFO: Test took 4436ms.
[12:14:24.474] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[12:14:24.742] <TB3> INFO: Expecting 41600 events.
[12:14:28.915] <TB3> INFO: 41600 events read in total (3617ms).
[12:14:28.916] <TB3> INFO: Test took 4442ms.
[12:14:28.920] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[12:14:29.188] <TB3> INFO: Expecting 41600 events.
[12:14:33.364] <TB3> INFO: 41600 events read in total (3619ms).
[12:14:33.365] <TB3> INFO: Test took 4445ms.
[12:14:33.367] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[12:14:33.636] <TB3> INFO: Expecting 41600 events.
[12:14:37.845] <TB3> INFO: 41600 events read in total (3652ms).
[12:14:37.845] <TB3> INFO: Test took 4478ms.
[12:14:37.848] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[12:14:38.109] <TB3> INFO: Expecting 41600 events.
[12:14:42.360] <TB3> INFO: 41600 events read in total (3695ms).
[12:14:42.360] <TB3> INFO: Test took 4512ms.
[12:14:42.363] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[12:14:42.643] <TB3> INFO: Expecting 41600 events.
[12:14:46.869] <TB3> INFO: 41600 events read in total (3669ms).
[12:14:46.870] <TB3> INFO: Test took 4507ms.
[12:14:47.367] <TB3> INFO: PixTestGainPedestal::measure() done
[12:15:22.150] <TB3> INFO: PixTestGainPedestal::fit() done
[12:15:22.150] <TB3> INFO: non-linearity mean: 0.956 0.961 0.960 0.950 0.957 0.958 0.954 0.951 0.960 0.957 0.955 0.950 0.957 0.956 0.955 0.958
[12:15:22.150] <TB3> INFO: non-linearity RMS: 0.005 0.006 0.005 0.006 0.006 0.005 0.007 0.006 0.007 0.006 0.006 0.006 0.007 0.005 0.005 0.005
[12:15:22.150] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[12:15:22.170] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[12:15:22.190] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[12:15:22.210] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[12:15:22.230] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[12:15:22.248] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[12:15:22.266] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[12:15:22.286] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[12:15:22.306] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[12:15:22.326] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[12:15:22.346] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[12:15:22.370] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[12:15:22.396] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[12:15:22.422] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[12:15:22.448] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[12:15:22.474] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[12:15:22.494] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 168 seconds
[12:15:22.494] <TB3> INFO: Decoding statistics:
[12:15:22.494] <TB3> INFO: General information:
[12:15:22.494] <TB3> INFO: 16bit words read: 2662400
[12:15:22.494] <TB3> INFO: valid events total: 166400
[12:15:22.494] <TB3> INFO: empty events: 0
[12:15:22.494] <TB3> INFO: valid events with pixels: 166400
[12:15:22.494] <TB3> INFO: valid pixel hits: 665550
[12:15:22.494] <TB3> INFO: Event errors: 0
[12:15:22.494] <TB3> INFO: start marker: 0
[12:15:22.494] <TB3> INFO: stop marker: 0
[12:15:22.494] <TB3> INFO: overflow: 0
[12:15:22.494] <TB3> INFO: invalid 5bit words: 0
[12:15:22.494] <TB3> INFO: invalid XOR eye diagram: 0
[12:15:22.494] <TB3> INFO: frame (failed synchr.): 0
[12:15:22.494] <TB3> INFO: idle data (no TBM trl): 0
[12:15:22.494] <TB3> INFO: no data (only TBM hdr): 0
[12:15:22.494] <TB3> INFO: TBM errors: 0
[12:15:22.494] <TB3> INFO: flawed TBM headers: 0
[12:15:22.494] <TB3> INFO: flawed TBM trailers: 0
[12:15:22.494] <TB3> INFO: event ID mismatches: 0
[12:15:22.494] <TB3> INFO: ROC errors: 0
[12:15:22.494] <TB3> INFO: missing ROC header(s): 0
[12:15:22.494] <TB3> INFO: misplaced readback start: 0
[12:15:22.494] <TB3> INFO: Pixel decoding errors: 0
[12:15:22.494] <TB3> INFO: pixel data incomplete: 0
[12:15:22.494] <TB3> INFO: pixel address: 0
[12:15:22.494] <TB3> INFO: pulse height fill bit: 0
[12:15:22.494] <TB3> INFO: buffer corruption: 0
[12:15:22.503] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C15.dat
[12:15:22.504] <TB3> INFO: ######################################################################
[12:15:22.504] <TB3> INFO: PixTestReadback::doTest()
[12:15:22.504] <TB3> INFO: ######################################################################
[12:15:22.504] <TB3> INFO: ----------------------------------------------------------------------
[12:15:22.504] <TB3> INFO: PixTestReadback::CalibrateVd()
[12:15:22.504] <TB3> INFO: ----------------------------------------------------------------------
[12:15:32.008] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C0.dat
[12:15:32.008] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C1.dat
[12:15:32.009] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C2.dat
[12:15:32.009] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C3.dat
[12:15:32.009] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C4.dat
[12:15:32.009] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C5.dat
[12:15:32.009] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C6.dat
[12:15:32.009] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C7.dat
[12:15:32.009] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C8.dat
[12:15:32.009] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C9.dat
[12:15:32.009] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C10.dat
[12:15:32.009] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C11.dat
[12:15:32.009] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C12.dat
[12:15:32.009] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C13.dat
[12:15:32.009] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C14.dat
[12:15:32.009] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C15.dat
[12:15:32.031] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[12:15:32.031] <TB3> INFO: ----------------------------------------------------------------------
[12:15:32.031] <TB3> INFO: PixTestReadback::CalibrateVa()
[12:15:32.031] <TB3> INFO: ----------------------------------------------------------------------
[12:15:41.479] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C0.dat
[12:15:41.479] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C1.dat
[12:15:41.479] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C2.dat
[12:15:41.479] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C3.dat
[12:15:41.479] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C4.dat
[12:15:41.479] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C5.dat
[12:15:41.479] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C6.dat
[12:15:41.479] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C7.dat
[12:15:41.479] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C8.dat
[12:15:41.480] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C9.dat
[12:15:41.480] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C10.dat
[12:15:41.480] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C11.dat
[12:15:41.480] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C12.dat
[12:15:41.480] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C13.dat
[12:15:41.480] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C14.dat
[12:15:41.480] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C15.dat
[12:15:41.511] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[12:15:41.511] <TB3> INFO: ----------------------------------------------------------------------
[12:15:41.511] <TB3> INFO: PixTestReadback::readbackVbg()
[12:15:41.511] <TB3> INFO: ----------------------------------------------------------------------
[12:15:48.833] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[12:15:48.833] <TB3> INFO: ----------------------------------------------------------------------
[12:15:48.833] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[12:15:48.833] <TB3> INFO: ----------------------------------------------------------------------
[12:15:48.833] <TB3> INFO: Vbg will be calibrated using Vd calibration
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 156.7calibrated Vbg = 1.18571 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 150calibrated Vbg = 1.18988 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 150.4calibrated Vbg = 1.18853 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153calibrated Vbg = 1.19886 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 159.2calibrated Vbg = 1.19917 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 153.2calibrated Vbg = 1.20453 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 150.4calibrated Vbg = 1.20571 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 156.8calibrated Vbg = 1.20548 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156.2calibrated Vbg = 1.20525 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 167.2calibrated Vbg = 1.20816 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 151.2calibrated Vbg = 1.2004 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 145.5calibrated Vbg = 1.18919 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 150.8calibrated Vbg = 1.18711 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 140.8calibrated Vbg = 1.19152 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 157.1calibrated Vbg = 1.20055 :::*/*/*/*/
[12:15:48.833] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156.4calibrated Vbg = 1.19935 :::*/*/*/*/
[12:15:48.836] <TB3> INFO: ----------------------------------------------------------------------
[12:15:48.836] <TB3> INFO: PixTestReadback::CalibrateIa()
[12:15:48.836] <TB3> INFO: ----------------------------------------------------------------------
[12:18:24.163] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C0.dat
[12:18:24.163] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C1.dat
[12:18:24.163] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C2.dat
[12:18:24.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C3.dat
[12:18:24.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C4.dat
[12:18:24.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C5.dat
[12:18:24.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C6.dat
[12:18:24.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C7.dat
[12:18:24.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C8.dat
[12:18:24.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C9.dat
[12:18:24.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C10.dat
[12:18:24.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C11.dat
[12:18:24.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C12.dat
[12:18:24.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C13.dat
[12:18:24.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C14.dat
[12:18:24.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2225_FullQualification_2016-05-04_09h11m_1462345901//002_FulltestPxar_m20//readbackCal_C15.dat
[12:18:24.196] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[12:18:24.197] <TB3> INFO: PixTestReadback::doTest() done
[12:18:24.197] <TB3> INFO: Decoding statistics:
[12:18:24.197] <TB3> INFO: General information:
[12:18:24.197] <TB3> INFO: 16bit words read: 1024
[12:18:24.197] <TB3> INFO: valid events total: 128
[12:18:24.197] <TB3> INFO: empty events: 128
[12:18:24.197] <TB3> INFO: valid events with pixels: 0
[12:18:24.197] <TB3> INFO: valid pixel hits: 0
[12:18:24.197] <TB3> INFO: Event errors: 0
[12:18:24.197] <TB3> INFO: start marker: 0
[12:18:24.197] <TB3> INFO: stop marker: 0
[12:18:24.197] <TB3> INFO: overflow: 0
[12:18:24.197] <TB3> INFO: invalid 5bit words: 0
[12:18:24.197] <TB3> INFO: invalid XOR eye diagram: 0
[12:18:24.197] <TB3> INFO: frame (failed synchr.): 0
[12:18:24.197] <TB3> INFO: idle data (no TBM trl): 0
[12:18:24.197] <TB3> INFO: no data (only TBM hdr): 0
[12:18:24.197] <TB3> INFO: TBM errors: 0
[12:18:24.197] <TB3> INFO: flawed TBM headers: 0
[12:18:24.197] <TB3> INFO: flawed TBM trailers: 0
[12:18:24.197] <TB3> INFO: event ID mismatches: 0
[12:18:24.197] <TB3> INFO: ROC errors: 0
[12:18:24.197] <TB3> INFO: missing ROC header(s): 0
[12:18:24.197] <TB3> INFO: misplaced readback start: 0
[12:18:24.197] <TB3> INFO: Pixel decoding errors: 0
[12:18:24.197] <TB3> INFO: pixel data incomplete: 0
[12:18:24.197] <TB3> INFO: pixel address: 0
[12:18:24.197] <TB3> INFO: pulse height fill bit: 0
[12:18:24.197] <TB3> INFO: buffer corruption: 0
[12:18:24.219] <TB3> INFO: Decoding statistics:
[12:18:24.219] <TB3> INFO: General information:
[12:18:24.219] <TB3> INFO: 16bit words read: 2755344
[12:18:24.219] <TB3> INFO: valid events total: 176768
[12:18:24.219] <TB3> INFO: empty events: 7868
[12:18:24.219] <TB3> INFO: valid events with pixels: 168900
[12:18:24.219] <TB3> INFO: valid pixel hits: 668050
[12:18:24.219] <TB3> INFO: Event errors: 0
[12:18:24.219] <TB3> INFO: start marker: 0
[12:18:24.219] <TB3> INFO: stop marker: 0
[12:18:24.219] <TB3> INFO: overflow: 0
[12:18:24.219] <TB3> INFO: invalid 5bit words: 0
[12:18:24.219] <TB3> INFO: invalid XOR eye diagram: 0
[12:18:24.219] <TB3> INFO: frame (failed synchr.): 0
[12:18:24.219] <TB3> INFO: idle data (no TBM trl): 0
[12:18:24.219] <TB3> INFO: no data (only TBM hdr): 0
[12:18:24.219] <TB3> INFO: TBM errors: 0
[12:18:24.219] <TB3> INFO: flawed TBM headers: 0
[12:18:24.219] <TB3> INFO: flawed TBM trailers: 0
[12:18:24.219] <TB3> INFO: event ID mismatches: 0
[12:18:24.219] <TB3> INFO: ROC errors: 0
[12:18:24.219] <TB3> INFO: missing ROC header(s): 0
[12:18:24.219] <TB3> INFO: misplaced readback start: 0
[12:18:24.219] <TB3> INFO: Pixel decoding errors: 0
[12:18:24.219] <TB3> INFO: pixel data incomplete: 0
[12:18:24.219] <TB3> INFO: pixel address: 0
[12:18:24.219] <TB3> INFO: pulse height fill bit: 0
[12:18:24.219] <TB3> INFO: buffer corruption: 0
[12:18:24.219] <TB3> INFO: enter test to run
[12:18:24.219] <TB3> INFO: test: exit no parameter change
[12:18:24.348] <TB3> QUIET: Connection to board 170 closed.
[12:18:24.429] <TB3> INFO: pXar: this is the end, my friend