[09:00:37.721] <TB3> INFO: *** Welcome to pxar ***
[09:00:37.721] <TB3> INFO: *** Today: 2016/09/23
[09:00:37.822] <TB3> INFO: *** Version: fe94
[09:00:37.822] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C15.dat
[09:00:37.823] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:00:37.823] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//defaultMaskFile.dat
[09:00:37.823] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters_C15.dat
[09:00:37.892] <TB3> INFO: clk: 4
[09:00:37.892] <TB3> INFO: ctr: 4
[09:00:37.892] <TB3> INFO: sda: 19
[09:00:37.892] <TB3> INFO: tin: 9
[09:00:37.892] <TB3> INFO: level: 15
[09:00:37.892] <TB3> INFO: triggerdelay: 0
[09:00:37.892] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[09:00:37.892] <TB3> INFO: Log level: INFO
[09:00:37.899] <TB3> INFO: Found DTB DTB_WZ4I6J
[09:00:37.908] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[09:00:37.910] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[09:00:37.912] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[09:00:39.440] <TB3> INFO: DUT info:
[09:00:39.440] <TB3> INFO: The DUT currently contains the following objects:
[09:00:39.440] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:00:39.440] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:00:39.440] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:00:39.440] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:00:39.440] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.440] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.440] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.440] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.440] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.440] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.440] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.440] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.440] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.440] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.440] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.440] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.440] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.441] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.441] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.441] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:39.842] <TB3> INFO: enter 'restricted' command line mode
[09:00:39.842] <TB3> INFO: enter test to run
[09:00:39.842] <TB3> INFO: test: pretest no parameter change
[09:00:39.842] <TB3> INFO: running: pretest
[09:00:39.851] <TB3> INFO: ----------------------------------------------------------------------
[09:00:39.851] <TB3> INFO: PixTestPretest::programROC()
[09:00:39.851] <TB3> INFO: ----------------------------------------------------------------------
[09:00:57.869] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:00:57.870] <TB3> INFO: IA differences per ROC: 20.1 16.1 20.9 20.1 18.5 21.7 17.7 20.1 20.1 19.3 20.1 20.1 16.9 18.5 19.3 20.9
[09:00:57.988] <TB3> INFO: enter test to run
[09:00:57.988] <TB3> INFO: test: pretest no parameter change
[09:00:57.988] <TB3> INFO: running: pretest
[09:00:57.989] <TB3> INFO: ----------------------------------------------------------------------
[09:00:57.989] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:00:57.989] <TB3> INFO: ----------------------------------------------------------------------
[09:01:05.106] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 397.9 mA = 24.8687 mA/ROC
[09:01:05.106] <TB3> INFO: i(loss) [mA/ROC]: 20.1 20.1 19.3 19.3 19.3 20.1 19.3 19.3 20.1 20.1 18.5 19.3 19.3 20.1 20.1 18.5
[09:01:05.152] <TB3> INFO: enter test to run
[09:01:05.152] <TB3> INFO: test: pretest no parameter change
[09:01:05.152] <TB3> INFO: running: pretest
[09:01:05.152] <TB3> INFO: ----------------------------------------------------------------------
[09:01:05.152] <TB3> INFO: PixTestPretest::findTiming()
[09:01:05.152] <TB3> INFO: ----------------------------------------------------------------------
[09:01:05.152] <TB3> INFO: PixTestCmd::init()

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


