[13:09:40.872] <TB1> INFO: *** Welcome to pxar ***
[13:09:40.872] <TB1> INFO: *** Today: 2016/03/23
[13:09:40.962] <TB1> INFO: *** Version: 9751-dirty
[13:09:40.962] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C15.dat
[13:09:40.962] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//tbmParameters_C0b.dat
[13:09:40.963] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//defaultMaskFile.dat
[13:09:40.963] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters_C15.dat
[13:09:41.032] <TB1> INFO: clk: 4
[13:09:41.032] <TB1> INFO: ctr: 4
[13:09:41.032] <TB1> INFO: sda: 19
[13:09:41.032] <TB1> INFO: tin: 9
[13:09:41.032] <TB1> INFO: level: 15
[13:09:41.032] <TB1> INFO: triggerdelay: 0
[13:09:41.032] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[13:09:41.032] <TB1> INFO: Log level: INFO
[13:09:41.039] <TB1> INFO: Found DTB DTB_WXBYFL
[13:09:41.049] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[13:09:41.051] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[13:09:41.053] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[13:09:42.601] <TB1> INFO: DUT info:
[13:09:42.601] <TB1> INFO: The DUT currently contains the following objects:
[13:09:42.601] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:09:42.601] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:09:42.601] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:09:42.601] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:09:42.601] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:42.601] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:43.002] <TB1> INFO: enter 'restricted' command line mode
[13:09:43.002] <TB1> INFO: enter test to run
[13:09:43.002] <TB1> INFO: test: pretest no parameter change
[13:09:43.002] <TB1> INFO: running: pretest
[13:09:43.011] <TB1> INFO: ----------------------------------------------------------------------
[13:09:43.011] <TB1> INFO: PixTestPretest::programROC()
[13:09:43.011] <TB1> INFO: ----------------------------------------------------------------------
[13:10:01.025] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[13:10:01.025] <TB1> INFO: IA differences per ROC: 18.5 20.9 17.7 17.7 16.9 20.1 16.9 17.7 18.5 19.3 18.5 19.3 18.5 17.7 20.9 20.1
[13:10:01.123] <TB1> INFO: enter test to run
[13:10:01.123] <TB1> INFO: test: pretest no parameter change
[13:10:01.123] <TB1> INFO: running: pretest
[13:10:01.124] <TB1> INFO: ----------------------------------------------------------------------
[13:10:01.124] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[13:10:01.124] <TB1> INFO: ----------------------------------------------------------------------
[13:10:22.395] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 383.5 mA = 23.9688 mA/ROC
[13:10:22.395] <TB1> INFO: i(loss) [mA/ROC]: 20.1 19.3 19.3 19.3 18.5 18.5 18.5 18.5 18.5 17.7 18.5 18.5 19.3 18.5 18.5 18.5
[13:10:22.429] <TB1> INFO: enter test to run
[13:10:22.429] <TB1> INFO: test: pretest no parameter change
[13:10:22.429] <TB1> INFO: running: pretest
[13:10:22.429] <TB1> INFO: ----------------------------------------------------------------------
[13:10:22.429] <TB1> INFO: PixTestPretest::findTiming()
[13:10:22.429] <TB1> INFO: ----------------------------------------------------------------------
[13:10:22.429] <TB1> INFO: PixTestCmd::init()
[13:10:23.386] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[13:11:52.806] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[13:11:52.806] <TB1> INFO: (success/tries = 100/100), width = 5
[13:11:52.806] <TB1> INFO: enter test to run
[13:11:52.806] <TB1> INFO: test: pretest no parameter change
[13:11:52.806] <TB1> INFO: running: pretest
[13:11:52.808] <TB1> INFO: ----------------------------------------------------------------------
[13:11:52.808] <TB1> INFO: PixTestPretest::findWorkingPixel()
[13:11:52.808] <TB1> INFO: ----------------------------------------------------------------------
[13:11:52.898] <TB1> INFO: Expecting 231680 events.
[13:11:58.675] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[13:11:58.760] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[13:12:03.129] <TB1> INFO: 231680 events read in total (9675ms).
[13:12:03.134] <TB1> INFO: Test took 10324ms.
[13:12:03.352] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[13:12:03.425] <TB1> INFO: enter test to run
[13:12:03.425] <TB1> INFO: test: pretest no parameter change
[13:12:03.425] <TB1> INFO: running: pretest
[13:12:03.426] <TB1> INFO: ----------------------------------------------------------------------
[13:12:03.426] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[13:12:03.426] <TB1> INFO: ----------------------------------------------------------------------
[13:12:03.518] <TB1> INFO: Expecting 231680 events.
[13:12:13.703] <TB1> INFO: 231680 events read in total (9629ms).
[13:12:13.706] <TB1> INFO: Test took 10276ms.
[13:12:13.956] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[13:12:13.956] <TB1> INFO: CalDel: 172 142 134 146 156 130 172 140 144 142 126 148 127 129 155 159
[13:12:13.956] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[13:12:13.994] <TB1> INFO: enter test to run
[13:12:13.995] <TB1> INFO: test: pretest no parameter change
[13:12:13.995] <TB1> INFO: running: pretest
[13:12:13.995] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C0.dat
[13:12:13.995] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C1.dat
[13:12:13.995] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C2.dat
[13:12:13.995] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C3.dat
[13:12:13.995] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C4.dat
[13:12:13.995] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C5.dat
[13:12:13.995] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C6.dat
[13:12:13.995] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C7.dat
[13:12:13.995] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C8.dat
[13:12:13.995] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C9.dat
[13:12:13.996] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C10.dat
[13:12:13.996] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C11.dat
[13:12:13.996] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C12.dat
[13:12:13.996] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C13.dat
[13:12:13.996] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C14.dat
[13:12:13.996] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters_C15.dat
[13:12:13.996] <TB1> INFO: enter test to run
[13:12:13.996] <TB1> INFO: test: fulltest no parameter change
[13:12:13.996] <TB1> INFO: running: fulltest
[13:12:13.996] <TB1> INFO: ######################################################################
[13:12:13.996] <TB1> INFO: PixTestFullTest::doTest()
[13:12:13.996] <TB1> INFO: ######################################################################
[13:12:13.997] <TB1> INFO: ######################################################################
[13:12:13.997] <TB1> INFO: PixTestAlive::doTest()
[13:12:13.997] <TB1> INFO: ######################################################################
[13:12:13.998] <TB1> INFO: ----------------------------------------------------------------------
[13:12:13.998] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:12:13.998] <TB1> INFO: ----------------------------------------------------------------------
[13:12:14.263] <TB1> INFO: Expecting 41600 events.
[13:12:18.110] <TB1> INFO: 41600 events read in total (3291ms).
[13:12:18.111] <TB1> INFO: Test took 4111ms.
[13:12:18.332] <TB1> INFO: PixTestAlive::aliveTest() done
[13:12:18.332] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:12:18.334] <TB1> INFO: ----------------------------------------------------------------------
[13:12:18.334] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:12:18.334] <TB1> INFO: ----------------------------------------------------------------------
[13:12:18.599] <TB1> INFO: Expecting 41600 events.
[13:12:21.606] <TB1> INFO: 41600 events read in total (2451ms).
[13:12:21.606] <TB1> INFO: Test took 3271ms.
[13:12:21.606] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:12:21.847] <TB1> INFO: PixTestAlive::maskTest() done
[13:12:21.847] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:12:21.849] <TB1> INFO: ----------------------------------------------------------------------
[13:12:21.849] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:12:21.849] <TB1> INFO: ----------------------------------------------------------------------
[13:12:22.132] <TB1> INFO: Expecting 41600 events.
[13:12:25.979] <TB1> INFO: 41600 events read in total (3291ms).
[13:12:25.980] <TB1> INFO: Test took 4130ms.
[13:12:26.202] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[13:12:26.202] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:12:26.202] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[13:12:26.202] <TB1> INFO: Decoding statistics:
[13:12:26.202] <TB1> INFO: General information:
[13:12:26.202] <TB1> INFO: 16bit words read: 0
[13:12:26.202] <TB1> INFO: valid events total: 0
[13:12:26.202] <TB1> INFO: empty events: 0
[13:12:26.202] <TB1> INFO: valid events with pixels: 0
[13:12:26.202] <TB1> INFO: valid pixel hits: 0
[13:12:26.202] <TB1> INFO: Event errors: 0
[13:12:26.202] <TB1> INFO: start marker: 0
[13:12:26.202] <TB1> INFO: stop marker: 0
[13:12:26.202] <TB1> INFO: overflow: 0
[13:12:26.202] <TB1> INFO: invalid 5bit words: 0
[13:12:26.202] <TB1> INFO: invalid XOR eye diagram: 0
[13:12:26.202] <TB1> INFO: frame (failed synchr.): 0
[13:12:26.202] <TB1> INFO: idle data (no TBM trl): 0
[13:12:26.202] <TB1> INFO: no data (only TBM hdr): 0
[13:12:26.202] <TB1> INFO: TBM errors: 0
[13:12:26.202] <TB1> INFO: flawed TBM headers: 0
[13:12:26.202] <TB1> INFO: flawed TBM trailers: 0
[13:12:26.202] <TB1> INFO: event ID mismatches: 0
[13:12:26.202] <TB1> INFO: ROC errors: 0
[13:12:26.202] <TB1> INFO: missing ROC header(s): 0
[13:12:26.202] <TB1> INFO: misplaced readback start: 0
[13:12:26.202] <TB1> INFO: Pixel decoding errors: 0
[13:12:26.202] <TB1> INFO: pixel data incomplete: 0
[13:12:26.202] <TB1> INFO: pixel address: 0
[13:12:26.202] <TB1> INFO: pulse height fill bit: 0
[13:12:26.202] <TB1> INFO: buffer corruption: 0
[13:12:26.209] <TB1> INFO: ######################################################################
[13:12:26.209] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[13:12:26.209] <TB1> INFO: ######################################################################
[13:12:26.211] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[13:12:26.224] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:12:26.224] <TB1> INFO: run 1 of 1
[13:12:26.516] <TB1> INFO: Expecting 3120000 events.
[13:13:08.503] <TB1> INFO: 877870 events read in total (41431ms).
[13:13:49.466] <TB1> INFO: 1738740 events read in total (82395ms).
[13:14:30.685] <TB1> INFO: 2605635 events read in total (123614ms).
[13:14:54.357] <TB1> INFO: 3120000 events read in total (147285ms).
[13:14:54.405] <TB1> INFO: Test took 148180ms.
[13:15:18.045] <TB1> INFO: PixTestBBMap::doTest() done, duration: 171 seconds
[13:15:18.045] <TB1> INFO: number of dead bumps (per ROC): 9 9 43 23 21 14 17 16 58 39 12 10 24 32 13 24
[13:15:18.045] <TB1> INFO: separation cut (per ROC): 88 111 83 81 67 103 76 79 72 75 74 87 98 82 83 81
[13:15:18.045] <TB1> INFO: Decoding statistics:
[13:15:18.045] <TB1> INFO: General information:
[13:15:18.045] <TB1> INFO: 16bit words read: 0
[13:15:18.045] <TB1> INFO: valid events total: 0
[13:15:18.045] <TB1> INFO: empty events: 0
[13:15:18.045] <TB1> INFO: valid events with pixels: 0
[13:15:18.045] <TB1> INFO: valid pixel hits: 0
[13:15:18.045] <TB1> INFO: Event errors: 0
[13:15:18.045] <TB1> INFO: start marker: 0
[13:15:18.045] <TB1> INFO: stop marker: 0
[13:15:18.045] <TB1> INFO: overflow: 0
[13:15:18.045] <TB1> INFO: invalid 5bit words: 0
[13:15:18.045] <TB1> INFO: invalid XOR eye diagram: 0
[13:15:18.045] <TB1> INFO: frame (failed synchr.): 0
[13:15:18.045] <TB1> INFO: idle data (no TBM trl): 0
[13:15:18.045] <TB1> INFO: no data (only TBM hdr): 0
[13:15:18.045] <TB1> INFO: TBM errors: 0
[13:15:18.045] <TB1> INFO: flawed TBM headers: 0
[13:15:18.045] <TB1> INFO: flawed TBM trailers: 0
[13:15:18.045] <TB1> INFO: event ID mismatches: 0
[13:15:18.045] <TB1> INFO: ROC errors: 0
[13:15:18.045] <TB1> INFO: missing ROC header(s): 0
[13:15:18.045] <TB1> INFO: misplaced readback start: 0
[13:15:18.045] <TB1> INFO: Pixel decoding errors: 0
[13:15:18.045] <TB1> INFO: pixel data incomplete: 0
[13:15:18.045] <TB1> INFO: pixel address: 0
[13:15:18.045] <TB1> INFO: pulse height fill bit: 0
[13:15:18.045] <TB1> INFO: buffer corruption: 0
[13:15:18.095] <TB1> INFO: ######################################################################
[13:15:18.095] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:15:18.095] <TB1> INFO: ######################################################################
[13:15:18.095] <TB1> INFO: ----------------------------------------------------------------------
[13:15:18.095] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:15:18.095] <TB1> INFO: ----------------------------------------------------------------------
[13:15:18.095] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:15:18.104] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:15:18.104] <TB1> INFO: run 1 of 1
[13:15:18.391] <TB1> INFO: Expecting 26208000 events.
[13:15:51.548] <TB1> INFO: 873750 events read in total (32601ms).
[13:16:24.606] <TB1> INFO: 1736400 events read in total (65659ms).
[13:16:57.523] <TB1> INFO: 2597950 events read in total (98576ms).
[13:17:30.693] <TB1> INFO: 3458100 events read in total (131746ms).
[13:18:03.434] <TB1> INFO: 4319200 events read in total (164487ms).
[13:18:36.111] <TB1> INFO: 5178150 events read in total (197164ms).
[13:19:07.998] <TB1> INFO: 6038550 events read in total (229051ms).
[13:19:40.600] <TB1> INFO: 6896900 events read in total (261653ms).
[13:20:13.241] <TB1> INFO: 7755950 events read in total (294294ms).
[13:20:45.836] <TB1> INFO: 8614800 events read in total (326889ms).
[13:21:17.885] <TB1> INFO: 9472200 events read in total (358938ms).
[13:21:50.540] <TB1> INFO: 10329250 events read in total (391593ms).
[13:22:23.002] <TB1> INFO: 11185450 events read in total (424055ms).
[13:22:55.586] <TB1> INFO: 12041150 events read in total (456639ms).
[13:23:28.418] <TB1> INFO: 12894950 events read in total (489471ms).
[13:24:00.817] <TB1> INFO: 13745500 events read in total (521870ms).
[13:24:33.246] <TB1> INFO: 14591550 events read in total (554299ms).
[13:25:05.878] <TB1> INFO: 15438100 events read in total (586931ms).
[13:25:38.320] <TB1> INFO: 16283250 events read in total (619373ms).
[13:26:10.611] <TB1> INFO: 17127500 events read in total (651664ms).
[13:26:43.224] <TB1> INFO: 17970800 events read in total (684277ms).
[13:27:15.453] <TB1> INFO: 18815000 events read in total (716506ms).
[13:27:47.978] <TB1> INFO: 19655500 events read in total (749031ms).
[13:28:20.240] <TB1> INFO: 20497350 events read in total (781293ms).
[13:28:52.690] <TB1> INFO: 21340500 events read in total (813743ms).
[13:29:24.899] <TB1> INFO: 22181950 events read in total (845952ms).
[13:29:57.069] <TB1> INFO: 23023500 events read in total (878122ms).
[13:30:29.022] <TB1> INFO: 23867550 events read in total (910075ms).
[13:31:00.906] <TB1> INFO: 24711700 events read in total (941959ms).
[13:31:33.169] <TB1> INFO: 25552800 events read in total (974222ms).
[13:31:58.441] <TB1> INFO: 26208000 events read in total (999494ms).
[13:31:58.483] <TB1> INFO: Test took 1000379ms.
[13:31:58.835] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:00.646] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:02.172] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:03.956] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:05.381] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:06.833] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:08.325] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:10.115] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:11.831] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:13.535] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:15.060] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:16.604] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:18.138] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:19.578] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:21.054] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:22.528] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:32:23.998] <TB1> INFO: PixTestScurves::scurves() done
[13:32:23.998] <TB1> INFO: Vcal mean: 86.16 107.85 84.03 86.54 75.62 95.96 83.27 93.53 77.65 77.10 80.18 83.65 97.46 84.39 84.15 94.21
[13:32:23.998] <TB1> INFO: Vcal RMS: 5.52 6.18 4.72 5.70 4.65 5.68 4.73 5.98 4.15 3.83 4.10 5.37 6.09 4.88 5.06 5.30
[13:32:23.998] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1025 seconds
[13:32:23.998] <TB1> INFO: Decoding statistics:
[13:32:23.998] <TB1> INFO: General information:
[13:32:23.998] <TB1> INFO: 16bit words read: 0
[13:32:23.998] <TB1> INFO: valid events total: 0
[13:32:23.998] <TB1> INFO: empty events: 0
[13:32:23.998] <TB1> INFO: valid events with pixels: 0
[13:32:23.998] <TB1> INFO: valid pixel hits: 0
[13:32:23.998] <TB1> INFO: Event errors: 0
[13:32:23.998] <TB1> INFO: start marker: 0
[13:32:23.998] <TB1> INFO: stop marker: 0
[13:32:23.998] <TB1> INFO: overflow: 0
[13:32:23.998] <TB1> INFO: invalid 5bit words: 0
[13:32:23.998] <TB1> INFO: invalid XOR eye diagram: 0
[13:32:23.998] <TB1> INFO: frame (failed synchr.): 0
[13:32:23.998] <TB1> INFO: idle data (no TBM trl): 0
[13:32:23.998] <TB1> INFO: no data (only TBM hdr): 0
[13:32:23.998] <TB1> INFO: TBM errors: 0
[13:32:23.998] <TB1> INFO: flawed TBM headers: 0
[13:32:23.998] <TB1> INFO: flawed TBM trailers: 0
[13:32:23.998] <TB1> INFO: event ID mismatches: 0
[13:32:23.998] <TB1> INFO: ROC errors: 0
[13:32:23.998] <TB1> INFO: missing ROC header(s): 0
[13:32:23.998] <TB1> INFO: misplaced readback start: 0
[13:32:23.998] <TB1> INFO: Pixel decoding errors: 0
[13:32:23.999] <TB1> INFO: pixel data incomplete: 0
[13:32:23.999] <TB1> INFO: pixel address: 0
[13:32:23.999] <TB1> INFO: pulse height fill bit: 0
[13:32:23.999] <TB1> INFO: buffer corruption: 0
[13:32:24.073] <TB1> INFO: ######################################################################
[13:32:24.073] <TB1> INFO: PixTestTrim::doTest()
[13:32:24.073] <TB1> INFO: ######################################################################
[13:32:24.074] <TB1> INFO: ----------------------------------------------------------------------
[13:32:24.074] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[13:32:24.074] <TB1> INFO: ----------------------------------------------------------------------
[13:32:24.159] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[13:32:24.159] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:32:24.168] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:32:24.168] <TB1> INFO: run 1 of 1
[13:32:24.436] <TB1> INFO: Expecting 6281600 events.
[13:33:04.367] <TB1> INFO: 1072620 events read in total (39375ms).
[13:33:42.968] <TB1> INFO: 2138200 events read in total (77976ms).
[13:34:21.674] <TB1> INFO: 3194350 events read in total (116682ms).
[13:35:01.027] <TB1> INFO: 4256200 events read in total (156035ms).
[13:35:39.647] <TB1> INFO: 5319470 events read in total (194655ms).
[13:36:14.897] <TB1> INFO: 6281600 events read in total (229905ms).
[13:36:14.932] <TB1> INFO: Test took 230764ms.
[13:36:35.136] <TB1> INFO: ROC 0 VthrComp = 86
[13:36:35.136] <TB1> INFO: ROC 1 VthrComp = 103
[13:36:35.136] <TB1> INFO: ROC 2 VthrComp = 91
[13:36:35.136] <TB1> INFO: ROC 3 VthrComp = 85
[13:36:35.136] <TB1> INFO: ROC 4 VthrComp = 76
[13:36:35.136] <TB1> INFO: ROC 5 VthrComp = 96
[13:36:35.136] <TB1> INFO: ROC 6 VthrComp = 82
[13:36:35.136] <TB1> INFO: ROC 7 VthrComp = 91
[13:36:35.136] <TB1> INFO: ROC 8 VthrComp = 83
[13:36:35.137] <TB1> INFO: ROC 9 VthrComp = 83
[13:36:35.137] <TB1> INFO: ROC 10 VthrComp = 87
[13:36:35.137] <TB1> INFO: ROC 11 VthrComp = 84
[13:36:35.137] <TB1> INFO: ROC 12 VthrComp = 96
[13:36:35.137] <TB1> INFO: ROC 13 VthrComp = 90
[13:36:35.137] <TB1> INFO: ROC 14 VthrComp = 87
[13:36:35.137] <TB1> INFO: ROC 15 VthrComp = 94
[13:36:35.137] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:36:35.137] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:36:35.146] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:36:35.146] <TB1> INFO: run 1 of 1
[13:36:35.405] <TB1> INFO: Expecting 6281600 events.
[13:37:11.215] <TB1> INFO: 755440 events read in total (35254ms).
[13:37:45.995] <TB1> INFO: 1509320 events read in total (70034ms).
[13:38:20.550] <TB1> INFO: 2264020 events read in total (104589ms).
[13:38:55.134] <TB1> INFO: 3018410 events read in total (139173ms).
[13:39:30.245] <TB1> INFO: 3768270 events read in total (174284ms).
[13:40:05.138] <TB1> INFO: 4514090 events read in total (209177ms).
[13:40:39.216] <TB1> INFO: 5258290 events read in total (243255ms).
[13:41:13.792] <TB1> INFO: 6002380 events read in total (277831ms).
[13:41:27.743] <TB1> INFO: 6281600 events read in total (291782ms).
[13:41:27.782] <TB1> INFO: Test took 292636ms.
[13:41:52.341] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 60.905 for pixel 22/75 mean/min/max = 46.4597/31.9922/60.9271
[13:41:52.341] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 64.807 for pixel 29/52 mean/min/max = 48.2712/31.7325/64.8098
[13:41:52.341] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 56.0917 for pixel 51/76 mean/min/max = 44.5498/32.6392/56.4605
[13:41:52.342] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 60.5836 for pixel 12/37 mean/min/max = 46.3333/31.7173/60.9493
[13:41:52.342] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 61.2666 for pixel 9/69 mean/min/max = 48.2987/35.324/61.2733
[13:41:52.342] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 59.9922 for pixel 24/1 mean/min/max = 45.6558/31.2985/60.0132
[13:41:52.343] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 58.0923 for pixel 12/19 mean/min/max = 45.5519/32.9096/58.1941
[13:41:52.343] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 61.2347 for pixel 17/70 mean/min/max = 46.9052/32.5115/61.2988
[13:41:52.343] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 56.8161 for pixel 17/68 mean/min/max = 44.6397/32.3622/56.9172
[13:41:52.344] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 56.213 for pixel 2/2 mean/min/max = 44.6428/32.9873/56.2982
[13:41:52.344] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 55.6877 for pixel 22/73 mean/min/max = 43.8491/31.8408/55.8574
[13:41:52.344] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 60.8457 for pixel 6/2 mean/min/max = 46.5167/32.1693/60.8641
[13:41:52.345] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 61.778 for pixel 51/71 mean/min/max = 46.6537/31.4733/61.8342
[13:41:52.345] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 57.4094 for pixel 47/71 mean/min/max = 45.1429/32.6748/57.6109
[13:41:52.345] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 58.0381 for pixel 13/70 mean/min/max = 45.1614/32.2199/58.1029
[13:41:52.345] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 59.3164 for pixel 1/16 mean/min/max = 46.0382/32.532/59.5444
[13:41:52.346] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:41:52.434] <TB1> INFO: Expecting 514560 events.
[13:42:05.188] <TB1> INFO: 514560 events read in total (12194ms).
[13:42:05.193] <TB1> INFO: Expecting 514560 events.
[13:42:18.102] <TB1> INFO: 514560 events read in total (12485ms).
[13:42:18.111] <TB1> INFO: Expecting 514560 events.
[13:42:30.971] <TB1> INFO: 514560 events read in total (12458ms).
[13:42:30.982] <TB1> INFO: Expecting 514560 events.
[13:42:43.758] <TB1> INFO: 514560 events read in total (12365ms).
[13:42:43.771] <TB1> INFO: Expecting 514560 events.
[13:42:56.611] <TB1> INFO: 514560 events read in total (12426ms).
[13:42:56.626] <TB1> INFO: Expecting 514560 events.
[13:43:09.406] <TB1> INFO: 514560 events read in total (12375ms).
[13:43:09.421] <TB1> INFO: Expecting 514560 events.
[13:43:22.128] <TB1> INFO: 514560 events read in total (12305ms).
[13:43:22.143] <TB1> INFO: Expecting 514560 events.
[13:43:34.995] <TB1> INFO: 514560 events read in total (12444ms).
[13:43:35.015] <TB1> INFO: Expecting 514560 events.
[13:43:47.985] <TB1> INFO: 514560 events read in total (12568ms).
[13:43:48.009] <TB1> INFO: Expecting 514560 events.
[13:44:00.862] <TB1> INFO: 514560 events read in total (12451ms).
[13:44:00.888] <TB1> INFO: Expecting 514560 events.
[13:44:13.801] <TB1> INFO: 514560 events read in total (12511ms).
[13:44:13.831] <TB1> INFO: Expecting 514560 events.
[13:44:26.573] <TB1> INFO: 514560 events read in total (12342ms).
[13:44:26.599] <TB1> INFO: Expecting 514560 events.
[13:44:39.389] <TB1> INFO: 514560 events read in total (12386ms).
[13:44:39.422] <TB1> INFO: Expecting 514560 events.
[13:44:52.439] <TB1> INFO: 514560 events read in total (12615ms).
[13:44:52.470] <TB1> INFO: Expecting 514560 events.
[13:45:05.328] <TB1> INFO: 514560 events read in total (12456ms).
[13:45:05.370] <TB1> INFO: Expecting 514560 events.
[13:45:18.172] <TB1> INFO: 514560 events read in total (12400ms).
[13:45:18.215] <TB1> INFO: Test took 205869ms.
[13:45:19.294] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:45:19.303] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:45:19.303] <TB1> INFO: run 1 of 1
[13:45:19.597] <TB1> INFO: Expecting 6281600 events.
[13:45:54.950] <TB1> INFO: 741140 events read in total (34797ms).
[13:46:29.728] <TB1> INFO: 1480950 events read in total (69575ms).
[13:47:05.280] <TB1> INFO: 2221740 events read in total (105127ms).
[13:47:40.423] <TB1> INFO: 2962820 events read in total (140270ms).
[13:48:14.603] <TB1> INFO: 3698380 events read in total (174450ms).
[13:48:49.339] <TB1> INFO: 4429770 events read in total (209186ms).
[13:49:23.774] <TB1> INFO: 5159420 events read in total (243621ms).
[13:49:58.336] <TB1> INFO: 5887220 events read in total (278183ms).
[13:50:17.308] <TB1> INFO: 6281600 events read in total (297155ms).
[13:50:17.370] <TB1> INFO: Test took 298067ms.
[13:50:42.883] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.073121 .. 255.000000
[13:50:42.964] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[13:50:42.972] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:50:42.972] <TB1> INFO: run 1 of 1
[13:50:43.242] <TB1> INFO: Expecting 10649600 events.
[13:51:18.310] <TB1> INFO: 712340 events read in total (34512ms).
[13:51:52.855] <TB1> INFO: 1424850 events read in total (69057ms).
[13:52:27.229] <TB1> INFO: 2137500 events read in total (103431ms).
[13:53:01.478] <TB1> INFO: 2850240 events read in total (137680ms).
[13:53:35.718] <TB1> INFO: 3562830 events read in total (171920ms).
[13:54:10.159] <TB1> INFO: 4275310 events read in total (206361ms).
[13:54:44.202] <TB1> INFO: 4987990 events read in total (240404ms).
[13:55:18.192] <TB1> INFO: 5700460 events read in total (274394ms).
[13:55:52.493] <TB1> INFO: 6412690 events read in total (308695ms).
[13:56:26.700] <TB1> INFO: 7124350 events read in total (342902ms).
[13:57:00.998] <TB1> INFO: 7835960 events read in total (377200ms).
[13:57:35.276] <TB1> INFO: 8547060 events read in total (411478ms).
[13:58:08.812] <TB1> INFO: 9257740 events read in total (445014ms).
[13:58:42.740] <TB1> INFO: 9968220 events read in total (478942ms).
[13:59:15.503] <TB1> INFO: 10649600 events read in total (511705ms).
[13:59:15.608] <TB1> INFO: Test took 512636ms.
[13:59:46.051] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 14.469655 .. 46.336195
[13:59:46.147] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 4 .. 56 (-1/-1) hits flags = 528 (plus default)
[13:59:46.155] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:59:46.155] <TB1> INFO: run 1 of 1
[13:59:46.420] <TB1> INFO: Expecting 2204800 events.
[14:00:24.813] <TB1> INFO: 935140 events read in total (37837ms).
[14:01:02.788] <TB1> INFO: 1868770 events read in total (75812ms).
[14:01:16.676] <TB1> INFO: 2204800 events read in total (89701ms).
[14:01:16.697] <TB1> INFO: Test took 90542ms.
[14:01:30.905] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 19.405147 .. 44.193003
[14:01:30.982] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 54 (-1/-1) hits flags = 528 (plus default)
[14:01:30.990] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[14:01:30.990] <TB1> INFO: run 1 of 1
[14:01:31.254] <TB1> INFO: Expecting 1913600 events.
[14:02:09.876] <TB1> INFO: 924370 events read in total (38066ms).
[14:02:47.809] <TB1> INFO: 1848060 events read in total (75999ms).
[14:02:50.929] <TB1> INFO: 1913600 events read in total (79120ms).
[14:02:50.951] <TB1> INFO: Test took 79962ms.
[14:03:04.897] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 2.891168 .. 43.717199
[14:03:04.975] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 53 (-1/-1) hits flags = 528 (plus default)
[14:03:04.983] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[14:03:04.987] <TB1> INFO: run 1 of 1
[14:03:05.246] <TB1> INFO: Expecting 2163200 events.
[14:03:44.305] <TB1> INFO: 971850 events read in total (38503ms).
[14:04:22.630] <TB1> INFO: 1944120 events read in total (76828ms).
[14:04:31.431] <TB1> INFO: 2163200 events read in total (85630ms).
[14:04:31.450] <TB1> INFO: Test took 86463ms.
[14:04:45.308] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[14:04:45.309] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[14:04:45.328] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[14:04:45.328] <TB1> INFO: run 1 of 1
[14:04:45.587] <TB1> INFO: Expecting 1705600 events.
[14:05:23.641] <TB1> INFO: 879310 events read in total (37498ms).
[14:05:58.539] <TB1> INFO: 1705600 events read in total (72396ms).
[14:05:58.559] <TB1> INFO: Test took 73231ms.
[14:06:11.716] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C0.dat
[14:06:11.716] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C1.dat
[14:06:11.716] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C2.dat
[14:06:11.717] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C3.dat
[14:06:11.717] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C4.dat
[14:06:11.717] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C5.dat
[14:06:11.717] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C6.dat
[14:06:11.717] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C7.dat
[14:06:11.717] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C8.dat
[14:06:11.717] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C9.dat
[14:06:11.717] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C10.dat
[14:06:11.717] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C11.dat
[14:06:11.718] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C12.dat
[14:06:11.718] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C13.dat
[14:06:11.718] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C14.dat
[14:06:11.718] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C15.dat
[14:06:11.718] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C0.dat
[14:06:11.727] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C1.dat
[14:06:11.735] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C2.dat
[14:06:11.743] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C3.dat
[14:06:11.751] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C4.dat
[14:06:11.757] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C5.dat
[14:06:11.764] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C6.dat
[14:06:11.771] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C7.dat
[14:06:11.777] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C8.dat
[14:06:11.783] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C9.dat
[14:06:11.789] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C10.dat
[14:06:11.795] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C11.dat
[14:06:11.801] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C12.dat
[14:06:11.807] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C13.dat
[14:06:11.814] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C14.dat
[14:06:11.820] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//trimParameters35_C15.dat
[14:06:11.826] <TB1> INFO: PixTestTrim::trimTest() done
[14:06:11.826] <TB1> INFO: vtrim: 105 114 91 91 100 96 83 104 110 89 100 88 89 107 96 96
[14:06:11.826] <TB1> INFO: vthrcomp: 86 103 91 85 76 96 82 91 83 83 87 84 96 90 87 94
[14:06:11.826] <TB1> INFO: vcal mean: 34.94 35.06 35.00 34.96 35.11 34.94 34.99 34.97 34.96 34.97 34.95 35.03 35.01 35.00 34.98 35.02
[14:06:11.826] <TB1> INFO: vcal RMS: 0.92 3.54 0.77 0.90 0.86 0.88 0.88 1.04 0.80 0.77 0.76 0.83 0.99 0.80 0.83 0.85
[14:06:11.826] <TB1> INFO: bits mean: 9.66 9.35 9.00 9.39 8.37 9.70 9.60 9.39 9.86 9.28 9.84 8.90 8.89 9.54 9.65 9.48
[14:06:11.826] <TB1> INFO: bits RMS: 2.61 2.59 2.95 2.72 2.52 2.66 2.54 2.61 2.55 2.75 2.62 2.89 2.87 2.60 2.63 2.62
[14:06:11.832] <TB1> INFO: ----------------------------------------------------------------------
[14:06:11.832] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[14:06:11.832] <TB1> INFO: ----------------------------------------------------------------------
[14:06:11.835] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[14:06:11.844] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[14:06:11.844] <TB1> INFO: run 1 of 1
[14:06:12.109] <TB1> INFO: Expecting 4160000 events.
[14:06:53.573] <TB1> INFO: 910590 events read in total (40906ms).
[14:07:34.964] <TB1> INFO: 1816045 events read in total (82297ms).
[14:08:16.097] <TB1> INFO: 2713755 events read in total (123430ms).
[14:08:57.014] <TB1> INFO: 3607155 events read in total (164347ms).
[14:09:22.645] <TB1> INFO: 4160000 events read in total (189978ms).
[14:09:22.684] <TB1> INFO: Test took 190840ms.
[14:09:51.458] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[14:09:51.468] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[14:09:51.468] <TB1> INFO: run 1 of 1
[14:09:51.755] <TB1> INFO: Expecting 3598400 events.
[14:10:33.307] <TB1> INFO: 929550 events read in total (40995ms).
[14:11:14.671] <TB1> INFO: 1852015 events read in total (82359ms).
[14:11:56.066] <TB1> INFO: 2763365 events read in total (123754ms).
[14:12:33.757] <TB1> INFO: 3598400 events read in total (161445ms).
[14:12:33.799] <TB1> INFO: Test took 162332ms.
[14:13:02.092] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 159 (-1/-1) hits flags = 528 (plus default)
[14:13:02.101] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[14:13:02.101] <TB1> INFO: run 1 of 1
[14:13:02.372] <TB1> INFO: Expecting 3328000 events.
[14:13:45.240] <TB1> INFO: 960430 events read in total (42312ms).
[14:14:26.950] <TB1> INFO: 1910290 events read in total (84022ms).
[14:15:08.792] <TB1> INFO: 2848920 events read in total (125864ms).
[14:15:30.355] <TB1> INFO: 3328000 events read in total (147427ms).
[14:15:30.389] <TB1> INFO: Test took 148287ms.
[14:15:57.557] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 158 (-1/-1) hits flags = 528 (plus default)
[14:15:57.566] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[14:15:57.566] <TB1> INFO: run 1 of 1
[14:15:57.844] <TB1> INFO: Expecting 3307200 events.
[14:16:42.798] <TB1> INFO: 962625 events read in total (44398ms).
[14:17:25.559] <TB1> INFO: 1914830 events read in total (87159ms).
[14:18:07.235] <TB1> INFO: 2855585 events read in total (128836ms).
[14:18:27.309] <TB1> INFO: 3307200 events read in total (148909ms).
[14:18:27.351] <TB1> INFO: Test took 149785ms.
[14:18:52.696] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 158 (-1/-1) hits flags = 528 (plus default)
[14:18:52.704] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[14:18:52.704] <TB1> INFO: run 1 of 1
[14:18:52.967] <TB1> INFO: Expecting 3307200 events.
[14:19:35.258] <TB1> INFO: 962390 events read in total (41734ms).
[14:20:17.883] <TB1> INFO: 1914190 events read in total (84359ms).
[14:20:59.430] <TB1> INFO: 2855000 events read in total (125907ms).
[14:21:19.662] <TB1> INFO: 3307200 events read in total (146138ms).
[14:21:19.699] <TB1> INFO: Test took 146994ms.
[14:21:45.171] <TB1> INFO: PixTestTrim::trimBitTest() done
[14:21:45.172] <TB1> INFO: PixTestTrim::doTest() done, duration: 2961 seconds
[14:21:45.172] <TB1> INFO: Decoding statistics:
[14:21:45.172] <TB1> INFO: General information:
[14:21:45.172] <TB1> INFO: 16bit words read: 0
[14:21:45.172] <TB1> INFO: valid events total: 0
[14:21:45.172] <TB1> INFO: empty events: 0
[14:21:45.172] <TB1> INFO: valid events with pixels: 0
[14:21:45.172] <TB1> INFO: valid pixel hits: 0
[14:21:45.172] <TB1> INFO: Event errors: 0
[14:21:45.172] <TB1> INFO: start marker: 0
[14:21:45.172] <TB1> INFO: stop marker: 0
[14:21:45.172] <TB1> INFO: overflow: 0
[14:21:45.172] <TB1> INFO: invalid 5bit words: 0
[14:21:45.172] <TB1> INFO: invalid XOR eye diagram: 0
[14:21:45.172] <TB1> INFO: frame (failed synchr.): 0
[14:21:45.172] <TB1> INFO: idle data (no TBM trl): 0
[14:21:45.172] <TB1> INFO: no data (only TBM hdr): 0
[14:21:45.172] <TB1> INFO: TBM errors: 0
[14:21:45.172] <TB1> INFO: flawed TBM headers: 0
[14:21:45.172] <TB1> INFO: flawed TBM trailers: 0
[14:21:45.172] <TB1> INFO: event ID mismatches: 0
[14:21:45.172] <TB1> INFO: ROC errors: 0
[14:21:45.172] <TB1> INFO: missing ROC header(s): 0
[14:21:45.172] <TB1> INFO: misplaced readback start: 0
[14:21:45.172] <TB1> INFO: Pixel decoding errors: 0
[14:21:45.172] <TB1> INFO: pixel data incomplete: 0
[14:21:45.172] <TB1> INFO: pixel address: 0
[14:21:45.172] <TB1> INFO: pulse height fill bit: 0
[14:21:45.172] <TB1> INFO: buffer corruption: 0
[14:21:45.975] <TB1> INFO: ######################################################################
[14:21:45.975] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[14:21:45.975] <TB1> INFO: ######################################################################
[14:21:46.235] <TB1> INFO: Expecting 41600 events.
[14:21:50.090] <TB1> INFO: 41600 events read in total (3299ms).
[14:21:50.091] <TB1> INFO: Test took 4115ms.
[14:21:50.558] <TB1> INFO: Expecting 41600 events.
[14:21:54.455] <TB1> INFO: 41600 events read in total (3341ms).
[14:21:54.455] <TB1> INFO: Test took 4164ms.
[14:21:54.767] <TB1> INFO: Expecting 41600 events.
[14:21:58.564] <TB1> INFO: 41600 events read in total (3241ms).
[14:21:58.564] <TB1> INFO: Test took 4087ms.
[14:21:58.819] <TB1> INFO: Expecting 2560 events.
[14:21:59.674] <TB1> INFO: 2560 events read in total (299ms).
[14:21:59.674] <TB1> INFO: Test took 1097ms.
[14:21:59.979] <TB1> INFO: Expecting 2560 events.
[14:22:00.832] <TB1> INFO: 2560 events read in total (297ms).
[14:22:00.832] <TB1> INFO: Test took 1158ms.
[14:22:01.137] <TB1> INFO: Expecting 2560 events.
[14:22:01.990] <TB1> INFO: 2560 events read in total (296ms).
[14:22:01.990] <TB1> INFO: Test took 1158ms.
[14:22:02.295] <TB1> INFO: Expecting 2560 events.
[14:22:03.146] <TB1> INFO: 2560 events read in total (295ms).
[14:22:03.147] <TB1> INFO: Test took 1156ms.
[14:22:03.452] <TB1> INFO: Expecting 2560 events.
[14:22:04.305] <TB1> INFO: 2560 events read in total (298ms).
[14:22:04.306] <TB1> INFO: Test took 1159ms.
[14:22:04.610] <TB1> INFO: Expecting 2560 events.
[14:22:05.463] <TB1> INFO: 2560 events read in total (297ms).
[14:22:05.463] <TB1> INFO: Test took 1156ms.
[14:22:05.768] <TB1> INFO: Expecting 2560 events.
[14:22:06.621] <TB1> INFO: 2560 events read in total (297ms).
[14:22:06.621] <TB1> INFO: Test took 1158ms.
[14:22:06.926] <TB1> INFO: Expecting 2560 events.
[14:22:07.779] <TB1> INFO: 2560 events read in total (297ms).
[14:22:07.779] <TB1> INFO: Test took 1158ms.
[14:22:08.084] <TB1> INFO: Expecting 2560 events.
[14:22:08.937] <TB1> INFO: 2560 events read in total (297ms).
[14:22:08.937] <TB1> INFO: Test took 1158ms.
[14:22:09.242] <TB1> INFO: Expecting 2560 events.
[14:22:10.096] <TB1> INFO: 2560 events read in total (298ms).
[14:22:10.096] <TB1> INFO: Test took 1158ms.
[14:22:10.401] <TB1> INFO: Expecting 2560 events.
[14:22:11.257] <TB1> INFO: 2560 events read in total (300ms).
[14:22:11.257] <TB1> INFO: Test took 1157ms.
[14:22:11.562] <TB1> INFO: Expecting 2560 events.
[14:22:12.414] <TB1> INFO: 2560 events read in total (296ms).
[14:22:12.414] <TB1> INFO: Test took 1157ms.
[14:22:12.720] <TB1> INFO: Expecting 2560 events.
[14:22:13.574] <TB1> INFO: 2560 events read in total (298ms).
[14:22:13.574] <TB1> INFO: Test took 1159ms.
[14:22:13.879] <TB1> INFO: Expecting 2560 events.
[14:22:14.732] <TB1> INFO: 2560 events read in total (297ms).
[14:22:14.732] <TB1> INFO: Test took 1157ms.
[14:22:15.037] <TB1> INFO: Expecting 2560 events.
[14:22:15.890] <TB1> INFO: 2560 events read in total (297ms).
[14:22:15.890] <TB1> INFO: Test took 1157ms.
[14:22:16.195] <TB1> INFO: Expecting 2560 events.
[14:22:17.049] <TB1> INFO: 2560 events read in total (297ms).
[14:22:17.049] <TB1> INFO: Test took 1158ms.
[14:22:17.051] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:22:17.355] <TB1> INFO: Expecting 655360 events.
[14:22:34.238] <TB1> INFO: 655360 events read in total (16326ms).
[14:22:34.249] <TB1> INFO: Expecting 655360 events.
[14:22:50.794] <TB1> INFO: 655360 events read in total (16143ms).
[14:22:50.807] <TB1> INFO: Expecting 655360 events.
[14:23:07.388] <TB1> INFO: 655360 events read in total (16180ms).
[14:23:07.406] <TB1> INFO: Expecting 655360 events.
[14:23:23.897] <TB1> INFO: 655360 events read in total (16089ms).
[14:23:23.916] <TB1> INFO: Expecting 655360 events.
[14:23:40.681] <TB1> INFO: 655360 events read in total (16363ms).
[14:23:40.704] <TB1> INFO: Expecting 655360 events.
[14:23:57.370] <TB1> INFO: 655360 events read in total (16264ms).
[14:23:57.396] <TB1> INFO: Expecting 655360 events.
[14:24:14.045] <TB1> INFO: 655360 events read in total (16248ms).
[14:24:14.076] <TB1> INFO: Expecting 655360 events.
[14:24:30.730] <TB1> INFO: 655360 events read in total (16252ms).
[14:24:30.763] <TB1> INFO: Expecting 655360 events.
[14:24:47.476] <TB1> INFO: 655360 events read in total (16311ms).
[14:24:47.514] <TB1> INFO: Expecting 655360 events.
[14:25:04.225] <TB1> INFO: 655360 events read in total (16309ms).
[14:25:04.266] <TB1> INFO: Expecting 655360 events.
[14:25:21.027] <TB1> INFO: 655360 events read in total (16359ms).
[14:25:21.072] <TB1> INFO: Expecting 655360 events.
[14:25:37.842] <TB1> INFO: 655360 events read in total (16368ms).
[14:25:37.899] <TB1> INFO: Expecting 655360 events.
[14:25:54.650] <TB1> INFO: 655360 events read in total (16349ms).
[14:25:54.703] <TB1> INFO: Expecting 655360 events.
[14:26:11.567] <TB1> INFO: 655360 events read in total (16462ms).
[14:26:11.625] <TB1> INFO: Expecting 655360 events.
[14:26:28.313] <TB1> INFO: 655360 events read in total (16287ms).
[14:26:28.380] <TB1> INFO: Expecting 655360 events.
[14:26:45.090] <TB1> INFO: 655360 events read in total (16308ms).
[14:26:45.154] <TB1> INFO: Test took 268103ms.
[14:26:45.237] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:45.467] <TB1> INFO: Expecting 655360 events.
[14:27:02.294] <TB1> INFO: 655360 events read in total (16271ms).
[14:27:02.305] <TB1> INFO: Expecting 655360 events.
[14:27:18.500] <TB1> INFO: 655360 events read in total (15793ms).
[14:27:18.512] <TB1> INFO: Expecting 655360 events.
[14:27:35.166] <TB1> INFO: 655360 events read in total (16252ms).
[14:27:35.184] <TB1> INFO: Expecting 655360 events.
[14:27:51.906] <TB1> INFO: 655360 events read in total (16320ms).
[14:27:51.925] <TB1> INFO: Expecting 655360 events.
[14:28:08.617] <TB1> INFO: 655360 events read in total (16290ms).
[14:28:08.641] <TB1> INFO: Expecting 655360 events.
[14:28:25.453] <TB1> INFO: 655360 events read in total (16410ms).
[14:28:25.478] <TB1> INFO: Expecting 655360 events.
[14:28:42.286] <TB1> INFO: 655360 events read in total (16406ms).
[14:28:42.329] <TB1> INFO: Expecting 655360 events.
[14:28:59.118] <TB1> INFO: 655360 events read in total (16388ms).
[14:28:59.156] <TB1> INFO: Expecting 655360 events.
[14:29:15.926] <TB1> INFO: 655360 events read in total (16368ms).
[14:29:15.975] <TB1> INFO: Expecting 655360 events.
[14:29:32.778] <TB1> INFO: 655360 events read in total (16401ms).
[14:29:32.819] <TB1> INFO: Expecting 655360 events.
[14:29:49.589] <TB1> INFO: 655360 events read in total (16368ms).
[14:29:49.634] <TB1> INFO: Expecting 655360 events.
[14:30:06.439] <TB1> INFO: 655360 events read in total (16403ms).
[14:30:06.492] <TB1> INFO: Expecting 655360 events.
[14:30:23.244] <TB1> INFO: 655360 events read in total (16350ms).
[14:30:23.296] <TB1> INFO: Expecting 655360 events.
[14:30:40.007] <TB1> INFO: 655360 events read in total (16310ms).
[14:30:40.067] <TB1> INFO: Expecting 655360 events.
[14:30:56.627] <TB1> INFO: 655360 events read in total (16159ms).
[14:30:56.686] <TB1> INFO: Expecting 655360 events.
[14:31:13.546] <TB1> INFO: 655360 events read in total (16458ms).
[14:31:13.612] <TB1> INFO: Test took 268375ms.
[14:31:13.809] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.816] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.824] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.831] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.838] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.845] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.852] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[14:31:13.859] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[14:31:13.866] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[14:31:13.874] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.881] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.888] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.895] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.903] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.910] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.917] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.925] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[14:31:13.931] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[14:31:13.938] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[14:31:13.945] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.952] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.959] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:31:13.966] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[14:31:13.973] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[14:31:13.980] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[14:31:13.988] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[14:31:13.994] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[14:31:14.001] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[14:31:14.009] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[14:31:14.016] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[14:31:14.022] <TB1> INFO: safety margin for low PH: adding 9, margin is now 29
[14:31:14.054] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C0.dat
[14:31:14.054] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C1.dat
[14:31:14.054] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C2.dat
[14:31:14.055] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C3.dat
[14:31:14.055] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C4.dat
[14:31:14.055] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C5.dat
[14:31:14.055] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C6.dat
[14:31:14.055] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C7.dat
[14:31:14.055] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C8.dat
[14:31:14.055] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C9.dat
[14:31:14.055] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C10.dat
[14:31:14.055] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C11.dat
[14:31:14.055] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C12.dat
[14:31:14.055] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C13.dat
[14:31:14.055] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C14.dat
[14:31:14.056] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//dacParameters35_C15.dat
[14:31:14.322] <TB1> INFO: Expecting 41600 events.
[14:31:17.845] <TB1> INFO: 41600 events read in total (2966ms).
[14:31:17.846] <TB1> INFO: Test took 3788ms.
[14:31:18.296] <TB1> INFO: Expecting 41600 events.
[14:31:21.790] <TB1> INFO: 41600 events read in total (2937ms).
[14:31:21.791] <TB1> INFO: Test took 3768ms.
[14:31:22.239] <TB1> INFO: Expecting 41600 events.
[14:31:25.730] <TB1> INFO: 41600 events read in total (2935ms).
[14:31:25.732] <TB1> INFO: Test took 3765ms.
[14:31:25.928] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:26.016] <TB1> INFO: Expecting 2560 events.
[14:31:26.873] <TB1> INFO: 2560 events read in total (301ms).
[14:31:26.874] <TB1> INFO: Test took 946ms.
[14:31:26.875] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:27.179] <TB1> INFO: Expecting 2560 events.
[14:31:28.042] <TB1> INFO: 2560 events read in total (307ms).
[14:31:28.042] <TB1> INFO: Test took 1167ms.
[14:31:28.043] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:28.347] <TB1> INFO: Expecting 2560 events.
[14:31:29.200] <TB1> INFO: 2560 events read in total (297ms).
[14:31:29.200] <TB1> INFO: Test took 1157ms.
[14:31:29.202] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:29.506] <TB1> INFO: Expecting 2560 events.
[14:31:30.359] <TB1> INFO: 2560 events read in total (297ms).
[14:31:30.359] <TB1> INFO: Test took 1157ms.
[14:31:30.361] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:30.665] <TB1> INFO: Expecting 2560 events.
[14:31:31.518] <TB1> INFO: 2560 events read in total (297ms).
[14:31:31.518] <TB1> INFO: Test took 1157ms.
[14:31:31.520] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:31.823] <TB1> INFO: Expecting 2560 events.
[14:31:32.679] <TB1> INFO: 2560 events read in total (300ms).
[14:31:32.679] <TB1> INFO: Test took 1159ms.
[14:31:32.682] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:32.984] <TB1> INFO: Expecting 2560 events.
[14:31:33.840] <TB1> INFO: 2560 events read in total (300ms).
[14:31:33.841] <TB1> INFO: Test took 1159ms.
[14:31:33.843] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:34.146] <TB1> INFO: Expecting 2560 events.
[14:31:35.006] <TB1> INFO: 2560 events read in total (304ms).
[14:31:35.006] <TB1> INFO: Test took 1163ms.
[14:31:35.009] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:35.311] <TB1> INFO: Expecting 2560 events.
[14:31:36.168] <TB1> INFO: 2560 events read in total (300ms).
[14:31:36.168] <TB1> INFO: Test took 1159ms.
[14:31:36.170] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:36.474] <TB1> INFO: Expecting 2560 events.
[14:31:37.328] <TB1> INFO: 2560 events read in total (298ms).
[14:31:37.328] <TB1> INFO: Test took 1158ms.
[14:31:37.331] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:37.633] <TB1> INFO: Expecting 2560 events.
[14:31:38.487] <TB1> INFO: 2560 events read in total (297ms).
[14:31:38.488] <TB1> INFO: Test took 1157ms.
[14:31:38.490] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:38.796] <TB1> INFO: Expecting 2560 events.
[14:31:39.655] <TB1> INFO: 2560 events read in total (303ms).
[14:31:39.656] <TB1> INFO: Test took 1166ms.
[14:31:39.658] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:39.961] <TB1> INFO: Expecting 2560 events.
[14:31:40.814] <TB1> INFO: 2560 events read in total (297ms).
[14:31:40.814] <TB1> INFO: Test took 1156ms.
[14:31:40.816] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:41.119] <TB1> INFO: Expecting 2560 events.
[14:31:41.973] <TB1> INFO: 2560 events read in total (298ms).
[14:31:41.973] <TB1> INFO: Test took 1157ms.
[14:31:41.976] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:42.278] <TB1> INFO: Expecting 2560 events.
[14:31:43.132] <TB1> INFO: 2560 events read in total (297ms).
[14:31:43.133] <TB1> INFO: Test took 1157ms.
[14:31:43.134] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:43.438] <TB1> INFO: Expecting 2560 events.
[14:31:44.292] <TB1> INFO: 2560 events read in total (298ms).
[14:31:44.292] <TB1> INFO: Test took 1158ms.
[14:31:44.296] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:44.597] <TB1> INFO: Expecting 2560 events.
[14:31:45.452] <TB1> INFO: 2560 events read in total (299ms).
[14:31:45.452] <TB1> INFO: Test took 1156ms.
[14:31:45.454] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:45.758] <TB1> INFO: Expecting 2560 events.
[14:31:46.616] <TB1> INFO: 2560 events read in total (302ms).
[14:31:46.616] <TB1> INFO: Test took 1163ms.
[14:31:46.618] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:46.922] <TB1> INFO: Expecting 2560 events.
[14:31:47.776] <TB1> INFO: 2560 events read in total (298ms).
[14:31:47.776] <TB1> INFO: Test took 1158ms.
[14:31:47.778] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:48.081] <TB1> INFO: Expecting 2560 events.
[14:31:48.936] <TB1> INFO: 2560 events read in total (298ms).
[14:31:48.936] <TB1> INFO: Test took 1159ms.
[14:31:48.939] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:49.242] <TB1> INFO: Expecting 2560 events.
[14:31:50.098] <TB1> INFO: 2560 events read in total (299ms).
[14:31:50.099] <TB1> INFO: Test took 1160ms.
[14:31:50.101] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:50.405] <TB1> INFO: Expecting 2560 events.
[14:31:51.260] <TB1> INFO: 2560 events read in total (299ms).
[14:31:51.260] <TB1> INFO: Test took 1159ms.
[14:31:51.262] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:51.566] <TB1> INFO: Expecting 2560 events.
[14:31:52.421] <TB1> INFO: 2560 events read in total (299ms).
[14:31:52.421] <TB1> INFO: Test took 1159ms.
[14:31:52.424] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:52.727] <TB1> INFO: Expecting 2560 events.
[14:31:53.587] <TB1> INFO: 2560 events read in total (304ms).
[14:31:53.588] <TB1> INFO: Test took 1164ms.
[14:31:53.591] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:53.893] <TB1> INFO: Expecting 2560 events.
[14:31:54.746] <TB1> INFO: 2560 events read in total (297ms).
[14:31:54.746] <TB1> INFO: Test took 1156ms.
[14:31:54.748] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:55.052] <TB1> INFO: Expecting 2560 events.
[14:31:55.909] <TB1> INFO: 2560 events read in total (301ms).
[14:31:55.909] <TB1> INFO: Test took 1161ms.
[14:31:55.912] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:56.214] <TB1> INFO: Expecting 2560 events.
[14:31:57.072] <TB1> INFO: 2560 events read in total (301ms).
[14:31:57.072] <TB1> INFO: Test took 1160ms.
[14:31:57.075] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:57.378] <TB1> INFO: Expecting 2560 events.
[14:31:58.231] <TB1> INFO: 2560 events read in total (297ms).
[14:31:58.232] <TB1> INFO: Test took 1158ms.
[14:31:58.234] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:58.537] <TB1> INFO: Expecting 2560 events.
[14:31:59.390] <TB1> INFO: 2560 events read in total (297ms).
[14:31:59.390] <TB1> INFO: Test took 1157ms.
[14:31:59.392] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:31:59.696] <TB1> INFO: Expecting 2560 events.
[14:32:00.549] <TB1> INFO: 2560 events read in total (297ms).
[14:32:00.550] <TB1> INFO: Test took 1158ms.
[14:32:00.552] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:32:00.856] <TB1> INFO: Expecting 2560 events.
[14:32:01.708] <TB1> INFO: 2560 events read in total (296ms).
[14:32:01.708] <TB1> INFO: Test took 1157ms.
[14:32:01.711] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:32:02.014] <TB1> INFO: Expecting 2560 events.
[14:32:02.868] <TB1> INFO: 2560 events read in total (298ms).
[14:32:02.868] <TB1> INFO: Test took 1157ms.
[14:32:03.327] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 617 seconds
[14:32:03.327] <TB1> INFO: PH scale (per ROC): 70 70 83 72 83 80 77 80 86 94 83 82 80 80 79 75
[14:32:03.327] <TB1> INFO: PH offset (per ROC): 175 160 158 169 150 171 156 172 161 161 165 157 154 154 164 176
[14:32:03.332] <TB1> INFO: Decoding statistics:
[14:32:03.332] <TB1> INFO: General information:
[14:32:03.332] <TB1> INFO: 16bit words read: 91924
[14:32:03.332] <TB1> INFO: valid events total: 10240
[14:32:03.332] <TB1> INFO: empty events: 7739
[14:32:03.332] <TB1> INFO: valid events with pixels: 2501
[14:32:03.332] <TB1> INFO: valid pixel hits: 2501
[14:32:03.332] <TB1> INFO: Event errors: 0
[14:32:03.332] <TB1> INFO: start marker: 0
[14:32:03.332] <TB1> INFO: stop marker: 0
[14:32:03.332] <TB1> INFO: overflow: 0
[14:32:03.332] <TB1> INFO: invalid 5bit words: 0
[14:32:03.332] <TB1> INFO: invalid XOR eye diagram: 0
[14:32:03.332] <TB1> INFO: frame (failed synchr.): 0
[14:32:03.332] <TB1> INFO: idle data (no TBM trl): 0
[14:32:03.332] <TB1> INFO: no data (only TBM hdr): 0
[14:32:03.332] <TB1> INFO: TBM errors: 0
[14:32:03.332] <TB1> INFO: flawed TBM headers: 0
[14:32:03.332] <TB1> INFO: flawed TBM trailers: 0
[14:32:03.332] <TB1> INFO: event ID mismatches: 0
[14:32:03.332] <TB1> INFO: ROC errors: 0
[14:32:03.332] <TB1> INFO: missing ROC header(s): 0
[14:32:03.332] <TB1> INFO: misplaced readback start: 0
[14:32:03.332] <TB1> INFO: Pixel decoding errors: 0
[14:32:03.332] <TB1> INFO: pixel data incomplete: 0
[14:32:03.332] <TB1> INFO: pixel address: 0
[14:32:03.332] <TB1> INFO: pulse height fill bit: 0
[14:32:03.332] <TB1> INFO: buffer corruption: 0
[14:32:03.532] <TB1> INFO: ######################################################################
[14:32:03.532] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[14:32:03.532] <TB1> INFO: ######################################################################
[14:32:03.542] <TB1> INFO: scanning low vcal = 10
[14:32:03.820] <TB1> INFO: Expecting 41600 events.
[14:32:07.377] <TB1> INFO: 41600 events read in total (3000ms).
[14:32:07.377] <TB1> INFO: Test took 3835ms.
[14:32:07.379] <TB1> INFO: scanning low vcal = 20
[14:32:07.681] <TB1> INFO: Expecting 41600 events.
[14:32:11.239] <TB1> INFO: 41600 events read in total (3002ms).
[14:32:11.239] <TB1> INFO: Test took 3860ms.
[14:32:11.242] <TB1> INFO: scanning low vcal = 30
[14:32:11.544] <TB1> INFO: Expecting 41600 events.
[14:32:15.094] <TB1> INFO: 41600 events read in total (2994ms).
[14:32:15.095] <TB1> INFO: Test took 3853ms.
[14:32:15.096] <TB1> INFO: scanning low vcal = 40
[14:32:15.395] <TB1> INFO: Expecting 41600 events.
[14:32:19.547] <TB1> INFO: 41600 events read in total (3596ms).
[14:32:19.548] <TB1> INFO: Test took 4452ms.
[14:32:19.551] <TB1> INFO: scanning low vcal = 50
[14:32:19.823] <TB1> INFO: Expecting 41600 events.
[14:32:24.068] <TB1> INFO: 41600 events read in total (3689ms).
[14:32:24.069] <TB1> INFO: Test took 4518ms.
[14:32:24.072] <TB1> INFO: scanning low vcal = 60
[14:32:24.365] <TB1> INFO: Expecting 41600 events.
[14:32:28.573] <TB1> INFO: 41600 events read in total (3652ms).
[14:32:28.574] <TB1> INFO: Test took 4501ms.
[14:32:28.577] <TB1> INFO: scanning low vcal = 70
[14:32:28.840] <TB1> INFO: Expecting 41600 events.
[14:32:33.044] <TB1> INFO: 41600 events read in total (3648ms).
[14:32:33.045] <TB1> INFO: Test took 4468ms.
[14:32:33.084] <TB1> INFO: scanning low vcal = 80
[14:32:33.343] <TB1> INFO: Expecting 41600 events.
[14:32:37.532] <TB1> INFO: 41600 events read in total (3633ms).
[14:32:37.533] <TB1> INFO: Test took 4449ms.
[14:32:37.535] <TB1> INFO: scanning low vcal = 90
[14:32:37.799] <TB1> INFO: Expecting 41600 events.
[14:32:42.003] <TB1> INFO: 41600 events read in total (3648ms).
[14:32:42.003] <TB1> INFO: Test took 4468ms.
[14:32:42.006] <TB1> INFO: scanning low vcal = 100
[14:32:42.270] <TB1> INFO: Expecting 41600 events.
[14:32:46.540] <TB1> INFO: 41600 events read in total (3714ms).
[14:32:46.540] <TB1> INFO: Test took 4534ms.
[14:32:46.543] <TB1> INFO: scanning low vcal = 110
[14:32:46.820] <TB1> INFO: Expecting 41600 events.
[14:32:50.999] <TB1> INFO: 41600 events read in total (3623ms).
[14:32:50.999] <TB1> INFO: Test took 4456ms.
[14:32:51.002] <TB1> INFO: scanning low vcal = 120
[14:32:51.276] <TB1> INFO: Expecting 41600 events.
[14:32:55.474] <TB1> INFO: 41600 events read in total (3642ms).
[14:32:55.475] <TB1> INFO: Test took 4473ms.
[14:32:55.477] <TB1> INFO: scanning low vcal = 130
[14:32:55.749] <TB1> INFO: Expecting 41600 events.
[14:33:00.012] <TB1> INFO: 41600 events read in total (3706ms).
[14:33:00.013] <TB1> INFO: Test took 4536ms.
[14:33:00.016] <TB1> INFO: scanning low vcal = 140
[14:33:00.286] <TB1> INFO: Expecting 41600 events.
[14:33:04.541] <TB1> INFO: 41600 events read in total (3698ms).
[14:33:04.542] <TB1> INFO: Test took 4526ms.
[14:33:04.544] <TB1> INFO: scanning low vcal = 150
[14:33:04.838] <TB1> INFO: Expecting 41600 events.
[14:33:09.094] <TB1> INFO: 41600 events read in total (3700ms).
[14:33:09.095] <TB1> INFO: Test took 4550ms.
[14:33:09.097] <TB1> INFO: scanning low vcal = 160
[14:33:09.369] <TB1> INFO: Expecting 41600 events.
[14:33:13.643] <TB1> INFO: 41600 events read in total (3717ms).
[14:33:13.644] <TB1> INFO: Test took 4547ms.
[14:33:13.647] <TB1> INFO: scanning low vcal = 170
[14:33:13.944] <TB1> INFO: Expecting 41600 events.
[14:33:18.206] <TB1> INFO: 41600 events read in total (3706ms).
[14:33:18.207] <TB1> INFO: Test took 4559ms.
[14:33:18.213] <TB1> INFO: scanning low vcal = 180
[14:33:18.497] <TB1> INFO: Expecting 41600 events.
[14:33:22.755] <TB1> INFO: 41600 events read in total (3702ms).
[14:33:22.756] <TB1> INFO: Test took 4543ms.
[14:33:22.759] <TB1> INFO: scanning low vcal = 190
[14:33:23.023] <TB1> INFO: Expecting 41600 events.
[14:33:27.284] <TB1> INFO: 41600 events read in total (3705ms).
[14:33:27.285] <TB1> INFO: Test took 4526ms.
[14:33:27.288] <TB1> INFO: scanning low vcal = 200
[14:33:27.585] <TB1> INFO: Expecting 41600 events.
[14:33:31.855] <TB1> INFO: 41600 events read in total (3714ms).
[14:33:31.856] <TB1> INFO: Test took 4568ms.
[14:33:31.859] <TB1> INFO: scanning low vcal = 210
[14:33:32.131] <TB1> INFO: Expecting 41600 events.
[14:33:36.354] <TB1> INFO: 41600 events read in total (3667ms).
[14:33:36.354] <TB1> INFO: Test took 4495ms.
[14:33:36.357] <TB1> INFO: scanning low vcal = 220
[14:33:36.622] <TB1> INFO: Expecting 41600 events.
[14:33:40.833] <TB1> INFO: 41600 events read in total (3655ms).
[14:33:40.834] <TB1> INFO: Test took 4477ms.
[14:33:40.836] <TB1> INFO: scanning low vcal = 230
[14:33:41.100] <TB1> INFO: Expecting 41600 events.
[14:33:45.342] <TB1> INFO: 41600 events read in total (3686ms).
[14:33:45.344] <TB1> INFO: Test took 4507ms.
[14:33:45.347] <TB1> INFO: scanning low vcal = 240
[14:33:45.627] <TB1> INFO: Expecting 41600 events.
[14:33:49.882] <TB1> INFO: 41600 events read in total (3697ms).
[14:33:49.883] <TB1> INFO: Test took 4536ms.
[14:33:49.887] <TB1> INFO: scanning low vcal = 250
[14:33:50.154] <TB1> INFO: Expecting 41600 events.
[14:33:54.375] <TB1> INFO: 41600 events read in total (3665ms).
[14:33:54.376] <TB1> INFO: Test took 4489ms.
[14:33:54.380] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[14:33:54.646] <TB1> INFO: Expecting 41600 events.
[14:33:58.857] <TB1> INFO: 41600 events read in total (3654ms).
[14:33:58.858] <TB1> INFO: Test took 4478ms.
[14:33:58.861] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[14:33:59.144] <TB1> INFO: Expecting 41600 events.
[14:34:03.319] <TB1> INFO: 41600 events read in total (3618ms).
[14:34:03.320] <TB1> INFO: Test took 4459ms.
[14:34:03.323] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[14:34:03.587] <TB1> INFO: Expecting 41600 events.
[14:34:07.775] <TB1> INFO: 41600 events read in total (3632ms).
[14:34:07.775] <TB1> INFO: Test took 4452ms.
[14:34:07.779] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[14:34:08.045] <TB1> INFO: Expecting 41600 events.
[14:34:12.218] <TB1> INFO: 41600 events read in total (3617ms).
[14:34:12.218] <TB1> INFO: Test took 4439ms.
[14:34:12.221] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[14:34:12.501] <TB1> INFO: Expecting 41600 events.
[14:34:16.691] <TB1> INFO: 41600 events read in total (3630ms).
[14:34:16.692] <TB1> INFO: Test took 4470ms.
[14:34:17.256] <TB1> INFO: PixTestGainPedestal::measure() done
[14:34:49.749] <TB1> INFO: PixTestGainPedestal::fit() done
[14:34:49.749] <TB1> INFO: non-linearity mean: 0.955 0.963 0.947 0.957 0.959 0.965 0.951 0.964 0.945 0.953 0.957 0.957 0.961 0.959 0.955 0.969
[14:34:49.749] <TB1> INFO: non-linearity RMS: 0.007 0.005 0.006 0.005 0.005 0.004 0.007 0.006 0.006 0.006 0.005 0.006 0.006 0.005 0.005 0.003
[14:34:49.750] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[14:34:49.771] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[14:34:49.789] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[14:34:49.808] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[14:34:49.826] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[14:34:49.844] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[14:34:49.863] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[14:34:49.885] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[14:34:49.903] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[14:34:49.924] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[14:34:49.944] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[14:34:49.962] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[14:34:49.979] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[14:34:49.998] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[14:34:50.016] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[14:34:50.035] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[14:34:50.058] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 166 seconds
[14:34:50.058] <TB1> INFO: Decoding statistics:
[14:34:50.058] <TB1> INFO: General information:
[14:34:50.058] <TB1> INFO: 16bit words read: 2662400
[14:34:50.058] <TB1> INFO: valid events total: 166400
[14:34:50.058] <TB1> INFO: empty events: 0
[14:34:50.058] <TB1> INFO: valid events with pixels: 166400
[14:34:50.058] <TB1> INFO: valid pixel hits: 665580
[14:34:50.058] <TB1> INFO: Event errors: 0
[14:34:50.058] <TB1> INFO: start marker: 0
[14:34:50.058] <TB1> INFO: stop marker: 0
[14:34:50.058] <TB1> INFO: overflow: 0
[14:34:50.058] <TB1> INFO: invalid 5bit words: 0
[14:34:50.058] <TB1> INFO: invalid XOR eye diagram: 0
[14:34:50.058] <TB1> INFO: frame (failed synchr.): 0
[14:34:50.058] <TB1> INFO: idle data (no TBM trl): 0
[14:34:50.058] <TB1> INFO: no data (only TBM hdr): 0
[14:34:50.058] <TB1> INFO: TBM errors: 0
[14:34:50.058] <TB1> INFO: flawed TBM headers: 0
[14:34:50.058] <TB1> INFO: flawed TBM trailers: 0
[14:34:50.058] <TB1> INFO: event ID mismatches: 0
[14:34:50.058] <TB1> INFO: ROC errors: 0
[14:34:50.058] <TB1> INFO: missing ROC header(s): 0
[14:34:50.058] <TB1> INFO: misplaced readback start: 0
[14:34:50.058] <TB1> INFO: Pixel decoding errors: 0
[14:34:50.058] <TB1> INFO: pixel data incomplete: 0
[14:34:50.058] <TB1> INFO: pixel address: 0
[14:34:50.058] <TB1> INFO: pulse height fill bit: 0
[14:34:50.058] <TB1> INFO: buffer corruption: 0
[14:34:50.068] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C15.dat
[14:34:50.069] <TB1> INFO: ######################################################################
[14:34:50.069] <TB1> INFO: PixTestReadback::doTest()
[14:34:50.069] <TB1> INFO: ######################################################################
[14:34:50.069] <TB1> INFO: ----------------------------------------------------------------------
[14:34:50.069] <TB1> INFO: PixTestReadback::CalibrateVd()
[14:34:50.069] <TB1> INFO: ----------------------------------------------------------------------
[14:34:59.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C0.dat
[14:34:59.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C1.dat
[14:34:59.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C2.dat
[14:34:59.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C3.dat
[14:34:59.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C4.dat
[14:34:59.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C5.dat
[14:34:59.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C6.dat
[14:34:59.569] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C7.dat
[14:34:59.570] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C8.dat
[14:34:59.570] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C9.dat
[14:34:59.570] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C10.dat
[14:34:59.570] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C11.dat
[14:34:59.570] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C12.dat
[14:34:59.570] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C13.dat
[14:34:59.570] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C14.dat
[14:34:59.570] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C15.dat
[14:34:59.598] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:34:59.598] <TB1> INFO: ----------------------------------------------------------------------
[14:34:59.598] <TB1> INFO: PixTestReadback::CalibrateVa()
[14:34:59.598] <TB1> INFO: ----------------------------------------------------------------------
[14:35:09.080] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C0.dat
[14:35:09.080] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C1.dat
[14:35:09.080] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C2.dat
[14:35:09.080] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C3.dat
[14:35:09.080] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C4.dat
[14:35:09.080] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C5.dat
[14:35:09.080] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C6.dat
[14:35:09.080] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C7.dat
[14:35:09.080] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C8.dat
[14:35:09.080] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C9.dat
[14:35:09.080] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C10.dat
[14:35:09.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C11.dat
[14:35:09.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C12.dat
[14:35:09.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C13.dat
[14:35:09.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C14.dat
[14:35:09.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C15.dat
[14:35:09.111] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:35:09.111] <TB1> INFO: ----------------------------------------------------------------------
[14:35:09.111] <TB1> INFO: PixTestReadback::readbackVbg()
[14:35:09.111] <TB1> INFO: ----------------------------------------------------------------------
[14:35:16.408] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:35:16.408] <TB1> INFO: ----------------------------------------------------------------------
[14:35:16.408] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[14:35:16.408] <TB1> INFO: ----------------------------------------------------------------------
[14:35:16.408] <TB1> INFO: Vbg will be calibrated using Vd calibration
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151.3calibrated Vbg = 1.17218 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 155.7calibrated Vbg = 1.17658 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 159calibrated Vbg = 1.17888 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 157.1calibrated Vbg = 1.18497 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 152calibrated Vbg = 1.18714 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 154.9calibrated Vbg = 1.18766 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 149.2calibrated Vbg = 1.18612 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 151calibrated Vbg = 1.18901 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.3calibrated Vbg = 1.19271 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 149.6calibrated Vbg = 1.18541 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 144.1calibrated Vbg = 1.18634 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 153calibrated Vbg = 1.18512 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 156.5calibrated Vbg = 1.18191 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154.4calibrated Vbg = 1.18297 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 147.2calibrated Vbg = 1.18831 :::*/*/*/*/
[14:35:16.409] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 154.7calibrated Vbg = 1.19139 :::*/*/*/*/
[14:35:16.413] <TB1> INFO: ----------------------------------------------------------------------
[14:35:16.413] <TB1> INFO: PixTestReadback::CalibrateIa()
[14:35:16.413] <TB1> INFO: ----------------------------------------------------------------------
[14:37:51.733] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C0.dat
[14:37:51.734] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C1.dat
[14:37:51.734] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C2.dat
[14:37:51.734] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C3.dat
[14:37:51.734] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C4.dat
[14:37:51.734] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C5.dat
[14:37:51.734] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C6.dat
[14:37:51.735] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C7.dat
[14:37:51.735] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C8.dat
[14:37:51.735] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C9.dat
[14:37:51.735] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C10.dat
[14:37:51.735] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C11.dat
[14:37:51.735] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C12.dat
[14:37:51.735] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C13.dat
[14:37:51.735] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C14.dat
[14:37:51.735] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//002_FulltestPxar_m20//readbackCal_C15.dat
[14:37:51.763] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:37:51.765] <TB1> INFO: PixTestReadback::doTest() done
[14:37:51.765] <TB1> INFO: Decoding statistics:
[14:37:51.765] <TB1> INFO: General information:
[14:37:51.765] <TB1> INFO: 16bit words read: 1024
[14:37:51.765] <TB1> INFO: valid events total: 128
[14:37:51.765] <TB1> INFO: empty events: 128
[14:37:51.765] <TB1> INFO: valid events with pixels: 0
[14:37:51.765] <TB1> INFO: valid pixel hits: 0
[14:37:51.765] <TB1> INFO: Event errors: 0
[14:37:51.765] <TB1> INFO: start marker: 0
[14:37:51.765] <TB1> INFO: stop marker: 0
[14:37:51.765] <TB1> INFO: overflow: 0
[14:37:51.765] <TB1> INFO: invalid 5bit words: 0
[14:37:51.765] <TB1> INFO: invalid XOR eye diagram: 0
[14:37:51.765] <TB1> INFO: frame (failed synchr.): 0
[14:37:51.765] <TB1> INFO: idle data (no TBM trl): 0
[14:37:51.765] <TB1> INFO: no data (only TBM hdr): 0
[14:37:51.765] <TB1> INFO: TBM errors: 0
[14:37:51.765] <TB1> INFO: flawed TBM headers: 0
[14:37:51.765] <TB1> INFO: flawed TBM trailers: 0
[14:37:51.765] <TB1> INFO: event ID mismatches: 0
[14:37:51.765] <TB1> INFO: ROC errors: 0
[14:37:51.765] <TB1> INFO: missing ROC header(s): 0
[14:37:51.765] <TB1> INFO: misplaced readback start: 0
[14:37:51.765] <TB1> INFO: Pixel decoding errors: 0
[14:37:51.765] <TB1> INFO: pixel data incomplete: 0
[14:37:51.765] <TB1> INFO: pixel address: 0
[14:37:51.765] <TB1> INFO: pulse height fill bit: 0
[14:37:51.765] <TB1> INFO: buffer corruption: 0
[14:37:51.786] <TB1> INFO: Decoding statistics:
[14:37:51.786] <TB1> INFO: General information:
[14:37:51.786] <TB1> INFO: 16bit words read: 2755348
[14:37:51.786] <TB1> INFO: valid events total: 176768
[14:37:51.786] <TB1> INFO: empty events: 7867
[14:37:51.786] <TB1> INFO: valid events with pixels: 168901
[14:37:51.786] <TB1> INFO: valid pixel hits: 668081
[14:37:51.786] <TB1> INFO: Event errors: 0
[14:37:51.786] <TB1> INFO: start marker: 0
[14:37:51.786] <TB1> INFO: stop marker: 0
[14:37:51.786] <TB1> INFO: overflow: 0
[14:37:51.786] <TB1> INFO: invalid 5bit words: 0
[14:37:51.786] <TB1> INFO: invalid XOR eye diagram: 0
[14:37:51.786] <TB1> INFO: frame (failed synchr.): 0
[14:37:51.786] <TB1> INFO: idle data (no TBM trl): 0
[14:37:51.786] <TB1> INFO: no data (only TBM hdr): 0
[14:37:51.786] <TB1> INFO: TBM errors: 0
[14:37:51.786] <TB1> INFO: flawed TBM headers: 0
[14:37:51.786] <TB1> INFO: flawed TBM trailers: 0
[14:37:51.786] <TB1> INFO: event ID mismatches: 0
[14:37:51.786] <TB1> INFO: ROC errors: 0
[14:37:51.786] <TB1> INFO: missing ROC header(s): 0
[14:37:51.786] <TB1> INFO: misplaced readback start: 0
[14:37:51.786] <TB1> INFO: Pixel decoding errors: 0
[14:37:51.786] <TB1> INFO: pixel data incomplete: 0
[14:37:51.786] <TB1> INFO: pixel address: 0
[14:37:51.786] <TB1> INFO: pulse height fill bit: 0
[14:37:51.786] <TB1> INFO: buffer corruption: 0
[14:37:51.786] <TB1> INFO: enter test to run
[14:37:51.786] <TB1> INFO: test: exit no parameter change
[14:37:51.937] <TB1> QUIET: Connection to board 153 closed.
[14:37:52.016] <TB1> INFO: pXar: this is the end, my friend