[07:34:30.506] <TB2> INFO: *** Welcome to pxar ***
[07:34:30.506] <TB2> INFO: *** Today: 2016/10/07
[07:34:30.636] <TB2> INFO: *** Version: 0b00-dirty
[07:34:30.636] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C15.dat
[07:34:30.637] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:34:30.638] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//defaultMaskFile.dat
[07:34:30.638] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters_C15.dat
[07:34:30.731] <TB2> INFO: clk: 4
[07:34:30.731] <TB2> INFO: ctr: 4
[07:34:30.731] <TB2> INFO: sda: 19
[07:34:30.731] <TB2> INFO: tin: 9
[07:34:30.731] <TB2> INFO: level: 15
[07:34:30.731] <TB2> INFO: triggerdelay: 0
[07:34:30.731] <TB2> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[07:34:30.731] <TB2> INFO: Log level: INFO
[07:34:30.740] <TB2> INFO: Found DTB DTB_WXC55Z
[07:34:30.748] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[07:34:30.750] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 156
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXC55Z
MAC address: 40D85511809C
Hostname: pixelDTB156
Comment:
------------------------------------------------------
[07:34:30.751] <TB2> INFO: RPC call hashes of host and DTB match: 486171790
[07:34:32.268] <TB2> INFO: DUT info:
[07:34:32.268] <TB2> INFO: The DUT currently contains the following objects:
[07:34:32.268] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[07:34:32.268] <TB2> INFO: TBM Core alpha (0): 7 registers set
[07:34:32.268] <TB2> INFO: TBM Core beta (1): 7 registers set
[07:34:32.268] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:34:32.268] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.268] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.268] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.268] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.268] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.268] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.268] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.268] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.268] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.268] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.268] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.269] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.269] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.269] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.269] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.269] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:32.670] <TB2> INFO: enter 'restricted' command line mode
[07:34:32.670] <TB2> INFO: enter test to run
[07:34:32.670] <TB2> INFO: test: pretest no parameter change
[07:34:32.670] <TB2> INFO: running: pretest
[07:34:32.675] <TB2> INFO: ----------------------------------------------------------------------
[07:34:32.675] <TB2> INFO: PixTestPretest::programROC()
[07:34:32.675] <TB2> INFO: ----------------------------------------------------------------------
[07:34:50.690] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:34:50.690] <TB2> INFO: IA differences per ROC: 20.9 17.7 17.7 18.5 16.1 20.1 18.5 16.9 18.5 20.1 17.7 19.3 20.9 19.3 19.3 20.9
[07:34:50.803] <TB2> INFO: enter test to run
[07:34:50.803] <TB2> INFO: test: pretest no parameter change
[07:34:50.803] <TB2> INFO: running: pretest
[07:34:50.804] <TB2> INFO: ----------------------------------------------------------------------
[07:34:50.804] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:34:50.804] <TB2> INFO: ----------------------------------------------------------------------
[07:35:12.082] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 382.7 mA = 23.9187 mA/ROC
[07:35:12.082] <TB2> INFO: i(loss) [mA/ROC]: 19.3 18.5 19.3 19.3 19.3 18.5 19.3 18.5 18.5 19.3 18.5 19.3 18.5 18.5 18.5 19.3
[07:35:12.116] <TB2> INFO: enter test to run
[07:35:12.116] <TB2> INFO: test: pretest no parameter change
[07:35:12.117] <TB2> INFO: running: pretest
[07:35:12.117] <TB2> INFO: ----------------------------------------------------------------------
[07:35:12.117] <TB2> INFO: PixTestPretest::findTiming()
[07:35:12.117] <TB2> INFO: ----------------------------------------------------------------------
[07:35:12.117] <TB2> INFO: PixTestCmd::init()
[07:35:12.952] <TB2> WARNING: Not unmasking DUT, not setting Calibrate bits!
[07:36:42.985] <TB2> INFO: TBM phases: 160MHz: 0, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[07:36:42.985] <TB2> INFO: (success/tries = 100/100), width = 5
[07:36:42.985] <TB2> INFO: enter test to run
[07:36:42.985] <TB2> INFO: test: pretest no parameter change
[07:36:42.985] <TB2> INFO: running: pretest
[07:36:42.986] <TB2> INFO: ----------------------------------------------------------------------
[07:36:42.986] <TB2> INFO: PixTestPretest::findWorkingPixel()
[07:36:42.986] <TB2> INFO: ----------------------------------------------------------------------
[07:36:43.076] <TB2> INFO: Expecting 231680 events.
[07:36:48.678] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[07:36:48.804] <TB2> ERROR: <datapipe.cc/CheckEventID:L485> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[07:36:52.317] <TB2> INFO: 231680 events read in total (8685ms).
[07:36:52.323] <TB2> INFO: Test took 9334ms.
[07:36:52.530] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[07:36:52.596] <TB2> INFO: enter test to run
[07:36:52.596] <TB2> INFO: test: pretest no parameter change
[07:36:52.596] <TB2> INFO: running: pretest
[07:36:52.598] <TB2> INFO: ----------------------------------------------------------------------
[07:36:52.598] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[07:36:52.598] <TB2> INFO: ----------------------------------------------------------------------
[07:36:52.691] <TB2> INFO: Expecting 231680 events.
[07:37:01.867] <TB2> INFO: 231680 events read in total (8620ms).
[07:37:01.873] <TB2> INFO: Test took 9271ms.
[07:37:02.081] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[07:37:02.081] <TB2> INFO: CalDel: 159 156 148 143 140 153 143 149 130 161 134 168 145 155 174 153
[07:37:02.081] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[07:37:02.115] <TB2> INFO: enter test to run
[07:37:02.115] <TB2> INFO: test: pretest no parameter change
[07:37:02.115] <TB2> INFO: running: pretest
[07:37:02.115] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C0.dat
[07:37:02.115] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C1.dat
[07:37:02.115] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C2.dat
[07:37:02.115] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C3.dat
[07:37:02.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C4.dat
[07:37:02.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C5.dat
[07:37:02.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C6.dat
[07:37:02.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C7.dat
[07:37:02.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C8.dat
[07:37:02.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C9.dat
[07:37:02.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C10.dat
[07:37:02.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C11.dat
[07:37:02.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C12.dat
[07:37:02.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C13.dat
[07:37:02.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C14.dat
[07:37:02.117] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C15.dat
[07:37:02.117] <TB2> INFO: enter test to run
[07:37:02.117] <TB2> INFO: test: fulltest no parameter change
[07:37:02.117] <TB2> INFO: running: fulltest
[07:37:02.117] <TB2> INFO: ######################################################################
[07:37:02.117] <TB2> INFO: PixTestFullTest::doTest()
[07:37:02.117] <TB2> INFO: ######################################################################
[07:37:02.118] <TB2> INFO: ######################################################################
[07:37:02.118] <TB2> INFO: PixTestAlive::doTest()
[07:37:02.118] <TB2> INFO: ######################################################################
[07:37:02.119] <TB2> INFO: ----------------------------------------------------------------------
[07:37:02.119] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:37:02.119] <TB2> INFO: ----------------------------------------------------------------------
[07:37:02.384] <TB2> INFO: Expecting 41600 events.
[07:37:05.802] <TB2> INFO: 41600 events read in total (2862ms).
[07:37:05.802] <TB2> INFO: Test took 3682ms.
[07:37:06.015] <TB2> INFO: PixTestAlive::aliveTest() done
[07:37:06.015] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:37:06.016] <TB2> INFO: ----------------------------------------------------------------------
[07:37:06.017] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:37:06.017] <TB2> INFO: ----------------------------------------------------------------------
[07:37:06.285] <TB2> INFO: Expecting 41600 events.
[07:37:08.992] <TB2> INFO: 41600 events read in total (2151ms).
[07:37:08.992] <TB2> INFO: Test took 2974ms.
[07:37:08.992] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:37:09.226] <TB2> INFO: PixTestAlive::maskTest() done
[07:37:09.226] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:37:09.229] <TB2> INFO: ----------------------------------------------------------------------
[07:37:09.229] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:37:09.229] <TB2> INFO: ----------------------------------------------------------------------
[07:37:09.498] <TB2> INFO: Expecting 41600 events.
[07:37:12.898] <TB2> INFO: 41600 events read in total (2844ms).
[07:37:12.899] <TB2> INFO: Test took 3669ms.
[07:37:13.121] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[07:37:13.121] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:37:13.121] <TB2> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[07:37:13.121] <TB2> INFO: Decoding statistics:
[07:37:13.121] <TB2> INFO: General information:
[07:37:13.121] <TB2> INFO: 16bit words read: 0
[07:37:13.121] <TB2> INFO: valid events total: 0
[07:37:13.121] <TB2> INFO: empty events: 0
[07:37:13.121] <TB2> INFO: valid events with pixels: 0
[07:37:13.121] <TB2> INFO: valid pixel hits: 0
[07:37:13.121] <TB2> INFO: Event errors: 0
[07:37:13.121] <TB2> INFO: start marker: 0
[07:37:13.121] <TB2> INFO: stop marker: 0
[07:37:13.121] <TB2> INFO: overflow: 0
[07:37:13.121] <TB2> INFO: invalid 5bit words: 0
[07:37:13.121] <TB2> INFO: invalid XOR eye diagram: 0
[07:37:13.121] <TB2> INFO: frame (failed synchr.): 0
[07:37:13.121] <TB2> INFO: idle data (no TBM trl): 0
[07:37:13.121] <TB2> INFO: no data (only TBM hdr): 0
[07:37:13.121] <TB2> INFO: TBM errors: 0
[07:37:13.121] <TB2> INFO: flawed TBM headers: 0
[07:37:13.121] <TB2> INFO: flawed TBM trailers: 0
[07:37:13.121] <TB2> INFO: event ID mismatches: 0
[07:37:13.121] <TB2> INFO: ROC errors: 0
[07:37:13.121] <TB2> INFO: missing ROC header(s): 0
[07:37:13.121] <TB2> INFO: misplaced readback start: 0
[07:37:13.121] <TB2> INFO: Pixel decoding errors: 0
[07:37:13.121] <TB2> INFO: pixel data incomplete: 0
[07:37:13.122] <TB2> INFO: pixel address: 0
[07:37:13.122] <TB2> INFO: pulse height fill bit: 0
[07:37:13.122] <TB2> INFO: buffer corruption: 0
[07:37:13.128] <TB2> INFO: ######################################################################
[07:37:13.128] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[07:37:13.128] <TB2> INFO: ######################################################################
[07:37:13.130] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[07:37:13.141] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:37:13.141] <TB2> INFO: run 1 of 1
[07:37:13.411] <TB2> INFO: Expecting 3120000 events.
[07:37:50.427] <TB2> INFO: 849100 events read in total (36460ms).
[07:38:27.258] <TB2> INFO: 1688795 events read in total (73292ms).
[07:39:05.821] <TB2> INFO: 2545215 events read in total (111854ms).
[07:39:31.565] <TB2> INFO: 3120000 events read in total (137598ms).
[07:39:31.644] <TB2> INFO: Test took 138502ms.
[07:39:55.251] <TB2> INFO: PixTestBBMap::doTest() done, duration: 162 seconds
[07:39:55.251] <TB2> INFO: number of dead bumps (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 3 0 2 0
[07:39:55.251] <TB2> INFO: separation cut (per ROC): 84 80 76 71 71 77 83 70 77 70 70 85 77 71 69 70
[07:39:55.251] <TB2> INFO: Decoding statistics:
[07:39:55.251] <TB2> INFO: General information:
[07:39:55.251] <TB2> INFO: 16bit words read: 0
[07:39:55.251] <TB2> INFO: valid events total: 0
[07:39:55.251] <TB2> INFO: empty events: 0
[07:39:55.251] <TB2> INFO: valid events with pixels: 0
[07:39:55.251] <TB2> INFO: valid pixel hits: 0
[07:39:55.251] <TB2> INFO: Event errors: 0
[07:39:55.251] <TB2> INFO: start marker: 0
[07:39:55.251] <TB2> INFO: stop marker: 0
[07:39:55.251] <TB2> INFO: overflow: 0
[07:39:55.251] <TB2> INFO: invalid 5bit words: 0
[07:39:55.251] <TB2> INFO: invalid XOR eye diagram: 0
[07:39:55.251] <TB2> INFO: frame (failed synchr.): 0
[07:39:55.251] <TB2> INFO: idle data (no TBM trl): 0
[07:39:55.251] <TB2> INFO: no data (only TBM hdr): 0
[07:39:55.251] <TB2> INFO: TBM errors: 0
[07:39:55.251] <TB2> INFO: flawed TBM headers: 0
[07:39:55.251] <TB2> INFO: flawed TBM trailers: 0
[07:39:55.251] <TB2> INFO: event ID mismatches: 0
[07:39:55.251] <TB2> INFO: ROC errors: 0
[07:39:55.251] <TB2> INFO: missing ROC header(s): 0
[07:39:55.251] <TB2> INFO: misplaced readback start: 0
[07:39:55.251] <TB2> INFO: Pixel decoding errors: 0
[07:39:55.251] <TB2> INFO: pixel data incomplete: 0
[07:39:55.251] <TB2> INFO: pixel address: 0
[07:39:55.251] <TB2> INFO: pulse height fill bit: 0
[07:39:55.251] <TB2> INFO: buffer corruption: 0
[07:39:55.336] <TB2> INFO: ######################################################################
[07:39:55.336] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:39:55.336] <TB2> INFO: ######################################################################
[07:39:55.336] <TB2> INFO: ----------------------------------------------------------------------
[07:39:55.336] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:39:55.336] <TB2> INFO: ----------------------------------------------------------------------
[07:39:55.336] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[07:39:55.347] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:39:55.347] <TB2> INFO: run 1 of 1
[07:39:55.649] <TB2> INFO: Expecting 36608000 events.
[07:40:24.073] <TB2> INFO: 807800 events read in total (27868ms).
[07:40:51.827] <TB2> INFO: 1601350 events read in total (55622ms).
[07:41:19.547] <TB2> INFO: 2398250 events read in total (83342ms).
[07:41:47.933] <TB2> INFO: 3191800 events read in total (111728ms).
[07:42:16.109] <TB2> INFO: 3985350 events read in total (139904ms).
[07:42:44.016] <TB2> INFO: 4778650 events read in total (167811ms).
[07:43:12.184] <TB2> INFO: 5570500 events read in total (195979ms).
[07:43:40.125] <TB2> INFO: 6366100 events read in total (223920ms).
[07:44:08.048] <TB2> INFO: 7158450 events read in total (251843ms).
[07:44:36.296] <TB2> INFO: 7951550 events read in total (280091ms).
[07:45:03.956] <TB2> INFO: 8744250 events read in total (307751ms).
[07:45:32.012] <TB2> INFO: 9536800 events read in total (335807ms).
[07:45:59.913] <TB2> INFO: 10329300 events read in total (363708ms).
[07:46:27.806] <TB2> INFO: 11121550 events read in total (391601ms).
[07:46:55.508] <TB2> INFO: 11913450 events read in total (419303ms).
[07:47:23.620] <TB2> INFO: 12706850 events read in total (447415ms).
[07:47:51.545] <TB2> INFO: 13499200 events read in total (475340ms).
[07:48:19.374] <TB2> INFO: 14289950 events read in total (503169ms).
[07:48:47.457] <TB2> INFO: 15081150 events read in total (531252ms).
[07:49:15.454] <TB2> INFO: 15871950 events read in total (559249ms).
[07:49:43.652] <TB2> INFO: 16663600 events read in total (587447ms).
[07:50:11.609] <TB2> INFO: 17454400 events read in total (615404ms).
[07:50:39.388] <TB2> INFO: 18243550 events read in total (643183ms).
[07:51:07.076] <TB2> INFO: 19030400 events read in total (670871ms).
[07:51:34.927] <TB2> INFO: 19816750 events read in total (698722ms).
[07:52:02.888] <TB2> INFO: 20603700 events read in total (726683ms).
[07:52:31.041] <TB2> INFO: 21389100 events read in total (754836ms).
[07:52:59.270] <TB2> INFO: 22174100 events read in total (783065ms).
[07:53:27.301] <TB2> INFO: 22958700 events read in total (811096ms).
[07:53:55.185] <TB2> INFO: 23744300 events read in total (838980ms).
[07:54:23.367] <TB2> INFO: 24529400 events read in total (867162ms).
[07:54:51.468] <TB2> INFO: 25314350 events read in total (895263ms).
[07:55:19.814] <TB2> INFO: 26099950 events read in total (923609ms).
[07:55:47.709] <TB2> INFO: 26883900 events read in total (951504ms).
[07:56:16.323] <TB2> INFO: 27668900 events read in total (980118ms).
[07:56:44.515] <TB2> INFO: 28452600 events read in total (1008310ms).
[07:57:12.234] <TB2> INFO: 29236350 events read in total (1036029ms).
[07:57:40.341] <TB2> INFO: 30021150 events read in total (1064136ms).
[07:58:08.623] <TB2> INFO: 30804550 events read in total (1092418ms).
[07:58:37.008] <TB2> INFO: 31588000 events read in total (1120803ms).
[07:59:05.341] <TB2> INFO: 32371900 events read in total (1149136ms).
[07:59:33.237] <TB2> INFO: 33157400 events read in total (1177032ms).
[08:00:01.112] <TB2> INFO: 33941050 events read in total (1204907ms).
[08:00:29.077] <TB2> INFO: 34726350 events read in total (1232872ms).
[08:00:56.932] <TB2> INFO: 35511650 events read in total (1260727ms).
[08:01:25.999] <TB2> INFO: 36304250 events read in total (1289794ms).
[08:01:36.933] <TB2> INFO: 36608000 events read in total (1300728ms).
[08:01:36.992] <TB2> INFO: Test took 1301645ms.
[08:01:37.438] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:01:39.509] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:01:41.364] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:01:43.473] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:01:45.364] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:01:47.338] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:01:49.035] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:01:51.061] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:01:53.046] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:01:54.808] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:01:56.548] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:01:58.304] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:02:00.096] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:02:01.801] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:02:03.578] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:02:05.582] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:02:07.602] <TB2> INFO: PixTestScurves::scurves() done
[08:02:07.602] <TB2> INFO: Vcal mean: 89.65 84.35 79.71 79.06 77.22 78.08 86.28 78.20 91.51 75.91 77.10 87.53 84.55 77.75 76.70 81.10
[08:02:07.602] <TB2> INFO: Vcal RMS: 5.27 4.72 4.07 4.08 3.80 4.05 4.62 4.32 5.51 4.14 4.50 4.73 4.68 4.26 3.81 4.20
[08:02:07.602] <TB2> INFO: PixTestScurves::fullTest() done, duration: 1332 seconds
[08:02:07.602] <TB2> INFO: Decoding statistics:
[08:02:07.602] <TB2> INFO: General information:
[08:02:07.602] <TB2> INFO: 16bit words read: 0
[08:02:07.602] <TB2> INFO: valid events total: 0
[08:02:07.602] <TB2> INFO: empty events: 0
[08:02:07.602] <TB2> INFO: valid events with pixels: 0
[08:02:07.602] <TB2> INFO: valid pixel hits: 0
[08:02:07.602] <TB2> INFO: Event errors: 0
[08:02:07.602] <TB2> INFO: start marker: 0
[08:02:07.602] <TB2> INFO: stop marker: 0
[08:02:07.602] <TB2> INFO: overflow: 0
[08:02:07.602] <TB2> INFO: invalid 5bit words: 0
[08:02:07.602] <TB2> INFO: invalid XOR eye diagram: 0
[08:02:07.602] <TB2> INFO: frame (failed synchr.): 0
[08:02:07.602] <TB2> INFO: idle data (no TBM trl): 0
[08:02:07.602] <TB2> INFO: no data (only TBM hdr): 0
[08:02:07.602] <TB2> INFO: TBM errors: 0
[08:02:07.602] <TB2> INFO: flawed TBM headers: 0
[08:02:07.602] <TB2> INFO: flawed TBM trailers: 0
[08:02:07.602] <TB2> INFO: event ID mismatches: 0
[08:02:07.602] <TB2> INFO: ROC errors: 0
[08:02:07.602] <TB2> INFO: missing ROC header(s): 0
[08:02:07.602] <TB2> INFO: misplaced readback start: 0
[08:02:07.602] <TB2> INFO: Pixel decoding errors: 0
[08:02:07.602] <TB2> INFO: pixel data incomplete: 0
[08:02:07.602] <TB2> INFO: pixel address: 0
[08:02:07.603] <TB2> INFO: pulse height fill bit: 0
[08:02:07.603] <TB2> INFO: buffer corruption: 0
[08:02:07.672] <TB2> INFO: ######################################################################
[08:02:07.672] <TB2> INFO: PixTestTrim::doTest()
[08:02:07.672] <TB2> INFO: ######################################################################
[08:02:07.673] <TB2> INFO: ----------------------------------------------------------------------
[08:02:07.673] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[08:02:07.673] <TB2> INFO: ----------------------------------------------------------------------
[08:02:07.755] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[08:02:07.755] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:02:07.767] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:02:07.767] <TB2> INFO: run 1 of 1
[08:02:08.039] <TB2> INFO: Expecting 6281600 events.
[08:02:44.266] <TB2> INFO: 1095210 events read in total (35669ms).
[08:03:20.285] <TB2> INFO: 2183720 events read in total (71688ms).
[08:03:55.851] <TB2> INFO: 3264700 events read in total (107254ms).
[08:04:30.722] <TB2> INFO: 4351550 events read in total (142125ms).
[08:05:06.510] <TB2> INFO: 5441010 events read in total (177913ms).
[08:05:34.153] <TB2> INFO: 6281600 events read in total (205556ms).
[08:05:34.201] <TB2> INFO: Test took 206435ms.
[08:05:54.289] <TB2> INFO: ROC 0 VthrComp = 93
[08:05:54.289] <TB2> INFO: ROC 1 VthrComp = 88
[08:05:54.289] <TB2> INFO: ROC 2 VthrComp = 88
[08:05:54.289] <TB2> INFO: ROC 3 VthrComp = 86
[08:05:54.289] <TB2> INFO: ROC 4 VthrComp = 84
[08:05:54.290] <TB2> INFO: ROC 5 VthrComp = 86
[08:05:54.290] <TB2> INFO: ROC 6 VthrComp = 95
[08:05:54.290] <TB2> INFO: ROC 7 VthrComp = 83
[08:05:54.290] <TB2> INFO: ROC 8 VthrComp = 95
[08:05:54.290] <TB2> INFO: ROC 9 VthrComp = 84
[08:05:54.290] <TB2> INFO: ROC 10 VthrComp = 83
[08:05:54.290] <TB2> INFO: ROC 11 VthrComp = 98
[08:05:54.290] <TB2> INFO: ROC 12 VthrComp = 94
[08:05:54.290] <TB2> INFO: ROC 13 VthrComp = 85
[08:05:54.290] <TB2> INFO: ROC 14 VthrComp = 85
[08:05:54.290] <TB2> INFO: ROC 15 VthrComp = 87
[08:05:54.290] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[08:05:54.290] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:05:54.299] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:05:54.299] <TB2> INFO: run 1 of 1
[08:05:54.561] <TB2> INFO: Expecting 6281600 events.
[08:06:25.678] <TB2> INFO: 757240 events read in total (30561ms).
[08:06:55.842] <TB2> INFO: 1512360 events read in total (60725ms).
[08:07:26.408] <TB2> INFO: 2268210 events read in total (91291ms).
[08:07:56.935] <TB2> INFO: 3023790 events read in total (121818ms).
[08:08:28.092] <TB2> INFO: 3774710 events read in total (152975ms).
[08:08:59.597] <TB2> INFO: 4522450 events read in total (184480ms).
[08:09:30.869] <TB2> INFO: 5268360 events read in total (215752ms).
[08:10:03.947] <TB2> INFO: 6013900 events read in total (248830ms).
[08:10:15.391] <TB2> INFO: 6281600 events read in total (260274ms).
[08:10:15.440] <TB2> INFO: Test took 261141ms.
[08:10:39.279] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 58.6735 for pixel 6/23 mean/min/max = 45.5309/32.2462/58.8155
[08:10:39.280] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 58.7434 for pixel 19/10 mean/min/max = 46.1001/33.3577/58.8424
[08:10:39.280] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 56.6218 for pixel 12/34 mean/min/max = 44.879/33.0932/56.6649
[08:10:39.280] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 56.0339 for pixel 0/78 mean/min/max = 43.9115/31.7861/56.037
[08:10:39.280] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 55.7891 for pixel 14/45 mean/min/max = 44.1684/32.4458/55.891
[08:10:39.281] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 56.225 for pixel 6/25 mean/min/max = 44.2332/32.2244/56.242
[08:10:39.281] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 55.758 for pixel 12/0 mean/min/max = 44.1547/32.2581/56.0513
[08:10:39.281] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 57.5962 for pixel 9/0 mean/min/max = 44.8342/32.055/57.6135
[08:10:39.281] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 59.3999 for pixel 0/2 mean/min/max = 45.4335/31.3141/59.5529
[08:10:39.282] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 56.0001 for pixel 14/71 mean/min/max = 44.196/32.2584/56.1337
[08:10:39.282] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 58.3481 for pixel 12/11 mean/min/max = 44.9532/31.4613/58.4451
[08:10:39.282] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 55.9099 for pixel 18/2 mean/min/max = 43.9656/31.7782/56.1531
[08:10:39.282] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 57.3121 for pixel 51/4 mean/min/max = 45.0632/32.7036/57.4228
[08:10:39.283] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 57.2121 for pixel 17/2 mean/min/max = 44.3736/31.4672/57.28
[08:10:39.283] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 54.6912 for pixel 7/8 mean/min/max = 43.4259/31.9424/54.9094
[08:10:39.283] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 56.6428 for pixel 22/0 mean/min/max = 44.2356/31.7354/56.7357
[08:10:39.284] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:10:39.372] <TB2> INFO: Expecting 514560 events.
[08:10:50.423] <TB2> INFO: 514560 events read in total (10495ms).
[08:10:50.429] <TB2> INFO: Expecting 514560 events.
[08:11:01.194] <TB2> INFO: 514560 events read in total (10363ms).
[08:11:01.203] <TB2> INFO: Expecting 514560 events.
[08:11:12.070] <TB2> INFO: 514560 events read in total (10466ms).
[08:11:12.083] <TB2> INFO: Expecting 514560 events.
[08:11:22.845] <TB2> INFO: 514560 events read in total (10360ms).
[08:11:22.865] <TB2> INFO: Expecting 514560 events.
[08:11:33.666] <TB2> INFO: 514560 events read in total (10400ms).
[08:11:33.683] <TB2> INFO: Expecting 514560 events.
[08:11:44.532] <TB2> INFO: 514560 events read in total (10447ms).
[08:11:44.553] <TB2> INFO: Expecting 514560 events.
[08:11:55.317] <TB2> INFO: 514560 events read in total (10362ms).
[08:11:55.339] <TB2> INFO: Expecting 514560 events.
[08:12:06.049] <TB2> INFO: 514560 events read in total (10308ms).
[08:12:06.074] <TB2> INFO: Expecting 514560 events.
[08:12:16.859] <TB2> INFO: 514560 events read in total (10383ms).
[08:12:16.886] <TB2> INFO: Expecting 514560 events.
[08:12:27.761] <TB2> INFO: 514560 events read in total (10473ms).
[08:12:27.798] <TB2> INFO: Expecting 514560 events.
[08:12:38.564] <TB2> INFO: 514560 events read in total (10365ms).
[08:12:38.597] <TB2> INFO: Expecting 514560 events.
[08:12:49.397] <TB2> INFO: 514560 events read in total (10398ms).
[08:12:49.434] <TB2> INFO: Expecting 514560 events.
[08:13:00.216] <TB2> INFO: 514560 events read in total (10380ms).
[08:13:00.256] <TB2> INFO: Expecting 514560 events.
[08:13:11.011] <TB2> INFO: 514560 events read in total (10353ms).
[08:13:11.052] <TB2> INFO: Expecting 514560 events.
[08:13:21.743] <TB2> INFO: 514560 events read in total (10289ms).
[08:13:21.786] <TB2> INFO: Expecting 514560 events.
[08:13:32.588] <TB2> INFO: 514560 events read in total (10400ms).
[08:13:32.638] <TB2> INFO: Test took 173355ms.
[08:13:33.756] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:13:33.764] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:13:33.764] <TB2> INFO: run 1 of 1
[08:13:34.030] <TB2> INFO: Expecting 6281600 events.
[08:14:05.210] <TB2> INFO: 745670 events read in total (30623ms).
[08:14:35.507] <TB2> INFO: 1489990 events read in total (60920ms).
[08:15:05.755] <TB2> INFO: 2234690 events read in total (91168ms).
[08:15:36.080] <TB2> INFO: 2978700 events read in total (121493ms).
[08:16:06.517] <TB2> INFO: 3718390 events read in total (151931ms).
[08:16:36.678] <TB2> INFO: 4454290 events read in total (182091ms).
[08:17:06.922] <TB2> INFO: 5188050 events read in total (212335ms).
[08:17:37.711] <TB2> INFO: 5921340 events read in total (243124ms).
[08:17:52.924] <TB2> INFO: 6281600 events read in total (258337ms).
[08:17:52.981] <TB2> INFO: Test took 259217ms.
[08:18:16.641] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 4.500000 .. 49.262640
[08:18:16.903] <TB2> INFO: Expecting 208000 events.
[08:18:26.800] <TB2> INFO: 208000 events read in total (9333ms).
[08:18:26.800] <TB2> INFO: Test took 10158ms.
[08:18:26.898] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 4 .. 59 (-1/-1) hits flags = 528 (plus default)
[08:18:26.907] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:18:26.907] <TB2> INFO: run 1 of 1
[08:18:27.167] <TB2> INFO: Expecting 2329600 events.
[08:19:01.521] <TB2> INFO: 938490 events read in total (33798ms).
[08:19:35.214] <TB2> INFO: 1867390 events read in total (67491ms).
[08:19:52.072] <TB2> INFO: 2329600 events read in total (84349ms).
[08:19:52.096] <TB2> INFO: Test took 85189ms.
[08:20:06.387] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 21.036926 .. 43.783278
[08:20:06.657] <TB2> INFO: Expecting 208000 events.
[08:20:16.684] <TB2> INFO: 208000 events read in total (9471ms).
[08:20:16.684] <TB2> INFO: Test took 10296ms.
[08:20:16.808] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 11 .. 53 (-1/-1) hits flags = 528 (plus default)
[08:20:16.819] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:20:16.819] <TB2> INFO: run 1 of 1
[08:20:17.116] <TB2> INFO: Expecting 1788800 events.
[08:20:51.202] <TB2> INFO: 937340 events read in total (33530ms).
[08:21:22.136] <TB2> INFO: 1788800 events read in total (64465ms).
[08:21:22.156] <TB2> INFO: Test took 65337ms.
[08:21:36.079] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 21.514855 .. 40.574557
[08:21:36.393] <TB2> INFO: Expecting 208000 events.
[08:21:46.764] <TB2> INFO: 208000 events read in total (9815ms).
[08:21:46.765] <TB2> INFO: Test took 10684ms.
[08:21:46.852] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 11 .. 50 (-1/-1) hits flags = 528 (plus default)
[08:21:46.860] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:21:46.860] <TB2> INFO: run 1 of 1
[08:21:47.126] <TB2> INFO: Expecting 1664000 events.
[08:22:22.153] <TB2> INFO: 967470 events read in total (34471ms).
[08:22:47.447] <TB2> INFO: 1664000 events read in total (59765ms).
[08:22:47.469] <TB2> INFO: Test took 60609ms.
[08:23:01.891] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 23.572009 .. 40.353541
[08:23:02.193] <TB2> INFO: Expecting 208000 events.
[08:23:12.730] <TB2> INFO: 208000 events read in total (9981ms).
[08:23:12.731] <TB2> INFO: Test took 10838ms.
[08:23:12.817] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 13 .. 50 (-1/-1) hits flags = 528 (plus default)
[08:23:12.826] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:23:12.827] <TB2> INFO: run 1 of 1
[08:23:13.088] <TB2> INFO: Expecting 1580800 events.
[08:23:48.437] <TB2> INFO: 955520 events read in total (34793ms).
[08:24:11.572] <TB2> INFO: 1580800 events read in total (57928ms).
[08:24:11.589] <TB2> INFO: Test took 58762ms.
[08:24:25.336] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[08:24:25.336] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:24:25.344] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:24:25.344] <TB2> INFO: run 1 of 1
[08:24:25.608] <TB2> INFO: Expecting 1705600 events.
[08:25:00.032] <TB2> INFO: 894170 events read in total (33868ms).
[08:25:30.943] <TB2> INFO: 1705600 events read in total (64779ms).
[08:25:30.961] <TB2> INFO: Test took 65617ms.
[08:25:45.082] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:25:45.083] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:25:45.083] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:25:45.083] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:25:45.083] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:25:45.083] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:25:45.083] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:25:45.083] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:25:45.084] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:25:45.084] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:25:45.084] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:25:45.084] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:25:45.084] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:25:45.084] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:25:45.085] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:25:45.085] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:25:45.085] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C0.dat
[08:25:45.095] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C1.dat
[08:25:45.102] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C2.dat
[08:25:45.109] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C3.dat
[08:25:45.115] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C4.dat
[08:25:45.122] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C5.dat
[08:25:45.130] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C6.dat
[08:25:45.142] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C7.dat
[08:25:45.153] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C8.dat
[08:25:45.165] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C9.dat
[08:25:45.177] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C10.dat
[08:25:45.189] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C11.dat
[08:25:45.201] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C12.dat
[08:25:45.213] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C13.dat
[08:25:45.220] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C14.dat
[08:25:45.227] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C15.dat
[08:25:45.233] <TB2> INFO: PixTestTrim::trimTest() done
[08:25:45.233] <TB2> INFO: vtrim: 96 108 107 92 107 101 95 95 103 108 97 109 99 115 105 114
[08:25:45.233] <TB2> INFO: vthrcomp: 93 88 88 86 84 86 95 83 95 84 83 98 94 85 85 87
[08:25:45.233] <TB2> INFO: vcal mean: 34.99 34.93 34.97 34.95 34.96 34.97 35.03 34.94 34.97 34.96 34.96 34.99 34.96 34.91 34.96 34.97
[08:25:45.233] <TB2> INFO: vcal RMS: 0.84 0.84 0.78 0.83 0.79 0.79 0.79 0.81 0.84 0.78 0.80 0.83 0.77 0.85 0.80 0.85
[08:25:45.233] <TB2> INFO: bits mean: 9.31 9.41 9.56 9.89 10.04 9.93 9.63 9.70 9.32 10.09 9.81 10.04 9.38 10.04 10.34 10.06
[08:25:45.233] <TB2> INFO: bits RMS: 2.69 2.49 2.52 2.65 2.46 2.48 2.67 2.58 2.85 2.43 2.65 2.58 2.69 2.58 2.39 2.52
[08:25:45.240] <TB2> INFO: ----------------------------------------------------------------------
[08:25:45.240] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[08:25:45.240] <TB2> INFO: ----------------------------------------------------------------------
[08:25:45.242] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[08:25:45.251] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[08:25:45.251] <TB2> INFO: run 1 of 1
[08:25:45.547] <TB2> INFO: Expecting 4160000 events.
[08:26:23.262] <TB2> INFO: 906880 events read in total (37160ms).
[08:27:00.303] <TB2> INFO: 1807595 events read in total (74200ms).
[08:27:36.741] <TB2> INFO: 2701440 events read in total (110638ms).
[08:28:13.668] <TB2> INFO: 3591840 events read in total (147565ms).
[08:28:37.806] <TB2> INFO: 4160000 events read in total (171703ms).
[08:28:37.865] <TB2> INFO: Test took 172615ms.
[08:29:09.560] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:29:09.568] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[08:29:09.568] <TB2> INFO: run 1 of 1
[08:29:09.832] <TB2> INFO: Expecting 3140800 events.
[08:29:48.836] <TB2> INFO: 982165 events read in total (38448ms).
[08:30:26.950] <TB2> INFO: 1951880 events read in total (76562ms).
[08:31:05.986] <TB2> INFO: 2913080 events read in total (115598ms).
[08:31:15.153] <TB2> INFO: 3140800 events read in total (124765ms).
[08:31:15.200] <TB2> INFO: Test took 125631ms.
[08:31:38.677] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 139 (-1/-1) hits flags = 528 (plus default)
[08:31:38.686] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[08:31:38.686] <TB2> INFO: run 1 of 1
[08:31:38.960] <TB2> INFO: Expecting 2912000 events.
[08:32:18.728] <TB2> INFO: 1019190 events read in total (39212ms).
[08:32:57.878] <TB2> INFO: 2022255 events read in total (78362ms).
[08:33:32.642] <TB2> INFO: 2912000 events read in total (113126ms).
[08:33:32.685] <TB2> INFO: Test took 114000ms.
[08:33:57.972] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 138 (-1/-1) hits flags = 528 (plus default)
[08:33:57.980] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[08:33:57.980] <TB2> INFO: run 1 of 1
[08:33:58.242] <TB2> INFO: Expecting 2891200 events.
[08:34:38.451] <TB2> INFO: 1021880 events read in total (39653ms).
[08:35:17.356] <TB2> INFO: 2027105 events read in total (78558ms).
[08:35:51.167] <TB2> INFO: 2891200 events read in total (112369ms).
[08:35:51.210] <TB2> INFO: Test took 113231ms.
[08:36:18.431] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 138 (-1/-1) hits flags = 528 (plus default)
[08:36:18.442] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[08:36:18.442] <TB2> INFO: run 1 of 1
[08:36:18.785] <TB2> INFO: Expecting 2891200 events.
[08:36:58.527] <TB2> INFO: 1021115 events read in total (39186ms).
[08:37:37.428] <TB2> INFO: 2025695 events read in total (78087ms).
[08:38:10.990] <TB2> INFO: 2891200 events read in total (111649ms).
[08:38:11.039] <TB2> INFO: Test took 112598ms.
[08:38:37.659] <TB2> INFO: PixTestTrim::trimBitTest() done
[08:38:37.660] <TB2> INFO: PixTestTrim::doTest() done, duration: 2189 seconds
[08:38:37.660] <TB2> INFO: Decoding statistics:
[08:38:37.660] <TB2> INFO: General information:
[08:38:37.660] <TB2> INFO: 16bit words read: 0
[08:38:37.660] <TB2> INFO: valid events total: 0
[08:38:37.660] <TB2> INFO: empty events: 0
[08:38:37.660] <TB2> INFO: valid events with pixels: 0
[08:38:37.660] <TB2> INFO: valid pixel hits: 0
[08:38:37.660] <TB2> INFO: Event errors: 0
[08:38:37.660] <TB2> INFO: start marker: 0
[08:38:37.661] <TB2> INFO: stop marker: 0
[08:38:37.661] <TB2> INFO: overflow: 0
[08:38:37.661] <TB2> INFO: invalid 5bit words: 0
[08:38:37.661] <TB2> INFO: invalid XOR eye diagram: 0
[08:38:37.661] <TB2> INFO: frame (failed synchr.): 0
[08:38:37.661] <TB2> INFO: idle data (no TBM trl): 0
[08:38:37.661] <TB2> INFO: no data (only TBM hdr): 0
[08:38:37.661] <TB2> INFO: TBM errors: 0
[08:38:37.661] <TB2> INFO: flawed TBM headers: 0
[08:38:37.661] <TB2> INFO: flawed TBM trailers: 0
[08:38:37.661] <TB2> INFO: event ID mismatches: 0
[08:38:37.661] <TB2> INFO: ROC errors: 0
[08:38:37.661] <TB2> INFO: missing ROC header(s): 0
[08:38:37.661] <TB2> INFO: misplaced readback start: 0
[08:38:37.661] <TB2> INFO: Pixel decoding errors: 0
[08:38:37.661] <TB2> INFO: pixel data incomplete: 0
[08:38:37.661] <TB2> INFO: pixel address: 0
[08:38:37.661] <TB2> INFO: pulse height fill bit: 0
[08:38:37.661] <TB2> INFO: buffer corruption: 0
[08:38:38.657] <TB2> INFO: ######################################################################
[08:38:38.657] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[08:38:38.657] <TB2> INFO: ######################################################################
[08:38:39.003] <TB2> INFO: Expecting 41600 events.
[08:38:42.438] <TB2> INFO: 41600 events read in total (2879ms).
[08:38:42.439] <TB2> INFO: Test took 3779ms.
[08:38:42.969] <TB2> INFO: Expecting 41600 events.
[08:38:46.766] <TB2> INFO: 41600 events read in total (3241ms).
[08:38:46.767] <TB2> INFO: Test took 4134ms.
[08:38:47.137] <TB2> INFO: Expecting 41600 events.
[08:38:50.911] <TB2> INFO: 41600 events read in total (3218ms).
[08:38:50.912] <TB2> INFO: Test took 4118ms.
[08:38:51.186] <TB2> INFO: Expecting 2560 events.
[08:38:52.044] <TB2> INFO: 2560 events read in total (300ms).
[08:38:52.044] <TB2> INFO: Test took 1113ms.
[08:38:52.349] <TB2> INFO: Expecting 2560 events.
[08:38:53.200] <TB2> INFO: 2560 events read in total (295ms).
[08:38:53.201] <TB2> INFO: Test took 1157ms.
[08:38:53.505] <TB2> INFO: Expecting 2560 events.
[08:38:54.361] <TB2> INFO: 2560 events read in total (299ms).
[08:38:54.361] <TB2> INFO: Test took 1160ms.
[08:38:54.666] <TB2> INFO: Expecting 2560 events.
[08:38:55.521] <TB2> INFO: 2560 events read in total (299ms).
[08:38:55.521] <TB2> INFO: Test took 1160ms.
[08:38:55.829] <TB2> INFO: Expecting 2560 events.
[08:38:56.685] <TB2> INFO: 2560 events read in total (300ms).
[08:38:56.686] <TB2> INFO: Test took 1164ms.
[08:38:56.990] <TB2> INFO: Expecting 2560 events.
[08:38:57.845] <TB2> INFO: 2560 events read in total (299ms).
[08:38:57.845] <TB2> INFO: Test took 1159ms.
[08:38:58.149] <TB2> INFO: Expecting 2560 events.
[08:38:59.001] <TB2> INFO: 2560 events read in total (295ms).
[08:38:59.001] <TB2> INFO: Test took 1156ms.
[08:38:59.306] <TB2> INFO: Expecting 2560 events.
[08:39:00.159] <TB2> INFO: 2560 events read in total (297ms).
[08:39:00.159] <TB2> INFO: Test took 1158ms.
[08:39:00.463] <TB2> INFO: Expecting 2560 events.
[08:39:01.318] <TB2> INFO: 2560 events read in total (298ms).
[08:39:01.318] <TB2> INFO: Test took 1159ms.
[08:39:01.622] <TB2> INFO: Expecting 2560 events.
[08:39:02.480] <TB2> INFO: 2560 events read in total (301ms).
[08:39:02.481] <TB2> INFO: Test took 1163ms.
[08:39:02.785] <TB2> INFO: Expecting 2560 events.
[08:39:03.640] <TB2> INFO: 2560 events read in total (299ms).
[08:39:03.641] <TB2> INFO: Test took 1160ms.
[08:39:03.945] <TB2> INFO: Expecting 2560 events.
[08:39:04.800] <TB2> INFO: 2560 events read in total (299ms).
[08:39:04.800] <TB2> INFO: Test took 1159ms.
[08:39:05.106] <TB2> INFO: Expecting 2560 events.
[08:39:05.959] <TB2> INFO: 2560 events read in total (297ms).
[08:39:05.959] <TB2> INFO: Test took 1158ms.
[08:39:06.264] <TB2> INFO: Expecting 2560 events.
[08:39:07.120] <TB2> INFO: 2560 events read in total (300ms).
[08:39:07.120] <TB2> INFO: Test took 1160ms.
[08:39:07.425] <TB2> INFO: Expecting 2560 events.
[08:39:08.280] <TB2> INFO: 2560 events read in total (299ms).
[08:39:08.282] <TB2> INFO: Test took 1162ms.
[08:39:08.585] <TB2> INFO: Expecting 2560 events.
[08:39:09.441] <TB2> INFO: 2560 events read in total (300ms).
[08:39:09.441] <TB2> INFO: Test took 1159ms.
[08:39:09.444] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:09.747] <TB2> INFO: Expecting 655360 events.
[08:39:23.800] <TB2> INFO: 655360 events read in total (13497ms).
[08:39:23.812] <TB2> INFO: Expecting 655360 events.
[08:39:37.526] <TB2> INFO: 655360 events read in total (13312ms).
[08:39:37.551] <TB2> INFO: Expecting 655360 events.
[08:39:51.360] <TB2> INFO: 655360 events read in total (13407ms).
[08:39:51.379] <TB2> INFO: Expecting 655360 events.
[08:40:05.224] <TB2> INFO: 655360 events read in total (13443ms).
[08:40:05.248] <TB2> INFO: Expecting 655360 events.
[08:40:19.165] <TB2> INFO: 655360 events read in total (13515ms).
[08:40:19.192] <TB2> INFO: Expecting 655360 events.
[08:40:32.926] <TB2> INFO: 655360 events read in total (13332ms).
[08:40:32.959] <TB2> INFO: Expecting 655360 events.
[08:40:46.708] <TB2> INFO: 655360 events read in total (13347ms).
[08:40:46.746] <TB2> INFO: Expecting 655360 events.
[08:41:00.715] <TB2> INFO: 655360 events read in total (13567ms).
[08:41:00.755] <TB2> INFO: Expecting 655360 events.
[08:41:14.622] <TB2> INFO: 655360 events read in total (13465ms).
[08:41:14.667] <TB2> INFO: Expecting 655360 events.
[08:41:28.699] <TB2> INFO: 655360 events read in total (13630ms).
[08:41:28.751] <TB2> INFO: Expecting 655360 events.
[08:41:42.498] <TB2> INFO: 655360 events read in total (13346ms).
[08:41:42.552] <TB2> INFO: Expecting 655360 events.
[08:41:56.323] <TB2> INFO: 655360 events read in total (13369ms).
[08:41:56.386] <TB2> INFO: Expecting 655360 events.
[08:42:10.199] <TB2> INFO: 655360 events read in total (13411ms).
[08:42:10.264] <TB2> INFO: Expecting 655360 events.
[08:42:24.084] <TB2> INFO: 655360 events read in total (13419ms).
[08:42:24.157] <TB2> INFO: Expecting 655360 events.
[08:42:38.126] <TB2> INFO: 655360 events read in total (13567ms).
[08:42:38.199] <TB2> INFO: Expecting 655360 events.
[08:42:52.251] <TB2> INFO: 655360 events read in total (13650ms).
[08:42:52.341] <TB2> INFO: Test took 222897ms.
[08:42:52.425] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:52.654] <TB2> INFO: Expecting 655360 events.
[08:43:06.807] <TB2> INFO: 655360 events read in total (13597ms).
[08:43:06.820] <TB2> INFO: Expecting 655360 events.
[08:43:20.738] <TB2> INFO: 655360 events read in total (13517ms).
[08:43:20.755] <TB2> INFO: Expecting 655360 events.
[08:43:34.736] <TB2> INFO: 655360 events read in total (13580ms).
[08:43:34.755] <TB2> INFO: Expecting 655360 events.
[08:43:48.733] <TB2> INFO: 655360 events read in total (13576ms).
[08:43:48.757] <TB2> INFO: Expecting 655360 events.
[08:44:02.682] <TB2> INFO: 655360 events read in total (13523ms).
[08:44:02.710] <TB2> INFO: Expecting 655360 events.
[08:44:16.599] <TB2> INFO: 655360 events read in total (13487ms).
[08:44:16.633] <TB2> INFO: Expecting 655360 events.
[08:44:30.513] <TB2> INFO: 655360 events read in total (13478ms).
[08:44:30.555] <TB2> INFO: Expecting 655360 events.
[08:44:44.507] <TB2> INFO: 655360 events read in total (13550ms).
[08:44:44.565] <TB2> INFO: Expecting 655360 events.
[08:44:58.535] <TB2> INFO: 655360 events read in total (13568ms).
[08:44:58.583] <TB2> INFO: Expecting 655360 events.
[08:45:12.583] <TB2> INFO: 655360 events read in total (13598ms).
[08:45:12.647] <TB2> INFO: Expecting 655360 events.
[08:45:26.600] <TB2> INFO: 655360 events read in total (13552ms).
[08:45:26.653] <TB2> INFO: Expecting 655360 events.
[08:45:40.790] <TB2> INFO: 655360 events read in total (13735ms).
[08:45:40.855] <TB2> INFO: Expecting 655360 events.
[08:45:54.700] <TB2> INFO: 655360 events read in total (13443ms).
[08:45:54.762] <TB2> INFO: Expecting 655360 events.
[08:46:08.617] <TB2> INFO: 655360 events read in total (13453ms).
[08:46:08.713] <TB2> INFO: Expecting 655360 events.
[08:46:22.595] <TB2> INFO: 655360 events read in total (13480ms).
[08:46:22.676] <TB2> INFO: Expecting 655360 events.
[08:46:36.494] <TB2> INFO: 655360 events read in total (13416ms).
[08:46:36.572] <TB2> INFO: Test took 224147ms.
[08:46:36.776] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.784] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.792] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.801] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.808] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.815] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.824] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.832] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.840] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.847] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.854] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.862] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.869] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.876] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.884] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.892] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:36.925] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:46:36.925] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:46:36.925] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:46:36.925] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:46:36.925] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:46:36.925] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:46:36.925] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:46:36.926] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:46:36.926] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:46:36.926] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:46:36.926] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:46:36.926] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:46:36.926] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:46:36.926] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:46:36.926] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:46:36.926] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:46:37.198] <TB2> INFO: Expecting 41600 events.
[08:46:40.314] <TB2> INFO: 41600 events read in total (2560ms).
[08:46:40.314] <TB2> INFO: Test took 3385ms.
[08:46:40.754] <TB2> INFO: Expecting 41600 events.
[08:46:43.890] <TB2> INFO: 41600 events read in total (2580ms).
[08:46:43.891] <TB2> INFO: Test took 3402ms.
[08:46:44.343] <TB2> INFO: Expecting 41600 events.
[08:46:47.466] <TB2> INFO: 41600 events read in total (2566ms).
[08:46:47.466] <TB2> INFO: Test took 3398ms.
[08:46:47.647] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:47.736] <TB2> INFO: Expecting 2560 events.
[08:46:48.587] <TB2> INFO: 2560 events read in total (295ms).
[08:46:48.587] <TB2> INFO: Test took 940ms.
[08:46:48.589] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:48.893] <TB2> INFO: Expecting 2560 events.
[08:46:49.745] <TB2> INFO: 2560 events read in total (296ms).
[08:46:49.745] <TB2> INFO: Test took 1156ms.
[08:46:49.747] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:50.050] <TB2> INFO: Expecting 2560 events.
[08:46:50.902] <TB2> INFO: 2560 events read in total (296ms).
[08:46:50.902] <TB2> INFO: Test took 1155ms.
[08:46:50.905] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:51.208] <TB2> INFO: Expecting 2560 events.
[08:46:52.060] <TB2> INFO: 2560 events read in total (296ms).
[08:46:52.060] <TB2> INFO: Test took 1155ms.
[08:46:52.062] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:52.366] <TB2> INFO: Expecting 2560 events.
[08:46:53.223] <TB2> INFO: 2560 events read in total (301ms).
[08:46:53.223] <TB2> INFO: Test took 1161ms.
[08:46:53.224] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:53.529] <TB2> INFO: Expecting 2560 events.
[08:46:54.385] <TB2> INFO: 2560 events read in total (300ms).
[08:46:54.386] <TB2> INFO: Test took 1162ms.
[08:46:54.388] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:54.691] <TB2> INFO: Expecting 2560 events.
[08:46:55.543] <TB2> INFO: 2560 events read in total (296ms).
[08:46:55.544] <TB2> INFO: Test took 1156ms.
[08:46:55.560] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:55.850] <TB2> INFO: Expecting 2560 events.
[08:46:56.708] <TB2> INFO: 2560 events read in total (302ms).
[08:46:56.708] <TB2> INFO: Test took 1149ms.
[08:46:56.710] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:57.014] <TB2> INFO: Expecting 2560 events.
[08:46:57.873] <TB2> INFO: 2560 events read in total (303ms).
[08:46:57.874] <TB2> INFO: Test took 1164ms.
[08:46:57.877] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:58.178] <TB2> INFO: Expecting 2560 events.
[08:46:59.031] <TB2> INFO: 2560 events read in total (297ms).
[08:46:59.031] <TB2> INFO: Test took 1154ms.
[08:46:59.034] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:59.337] <TB2> INFO: Expecting 2560 events.
[08:47:00.189] <TB2> INFO: 2560 events read in total (296ms).
[08:47:00.191] <TB2> INFO: Test took 1157ms.
[08:47:00.193] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:00.495] <TB2> INFO: Expecting 2560 events.
[08:47:01.351] <TB2> INFO: 2560 events read in total (300ms).
[08:47:01.351] <TB2> INFO: Test took 1158ms.
[08:47:01.353] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:01.657] <TB2> INFO: Expecting 2560 events.
[08:47:02.514] <TB2> INFO: 2560 events read in total (301ms).
[08:47:02.514] <TB2> INFO: Test took 1162ms.
[08:47:02.515] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:02.819] <TB2> INFO: Expecting 2560 events.
[08:47:03.671] <TB2> INFO: 2560 events read in total (296ms).
[08:47:03.672] <TB2> INFO: Test took 1157ms.
[08:47:03.673] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:03.977] <TB2> INFO: Expecting 2560 events.
[08:47:04.832] <TB2> INFO: 2560 events read in total (299ms).
[08:47:04.832] <TB2> INFO: Test took 1159ms.
[08:47:04.834] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:05.138] <TB2> INFO: Expecting 2560 events.
[08:47:05.999] <TB2> INFO: 2560 events read in total (305ms).
[08:47:06.000] <TB2> INFO: Test took 1166ms.
[08:47:06.004] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:06.303] <TB2> INFO: Expecting 2560 events.
[08:47:07.155] <TB2> INFO: 2560 events read in total (295ms).
[08:47:07.155] <TB2> INFO: Test took 1151ms.
[08:47:07.157] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:07.461] <TB2> INFO: Expecting 2560 events.
[08:47:08.316] <TB2> INFO: 2560 events read in total (299ms).
[08:47:08.316] <TB2> INFO: Test took 1159ms.
[08:47:08.319] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:08.621] <TB2> INFO: Expecting 2560 events.
[08:47:09.482] <TB2> INFO: 2560 events read in total (304ms).
[08:47:09.482] <TB2> INFO: Test took 1163ms.
[08:47:09.485] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:09.788] <TB2> INFO: Expecting 2560 events.
[08:47:10.643] <TB2> INFO: 2560 events read in total (299ms).
[08:47:10.644] <TB2> INFO: Test took 1159ms.
[08:47:10.646] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:10.949] <TB2> INFO: Expecting 2560 events.
[08:47:11.802] <TB2> INFO: 2560 events read in total (297ms).
[08:47:11.802] <TB2> INFO: Test took 1157ms.
[08:47:11.805] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:12.107] <TB2> INFO: Expecting 2560 events.
[08:47:12.960] <TB2> INFO: 2560 events read in total (297ms).
[08:47:12.960] <TB2> INFO: Test took 1156ms.
[08:47:12.963] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:13.266] <TB2> INFO: Expecting 2560 events.
[08:47:14.120] <TB2> INFO: 2560 events read in total (298ms).
[08:47:14.121] <TB2> INFO: Test took 1158ms.
[08:47:14.123] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:14.426] <TB2> INFO: Expecting 2560 events.
[08:47:15.278] <TB2> INFO: 2560 events read in total (296ms).
[08:47:15.278] <TB2> INFO: Test took 1155ms.
[08:47:15.280] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:15.584] <TB2> INFO: Expecting 2560 events.
[08:47:16.437] <TB2> INFO: 2560 events read in total (297ms).
[08:47:16.437] <TB2> INFO: Test took 1158ms.
[08:47:16.439] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:16.743] <TB2> INFO: Expecting 2560 events.
[08:47:17.602] <TB2> INFO: 2560 events read in total (303ms).
[08:47:17.602] <TB2> INFO: Test took 1163ms.
[08:47:17.604] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:17.908] <TB2> INFO: Expecting 2560 events.
[08:47:18.763] <TB2> INFO: 2560 events read in total (299ms).
[08:47:18.764] <TB2> INFO: Test took 1161ms.
[08:47:18.766] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:19.068] <TB2> INFO: Expecting 2560 events.
[08:47:19.921] <TB2> INFO: 2560 events read in total (297ms).
[08:47:19.921] <TB2> INFO: Test took 1155ms.
[08:47:19.923] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:20.225] <TB2> INFO: Expecting 2560 events.
[08:47:21.079] <TB2> INFO: 2560 events read in total (297ms).
[08:47:21.079] <TB2> INFO: Test took 1157ms.
[08:47:21.082] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:21.384] <TB2> INFO: Expecting 2560 events.
[08:47:22.237] <TB2> INFO: 2560 events read in total (297ms).
[08:47:22.237] <TB2> INFO: Test took 1155ms.
[08:47:22.240] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:22.542] <TB2> INFO: Expecting 2560 events.
[08:47:23.396] <TB2> INFO: 2560 events read in total (298ms).
[08:47:23.396] <TB2> INFO: Test took 1157ms.
[08:47:23.400] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:23.701] <TB2> INFO: Expecting 2560 events.
[08:47:24.557] <TB2> INFO: 2560 events read in total (299ms).
[08:47:24.557] <TB2> INFO: Test took 1158ms.
[08:47:25.034] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 526 seconds
[08:47:25.034] <TB2> INFO: PH scale (per ROC): 82 85 97 91 90 95 93 95 80 92 93 87 94 92 78 77
[08:47:25.034] <TB2> INFO: PH offset (per ROC): 176 176 149 153 153 149 149 144 157 131 144 140 144 154 146 170
[08:47:25.042] <TB2> INFO: Decoding statistics:
[08:47:25.042] <TB2> INFO: General information:
[08:47:25.042] <TB2> INFO: 16bit words read: 86922
[08:47:25.042] <TB2> INFO: valid events total: 10240
[08:47:25.042] <TB2> INFO: empty events: 7739
[08:47:25.042] <TB2> INFO: valid events with pixels: 2501
[08:47:25.042] <TB2> INFO: valid pixel hits: 2501
[08:47:25.042] <TB2> INFO: Event errors: 0
[08:47:25.042] <TB2> INFO: start marker: 0
[08:47:25.042] <TB2> INFO: stop marker: 0
[08:47:25.042] <TB2> INFO: overflow: 0
[08:47:25.042] <TB2> INFO: invalid 5bit words: 0
[08:47:25.042] <TB2> INFO: invalid XOR eye diagram: 0
[08:47:25.042] <TB2> INFO: frame (failed synchr.): 0
[08:47:25.042] <TB2> INFO: idle data (no TBM trl): 0
[08:47:25.042] <TB2> INFO: no data (only TBM hdr): 0
[08:47:25.042] <TB2> INFO: TBM errors: 0
[08:47:25.042] <TB2> INFO: flawed TBM headers: 0
[08:47:25.042] <TB2> INFO: flawed TBM trailers: 0
[08:47:25.042] <TB2> INFO: event ID mismatches: 0
[08:47:25.042] <TB2> INFO: ROC errors: 0
[08:47:25.042] <TB2> INFO: missing ROC header(s): 0
[08:47:25.042] <TB2> INFO: misplaced readback start: 0
[08:47:25.042] <TB2> INFO: Pixel decoding errors: 0
[08:47:25.042] <TB2> INFO: pixel data incomplete: 0
[08:47:25.042] <TB2> INFO: pixel address: 0
[08:47:25.042] <TB2> INFO: pulse height fill bit: 0
[08:47:25.042] <TB2> INFO: buffer corruption: 0
[08:47:25.214] <TB2> INFO: ######################################################################
[08:47:25.214] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[08:47:25.214] <TB2> INFO: ######################################################################
[08:47:25.224] <TB2> INFO: scanning low vcal = 10
[08:47:25.487] <TB2> INFO: Expecting 41600 events.
[08:47:28.863] <TB2> INFO: 41600 events read in total (2820ms).
[08:47:28.864] <TB2> INFO: Test took 3639ms.
[08:47:28.865] <TB2> INFO: scanning low vcal = 20
[08:47:29.162] <TB2> INFO: Expecting 41600 events.
[08:47:32.552] <TB2> INFO: 41600 events read in total (2834ms).
[08:47:32.552] <TB2> INFO: Test took 3686ms.
[08:47:32.553] <TB2> INFO: scanning low vcal = 30
[08:47:32.850] <TB2> INFO: Expecting 41600 events.
[08:47:36.271] <TB2> INFO: 41600 events read in total (2865ms).
[08:47:36.271] <TB2> INFO: Test took 3718ms.
[08:47:36.273] <TB2> INFO: scanning low vcal = 40
[08:47:36.563] <TB2> INFO: Expecting 41600 events.
[08:47:40.449] <TB2> INFO: 41600 events read in total (3330ms).
[08:47:40.449] <TB2> INFO: Test took 4176ms.
[08:47:40.452] <TB2> INFO: scanning low vcal = 50
[08:47:40.715] <TB2> INFO: Expecting 41600 events.
[08:47:44.667] <TB2> INFO: 41600 events read in total (3396ms).
[08:47:44.668] <TB2> INFO: Test took 4216ms.
[08:47:44.671] <TB2> INFO: scanning low vcal = 60
[08:47:44.937] <TB2> INFO: Expecting 41600 events.
[08:47:48.865] <TB2> INFO: 41600 events read in total (3372ms).
[08:47:48.865] <TB2> INFO: Test took 4194ms.
[08:47:48.868] <TB2> INFO: scanning low vcal = 70
[08:47:49.177] <TB2> INFO: Expecting 41600 events.
[08:47:53.133] <TB2> INFO: 41600 events read in total (3400ms).
[08:47:53.134] <TB2> INFO: Test took 4266ms.
[08:47:53.136] <TB2> INFO: scanning low vcal = 80
[08:47:53.420] <TB2> INFO: Expecting 41600 events.
[08:47:57.377] <TB2> INFO: 41600 events read in total (3400ms).
[08:47:57.378] <TB2> INFO: Test took 4242ms.
[08:47:57.381] <TB2> INFO: scanning low vcal = 90
[08:47:57.645] <TB2> INFO: Expecting 41600 events.
[08:48:01.595] <TB2> INFO: 41600 events read in total (3394ms).
[08:48:01.595] <TB2> INFO: Test took 4214ms.
[08:48:01.598] <TB2> INFO: scanning low vcal = 100
[08:48:01.864] <TB2> INFO: Expecting 41600 events.
[08:48:05.794] <TB2> INFO: 41600 events read in total (3374ms).
[08:48:05.794] <TB2> INFO: Test took 4196ms.
[08:48:05.797] <TB2> INFO: scanning low vcal = 110
[08:48:06.080] <TB2> INFO: Expecting 41600 events.
[08:48:10.057] <TB2> INFO: 41600 events read in total (3421ms).
[08:48:10.057] <TB2> INFO: Test took 4260ms.
[08:48:10.061] <TB2> INFO: scanning low vcal = 120
[08:48:10.375] <TB2> INFO: Expecting 41600 events.
[08:48:14.299] <TB2> INFO: 41600 events read in total (3368ms).
[08:48:14.299] <TB2> INFO: Test took 4238ms.
[08:48:14.302] <TB2> INFO: scanning low vcal = 130
[08:48:14.565] <TB2> INFO: Expecting 41600 events.
[08:48:18.498] <TB2> INFO: 41600 events read in total (3376ms).
[08:48:18.498] <TB2> INFO: Test took 4196ms.
[08:48:18.501] <TB2> INFO: scanning low vcal = 140
[08:48:18.766] <TB2> INFO: Expecting 41600 events.
[08:48:22.716] <TB2> INFO: 41600 events read in total (3394ms).
[08:48:22.717] <TB2> INFO: Test took 4216ms.
[08:48:22.719] <TB2> INFO: scanning low vcal = 150
[08:48:23.000] <TB2> INFO: Expecting 41600 events.
[08:48:26.954] <TB2> INFO: 41600 events read in total (3398ms).
[08:48:26.955] <TB2> INFO: Test took 4236ms.
[08:48:26.957] <TB2> INFO: scanning low vcal = 160
[08:48:27.220] <TB2> INFO: Expecting 41600 events.
[08:48:31.148] <TB2> INFO: 41600 events read in total (3372ms).
[08:48:31.149] <TB2> INFO: Test took 4192ms.
[08:48:31.151] <TB2> INFO: scanning low vcal = 170
[08:48:31.415] <TB2> INFO: Expecting 41600 events.
[08:48:35.370] <TB2> INFO: 41600 events read in total (3399ms).
[08:48:35.371] <TB2> INFO: Test took 4220ms.
[08:48:35.375] <TB2> INFO: scanning low vcal = 180
[08:48:35.634] <TB2> INFO: Expecting 41600 events.
[08:48:39.568] <TB2> INFO: 41600 events read in total (3377ms).
[08:48:39.569] <TB2> INFO: Test took 4194ms.
[08:48:39.571] <TB2> INFO: scanning low vcal = 190
[08:48:39.836] <TB2> INFO: Expecting 41600 events.
[08:48:43.777] <TB2> INFO: 41600 events read in total (3385ms).
[08:48:43.778] <TB2> INFO: Test took 4207ms.
[08:48:43.780] <TB2> INFO: scanning low vcal = 200
[08:48:44.046] <TB2> INFO: Expecting 41600 events.
[08:48:48.001] <TB2> INFO: 41600 events read in total (3399ms).
[08:48:48.002] <TB2> INFO: Test took 4221ms.
[08:48:48.005] <TB2> INFO: scanning low vcal = 210
[08:48:48.289] <TB2> INFO: Expecting 41600 events.
[08:48:52.245] <TB2> INFO: 41600 events read in total (3400ms).
[08:48:52.246] <TB2> INFO: Test took 4241ms.
[08:48:52.249] <TB2> INFO: scanning low vcal = 220
[08:48:52.511] <TB2> INFO: Expecting 41600 events.
[08:48:56.440] <TB2> INFO: 41600 events read in total (3372ms).
[08:48:56.440] <TB2> INFO: Test took 4190ms.
[08:48:56.443] <TB2> INFO: scanning low vcal = 230
[08:48:56.728] <TB2> INFO: Expecting 41600 events.
[08:49:00.678] <TB2> INFO: 41600 events read in total (3394ms).
[08:49:00.679] <TB2> INFO: Test took 4236ms.
[08:49:00.682] <TB2> INFO: scanning low vcal = 240
[08:49:00.959] <TB2> INFO: Expecting 41600 events.
[08:49:04.893] <TB2> INFO: 41600 events read in total (3378ms).
[08:49:04.893] <TB2> INFO: Test took 4211ms.
[08:49:04.896] <TB2> INFO: scanning low vcal = 250
[08:49:05.160] <TB2> INFO: Expecting 41600 events.
[08:49:09.109] <TB2> INFO: 41600 events read in total (3393ms).
[08:49:09.110] <TB2> INFO: Test took 4214ms.
[08:49:09.113] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[08:49:09.392] <TB2> INFO: Expecting 41600 events.
[08:49:13.349] <TB2> INFO: 41600 events read in total (3400ms).
[08:49:13.350] <TB2> INFO: Test took 4236ms.
[08:49:13.352] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[08:49:13.612] <TB2> INFO: Expecting 41600 events.
[08:49:17.567] <TB2> INFO: 41600 events read in total (3399ms).
[08:49:17.567] <TB2> INFO: Test took 4215ms.
[08:49:17.570] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[08:49:17.943] <TB2> INFO: Expecting 41600 events.
[08:49:21.894] <TB2> INFO: 41600 events read in total (3395ms).
[08:49:21.894] <TB2> INFO: Test took 4324ms.
[08:49:21.904] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[08:49:22.168] <TB2> INFO: Expecting 41600 events.
[08:49:26.112] <TB2> INFO: 41600 events read in total (3388ms).
[08:49:26.113] <TB2> INFO: Test took 4209ms.
[08:49:26.115] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[08:49:26.393] <TB2> INFO: Expecting 41600 events.
[08:49:30.336] <TB2> INFO: 41600 events read in total (3386ms).
[08:49:30.336] <TB2> INFO: Test took 4221ms.
[08:49:30.810] <TB2> INFO: PixTestGainPedestal::measure() done
[08:50:04.495] <TB2> INFO: PixTestGainPedestal::fit() done
[08:50:04.495] <TB2> INFO: non-linearity mean: 0.951 0.953 0.955 0.948 0.954 0.948 0.957 0.953 0.952 0.954 0.957 0.956 0.951 0.956 0.951 0.956
[08:50:04.495] <TB2> INFO: non-linearity RMS: 0.007 0.005 0.006 0.007 0.007 0.006 0.006 0.006 0.008 0.006 0.005 0.005 0.006 0.007 0.006 0.006
[08:50:04.495] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[08:50:04.514] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[08:50:04.533] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[08:50:04.552] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[08:50:04.572] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[08:50:04.591] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[08:50:04.611] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[08:50:04.633] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[08:50:04.656] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[08:50:04.675] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[08:50:04.694] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[08:50:04.713] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[08:50:04.734] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[08:50:04.753] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[08:50:04.774] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[08:50:04.794] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[08:50:04.813] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 159 seconds
[08:50:04.813] <TB2> INFO: Decoding statistics:
[08:50:04.813] <TB2> INFO: General information:
[08:50:04.813] <TB2> INFO: 16bit words read: 2662400
[08:50:04.813] <TB2> INFO: valid events total: 166400
[08:50:04.813] <TB2> INFO: empty events: 0
[08:50:04.813] <TB2> INFO: valid events with pixels: 166400
[08:50:04.813] <TB2> INFO: valid pixel hits: 665600
[08:50:04.813] <TB2> INFO: Event errors: 0
[08:50:04.813] <TB2> INFO: start marker: 0
[08:50:04.813] <TB2> INFO: stop marker: 0
[08:50:04.813] <TB2> INFO: overflow: 0
[08:50:04.813] <TB2> INFO: invalid 5bit words: 0
[08:50:04.813] <TB2> INFO: invalid XOR eye diagram: 0
[08:50:04.813] <TB2> INFO: frame (failed synchr.): 0
[08:50:04.813] <TB2> INFO: idle data (no TBM trl): 0
[08:50:04.813] <TB2> INFO: no data (only TBM hdr): 0
[08:50:04.813] <TB2> INFO: TBM errors: 0
[08:50:04.813] <TB2> INFO: flawed TBM headers: 0
[08:50:04.813] <TB2> INFO: flawed TBM trailers: 0
[08:50:04.813] <TB2> INFO: event ID mismatches: 0
[08:50:04.813] <TB2> INFO: ROC errors: 0
[08:50:04.813] <TB2> INFO: missing ROC header(s): 0
[08:50:04.813] <TB2> INFO: misplaced readback start: 0
[08:50:04.813] <TB2> INFO: Pixel decoding errors: 0
[08:50:04.813] <TB2> INFO: pixel data incomplete: 0
[08:50:04.813] <TB2> INFO: pixel address: 0
[08:50:04.814] <TB2> INFO: pulse height fill bit: 0
[08:50:04.814] <TB2> INFO: buffer corruption: 0
[08:50:04.877] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C15.dat
[08:50:04.914] <TB2> INFO: ######################################################################
[08:50:04.914] <TB2> INFO: PixTestReadback::doTest()
[08:50:04.914] <TB2> INFO: ######################################################################
[08:50:04.914] <TB2> INFO: ----------------------------------------------------------------------
[08:50:04.914] <TB2> INFO: PixTestReadback::CalibrateVd()
[08:50:04.914] <TB2> INFO: ----------------------------------------------------------------------
[08:50:14.413] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C0.dat
[08:50:14.413] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C1.dat
[08:50:14.413] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C2.dat
[08:50:14.413] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C3.dat
[08:50:14.414] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C4.dat
[08:50:14.414] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C5.dat
[08:50:14.414] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C6.dat
[08:50:14.414] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C7.dat
[08:50:14.414] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C8.dat
[08:50:14.414] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C9.dat
[08:50:14.414] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C10.dat
[08:50:14.414] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C11.dat
[08:50:14.414] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C12.dat
[08:50:14.414] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C13.dat
[08:50:14.414] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C14.dat
[08:50:14.415] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C15.dat
[08:50:14.438] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:50:14.438] <TB2> INFO: ----------------------------------------------------------------------
[08:50:14.438] <TB2> INFO: PixTestReadback::CalibrateVa()
[08:50:14.438] <TB2> INFO: ----------------------------------------------------------------------
[08:50:25.003] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C0.dat
[08:50:25.004] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C1.dat
[08:50:25.004] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C2.dat
[08:50:25.004] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C3.dat
[08:50:25.004] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C4.dat
[08:50:25.004] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C5.dat
[08:50:25.004] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C6.dat
[08:50:25.004] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C7.dat
[08:50:25.004] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C8.dat
[08:50:25.004] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C9.dat
[08:50:25.005] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C10.dat
[08:50:25.005] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C11.dat
[08:50:25.005] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C12.dat
[08:50:25.005] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C13.dat
[08:50:25.005] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C14.dat
[08:50:25.005] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C15.dat
[08:50:25.035] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:50:25.035] <TB2> INFO: ----------------------------------------------------------------------
[08:50:25.035] <TB2> INFO: PixTestReadback::readbackVbg()
[08:50:25.035] <TB2> INFO: ----------------------------------------------------------------------
[08:50:32.339] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:50:32.339] <TB2> INFO: ----------------------------------------------------------------------
[08:50:32.339] <TB2> INFO: PixTestReadback::getCalibratedVbg()
[08:50:32.339] <TB2> INFO: ----------------------------------------------------------------------
[08:50:32.339] <TB2> INFO: Vbg will be calibrated using Vd calibration
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 157.9calibrated Vbg = 1.22814 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 154.5calibrated Vbg = 1.21875 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 152.6calibrated Vbg = 1.22563 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 148.7calibrated Vbg = 1.23274 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 149.8calibrated Vbg = 1.23372 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 153.8calibrated Vbg = 1.23687 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 149.1calibrated Vbg = 1.23248 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 148.1calibrated Vbg = 1.23619 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 159.2calibrated Vbg = 1.23782 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 148.9calibrated Vbg = 1.24283 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153.4calibrated Vbg = 1.2364 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 147.6calibrated Vbg = 1.23127 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 156.8calibrated Vbg = 1.2244 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 156.8calibrated Vbg = 1.22795 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 142.7calibrated Vbg = 1.23288 :::*/*/*/*/
[08:50:32.339] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 144.7calibrated Vbg = 1.22428 :::*/*/*/*/
[08:50:32.342] <TB2> INFO: ----------------------------------------------------------------------
[08:50:32.342] <TB2> INFO: PixTestReadback::CalibrateIa()
[08:50:32.342] <TB2> INFO: ----------------------------------------------------------------------
[08:53:07.569] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C0.dat
[08:53:07.569] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C1.dat
[08:53:07.569] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C2.dat
[08:53:07.569] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C3.dat
[08:53:07.569] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C4.dat
[08:53:07.569] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C5.dat
[08:53:07.569] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C6.dat
[08:53:07.569] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C7.dat
[08:53:07.569] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C8.dat
[08:53:07.570] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C9.dat
[08:53:07.570] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C10.dat
[08:53:07.570] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C11.dat
[08:53:07.570] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C12.dat
[08:53:07.570] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C13.dat
[08:53:07.570] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C14.dat
[08:53:07.570] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2354_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C15.dat
[08:53:07.592] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:53:07.593] <TB2> INFO: PixTestReadback::doTest() done
[08:53:07.593] <TB2> INFO: Decoding statistics:
[08:53:07.593] <TB2> INFO: General information:
[08:53:07.593] <TB2> INFO: 16bit words read: 1024
[08:53:07.593] <TB2> INFO: valid events total: 128
[08:53:07.593] <TB2> INFO: empty events: 128
[08:53:07.593] <TB2> INFO: valid events with pixels: 0
[08:53:07.593] <TB2> INFO: valid pixel hits: 0
[08:53:07.593] <TB2> INFO: Event errors: 0
[08:53:07.593] <TB2> INFO: start marker: 0
[08:53:07.593] <TB2> INFO: stop marker: 0
[08:53:07.593] <TB2> INFO: overflow: 0
[08:53:07.593] <TB2> INFO: invalid 5bit words: 0
[08:53:07.593] <TB2> INFO: invalid XOR eye diagram: 0
[08:53:07.593] <TB2> INFO: frame (failed synchr.): 0
[08:53:07.593] <TB2> INFO: idle data (no TBM trl): 0
[08:53:07.593] <TB2> INFO: no data (only TBM hdr): 0
[08:53:07.593] <TB2> INFO: TBM errors: 0
[08:53:07.593] <TB2> INFO: flawed TBM headers: 0
[08:53:07.593] <TB2> INFO: flawed TBM trailers: 0
[08:53:07.593] <TB2> INFO: event ID mismatches: 0
[08:53:07.593] <TB2> INFO: ROC errors: 0
[08:53:07.593] <TB2> INFO: missing ROC header(s): 0
[08:53:07.593] <TB2> INFO: misplaced readback start: 0
[08:53:07.593] <TB2> INFO: Pixel decoding errors: 0
[08:53:07.593] <TB2> INFO: pixel data incomplete: 0
[08:53:07.593] <TB2> INFO: pixel address: 0
[08:53:07.593] <TB2> INFO: pulse height fill bit: 0
[08:53:07.593] <TB2> INFO: buffer corruption: 0
[08:53:07.662] <TB2> INFO: Decoding statistics:
[08:53:07.662] <TB2> INFO: General information:
[08:53:07.662] <TB2> INFO: 16bit words read: 2750346
[08:53:07.662] <TB2> INFO: valid events total: 176768
[08:53:07.662] <TB2> INFO: empty events: 7867
[08:53:07.662] <TB2> INFO: valid events with pixels: 168901
[08:53:07.662] <TB2> INFO: valid pixel hits: 668101
[08:53:07.662] <TB2> INFO: Event errors: 0
[08:53:07.662] <TB2> INFO: start marker: 0
[08:53:07.662] <TB2> INFO: stop marker: 0
[08:53:07.662] <TB2> INFO: overflow: 0
[08:53:07.662] <TB2> INFO: invalid 5bit words: 0
[08:53:07.662] <TB2> INFO: invalid XOR eye diagram: 0
[08:53:07.662] <TB2> INFO: frame (failed synchr.): 0
[08:53:07.663] <TB2> INFO: idle data (no TBM trl): 0
[08:53:07.663] <TB2> INFO: no data (only TBM hdr): 0
[08:53:07.663] <TB2> INFO: TBM errors: 0
[08:53:07.663] <TB2> INFO: flawed TBM headers: 0
[08:53:07.663] <TB2> INFO: flawed TBM trailers: 0
[08:53:07.663] <TB2> INFO: event ID mismatches: 0
[08:53:07.663] <TB2> INFO: ROC errors: 0
[08:53:07.663] <TB2> INFO: missing ROC header(s): 0
[08:53:07.663] <TB2> INFO: misplaced readback start: 0
[08:53:07.663] <TB2> INFO: Pixel decoding errors: 0
[08:53:07.663] <TB2> INFO: pixel data incomplete: 0
[08:53:07.663] <TB2> INFO: pixel address: 0
[08:53:07.663] <TB2> INFO: pulse height fill bit: 0
[08:53:07.663] <TB2> INFO: buffer corruption: 0
[08:53:07.663] <TB2> INFO: enter test to run
[08:53:07.663] <TB2> INFO: test: exit no parameter change
[08:53:07.897] <TB2> QUIET: Connection to board 156 closed.
[08:53:07.976] <TB2> INFO: pXar: this is the end, my friend