[10:46:20.903] <TB1> INFO: *** Welcome to pxar ***
[10:46:20.903] <TB1> INFO: *** Today: 2016/10/04
[10:46:21.029] <TB1> INFO: *** Version: 0b00-dirty
[10:46:21.029] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C15.dat
[10:46:21.030] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//tbmParameters_C0b.dat
[10:46:21.031] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//defaultMaskFile.dat
[10:46:21.031] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters_C15.dat
[10:46:21.097] <TB1> INFO: clk: 4
[10:46:21.097] <TB1> INFO: ctr: 4
[10:46:21.097] <TB1> INFO: sda: 19
[10:46:21.097] <TB1> INFO: tin: 9
[10:46:21.097] <TB1> INFO: level: 15
[10:46:21.097] <TB1> INFO: triggerdelay: 0
[10:46:21.097] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[10:46:21.097] <TB1> INFO: Log level: INFO
[10:46:21.106] <TB1> INFO: Found DTB DTB_WXBYFL
[10:46:21.119] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[10:46:21.121] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[10:46:21.123] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[10:46:22.655] <TB1> INFO: DUT info:
[10:46:22.655] <TB1> INFO: The DUT currently contains the following objects:
[10:46:22.655] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:46:22.655] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:46:22.655] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:46:22.656] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:46:22.656] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:22.656] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:46:23.057] <TB1> INFO: enter 'restricted' command line mode
[10:46:23.057] <TB1> INFO: enter test to run
[10:46:23.057] <TB1> INFO: test: pretest no parameter change
[10:46:23.057] <TB1> INFO: running: pretest
[10:46:23.064] <TB1> INFO: ----------------------------------------------------------------------
[10:46:23.064] <TB1> INFO: PixTestPretest::programROC()
[10:46:23.064] <TB1> INFO: ----------------------------------------------------------------------
[10:46:41.079] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:46:41.079] <TB1> INFO: IA differences per ROC: 18.5 19.3 19.3 17.7 20.1 20.9 19.3 20.9 19.3 19.3 19.3 17.7 18.5 17.7 19.3 16.9
[10:46:41.180] <TB1> INFO: enter test to run
[10:46:41.180] <TB1> INFO: test: pretest no parameter change
[10:46:41.180] <TB1> INFO: running: pretest
[10:46:41.181] <TB1> INFO: ----------------------------------------------------------------------
[10:46:41.181] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:46:41.181] <TB1> INFO: ----------------------------------------------------------------------
[10:46:48.376] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 390.7 mA = 24.4187 mA/ROC
[10:46:48.376] <TB1> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 18.5 19.3 18.5
[10:46:48.414] <TB1> INFO: enter test to run
[10:46:48.415] <TB1> INFO: test: pretest no parameter change
[10:46:48.415] <TB1> INFO: running: pretest
[10:46:48.415] <TB1> INFO: ----------------------------------------------------------------------
[10:46:48.415] <TB1> INFO: PixTestPretest::findTiming()
[10:46:48.415] <TB1> INFO: ----------------------------------------------------------------------
[10:46:48.415] <TB1> INFO: PixTestCmd::init()
[10:46:49.247] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[10:48:19.809] <TB1> INFO: TBM phases: 160MHz: 6, 400MHz: 2, TBM delays: ROC(0/1):3, header/trailer: 1, token: 1
[10:48:19.809] <TB1> INFO: (success/tries = 100/100), width = 5
[10:48:19.809] <TB1> INFO: enter test to run
[10:48:19.809] <TB1> INFO: test: pretest no parameter change
[10:48:19.809] <TB1> INFO: running: pretest
[10:48:19.811] <TB1> INFO: ----------------------------------------------------------------------
[10:48:19.811] <TB1> INFO: PixTestPretest::findWorkingPixel()
[10:48:19.811] <TB1> INFO: ----------------------------------------------------------------------
[10:48:19.902] <TB1> INFO: Expecting 231680 events.
[10:48:25.500] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[10:48:25.628] <TB1> ERROR: <datapipe.cc/CheckEventID:L485> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[10:48:29.178] <TB1> INFO: 231680 events read in total (8720ms).
[10:48:29.184] <TB1> INFO: Test took 9370ms.
[10:48:29.394] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:48:29.460] <TB1> INFO: enter test to run
[10:48:29.461] <TB1> INFO: test: pretest no parameter change
[10:48:29.461] <TB1> INFO: running: pretest
[10:48:29.462] <TB1> INFO: ----------------------------------------------------------------------
[10:48:29.462] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[10:48:29.462] <TB1> INFO: ----------------------------------------------------------------------
[10:48:29.554] <TB1> INFO: Expecting 231680 events.
[10:48:38.829] <TB1> INFO: 231680 events read in total (8719ms).
[10:48:38.835] <TB1> INFO: Test took 9370ms.
[10:48:39.052] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[10:48:39.052] <TB1> INFO: CalDel: 149 142 143 139 124 140 133 134 135 149 131 160 130 137 130 132
[10:48:39.052] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:48:39.088] <TB1> INFO: enter test to run
[10:48:39.088] <TB1> INFO: test: pretest no parameter change
[10:48:39.088] <TB1> INFO: running: pretest
[10:48:39.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C0.dat
[10:48:39.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C1.dat
[10:48:39.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C2.dat
[10:48:39.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C3.dat
[10:48:39.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C4.dat
[10:48:39.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C5.dat
[10:48:39.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C6.dat
[10:48:39.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C7.dat
[10:48:39.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C8.dat
[10:48:39.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C9.dat
[10:48:39.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C10.dat
[10:48:39.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C11.dat
[10:48:39.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C12.dat
[10:48:39.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C13.dat
[10:48:39.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C14.dat
[10:48:39.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters_C15.dat
[10:48:39.090] <TB1> INFO: enter test to run
[10:48:39.090] <TB1> INFO: test: fulltest no parameter change
[10:48:39.090] <TB1> INFO: running: fulltest
[10:48:39.090] <TB1> INFO: ######################################################################
[10:48:39.090] <TB1> INFO: PixTestFullTest::doTest()
[10:48:39.090] <TB1> INFO: ######################################################################
[10:48:39.091] <TB1> INFO: ######################################################################
[10:48:39.092] <TB1> INFO: PixTestAlive::doTest()
[10:48:39.092] <TB1> INFO: ######################################################################
[10:48:39.093] <TB1> INFO: ----------------------------------------------------------------------
[10:48:39.093] <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)
[10:48:39.093] <TB1> INFO: ----------------------------------------------------------------------
[10:48:39.355] <TB1> INFO: Expecting 41600 events.
[10:48:42.808] <TB1> INFO: 41600 events read in total (2896ms).
[10:48:42.809] <TB1> INFO: Test took 3715ms.
[10:48:43.029] <TB1> INFO: PixTestAlive::aliveTest() done
[10:48:43.029] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 4 0 0
[10:48:43.031] <TB1> INFO: ----------------------------------------------------------------------
[10:48:43.031] <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)
[10:48:43.031] <TB1> INFO: ----------------------------------------------------------------------
[10:48:43.311] <TB1> INFO: Expecting 41600 events.
[10:48:46.032] <TB1> INFO: 41600 events read in total (2164ms).
[10:48:46.033] <TB1> INFO: Test took 3000ms.
[10:48:46.033] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:48:46.270] <TB1> INFO: PixTestAlive::maskTest() done
[10:48:46.270] <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
[10:48:46.271] <TB1> INFO: ----------------------------------------------------------------------
[10:48:46.271] <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)
[10:48:46.271] <TB1> INFO: ----------------------------------------------------------------------
[10:48:46.547] <TB1> INFO: Expecting 41600 events.
[10:48:49.990] <TB1> INFO: 41600 events read in total (2887ms).
[10:48:49.991] <TB1> INFO: Test took 3718ms.
[10:48:50.212] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[10:48:50.212] <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
[10:48:50.212] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[10:48:50.212] <TB1> INFO: Decoding statistics:
[10:48:50.212] <TB1> INFO: General information:
[10:48:50.212] <TB1> INFO: 16bit words read: 0
[10:48:50.212] <TB1> INFO: valid events total: 0
[10:48:50.212] <TB1> INFO: empty events: 0
[10:48:50.212] <TB1> INFO: valid events with pixels: 0
[10:48:50.212] <TB1> INFO: valid pixel hits: 0
[10:48:50.212] <TB1> INFO: Event errors: 0
[10:48:50.212] <TB1> INFO: start marker: 0
[10:48:50.212] <TB1> INFO: stop marker: 0
[10:48:50.212] <TB1> INFO: overflow: 0
[10:48:50.212] <TB1> INFO: invalid 5bit words: 0
[10:48:50.212] <TB1> INFO: invalid XOR eye diagram: 0
[10:48:50.212] <TB1> INFO: frame (failed synchr.): 0
[10:48:50.212] <TB1> INFO: idle data (no TBM trl): 0
[10:48:50.212] <TB1> INFO: no data (only TBM hdr): 0
[10:48:50.212] <TB1> INFO: TBM errors: 0
[10:48:50.212] <TB1> INFO: flawed TBM headers: 0
[10:48:50.212] <TB1> INFO: flawed TBM trailers: 0
[10:48:50.213] <TB1> INFO: event ID mismatches: 0
[10:48:50.213] <TB1> INFO: ROC errors: 0
[10:48:50.213] <TB1> INFO: missing ROC header(s): 0
[10:48:50.213] <TB1> INFO: misplaced readback start: 0
[10:48:50.213] <TB1> INFO: Pixel decoding errors: 0
[10:48:50.213] <TB1> INFO: pixel data incomplete: 0
[10:48:50.213] <TB1> INFO: pixel address: 0
[10:48:50.213] <TB1> INFO: pulse height fill bit: 0
[10:48:50.213] <TB1> INFO: buffer corruption: 0
[10:48:50.224] <TB1> INFO: ######################################################################
[10:48:50.224] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:48:50.224] <TB1> INFO: ######################################################################
[10:48:50.227] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:48:50.239] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:48:50.239] <TB1> INFO: run 1 of 1
[10:48:50.504] <TB1> INFO: Expecting 3120000 events.
[10:49:28.663] <TB1> INFO: 882495 events read in total (37603ms).
[10:50:05.732] <TB1> INFO: 1748915 events read in total (74672ms).
[10:50:42.941] <TB1> INFO: 2628110 events read in total (111881ms).
[10:51:04.142] <TB1> INFO: 3120000 events read in total (133082ms).
[10:51:04.213] <TB1> INFO: Test took 133974ms.
[10:51:30.026] <TB1> INFO: PixTestBBMap::doTest() done, duration: 159 seconds
[10:51:30.026] <TB1> INFO: number of dead bumps (per ROC): 0 0 1 1 2 1 0 0 4 0 1 0 3 2 0 2
[10:51:30.026] <TB1> INFO: separation cut (per ROC): 87 86 103 92 90 103 72 101 91 92 90 85 92 85 87 98
[10:51:30.026] <TB1> INFO: Decoding statistics:
[10:51:30.026] <TB1> INFO: General information:
[10:51:30.026] <TB1> INFO: 16bit words read: 0
[10:51:30.026] <TB1> INFO: valid events total: 0
[10:51:30.026] <TB1> INFO: empty events: 0
[10:51:30.026] <TB1> INFO: valid events with pixels: 0
[10:51:30.026] <TB1> INFO: valid pixel hits: 0
[10:51:30.026] <TB1> INFO: Event errors: 0
[10:51:30.026] <TB1> INFO: start marker: 0
[10:51:30.026] <TB1> INFO: stop marker: 0
[10:51:30.026] <TB1> INFO: overflow: 0
[10:51:30.026] <TB1> INFO: invalid 5bit words: 0
[10:51:30.026] <TB1> INFO: invalid XOR eye diagram: 0
[10:51:30.026] <TB1> INFO: frame (failed synchr.): 0
[10:51:30.026] <TB1> INFO: idle data (no TBM trl): 0
[10:51:30.026] <TB1> INFO: no data (only TBM hdr): 0
[10:51:30.026] <TB1> INFO: TBM errors: 0
[10:51:30.026] <TB1> INFO: flawed TBM headers: 0
[10:51:30.026] <TB1> INFO: flawed TBM trailers: 0
[10:51:30.026] <TB1> INFO: event ID mismatches: 0
[10:51:30.026] <TB1> INFO: ROC errors: 0
[10:51:30.026] <TB1> INFO: missing ROC header(s): 0
[10:51:30.026] <TB1> INFO: misplaced readback start: 0
[10:51:30.026] <TB1> INFO: Pixel decoding errors: 0
[10:51:30.026] <TB1> INFO: pixel data incomplete: 0
[10:51:30.026] <TB1> INFO: pixel address: 0
[10:51:30.026] <TB1> INFO: pulse height fill bit: 0
[10:51:30.026] <TB1> INFO: buffer corruption: 0
[10:51:30.100] <TB1> INFO: ######################################################################
[10:51:30.100] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:51:30.100] <TB1> INFO: ######################################################################
[10:51:30.100] <TB1> INFO: ----------------------------------------------------------------------
[10:51:30.100] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:51:30.100] <TB1> INFO: ----------------------------------------------------------------------
[10:51:30.100] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[10:51:30.109] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:51:30.109] <TB1> INFO: run 1 of 1
[10:51:30.374] <TB1> INFO: Expecting 36608000 events.
[10:52:01.083] <TB1> INFO: 848700 events read in total (30153ms).
[10:52:30.004] <TB1> INFO: 1679800 events read in total (59074ms).
[10:52:58.729] <TB1> INFO: 2512900 events read in total (87799ms).
[10:53:27.875] <TB1> INFO: 3343700 events read in total (116945ms).
[10:53:56.865] <TB1> INFO: 4175450 events read in total (145935ms).
[10:54:25.722] <TB1> INFO: 5005100 events read in total (174792ms).
[10:54:54.886] <TB1> INFO: 5833200 events read in total (203956ms).
[10:55:23.689] <TB1> INFO: 6660550 events read in total (232759ms).
[10:55:52.892] <TB1> INFO: 7487950 events read in total (261962ms).
[10:56:21.971] <TB1> INFO: 8315850 events read in total (291041ms).
[10:56:50.935] <TB1> INFO: 9144450 events read in total (320005ms).
[10:57:19.777] <TB1> INFO: 9969050 events read in total (348847ms).
[10:57:48.527] <TB1> INFO: 10795100 events read in total (377597ms).
[10:58:17.140] <TB1> INFO: 11620150 events read in total (406210ms).
[10:58:45.756] <TB1> INFO: 12445200 events read in total (434826ms).
[10:59:14.777] <TB1> INFO: 13270100 events read in total (463847ms).
[10:59:43.515] <TB1> INFO: 14094450 events read in total (492585ms).
[11:00:12.411] <TB1> INFO: 14919500 events read in total (521481ms).
[11:00:41.458] <TB1> INFO: 15740500 events read in total (550528ms).
[11:01:10.087] <TB1> INFO: 16561350 events read in total (579157ms).
[11:01:38.821] <TB1> INFO: 17383900 events read in total (607891ms).
[11:02:07.207] <TB1> INFO: 18202850 events read in total (636277ms).
[11:02:35.757] <TB1> INFO: 19018250 events read in total (664827ms).
[11:03:04.535] <TB1> INFO: 19832650 events read in total (693605ms).
[11:03:33.423] <TB1> INFO: 20645950 events read in total (722493ms).
[11:04:01.886] <TB1> INFO: 21458800 events read in total (750956ms).
[11:04:30.566] <TB1> INFO: 22271000 events read in total (779636ms).
[11:04:59.220] <TB1> INFO: 23083150 events read in total (808290ms).
[11:05:27.621] <TB1> INFO: 23896100 events read in total (836691ms).
[11:05:56.007] <TB1> INFO: 24707500 events read in total (865077ms).
[11:06:24.688] <TB1> INFO: 25517850 events read in total (893758ms).
[11:06:53.377] <TB1> INFO: 26328500 events read in total (922447ms).
[11:07:21.956] <TB1> INFO: 27137200 events read in total (951026ms).
[11:07:50.799] <TB1> INFO: 27947200 events read in total (979869ms).
[11:08:20.368] <TB1> INFO: 28757350 events read in total (1009438ms).
[11:08:50.061] <TB1> INFO: 29567250 events read in total (1039131ms).
[11:09:20.471] <TB1> INFO: 30377400 events read in total (1069541ms).
[11:09:49.982] <TB1> INFO: 31184600 events read in total (1099052ms).
[11:10:18.841] <TB1> INFO: 31993750 events read in total (1127911ms).
[11:10:47.379] <TB1> INFO: 32804000 events read in total (1156449ms).
[11:11:16.364] <TB1> INFO: 33612100 events read in total (1185434ms).
[11:11:44.814] <TB1> INFO: 34421550 events read in total (1213884ms).
[11:12:13.326] <TB1> INFO: 35230800 events read in total (1242396ms).
[11:12:42.192] <TB1> INFO: 36043900 events read in total (1271262ms).
[11:13:02.850] <TB1> INFO: 36608000 events read in total (1291920ms).
[11:13:02.906] <TB1> INFO: Test took 1292797ms.
[11:13:03.333] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:05.299] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:07.962] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:10.651] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:12.423] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:14.601] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:17.267] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:19.685] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:21.671] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:23.388] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:25.081] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:26.775] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:28.524] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:30.270] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:32.038] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:33.810] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:13:35.612] <TB1> INFO: PixTestScurves::scurves() done
[11:13:35.612] <TB1> INFO: Vcal mean: 91.56 82.77 108.51 101.03 94.49 105.02 83.22 95.57 85.23 97.69 89.19 90.62 92.77 90.93 93.02 97.18
[11:13:35.612] <TB1> INFO: Vcal RMS: 4.98 4.35 4.48 4.73 5.33 5.13 4.49 5.09 5.21 6.00 5.19 4.47 5.45 5.99 4.93 5.20
[11:13:35.612] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1325 seconds
[11:13:35.612] <TB1> INFO: Decoding statistics:
[11:13:35.612] <TB1> INFO: General information:
[11:13:35.612] <TB1> INFO: 16bit words read: 0
[11:13:35.612] <TB1> INFO: valid events total: 0
[11:13:35.612] <TB1> INFO: empty events: 0
[11:13:35.612] <TB1> INFO: valid events with pixels: 0
[11:13:35.612] <TB1> INFO: valid pixel hits: 0
[11:13:35.612] <TB1> INFO: Event errors: 0
[11:13:35.612] <TB1> INFO: start marker: 0
[11:13:35.612] <TB1> INFO: stop marker: 0
[11:13:35.612] <TB1> INFO: overflow: 0
[11:13:35.612] <TB1> INFO: invalid 5bit words: 0
[11:13:35.612] <TB1> INFO: invalid XOR eye diagram: 0
[11:13:35.612] <TB1> INFO: frame (failed synchr.): 0
[11:13:35.612] <TB1> INFO: idle data (no TBM trl): 0
[11:13:35.612] <TB1> INFO: no data (only TBM hdr): 0
[11:13:35.612] <TB1> INFO: TBM errors: 0
[11:13:35.612] <TB1> INFO: flawed TBM headers: 0
[11:13:35.612] <TB1> INFO: flawed TBM trailers: 0
[11:13:35.612] <TB1> INFO: event ID mismatches: 0
[11:13:35.612] <TB1> INFO: ROC errors: 0
[11:13:35.612] <TB1> INFO: missing ROC header(s): 0
[11:13:35.612] <TB1> INFO: misplaced readback start: 0
[11:13:35.612] <TB1> INFO: Pixel decoding errors: 0
[11:13:35.612] <TB1> INFO: pixel data incomplete: 0
[11:13:35.612] <TB1> INFO: pixel address: 0
[11:13:35.612] <TB1> INFO: pulse height fill bit: 0
[11:13:35.612] <TB1> INFO: buffer corruption: 0
[11:13:35.686] <TB1> INFO: ######################################################################
[11:13:35.686] <TB1> INFO: PixTestTrim::doTest()
[11:13:35.686] <TB1> INFO: ######################################################################
[11:13:35.688] <TB1> INFO: ----------------------------------------------------------------------
[11:13:35.688] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[11:13:35.688] <TB1> INFO: ----------------------------------------------------------------------
[11:13:35.769] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[11:13:35.769] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:13:35.777] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:13:35.777] <TB1> INFO: run 1 of 1
[11:13:36.042] <TB1> INFO: Expecting 6281600 events.
[11:14:13.255] <TB1> INFO: 1108010 events read in total (36655ms).
[11:14:49.291] <TB1> INFO: 2208470 events read in total (72692ms).
[11:15:25.669] <TB1> INFO: 3301380 events read in total (109069ms).
[11:16:02.332] <TB1> INFO: 4401960 events read in total (145732ms).
[11:16:39.094] <TB1> INFO: 5505920 events read in total (182494ms).
[11:17:07.030] <TB1> INFO: 6281600 events read in total (210430ms).
[11:17:07.077] <TB1> INFO: Test took 211300ms.
[11:17:27.482] <TB1> INFO: ROC 0 VthrComp = 93
[11:17:27.483] <TB1> INFO: ROC 1 VthrComp = 88
[11:17:27.483] <TB1> INFO: ROC 2 VthrComp = 110
[11:17:27.483] <TB1> INFO: ROC 3 VthrComp = 102
[11:17:27.483] <TB1> INFO: ROC 4 VthrComp = 98
[11:17:27.483] <TB1> INFO: ROC 5 VthrComp = 106
[11:17:27.483] <TB1> INFO: ROC 6 VthrComp = 88
[11:17:27.483] <TB1> INFO: ROC 7 VthrComp = 100
[11:17:27.483] <TB1> INFO: ROC 8 VthrComp = 90
[11:17:27.483] <TB1> INFO: ROC 9 VthrComp = 100
[11:17:27.483] <TB1> INFO: ROC 10 VthrComp = 97
[11:17:27.484] <TB1> INFO: ROC 11 VthrComp = 93
[11:17:27.484] <TB1> INFO: ROC 12 VthrComp = 95
[11:17:27.484] <TB1> INFO: ROC 13 VthrComp = 91
[11:17:27.484] <TB1> INFO: ROC 14 VthrComp = 97
[11:17:27.484] <TB1> INFO: ROC 15 VthrComp = 99
[11:17:27.484] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[11:17:27.484] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:17:27.493] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:17:27.493] <TB1> INFO: run 1 of 1
[11:17:27.753] <TB1> INFO: Expecting 6281600 events.
[11:17:59.709] <TB1> INFO: 759100 events read in total (31400ms).
[11:18:30.794] <TB1> INFO: 1517240 events read in total (62485ms).
[11:19:01.659] <TB1> INFO: 2276570 events read in total (93350ms).
[11:19:32.504] <TB1> INFO: 3036570 events read in total (124195ms).
[11:20:03.079] <TB1> INFO: 3789960 events read in total (154770ms).
[11:20:33.961] <TB1> INFO: 4539800 events read in total (185652ms).
[11:21:04.639] <TB1> INFO: 5287140 events read in total (216330ms).
[11:21:36.438] <TB1> INFO: 6033620 events read in total (248129ms).
[11:21:48.342] <TB1> INFO: 6281600 events read in total (260033ms).
[11:21:48.393] <TB1> INFO: Test took 260900ms.
[11:22:12.675] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 56.5874 for pixel 9/2 mean/min/max = 45.4716/34.0856/56.8576
[11:22:12.675] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 55.8935 for pixel 20/3 mean/min/max = 44.8994/33.7212/56.0776
[11:22:12.676] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 58.8104 for pixel 12/62 mean/min/max = 46.7211/34.4576/58.9846
[11:22:12.676] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 56.9257 for pixel 16/7 mean/min/max = 45.3016/33.4793/57.1239
[11:22:12.676] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 56.6216 for pixel 8/64 mean/min/max = 44.4305/31.7228/57.1382
[11:22:12.676] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 60.1558 for pixel 11/7 mean/min/max = 47.389/34.6216/60.1565
[11:22:12.677] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 58.1355 for pixel 0/7 mean/min/max = 46.2478/34.3163/58.1793
[11:22:12.677] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 57.3973 for pixel 15/4 mean/min/max = 44.5368/31.5972/57.4763
[11:22:12.677] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 58.2062 for pixel 3/67 mean/min/max = 45.7305/33.221/58.24
[11:22:12.677] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 58.7206 for pixel 0/15 mean/min/max = 45.3456/31.8938/58.7973
[11:22:12.677] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 58.1267 for pixel 19/9 mean/min/max = 45.188/32.132/58.2439
[11:22:12.678] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 56.7034 for pixel 51/10 mean/min/max = 45.236/33.759/56.713
[11:22:12.678] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 61.0651 for pixel 0/16 mean/min/max = 47.0578/32.9/61.2156
[11:22:12.678] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 60.1015 for pixel 0/10 mean/min/max = 46.7929/33.4657/60.1202
[11:22:12.678] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 57.4192 for pixel 9/0 mean/min/max = 44.708/31.9009/57.5152
[11:22:12.679] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.8494 for pixel 22/75 mean/min/max = 44.8863/31.7795/57.9931
[11:22:12.679] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:12.767] <TB1> INFO: Expecting 514560 events.
[11:22:23.692] <TB1> INFO: 514560 events read in total (10369ms).
[11:22:23.699] <TB1> INFO: Expecting 514560 events.
[11:22:34.397] <TB1> INFO: 514560 events read in total (10296ms).
[11:22:34.408] <TB1> INFO: Expecting 514560 events.
[11:22:45.116] <TB1> INFO: 514560 events read in total (10306ms).
[11:22:45.130] <TB1> INFO: Expecting 514560 events.
[11:22:55.867] <TB1> INFO: 514560 events read in total (10336ms).
[11:22:55.881] <TB1> INFO: Expecting 514560 events.
[11:23:06.588] <TB1> INFO: 514560 events read in total (10305ms).
[11:23:06.606] <TB1> INFO: Expecting 514560 events.
[11:23:17.436] <TB1> INFO: 514560 events read in total (10428ms).
[11:23:17.457] <TB1> INFO: Expecting 514560 events.
[11:23:28.189] <TB1> INFO: 514560 events read in total (10330ms).
[11:23:28.228] <TB1> INFO: Expecting 514560 events.
[11:23:38.940] <TB1> INFO: 514560 events read in total (10310ms).
[11:23:38.965] <TB1> INFO: Expecting 514560 events.
[11:23:49.760] <TB1> INFO: 514560 events read in total (10393ms).
[11:23:49.793] <TB1> INFO: Expecting 514560 events.
[11:24:00.491] <TB1> INFO: 514560 events read in total (10297ms).
[11:24:00.522] <TB1> INFO: Expecting 514560 events.
[11:24:11.266] <TB1> INFO: 514560 events read in total (10342ms).
[11:24:11.301] <TB1> INFO: Expecting 514560 events.
[11:24:22.050] <TB1> INFO: 514560 events read in total (10347ms).
[11:24:22.104] <TB1> INFO: Expecting 514560 events.
[11:24:32.908] <TB1> INFO: 514560 events read in total (10403ms).
[11:24:32.947] <TB1> INFO: Expecting 514560 events.
[11:24:43.683] <TB1> INFO: 514560 events read in total (10335ms).
[11:24:43.726] <TB1> INFO: Expecting 514560 events.
[11:24:54.517] <TB1> INFO: 514560 events read in total (10389ms).
[11:24:54.575] <TB1> INFO: Expecting 514560 events.
[11:25:05.341] <TB1> INFO: 514560 events read in total (10364ms).
[11:25:05.392] <TB1> INFO: Test took 172713ms.
[11:25:06.467] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[11:25:06.476] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:25:06.476] <TB1> INFO: run 1 of 1
[11:25:06.782] <TB1> INFO: Expecting 6281600 events.
[11:25:38.331] <TB1> INFO: 746420 events read in total (30992ms).
[11:26:09.137] <TB1> INFO: 1492270 events read in total (61798ms).
[11:26:39.834] <TB1> INFO: 2239020 events read in total (92495ms).
[11:27:10.569] <TB1> INFO: 2986970 events read in total (123230ms).
[11:27:41.277] <TB1> INFO: 3728590 events read in total (153938ms).
[11:28:11.977] <TB1> INFO: 4466120 events read in total (184638ms).
[11:28:42.565] <TB1> INFO: 5201180 events read in total (215226ms).
[11:29:14.205] <TB1> INFO: 5934870 events read in total (246866ms).
[11:29:29.836] <TB1> INFO: 6281600 events read in total (262497ms).
[11:29:29.915] <TB1> INFO: Test took 263440ms.
[11:29:54.600] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 11.043821 .. 50.851699
[11:29:54.862] <TB1> INFO: Expecting 208000 events.
[11:30:04.809] <TB1> INFO: 208000 events read in total (9391ms).
[11:30:04.810] <TB1> INFO: Test took 10209ms.
[11:30:04.941] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 1 .. 60 (-1/-1) hits flags = 528 (plus default)
[11:30:04.950] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:30:04.950] <TB1> INFO: run 1 of 1
[11:30:05.245] <TB1> INFO: Expecting 2496000 events.
[11:30:39.815] <TB1> INFO: 948370 events read in total (34014ms).
[11:31:14.056] <TB1> INFO: 1892720 events read in total (68255ms).
[11:31:36.143] <TB1> INFO: 2496000 events read in total (90342ms).
[11:31:36.169] <TB1> INFO: Test took 91219ms.
[11:31:51.936] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 22.141196 .. 45.497228
[11:31:52.198] <TB1> INFO: Expecting 208000 events.
[11:32:02.050] <TB1> INFO: 208000 events read in total (9295ms).
[11:32:02.050] <TB1> INFO: Test took 10111ms.
[11:32:02.148] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 12 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:32:02.157] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:32:02.157] <TB1> INFO: run 1 of 1
[11:32:02.442] <TB1> INFO: Expecting 1830400 events.
[11:32:37.052] <TB1> INFO: 916000 events read in total (34054ms).
[11:33:10.793] <TB1> INFO: 1829370 events read in total (67795ms).
[11:33:11.228] <TB1> INFO: 1830400 events read in total (68230ms).
[11:33:11.246] <TB1> INFO: Test took 69089ms.
[11:33:25.928] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 22.309216 .. 41.740319
[11:33:26.197] <TB1> INFO: Expecting 208000 events.
[11:33:36.056] <TB1> INFO: 208000 events read in total (9303ms).
[11:33:36.056] <TB1> INFO: Test took 10125ms.
[11:33:36.141] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 12 .. 51 (-1/-1) hits flags = 528 (plus default)
[11:33:36.150] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:33:36.150] <TB1> INFO: run 1 of 1
[11:33:36.412] <TB1> INFO: Expecting 1664000 events.
[11:34:12.670] <TB1> INFO: 951700 events read in total (35702ms).
[11:34:38.831] <TB1> INFO: 1664000 events read in total (61863ms).
[11:34:38.848] <TB1> INFO: Test took 62699ms.
[11:34:52.848] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 25.372679 .. 41.740319
[11:34:53.121] <TB1> INFO: Expecting 208000 events.
[11:35:03.079] <TB1> INFO: 208000 events read in total (9401ms).
[11:35:03.080] <TB1> INFO: Test took 10231ms.
[11:35:03.165] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 15 .. 51 (-1/-1) hits flags = 528 (plus default)
[11:35:03.176] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:35:03.176] <TB1> INFO: run 1 of 1
[11:35:03.439] <TB1> INFO: Expecting 1539200 events.
[11:35:38.650] <TB1> INFO: 931520 events read in total (34654ms).
[11:36:01.307] <TB1> INFO: 1539200 events read in total (57311ms).
[11:36:01.324] <TB1> INFO: Test took 58148ms.
[11:36:15.054] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:36:15.054] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:36:15.065] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:36:15.065] <TB1> INFO: run 1 of 1
[11:36:15.353] <TB1> INFO: Expecting 1705600 events.
[11:36:50.311] <TB1> INFO: 895000 events read in total (34401ms).
[11:37:21.039] <TB1> INFO: 1705600 events read in total (65130ms).
[11:37:21.067] <TB1> INFO: Test took 66001ms.
[11:37:34.461] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C0.dat
[11:37:34.461] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C1.dat
[11:37:34.461] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C2.dat
[11:37:34.461] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C3.dat
[11:37:34.461] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C4.dat
[11:37:34.461] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C5.dat
[11:37:34.461] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C6.dat
[11:37:34.462] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C7.dat
[11:37:34.462] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C8.dat
[11:37:34.462] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C9.dat
[11:37:34.462] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C10.dat
[11:37:34.462] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C11.dat
[11:37:34.462] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C12.dat
[11:37:34.462] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C13.dat
[11:37:34.462] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C14.dat
[11:37:34.463] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C15.dat
[11:37:34.463] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C0.dat
[11:37:34.469] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C1.dat
[11:37:34.476] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C2.dat
[11:37:34.482] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C3.dat
[11:37:34.488] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C4.dat
[11:37:34.494] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C5.dat
[11:37:34.500] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C6.dat
[11:37:34.507] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C7.dat
[11:37:34.513] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C8.dat
[11:37:34.519] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C9.dat
[11:37:34.525] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C10.dat
[11:37:34.531] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C11.dat
[11:37:34.538] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C12.dat
[11:37:34.544] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C13.dat
[11:37:34.551] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C14.dat
[11:37:34.558] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//trimParameters35_C15.dat
[11:37:34.564] <TB1> INFO: PixTestTrim::trimTest() done
[11:37:34.564] <TB1> INFO: vtrim: 101 99 116 105 102 120 95 114 103 94 116 92 105 111 99 100
[11:37:34.564] <TB1> INFO: vthrcomp: 93 88 110 102 98 106 88 100 90 100 97 93 95 91 97 99
[11:37:34.564] <TB1> INFO: vcal mean: 34.95 34.97 34.96 35.01 34.97 34.99 34.99 34.93 34.96 35.03 34.96 35.02 35.00 34.93 34.98 34.96
[11:37:34.564] <TB1> INFO: vcal RMS: 0.82 0.79 0.83 0.83 0.81 0.82 0.78 0.84 0.82 0.82 0.82 0.80 0.83 1.37 0.83 0.84
[11:37:34.564] <TB1> INFO: bits mean: 9.44 9.68 8.92 9.71 9.83 8.74 8.46 10.06 9.61 9.19 9.85 9.11 8.75 8.79 9.64 9.90
[11:37:34.564] <TB1> INFO: bits RMS: 2.47 2.43 2.54 2.43 2.68 2.54 2.75 2.59 2.50 2.89 2.55 2.63 2.80 2.79 2.74 2.63
[11:37:34.571] <TB1> INFO: ----------------------------------------------------------------------
[11:37:34.571] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:37:34.571] <TB1> INFO: ----------------------------------------------------------------------
[11:37:34.573] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:37:34.582] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:37:34.582] <TB1> INFO: run 1 of 1
[11:37:34.844] <TB1> INFO: Expecting 4160000 events.
[11:38:14.028] <TB1> INFO: 954890 events read in total (38628ms).
[11:38:53.338] <TB1> INFO: 1898845 events read in total (77938ms).
[11:39:32.636] <TB1> INFO: 2830950 events read in total (117236ms).
[11:40:10.848] <TB1> INFO: 3758730 events read in total (155448ms).
[11:40:27.687] <TB1> INFO: 4160000 events read in total (172287ms).
[11:40:27.758] <TB1> INFO: Test took 173176ms.
[11:40:56.927] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 171 (-1/-1) hits flags = 528 (plus default)
[11:40:56.936] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:40:56.936] <TB1> INFO: run 1 of 1
[11:40:57.208] <TB1> INFO: Expecting 3577600 events.
[11:41:36.459] <TB1> INFO: 984445 events read in total (38695ms).
[11:42:15.365] <TB1> INFO: 1953915 events read in total (77602ms).
[11:42:54.601] <TB1> INFO: 2909265 events read in total (116837ms).
[11:43:22.072] <TB1> INFO: 3577600 events read in total (144308ms).
[11:43:22.139] <TB1> INFO: Test took 145203ms.
[11:43:48.163] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:43:48.171] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:43:48.171] <TB1> INFO: run 1 of 1
[11:43:48.434] <TB1> INFO: Expecting 3348800 events.
[11:44:28.235] <TB1> INFO: 1016545 events read in total (39245ms).
[11:45:07.960] <TB1> INFO: 2013880 events read in total (78970ms).
[11:45:46.739] <TB1> INFO: 2996855 events read in total (117749ms).
[11:46:00.782] <TB1> INFO: 3348800 events read in total (131792ms).
[11:46:00.831] <TB1> INFO: Test took 132661ms.
[11:46:26.514] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:46:26.523] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:46:26.523] <TB1> INFO: run 1 of 1
[11:46:26.788] <TB1> INFO: Expecting 3348800 events.
[11:47:06.412] <TB1> INFO: 1015370 events read in total (39067ms).
[11:47:45.862] <TB1> INFO: 2012115 events read in total (78517ms).
[11:48:24.936] <TB1> INFO: 2994415 events read in total (117591ms).
[11:48:39.089] <TB1> INFO: 3348800 events read in total (131744ms).
[11:48:39.137] <TB1> INFO: Test took 132615ms.
[11:49:03.863] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 159 (-1/-1) hits flags = 528 (plus default)
[11:49:03.872] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:49:03.872] <TB1> INFO: run 1 of 1
[11:49:04.136] <TB1> INFO: Expecting 3328000 events.
[11:49:44.374] <TB1> INFO: 1018305 events read in total (39682ms).
[11:50:23.687] <TB1> INFO: 2016750 events read in total (78995ms).
[11:51:02.777] <TB1> INFO: 3001475 events read in total (118085ms).
[11:51:15.761] <TB1> INFO: 3328000 events read in total (131069ms).
[11:51:15.810] <TB1> INFO: Test took 131939ms.
[11:51:41.192] <TB1> INFO: PixTestTrim::trimBitTest() done
[11:51:41.193] <TB1> INFO: PixTestTrim::doTest() done, duration: 2285 seconds
[11:51:41.193] <TB1> INFO: Decoding statistics:
[11:51:41.193] <TB1> INFO: General information:
[11:51:41.193] <TB1> INFO: 16bit words read: 0
[11:51:41.193] <TB1> INFO: valid events total: 0
[11:51:41.193] <TB1> INFO: empty events: 0
[11:51:41.193] <TB1> INFO: valid events with pixels: 0
[11:51:41.193] <TB1> INFO: valid pixel hits: 0
[11:51:41.193] <TB1> INFO: Event errors: 0
[11:51:41.193] <TB1> INFO: start marker: 0
[11:51:41.193] <TB1> INFO: stop marker: 0
[11:51:41.193] <TB1> INFO: overflow: 0
[11:51:41.193] <TB1> INFO: invalid 5bit words: 0
[11:51:41.193] <TB1> INFO: invalid XOR eye diagram: 0
[11:51:41.193] <TB1> INFO: frame (failed synchr.): 0
[11:51:41.193] <TB1> INFO: idle data (no TBM trl): 0
[11:51:41.193] <TB1> INFO: no data (only TBM hdr): 0
[11:51:41.193] <TB1> INFO: TBM errors: 0
[11:51:41.193] <TB1> INFO: flawed TBM headers: 0
[11:51:41.193] <TB1> INFO: flawed TBM trailers: 0
[11:51:41.193] <TB1> INFO: event ID mismatches: 0
[11:51:41.193] <TB1> INFO: ROC errors: 0
[11:51:41.193] <TB1> INFO: missing ROC header(s): 0
[11:51:41.193] <TB1> INFO: misplaced readback start: 0
[11:51:41.193] <TB1> INFO: Pixel decoding errors: 0
[11:51:41.193] <TB1> INFO: pixel data incomplete: 0
[11:51:41.193] <TB1> INFO: pixel address: 0
[11:51:41.193] <TB1> INFO: pulse height fill bit: 0
[11:51:41.193] <TB1> INFO: buffer corruption: 0
[11:51:41.955] <TB1> INFO: ######################################################################
[11:51:41.955] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:51:41.955] <TB1> INFO: ######################################################################
[11:51:42.220] <TB1> INFO: Expecting 41600 events.
[11:51:45.701] <TB1> INFO: 41600 events read in total (2925ms).
[11:51:45.702] <TB1> INFO: Test took 3745ms.
[11:51:46.156] <TB1> INFO: Expecting 41600 events.
[11:51:49.638] <TB1> INFO: 41600 events read in total (2925ms).
[11:51:49.639] <TB1> INFO: Test took 3740ms.
[11:51:49.923] <TB1> INFO: Expecting 41600 events.
[11:51:53.414] <TB1> INFO: 41600 events read in total (2934ms).
[11:51:53.415] <TB1> INFO: Test took 3755ms.
[11:51:53.670] <TB1> INFO: Expecting 2560 events.
[11:51:54.523] <TB1> INFO: 2560 events read in total (296ms).
[11:51:54.523] <TB1> INFO: Test took 1095ms.
[11:51:54.828] <TB1> INFO: Expecting 2560 events.
[11:51:55.681] <TB1> INFO: 2560 events read in total (297ms).
[11:51:55.681] <TB1> INFO: Test took 1157ms.
[11:51:55.986] <TB1> INFO: Expecting 2560 events.
[11:51:56.838] <TB1> INFO: 2560 events read in total (296ms).
[11:51:56.838] <TB1> INFO: Test took 1156ms.
[11:51:57.143] <TB1> INFO: Expecting 2560 events.
[11:51:57.996] <TB1> INFO: 2560 events read in total (296ms).
[11:51:57.996] <TB1> INFO: Test took 1157ms.
[11:51:58.301] <TB1> INFO: Expecting 2560 events.
[11:51:59.154] <TB1> INFO: 2560 events read in total (297ms).
[11:51:59.154] <TB1> INFO: Test took 1158ms.
[11:51:59.458] <TB1> INFO: Expecting 2560 events.
[11:52:00.310] <TB1> INFO: 2560 events read in total (296ms).
[11:52:00.311] <TB1> INFO: Test took 1156ms.
[11:52:00.616] <TB1> INFO: Expecting 2560 events.
[11:52:01.475] <TB1> INFO: 2560 events read in total (303ms).
[11:52:01.475] <TB1> INFO: Test took 1164ms.
[11:52:01.779] <TB1> INFO: Expecting 2560 events.
[11:52:02.641] <TB1> INFO: 2560 events read in total (306ms).
[11:52:02.641] <TB1> INFO: Test took 1165ms.
[11:52:02.946] <TB1> INFO: Expecting 2560 events.
[11:52:03.804] <TB1> INFO: 2560 events read in total (302ms).
[11:52:03.804] <TB1> INFO: Test took 1162ms.
[11:52:04.109] <TB1> INFO: Expecting 2560 events.
[11:52:04.965] <TB1> INFO: 2560 events read in total (300ms).
[11:52:04.965] <TB1> INFO: Test took 1161ms.
[11:52:05.269] <TB1> INFO: Expecting 2560 events.
[11:52:06.124] <TB1> INFO: 2560 events read in total (298ms).
[11:52:06.124] <TB1> INFO: Test took 1158ms.
[11:52:06.428] <TB1> INFO: Expecting 2560 events.
[11:52:07.283] <TB1> INFO: 2560 events read in total (299ms).
[11:52:07.283] <TB1> INFO: Test took 1158ms.
[11:52:07.587] <TB1> INFO: Expecting 2560 events.
[11:52:08.439] <TB1> INFO: 2560 events read in total (296ms).
[11:52:08.439] <TB1> INFO: Test took 1155ms.
[11:52:08.744] <TB1> INFO: Expecting 2560 events.
[11:52:09.602] <TB1> INFO: 2560 events read in total (301ms).
[11:52:09.602] <TB1> INFO: Test took 1162ms.
[11:52:09.907] <TB1> INFO: Expecting 2560 events.
[11:52:10.761] <TB1> INFO: 2560 events read in total (297ms).
[11:52:10.761] <TB1> INFO: Test took 1159ms.
[11:52:11.066] <TB1> INFO: Expecting 2560 events.
[11:52:11.918] <TB1> INFO: 2560 events read in total (296ms).
[11:52:11.919] <TB1> INFO: Test took 1158ms.
[11:52:11.931] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:52:12.224] <TB1> INFO: Expecting 655360 events.
[11:52:26.225] <TB1> INFO: 655360 events read in total (13445ms).
[11:52:26.237] <TB1> INFO: Expecting 655360 events.
[11:52:40.029] <TB1> INFO: 655360 events read in total (13390ms).
[11:52:40.045] <TB1> INFO: Expecting 655360 events.
[11:52:53.860] <TB1> INFO: 655360 events read in total (13414ms).
[11:52:53.878] <TB1> INFO: Expecting 655360 events.
[11:53:07.716] <TB1> INFO: 655360 events read in total (13436ms).
[11:53:07.741] <TB1> INFO: Expecting 655360 events.
[11:53:21.453] <TB1> INFO: 655360 events read in total (13310ms).
[11:53:21.481] <TB1> INFO: Expecting 655360 events.
[11:53:35.253] <TB1> INFO: 655360 events read in total (13371ms).
[11:53:35.290] <TB1> INFO: Expecting 655360 events.
[11:53:49.100] <TB1> INFO: 655360 events read in total (13408ms).
[11:53:49.136] <TB1> INFO: Expecting 655360 events.
[11:54:02.841] <TB1> INFO: 655360 events read in total (13303ms).
[11:54:02.882] <TB1> INFO: Expecting 655360 events.
[11:54:16.609] <TB1> INFO: 655360 events read in total (13325ms).
[11:54:16.655] <TB1> INFO: Expecting 655360 events.
[11:54:30.376] <TB1> INFO: 655360 events read in total (13319ms).
[11:54:30.423] <TB1> INFO: Expecting 655360 events.
[11:54:44.120] <TB1> INFO: 655360 events read in total (13295ms).
[11:54:44.176] <TB1> INFO: Expecting 655360 events.
[11:54:57.960] <TB1> INFO: 655360 events read in total (13383ms).
[11:54:58.027] <TB1> INFO: Expecting 655360 events.
[11:55:11.746] <TB1> INFO: 655360 events read in total (13317ms).
[11:55:11.808] <TB1> INFO: Expecting 655360 events.
[11:55:25.535] <TB1> INFO: 655360 events read in total (13325ms).
[11:55:25.616] <TB1> INFO: Expecting 655360 events.
[11:55:39.391] <TB1> INFO: 655360 events read in total (13373ms).
[11:55:39.467] <TB1> INFO: Expecting 655360 events.
[11:55:53.341] <TB1> INFO: 655360 events read in total (13472ms).
[11:55:53.427] <TB1> INFO: Test took 221496ms.
[11:55:53.516] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:53.746] <TB1> INFO: Expecting 655360 events.
[11:56:07.617] <TB1> INFO: 655360 events read in total (13315ms).
[11:56:07.629] <TB1> INFO: Expecting 655360 events.
[11:56:21.406] <TB1> INFO: 655360 events read in total (13375ms).
[11:56:21.421] <TB1> INFO: Expecting 655360 events.
[11:56:35.201] <TB1> INFO: 655360 events read in total (13379ms).
[11:56:35.220] <TB1> INFO: Expecting 655360 events.
[11:56:48.933] <TB1> INFO: 655360 events read in total (13311ms).
[11:56:48.958] <TB1> INFO: Expecting 655360 events.
[11:57:02.680] <TB1> INFO: 655360 events read in total (13320ms).
[11:57:02.707] <TB1> INFO: Expecting 655360 events.
[11:57:16.312] <TB1> INFO: 655360 events read in total (13195ms).
[11:57:16.344] <TB1> INFO: Expecting 655360 events.
[11:57:30.026] <TB1> INFO: 655360 events read in total (13280ms).
[11:57:30.064] <TB1> INFO: Expecting 655360 events.
[11:57:43.792] <TB1> INFO: 655360 events read in total (13326ms).
[11:57:43.831] <TB1> INFO: Expecting 655360 events.
[11:57:57.643] <TB1> INFO: 655360 events read in total (13410ms).
[11:57:57.688] <TB1> INFO: Expecting 655360 events.
[11:58:11.404] <TB1> INFO: 655360 events read in total (13314ms).
[11:58:11.453] <TB1> INFO: Expecting 655360 events.
[11:58:25.221] <TB1> INFO: 655360 events read in total (13366ms).
[11:58:25.274] <TB1> INFO: Expecting 655360 events.
[11:58:39.079] <TB1> INFO: 655360 events read in total (13404ms).
[11:58:39.139] <TB1> INFO: Expecting 655360 events.
[11:58:52.921] <TB1> INFO: 655360 events read in total (13380ms).
[11:58:52.996] <TB1> INFO: Expecting 655360 events.
[11:59:06.697] <TB1> INFO: 655360 events read in total (13298ms).
[11:59:06.766] <TB1> INFO: Expecting 655360 events.
[11:59:20.570] <TB1> INFO: 655360 events read in total (13402ms).
[11:59:20.634] <TB1> INFO: Expecting 655360 events.
[11:59:34.395] <TB1> INFO: 655360 events read in total (13359ms).
[11:59:34.469] <TB1> INFO: Test took 220953ms.
[11:59:34.688] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.695] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.702] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.709] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.717] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.724] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.732] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:59:34.740] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[11:59:34.748] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[11:59:34.756] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[11:59:34.764] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[11:59:34.773] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[11:59:34.780] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[11:59:34.788] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.796] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.804] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.811] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.819] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.826] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.834] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.842] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.850] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.858] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:59:34.908] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C0.dat
[11:59:34.908] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C1.dat
[11:59:34.909] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C2.dat
[11:59:34.909] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C3.dat
[11:59:34.909] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C4.dat
[11:59:34.909] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C5.dat
[11:59:34.909] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C6.dat
[11:59:34.909] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C7.dat
[11:59:34.909] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C8.dat
[11:59:34.909] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C9.dat
[11:59:34.909] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C10.dat
[11:59:34.910] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C11.dat
[11:59:34.910] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C12.dat
[11:59:34.910] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C13.dat
[11:59:34.910] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C14.dat
[11:59:34.910] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//dacParameters35_C15.dat
[11:59:35.180] <TB1> INFO: Expecting 41600 events.
[11:59:38.290] <TB1> INFO: 41600 events read in total (2554ms).
[11:59:38.290] <TB1> INFO: Test took 3377ms.
[11:59:38.733] <TB1> INFO: Expecting 41600 events.
[11:59:41.805] <TB1> INFO: 41600 events read in total (2516ms).
[11:59:41.806] <TB1> INFO: Test took 3341ms.
[11:59:42.246] <TB1> INFO: Expecting 41600 events.
[11:59:45.361] <TB1> INFO: 41600 events read in total (2559ms).
[11:59:45.361] <TB1> INFO: Test took 3381ms.
[11:59:45.547] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:59:45.636] <TB1> INFO: Expecting 2560 events.
[11:59:46.489] <TB1> INFO: 2560 events read in total (297ms).
[11:59:46.489] <TB1> INFO: Test took 942ms.
[11:59:46.491] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:59:46.795] <TB1> INFO: Expecting 2560 events.
[11:59:47.653] <TB1> INFO: 2560 events read in total (302ms).
[11:59:47.653] <TB1> INFO: Test took 1162ms.
[11:59:47.656] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:59:47.959] <TB1> INFO: Expecting 2560 events.
[11:59:48.812] <TB1> INFO: 2560 events read in total (297ms).
[11:59:48.813] <TB1> INFO: Test took 1157ms.
[11:59:48.814] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:59:49.117] <TB1> INFO: Expecting 2560 events.
[11:59:49.971] <TB1> INFO: 2560 events read in total (298ms).
[11:59:49.972] <TB1> INFO: Test took 1158ms.
[11:59:49.973] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:59:50.278] <TB1> INFO: Expecting 2560 events.
[11:59:51.131] <TB1> INFO: 2560 events read in total (297ms).
[11:59:51.132] <TB1> INFO: Test took 1159ms.
[11:59:51.136] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:59:51.437] <TB1> INFO: Expecting 2560 events.
[11:59:52.299] <TB1> INFO: 2560 events read in total (306ms).
[11:59:52.299] <TB1> INFO: Test took 1163ms.
[11:59:52.301] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:59:52.605] <TB1> INFO: Expecting 2560 events.
[11:59:53.459] <TB1> INFO: 2560 events read in total (298ms).
[11:59:53.460] <TB1> INFO: Test took 1159ms.
[11:59:53.463] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:59:53.764] <TB1> INFO: Expecting 2560 events.
[11:59:54.622] <TB1> INFO: 2560 events read in total (302ms).
[11:59:54.622] <TB1> INFO: Test took 1160ms.
[11:59:54.624] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:59:54.929] <TB1> INFO: Expecting 2560 events.
[11:59:55.781] <TB1> INFO: 2560 events read in total (296ms).
[11:59:55.781] <TB1> INFO: Test took 1157ms.
[11:59:55.783] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:59:56.086] <TB1> INFO: Expecting 2560 events.
[11:59:56.944] <TB1> INFO: 2560 events read in total (301ms).
[11:59:56.944] <TB1> INFO: Test took 1161ms.
[11:59:56.946] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:59:57.250] <TB1> INFO: Expecting 2560 events.
[11:59:58.102] <TB1> INFO: 2560 events read in total (296ms).
[11:59:58.102] <TB1> INFO: Test took 1156ms.
[11:59:58.104] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:59:58.408] <TB1> INFO: Expecting 2560 events.
[11:59:59.269] <TB1> INFO: 2560 events read in total (304ms).
[11:59:59.269] <TB1> INFO: Test took 1165ms.
[11:59:59.271] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:59:59.575] <TB1> INFO: Expecting 2560 events.
[12:00:00.427] <TB1> INFO: 2560 events read in total (296ms).
[12:00:00.427] <TB1> INFO: Test took 1157ms.
[12:00:00.430] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:00.733] <TB1> INFO: Expecting 2560 events.
[12:00:01.589] <TB1> INFO: 2560 events read in total (299ms).
[12:00:01.589] <TB1> INFO: Test took 1160ms.
[12:00:01.591] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:01.895] <TB1> INFO: Expecting 2560 events.
[12:00:02.747] <TB1> INFO: 2560 events read in total (296ms).
[12:00:02.747] <TB1> INFO: Test took 1156ms.
[12:00:02.749] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:03.052] <TB1> INFO: Expecting 2560 events.
[12:00:03.906] <TB1> INFO: 2560 events read in total (297ms).
[12:00:03.906] <TB1> INFO: Test took 1157ms.
[12:00:03.909] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:04.211] <TB1> INFO: Expecting 2560 events.
[12:00:05.067] <TB1> INFO: 2560 events read in total (299ms).
[12:00:05.067] <TB1> INFO: Test took 1158ms.
[12:00:05.070] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:05.372] <TB1> INFO: Expecting 2560 events.
[12:00:06.226] <TB1> INFO: 2560 events read in total (298ms).
[12:00:06.227] <TB1> INFO: Test took 1157ms.
[12:00:06.229] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:06.531] <TB1> INFO: Expecting 2560 events.
[12:00:07.385] <TB1> INFO: 2560 events read in total (298ms).
[12:00:07.386] <TB1> INFO: Test took 1157ms.
[12:00:07.389] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:07.690] <TB1> INFO: Expecting 2560 events.
[12:00:08.547] <TB1> INFO: 2560 events read in total (300ms).
[12:00:08.548] <TB1> INFO: Test took 1159ms.
[12:00:08.551] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:08.852] <TB1> INFO: Expecting 2560 events.
[12:00:09.704] <TB1> INFO: 2560 events read in total (295ms).
[12:00:09.704] <TB1> INFO: Test took 1154ms.
[12:00:09.706] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:10.010] <TB1> INFO: Expecting 2560 events.
[12:00:10.864] <TB1> INFO: 2560 events read in total (298ms).
[12:00:10.864] <TB1> INFO: Test took 1158ms.
[12:00:10.866] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:11.170] <TB1> INFO: Expecting 2560 events.
[12:00:12.023] <TB1> INFO: 2560 events read in total (297ms).
[12:00:12.050] <TB1> INFO: Test took 1184ms.
[12:00:12.051] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:12.329] <TB1> INFO: Expecting 2560 events.
[12:00:13.189] <TB1> INFO: 2560 events read in total (304ms).
[12:00:13.189] <TB1> INFO: Test took 1138ms.
[12:00:13.193] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:13.493] <TB1> INFO: Expecting 2560 events.
[12:00:14.346] <TB1> INFO: 2560 events read in total (297ms).
[12:00:14.346] <TB1> INFO: Test took 1154ms.
[12:00:14.348] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:14.651] <TB1> INFO: Expecting 2560 events.
[12:00:15.506] <TB1> INFO: 2560 events read in total (299ms).
[12:00:15.507] <TB1> INFO: Test took 1159ms.
[12:00:15.509] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:15.812] <TB1> INFO: Expecting 2560 events.
[12:00:16.671] <TB1> INFO: 2560 events read in total (303ms).
[12:00:16.671] <TB1> INFO: Test took 1162ms.
[12:00:16.674] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:16.977] <TB1> INFO: Expecting 2560 events.
[12:00:17.838] <TB1> INFO: 2560 events read in total (305ms).
[12:00:17.839] <TB1> INFO: Test took 1165ms.
[12:00:17.853] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:18.143] <TB1> INFO: Expecting 2560 events.
[12:00:18.995] <TB1> INFO: 2560 events read in total (295ms).
[12:00:18.995] <TB1> INFO: Test took 1143ms.
[12:00:18.997] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:19.300] <TB1> INFO: Expecting 2560 events.
[12:00:20.158] <TB1> INFO: 2560 events read in total (301ms).
[12:00:20.158] <TB1> INFO: Test took 1161ms.
[12:00:20.161] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:20.463] <TB1> INFO: Expecting 2560 events.
[12:00:21.317] <TB1> INFO: 2560 events read in total (298ms).
[12:00:21.317] <TB1> INFO: Test took 1156ms.
[12:00:21.320] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:00:21.622] <TB1> INFO: Expecting 2560 events.
[12:00:22.476] <TB1> INFO: 2560 events read in total (298ms).
[12:00:22.476] <TB1> INFO: Test took 1156ms.
[12:00:22.956] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 521 seconds
[12:00:22.956] <TB1> INFO: PH scale (per ROC): 80 81 77 74 66 75 79 70 81 80 85 80 74 72 81 76
[12:00:22.956] <TB1> INFO: PH offset (per ROC): 170 159 179 177 182 176 174 184 167 165 160 181 175 176 165 174
[12:00:22.961] <TB1> INFO: Decoding statistics:
[12:00:22.961] <TB1> INFO: General information:
[12:00:22.961] <TB1> INFO: 16bit words read: 86926
[12:00:22.961] <TB1> INFO: valid events total: 10240
[12:00:22.961] <TB1> INFO: empty events: 7737
[12:00:22.961] <TB1> INFO: valid events with pixels: 2503
[12:00:22.961] <TB1> INFO: valid pixel hits: 2503
[12:00:22.961] <TB1> INFO: Event errors: 0
[12:00:22.961] <TB1> INFO: start marker: 0
[12:00:22.961] <TB1> INFO: stop marker: 0
[12:00:22.961] <TB1> INFO: overflow: 0
[12:00:22.961] <TB1> INFO: invalid 5bit words: 0
[12:00:22.961] <TB1> INFO: invalid XOR eye diagram: 0
[12:00:22.961] <TB1> INFO: frame (failed synchr.): 0
[12:00:22.961] <TB1> INFO: idle data (no TBM trl): 0
[12:00:22.961] <TB1> INFO: no data (only TBM hdr): 0
[12:00:22.961] <TB1> INFO: TBM errors: 0
[12:00:22.961] <TB1> INFO: flawed TBM headers: 0
[12:00:22.961] <TB1> INFO: flawed TBM trailers: 0
[12:00:22.961] <TB1> INFO: event ID mismatches: 0
[12:00:22.961] <TB1> INFO: ROC errors: 0
[12:00:22.961] <TB1> INFO: missing ROC header(s): 0
[12:00:22.961] <TB1> INFO: misplaced readback start: 0
[12:00:22.961] <TB1> INFO: Pixel decoding errors: 0
[12:00:22.961] <TB1> INFO: pixel data incomplete: 0
[12:00:22.961] <TB1> INFO: pixel address: 0
[12:00:22.961] <TB1> INFO: pulse height fill bit: 0
[12:00:22.961] <TB1> INFO: buffer corruption: 0
[12:00:23.142] <TB1> INFO: ######################################################################
[12:00:23.142] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[12:00:23.142] <TB1> INFO: ######################################################################
[12:00:23.152] <TB1> INFO: scanning low vcal = 10
[12:00:23.419] <TB1> INFO: Expecting 41600 events.
[12:00:26.797] <TB1> INFO: 41600 events read in total (2822ms).
[12:00:26.797] <TB1> INFO: Test took 3645ms.
[12:00:26.799] <TB1> INFO: scanning low vcal = 20
[12:00:27.096] <TB1> INFO: Expecting 41600 events.
[12:00:30.498] <TB1> INFO: 41600 events read in total (2846ms).
[12:00:30.498] <TB1> INFO: Test took 3699ms.
[12:00:30.499] <TB1> INFO: scanning low vcal = 30
[12:00:30.793] <TB1> INFO: Expecting 41600 events.
[12:00:34.212] <TB1> INFO: 41600 events read in total (2863ms).
[12:00:34.212] <TB1> INFO: Test took 3713ms.
[12:00:34.214] <TB1> INFO: scanning low vcal = 40
[12:00:34.501] <TB1> INFO: Expecting 41600 events.
[12:00:38.386] <TB1> INFO: 41600 events read in total (3329ms).
[12:00:38.387] <TB1> INFO: Test took 4173ms.
[12:00:38.390] <TB1> INFO: scanning low vcal = 50
[12:00:38.655] <TB1> INFO: Expecting 41600 events.
[12:00:42.621] <TB1> INFO: 41600 events read in total (3410ms).
[12:00:42.623] <TB1> INFO: Test took 4232ms.
[12:00:42.625] <TB1> INFO: scanning low vcal = 60
[12:00:42.899] <TB1> INFO: Expecting 41600 events.
[12:00:46.845] <TB1> INFO: 41600 events read in total (3390ms).
[12:00:46.847] <TB1> INFO: Test took 4222ms.
[12:00:46.850] <TB1> INFO: scanning low vcal = 70
[12:00:47.110] <TB1> INFO: Expecting 41600 events.
[12:00:51.070] <TB1> INFO: 41600 events read in total (3403ms).
[12:00:51.071] <TB1> INFO: Test took 4221ms.
[12:00:51.074] <TB1> INFO: scanning low vcal = 80
[12:00:51.336] <TB1> INFO: Expecting 41600 events.
[12:00:55.287] <TB1> INFO: 41600 events read in total (3395ms).
[12:00:55.288] <TB1> INFO: Test took 4214ms.
[12:00:55.291] <TB1> INFO: scanning low vcal = 90
[12:00:55.555] <TB1> INFO: Expecting 41600 events.
[12:00:59.526] <TB1> INFO: 41600 events read in total (3415ms).
[12:00:59.528] <TB1> INFO: Test took 4237ms.
[12:00:59.531] <TB1> INFO: scanning low vcal = 100
[12:00:59.795] <TB1> INFO: Expecting 41600 events.
[12:01:03.792] <TB1> INFO: 41600 events read in total (3440ms).
[12:01:03.794] <TB1> INFO: Test took 4263ms.
[12:01:03.796] <TB1> INFO: scanning low vcal = 110
[12:01:04.062] <TB1> INFO: Expecting 41600 events.
[12:01:08.024] <TB1> INFO: 41600 events read in total (3406ms).
[12:01:08.026] <TB1> INFO: Test took 4230ms.
[12:01:08.029] <TB1> INFO: scanning low vcal = 120
[12:01:08.296] <TB1> INFO: Expecting 41600 events.
[12:01:12.270] <TB1> INFO: 41600 events read in total (3417ms).
[12:01:12.271] <TB1> INFO: Test took 4242ms.
[12:01:12.274] <TB1> INFO: scanning low vcal = 130
[12:01:12.539] <TB1> INFO: Expecting 41600 events.
[12:01:16.521] <TB1> INFO: 41600 events read in total (3425ms).
[12:01:16.522] <TB1> INFO: Test took 4248ms.
[12:01:16.526] <TB1> INFO: scanning low vcal = 140
[12:01:16.811] <TB1> INFO: Expecting 41600 events.
[12:01:20.781] <TB1> INFO: 41600 events read in total (3414ms).
[12:01:20.783] <TB1> INFO: Test took 4257ms.
[12:01:20.786] <TB1> INFO: scanning low vcal = 150
[12:01:21.057] <TB1> INFO: Expecting 41600 events.
[12:01:25.047] <TB1> INFO: 41600 events read in total (3433ms).
[12:01:25.049] <TB1> INFO: Test took 4263ms.
[12:01:25.052] <TB1> INFO: scanning low vcal = 160
[12:01:25.338] <TB1> INFO: Expecting 41600 events.
[12:01:29.287] <TB1> INFO: 41600 events read in total (3393ms).
[12:01:29.289] <TB1> INFO: Test took 4237ms.
[12:01:29.292] <TB1> INFO: scanning low vcal = 170
[12:01:29.556] <TB1> INFO: Expecting 41600 events.
[12:01:33.544] <TB1> INFO: 41600 events read in total (3432ms).
[12:01:33.545] <TB1> INFO: Test took 4253ms.
[12:01:33.551] <TB1> INFO: scanning low vcal = 180
[12:01:33.815] <TB1> INFO: Expecting 41600 events.
[12:01:37.799] <TB1> INFO: 41600 events read in total (3428ms).
[12:01:37.802] <TB1> INFO: Test took 4251ms.
[12:01:37.814] <TB1> INFO: scanning low vcal = 190
[12:01:38.095] <TB1> INFO: Expecting 41600 events.
[12:01:42.032] <TB1> INFO: 41600 events read in total (3380ms).
[12:01:42.033] <TB1> INFO: Test took 4219ms.
[12:01:42.036] <TB1> INFO: scanning low vcal = 200
[12:01:42.301] <TB1> INFO: Expecting 41600 events.
[12:01:46.229] <TB1> INFO: 41600 events read in total (3371ms).
[12:01:46.230] <TB1> INFO: Test took 4194ms.
[12:01:46.233] <TB1> INFO: scanning low vcal = 210
[12:01:46.494] <TB1> INFO: Expecting 41600 events.
[12:01:50.435] <TB1> INFO: 41600 events read in total (3385ms).
[12:01:50.436] <TB1> INFO: Test took 4203ms.
[12:01:50.439] <TB1> INFO: scanning low vcal = 220
[12:01:50.725] <TB1> INFO: Expecting 41600 events.
[12:01:54.645] <TB1> INFO: 41600 events read in total (3364ms).
[12:01:54.647] <TB1> INFO: Test took 4208ms.
[12:01:54.649] <TB1> INFO: scanning low vcal = 230
[12:01:54.931] <TB1> INFO: Expecting 41600 events.
[12:01:58.920] <TB1> INFO: 41600 events read in total (3433ms).
[12:01:58.922] <TB1> INFO: Test took 4273ms.
[12:01:58.935] <TB1> INFO: scanning low vcal = 240
[12:01:59.201] <TB1> INFO: Expecting 41600 events.
[12:02:03.161] <TB1> INFO: 41600 events read in total (3403ms).
[12:02:03.163] <TB1> INFO: Test took 4228ms.
[12:02:03.166] <TB1> INFO: scanning low vcal = 250
[12:02:03.429] <TB1> INFO: Expecting 41600 events.
[12:02:07.401] <TB1> INFO: 41600 events read in total (3415ms).
[12:02:07.403] <TB1> INFO: Test took 4237ms.
[12:02:07.407] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[12:02:07.668] <TB1> INFO: Expecting 41600 events.
[12:02:11.606] <TB1> INFO: 41600 events read in total (3382ms).
[12:02:11.608] <TB1> INFO: Test took 4201ms.
[12:02:11.610] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[12:02:11.870] <TB1> INFO: Expecting 41600 events.
[12:02:15.833] <TB1> INFO: 41600 events read in total (3406ms).
[12:02:15.834] <TB1> INFO: Test took 4223ms.
[12:02:15.838] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[12:02:16.103] <TB1> INFO: Expecting 41600 events.
[12:02:20.051] <TB1> INFO: 41600 events read in total (3392ms).
[12:02:20.052] <TB1> INFO: Test took 4214ms.
[12:02:20.055] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[12:02:20.334] <TB1> INFO: Expecting 41600 events.
[12:02:24.281] <TB1> INFO: 41600 events read in total (3391ms).
[12:02:24.283] <TB1> INFO: Test took 4227ms.
[12:02:24.286] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[12:02:24.551] <TB1> INFO: Expecting 41600 events.
[12:02:28.487] <TB1> INFO: 41600 events read in total (3380ms).
[12:02:28.489] <TB1> INFO: Test took 4203ms.
[12:02:28.981] <TB1> INFO: PixTestGainPedestal::measure() done
[12:03:02.596] <TB1> INFO: PixTestGainPedestal::fit() done
[12:03:02.596] <TB1> INFO: non-linearity mean: 0.953 0.945 0.957 0.958 0.951 0.962 0.960 0.954 0.949 0.962 0.945 0.956 0.955 0.955 0.959 0.956
[12:03:02.596] <TB1> INFO: non-linearity RMS: 0.007 0.007 0.007 0.006 0.008 0.004 0.006 0.009 0.007 0.006 0.007 0.007 0.008 0.007 0.006 0.007
[12:03:02.596] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[12:03:02.615] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[12:03:02.634] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[12:03:02.655] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[12:03:02.675] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[12:03:02.693] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[12:03:02.711] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[12:03:02.730] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[12:03:02.749] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[12:03:02.767] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[12:03:02.786] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[12:03:02.805] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[12:03:02.823] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[12:03:02.843] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[12:03:02.863] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[12:03:02.882] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[12:03:02.900] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 159 seconds
[12:03:02.900] <TB1> INFO: Decoding statistics:
[12:03:02.900] <TB1> INFO: General information:
[12:03:02.900] <TB1> INFO: 16bit words read: 2662320
[12:03:02.900] <TB1> INFO: valid events total: 166400
[12:03:02.900] <TB1> INFO: empty events: 0
[12:03:02.900] <TB1> INFO: valid events with pixels: 166400
[12:03:02.900] <TB1> INFO: valid pixel hits: 665560
[12:03:02.900] <TB1> INFO: Event errors: 0
[12:03:02.900] <TB1> INFO: start marker: 0
[12:03:02.900] <TB1> INFO: stop marker: 0
[12:03:02.900] <TB1> INFO: overflow: 0
[12:03:02.900] <TB1> INFO: invalid 5bit words: 0
[12:03:02.900] <TB1> INFO: invalid XOR eye diagram: 0
[12:03:02.900] <TB1> INFO: frame (failed synchr.): 0
[12:03:02.900] <TB1> INFO: idle data (no TBM trl): 0
[12:03:02.900] <TB1> INFO: no data (only TBM hdr): 0
[12:03:02.900] <TB1> INFO: TBM errors: 0
[12:03:02.900] <TB1> INFO: flawed TBM headers: 0
[12:03:02.900] <TB1> INFO: flawed TBM trailers: 0
[12:03:02.900] <TB1> INFO: event ID mismatches: 0
[12:03:02.900] <TB1> INFO: ROC errors: 0
[12:03:02.900] <TB1> INFO: missing ROC header(s): 0
[12:03:02.900] <TB1> INFO: misplaced readback start: 0
[12:03:02.900] <TB1> INFO: Pixel decoding errors: 0
[12:03:02.900] <TB1> INFO: pixel data incomplete: 0
[12:03:02.900] <TB1> INFO: pixel address: 0
[12:03:02.900] <TB1> INFO: pulse height fill bit: 0
[12:03:02.900] <TB1> INFO: buffer corruption: 0
[12:03:02.909] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C15.dat
[12:03:02.911] <TB1> INFO: ######################################################################
[12:03:02.911] <TB1> INFO: PixTestReadback::doTest()
[12:03:02.911] <TB1> INFO: ######################################################################
[12:03:02.911] <TB1> INFO: ----------------------------------------------------------------------
[12:03:02.911] <TB1> INFO: PixTestReadback::CalibrateVd()
[12:03:02.911] <TB1> INFO: ----------------------------------------------------------------------
[12:03:12.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C0.dat
[12:03:12.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C1.dat
[12:03:12.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C2.dat
[12:03:12.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C3.dat
[12:03:12.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C4.dat
[12:03:12.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C5.dat
[12:03:12.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C6.dat
[12:03:12.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C7.dat
[12:03:12.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C8.dat
[12:03:12.358] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C9.dat
[12:03:12.358] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C10.dat
[12:03:12.358] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C11.dat
[12:03:12.358] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C12.dat
[12:03:12.358] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C13.dat
[12:03:12.358] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C14.dat
[12:03:12.358] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C15.dat
[12:03:12.389] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[12:03:12.389] <TB1> INFO: ----------------------------------------------------------------------
[12:03:12.389] <TB1> INFO: PixTestReadback::CalibrateVa()
[12:03:12.389] <TB1> INFO: ----------------------------------------------------------------------
[12:03:21.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C0.dat
[12:03:21.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C1.dat
[12:03:21.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C2.dat
[12:03:21.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C3.dat
[12:03:21.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C4.dat
[12:03:21.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C5.dat
[12:03:21.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C6.dat
[12:03:21.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C7.dat
[12:03:21.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C8.dat
[12:03:21.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C9.dat
[12:03:21.892] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C10.dat
[12:03:21.892] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C11.dat
[12:03:21.892] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C12.dat
[12:03:21.892] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C13.dat
[12:03:21.892] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C14.dat
[12:03:21.892] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C15.dat
[12:03:21.922] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[12:03:21.922] <TB1> INFO: ----------------------------------------------------------------------
[12:03:21.922] <TB1> INFO: PixTestReadback::readbackVbg()
[12:03:21.922] <TB1> INFO: ----------------------------------------------------------------------
[12:03:29.206] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[12:03:29.206] <TB1> INFO: ----------------------------------------------------------------------
[12:03:29.206] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[12:03:29.206] <TB1> INFO: ----------------------------------------------------------------------
[12:03:29.206] <TB1> INFO: Vbg will be calibrated using Vd calibration
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 153.8calibrated Vbg = 1.20638 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 152.8calibrated Vbg = 1.20176 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 159.9calibrated Vbg = 1.20838 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 154.6calibrated Vbg = 1.21171 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 148.8calibrated Vbg = 1.21276 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152.2calibrated Vbg = 1.21654 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 151calibrated Vbg = 1.21718 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 162calibrated Vbg = 1.21956 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 148.9calibrated Vbg = 1.21732 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 138.7calibrated Vbg = 1.22027 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 159.9calibrated Vbg = 1.21985 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 153.8calibrated Vbg = 1.217 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 158.4calibrated Vbg = 1.20423 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 160.8calibrated Vbg = 1.20774 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 146.3calibrated Vbg = 1.20627 :::*/*/*/*/
[12:03:29.206] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 162.2calibrated Vbg = 1.20723 :::*/*/*/*/
[12:03:29.209] <TB1> INFO: ----------------------------------------------------------------------
[12:03:29.209] <TB1> INFO: PixTestReadback::CalibrateIa()
[12:03:29.209] <TB1> INFO: ----------------------------------------------------------------------
[12:06:04.537] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C0.dat
[12:06:04.537] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C1.dat
[12:06:04.537] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C2.dat
[12:06:04.537] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C3.dat
[12:06:04.537] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C4.dat
[12:06:04.538] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C5.dat
[12:06:04.538] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C6.dat
[12:06:04.538] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C7.dat
[12:06:04.538] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C8.dat
[12:06:04.538] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C9.dat
[12:06:04.538] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C10.dat
[12:06:04.538] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C11.dat
[12:06:04.538] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C12.dat
[12:06:04.538] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C13.dat
[12:06:04.538] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C14.dat
[12:06:04.538] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-04_11h02m_1475571737//002_FulltestPxar_p17//readbackCal_C15.dat
[12:06:04.569] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[12:06:04.570] <TB1> INFO: PixTestReadback::doTest() done
[12:06:04.570] <TB1> INFO: Decoding statistics:
[12:06:04.570] <TB1> INFO: General information:
[12:06:04.570] <TB1> INFO: 16bit words read: 1024
[12:06:04.570] <TB1> INFO: valid events total: 128
[12:06:04.570] <TB1> INFO: empty events: 128
[12:06:04.570] <TB1> INFO: valid events with pixels: 0
[12:06:04.570] <TB1> INFO: valid pixel hits: 0
[12:06:04.570] <TB1> INFO: Event errors: 0
[12:06:04.570] <TB1> INFO: start marker: 0
[12:06:04.570] <TB1> INFO: stop marker: 0
[12:06:04.570] <TB1> INFO: overflow: 0
[12:06:04.570] <TB1> INFO: invalid 5bit words: 0
[12:06:04.570] <TB1> INFO: invalid XOR eye diagram: 0
[12:06:04.570] <TB1> INFO: frame (failed synchr.): 0
[12:06:04.570] <TB1> INFO: idle data (no TBM trl): 0
[12:06:04.570] <TB1> INFO: no data (only TBM hdr): 0
[12:06:04.570] <TB1> INFO: TBM errors: 0
[12:06:04.570] <TB1> INFO: flawed TBM headers: 0
[12:06:04.570] <TB1> INFO: flawed TBM trailers: 0
[12:06:04.570] <TB1> INFO: event ID mismatches: 0
[12:06:04.570] <TB1> INFO: ROC errors: 0
[12:06:04.570] <TB1> INFO: missing ROC header(s): 0
[12:06:04.570] <TB1> INFO: misplaced readback start: 0
[12:06:04.570] <TB1> INFO: Pixel decoding errors: 0
[12:06:04.570] <TB1> INFO: pixel data incomplete: 0
[12:06:04.570] <TB1> INFO: pixel address: 0
[12:06:04.570] <TB1> INFO: pulse height fill bit: 0
[12:06:04.570] <TB1> INFO: buffer corruption: 0
[12:06:04.590] <TB1> INFO: Decoding statistics:
[12:06:04.590] <TB1> INFO: General information:
[12:06:04.590] <TB1> INFO: 16bit words read: 2750270
[12:06:04.590] <TB1> INFO: valid events total: 176768
[12:06:04.590] <TB1> INFO: empty events: 7865
[12:06:04.590] <TB1> INFO: valid events with pixels: 168903
[12:06:04.590] <TB1> INFO: valid pixel hits: 668063
[12:06:04.590] <TB1> INFO: Event errors: 0
[12:06:04.590] <TB1> INFO: start marker: 0
[12:06:04.590] <TB1> INFO: stop marker: 0
[12:06:04.590] <TB1> INFO: overflow: 0
[12:06:04.590] <TB1> INFO: invalid 5bit words: 0
[12:06:04.590] <TB1> INFO: invalid XOR eye diagram: 0
[12:06:04.590] <TB1> INFO: frame (failed synchr.): 0
[12:06:04.590] <TB1> INFO: idle data (no TBM trl): 0
[12:06:04.590] <TB1> INFO: no data (only TBM hdr): 0
[12:06:04.590] <TB1> INFO: TBM errors: 0
[12:06:04.590] <TB1> INFO: flawed TBM headers: 0
[12:06:04.590] <TB1> INFO: flawed TBM trailers: 0
[12:06:04.590] <TB1> INFO: event ID mismatches: 0
[12:06:04.590] <TB1> INFO: ROC errors: 0
[12:06:04.590] <TB1> INFO: missing ROC header(s): 0
[12:06:04.590] <TB1> INFO: misplaced readback start: 0
[12:06:04.590] <TB1> INFO: Pixel decoding errors: 0
[12:06:04.590] <TB1> INFO: pixel data incomplete: 0
[12:06:04.590] <TB1> INFO: pixel address: 0
[12:06:04.590] <TB1> INFO: pulse height fill bit: 0
[12:06:04.590] <TB1> INFO: buffer corruption: 0
[12:06:04.590] <TB1> INFO: enter test to run
[12:06:04.590] <TB1> INFO: test: exit no parameter change
[12:06:04.768] <TB1> QUIET: Connection to board 153 closed.
[12:06:04.848] <TB1> INFO: pXar: this is the end, my friend