[09:02:45.836] <TB3> INFO: 231680 events read in total (8787ms).
[09:02:45.843] <TB3> INFO: Test took 9439ms.
[09:02:46.069] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:02:46.151] <TB3> INFO: enter test to run
[09:02:46.151] <TB3> INFO: test: pretest no parameter change
[09:02:46.151] <TB3> INFO: running: pretest
[09:02:46.153] <TB3> INFO: ----------------------------------------------------------------------
[09:02:46.153] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[09:02:46.153] <TB3> INFO: ----------------------------------------------------------------------
[09:02:46.248] <TB3> INFO: Expecting 231680 events.
[09:02:55.567] <TB3> INFO: 231680 events read in total (8762ms).
[09:02:55.574] <TB3> INFO: Test took 9416ms.
[09:02:55.809] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[09:02:55.809] <TB3> INFO: CalDel: 143 143 140 159 150 150 152 157 156 143 179 177 145 159 177 147
[09:02:55.809] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[09:02:55.842] <TB3> INFO: enter test to run
[09:02:55.842] <TB3> INFO: test: pretest no parameter change
[09:02:55.842] <TB3> INFO: running: pretest
[09:02:55.843] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C0.dat
[09:02:55.843] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C1.dat
[09:02:55.843] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C2.dat
[09:02:55.843] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C3.dat
[09:02:55.843] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C4.dat
[09:02:55.843] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C5.dat
[09:02:55.843] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C6.dat
[09:02:55.843] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C7.dat
[09:02:55.843] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C8.dat
[09:02:55.844] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C9.dat
[09:02:55.844] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C10.dat
[09:02:55.844] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C11.dat
[09:02:55.844] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C12.dat
[09:02:55.844] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C13.dat
[09:02:55.844] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C14.dat
[09:02:55.844] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C15.dat
[09:02:55.844] <TB3> INFO: enter test to run
[09:02:55.844] <TB3> INFO: test: fulltest no parameter change
[09:02:55.844] <TB3> INFO: running: fulltest
[09:02:55.845] <TB3> INFO: ######################################################################
[09:02:55.845] <TB3> INFO: PixTestFullTest::doTest()
[09:02:55.845] <TB3> INFO: ######################################################################
[09:02:55.846] <TB3> INFO: ######################################################################
[09:02:55.846] <TB3> INFO: PixTestAlive::doTest()
[09:02:55.846] <TB3> INFO: ######################################################################
[09:02:55.847] <TB3> INFO: ----------------------------------------------------------------------
[09:02:55.847] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:02:55.847] <TB3> INFO: ----------------------------------------------------------------------
[09:02:56.166] <TB3> INFO: Expecting 41600 events.
[09:02:59.636] <TB3> INFO: 41600 events read in total (2913ms).
[09:02:59.636] <TB3> INFO: Test took 3787ms.
[09:02:59.875] <TB3> INFO: PixTestAlive::aliveTest() done
[09:02:59.875] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:02:59.877] <TB3> INFO: ----------------------------------------------------------------------
[09:02:59.877] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:02:59.877] <TB3> INFO: ----------------------------------------------------------------------
[09:03:00.145] <TB3> INFO: Expecting 41600 events.
[09:03:02.865] <TB3> INFO: 41600 events read in total (2163ms).
[09:03:02.865] <TB3> INFO: Test took 2986ms.
[09:03:02.865] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:03:03.118] <TB3> INFO: PixTestAlive::maskTest() done
[09:03:03.118] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:03:03.119] <TB3> INFO: ----------------------------------------------------------------------
[09:03:03.119] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:03:03.119] <TB3> INFO: ----------------------------------------------------------------------
[09:03:03.401] <TB3> INFO: Expecting 41600 events.
[09:03:06.801] <TB3> INFO: 41600 events read in total (2843ms).
[09:03:06.803] <TB3> INFO: Test took 3681ms.
[09:03:07.037] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[09:03:07.038] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:03:07.038] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[09:03:07.038] <TB3> INFO: Decoding statistics:
[09:03:07.038] <TB3> INFO: General information:
[09:03:07.038] <TB3> INFO: 16bit words read: 0
[09:03:07.038] <TB3> INFO: valid events total: 0
[09:03:07.038] <TB3> INFO: empty events: 0
[09:03:07.038] <TB3> INFO: valid events with pixels: 0
[09:03:07.038] <TB3> INFO: valid pixel hits: 0
[09:03:07.038] <TB3> INFO: Event errors: 0
[09:03:07.038] <TB3> INFO: start marker: 0
[09:03:07.038] <TB3> INFO: stop marker: 0
[09:03:07.038] <TB3> INFO: overflow: 0
[09:03:07.038] <TB3> INFO: invalid 5bit words: 0
[09:03:07.038] <TB3> INFO: invalid XOR eye diagram: 0
[09:03:07.038] <TB3> INFO: frame (failed synchr.): 0
[09:03:07.038] <TB3> INFO: idle data (no TBM trl): 0
[09:03:07.038] <TB3> INFO: no data (only TBM hdr): 0
[09:03:07.038] <TB3> INFO: TBM errors: 0
[09:03:07.038] <TB3> INFO: flawed TBM headers: 0
[09:03:07.038] <TB3> INFO: flawed TBM trailers: 0
[09:03:07.038] <TB3> INFO: event ID mismatches: 0
[09:03:07.038] <TB3> INFO: ROC errors: 0
[09:03:07.038] <TB3> INFO: missing ROC header(s): 0
[09:03:07.038] <TB3> INFO: misplaced readback start: 0
[09:03:07.038] <TB3> INFO: Pixel decoding errors: 0
[09:03:07.038] <TB3> INFO: pixel data incomplete: 0
[09:03:07.038] <TB3> INFO: pixel address: 0
[09:03:07.038] <TB3> INFO: pulse height fill bit: 0
[09:03:07.038] <TB3> INFO: buffer corruption: 0
[09:03:07.046] <TB3> INFO: ######################################################################
[09:03:07.046] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:03:07.046] <TB3> INFO: ######################################################################
[09:03:07.050] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:03:07.272] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:03:07.272] <TB3> INFO: run 1 of 1
[09:03:07.542] <TB3> INFO: Expecting 3120000 events.
[09:03:44.519] <TB3> INFO: 859185 events read in total (36420ms).
[09:04:20.627] <TB3> INFO: 1704085 events read in total (72528ms).
[09:04:56.995] <TB3> INFO: 2560865 events read in total (108896ms).
[09:05:22.185] <TB3> INFO: 3120000 events read in total (134086ms).
[09:05:22.280] <TB3> INFO: Test took 135009ms.
[09:05:46.642] <TB3> INFO: PixTestBBMap::doTest() done, duration: 159 seconds
[09:05:46.642] <TB3> INFO: number of dead bumps (per ROC): 1 2 0 0 0 0 0 0 0 0 0 0 0 0 2 1
[09:05:46.642] <TB3> INFO: separation cut (per ROC): 90 90 77 87 71 102 69 91 87 99 102 79 103 83 87 102
[09:05:46.642] <TB3> INFO: Decoding statistics:
[09:05:46.642] <TB3> INFO: General information:
[09:05:46.642] <TB3> INFO: 16bit words read: 0
[09:05:46.642] <TB3> INFO: valid events total: 0
[09:05:46.642] <TB3> INFO: empty events: 0
[09:05:46.642] <TB3> INFO: valid events with pixels: 0
[09:05:46.642] <TB3> INFO: valid pixel hits: 0
[09:05:46.642] <TB3> INFO: Event errors: 0
[09:05:46.642] <TB3> INFO: start marker: 0
[09:05:46.642] <TB3> INFO: stop marker: 0
[09:05:46.642] <TB3> INFO: overflow: 0
[09:05:46.642] <TB3> INFO: invalid 5bit words: 0
[09:05:46.642] <TB3> INFO: invalid XOR eye diagram: 0
[09:05:46.642] <TB3> INFO: frame (failed synchr.): 0
[09:05:46.642] <TB3> INFO: idle data (no TBM trl): 0
[09:05:46.642] <TB3> INFO: no data (only TBM hdr): 0
[09:05:46.642] <TB3> INFO: TBM errors: 0
[09:05:46.642] <TB3> INFO: flawed TBM headers: 0
[09:05:46.642] <TB3> INFO: flawed TBM trailers: 0
[09:05:46.642] <TB3> INFO: event ID mismatches: 0
[09:05:46.642] <TB3> INFO: ROC errors: 0
[09:05:46.642] <TB3> INFO: missing ROC header(s): 0
[09:05:46.642] <TB3> INFO: misplaced readback start: 0
[09:05:46.642] <TB3> INFO: Pixel decoding errors: 0
[09:05:46.642] <TB3> INFO: pixel data incomplete: 0
[09:05:46.642] <TB3> INFO: pixel address: 0
[09:05:46.642] <TB3> INFO: pulse height fill bit: 0
[09:05:46.642] <TB3> INFO: buffer corruption: 0
[09:05:46.720] <TB3> INFO: ######################################################################
[09:05:46.720] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:05:46.720] <TB3> INFO: ######################################################################
[09:05:46.720] <TB3> INFO: ----------------------------------------------------------------------
[09:05:46.720] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:05:46.720] <TB3> INFO: ----------------------------------------------------------------------
[09:05:46.720] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[09:05:46.729] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:05:46.729] <TB3> INFO: run 1 of 1
[09:05:46.994] <TB3> INFO: Expecting 36608000 events.
[09:06:16.487] <TB3> INFO: 822200 events read in total (28936ms).
[09:06:46.436] <TB3> INFO: 1629450 events read in total (58885ms).
[09:07:15.651] <TB3> INFO: 2435750 events read in total (88100ms).
[09:07:44.735] <TB3> INFO: 3241550 events read in total (117184ms).
[09:08:13.026] <TB3> INFO: 4047400 events read in total (145475ms).
[09:08:41.183] <TB3> INFO: 4854300 events read in total (173632ms).
[09:09:09.239] <TB3> INFO: 5659600 events read in total (201688ms).
[09:09:37.392] <TB3> INFO: 6465450 events read in total (229841ms).
[09:10:05.341] <TB3> INFO: 7269500 events read in total (257790ms).
[09:10:33.341] <TB3> INFO: 8076150 events read in total (285790ms).
[09:11:01.349] <TB3> INFO: 8882500 events read in total (313798ms).
[09:11:29.266] <TB3> INFO: 9686750 events read in total (341715ms).
[09:11:57.213] <TB3> INFO: 10492700 events read in total (369662ms).
[09:12:25.345] <TB3> INFO: 11297400 events read in total (397794ms).
[09:12:53.950] <TB3> INFO: 12101900 events read in total (426399ms).
[09:13:22.279] <TB3> INFO: 12906600 events read in total (454728ms).
[09:13:50.513] <TB3> INFO: 13710000 events read in total (482962ms).
[09:14:18.350] <TB3> INFO: 14513950 events read in total (510799ms).
[09:14:46.507] <TB3> INFO: 15317100 events read in total (538956ms).
[09:15:14.430] <TB3> INFO: 16119950 events read in total (566879ms).
[09:15:42.978] <TB3> INFO: 16922450 events read in total (595427ms).
[09:16:11.176] <TB3> INFO: 17725850 events read in total (623625ms).
[09:16:39.336] <TB3> INFO: 18524100 events read in total (651785ms).
[09:17:07.598] <TB3> INFO: 19322200 events read in total (680047ms).
[09:17:36.119] <TB3> INFO: 20119950 events read in total (708568ms).
[09:18:04.328] <TB3> INFO: 20915200 events read in total (736777ms).
[09:18:33.273] <TB3> INFO: 21713300 events read in total (765722ms).
[09:19:01.812] <TB3> INFO: 22508750 events read in total (794261ms).
[09:19:30.347] <TB3> INFO: 23306050 events read in total (822796ms).
[09:19:58.763] <TB3> INFO: 24101750 events read in total (851212ms).
[09:20:26.971] <TB3> INFO: 24898950 events read in total (879420ms).
[09:20:55.890] <TB3> INFO: 25694250 events read in total (908339ms).
[09:21:24.417] <TB3> INFO: 26490350 events read in total (936866ms).
[09:21:53.123] <TB3> INFO: 27284900 events read in total (965572ms).
[09:22:21.812] <TB3> INFO: 28079600 events read in total (994261ms).
[09:22:51.014] <TB3> INFO: 28876650 events read in total (1023463ms).
[09:23:19.933] <TB3> INFO: 29670350 events read in total (1052382ms).
[09:23:48.676] <TB3> INFO: 30466600 events read in total (1081125ms).
[09:24:16.992] <TB3> INFO: 31262100 events read in total (1109441ms).
[09:24:45.560] <TB3> INFO: 32056650 events read in total (1138009ms).
[09:25:13.948] <TB3> INFO: 32853850 events read in total (1166397ms).
[09:25:43.002] <TB3> INFO: 33649200 events read in total (1195451ms).
[09:26:11.851] <TB3> INFO: 34448000 events read in total (1224300ms).
[09:26:40.381] <TB3> INFO: 35243850 events read in total (1252830ms).
[09:27:09.739] <TB3> INFO: 36044000 events read in total (1282188ms).
[09:27:30.425] <TB3> INFO: 36608000 events read in total (1302874ms).
[09:27:30.484] <TB3> INFO: Test took 1303755ms.
[09:27:30.885] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:27:32.658] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:27:34.603] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:27:36.477] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:27:38.428] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:27:40.351] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:27:42.096] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:27:43.806] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:27:45.531] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:27:47.748] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:27:49.892] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:27:51.692] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:27:54.162] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:27:56.297] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:27:58.373] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:28:00.377] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:28:02.301] <TB3> INFO: PixTestScurves::scurves() done
[09:28:02.301] <TB3> INFO: Vcal mean: 82.79 91.41 81.63 82.39 77.16 94.15 79.20 86.53 83.69 89.20 100.77 77.25 98.71 82.20 82.53 91.50
[09:28:02.301] <TB3> INFO: Vcal RMS: 4.10 5.80 4.31 4.27 4.49 4.72 3.80 4.69 4.73 5.17 6.51 4.06 5.40 4.51 4.14 5.57
[09:28:02.301] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1335 seconds
[09:28:02.301] <TB3> INFO: Decoding statistics:
[09:28:02.301] <TB3> INFO: General information:
[09:28:02.301] <TB3> INFO: 16bit words read: 0
[09:28:02.301] <TB3> INFO: valid events total: 0
[09:28:02.301] <TB3> INFO: empty events: 0
[09:28:02.301] <TB3> INFO: valid events with pixels: 0
[09:28:02.301] <TB3> INFO: valid pixel hits: 0
[09:28:02.301] <TB3> INFO: Event errors: 0
[09:28:02.301] <TB3> INFO: start marker: 0
[09:28:02.301] <TB3> INFO: stop marker: 0
[09:28:02.301] <TB3> INFO: overflow: 0
[09:28:02.301] <TB3> INFO: invalid 5bit words: 0
[09:28:02.301] <TB3> INFO: invalid XOR eye diagram: 0
[09:28:02.301] <TB3> INFO: frame (failed synchr.): 0
[09:28:02.301] <TB3> INFO: idle data (no TBM trl): 0
[09:28:02.301] <TB3> INFO: no data (only TBM hdr): 0
[09:28:02.301] <TB3> INFO: TBM errors: 0
[09:28:02.301] <TB3> INFO: flawed TBM headers: 0
[09:28:02.301] <TB3> INFO: flawed TBM trailers: 0
[09:28:02.301] <TB3> INFO: event ID mismatches: 0
[09:28:02.301] <TB3> INFO: ROC errors: 0
[09:28:02.301] <TB3> INFO: missing ROC header(s): 0
[09:28:02.301] <TB3> INFO: misplaced readback start: 0
[09:28:02.301] <TB3> INFO: Pixel decoding errors: 0
[09:28:02.301] <TB3> INFO: pixel data incomplete: 0
[09:28:02.301] <TB3> INFO: pixel address: 0
[09:28:02.301] <TB3> INFO: pulse height fill bit: 0
[09:28:02.301] <TB3> INFO: buffer corruption: 0
[09:28:02.416] <TB3> INFO: ######################################################################
[09:28:02.416] <TB3> INFO: PixTestTrim::doTest()
[09:28:02.416] <TB3> INFO: ######################################################################
[09:28:02.417] <TB3> INFO: ----------------------------------------------------------------------
[09:28:02.417] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[09:28:02.417] <TB3> INFO: ----------------------------------------------------------------------
[09:28:02.520] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:28:02.520] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:28:02.529] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:28:02.529] <TB3> INFO: run 1 of 1
[09:28:02.840] <TB3> INFO: Expecting 6281600 events.
[09:28:39.740] <TB3> INFO: 1091090 events read in total (36343ms).
[09:29:16.127] <TB3> INFO: 2175490 events read in total (72730ms).
[09:29:52.641] <TB3> INFO: 3253170 events read in total (109244ms).
[09:30:28.937] <TB3> INFO: 4337880 events read in total (145540ms).
[09:31:05.102] <TB3> INFO: 5425400 events read in total (181705ms).
[09:31:33.670] <TB3> INFO: 6281600 events read in total (210273ms).
[09:31:33.721] <TB3> INFO: Test took 211192ms.
[09:31:53.304] <TB3> INFO: ROC 0 VthrComp = 94
[09:31:53.304] <TB3> INFO: ROC 1 VthrComp = 93
[09:31:53.304] <TB3> INFO: ROC 2 VthrComp = 89
[09:31:53.304] <TB3> INFO: ROC 3 VthrComp = 93
[09:31:53.304] <TB3> INFO: ROC 4 VthrComp = 81
[09:31:53.305] <TB3> INFO: ROC 5 VthrComp = 105
[09:31:53.305] <TB3> INFO: ROC 6 VthrComp = 83
[09:31:53.305] <TB3> INFO: ROC 7 VthrComp = 94
[09:31:53.305] <TB3> INFO: ROC 8 VthrComp = 93
[09:31:53.305] <TB3> INFO: ROC 9 VthrComp = 97
[09:31:53.305] <TB3> INFO: ROC 10 VthrComp = 97
[09:31:53.305] <TB3> INFO: ROC 11 VthrComp = 85
[09:31:53.305] <TB3> INFO: ROC 12 VthrComp = 100
[09:31:53.306] <TB3> INFO: ROC 13 VthrComp = 87
[09:31:53.306] <TB3> INFO: ROC 14 VthrComp = 92
[09:31:53.306] <TB3> INFO: ROC 15 VthrComp = 101
[09:31:53.306] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[09:31:53.306] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:31:53.316] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:31:53.317] <TB3> INFO: run 1 of 1
[09:31:53.615] <TB3> INFO: Expecting 6281600 events.
[09:32:25.171] <TB3> INFO: 758760 events read in total (30999ms).
[09:32:55.937] <TB3> INFO: 1515370 events read in total (61765ms).
[09:33:26.915] <TB3> INFO: 2271760 events read in total (92743ms).
[09:33:57.634] <TB3> INFO: 3028260 events read in total (123462ms).
[09:34:28.521] <TB3> INFO: 3779300 events read in total (154349ms).
[09:34:59.274] <TB3> INFO: 4527900 events read in total (185102ms).
[09:35:29.750] <TB3> INFO: 5274690 events read in total (215578ms).
[09:36:00.699] <TB3> INFO: 6021260 events read in total (246527ms).
[09:36:11.785] <TB3> INFO: 6281600 events read in total (257613ms).
[09:36:11.847] <TB3> INFO: Test took 258530ms.
[09:36:36.042] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 54.4656 for pixel 24/79 mean/min/max = 43.4881/32.2673/54.709
[09:36:36.042] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 58.2296 for pixel 5/1 mean/min/max = 45.4671/32.4102/58.5239
[09:36:36.043] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 57.5352 for pixel 5/19 mean/min/max = 45.5524/33.4469/57.6579
[09:36:36.043] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 55.0732 for pixel 15/67 mean/min/max = 43.8506/32.2147/55.4866
[09:36:36.043] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 57.9597 for pixel 51/16 mean/min/max = 45.1447/32.2702/58.0192
[09:36:36.044] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 57.4035 for pixel 25/79 mean/min/max = 45.4954/33.5858/57.405
[09:36:36.044] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 56.0856 for pixel 45/0 mean/min/max = 44.6478/33.0615/56.2341
[09:36:36.044] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 56.4419 for pixel 0/41 mean/min/max = 44.5096/32.5498/56.4694
[09:36:36.045] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 56.5285 for pixel 23/76 mean/min/max = 44.4621/32.3917/56.5324
[09:36:36.045] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 57.8364 for pixel 30/2 mean/min/max = 44.9334/31.678/58.1888
[09:36:36.045] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 65.4557 for pixel 18/18 mean/min/max = 48.0788/30.6649/65.4927
[09:36:36.045] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 54.7226 for pixel 46/76 mean/min/max = 43.4721/32.0672/54.877
[09:36:36.046] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 59.4438 for pixel 8/2 mean/min/max = 45.6231/31.6847/59.5615
[09:36:36.046] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 57.3825 for pixel 0/66 mean/min/max = 44.6898/31.8003/57.5792
[09:36:36.046] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 54.9238 for pixel 18/41 mean/min/max = 43.635/32.2335/55.0365
[09:36:36.046] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 58.0892 for pixel 8/13 mean/min/max = 45.0633/31.7817/58.3448
[09:36:36.047] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:36:36.136] <TB3> INFO: Expecting 514560 events.
[09:36:47.144] <TB3> INFO: 514560 events read in total (10451ms).
[09:36:47.153] <TB3> INFO: Expecting 514560 events.
[09:36:58.103] <TB3> INFO: 514560 events read in total (10547ms).
[09:36:58.113] <TB3> INFO: Expecting 514560 events.
[09:37:09.167] <TB3> INFO: 514560 events read in total (10652ms).
[09:37:09.180] <TB3> INFO: Expecting 514560 events.
[09:37:20.026] <TB3> INFO: 514560 events read in total (10444ms).
[09:37:20.041] <TB3> INFO: Expecting 514560 events.
[09:37:30.965] <TB3> INFO: 514560 events read in total (10522ms).
[09:37:30.991] <TB3> INFO: Expecting 514560 events.
[09:37:41.827] <TB3> INFO: 514560 events read in total (10434ms).
[09:37:41.846] <TB3> INFO: Expecting 514560 events.
[09:37:52.643] <TB3> INFO: 514560 events read in total (10394ms).
[09:37:52.668] <TB3> INFO: Expecting 514560 events.
[09:38:03.376] <TB3> INFO: 514560 events read in total (10305ms).
[09:38:03.402] <TB3> INFO: Expecting 514560 events.
[09:38:14.113] <TB3> INFO: 514560 events read in total (10309ms).
[09:38:14.141] <TB3> INFO: Expecting 514560 events.
[09:38:24.920] <TB3> INFO: 514560 events read in total (10377ms).
[09:38:24.951] <TB3> INFO: Expecting 514560 events.
[09:38:35.672] <TB3> INFO: 514560 events read in total (10318ms).
[09:38:35.707] <TB3> INFO: Expecting 514560 events.
[09:38:46.475] <TB3> INFO: 514560 events read in total (10365ms).
[09:38:46.532] <TB3> INFO: Expecting 514560 events.
[09:38:57.418] <TB3> INFO: 514560 events read in total (10484ms).
[09:38:57.465] <TB3> INFO: Expecting 514560 events.
[09:39:08.374] <TB3> INFO: 514560 events read in total (10507ms).
[09:39:08.415] <TB3> INFO: Expecting 514560 events.
[09:39:19.263] <TB3> INFO: 514560 events read in total (10446ms).
[09:39:19.309] <TB3> INFO: Expecting 514560 events.
[09:39:30.096] <TB3> INFO: 514560 events read in total (10384ms).
[09:39:30.145] <TB3> INFO: Test took 174098ms.
[09:39:31.220] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:39:31.228] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:39:31.229] <TB3> INFO: run 1 of 1
[09:39:31.493] <TB3> INFO: Expecting 6281600 events.
[09:40:02.528] <TB3> INFO: 745900 events read in total (30478ms).
[09:40:33.057] <TB3> INFO: 1491330 events read in total (61007ms).
[09:41:03.980] <TB3> INFO: 2237510 events read in total (91930ms).
[09:41:34.368] <TB3> INFO: 2983460 events read in total (122318ms).
[09:42:04.346] <TB3> INFO: 3723540 events read in total (152296ms).
[09:42:35.121] <TB3> INFO: 4460560 events read in total (183071ms).
[09:43:05.511] <TB3> INFO: 5196230 events read in total (213461ms).
[09:43:36.962] <TB3> INFO: 5930730 events read in total (244912ms).
[09:43:51.734] <TB3> INFO: 6281600 events read in total (259684ms).
[09:43:51.796] <TB3> INFO: Test took 260568ms.
[09:44:15.227] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 9.287981 .. 54.003300
[09:44:15.525] <TB3> INFO: Expecting 208000 events.
[09:44:25.469] <TB3> INFO: 208000 events read in total (9387ms).
[09:44:25.471] <TB3> INFO: Test took 10241ms.
[09:44:25.557] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 9 .. 64 (-1/-1) hits flags = 528 (plus default)
[09:44:25.566] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:44:25.566] <TB3> INFO: run 1 of 1
[09:44:25.834] <TB3> INFO: Expecting 2329600 events.
[09:44:59.973] <TB3> INFO: 882920 events read in total (33583ms).
[09:45:32.875] <TB3> INFO: 1760980 events read in total (66485ms).
[09:45:54.349] <TB3> INFO: 2329600 events read in total (87959ms).
[09:45:54.372] <TB3> INFO: Test took 88806ms.
[09:46:09.087] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 21.194467 .. 46.090504
[09:46:09.350] <TB3> INFO: Expecting 208000 events.
[09:46:19.481] <TB3> INFO: 208000 events read in total (9574ms).
[09:46:19.482] <TB3> INFO: Test took 10392ms.
[09:46:19.569] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 11 .. 56 (-1/-1) hits flags = 528 (plus default)
[09:46:19.577] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:46:19.578] <TB3> INFO: run 1 of 1
[09:46:19.843] <TB3> INFO: Expecting 1913600 events.
[09:46:54.139] <TB3> INFO: 913570 events read in total (33740ms).
[09:47:27.958] <TB3> INFO: 1824430 events read in total (67559ms).
[09:47:31.539] <TB3> INFO: 1913600 events read in total (71140ms).
[09:47:31.558] <TB3> INFO: Test took 71980ms.
[09:47:46.084] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 19.657515 .. 43.140045
[09:47:46.365] <TB3> INFO: Expecting 208000 events.
[09:47:56.425] <TB3> INFO: 208000 events read in total (9503ms).
[09:47:56.426] <TB3> INFO: Test took 10340ms.
[09:47:56.512] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 53 (-1/-1) hits flags = 528 (plus default)
[09:47:56.521] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:47:56.521] <TB3> INFO: run 1 of 1
[09:47:56.787] <TB3> INFO: Expecting 1872000 events.
[09:48:31.643] <TB3> INFO: 950770 events read in total (34300ms).
[09:49:04.518] <TB3> INFO: 1872000 events read in total (67175ms).
[09:49:04.543] <TB3> INFO: Test took 68022ms.
[09:49:18.936] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 22.598505 .. 43.140045
[09:49:19.218] <TB3> INFO: Expecting 208000 events.
[09:49:29.211] <TB3> INFO: 208000 events read in total (9436ms).
[09:49:29.211] <TB3> INFO: Test took 10271ms.
[09:49:29.296] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 12 .. 53 (-1/-1) hits flags = 528 (plus default)
[09:49:29.305] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:49:29.305] <TB3> INFO: run 1 of 1
[09:49:29.567] <TB3> INFO: Expecting 1747200 events.
[09:50:03.994] <TB3> INFO: 931890 events read in total (33870ms).
[09:50:34.748] <TB3> INFO: 1747200 events read in total (64624ms).
[09:50:34.766] <TB3> INFO: Test took 65461ms.
[09:50:48.270] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:50:48.270] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:50:48.278] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:50:48.278] <TB3> INFO: run 1 of 1
[09:50:48.565] <TB3> INFO: Expecting 1705600 events.
[09:51:23.082] <TB3> INFO: 894550 events read in total (33960ms).
[09:51:55.069] <TB3> INFO: 1705600 events read in total (65948ms).
[09:51:55.088] <TB3> INFO: Test took 66809ms.
[09:52:08.737] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:52:08.737] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:52:08.737] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:52:08.737] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:52:08.738] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:52:08.738] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:52:08.738] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:52:08.738] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:52:08.738] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:52:08.738] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:52:08.738] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:52:08.738] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:52:08.738] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:52:08.738] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:52:08.739] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:52:08.739] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:52:08.739] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:52:08.745] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:52:08.751] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:52:08.757] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:52:08.764] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:52:08.770] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:52:08.777] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:52:08.784] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:52:08.791] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:52:08.797] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:52:08.803] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:52:08.809] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:52:08.815] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:52:08.821] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:52:08.827] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:52:08.834] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:52:08.840] <TB3> INFO: PixTestTrim::trimTest() done
[09:52:08.840] <TB3> INFO: vtrim: 94 108 101 95 92 110 96 94 90 112 113 88 114 92 102 113
[09:52:08.840] <TB3> INFO: vthrcomp: 94 93 89 93 81 105 83 94 93 97 97 85 100 87 92 101
[09:52:08.840] <TB3> INFO: vcal mean: 34.99 35.02 35.03 34.95 34.95 35.00 34.99 34.98 34.95 34.95 35.01 35.00 34.97 34.95 34.97 34.96
[09:52:08.840] <TB3> INFO: vcal RMS: 0.76 0.86 0.80 0.79 0.81 0.77 0.79 0.80 0.80 0.81 1.01 0.79 0.89 0.85 0.77 0.83
[09:52:08.840] <TB3> INFO: bits mean: 9.83 9.34 9.03 9.83 9.53 8.82 9.48 9.24 9.77 9.84 9.52 9.81 9.61 9.41 10.06 9.78
[09:52:08.840] <TB3> INFO: bits RMS: 2.61 2.75 2.73 2.58 2.67 2.74 2.63 2.80 2.60 2.62 2.65 2.65 2.70 2.86 2.46 2.64
[09:52:08.848] <TB3> INFO: ----------------------------------------------------------------------
[09:52:08.848] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[09:52:08.848] <TB3> INFO: ----------------------------------------------------------------------
[09:52:08.853] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[09:52:08.861] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:52:08.861] <TB3> INFO: run 1 of 1
[09:52:09.123] <TB3> INFO: Expecting 4160000 events.
[09:52:48.068] <TB3> INFO: 922225 events read in total (38388ms).
[09:53:26.308] <TB3> INFO: 1839385 events read in total (76628ms).
[09:54:04.188] <TB3> INFO: 2747935 events read in total (114508ms).
[09:54:41.024] <TB3> INFO: 3654545 events read in total (151344ms).
[09:55:02.893] <TB3> INFO: 4160000 events read in total (173213ms).
[09:55:02.948] <TB3> INFO: Test took 174088ms.
[09:55:33.183] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 164 (-1/-1) hits flags = 528 (plus default)
[09:55:33.191] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:55:33.191] <TB3> INFO: run 1 of 1
[09:55:33.456] <TB3> INFO: Expecting 3432000 events.
[09:56:11.995] <TB3> INFO: 965000 events read in total (37982ms).
[09:56:50.411] <TB3> INFO: 1920520 events read in total (76398ms).
[09:57:28.007] <TB3> INFO: 2866395 events read in total (113994ms).
[09:57:51.404] <TB3> INFO: 3432000 events read in total (137391ms).
[09:57:51.463] <TB3> INFO: Test took 138271ms.
[09:58:17.464] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[09:58:17.476] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:58:17.476] <TB3> INFO: run 1 of 1
[09:58:17.764] <TB3> INFO: Expecting 3182400 events.
[09:58:57.800] <TB3> INFO: 999575 events read in total (39479ms).
[09:59:36.682] <TB3> INFO: 1985560 events read in total (78361ms).
[10:00:16.610] <TB3> INFO: 2964080 events read in total (118289ms).
[10:00:25.676] <TB3> INFO: 3182400 events read in total (127355ms).
[10:00:25.715] <TB3> INFO: Test took 128239ms.
[10:00:49.856] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 153 (-1/-1) hits flags = 528 (plus default)
[10:00:49.865] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:00:49.865] <TB3> INFO: run 1 of 1
[10:00:50.131] <TB3> INFO: Expecting 3203200 events.
[10:01:29.799] <TB3> INFO: 995420 events read in total (39111ms).
[10:02:08.615] <TB3> INFO: 1977590 events read in total (77928ms).
[10:02:46.817] <TB3> INFO: 2951945 events read in total (116129ms).
[10:02:56.921] <TB3> INFO: 3203200 events read in total (126233ms).
[10:02:56.971] <TB3> INFO: Test took 127106ms.
[10:03:22.373] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[10:03:22.382] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:03:22.382] <TB3> INFO: run 1 of 1
[10:03:22.645] <TB3> INFO: Expecting 3182400 events.
[10:04:02.038] <TB3> INFO: 997860 events read in total (38836ms).
[10:04:42.170] <TB3> INFO: 1981965 events read in total (78968ms).
[10:05:20.734] <TB3> INFO: 2958745 events read in total (117532ms).
[10:05:29.963] <TB3> INFO: 3182400 events read in total (126761ms).
[10:05:30.008] <TB3> INFO: Test took 127626ms.
[10:05:55.214] <TB3> INFO: PixTestTrim::trimBitTest() done
[10:05:55.216] <TB3> INFO: PixTestTrim::doTest() done, duration: 2272 seconds
[10:05:55.216] <TB3> INFO: Decoding statistics:
[10:05:55.216] <TB3> INFO: General information:
[10:05:55.216] <TB3> INFO: 16bit words read: 0
[10:05:55.216] <TB3> INFO: valid events total: 0
[10:05:55.216] <TB3> INFO: empty events: 0
[10:05:55.216] <TB3> INFO: valid events with pixels: 0
[10:05:55.216] <TB3> INFO: valid pixel hits: 0
[10:05:55.216] <TB3> INFO: Event errors: 0
[10:05:55.216] <TB3> INFO: start marker: 0
[10:05:55.216] <TB3> INFO: stop marker: 0
[10:05:55.216] <TB3> INFO: overflow: 0
[10:05:55.216] <TB3> INFO: invalid 5bit words: 0
[10:05:55.216] <TB3> INFO: invalid XOR eye diagram: 0
[10:05:55.216] <TB3> INFO: frame (failed synchr.): 0
[10:05:55.216] <TB3> INFO: idle data (no TBM trl): 0
[10:05:55.216] <TB3> INFO: no data (only TBM hdr): 0
[10:05:55.216] <TB3> INFO: TBM errors: 0
[10:05:55.216] <TB3> INFO: flawed TBM headers: 0
[10:05:55.216] <TB3> INFO: flawed TBM trailers: 0
[10:05:55.216] <TB3> INFO: event ID mismatches: 0
[10:05:55.216] <TB3> INFO: ROC errors: 0
[10:05:55.216] <TB3> INFO: missing ROC header(s): 0
[10:05:55.216] <TB3> INFO: misplaced readback start: 0
[10:05:55.216] <TB3> INFO: Pixel decoding errors: 0
[10:05:55.216] <TB3> INFO: pixel data incomplete: 0
[10:05:55.216] <TB3> INFO: pixel address: 0
[10:05:55.216] <TB3> INFO: pulse height fill bit: 0
[10:05:55.216] <TB3> INFO: buffer corruption: 0
[10:05:55.924] <TB3> INFO: ######################################################################
[10:05:55.924] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[10:05:55.924] <TB3> INFO: ######################################################################
[10:05:56.192] <TB3> INFO: Expecting 41600 events.
[10:05:59.630] <TB3> INFO: 41600 events read in total (2882ms).
[10:05:59.631] <TB3> INFO: Test took 3706ms.
[10:06:00.091] <TB3> INFO: Expecting 41600 events.
[10:06:03.513] <TB3> INFO: 41600 events read in total (2866ms).
[10:06:03.514] <TB3> INFO: Test took 3688ms.
[10:06:03.800] <TB3> INFO: Expecting 41600 events.
[10:06:07.358] <TB3> INFO: 41600 events read in total (3001ms).
[10:06:07.358] <TB3> INFO: Test took 3821ms.
[10:06:07.615] <TB3> INFO: Expecting 2560 events.
[10:06:08.470] <TB3> INFO: 2560 events read in total (298ms).
[10:06:08.470] <TB3> INFO: Test took 1098ms.
[10:06:08.777] <TB3> INFO: Expecting 2560 events.
[10:06:09.631] <TB3> INFO: 2560 events read in total (297ms).
[10:06:09.631] <TB3> INFO: Test took 1160ms.
[10:06:09.937] <TB3> INFO: Expecting 2560 events.
[10:06:10.792] <TB3> INFO: 2560 events read in total (298ms).
[10:06:10.792] <TB3> INFO: Test took 1160ms.
[10:06:11.098] <TB3> INFO: Expecting 2560 events.
[10:06:11.952] <TB3> INFO: 2560 events read in total (298ms).
[10:06:11.952] <TB3> INFO: Test took 1160ms.
[10:06:12.258] <TB3> INFO: Expecting 2560 events.
[10:06:13.112] <TB3> INFO: 2560 events read in total (297ms).
[10:06:13.112] <TB3> INFO: Test took 1159ms.
[10:06:13.418] <TB3> INFO: Expecting 2560 events.
[10:06:14.274] <TB3> INFO: 2560 events read in total (299ms).
[10:06:14.274] <TB3> INFO: Test took 1161ms.
[10:06:14.580] <TB3> INFO: Expecting 2560 events.
[10:06:15.434] <TB3> INFO: 2560 events read in total (298ms).
[10:06:15.434] <TB3> INFO: Test took 1160ms.
[10:06:15.740] <TB3> INFO: Expecting 2560 events.
[10:06:16.596] <TB3> INFO: 2560 events read in total (299ms).
[10:06:16.596] <TB3> INFO: Test took 1161ms.
[10:06:16.902] <TB3> INFO: Expecting 2560 events.
[10:06:17.755] <TB3> INFO: 2560 events read in total (296ms).
[10:06:17.755] <TB3> INFO: Test took 1158ms.
[10:06:18.061] <TB3> INFO: Expecting 2560 events.
[10:06:18.915] <TB3> INFO: 2560 events read in total (297ms).
[10:06:18.915] <TB3> INFO: Test took 1158ms.
[10:06:19.221] <TB3> INFO: Expecting 2560 events.
[10:06:20.079] <TB3> INFO: 2560 events read in total (301ms).
[10:06:20.079] <TB3> INFO: Test took 1162ms.
[10:06:20.386] <TB3> INFO: Expecting 2560 events.
[10:06:21.240] <TB3> INFO: 2560 events read in total (297ms).
[10:06:21.240] <TB3> INFO: Test took 1160ms.
[10:06:21.547] <TB3> INFO: Expecting 2560 events.
[10:06:22.400] <TB3> INFO: 2560 events read in total (297ms).
[10:06:22.400] <TB3> INFO: Test took 1160ms.
[10:06:22.706] <TB3> INFO: Expecting 2560 events.
[10:06:23.560] <TB3> INFO: 2560 events read in total (297ms).
[10:06:23.560] <TB3> INFO: Test took 1160ms.
[10:06:23.867] <TB3> INFO: Expecting 2560 events.
[10:06:24.721] <TB3> INFO: 2560 events read in total (298ms).
[10:06:24.721] <TB3> INFO: Test took 1160ms.
[10:06:25.027] <TB3> INFO: Expecting 2560 events.
[10:06:25.883] <TB3> INFO: 2560 events read in total (299ms).
[10:06:25.883] <TB3> INFO: Test took 1162ms.
[10:06:25.888] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:06:26.190] <TB3> INFO: Expecting 655360 events.
[10:06:40.247] <TB3> INFO: 655360 events read in total (13500ms).
[10:06:40.258] <TB3> INFO: Expecting 655360 events.
[10:06:54.204] <TB3> INFO: 655360 events read in total (13544ms).
[10:06:54.219] <TB3> INFO: Expecting 655360 events.
[10:07:08.288] <TB3> INFO: 655360 events read in total (13667ms).
[10:07:08.308] <TB3> INFO: Expecting 655360 events.
[10:07:22.329] <TB3> INFO: 655360 events read in total (13618ms).
[10:07:22.351] <TB3> INFO: Expecting 655360 events.
[10:07:36.304] <TB3> INFO: 655360 events read in total (13551ms).
[10:07:36.332] <TB3> INFO: Expecting 655360 events.
[10:07:50.226] <TB3> INFO: 655360 events read in total (13492ms).
[10:07:50.262] <TB3> INFO: Expecting 655360 events.
[10:08:04.166] <TB3> INFO: 655360 events read in total (13502ms).
[10:08:04.203] <TB3> INFO: Expecting 655360 events.
[10:08:18.036] <TB3> INFO: 655360 events read in total (13430ms).
[10:08:18.078] <TB3> INFO: Expecting 655360 events.
[10:08:31.882] <TB3> INFO: 655360 events read in total (13401ms).
[10:08:31.926] <TB3> INFO: Expecting 655360 events.
[10:08:45.913] <TB3> INFO: 655360 events read in total (13584ms).
[10:08:45.962] <TB3> INFO: Expecting 655360 events.
[10:08:59.867] <TB3> INFO: 655360 events read in total (13503ms).
[10:08:59.920] <TB3> INFO: Expecting 655360 events.
[10:09:13.827] <TB3> INFO: 655360 events read in total (13505ms).
[10:09:13.885] <TB3> INFO: Expecting 655360 events.
[10:09:27.762] <TB3> INFO: 655360 events read in total (13475ms).
[10:09:27.822] <TB3> INFO: Expecting 655360 events.
[10:09:41.812] <TB3> INFO: 655360 events read in total (13587ms).
[10:09:41.876] <TB3> INFO: Expecting 655360 events.
[10:09:55.726] <TB3> INFO: 655360 events read in total (13448ms).
[10:09:55.795] <TB3> INFO: Expecting 655360 events.
[10:10:09.776] <TB3> INFO: 655360 events read in total (13579ms).
[10:10:09.858] <TB3> INFO: Test took 223970ms.
[10:10:09.953] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:10:10.181] <TB3> INFO: Expecting 655360 events.
[10:10:24.224] <TB3> INFO: 655360 events read in total (13486ms).
[10:10:24.235] <TB3> INFO: Expecting 655360 events.
[10:10:38.167] <TB3> INFO: 655360 events read in total (13530ms).
[10:10:38.181] <TB3> INFO: Expecting 655360 events.
[10:10:52.045] <TB3> INFO: 655360 events read in total (13461ms).
[10:10:52.063] <TB3> INFO: Expecting 655360 events.
[10:11:05.872] <TB3> INFO: 655360 events read in total (13406ms).
[10:11:05.894] <TB3> INFO: Expecting 655360 events.
[10:11:19.792] <TB3> INFO: 655360 events read in total (13495ms).
[10:11:19.821] <TB3> INFO: Expecting 655360 events.
[10:11:33.687] <TB3> INFO: 655360 events read in total (13464ms).
[10:11:33.717] <TB3> INFO: Expecting 655360 events.
[10:11:47.597] <TB3> INFO: 655360 events read in total (13478ms).
[10:11:47.632] <TB3> INFO: Expecting 655360 events.
[10:12:01.519] <TB3> INFO: 655360 events read in total (13485ms).
[10:12:01.560] <TB3> INFO: Expecting 655360 events.
[10:12:15.385] <TB3> INFO: 655360 events read in total (13423ms).
[10:12:15.427] <TB3> INFO: Expecting 655360 events.
[10:12:29.433] <TB3> INFO: 655360 events read in total (13603ms).
[10:12:29.482] <TB3> INFO: Expecting 655360 events.
[10:12:43.197] <TB3> INFO: 655360 events read in total (13313ms).
[10:12:43.254] <TB3> INFO: Expecting 655360 events.
[10:12:57.042] <TB3> INFO: 655360 events read in total (13385ms).
[10:12:57.096] <TB3> INFO: Expecting 655360 events.
[10:13:10.951] <TB3> INFO: 655360 events read in total (13452ms).
[10:13:11.010] <TB3> INFO: Expecting 655360 events.
[10:13:24.982] <TB3> INFO: 655360 events read in total (13570ms).
[10:13:25.049] <TB3> INFO: Expecting 655360 events.
[10:13:39.176] <TB3> INFO: 655360 events read in total (13725ms).
[10:13:39.243] <TB3> INFO: Expecting 655360 events.
[10:13:53.309] <TB3> INFO: 655360 events read in total (13664ms).
[10:13:53.380] <TB3> INFO: Test took 223427ms.
[10:13:53.579] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.586] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.595] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.603] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.611] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.621] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.629] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.637] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.646] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.654] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.661] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[10:13:53.670] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[10:13:53.678] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[10:13:53.687] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.695] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.703] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.711] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.720] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.726] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:13:53.802] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:13:53.803] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:13:53.803] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:13:53.803] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:13:53.803] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:13:53.803] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:13:53.803] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:13:53.803] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:13:53.803] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:13:53.804] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:13:53.804] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:13:53.804] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:13:53.804] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:13:53.804] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:13:53.804] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:13:53.804] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:13:54.074] <TB3> INFO: Expecting 41600 events.
[10:13:57.241] <TB3> INFO: 41600 events read in total (2610ms).
[10:13:57.242] <TB3> INFO: Test took 3433ms.
[10:13:57.700] <TB3> INFO: Expecting 41600 events.
[10:14:00.814] <TB3> INFO: 41600 events read in total (2557ms).
[10:14:00.815] <TB3> INFO: Test took 3385ms.
[10:14:01.276] <TB3> INFO: Expecting 41600 events.
[10:14:04.477] <TB3> INFO: 41600 events read in total (2644ms).
[10:14:04.478] <TB3> INFO: Test took 3475ms.
[10:14:04.673] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:04.763] <TB3> INFO: Expecting 2560 events.
[10:14:05.617] <TB3> INFO: 2560 events read in total (297ms).
[10:14:05.617] <TB3> INFO: Test took 945ms.
[10:14:05.620] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:05.924] <TB3> INFO: Expecting 2560 events.
[10:14:06.779] <TB3> INFO: 2560 events read in total (298ms).
[10:14:06.779] <TB3> INFO: Test took 1159ms.
[10:14:06.783] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:07.085] <TB3> INFO: Expecting 2560 events.
[10:14:07.944] <TB3> INFO: 2560 events read in total (302ms).
[10:14:07.944] <TB3> INFO: Test took 1162ms.
[10:14:07.946] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:08.252] <TB3> INFO: Expecting 2560 events.
[10:14:09.114] <TB3> INFO: 2560 events read in total (305ms).
[10:14:09.114] <TB3> INFO: Test took 1168ms.
[10:14:09.118] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:09.421] <TB3> INFO: Expecting 2560 events.
[10:14:10.274] <TB3> INFO: 2560 events read in total (296ms).
[10:14:10.274] <TB3> INFO: Test took 1156ms.
[10:14:10.276] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:10.580] <TB3> INFO: Expecting 2560 events.
[10:14:11.438] <TB3> INFO: 2560 events read in total (301ms).
[10:14:11.439] <TB3> INFO: Test took 1163ms.
[10:14:11.443] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:11.744] <TB3> INFO: Expecting 2560 events.
[10:14:12.604] <TB3> INFO: 2560 events read in total (303ms).
[10:14:12.605] <TB3> INFO: Test took 1163ms.
[10:14:12.608] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:12.911] <TB3> INFO: Expecting 2560 events.
[10:14:13.765] <TB3> INFO: 2560 events read in total (297ms).
[10:14:13.766] <TB3> INFO: Test took 1158ms.
[10:14:13.768] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:14.072] <TB3> INFO: Expecting 2560 events.
[10:14:14.930] <TB3> INFO: 2560 events read in total (301ms).
[10:14:14.930] <TB3> INFO: Test took 1162ms.
[10:14:14.933] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:15.236] <TB3> INFO: Expecting 2560 events.
[10:14:16.096] <TB3> INFO: 2560 events read in total (303ms).
[10:14:16.096] <TB3> INFO: Test took 1163ms.
[10:14:16.099] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:16.402] <TB3> INFO: Expecting 2560 events.
[10:14:17.265] <TB3> INFO: 2560 events read in total (306ms).
[10:14:17.265] <TB3> INFO: Test took 1166ms.
[10:14:17.269] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:17.571] <TB3> INFO: Expecting 2560 events.
[10:14:18.424] <TB3> INFO: 2560 events read in total (296ms).
[10:14:18.425] <TB3> INFO: Test took 1157ms.
[10:14:18.428] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:18.731] <TB3> INFO: Expecting 2560 events.
[10:14:19.594] <TB3> INFO: 2560 events read in total (306ms).
[10:14:19.595] <TB3> INFO: Test took 1167ms.
[10:14:19.599] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:19.901] <TB3> INFO: Expecting 2560 events.
[10:14:20.758] <TB3> INFO: 2560 events read in total (300ms).
[10:14:20.758] <TB3> INFO: Test took 1159ms.
[10:14:20.763] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:21.064] <TB3> INFO: Expecting 2560 events.
[10:14:21.924] <TB3> INFO: 2560 events read in total (303ms).
[10:14:21.925] <TB3> INFO: Test took 1163ms.
[10:14:21.928] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:22.230] <TB3> INFO: Expecting 2560 events.
[10:14:23.087] <TB3> INFO: 2560 events read in total (300ms).
[10:14:23.087] <TB3> INFO: Test took 1159ms.
[10:14:23.090] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:23.394] <TB3> INFO: Expecting 2560 events.
[10:14:24.254] <TB3> INFO: 2560 events read in total (303ms).
[10:14:24.254] <TB3> INFO: Test took 1164ms.
[10:14:24.257] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:24.561] <TB3> INFO: Expecting 2560 events.
[10:14:25.418] <TB3> INFO: 2560 events read in total (301ms).
[10:14:25.419] <TB3> INFO: Test took 1162ms.
[10:14:25.422] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:25.725] <TB3> INFO: Expecting 2560 events.
[10:14:26.581] <TB3> INFO: 2560 events read in total (299ms).
[10:14:26.581] <TB3> INFO: Test took 1159ms.
[10:14:26.584] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:26.890] <TB3> INFO: Expecting 2560 events.
[10:14:27.747] <TB3> INFO: 2560 events read in total (300ms).
[10:14:27.747] <TB3> INFO: Test took 1163ms.
[10:14:27.750] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:28.054] <TB3> INFO: Expecting 2560 events.
[10:14:28.916] <TB3> INFO: 2560 events read in total (305ms).
[10:14:28.916] <TB3> INFO: Test took 1166ms.
[10:14:28.920] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:29.222] <TB3> INFO: Expecting 2560 events.
[10:14:30.078] <TB3> INFO: 2560 events read in total (299ms).
[10:14:30.079] <TB3> INFO: Test took 1160ms.
[10:14:30.082] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:30.384] <TB3> INFO: Expecting 2560 events.
[10:14:31.238] <TB3> INFO: 2560 events read in total (297ms).
[10:14:31.238] <TB3> INFO: Test took 1156ms.
[10:14:31.241] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:31.545] <TB3> INFO: Expecting 2560 events.
[10:14:32.405] <TB3> INFO: 2560 events read in total (303ms).
[10:14:32.405] <TB3> INFO: Test took 1165ms.
[10:14:32.409] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:32.712] <TB3> INFO: Expecting 2560 events.
[10:14:33.568] <TB3> INFO: 2560 events read in total (299ms).
[10:14:33.569] <TB3> INFO: Test took 1160ms.
[10:14:33.572] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:33.877] <TB3> INFO: Expecting 2560 events.
[10:14:34.730] <TB3> INFO: 2560 events read in total (297ms).
[10:14:34.731] <TB3> INFO: Test took 1159ms.
[10:14:34.734] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:35.037] <TB3> INFO: Expecting 2560 events.
[10:14:35.891] <TB3> INFO: 2560 events read in total (297ms).
[10:14:35.891] <TB3> INFO: Test took 1157ms.
[10:14:35.894] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:36.198] <TB3> INFO: Expecting 2560 events.
[10:14:37.056] <TB3> INFO: 2560 events read in total (301ms).
[10:14:37.056] <TB3> INFO: Test took 1162ms.
[10:14:37.059] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:37.363] <TB3> INFO: Expecting 2560 events.
[10:14:38.224] <TB3> INFO: 2560 events read in total (304ms).
[10:14:38.225] <TB3> INFO: Test took 1166ms.
[10:14:38.228] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:38.531] <TB3> INFO: Expecting 2560 events.
[10:14:39.385] <TB3> INFO: 2560 events read in total (298ms).
[10:14:39.385] <TB3> INFO: Test took 1157ms.
[10:14:39.389] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:39.692] <TB3> INFO: Expecting 2560 events.
[10:14:40.549] <TB3> INFO: 2560 events read in total (301ms).
[10:14:40.550] <TB3> INFO: Test took 1162ms.
[10:14:40.554] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:40.856] <TB3> INFO: Expecting 2560 events.
[10:14:41.715] <TB3> INFO: 2560 events read in total (303ms).
[10:14:41.716] <TB3> INFO: Test took 1162ms.
[10:14:42.228] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 526 seconds
[10:14:42.228] <TB3> INFO: PH scale (per ROC): 89 83 82 92 102 88 87 77 86 86 78 83 75 79 85 85
[10:14:42.228] <TB3> INFO: PH offset (per ROC): 145 157 172 151 149 160 149 137 144 144 169 137 159 149 148 150
[10:14:42.233] <TB3> INFO: Decoding statistics:
[10:14:42.233] <TB3> INFO: General information:
[10:14:42.233] <TB3> INFO: 16bit words read: 86918
[10:14:42.233] <TB3> INFO: valid events total: 10240
[10:14:42.233] <TB3> INFO: empty events: 7741
[10:14:42.233] <TB3> INFO: valid events with pixels: 2499
[10:14:42.233] <TB3> INFO: valid pixel hits: 2499
[10:14:42.233] <TB3> INFO: Event errors: 0
[10:14:42.233] <TB3> INFO: start marker: 0
[10:14:42.233] <TB3> INFO: stop marker: 0
[10:14:42.233] <TB3> INFO: overflow: 0
[10:14:42.233] <TB3> INFO: invalid 5bit words: 0
[10:14:42.233] <TB3> INFO: invalid XOR eye diagram: 0
[10:14:42.233] <TB3> INFO: frame (failed synchr.): 0
[10:14:42.233] <TB3> INFO: idle data (no TBM trl): 0
[10:14:42.233] <TB3> INFO: no data (only TBM hdr): 0
[10:14:42.233] <TB3> INFO: TBM errors: 0
[10:14:42.233] <TB3> INFO: flawed TBM headers: 0
[10:14:42.233] <TB3> INFO: flawed TBM trailers: 0
[10:14:42.233] <TB3> INFO: event ID mismatches: 0
[10:14:42.233] <TB3> INFO: ROC errors: 0
[10:14:42.233] <TB3> INFO: missing ROC header(s): 0
[10:14:42.233] <TB3> INFO: misplaced readback start: 0
[10:14:42.233] <TB3> INFO: Pixel decoding errors: 0
[10:14:42.233] <TB3> INFO: pixel data incomplete: 0
[10:14:42.233] <TB3> INFO: pixel address: 0
[10:14:42.233] <TB3> INFO: pulse height fill bit: 0
[10:14:42.233] <TB3> INFO: buffer corruption: 0
[10:14:42.410] <TB3> INFO: ######################################################################
[10:14:42.410] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[10:14:42.411] <TB3> INFO: ######################################################################
[10:14:42.421] <TB3> INFO: scanning low vcal = 10
[10:14:42.707] <TB3> INFO: Expecting 41600 events.
[10:14:46.137] <TB3> INFO: 41600 events read in total (2874ms).
[10:14:46.137] <TB3> INFO: Test took 3715ms.
[10:14:46.139] <TB3> INFO: scanning low vcal = 20
[10:14:46.433] <TB3> INFO: Expecting 41600 events.
[10:14:49.839] <TB3> INFO: 41600 events read in total (2849ms).
[10:14:49.839] <TB3> INFO: Test took 3699ms.
[10:14:49.842] <TB3> INFO: scanning low vcal = 30
[10:14:50.138] <TB3> INFO: Expecting 41600 events.
[10:14:53.562] <TB3> INFO: 41600 events read in total (2867ms).
[10:14:53.562] <TB3> INFO: Test took 3720ms.
[10:14:53.579] <TB3> INFO: scanning low vcal = 40
[10:14:53.852] <TB3> INFO: Expecting 41600 events.
[10:14:57.738] <TB3> INFO: 41600 events read in total (3329ms).
[10:14:57.739] <TB3> INFO: Test took 4159ms.
[10:14:57.742] <TB3> INFO: scanning low vcal = 50
[10:14:58.010] <TB3> INFO: Expecting 41600 events.
[10:15:01.954] <TB3> INFO: 41600 events read in total (3387ms).
[10:15:01.954] <TB3> INFO: Test took 4212ms.
[10:15:01.958] <TB3> INFO: scanning low vcal = 60
[10:15:02.224] <TB3> INFO: Expecting 41600 events.
[10:15:06.183] <TB3> INFO: 41600 events read in total (3402ms).
[10:15:06.184] <TB3> INFO: Test took 4226ms.
[10:15:06.187] <TB3> INFO: scanning low vcal = 70
[10:15:06.453] <TB3> INFO: Expecting 41600 events.
[10:15:10.424] <TB3> INFO: 41600 events read in total (3414ms).
[10:15:10.424] <TB3> INFO: Test took 4237ms.
[10:15:10.428] <TB3> INFO: scanning low vcal = 80
[10:15:10.690] <TB3> INFO: Expecting 41600 events.
[10:15:14.642] <TB3> INFO: 41600 events read in total (3394ms).
[10:15:14.643] <TB3> INFO: Test took 4215ms.
[10:15:14.646] <TB3> INFO: scanning low vcal = 90
[10:15:14.934] <TB3> INFO: Expecting 41600 events.
[10:15:18.892] <TB3> INFO: 41600 events read in total (3401ms).
[10:15:18.893] <TB3> INFO: Test took 4246ms.
[10:15:18.917] <TB3> INFO: scanning low vcal = 100
[10:15:19.181] <TB3> INFO: Expecting 41600 events.
[10:15:23.140] <TB3> INFO: 41600 events read in total (3402ms).
[10:15:23.141] <TB3> INFO: Test took 4224ms.
[10:15:23.144] <TB3> INFO: scanning low vcal = 110
[10:15:23.407] <TB3> INFO: Expecting 41600 events.
[10:15:27.368] <TB3> INFO: 41600 events read in total (3405ms).
[10:15:27.368] <TB3> INFO: Test took 4223ms.
[10:15:27.372] <TB3> INFO: scanning low vcal = 120
[10:15:27.638] <TB3> INFO: Expecting 41600 events.
[10:15:31.618] <TB3> INFO: 41600 events read in total (3423ms).
[10:15:31.619] <TB3> INFO: Test took 4246ms.
[10:15:31.622] <TB3> INFO: scanning low vcal = 130
[10:15:31.887] <TB3> INFO: Expecting 41600 events.
[10:15:35.817] <TB3> INFO: 41600 events read in total (3373ms).
[10:15:35.818] <TB3> INFO: Test took 4196ms.
[10:15:35.821] <TB3> INFO: scanning low vcal = 140
[10:15:36.084] <TB3> INFO: Expecting 41600 events.
[10:15:40.031] <TB3> INFO: 41600 events read in total (3392ms).
[10:15:40.032] <TB3> INFO: Test took 4211ms.
[10:15:40.036] <TB3> INFO: scanning low vcal = 150
[10:15:40.296] <TB3> INFO: Expecting 41600 events.
[10:15:44.256] <TB3> INFO: 41600 events read in total (3403ms).
[10:15:44.256] <TB3> INFO: Test took 4220ms.
[10:15:44.260] <TB3> INFO: scanning low vcal = 160
[10:15:44.525] <TB3> INFO: Expecting 41600 events.
[10:15:48.487] <TB3> INFO: 41600 events read in total (3405ms).
[10:15:48.488] <TB3> INFO: Test took 4228ms.
[10:15:48.518] <TB3> INFO: scanning low vcal = 170
[10:15:48.859] <TB3> INFO: Expecting 41600 events.
[10:15:52.840] <TB3> INFO: 41600 events read in total (3424ms).
[10:15:52.840] <TB3> INFO: Test took 4322ms.
[10:15:52.845] <TB3> INFO: scanning low vcal = 180
[10:15:53.107] <TB3> INFO: Expecting 41600 events.
[10:15:57.105] <TB3> INFO: 41600 events read in total (3441ms).
[10:15:57.105] <TB3> INFO: Test took 4260ms.
[10:15:57.109] <TB3> INFO: scanning low vcal = 190
[10:15:57.371] <TB3> INFO: Expecting 41600 events.
[10:16:01.326] <TB3> INFO: 41600 events read in total (3398ms).
[10:16:01.326] <TB3> INFO: Test took 4217ms.
[10:16:01.330] <TB3> INFO: scanning low vcal = 200
[10:16:01.603] <TB3> INFO: Expecting 41600 events.
[10:16:05.532] <TB3> INFO: 41600 events read in total (3372ms).
[10:16:05.533] <TB3> INFO: Test took 4203ms.
[10:16:05.551] <TB3> INFO: scanning low vcal = 210
[10:16:05.817] <TB3> INFO: Expecting 41600 events.
[10:16:09.734] <TB3> INFO: 41600 events read in total (3360ms).
[10:16:09.736] <TB3> INFO: Test took 4185ms.
[10:16:09.740] <TB3> INFO: scanning low vcal = 220
[10:16:10.002] <TB3> INFO: Expecting 41600 events.
[10:16:13.918] <TB3> INFO: 41600 events read in total (3359ms).
[10:16:13.918] <TB3> INFO: Test took 4177ms.
[10:16:13.922] <TB3> INFO: scanning low vcal = 230
[10:16:14.205] <TB3> INFO: Expecting 41600 events.
[10:16:18.141] <TB3> INFO: 41600 events read in total (3380ms).
[10:16:18.142] <TB3> INFO: Test took 4220ms.
[10:16:18.145] <TB3> INFO: scanning low vcal = 240
[10:16:18.411] <TB3> INFO: Expecting 41600 events.
[10:16:22.337] <TB3> INFO: 41600 events read in total (3369ms).
[10:16:22.338] <TB3> INFO: Test took 4192ms.
[10:16:22.342] <TB3> INFO: scanning low vcal = 250
[10:16:22.603] <TB3> INFO: Expecting 41600 events.
[10:16:26.530] <TB3> INFO: 41600 events read in total (3370ms).
[10:16:26.531] <TB3> INFO: Test took 4189ms.
[10:16:26.536] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[10:16:26.797] <TB3> INFO: Expecting 41600 events.
[10:16:30.724] <TB3> INFO: 41600 events read in total (3370ms).
[10:16:30.725] <TB3> INFO: Test took 4189ms.
[10:16:30.729] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[10:16:30.991] <TB3> INFO: Expecting 41600 events.
[10:16:34.962] <TB3> INFO: 41600 events read in total (3414ms).
[10:16:34.963] <TB3> INFO: Test took 4234ms.
[10:16:34.966] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[10:16:35.231] <TB3> INFO: Expecting 41600 events.
[10:16:39.174] <TB3> INFO: 41600 events read in total (3386ms).
[10:16:39.175] <TB3> INFO: Test took 4208ms.
[10:16:39.178] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[10:16:39.440] <TB3> INFO: Expecting 41600 events.
[10:16:43.394] <TB3> INFO: 41600 events read in total (3398ms).
[10:16:43.394] <TB3> INFO: Test took 4215ms.
[10:16:43.398] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[10:16:43.665] <TB3> INFO: Expecting 41600 events.
[10:16:47.643] <TB3> INFO: 41600 events read in total (3421ms).
[10:16:47.643] <TB3> INFO: Test took 4245ms.
[10:16:48.162] <TB3> INFO: PixTestGainPedestal::measure() done
[10:17:19.648] <TB3> INFO: PixTestGainPedestal::fit() done
[10:17:19.649] <TB3> INFO: non-linearity mean: 0.949 0.955 0.946 0.952 0.956 0.952 0.957 0.952 0.952 0.953 0.961 0.955 0.960 0.956 0.954 0.954
[10:17:19.649] <TB3> INFO: non-linearity RMS: 0.006 0.006 0.007 0.006 0.005 0.007 0.006 0.006 0.006 0.006 0.005 0.006 0.006 0.007 0.005 0.005
[10:17:19.649] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[10:17:19.668] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[10:17:19.688] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[10:17:19.707] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[10:17:19.726] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[10:17:19.745] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[10:17:19.779] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[10:17:19.801] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[10:17:19.823] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[10:17:19.844] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[10:17:19.864] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[10:17:19.884] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[10:17:19.904] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[10:17:19.923] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[10:17:19.944] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[10:17:19.964] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[10:17:19.988] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 157 seconds
[10:17:19.988] <TB3> INFO: Decoding statistics:
[10:17:19.988] <TB3> INFO: General information:
[10:17:19.988] <TB3> INFO: 16bit words read: 2662400
[10:17:19.988] <TB3> INFO: valid events total: 166400
[10:17:19.988] <TB3> INFO: empty events: 0
[10:17:19.988] <TB3> INFO: valid events with pixels: 166400
[10:17:19.988] <TB3> INFO: valid pixel hits: 665600
[10:17:19.988] <TB3> INFO: Event errors: 0
[10:17:19.988] <TB3> INFO: start marker: 0
[10:17:19.988] <TB3> INFO: stop marker: 0
[10:17:19.988] <TB3> INFO: overflow: 0
[10:17:19.988] <TB3> INFO: invalid 5bit words: 0
[10:17:19.988] <TB3> INFO: invalid XOR eye diagram: 0
[10:17:19.989] <TB3> INFO: frame (failed synchr.): 0
[10:17:19.989] <TB3> INFO: idle data (no TBM trl): 0
[10:17:19.989] <TB3> INFO: no data (only TBM hdr): 0
[10:17:19.989] <TB3> INFO: TBM errors: 0
[10:17:19.989] <TB3> INFO: flawed TBM headers: 0
[10:17:19.989] <TB3> INFO: flawed TBM trailers: 0
[10:17:19.989] <TB3> INFO: event ID mismatches: 0
[10:17:19.989] <TB3> INFO: ROC errors: 0
[10:17:19.989] <TB3> INFO: missing ROC header(s): 0
[10:17:19.989] <TB3> INFO: misplaced readback start: 0
[10:17:19.989] <TB3> INFO: Pixel decoding errors: 0
[10:17:19.989] <TB3> INFO: pixel data incomplete: 0
[10:17:19.989] <TB3> INFO: pixel address: 0
[10:17:19.989] <TB3> INFO: pulse height fill bit: 0
[10:17:19.989] <TB3> INFO: buffer corruption: 0
[10:17:19.998] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C15.dat
[10:17:20.027] <TB3> INFO: ######################################################################
[10:17:20.027] <TB3> INFO: PixTestReadback::doTest()
[10:17:20.027] <TB3> INFO: ######################################################################
[10:17:20.027] <TB3> INFO: ----------------------------------------------------------------------
[10:17:20.027] <TB3> INFO: PixTestReadback::CalibrateVd()
[10:17:20.027] <TB3> INFO: ----------------------------------------------------------------------
[10:17:29.592] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C0.dat
[10:17:29.592] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C1.dat
[10:17:29.592] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C2.dat
[10:17:29.592] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C3.dat
[10:17:29.592] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C4.dat
[10:17:29.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C5.dat
[10:17:29.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C6.dat
[10:17:29.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C7.dat
[10:17:29.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C8.dat
[10:17:29.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C9.dat
[10:17:29.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C10.dat
[10:17:29.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C11.dat
[10:17:29.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C12.dat
[10:17:29.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C13.dat
[10:17:29.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C14.dat
[10:17:29.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C15.dat
[10:17:29.639] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[10:17:29.639] <TB3> INFO: ----------------------------------------------------------------------
[10:17:29.639] <TB3> INFO: PixTestReadback::CalibrateVa()
[10:17:29.639] <TB3> INFO: ----------------------------------------------------------------------
[10:17:39.193] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C0.dat
[10:17:39.193] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C1.dat
[10:17:39.193] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C2.dat
[10:17:39.193] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C3.dat
[10:17:39.193] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C4.dat
[10:17:39.193] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C5.dat
[10:17:39.193] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C6.dat
[10:17:39.193] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C7.dat
[10:17:39.193] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C8.dat
[10:17:39.194] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C9.dat
[10:17:39.194] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C10.dat
[10:17:39.194] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C11.dat
[10:17:39.194] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C12.dat
[10:17:39.194] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C13.dat
[10:17:39.194] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C14.dat
[10:17:39.194] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C15.dat
[10:17:39.241] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[10:17:39.241] <TB3> INFO: ----------------------------------------------------------------------
[10:17:39.241] <TB3> INFO: PixTestReadback::readbackVbg()
[10:17:39.241] <TB3> INFO: ----------------------------------------------------------------------
[10:17:46.585] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[10:17:46.585] <TB3> INFO: ----------------------------------------------------------------------
[10:17:46.585] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[10:17:46.585] <TB3> INFO: ----------------------------------------------------------------------
[10:17:46.585] <TB3> INFO: Vbg will be calibrated using Vd calibration
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 148calibrated Vbg = 1.20521 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 155calibrated Vbg = 1.20551 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155.5calibrated Vbg = 1.21126 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 155.3calibrated Vbg = 1.2135 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 159.6calibrated Vbg = 1.21846 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 155.7calibrated Vbg = 1.21597 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155.5calibrated Vbg = 1.2147 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 154calibrated Vbg = 1.21182 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 149.4calibrated Vbg = 1.2228 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 150calibrated Vbg = 1.21367 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 149.8calibrated Vbg = 1.21218 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 145calibrated Vbg = 1.21785 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 152calibrated Vbg = 1.20367 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 141.3calibrated Vbg = 1.19885 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 150.2calibrated Vbg = 1.20294 :::*/*/*/*/
[10:17:46.585] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 142.6calibrated Vbg = 1.20143 :::*/*/*/*/
[10:17:46.588] <TB3> INFO: ----------------------------------------------------------------------
[10:17:46.588] <TB3> INFO: PixTestReadback::CalibrateIa()
[10:17:46.588] <TB3> INFO: ----------------------------------------------------------------------
[10:20:22.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C0.dat
[10:20:22.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C1.dat
[10:20:22.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C2.dat
[10:20:22.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C3.dat
[10:20:22.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C4.dat
[10:20:22.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C5.dat
[10:20:22.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C6.dat
[10:20:22.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C7.dat
[10:20:22.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C8.dat
[10:20:22.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C9.dat
[10:20:22.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C10.dat
[10:20:22.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C11.dat
[10:20:22.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C12.dat
[10:20:22.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C13.dat
[10:20:22.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C14.dat
[10:20:22.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2332_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C15.dat
[10:20:22.633] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[10:20:22.634] <TB3> INFO: PixTestReadback::doTest() done
[10:20:22.634] <TB3> INFO: Decoding statistics:
[10:20:22.634] <TB3> INFO: General information:
[10:20:22.634] <TB3> INFO: 16bit words read: 1024
[10:20:22.634] <TB3> INFO: valid events total: 128
[10:20:22.634] <TB3> INFO: empty events: 128
[10:20:22.634] <TB3> INFO: valid events with pixels: 0
[10:20:22.634] <TB3> INFO: valid pixel hits: 0
[10:20:22.634] <TB3> INFO: Event errors: 0
[10:20:22.634] <TB3> INFO: start marker: 0
[10:20:22.634] <TB3> INFO: stop marker: 0
[10:20:22.634] <TB3> INFO: overflow: 0
[10:20:22.634] <TB3> INFO: invalid 5bit words: 0
[10:20:22.634] <TB3> INFO: invalid XOR eye diagram: 0
[10:20:22.634] <TB3> INFO: frame (failed synchr.): 0
[10:20:22.634] <TB3> INFO: idle data (no TBM trl): 0
[10:20:22.634] <TB3> INFO: no data (only TBM hdr): 0
[10:20:22.634] <TB3> INFO: TBM errors: 0
[10:20:22.634] <TB3> INFO: flawed TBM headers: 0
[10:20:22.634] <TB3> INFO: flawed TBM trailers: 0
[10:20:22.634] <TB3> INFO: event ID mismatches: 0
[10:20:22.634] <TB3> INFO: ROC errors: 0
[10:20:22.634] <TB3> INFO: missing ROC header(s): 0
[10:20:22.634] <TB3> INFO: misplaced readback start: 0
[10:20:22.634] <TB3> INFO: Pixel decoding errors: 0
[10:20:22.634] <TB3> INFO: pixel data incomplete: 0
[10:20:22.634] <TB3> INFO: pixel address: 0
[10:20:22.634] <TB3> INFO: pulse height fill bit: 0
[10:20:22.634] <TB3> INFO: buffer corruption: 0
[10:20:22.652] <TB3> INFO: Decoding statistics:
[10:20:22.652] <TB3> INFO: General information:
[10:20:22.652] <TB3> INFO: 16bit words read: 2750342
[10:20:22.652] <TB3> INFO: valid events total: 176768
[10:20:22.652] <TB3> INFO: empty events: 7869
[10:20:22.652] <TB3> INFO: valid events with pixels: 168899
[10:20:22.652] <TB3> INFO: valid pixel hits: 668099
[10:20:22.652] <TB3> INFO: Event errors: 0
[10:20:22.652] <TB3> INFO: start marker: 0
[10:20:22.652] <TB3> INFO: stop marker: 0
[10:20:22.652] <TB3> INFO: overflow: 0
[10:20:22.652] <TB3> INFO: invalid 5bit words: 0
[10:20:22.652] <TB3> INFO: invalid XOR eye diagram: 0
[10:20:22.652] <TB3> INFO: frame (failed synchr.): 0
[10:20:22.652] <TB3> INFO: idle data (no TBM trl): 0
[10:20:22.652] <TB3> INFO: no data (only TBM hdr): 0
[10:20:22.652] <TB3> INFO: TBM errors: 0
[10:20:22.652] <TB3> INFO: flawed TBM headers: 0
[10:20:22.652] <TB3> INFO: flawed TBM trailers: 0
[10:20:22.652] <TB3> INFO: event ID mismatches: 0
[10:20:22.652] <TB3> INFO: ROC errors: 0
[10:20:22.652] <TB3> INFO: missing ROC header(s): 0
[10:20:22.652] <TB3> INFO: misplaced readback start: 0
[10:20:22.652] <TB3> INFO: Pixel decoding errors: 0
[10:20:22.652] <TB3> INFO: pixel data incomplete: 0
[10:20:22.652] <TB3> INFO: pixel address: 0
[10:20:22.652] <TB3> INFO: pulse height fill bit: 0
[10:20:22.652] <TB3> INFO: buffer corruption: 0
[10:20:22.652] <TB3> INFO: enter test to run
[10:20:22.652] <TB3> INFO: test: exit no parameter change
[10:20:22.860] <TB3> QUIET: Connection to board 170 closed.
[10:20:22.941] <TB3> INFO: pXar: this is the end, my friend