[15:11:05.793] <TB0> INFO: *** Welcome to pxar ***
[15:11:05.793] <TB0> INFO: *** Today: 2016/03/23
[15:11:06.298] <TB0> INFO: *** Version: 9751-dirty
[15:11:06.298] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C15.dat
[15:11:06.299] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:11:06.299] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//defaultMaskFile.dat
[15:11:06.299] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters_C15.dat
[15:11:06.365] <TB0> INFO: clk: 4
[15:11:06.365] <TB0> INFO: ctr: 4
[15:11:06.365] <TB0> INFO: sda: 19
[15:11:06.365] <TB0> INFO: tin: 9
[15:11:06.365] <TB0> INFO: level: 15
[15:11:06.365] <TB0> INFO: triggerdelay: 0
[15:11:06.365] <TB0> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[15:11:06.365] <TB0> INFO: Log level: INFO
[15:11:06.372] <TB0> INFO: Found DTB DTB_WS6AYH
[15:11:06.384] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[15:11:06.386] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[15:11:06.388] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[15:11:07.915] <TB0> INFO: DUT info:
[15:11:07.915] <TB0> INFO: The DUT currently contains the following objects:
[15:11:07.915] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[15:11:07.915] <TB0> INFO: TBM Core alpha (0): 7 registers set
[15:11:07.915] <TB0> INFO: TBM Core beta (1): 7 registers set
[15:11:07.915] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:11:07.915] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:07.915] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:08.316] <TB0> INFO: enter 'restricted' command line mode
[15:11:08.316] <TB0> INFO: enter test to run
[15:11:08.316] <TB0> INFO: test: pretest no parameter change
[15:11:08.316] <TB0> INFO: running: pretest
[15:11:08.320] <TB0> INFO: ----------------------------------------------------------------------
[15:11:08.320] <TB0> INFO: PixTestPretest::programROC()
[15:11:08.320] <TB0> INFO: ----------------------------------------------------------------------
[15:11:26.334] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:11:26.334] <TB0> INFO: IA differences per ROC: 20.1 20.1 18.5 17.7 19.3 18.5 19.3 22.5 20.1 20.1 20.1 18.5 19.3 20.1 20.1 19.3
[15:11:26.429] <TB0> INFO: enter test to run
[15:11:26.429] <TB0> INFO: test: pretest no parameter change
[15:11:26.429] <TB0> INFO: running: pretest
[15:11:26.430] <TB0> INFO: ----------------------------------------------------------------------
[15:11:26.430] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:11:26.430] <TB0> INFO: ----------------------------------------------------------------------
[15:11:34.127] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 381 mA = 23.8125 mA/ROC
[15:11:34.127] <TB0> INFO: i(loss) [mA/ROC]: 19.2 18.4 18.4 18.4 18.4 19.2 19.2 18.4 17.6 17.6 18.4 18.4 17.6 18.4 18.4 18.4
[15:11:34.160] <TB0> INFO: enter test to run
[15:11:34.160] <TB0> INFO: test: pretest no parameter change
[15:11:34.160] <TB0> INFO: running: pretest
[15:11:34.160] <TB0> INFO: ----------------------------------------------------------------------
[15:11:34.160] <TB0> INFO: PixTestPretest::findTiming()
[15:11:34.160] <TB0> INFO: ----------------------------------------------------------------------
[15:11:34.160] <TB0> INFO: PixTestCmd::init()

[15:13:03.435] <TB0> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[15:13:03.435] <TB0> INFO: (success/tries = 100/100), width = 4
[15:13:03.436] <TB0> INFO: enter test to run
[15:13:03.436] <TB0> INFO: test: pretest no parameter change
[15:13:03.436] <TB0> INFO: running: pretest
[15:13:03.437] <TB0> INFO: ----------------------------------------------------------------------
[15:13:03.437] <TB0> INFO: PixTestPretest::findWorkingPixel()
[15:13:03.437] <TB0> INFO: ----------------------------------------------------------------------
[15:13:03.532] <TB0> INFO: Expecting 231680 events.


[15:13:13.791] <TB0> INFO: 231680 events read in total (9703ms).
[15:13:13.795] <TB0> INFO: Test took 10352ms.
[15:13:14.033] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:13:14.106] <TB0> INFO: enter test to run
[15:13:14.106] <TB0> INFO: test: pretest no parameter change
[15:13:14.106] <TB0> INFO: running: pretest
[15:13:14.107] <TB0> INFO: ----------------------------------------------------------------------
[15:13:14.107] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[15:13:14.107] <TB0> INFO: ----------------------------------------------------------------------
[15:13:14.199] <TB0> INFO: Expecting 231680 events.
[15:13:24.412] <TB0> INFO: 231680 events read in total (9657ms).
[15:13:24.416] <TB0> INFO: Test took 10305ms.
[15:13:24.676] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[15:13:24.676] <TB0> INFO: CalDel: 138 148 128 135 137 144 154 143 138 119 141 137 134 126 135 138
[15:13:24.676] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[15:13:24.714] <TB0> INFO: enter test to run
[15:13:24.714] <TB0> INFO: test: pretest no parameter change
[15:13:24.714] <TB0> INFO: running: pretest
[15:13:24.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C0.dat
[15:13:24.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C1.dat
[15:13:24.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C2.dat
[15:13:24.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C3.dat
[15:13:24.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C4.dat
[15:13:24.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C5.dat
[15:13:24.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C6.dat
[15:13:24.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C7.dat
[15:13:24.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C8.dat
[15:13:24.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C9.dat
[15:13:24.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C10.dat
[15:13:24.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C11.dat
[15:13:24.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C12.dat
[15:13:24.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C13.dat
[15:13:24.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C14.dat
[15:13:24.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C15.dat
[15:13:24.716] <TB0> INFO: enter test to run
[15:13:24.716] <TB0> INFO: test: fulltest no parameter change
[15:13:24.716] <TB0> INFO: running: fulltest
[15:13:24.716] <TB0> INFO: ######################################################################
[15:13:24.716] <TB0> INFO: PixTestFullTest::doTest()
[15:13:24.716] <TB0> INFO: ######################################################################
[15:13:24.717] <TB0> INFO: ######################################################################
[15:13:24.717] <TB0> INFO: PixTestAlive::doTest()
[15:13:24.717] <TB0> INFO: ######################################################################
[15:13:24.718] <TB0> INFO: ----------------------------------------------------------------------
[15:13:24.718] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:13:24.718] <TB0> INFO: ----------------------------------------------------------------------
[15:13:24.983] <TB0> INFO: Expecting 41600 events.
[15:13:28.747] <TB0> INFO: 41600 events read in total (3207ms).
[15:13:28.748] <TB0> INFO: Test took 4028ms.
[15:13:28.982] <TB0> INFO: PixTestAlive::aliveTest() done
[15:13:28.982] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:13:28.983] <TB0> INFO: ----------------------------------------------------------------------
[15:13:28.983] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:13:28.983] <TB0> INFO: ----------------------------------------------------------------------
[15:13:29.260] <TB0> INFO: Expecting 41600 events.
[15:13:32.278] <TB0> INFO: 41600 events read in total (2462ms).
[15:13:32.278] <TB0> INFO: Test took 3293ms.
[15:13:32.279] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:13:32.523] <TB0> INFO: PixTestAlive::maskTest() done
[15:13:32.523] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:13:32.525] <TB0> INFO: ----------------------------------------------------------------------
[15:13:32.525] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:13:32.525] <TB0> INFO: ----------------------------------------------------------------------
[15:13:32.799] <TB0> INFO: Expecting 41600 events.
[15:13:36.635] <TB0> INFO: 41600 events read in total (3280ms).
[15:13:36.636] <TB0> INFO: Test took 4110ms.
[15:13:36.863] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[15:13:36.863] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:13:36.863] <TB0> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[15:13:36.863] <TB0> INFO: Decoding statistics:
[15:13:36.863] <TB0> INFO: General information:
[15:13:36.863] <TB0> INFO: 16bit words read: 0
[15:13:36.863] <TB0> INFO: valid events total: 0
[15:13:36.863] <TB0> INFO: empty events: 0
[15:13:36.863] <TB0> INFO: valid events with pixels: 0
[15:13:36.863] <TB0> INFO: valid pixel hits: 0
[15:13:36.863] <TB0> INFO: Event errors: 0
[15:13:36.863] <TB0> INFO: start marker: 0
[15:13:36.863] <TB0> INFO: stop marker: 0
[15:13:36.863] <TB0> INFO: overflow: 0
[15:13:36.863] <TB0> INFO: invalid 5bit words: 0
[15:13:36.863] <TB0> INFO: invalid XOR eye diagram: 0
[15:13:36.863] <TB0> INFO: frame (failed synchr.): 0
[15:13:36.863] <TB0> INFO: idle data (no TBM trl): 0
[15:13:36.863] <TB0> INFO: no data (only TBM hdr): 0
[15:13:36.863] <TB0> INFO: TBM errors: 0
[15:13:36.863] <TB0> INFO: flawed TBM headers: 0
[15:13:36.863] <TB0> INFO: flawed TBM trailers: 0
[15:13:36.863] <TB0> INFO: event ID mismatches: 0
[15:13:36.863] <TB0> INFO: ROC errors: 0
[15:13:36.863] <TB0> INFO: missing ROC header(s): 0
[15:13:36.863] <TB0> INFO: misplaced readback start: 0
[15:13:36.863] <TB0> INFO: Pixel decoding errors: 0
[15:13:36.863] <TB0> INFO: pixel data incomplete: 0
[15:13:36.863] <TB0> INFO: pixel address: 0
[15:13:36.863] <TB0> INFO: pulse height fill bit: 0
[15:13:36.863] <TB0> INFO: buffer corruption: 0
[15:13:36.870] <TB0> INFO: ######################################################################
[15:13:36.870] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:13:36.870] <TB0> INFO: ######################################################################
[15:13:36.873] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:13:36.883] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[15:13:36.883] <TB0> INFO: run 1 of 1
[15:13:37.143] <TB0> INFO: Expecting 3120000 events.
[15:14:18.790] <TB0> INFO: 884695 events read in total (41091ms).
[15:14:59.744] <TB0> INFO: 1759210 events read in total (82045ms).
[15:15:40.801] <TB0> INFO: 2649600 events read in total (123102ms).
[15:16:02.341] <TB0> INFO: 3120000 events read in total (144642ms).
[15:16:02.382] <TB0> INFO: Test took 145498ms.
[15:16:27.858] <TB0> INFO: PixTestBBMap::doTest() done, duration: 170 seconds
[15:16:27.858] <TB0> INFO: number of dead bumps (per ROC): 29 6 12 1 1 7 0 5 125 122 56 33 68 66 23 44
[15:16:27.858] <TB0> INFO: separation cut (per ROC): 91 95 89 94 87 89 85 115 81 86 84 83 84 85 76 83
[15:16:27.858] <TB0> INFO: Decoding statistics:
[15:16:27.858] <TB0> INFO: General information:
[15:16:27.858] <TB0> INFO: 16bit words read: 0
[15:16:27.858] <TB0> INFO: valid events total: 0
[15:16:27.858] <TB0> INFO: empty events: 0
[15:16:27.858] <TB0> INFO: valid events with pixels: 0
[15:16:27.858] <TB0> INFO: valid pixel hits: 0
[15:16:27.858] <TB0> INFO: Event errors: 0
[15:16:27.858] <TB0> INFO: start marker: 0
[15:16:27.858] <TB0> INFO: stop marker: 0
[15:16:27.858] <TB0> INFO: overflow: 0
[15:16:27.858] <TB0> INFO: invalid 5bit words: 0
[15:16:27.858] <TB0> INFO: invalid XOR eye diagram: 0
[15:16:27.858] <TB0> INFO: frame (failed synchr.): 0
[15:16:27.858] <TB0> INFO: idle data (no TBM trl): 0
[15:16:27.858] <TB0> INFO: no data (only TBM hdr): 0
[15:16:27.858] <TB0> INFO: TBM errors: 0
[15:16:27.858] <TB0> INFO: flawed TBM headers: 0
[15:16:27.858] <TB0> INFO: flawed TBM trailers: 0
[15:16:27.858] <TB0> INFO: event ID mismatches: 0
[15:16:27.858] <TB0> INFO: ROC errors: 0
[15:16:27.858] <TB0> INFO: missing ROC header(s): 0
[15:16:27.858] <TB0> INFO: misplaced readback start: 0
[15:16:27.858] <TB0> INFO: Pixel decoding errors: 0
[15:16:27.858] <TB0> INFO: pixel data incomplete: 0
[15:16:27.858] <TB0> INFO: pixel address: 0
[15:16:27.858] <TB0> INFO: pulse height fill bit: 0
[15:16:27.858] <TB0> INFO: buffer corruption: 0
[15:16:27.928] <TB0> INFO: ######################################################################
[15:16:27.928] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:16:27.928] <TB0> INFO: ######################################################################
[15:16:27.928] <TB0> INFO: ----------------------------------------------------------------------
[15:16:27.928] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:16:27.928] <TB0> INFO: ----------------------------------------------------------------------
[15:16:27.928] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:16:27.938] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[15:16:27.938] <TB0> INFO: run 1 of 1
[15:16:28.202] <TB0> INFO: Expecting 26208000 events.
[15:17:05.284] <TB0> INFO: 919800 events read in total (36526ms).
[15:17:39.335] <TB0> INFO: 1829650 events read in total (70577ms).
[15:18:11.987] <TB0> INFO: 2738150 events read in total (103229ms).
[15:18:45.307] <TB0> INFO: 3646750 events read in total (136549ms).
[15:19:18.729] <TB0> INFO: 4558050 events read in total (169971ms).
[15:19:51.659] <TB0> INFO: 5465450 events read in total (202901ms).
[15:20:24.950] <TB0> INFO: 6373800 events read in total (236192ms).
[15:20:58.271] <TB0> INFO: 7281250 events read in total (269513ms).
[15:21:31.759] <TB0> INFO: 8188550 events read in total (303001ms).
[15:22:05.129] <TB0> INFO: 9095800 events read in total (336371ms).
[15:22:38.560] <TB0> INFO: 10001050 events read in total (369802ms).
[15:23:12.046] <TB0> INFO: 10905000 events read in total (403288ms).
[15:23:45.326] <TB0> INFO: 11810350 events read in total (436568ms).
[15:24:18.225] <TB0> INFO: 12712850 events read in total (469467ms).
[15:24:51.150] <TB0> INFO: 13606550 events read in total (502392ms).
[15:25:23.890] <TB0> INFO: 14498650 events read in total (535132ms).
[15:25:57.130] <TB0> INFO: 15390600 events read in total (568372ms).
[15:26:30.596] <TB0> INFO: 16282400 events read in total (601838ms).
[15:27:03.797] <TB0> INFO: 17171900 events read in total (635039ms).
[15:27:36.810] <TB0> INFO: 18059950 events read in total (668052ms).
[15:28:10.097] <TB0> INFO: 18948600 events read in total (701339ms).
[15:28:42.892] <TB0> INFO: 19835000 events read in total (734134ms).
[15:29:16.421] <TB0> INFO: 20723000 events read in total (767663ms).
[15:29:49.486] <TB0> INFO: 21609100 events read in total (800728ms).
[15:30:22.468] <TB0> INFO: 22495900 events read in total (833710ms).
[15:30:55.681] <TB0> INFO: 23385250 events read in total (866923ms).
[15:31:28.769] <TB0> INFO: 24273450 events read in total (900011ms).
[15:32:02.084] <TB0> INFO: 25161800 events read in total (933326ms).
[15:32:35.378] <TB0> INFO: 26058850 events read in total (966620ms).
[15:32:41.050] <TB0> INFO: 26208000 events read in total (972292ms).
[15:32:41.078] <TB0> INFO: Test took 973140ms.
[15:32:41.333] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:32:42.769] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:32:44.325] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:32:45.781] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:32:47.219] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:32:48.708] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:32:50.187] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:32:51.647] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:32:53.045] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:32:54.523] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:32:56.026] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:32:57.671] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:32:59.334] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:33:01.520] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:33:03.322] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:33:05.342] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:33:06.897] <TB0> INFO: PixTestScurves::scurves() done
[15:33:06.897] <TB0> INFO: Vcal mean: 102.48 96.41 97.32 99.83 91.29 84.29 88.67 100.69 95.14 97.07 105.51 83.37 90.04 90.05 79.19 103.07
[15:33:06.897] <TB0> INFO: Vcal RMS: 5.98 5.33 5.49 5.54 4.79 4.82 4.96 5.34 5.52 5.65 5.50 4.70 5.57 4.72 3.83 5.06
[15:33:06.897] <TB0> INFO: PixTestScurves::fullTest() done, duration: 998 seconds
[15:33:06.897] <TB0> INFO: Decoding statistics:
[15:33:06.897] <TB0> INFO: General information:
[15:33:06.897] <TB0> INFO: 16bit words read: 0
[15:33:06.897] <TB0> INFO: valid events total: 0
[15:33:06.897] <TB0> INFO: empty events: 0
[15:33:06.897] <TB0> INFO: valid events with pixels: 0
[15:33:06.897] <TB0> INFO: valid pixel hits: 0
[15:33:06.897] <TB0> INFO: Event errors: 0
[15:33:06.897] <TB0> INFO: start marker: 0
[15:33:06.897] <TB0> INFO: stop marker: 0
[15:33:06.897] <TB0> INFO: overflow: 0
[15:33:06.897] <TB0> INFO: invalid 5bit words: 0
[15:33:06.897] <TB0> INFO: invalid XOR eye diagram: 0
[15:33:06.897] <TB0> INFO: frame (failed synchr.): 0
[15:33:06.897] <TB0> INFO: idle data (no TBM trl): 0
[15:33:06.897] <TB0> INFO: no data (only TBM hdr): 0
[15:33:06.897] <TB0> INFO: TBM errors: 0
[15:33:06.897] <TB0> INFO: flawed TBM headers: 0
[15:33:06.897] <TB0> INFO: flawed TBM trailers: 0
[15:33:06.897] <TB0> INFO: event ID mismatches: 0
[15:33:06.897] <TB0> INFO: ROC errors: 0
[15:33:06.897] <TB0> INFO: missing ROC header(s): 0
[15:33:06.897] <TB0> INFO: misplaced readback start: 0
[15:33:06.897] <TB0> INFO: Pixel decoding errors: 0
[15:33:06.897] <TB0> INFO: pixel data incomplete: 0
[15:33:06.897] <TB0> INFO: pixel address: 0
[15:33:06.897] <TB0> INFO: pulse height fill bit: 0
[15:33:06.897] <TB0> INFO: buffer corruption: 0
[15:33:06.968] <TB0> INFO: ######################################################################
[15:33:06.968] <TB0> INFO: PixTestTrim::doTest()
[15:33:06.968] <TB0> INFO: ######################################################################
[15:33:06.969] <TB0> INFO: ----------------------------------------------------------------------
[15:33:06.969] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[15:33:06.969] <TB0> INFO: ----------------------------------------------------------------------
[15:33:07.048] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:33:07.048] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:33:07.057] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:33:07.057] <TB0> INFO: run 1 of 1
[15:33:07.362] <TB0> INFO: Expecting 6281600 events.
[15:33:47.548] <TB0> INFO: 1074630 events read in total (39630ms).
[15:34:27.296] <TB0> INFO: 2145910 events read in total (79378ms).
[15:35:06.628] <TB0> INFO: 3212780 events read in total (118710ms).
[15:35:46.475] <TB0> INFO: 4291440 events read in total (158557ms).
[15:36:26.436] <TB0> INFO: 5378150 events read in total (198518ms).
[15:36:59.183] <TB0> INFO: 6281600 events read in total (231265ms).
[15:36:59.210] <TB0> INFO: Test took 232153ms.
[15:37:19.596] <TB0> INFO: ROC 0 VthrComp = 99
[15:37:19.596] <TB0> INFO: ROC 1 VthrComp = 98
[15:37:19.597] <TB0> INFO: ROC 2 VthrComp = 99
[15:37:19.597] <TB0> INFO: ROC 3 VthrComp = 96
[15:37:19.597] <TB0> INFO: ROC 4 VthrComp = 90
[15:37:19.597] <TB0> INFO: ROC 5 VthrComp = 87
[15:37:19.597] <TB0> INFO: ROC 6 VthrComp = 87
[15:37:19.597] <TB0> INFO: ROC 7 VthrComp = 101
[15:37:19.597] <TB0> INFO: ROC 8 VthrComp = 93
[15:37:19.597] <TB0> INFO: ROC 9 VthrComp = 95
[15:37:19.597] <TB0> INFO: ROC 10 VthrComp = 100
[15:37:19.598] <TB0> INFO: ROC 11 VthrComp = 87
[15:37:19.598] <TB0> INFO: ROC 12 VthrComp = 89
[15:37:19.598] <TB0> INFO: ROC 13 VthrComp = 93
[15:37:19.598] <TB0> INFO: ROC 14 VthrComp = 83
[15:37:19.598] <TB0> INFO: ROC 15 VthrComp = 101
[15:37:19.598] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:37:19.598] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:37:19.609] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:37:19.609] <TB0> INFO: run 1 of 1
[15:37:19.950] <TB0> INFO: Expecting 6281600 events.
[15:37:55.612] <TB0> INFO: 754870 events read in total (35105ms).
[15:38:30.141] <TB0> INFO: 1509220 events read in total (69634ms).
[15:39:05.016] <TB0> INFO: 2263770 events read in total (104509ms).
[15:39:40.156] <TB0> INFO: 3018990 events read in total (139649ms).
[15:40:15.067] <TB0> INFO: 3768090 events read in total (174560ms).
[15:40:50.099] <TB0> INFO: 4513120 events read in total (209592ms).
[15:41:25.231] <TB0> INFO: 5255970 events read in total (244724ms).
[15:41:59.965] <TB0> INFO: 5998410 events read in total (279458ms).
[15:42:13.319] <TB0> INFO: 6281600 events read in total (292812ms).
[15:42:13.361] <TB0> INFO: Test took 293751ms.
[15:42:39.792] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 60.9963 for pixel 12/77 mean/min/max = 46.2928/31.4357/61.15
[15:42:39.792] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 58.623 for pixel 0/45 mean/min/max = 45.1309/31.5861/58.6758
[15:42:39.792] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 57.8159 for pixel 51/1 mean/min/max = 44.5224/31.179/57.8658
[15:42:39.793] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 60.0213 for pixel 6/5 mean/min/max = 45.9443/31.835/60.0537
[15:42:39.793] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 57.7387 for pixel 25/11 mean/min/max = 46.0025/34.1824/57.8227
[15:42:39.793] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 57.351 for pixel 12/75 mean/min/max = 44.4605/31.5205/57.4006
[15:42:39.794] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 56.4285 for pixel 0/6 mean/min/max = 44.3723/32.2819/56.4627
[15:42:39.794] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 57.75 for pixel 15/1 mean/min/max = 45.094/32.3642/57.8238
[15:42:39.794] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 60.3459 for pixel 0/33 mean/min/max = 46.5912/32.7491/60.4333
[15:42:39.795] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 58.8448 for pixel 22/77 mean/min/max = 45.865/32.8768/58.8531
[15:42:39.795] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 61.9588 for pixel 0/59 mean/min/max = 47.4621/32.9528/61.9715
[15:42:39.795] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 56.4773 for pixel 17/70 mean/min/max = 44.3982/32.2912/56.5051
[15:42:39.796] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 58.6792 for pixel 14/79 mean/min/max = 46.5149/34.111/58.9189
[15:42:39.796] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 56.3053 for pixel 14/6 mean/min/max = 44.8675/33.3678/56.3673
[15:42:39.796] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 54.6081 for pixel 51/4 mean/min/max = 43.5296/32.0066/55.0526
[15:42:39.797] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 56.6086 for pixel 40/0 mean/min/max = 44.3292/31.8351/56.8234
[15:42:39.797] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:42:39.885] <TB0> INFO: Expecting 514560 events.
[15:42:52.917] <TB0> INFO: 514560 events read in total (12476ms).
[15:42:52.922] <TB0> INFO: Expecting 514560 events.
[15:43:05.709] <TB0> INFO: 514560 events read in total (12363ms).
[15:43:05.715] <TB0> INFO: Expecting 514560 events.
[15:43:18.403] <TB0> INFO: 514560 events read in total (12267ms).
[15:43:18.411] <TB0> INFO: Expecting 514560 events.
[15:43:31.199] <TB0> INFO: 514560 events read in total (12366ms).
[15:43:31.210] <TB0> INFO: Expecting 514560 events.
[15:43:44.003] <TB0> INFO: 514560 events read in total (12378ms).
[15:43:44.014] <TB0> INFO: Expecting 514560 events.
[15:43:56.875] <TB0> INFO: 514560 events read in total (12442ms).
[15:43:56.891] <TB0> INFO: Expecting 514560 events.
[15:44:09.571] <TB0> INFO: 514560 events read in total (12275ms).
[15:44:09.586] <TB0> INFO: Expecting 514560 events.
[15:44:22.467] <TB0> INFO: 514560 events read in total (12465ms).
[15:44:22.495] <TB0> INFO: Expecting 514560 events.
[15:44:35.312] <TB0> INFO: 514560 events read in total (12415ms).
[15:44:35.330] <TB0> INFO: Expecting 514560 events.
[15:44:48.158] <TB0> INFO: 514560 events read in total (12413ms).
[15:44:48.178] <TB0> INFO: Expecting 514560 events.
[15:45:00.903] <TB0> INFO: 514560 events read in total (12318ms).
[15:45:00.927] <TB0> INFO: Expecting 514560 events.
[15:45:13.718] <TB0> INFO: 514560 events read in total (12387ms).
[15:45:13.742] <TB0> INFO: Expecting 514560 events.
[15:45:26.550] <TB0> INFO: 514560 events read in total (12405ms).
[15:45:26.577] <TB0> INFO: Expecting 514560 events.
[15:45:39.429] <TB0> INFO: 514560 events read in total (12450ms).
[15:45:39.457] <TB0> INFO: Expecting 514560 events.
[15:45:52.315] <TB0> INFO: 514560 events read in total (12456ms).
[15:45:52.344] <TB0> INFO: Expecting 514560 events.
[15:46:05.167] <TB0> INFO: 514560 events read in total (12421ms).
[15:46:05.199] <TB0> INFO: Test took 205402ms.
[15:46:06.078] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:46:06.086] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:46:06.090] <TB0> INFO: run 1 of 1
[15:46:06.373] <TB0> INFO: Expecting 6281600 events.
[15:46:41.848] <TB0> INFO: 742570 events read in total (34919ms).
[15:47:17.139] <TB0> INFO: 1484080 events read in total (70210ms).
[15:47:52.306] <TB0> INFO: 2226000 events read in total (105377ms).
[15:48:27.681] <TB0> INFO: 2968630 events read in total (140752ms).
[15:49:02.128] <TB0> INFO: 3704710 events read in total (175199ms).
[15:49:37.302] <TB0> INFO: 4436060 events read in total (210373ms).
[15:50:11.678] <TB0> INFO: 5165010 events read in total (244749ms).
[15:50:46.444] <TB0> INFO: 5893030 events read in total (279515ms).
[15:51:05.100] <TB0> INFO: 6281600 events read in total (298171ms).
[15:51:05.154] <TB0> INFO: Test took 299064ms.
[15:51:32.311] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.126333 .. 255.000000
[15:51:32.434] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[15:51:32.445] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:51:32.445] <TB0> INFO: run 1 of 1
[15:51:32.789] <TB0> INFO: Expecting 10649600 events.
[15:52:07.330] <TB0> INFO: 712370 events read in total (33983ms).
[15:52:41.279] <TB0> INFO: 1424590 events read in total (67932ms).
[15:53:15.569] <TB0> INFO: 2137240 events read in total (102222ms).
[15:53:50.162] <TB0> INFO: 2850070 events read in total (136815ms).
[15:54:24.257] <TB0> INFO: 3562900 events read in total (170910ms).
[15:54:58.401] <TB0> INFO: 4275970 events read in total (205054ms).
[15:55:32.511] <TB0> INFO: 4988750 events read in total (239164ms).
[15:56:06.945] <TB0> INFO: 5701520 events read in total (273598ms).
[15:56:40.976] <TB0> INFO: 6413900 events read in total (307629ms).
[15:57:15.403] <TB0> INFO: 7125220 events read in total (342056ms).
[15:57:49.797] <TB0> INFO: 7836480 events read in total (376450ms).
[15:58:24.251] <TB0> INFO: 8546920 events read in total (410904ms).
[15:58:58.567] <TB0> INFO: 9257100 events read in total (445220ms).
[15:59:32.544] <TB0> INFO: 9967400 events read in total (479197ms).
[16:00:05.335] <TB0> INFO: 10649600 events read in total (511988ms).
[16:00:05.429] <TB0> INFO: Test took 512983ms.
[16:00:37.759] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 1.937217 .. 45.271399
[16:00:37.881] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 1 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:00:37.893] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:00:37.893] <TB0> INFO: run 1 of 1
[16:00:38.239] <TB0> INFO: Expecting 2288000 events.
[16:01:17.469] <TB0> INFO: 958790 events read in total (38674ms).
[16:01:55.915] <TB0> INFO: 1915570 events read in total (77120ms).
[16:02:11.204] <TB0> INFO: 2288000 events read in total (92409ms).
[16:02:11.216] <TB0> INFO: Test took 93323ms.
[16:02:26.463] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 17.958709 .. 42.101653
[16:02:26.561] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 7 .. 52 (-1/-1) hits flags = 528 (plus default)
[16:02:26.570] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:02:26.570] <TB0> INFO: run 1 of 1
[16:02:26.833] <TB0> INFO: Expecting 1913600 events.
[16:03:05.784] <TB0> INFO: 954230 events read in total (38395ms).
[16:03:44.285] <TB0> INFO: 1906040 events read in total (76896ms).
[16:03:44.982] <TB0> INFO: 1913600 events read in total (77594ms).
[16:03:45.001] <TB0> INFO: Test took 78431ms.
[16:03:57.820] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 2.086094 .. 41.816519
[16:03:57.895] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 51 (-1/-1) hits flags = 528 (plus default)
[16:03:57.904] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:03:57.904] <TB0> INFO: run 1 of 1
[16:03:58.163] <TB0> INFO: Expecting 2080000 events.
[16:04:37.260] <TB0> INFO: 991400 events read in total (38541ms).
[16:05:15.968] <TB0> INFO: 1981920 events read in total (77250ms).
[16:05:20.307] <TB0> INFO: 2080000 events read in total (81589ms).
[16:05:20.328] <TB0> INFO: Test took 82425ms.
[16:05:33.144] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[16:05:33.144] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:05:33.154] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:05:33.154] <TB0> INFO: run 1 of 1
[16:05:33.415] <TB0> INFO: Expecting 1705600 events.
[16:06:10.650] <TB0> INFO: 878900 events read in total (36679ms).
[16:06:45.976] <TB0> INFO: 1705600 events read in total (72005ms).
[16:06:45.995] <TB0> INFO: Test took 72840ms.
[16:07:01.158] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:07:01.158] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:07:01.158] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:07:01.158] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:07:01.158] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:07:01.158] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:07:01.159] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:07:01.159] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:07:01.159] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:07:01.159] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:07:01.159] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:07:01.159] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:07:01.159] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:07:01.159] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:07:01.160] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:07:01.160] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:07:01.160] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C0.dat
[16:07:01.167] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C1.dat
[16:07:01.175] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C2.dat
[16:07:01.182] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C3.dat
[16:07:01.192] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C4.dat
[16:07:01.201] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C5.dat
[16:07:01.208] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C6.dat
[16:07:01.215] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C7.dat
[16:07:01.222] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C8.dat
[16:07:01.229] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C9.dat
[16:07:01.237] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C10.dat
[16:07:01.244] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C11.dat
[16:07:01.251] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C12.dat
[16:07:01.262] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C13.dat
[16:07:01.274] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C14.dat
[16:07:01.286] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C15.dat
[16:07:01.297] <TB0> INFO: PixTestTrim::trimTest() done
[16:07:01.297] <TB0> INFO: vtrim: 94 103 88 93 95 97 89 87 92 92 103 91 94 86 79 87
[16:07:01.297] <TB0> INFO: vthrcomp: 99 98 99 96 90 87 87 101 93 95 100 87 89 93 83 101
[16:07:01.297] <TB0> INFO: vcal mean: 34.96 34.97 34.97 34.95 34.99 34.96 34.94 35.01 35.01 34.93 34.97 34.98 34.96 34.96 34.96 34.96
[16:07:01.297] <TB0> INFO: vcal RMS: 0.96 0.86 0.90 0.92 0.84 0.86 0.87 0.88 0.87 0.95 1.04 0.84 0.84 0.80 0.81 0.90
[16:07:01.297] <TB0> INFO: bits mean: 9.58 9.39 9.44 9.62 9.13 9.84 9.85 9.39 8.96 9.42 8.75 9.78 8.71 9.67 9.97 10.23
[16:07:01.297] <TB0> INFO: bits RMS: 2.69 2.88 2.99 2.66 2.54 2.73 2.64 2.79 2.78 2.62 2.79 2.67 2.69 2.48 2.63 2.45
[16:07:01.304] <TB0> INFO: ----------------------------------------------------------------------
[16:07:01.304] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[16:07:01.304] <TB0> INFO: ----------------------------------------------------------------------
[16:07:01.307] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[16:07:01.315] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:07:01.315] <TB0> INFO: run 1 of 1
[16:07:01.607] <TB0> INFO: Expecting 4160000 events.
[16:07:44.080] <TB0> INFO: 954505 events read in total (41917ms).
[16:08:26.166] <TB0> INFO: 1902800 events read in total (84003ms).
[16:09:07.782] <TB0> INFO: 2840050 events read in total (125620ms).
[16:09:49.826] <TB0> INFO: 3774675 events read in total (167663ms).
[16:10:07.261] <TB0> INFO: 4160000 events read in total (185098ms).
[16:10:07.298] <TB0> INFO: Test took 185983ms.
[16:10:37.744] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 193 (-1/-1) hits flags = 528 (plus default)
[16:10:37.753] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:10:37.753] <TB0> INFO: run 1 of 1
[16:10:38.041] <TB0> INFO: Expecting 4035200 events.
[16:11:19.517] <TB0> INFO: 929835 events read in total (40920ms).
[16:12:00.976] <TB0> INFO: 1854640 events read in total (82380ms).
[16:12:42.871] <TB0> INFO: 2769840 events read in total (124275ms).
[16:13:24.235] <TB0> INFO: 3682075 events read in total (165638ms).
[16:13:40.785] <TB0> INFO: 4035200 events read in total (182188ms).
[16:13:40.822] <TB0> INFO: Test took 183069ms.
[16:14:10.817] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 176 (-1/-1) hits flags = 528 (plus default)
[16:14:10.825] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:14:10.825] <TB0> INFO: run 1 of 1
[16:14:11.085] <TB0> INFO: Expecting 3681600 events.
[16:14:53.645] <TB0> INFO: 966250 events read in total (42004ms).
[16:15:35.881] <TB0> INFO: 1924885 events read in total (84240ms).
[16:16:17.454] <TB0> INFO: 2871880 events read in total (125813ms).
[16:16:54.761] <TB0> INFO: 3681600 events read in total (163120ms).
[16:16:54.806] <TB0> INFO: Test took 163980ms.
[16:17:25.426] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[16:17:25.440] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:17:25.441] <TB0> INFO: run 1 of 1
[16:17:25.734] <TB0> INFO: Expecting 3598400 events.
[16:18:09.332] <TB0> INFO: 976030 events read in total (43042ms).
[16:18:51.981] <TB0> INFO: 1943195 events read in total (85691ms).
[16:19:34.416] <TB0> INFO: 2898540 events read in total (128127ms).
[16:20:05.606] <TB0> INFO: 3598400 events read in total (159316ms).
[16:20:05.640] <TB0> INFO: Test took 160199ms.
[16:20:32.079] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 157 (-1/-1) hits flags = 528 (plus default)
[16:20:32.088] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:20:32.088] <TB0> INFO: run 1 of 1
[16:20:32.348] <TB0> INFO: Expecting 3286400 events.
[16:21:16.425] <TB0> INFO: 1020120 events read in total (43521ms).
[16:21:59.413] <TB0> INFO: 2026090 events read in total (86509ms).
[16:22:42.239] <TB0> INFO: 3021185 events read in total (129335ms).
[16:22:54.029] <TB0> INFO: 3286400 events read in total (141125ms).
[16:22:54.055] <TB0> INFO: Test took 141967ms.
[16:23:20.714] <TB0> INFO: PixTestTrim::trimBitTest() done
[16:23:20.715] <TB0> INFO: PixTestTrim::doTest() done, duration: 3013 seconds
[16:23:20.715] <TB0> INFO: Decoding statistics:
[16:23:20.715] <TB0> INFO: General information:
[16:23:20.715] <TB0> INFO: 16bit words read: 0
[16:23:20.715] <TB0> INFO: valid events total: 0
[16:23:20.715] <TB0> INFO: empty events: 0
[16:23:20.716] <TB0> INFO: valid events with pixels: 0
[16:23:20.716] <TB0> INFO: valid pixel hits: 0
[16:23:20.716] <TB0> INFO: Event errors: 0
[16:23:20.716] <TB0> INFO: start marker: 0
[16:23:20.716] <TB0> INFO: stop marker: 0
[16:23:20.716] <TB0> INFO: overflow: 0
[16:23:20.716] <TB0> INFO: invalid 5bit words: 0
[16:23:20.726] <TB0> INFO: invalid XOR eye diagram: 0
[16:23:20.726] <TB0> INFO: frame (failed synchr.): 0
[16:23:20.726] <TB0> INFO: idle data (no TBM trl): 0
[16:23:20.726] <TB0> INFO: no data (only TBM hdr): 0
[16:23:20.726] <TB0> INFO: TBM errors: 0
[16:23:20.726] <TB0> INFO: flawed TBM headers: 0
[16:23:20.726] <TB0> INFO: flawed TBM trailers: 0
[16:23:20.726] <TB0> INFO: event ID mismatches: 0
[16:23:20.726] <TB0> INFO: ROC errors: 0
[16:23:20.726] <TB0> INFO: missing ROC header(s): 0
[16:23:20.726] <TB0> INFO: misplaced readback start: 0
[16:23:20.726] <TB0> INFO: Pixel decoding errors: 0
[16:23:20.726] <TB0> INFO: pixel data incomplete: 0
[16:23:20.726] <TB0> INFO: pixel address: 0
[16:23:20.726] <TB0> INFO: pulse height fill bit: 0
[16:23:20.726] <TB0> INFO: buffer corruption: 0
[16:23:21.389] <TB0> INFO: ######################################################################
[16:23:21.389] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:23:21.389] <TB0> INFO: ######################################################################
[16:23:21.699] <TB0> INFO: Expecting 41600 events.
[16:23:25.530] <TB0> INFO: 41600 events read in total (3274ms).
[16:23:25.531] <TB0> INFO: Test took 4141ms.
[16:23:26.002] <TB0> INFO: Expecting 41600 events.
[16:23:29.872] <TB0> INFO: 41600 events read in total (3314ms).
[16:23:29.872] <TB0> INFO: Test took 4143ms.
[16:23:30.154] <TB0> INFO: Expecting 41600 events.
[16:23:34.024] <TB0> INFO: 41600 events read in total (3314ms).
[16:23:34.025] <TB0> INFO: Test took 4130ms.
[16:23:34.281] <TB0> INFO: Expecting 2560 events.
[16:23:35.136] <TB0> INFO: 2560 events read in total (299ms).
[16:23:35.136] <TB0> INFO: Test took 1097ms.
[16:23:35.441] <TB0> INFO: Expecting 2560 events.
[16:23:36.295] <TB0> INFO: 2560 events read in total (298ms).
[16:23:36.296] <TB0> INFO: Test took 1159ms.
[16:23:36.600] <TB0> INFO: Expecting 2560 events.
[16:23:37.453] <TB0> INFO: 2560 events read in total (297ms).
[16:23:37.453] <TB0> INFO: Test took 1157ms.
[16:23:37.758] <TB0> INFO: Expecting 2560 events.
[16:23:38.611] <TB0> INFO: 2560 events read in total (297ms).
[16:23:38.611] <TB0> INFO: Test took 1157ms.
[16:23:38.916] <TB0> INFO: Expecting 2560 events.
[16:23:39.773] <TB0> INFO: 2560 events read in total (301ms).
[16:23:39.773] <TB0> INFO: Test took 1162ms.
[16:23:40.078] <TB0> INFO: Expecting 2560 events.
[16:23:40.933] <TB0> INFO: 2560 events read in total (299ms).
[16:23:40.933] <TB0> INFO: Test took 1159ms.
[16:23:41.238] <TB0> INFO: Expecting 2560 events.
[16:23:42.091] <TB0> INFO: 2560 events read in total (297ms).
[16:23:42.091] <TB0> INFO: Test took 1158ms.
[16:23:42.396] <TB0> INFO: Expecting 2560 events.
[16:23:43.249] <TB0> INFO: 2560 events read in total (297ms).
[16:23:43.249] <TB0> INFO: Test took 1156ms.
[16:23:43.555] <TB0> INFO: Expecting 2560 events.
[16:23:44.412] <TB0> INFO: 2560 events read in total (301ms).
[16:23:44.412] <TB0> INFO: Test took 1162ms.
[16:23:44.717] <TB0> INFO: Expecting 2560 events.
[16:23:45.572] <TB0> INFO: 2560 events read in total (299ms).
[16:23:45.572] <TB0> INFO: Test took 1160ms.
[16:23:45.878] <TB0> INFO: Expecting 2560 events.
[16:23:46.743] <TB0> INFO: 2560 events read in total (298ms).
[16:23:46.743] <TB0> INFO: Test took 1170ms.
[16:23:47.048] <TB0> INFO: Expecting 2560 events.
[16:23:47.902] <TB0> INFO: 2560 events read in total (298ms).
[16:23:47.903] <TB0> INFO: Test took 1160ms.
[16:23:48.207] <TB0> INFO: Expecting 2560 events.
[16:23:49.060] <TB0> INFO: 2560 events read in total (296ms).
[16:23:49.060] <TB0> INFO: Test took 1157ms.
[16:23:49.367] <TB0> INFO: Expecting 2560 events.
[16:23:50.220] <TB0> INFO: 2560 events read in total (297ms).
[16:23:50.220] <TB0> INFO: Test took 1159ms.
[16:23:50.525] <TB0> INFO: Expecting 2560 events.
[16:23:51.378] <TB0> INFO: 2560 events read in total (297ms).
[16:23:51.379] <TB0> INFO: Test took 1158ms.
[16:23:51.684] <TB0> INFO: Expecting 2560 events.
[16:23:52.537] <TB0> INFO: 2560 events read in total (297ms).
[16:23:52.537] <TB0> INFO: Test took 1158ms.
[16:23:52.540] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:52.843] <TB0> INFO: Expecting 655360 events.
[16:24:09.681] <TB0> INFO: 655360 events read in total (16282ms).
[16:24:09.689] <TB0> INFO: Expecting 655360 events.
[16:24:26.272] <TB0> INFO: 655360 events read in total (16181ms).
[16:24:26.286] <TB0> INFO: Expecting 655360 events.
[16:24:43.194] <TB0> INFO: 655360 events read in total (16506ms).
[16:24:43.211] <TB0> INFO: Expecting 655360 events.
[16:24:59.996] <TB0> INFO: 655360 events read in total (16383ms).
[16:25:00.016] <TB0> INFO: Expecting 655360 events.
[16:25:16.717] <TB0> INFO: 655360 events read in total (16300ms).
[16:25:16.740] <TB0> INFO: Expecting 655360 events.
[16:25:33.653] <TB0> INFO: 655360 events read in total (16512ms).
[16:25:33.679] <TB0> INFO: Expecting 655360 events.
[16:25:50.413] <TB0> INFO: 655360 events read in total (16332ms).
[16:25:50.441] <TB0> INFO: Expecting 655360 events.
[16:26:07.293] <TB0> INFO: 655360 events read in total (16450ms).
[16:26:07.325] <TB0> INFO: Expecting 655360 events.
[16:26:24.198] <TB0> INFO: 655360 events read in total (16471ms).
[16:26:24.237] <TB0> INFO: Expecting 655360 events.
[16:26:41.094] <TB0> INFO: 655360 events read in total (16456ms).
[16:26:41.136] <TB0> INFO: Expecting 655360 events.
[16:26:57.888] <TB0> INFO: 655360 events read in total (16350ms).
[16:26:57.931] <TB0> INFO: Expecting 655360 events.
[16:27:14.804] <TB0> INFO: 655360 events read in total (16472ms).
[16:27:14.854] <TB0> INFO: Expecting 655360 events.
[16:27:31.745] <TB0> INFO: 655360 events read in total (16489ms).
[16:27:31.793] <TB0> INFO: Expecting 655360 events.
[16:27:48.593] <TB0> INFO: 655360 events read in total (16398ms).
[16:27:48.645] <TB0> INFO: Expecting 655360 events.
[16:28:05.484] <TB0> INFO: 655360 events read in total (16437ms).
[16:28:05.540] <TB0> INFO: Expecting 655360 events.
[16:28:22.469] <TB0> INFO: 655360 events read in total (16527ms).
[16:28:22.535] <TB0> INFO: Test took 269995ms.
[16:28:22.617] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:28:22.847] <TB0> INFO: Expecting 655360 events.
[16:28:39.932] <TB0> INFO: 655360 events read in total (16529ms).
[16:28:39.942] <TB0> INFO: Expecting 655360 events.
[16:28:56.689] <TB0> INFO: 655360 events read in total (16345ms).
[16:28:56.701] <TB0> INFO: Expecting 655360 events.
[16:29:13.549] <TB0> INFO: 655360 events read in total (16446ms).
[16:29:13.564] <TB0> INFO: Expecting 655360 events.
[16:29:30.295] <TB0> INFO: 655360 events read in total (16329ms).
[16:29:30.314] <TB0> INFO: Expecting 655360 events.
[16:29:47.084] <TB0> INFO: 655360 events read in total (16368ms).
[16:29:47.106] <TB0> INFO: Expecting 655360 events.
[16:30:03.803] <TB0> INFO: 655360 events read in total (16295ms).
[16:30:03.829] <TB0> INFO: Expecting 655360 events.
[16:30:20.648] <TB0> INFO: 655360 events read in total (16417ms).
[16:30:20.685] <TB0> INFO: Expecting 655360 events.
[16:30:37.361] <TB0> INFO: 655360 events read in total (16274ms).
[16:30:37.395] <TB0> INFO: Expecting 655360 events.
[16:30:54.036] <TB0> INFO: 655360 events read in total (16240ms).
[16:30:54.074] <TB0> INFO: Expecting 655360 events.
[16:31:10.828] <TB0> INFO: 655360 events read in total (16353ms).
[16:31:10.869] <TB0> INFO: Expecting 655360 events.
[16:31:27.681] <TB0> INFO: 655360 events read in total (16410ms).
[16:31:27.724] <TB0> INFO: Expecting 655360 events.
[16:31:44.559] <TB0> INFO: 655360 events read in total (16433ms).
[16:31:44.608] <TB0> INFO: Expecting 655360 events.
[16:32:01.260] <TB0> INFO: 655360 events read in total (16250ms).
[16:32:01.312] <TB0> INFO: Expecting 655360 events.
[16:32:18.050] <TB0> INFO: 655360 events read in total (16337ms).
[16:32:18.105] <TB0> INFO: Expecting 655360 events.
[16:32:34.794] <TB0> INFO: 655360 events read in total (16287ms).
[16:32:34.850] <TB0> INFO: Expecting 655360 events.
[16:32:51.721] <TB0> INFO: 655360 events read in total (16469ms).
[16:32:51.794] <TB0> INFO: Test took 269177ms.
[16:32:51.985] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:51.993] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.002] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.010] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.020] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.028] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.036] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.044] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.051] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.058] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.065] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.072] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.080] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[16:32:52.087] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[16:32:52.094] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[16:32:52.102] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[16:32:52.109] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[16:32:52.116] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[16:32:52.124] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[16:32:52.131] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[16:32:52.138] <TB0> INFO: safety margin for low PH: adding 9, margin is now 29
[16:32:52.145] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.152] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.161] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.170] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:52.221] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:32:52.221] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:32:52.221] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:32:52.221] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:32:52.221] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:32:52.221] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:32:52.221] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:32:52.221] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:32:52.222] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:32:52.222] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:32:52.222] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:32:52.222] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:32:52.222] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:32:52.222] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:32:52.222] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:32:52.222] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:32:52.498] <TB0> INFO: Expecting 41600 events.
[16:32:55.998] <TB0> INFO: 41600 events read in total (2944ms).
[16:32:55.999] <TB0> INFO: Test took 3774ms.
[16:32:56.457] <TB0> INFO: Expecting 41600 events.
[16:32:59.960] <TB0> INFO: 41600 events read in total (2947ms).
[16:32:59.961] <TB0> INFO: Test took 3781ms.
[16:33:00.412] <TB0> INFO: Expecting 41600 events.
[16:33:03.887] <TB0> INFO: 41600 events read in total (2919ms).
[16:33:03.888] <TB0> INFO: Test took 3746ms.
[16:33:04.069] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:04.157] <TB0> INFO: Expecting 2560 events.
[16:33:05.016] <TB0> INFO: 2560 events read in total (303ms).
[16:33:05.016] <TB0> INFO: Test took 947ms.
[16:33:05.019] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:05.323] <TB0> INFO: Expecting 2560 events.
[16:33:06.178] <TB0> INFO: 2560 events read in total (299ms).
[16:33:06.178] <TB0> INFO: Test took 1159ms.
[16:33:06.181] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:06.483] <TB0> INFO: Expecting 2560 events.
[16:33:07.337] <TB0> INFO: 2560 events read in total (298ms).
[16:33:07.338] <TB0> INFO: Test took 1157ms.
[16:33:07.341] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:07.644] <TB0> INFO: Expecting 2560 events.
[16:33:08.497] <TB0> INFO: 2560 events read in total (297ms).
[16:33:08.497] <TB0> INFO: Test took 1156ms.
[16:33:08.499] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:08.803] <TB0> INFO: Expecting 2560 events.
[16:33:09.658] <TB0> INFO: 2560 events read in total (299ms).
[16:33:09.658] <TB0> INFO: Test took 1159ms.
[16:33:09.671] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:09.965] <TB0> INFO: Expecting 2560 events.
[16:33:10.823] <TB0> INFO: 2560 events read in total (302ms).
[16:33:10.823] <TB0> INFO: Test took 1152ms.
[16:33:10.826] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:11.129] <TB0> INFO: Expecting 2560 events.
[16:33:11.987] <TB0> INFO: 2560 events read in total (302ms).
[16:33:11.987] <TB0> INFO: Test took 1161ms.
[16:33:11.990] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:12.293] <TB0> INFO: Expecting 2560 events.
[16:33:13.147] <TB0> INFO: 2560 events read in total (298ms).
[16:33:13.148] <TB0> INFO: Test took 1158ms.
[16:33:13.152] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:13.452] <TB0> INFO: Expecting 2560 events.
[16:33:14.307] <TB0> INFO: 2560 events read in total (298ms).
[16:33:14.307] <TB0> INFO: Test took 1155ms.
[16:33:14.309] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:14.612] <TB0> INFO: Expecting 2560 events.
[16:33:15.470] <TB0> INFO: 2560 events read in total (302ms).
[16:33:15.471] <TB0> INFO: Test took 1162ms.
[16:33:15.473] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:15.776] <TB0> INFO: Expecting 2560 events.
[16:33:16.630] <TB0> INFO: 2560 events read in total (297ms).
[16:33:16.630] <TB0> INFO: Test took 1157ms.
[16:33:16.632] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:16.936] <TB0> INFO: Expecting 2560 events.
[16:33:17.790] <TB0> INFO: 2560 events read in total (298ms).
[16:33:17.790] <TB0> INFO: Test took 1159ms.
[16:33:17.792] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:18.097] <TB0> INFO: Expecting 2560 events.
[16:33:18.954] <TB0> INFO: 2560 events read in total (301ms).
[16:33:18.954] <TB0> INFO: Test took 1162ms.
[16:33:18.957] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:19.260] <TB0> INFO: Expecting 2560 events.
[16:33:20.113] <TB0> INFO: 2560 events read in total (297ms).
[16:33:20.113] <TB0> INFO: Test took 1156ms.
[16:33:20.115] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:20.418] <TB0> INFO: Expecting 2560 events.
[16:33:21.272] <TB0> INFO: 2560 events read in total (297ms).
[16:33:21.273] <TB0> INFO: Test took 1158ms.
[16:33:21.275] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:21.578] <TB0> INFO: Expecting 2560 events.
[16:33:22.430] <TB0> INFO: 2560 events read in total (295ms).
[16:33:22.431] <TB0> INFO: Test took 1157ms.
[16:33:22.433] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:22.736] <TB0> INFO: Expecting 2560 events.
[16:33:23.593] <TB0> INFO: 2560 events read in total (301ms).
[16:33:23.593] <TB0> INFO: Test took 1160ms.
[16:33:23.595] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:23.899] <TB0> INFO: Expecting 2560 events.
[16:33:24.753] <TB0> INFO: 2560 events read in total (298ms).
[16:33:24.753] <TB0> INFO: Test took 1158ms.
[16:33:24.755] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:25.058] <TB0> INFO: Expecting 2560 events.
[16:33:25.912] <TB0> INFO: 2560 events read in total (297ms).
[16:33:25.913] <TB0> INFO: Test took 1158ms.
[16:33:25.915] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:26.218] <TB0> INFO: Expecting 2560 events.
[16:33:27.078] <TB0> INFO: 2560 events read in total (304ms).
[16:33:27.078] <TB0> INFO: Test took 1163ms.
[16:33:27.081] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:27.384] <TB0> INFO: Expecting 2560 events.
[16:33:28.239] <TB0> INFO: 2560 events read in total (299ms).
[16:33:28.239] <TB0> INFO: Test took 1159ms.
[16:33:28.240] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:28.544] <TB0> INFO: Expecting 2560 events.
[16:33:29.399] <TB0> INFO: 2560 events read in total (299ms).
[16:33:29.400] <TB0> INFO: Test took 1160ms.
[16:33:29.403] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:29.705] <TB0> INFO: Expecting 2560 events.
[16:33:30.561] <TB0> INFO: 2560 events read in total (299ms).
[16:33:30.562] <TB0> INFO: Test took 1159ms.
[16:33:30.564] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:30.866] <TB0> INFO: Expecting 2560 events.
[16:33:31.722] <TB0> INFO: 2560 events read in total (299ms).
[16:33:31.722] <TB0> INFO: Test took 1158ms.
[16:33:31.724] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:32.028] <TB0> INFO: Expecting 2560 events.
[16:33:32.890] <TB0> INFO: 2560 events read in total (305ms).
[16:33:32.890] <TB0> INFO: Test took 1167ms.
[16:33:32.893] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:33.195] <TB0> INFO: Expecting 2560 events.
[16:33:34.049] <TB0> INFO: 2560 events read in total (297ms).
[16:33:34.050] <TB0> INFO: Test took 1157ms.
[16:33:34.053] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:34.356] <TB0> INFO: Expecting 2560 events.
[16:33:35.216] <TB0> INFO: 2560 events read in total (304ms).
[16:33:35.216] <TB0> INFO: Test took 1164ms.
[16:33:35.219] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:35.521] <TB0> INFO: Expecting 2560 events.
[16:33:36.374] <TB0> INFO: 2560 events read in total (297ms).
[16:33:36.374] <TB0> INFO: Test took 1156ms.
[16:33:36.376] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:36.680] <TB0> INFO: Expecting 2560 events.
[16:33:37.535] <TB0> INFO: 2560 events read in total (298ms).
[16:33:37.536] <TB0> INFO: Test took 1160ms.
[16:33:37.538] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:37.841] <TB0> INFO: Expecting 2560 events.
[16:33:38.694] <TB0> INFO: 2560 events read in total (297ms).
[16:33:38.694] <TB0> INFO: Test took 1157ms.
[16:33:38.695] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:39.000] <TB0> INFO: Expecting 2560 events.
[16:33:39.853] <TB0> INFO: 2560 events read in total (297ms).
[16:33:39.853] <TB0> INFO: Test took 1158ms.
[16:33:39.855] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:40.158] <TB0> INFO: Expecting 2560 events.
[16:33:41.015] <TB0> INFO: 2560 events read in total (300ms).
[16:33:41.015] <TB0> INFO: Test took 1161ms.
[16:33:41.468] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 620 seconds
[16:33:41.468] <TB0> INFO: PH scale (per ROC): 67 75 78 75 68 75 70 74 78 68 63 80 67 74 80 70
[16:33:41.468] <TB0> INFO: PH offset (per ROC): 188 182 177 190 167 181 165 187 197 186 193 177 179 181 170 176
[16:33:41.472] <TB0> INFO: Decoding statistics:
[16:33:41.473] <TB0> INFO: General information:
[16:33:41.473] <TB0> INFO: 16bit words read: 91936
[16:33:41.473] <TB0> INFO: valid events total: 10240
[16:33:41.473] <TB0> INFO: empty events: 7736
[16:33:41.473] <TB0> INFO: valid events with pixels: 2504
[16:33:41.473] <TB0> INFO: valid pixel hits: 2504
[16:33:41.473] <TB0> INFO: Event errors: 0
[16:33:41.473] <TB0> INFO: start marker: 0
[16:33:41.473] <TB0> INFO: stop marker: 0
[16:33:41.473] <TB0> INFO: overflow: 0
[16:33:41.473] <TB0> INFO: invalid 5bit words: 0
[16:33:41.473] <TB0> INFO: invalid XOR eye diagram: 0
[16:33:41.473] <TB0> INFO: frame (failed synchr.): 0
[16:33:41.473] <TB0> INFO: idle data (no TBM trl): 0
[16:33:41.473] <TB0> INFO: no data (only TBM hdr): 0
[16:33:41.473] <TB0> INFO: TBM errors: 0
[16:33:41.473] <TB0> INFO: flawed TBM headers: 0
[16:33:41.473] <TB0> INFO: flawed TBM trailers: 0
[16:33:41.473] <TB0> INFO: event ID mismatches: 0
[16:33:41.473] <TB0> INFO: ROC errors: 0
[16:33:41.473] <TB0> INFO: missing ROC header(s): 0
[16:33:41.473] <TB0> INFO: misplaced readback start: 0
[16:33:41.473] <TB0> INFO: Pixel decoding errors: 0
[16:33:41.473] <TB0> INFO: pixel data incomplete: 0
[16:33:41.473] <TB0> INFO: pixel address: 0
[16:33:41.473] <TB0> INFO: pulse height fill bit: 0
[16:33:41.473] <TB0> INFO: buffer corruption: 0
[16:33:41.643] <TB0> INFO: ######################################################################
[16:33:41.643] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:33:41.643] <TB0> INFO: ######################################################################
[16:33:41.653] <TB0> INFO: scanning low vcal = 10
[16:33:41.917] <TB0> INFO: Expecting 41600 events.
[16:33:45.457] <TB0> INFO: 41600 events read in total (2984ms).
[16:33:45.457] <TB0> INFO: Test took 3804ms.
[16:33:45.458] <TB0> INFO: scanning low vcal = 20
[16:33:45.762] <TB0> INFO: Expecting 41600 events.
[16:33:49.323] <TB0> INFO: 41600 events read in total (3005ms).
[16:33:49.323] <TB0> INFO: Test took 3865ms.
[16:33:49.324] <TB0> INFO: scanning low vcal = 30
[16:33:49.628] <TB0> INFO: Expecting 41600 events.
[16:33:53.182] <TB0> INFO: 41600 events read in total (2998ms).
[16:33:53.182] <TB0> INFO: Test took 3858ms.
[16:33:53.184] <TB0> INFO: scanning low vcal = 40
[16:33:53.481] <TB0> INFO: Expecting 41600 events.
[16:33:57.654] <TB0> INFO: 41600 events read in total (3617ms).
[16:33:57.655] <TB0> INFO: Test took 4471ms.
[16:33:57.658] <TB0> INFO: scanning low vcal = 50
[16:33:57.943] <TB0> INFO: Expecting 41600 events.
[16:34:02.170] <TB0> INFO: 41600 events read in total (3671ms).
[16:34:02.170] <TB0> INFO: Test took 4512ms.
[16:34:02.173] <TB0> INFO: scanning low vcal = 60
[16:34:02.434] <TB0> INFO: Expecting 41600 events.
[16:34:06.654] <TB0> INFO: 41600 events read in total (3664ms).
[16:34:06.654] <TB0> INFO: Test took 4481ms.
[16:34:06.657] <TB0> INFO: scanning low vcal = 70
[16:34:06.923] <TB0> INFO: Expecting 41600 events.
[16:34:11.129] <TB0> INFO: 41600 events read in total (3650ms).
[16:34:11.130] <TB0> INFO: Test took 4473ms.
[16:34:11.132] <TB0> INFO: scanning low vcal = 80
[16:34:11.395] <TB0> INFO: Expecting 41600 events.
[16:34:15.634] <TB0> INFO: 41600 events read in total (3682ms).
[16:34:15.634] <TB0> INFO: Test took 4502ms.
[16:34:15.637] <TB0> INFO: scanning low vcal = 90
[16:34:15.925] <TB0> INFO: Expecting 41600 events.
[16:34:20.192] <TB0> INFO: 41600 events read in total (3711ms).
[16:34:20.193] <TB0> INFO: Test took 4556ms.
[16:34:20.196] <TB0> INFO: scanning low vcal = 100
[16:34:20.486] <TB0> INFO: Expecting 41600 events.
[16:34:24.700] <TB0> INFO: 41600 events read in total (3657ms).
[16:34:24.700] <TB0> INFO: Test took 4504ms.
[16:34:24.703] <TB0> INFO: scanning low vcal = 110
[16:34:24.983] <TB0> INFO: Expecting 41600 events.
[16:34:29.213] <TB0> INFO: 41600 events read in total (3673ms).
[16:34:29.214] <TB0> INFO: Test took 4511ms.
[16:34:29.217] <TB0> INFO: scanning low vcal = 120
[16:34:29.588] <TB0> INFO: Expecting 41600 events.
[16:34:33.792] <TB0> INFO: 41600 events read in total (3648ms).
[16:34:33.792] <TB0> INFO: Test took 4575ms.
[16:34:33.794] <TB0> INFO: scanning low vcal = 130
[16:34:34.057] <TB0> INFO: Expecting 41600 events.
[16:34:38.241] <TB0> INFO: 41600 events read in total (3628ms).
[16:34:38.242] <TB0> INFO: Test took 4448ms.
[16:34:38.244] <TB0> INFO: scanning low vcal = 140
[16:34:38.510] <TB0> INFO: Expecting 41600 events.
[16:34:42.705] <TB0> INFO: 41600 events read in total (3639ms).
[16:34:42.706] <TB0> INFO: Test took 4462ms.
[16:34:42.708] <TB0> INFO: scanning low vcal = 150
[16:34:42.970] <TB0> INFO: Expecting 41600 events.
[16:34:47.216] <TB0> INFO: 41600 events read in total (3689ms).
[16:34:47.217] <TB0> INFO: Test took 4509ms.
[16:34:47.220] <TB0> INFO: scanning low vcal = 160
[16:34:47.509] <TB0> INFO: Expecting 41600 events.
[16:34:51.750] <TB0> INFO: 41600 events read in total (3685ms).
[16:34:51.751] <TB0> INFO: Test took 4531ms.
[16:34:51.753] <TB0> INFO: scanning low vcal = 170
[16:34:52.026] <TB0> INFO: Expecting 41600 events.
[16:34:56.245] <TB0> INFO: 41600 events read in total (3662ms).
[16:34:56.245] <TB0> INFO: Test took 4492ms.
[16:34:56.249] <TB0> INFO: scanning low vcal = 180
[16:34:56.513] <TB0> INFO: Expecting 41600 events.
[16:35:00.749] <TB0> INFO: 41600 events read in total (3680ms).
[16:35:00.750] <TB0> INFO: Test took 4501ms.
[16:35:00.752] <TB0> INFO: scanning low vcal = 190
[16:35:01.032] <TB0> INFO: Expecting 41600 events.
[16:35:05.232] <TB0> INFO: 41600 events read in total (3644ms).
[16:35:05.233] <TB0> INFO: Test took 4481ms.
[16:35:05.235] <TB0> INFO: scanning low vcal = 200
[16:35:05.516] <TB0> INFO: Expecting 41600 events.
[16:35:09.746] <TB0> INFO: 41600 events read in total (3674ms).
[16:35:09.746] <TB0> INFO: Test took 4511ms.
[16:35:09.749] <TB0> INFO: scanning low vcal = 210
[16:35:10.044] <TB0> INFO: Expecting 41600 events.
[16:35:14.261] <TB0> INFO: 41600 events read in total (3661ms).
[16:35:14.262] <TB0> INFO: Test took 4513ms.
[16:35:14.264] <TB0> INFO: scanning low vcal = 220
[16:35:14.597] <TB0> INFO: Expecting 41600 events.
[16:35:18.799] <TB0> INFO: 41600 events read in total (3646ms).
[16:35:18.800] <TB0> INFO: Test took 4536ms.
[16:35:18.803] <TB0> INFO: scanning low vcal = 230
[16:35:19.105] <TB0> INFO: Expecting 41600 events.
[16:35:23.304] <TB0> INFO: 41600 events read in total (3643ms).
[16:35:23.305] <TB0> INFO: Test took 4502ms.
[16:35:23.307] <TB0> INFO: scanning low vcal = 240
[16:35:23.576] <TB0> INFO: Expecting 41600 events.
[16:35:27.797] <TB0> INFO: 41600 events read in total (3664ms).
[16:35:27.798] <TB0> INFO: Test took 4491ms.
[16:35:27.801] <TB0> INFO: scanning low vcal = 250
[16:35:28.077] <TB0> INFO: Expecting 41600 events.
[16:35:32.311] <TB0> INFO: 41600 events read in total (3678ms).
[16:35:32.311] <TB0> INFO: Test took 4510ms.
[16:35:32.315] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[16:35:32.594] <TB0> INFO: Expecting 41600 events.
[16:35:36.816] <TB0> INFO: 41600 events read in total (3666ms).
[16:35:36.817] <TB0> INFO: Test took 4502ms.
[16:35:36.819] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[16:35:37.088] <TB0> INFO: Expecting 41600 events.
[16:35:41.294] <TB0> INFO: 41600 events read in total (3649ms).
[16:35:41.295] <TB0> INFO: Test took 4476ms.
[16:35:41.297] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[16:35:41.569] <TB0> INFO: Expecting 41600 events.
[16:35:45.752] <TB0> INFO: 41600 events read in total (3627ms).
[16:35:45.753] <TB0> INFO: Test took 4456ms.
[16:35:45.756] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[16:35:46.020] <TB0> INFO: Expecting 41600 events.
[16:35:50.205] <TB0> INFO: 41600 events read in total (3629ms).
[16:35:50.205] <TB0> INFO: Test took 4449ms.
[16:35:50.208] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:35:50.475] <TB0> INFO: Expecting 41600 events.
[16:35:54.657] <TB0> INFO: 41600 events read in total (3626ms).
[16:35:54.658] <TB0> INFO: Test took 4450ms.
[16:35:55.195] <TB0> INFO: PixTestGainPedestal::measure() done
[16:36:32.995] <TB0> INFO: PixTestGainPedestal::fit() done
[16:36:32.995] <TB0> INFO: non-linearity mean: 0.960 0.958 0.962 0.959 0.958 0.950 0.957 0.955 0.962 0.956 0.954 0.964 0.956 0.954 0.953 0.961
[16:36:32.995] <TB0> INFO: non-linearity RMS: 0.005 0.006 0.005 0.006 0.007 0.008 0.006 0.007 0.006 0.007 0.008 0.004 0.007 0.006 0.007 0.006
[16:36:32.995] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:36:33.015] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:36:33.033] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:36:33.060] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:36:33.079] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:36:33.098] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:36:33.118] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:36:33.136] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:36:33.159] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:36:33.180] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:36:33.200] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:36:33.224] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:36:33.247] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:36:33.272] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:36:33.297] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:36:33.317] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:36:33.344] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 171 seconds
[16:36:33.344] <TB0> INFO: Decoding statistics:
[16:36:33.344] <TB0> INFO: General information:
[16:36:33.344] <TB0> INFO: 16bit words read: 2662400
[16:36:33.344] <TB0> INFO: valid events total: 166400
[16:36:33.344] <TB0> INFO: empty events: 0
[16:36:33.344] <TB0> INFO: valid events with pixels: 166400
[16:36:33.344] <TB0> INFO: valid pixel hits: 665580
[16:36:33.344] <TB0> INFO: Event errors: 0
[16:36:33.344] <TB0> INFO: start marker: 0
[16:36:33.344] <TB0> INFO: stop marker: 0
[16:36:33.344] <TB0> INFO: overflow: 0
[16:36:33.344] <TB0> INFO: invalid 5bit words: 0
[16:36:33.344] <TB0> INFO: invalid XOR eye diagram: 0
[16:36:33.344] <TB0> INFO: frame (failed synchr.): 0
[16:36:33.344] <TB0> INFO: idle data (no TBM trl): 0
[16:36:33.344] <TB0> INFO: no data (only TBM hdr): 0
[16:36:33.344] <TB0> INFO: TBM errors: 0
[16:36:33.344] <TB0> INFO: flawed TBM headers: 0
[16:36:33.344] <TB0> INFO: flawed TBM trailers: 0
[16:36:33.344] <TB0> INFO: event ID mismatches: 0
[16:36:33.344] <TB0> INFO: ROC errors: 0
[16:36:33.344] <TB0> INFO: missing ROC header(s): 0
[16:36:33.345] <TB0> INFO: misplaced readback start: 0
[16:36:33.345] <TB0> INFO: Pixel decoding errors: 0
[16:36:33.345] <TB0> INFO: pixel data incomplete: 0
[16:36:33.345] <TB0> INFO: pixel address: 0
[16:36:33.345] <TB0> INFO: pulse height fill bit: 0
[16:36:33.345] <TB0> INFO: buffer corruption: 0
[16:36:33.354] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C15.dat
[16:36:33.361] <TB0> INFO: ######################################################################
[16:36:33.361] <TB0> INFO: PixTestReadback::doTest()
[16:36:33.361] <TB0> INFO: ######################################################################
[16:36:33.361] <TB0> INFO: ----------------------------------------------------------------------
[16:36:33.361] <TB0> INFO: PixTestReadback::CalibrateVd()
[16:36:33.361] <TB0> INFO: ----------------------------------------------------------------------
[16:36:42.825] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C0.dat
[16:36:42.826] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C1.dat
[16:36:42.826] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C2.dat
[16:36:42.826] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C3.dat
[16:36:42.826] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C4.dat
[16:36:42.826] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C5.dat
[16:36:42.827] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C6.dat
[16:36:42.827] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C7.dat
[16:36:42.827] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C8.dat
[16:36:42.827] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C9.dat
[16:36:42.827] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C10.dat
[16:36:42.827] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C11.dat
[16:36:42.827] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C12.dat
[16:36:42.827] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C13.dat
[16:36:42.827] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C14.dat
[16:36:42.827] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C15.dat
[16:36:42.850] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:36:42.850] <TB0> INFO: ----------------------------------------------------------------------
[16:36:42.850] <TB0> INFO: PixTestReadback::CalibrateVa()
[16:36:42.850] <TB0> INFO: ----------------------------------------------------------------------
[16:36:52.320] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C0.dat
[16:36:52.320] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C1.dat
[16:36:52.321] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C2.dat
[16:36:52.321] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C3.dat
[16:36:52.321] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C4.dat
[16:36:52.321] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C5.dat
[16:36:52.321] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C6.dat
[16:36:52.321] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C7.dat
[16:36:52.321] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C8.dat
[16:36:52.321] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C9.dat
[16:36:52.321] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C10.dat
[16:36:52.321] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C11.dat
[16:36:52.321] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C12.dat
[16:36:52.322] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C13.dat
[16:36:52.322] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C14.dat
[16:36:52.322] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C15.dat
[16:36:52.350] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:36:52.350] <TB0> INFO: ----------------------------------------------------------------------
[16:36:52.350] <TB0> INFO: PixTestReadback::readbackVbg()
[16:36:52.350] <TB0> INFO: ----------------------------------------------------------------------
[16:36:59.631] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:36:59.631] <TB0> INFO: ----------------------------------------------------------------------
[16:36:59.631] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[16:36:59.631] <TB0> INFO: ----------------------------------------------------------------------
[16:36:59.631] <TB0> INFO: Vbg will be calibrated using Vd calibration
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 157.6calibrated Vbg = 1.23803 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 151.5calibrated Vbg = 1.23698 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 154.1calibrated Vbg = 1.23933 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 164.1calibrated Vbg = 1.24377 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 146.7calibrated Vbg = 1.24613 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 155.4calibrated Vbg = 1.25011 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 154.7calibrated Vbg = 1.24815 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 153.9calibrated Vbg = 1.2498 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156.6calibrated Vbg = 1.24932 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 154.1calibrated Vbg = 1.25053 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 155.2calibrated Vbg = 1.24389 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 157.4calibrated Vbg = 1.253 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 157calibrated Vbg = 1.23732 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 160calibrated Vbg = 1.23199 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 146.3calibrated Vbg = 1.23772 :::*/*/*/*/
[16:36:59.631] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 153.5calibrated Vbg = 1.2318 :::*/*/*/*/
[16:36:59.632] <TB0> INFO: ----------------------------------------------------------------------
[16:36:59.632] <TB0> INFO: PixTestReadback::CalibrateIa()
[16:36:59.632] <TB0> INFO: ----------------------------------------------------------------------
[16:39:34.944] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C0.dat
[16:39:34.944] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C1.dat
[16:39:34.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C2.dat
[16:39:34.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C3.dat
[16:39:34.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C4.dat
[16:39:34.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C5.dat
[16:39:34.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C6.dat
[16:39:34.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C7.dat
[16:39:34.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C8.dat
[16:39:34.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C9.dat
[16:39:34.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C10.dat
[16:39:34.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C11.dat
[16:39:34.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C12.dat
[16:39:34.946] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C13.dat
[16:39:34.946] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C14.dat
[16:39:34.946] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2188_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C15.dat
[16:39:34.973] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:39:34.974] <TB0> INFO: PixTestReadback::doTest() done
[16:39:34.974] <TB0> INFO: Decoding statistics:
[16:39:34.974] <TB0> INFO: General information:
[16:39:34.974] <TB0> INFO: 16bit words read: 1024
[16:39:34.974] <TB0> INFO: valid events total: 128
[16:39:34.974] <TB0> INFO: empty events: 128
[16:39:34.974] <TB0> INFO: valid events with pixels: 0
[16:39:34.974] <TB0> INFO: valid pixel hits: 0
[16:39:34.974] <TB0> INFO: Event errors: 0
[16:39:34.974] <TB0> INFO: start marker: 0
[16:39:34.974] <TB0> INFO: stop marker: 0
[16:39:34.974] <TB0> INFO: overflow: 0
[16:39:34.974] <TB0> INFO: invalid 5bit words: 0
[16:39:34.974] <TB0> INFO: invalid XOR eye diagram: 0
[16:39:34.974] <TB0> INFO: frame (failed synchr.): 0
[16:39:34.974] <TB0> INFO: idle data (no TBM trl): 0
[16:39:34.974] <TB0> INFO: no data (only TBM hdr): 0
[16:39:34.974] <TB0> INFO: TBM errors: 0
[16:39:34.974] <TB0> INFO: flawed TBM headers: 0
[16:39:34.974] <TB0> INFO: flawed TBM trailers: 0
[16:39:34.974] <TB0> INFO: event ID mismatches: 0
[16:39:34.974] <TB0> INFO: ROC errors: 0
[16:39:34.974] <TB0> INFO: missing ROC header(s): 0
[16:39:34.974] <TB0> INFO: misplaced readback start: 0
[16:39:34.974] <TB0> INFO: Pixel decoding errors: 0
[16:39:34.974] <TB0> INFO: pixel data incomplete: 0
[16:39:34.974] <TB0> INFO: pixel address: 0
[16:39:34.974] <TB0> INFO: pulse height fill bit: 0
[16:39:34.974] <TB0> INFO: buffer corruption: 0
[16:39:34.992] <TB0> INFO: Decoding statistics:
[16:39:34.992] <TB0> INFO: General information:
[16:39:34.992] <TB0> INFO: 16bit words read: 2755360
[16:39:34.992] <TB0> INFO: valid events total: 176768
[16:39:34.992] <TB0> INFO: empty events: 7864
[16:39:34.992] <TB0> INFO: valid events with pixels: 168904
[16:39:34.992] <TB0> INFO: valid pixel hits: 668084
[16:39:34.992] <TB0> INFO: Event errors: 0
[16:39:34.992] <TB0> INFO: start marker: 0
[16:39:34.992] <TB0> INFO: stop marker: 0
[16:39:34.992] <TB0> INFO: overflow: 0
[16:39:34.992] <TB0> INFO: invalid 5bit words: 0
[16:39:34.992] <TB0> INFO: invalid XOR eye diagram: 0
[16:39:34.992] <TB0> INFO: frame (failed synchr.): 0
[16:39:34.992] <TB0> INFO: idle data (no TBM trl): 0
[16:39:34.992] <TB0> INFO: no data (only TBM hdr): 0
[16:39:34.992] <TB0> INFO: TBM errors: 0
[16:39:34.992] <TB0> INFO: flawed TBM headers: 0
[16:39:34.992] <TB0> INFO: flawed TBM trailers: 0
[16:39:34.992] <TB0> INFO: event ID mismatches: 0
[16:39:34.992] <TB0> INFO: ROC errors: 0
[16:39:34.992] <TB0> INFO: missing ROC header(s): 0
[16:39:34.992] <TB0> INFO: misplaced readback start: 0
[16:39:34.992] <TB0> INFO: Pixel decoding errors: 0
[16:39:34.992] <TB0> INFO: pixel data incomplete: 0
[16:39:34.992] <TB0> INFO: pixel address: 0
[16:39:34.992] <TB0> INFO: pulse height fill bit: 0
[16:39:34.992] <TB0> INFO: buffer corruption: 0
[16:39:34.992] <TB0> INFO: enter test to run
[16:39:34.992] <TB0> INFO: test: exit no parameter change
[16:39:35.127] <TB0> QUIET: Connection to board 73 closed.
[16:39:35.207] <TB0> INFO: pXar: this is the end, my friend