[10:00:27.802] <TB0> INFO: *** Welcome to pxar ***
[10:00:27.802] <TB0> INFO: *** Today: 2016/06/15
[10:00:28.306] <TB0> INFO: *** Version: 9751-dirty
[10:00:28.306] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C15.dat
[10:00:28.307] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//tbmParameters_C0b.dat
[10:00:28.307] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//defaultMaskFile.dat
[10:00:28.307] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters_C15.dat
[10:00:28.374] <TB0> INFO: clk: 4
[10:00:28.374] <TB0> INFO: ctr: 4
[10:00:28.374] <TB0> INFO: sda: 19
[10:00:28.374] <TB0> INFO: tin: 9
[10:00:28.374] <TB0> INFO: level: 15
[10:00:28.374] <TB0> INFO: triggerdelay: 0
[10:00:28.374] <TB0> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[10:00:28.374] <TB0> INFO: Log level: INFO
[10:00:28.382] <TB0> INFO: Found DTB DTB_WS6AYH
[10:00:28.393] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[10:00:28.395] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[10:00:28.397] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[10:00:29.924] <TB0> INFO: DUT info:
[10:00:29.924] <TB0> INFO: The DUT currently contains the following objects:
[10:00:29.924] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[10:00:29.924] <TB0> INFO: TBM Core alpha (0): 7 registers set
[10:00:29.924] <TB0> INFO: TBM Core beta (1): 7 registers set
[10:00:29.924] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:00:29.924] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:29.924] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:30.324] <TB0> INFO: enter 'restricted' command line mode
[10:00:30.324] <TB0> INFO: enter test to run
[10:00:30.325] <TB0> INFO: test: pretest no parameter change
[10:00:30.325] <TB0> INFO: running: pretest
[10:00:30.328] <TB0> INFO: ----------------------------------------------------------------------
[10:00:30.328] <TB0> INFO: PixTestPretest::programROC()
[10:00:30.328] <TB0> INFO: ----------------------------------------------------------------------
[10:00:48.342] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:00:48.342] <TB0> INFO: IA differences per ROC: 21.7 20.1 20.1 18.5 18.5 20.9 20.1 19.3 18.5 19.3 17.7 19.3 20.1 20.1 20.1 17.7
[10:00:48.450] <TB0> INFO: enter test to run
[10:00:48.450] <TB0> INFO: test: pretest no parameter change
[10:00:48.450] <TB0> INFO: running: pretest
[10:00:48.451] <TB0> INFO: ----------------------------------------------------------------------
[10:00:48.451] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:00:48.451] <TB0> INFO: ----------------------------------------------------------------------
[10:00:54.038] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 379.4 mA = 23.7125 mA/ROC
[10:00:54.038] <TB0> INFO: i(loss) [mA/ROC]: 18.5 19.3 19.3 18.5 19.3 18.5 18.5 18.5 18.5 18.5 19.3 18.5 18.5 18.5 17.6 18.5
[10:00:54.072] <TB0> INFO: enter test to run
[10:00:54.072] <TB0> INFO: test: pretest no parameter change
[10:00:54.072] <TB0> INFO: running: pretest
[10:00:54.072] <TB0> INFO: ----------------------------------------------------------------------
[10:00:54.072] <TB0> INFO: PixTestPretest::findTiming()
[10:00:54.072] <TB0> INFO: ----------------------------------------------------------------------
[10:00:54.072] <TB0> INFO: PixTestCmd::init()

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


[10:02:32.789] <TB0> INFO: 231680 events read in total (9635ms).
[10:02:32.792] <TB0> INFO: Test took 10282ms.
[10:02:33.022] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:02:33.085] <TB0> INFO: enter test to run
[10:02:33.085] <TB0> INFO: test: pretest no parameter change
[10:02:33.085] <TB0> INFO: running: pretest
[10:02:33.086] <TB0> INFO: ----------------------------------------------------------------------
[10:02:33.086] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[10:02:33.086] <TB0> INFO: ----------------------------------------------------------------------
[10:02:33.178] <TB0> INFO: Expecting 231680 events.
[10:02:43.427] <TB0> INFO: 231680 events read in total (9693ms).
[10:02:43.430] <TB0> INFO: Test took 10340ms.
[10:02:43.673] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[10:02:43.673] <TB0> INFO: CalDel: 141 152 132 140 152 143 143 152 159 142 130 143 142 131 137 149
[10:02:43.673] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:02:43.705] <TB0> INFO: enter test to run
[10:02:43.705] <TB0> INFO: test: pretest no parameter change
[10:02:43.705] <TB0> INFO: running: pretest
[10:02:43.705] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C0.dat
[10:02:43.705] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C1.dat
[10:02:43.705] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C2.dat
[10:02:43.705] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C3.dat
[10:02:43.705] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C4.dat
[10:02:43.705] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C5.dat
[10:02:43.705] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C6.dat
[10:02:43.706] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C7.dat
[10:02:43.706] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C8.dat
[10:02:43.706] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C9.dat
[10:02:43.706] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C10.dat
[10:02:43.706] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C11.dat
[10:02:43.706] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C12.dat
[10:02:43.706] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C13.dat
[10:02:43.707] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C14.dat
[10:02:43.707] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C15.dat
[10:02:43.707] <TB0> INFO: enter test to run
[10:02:43.707] <TB0> INFO: test: fulltest no parameter change
[10:02:43.707] <TB0> INFO: running: fulltest
[10:02:43.707] <TB0> INFO: ######################################################################
[10:02:43.707] <TB0> INFO: PixTestFullTest::doTest()
[10:02:43.707] <TB0> INFO: ######################################################################
[10:02:43.708] <TB0> INFO: ######################################################################
[10:02:43.708] <TB0> INFO: PixTestAlive::doTest()
[10:02:43.708] <TB0> INFO: ######################################################################
[10:02:43.709] <TB0> INFO: ----------------------------------------------------------------------
[10:02:43.709] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:02:43.709] <TB0> INFO: ----------------------------------------------------------------------
[10:02:43.980] <TB0> INFO: Expecting 41600 events.
[10:02:47.823] <TB0> INFO: 41600 events read in total (3287ms).
[10:02:47.824] <TB0> INFO: Test took 4113ms.
[10:02:48.047] <TB0> INFO: PixTestAlive::aliveTest() done
[10:02:48.047] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:02:48.049] <TB0> INFO: ----------------------------------------------------------------------
[10:02:48.049] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:02:48.049] <TB0> INFO: ----------------------------------------------------------------------
[10:02:48.330] <TB0> INFO: Expecting 41600 events.
[10:02:51.316] <TB0> INFO: 41600 events read in total (2430ms).
[10:02:51.316] <TB0> INFO: Test took 3266ms.
[10:02:51.317] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:02:51.560] <TB0> INFO: PixTestAlive::maskTest() done
[10:02:51.560] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:02:51.561] <TB0> INFO: ----------------------------------------------------------------------
[10:02:51.561] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:02:51.561] <TB0> INFO: ----------------------------------------------------------------------
[10:02:51.836] <TB0> INFO: Expecting 41600 events.
[10:02:55.630] <TB0> INFO: 41600 events read in total (3238ms).
[10:02:55.631] <TB0> INFO: Test took 4068ms.
[10:02:55.864] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[10:02:55.864] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:02:55.864] <TB0> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[10:02:55.864] <TB0> INFO: Decoding statistics:
[10:02:55.864] <TB0> INFO: General information:
[10:02:55.864] <TB0> INFO: 16bit words read: 0
[10:02:55.864] <TB0> INFO: valid events total: 0
[10:02:55.864] <TB0> INFO: empty events: 0
[10:02:55.864] <TB0> INFO: valid events with pixels: 0
[10:02:55.864] <TB0> INFO: valid pixel hits: 0
[10:02:55.865] <TB0> INFO: Event errors: 0
[10:02:55.865] <TB0> INFO: start marker: 0
[10:02:55.865] <TB0> INFO: stop marker: 0
[10:02:55.865] <TB0> INFO: overflow: 0
[10:02:55.865] <TB0> INFO: invalid 5bit words: 0
[10:02:55.865] <TB0> INFO: invalid XOR eye diagram: 0
[10:02:55.865] <TB0> INFO: frame (failed synchr.): 0
[10:02:55.865] <TB0> INFO: idle data (no TBM trl): 0
[10:02:55.865] <TB0> INFO: no data (only TBM hdr): 0
[10:02:55.865] <TB0> INFO: TBM errors: 0
[10:02:55.865] <TB0> INFO: flawed TBM headers: 0
[10:02:55.865] <TB0> INFO: flawed TBM trailers: 0
[10:02:55.865] <TB0> INFO: event ID mismatches: 0
[10:02:55.865] <TB0> INFO: ROC errors: 0
[10:02:55.865] <TB0> INFO: missing ROC header(s): 0
[10:02:55.865] <TB0> INFO: misplaced readback start: 0
[10:02:55.865] <TB0> INFO: Pixel decoding errors: 0
[10:02:55.865] <TB0> INFO: pixel data incomplete: 0
[10:02:55.865] <TB0> INFO: pixel address: 0
[10:02:55.865] <TB0> INFO: pulse height fill bit: 0
[10:02:55.865] <TB0> INFO: buffer corruption: 0
[10:02:55.878] <TB0> INFO: ######################################################################
[10:02:55.878] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:02:55.878] <TB0> INFO: ######################################################################
[10:02:55.881] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:02:55.893] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[10:02:55.893] <TB0> INFO: run 1 of 1
[10:02:56.165] <TB0> INFO: Expecting 3120000 events.
[10:03:37.703] <TB0> INFO: 863715 events read in total (40982ms).
[10:04:17.752] <TB0> INFO: 1718975 events read in total (81031ms).
[10:04:57.877] <TB0> INFO: 2586300 events read in total (121156ms).
[10:05:22.913] <TB0> INFO: 3120000 events read in total (146192ms).
[10:05:22.960] <TB0> INFO: Test took 147068ms.
[10:05:45.698] <TB0> INFO: PixTestBBMap::doTest() done, duration: 169 seconds
[10:05:45.698] <TB0> INFO: number of dead bumps (per ROC): 18 0 1 0 0 0 0 0 2 0 0 5 2 1 9 31
[10:05:45.698] <TB0> INFO: separation cut (per ROC): 81 85 91 93 91 97 85 72 73 72 68 78 77 92 72 68
[10:05:45.698] <TB0> INFO: Decoding statistics:
[10:05:45.698] <TB0> INFO: General information:
[10:05:45.698] <TB0> INFO: 16bit words read: 0
[10:05:45.698] <TB0> INFO: valid events total: 0
[10:05:45.698] <TB0> INFO: empty events: 0
[10:05:45.698] <TB0> INFO: valid events with pixels: 0
[10:05:45.698] <TB0> INFO: valid pixel hits: 0
[10:05:45.698] <TB0> INFO: Event errors: 0
[10:05:45.698] <TB0> INFO: start marker: 0
[10:05:45.698] <TB0> INFO: stop marker: 0
[10:05:45.698] <TB0> INFO: overflow: 0
[10:05:45.698] <TB0> INFO: invalid 5bit words: 0
[10:05:45.698] <TB0> INFO: invalid XOR eye diagram: 0
[10:05:45.698] <TB0> INFO: frame (failed synchr.): 0
[10:05:45.698] <TB0> INFO: idle data (no TBM trl): 0
[10:05:45.698] <TB0> INFO: no data (only TBM hdr): 0
[10:05:45.698] <TB0> INFO: TBM errors: 0
[10:05:45.698] <TB0> INFO: flawed TBM headers: 0
[10:05:45.698] <TB0> INFO: flawed TBM trailers: 0
[10:05:45.698] <TB0> INFO: event ID mismatches: 0
[10:05:45.698] <TB0> INFO: ROC errors: 0
[10:05:45.698] <TB0> INFO: missing ROC header(s): 0
[10:05:45.698] <TB0> INFO: misplaced readback start: 0
[10:05:45.698] <TB0> INFO: Pixel decoding errors: 0
[10:05:45.698] <TB0> INFO: pixel data incomplete: 0
[10:05:45.698] <TB0> INFO: pixel address: 0
[10:05:45.698] <TB0> INFO: pulse height fill bit: 0
[10:05:45.698] <TB0> INFO: buffer corruption: 0
[10:05:45.777] <TB0> INFO: ######################################################################
[10:05:45.777] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:05:45.777] <TB0> INFO: ######################################################################
[10:05:45.777] <TB0> INFO: ----------------------------------------------------------------------
[10:05:45.777] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:05:45.777] <TB0> INFO: ----------------------------------------------------------------------
[10:05:45.777] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:05:45.786] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[10:05:45.786] <TB0> INFO: run 1 of 1
[10:05:46.047] <TB0> INFO: Expecting 26208000 events.
[10:06:19.271] <TB0> INFO: 860000 events read in total (32668ms).
[10:06:51.262] <TB0> INFO: 1708150 events read in total (64659ms).
[10:07:23.567] <TB0> INFO: 2555150 events read in total (96964ms).
[10:07:55.820] <TB0> INFO: 3400400 events read in total (129217ms).
[10:08:28.010] <TB0> INFO: 4243500 events read in total (161407ms).
[10:08:59.912] <TB0> INFO: 5088150 events read in total (193309ms).
[10:09:32.228] <TB0> INFO: 5928150 events read in total (225625ms).
[10:10:04.381] <TB0> INFO: 6770100 events read in total (257778ms).
[10:10:36.377] <TB0> INFO: 7610350 events read in total (289774ms).
[10:11:08.698] <TB0> INFO: 8447450 events read in total (322095ms).
[10:11:40.586] <TB0> INFO: 9287650 events read in total (353983ms).
[10:12:12.673] <TB0> INFO: 10126600 events read in total (386070ms).
[10:12:44.277] <TB0> INFO: 10964000 events read in total (417674ms).
[10:13:16.490] <TB0> INFO: 11803050 events read in total (449887ms).
[10:13:48.471] <TB0> INFO: 12641750 events read in total (481868ms).
[10:14:20.312] <TB0> INFO: 13473350 events read in total (513709ms).
[10:14:52.863] <TB0> INFO: 14305500 events read in total (546260ms).
[10:15:25.414] <TB0> INFO: 15135000 events read in total (578811ms).
[10:15:57.498] <TB0> INFO: 15961300 events read in total (610896ms).
[10:16:29.219] <TB0> INFO: 16788600 events read in total (642616ms).
[10:17:01.200] <TB0> INFO: 17614100 events read in total (674597ms).
[10:17:33.506] <TB0> INFO: 18439850 events read in total (706903ms).
[10:18:05.522] <TB0> INFO: 19267200 events read in total (738919ms).
[10:18:37.143] <TB0> INFO: 20091450 events read in total (770540ms).
[10:19:08.918] <TB0> INFO: 20916750 events read in total (802315ms).
[10:19:40.639] <TB0> INFO: 21743550 events read in total (834036ms).
[10:20:12.654] <TB0> INFO: 22565850 events read in total (866051ms).
[10:20:44.215] <TB0> INFO: 23393100 events read in total (897612ms).
[10:21:15.999] <TB0> INFO: 24219450 events read in total (929396ms).
[10:21:47.887] <TB0> INFO: 25044000 events read in total (961284ms).
[10:22:19.819] <TB0> INFO: 25874600 events read in total (993216ms).
[10:22:32.665] <TB0> INFO: 26208000 events read in total (1006062ms).
[10:22:32.694] <TB0> INFO: Test took 1006908ms.
[10:22:32.999] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:34.646] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:36.297] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:37.747] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:39.206] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:40.609] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:42.220] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:43.888] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:45.406] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:46.938] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:48.376] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:50.121] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:51.847] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:53.269] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:54.730] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:56.314] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:57.734] <TB0> INFO: PixTestScurves::scurves() done
[10:22:57.734] <TB0> INFO: Vcal mean: 95.36 82.79 87.71 88.64 86.99 92.33 83.25 77.79 82.33 70.09 69.51 81.19 82.47 90.27 77.22 82.19
[10:22:57.734] <TB0> INFO: Vcal RMS: 5.50 4.47 4.99 5.59 5.41 6.43 5.02 4.41 4.81 4.85 4.68 4.58 4.96 5.31 3.98 4.87
[10:22:57.734] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1031 seconds
[10:22:57.734] <TB0> INFO: Decoding statistics:
[10:22:57.734] <TB0> INFO: General information:
[10:22:57.734] <TB0> INFO: 16bit words read: 0
[10:22:57.734] <TB0> INFO: valid events total: 0
[10:22:57.734] <TB0> INFO: empty events: 0
[10:22:57.734] <TB0> INFO: valid events with pixels: 0
[10:22:57.734] <TB0> INFO: valid pixel hits: 0
[10:22:57.734] <TB0> INFO: Event errors: 0
[10:22:57.734] <TB0> INFO: start marker: 0
[10:22:57.734] <TB0> INFO: stop marker: 0
[10:22:57.734] <TB0> INFO: overflow: 0
[10:22:57.734] <TB0> INFO: invalid 5bit words: 0
[10:22:57.734] <TB0> INFO: invalid XOR eye diagram: 0
[10:22:57.734] <TB0> INFO: frame (failed synchr.): 0
[10:22:57.734] <TB0> INFO: idle data (no TBM trl): 0
[10:22:57.734] <TB0> INFO: no data (only TBM hdr): 0
[10:22:57.734] <TB0> INFO: TBM errors: 0
[10:22:57.734] <TB0> INFO: flawed TBM headers: 0
[10:22:57.734] <TB0> INFO: flawed TBM trailers: 0
[10:22:57.734] <TB0> INFO: event ID mismatches: 0
[10:22:57.734] <TB0> INFO: ROC errors: 0
[10:22:57.734] <TB0> INFO: missing ROC header(s): 0
[10:22:57.734] <TB0> INFO: misplaced readback start: 0
[10:22:57.734] <TB0> INFO: Pixel decoding errors: 0
[10:22:57.734] <TB0> INFO: pixel data incomplete: 0
[10:22:57.734] <TB0> INFO: pixel address: 0
[10:22:57.734] <TB0> INFO: pulse height fill bit: 0
[10:22:57.734] <TB0> INFO: buffer corruption: 0
[10:22:57.806] <TB0> INFO: ######################################################################
[10:22:57.806] <TB0> INFO: PixTestTrim::doTest()
[10:22:57.806] <TB0> INFO: ######################################################################
[10:22:57.807] <TB0> INFO: ----------------------------------------------------------------------
[10:22:57.807] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:22:57.807] <TB0> INFO: ----------------------------------------------------------------------
[10:22:57.889] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:22:57.889] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:22:57.897] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:22:57.897] <TB0> INFO: run 1 of 1
[10:22:58.185] <TB0> INFO: Expecting 6281600 events.
[10:23:39.193] <TB0> INFO: 1068690 events read in total (40445ms).
[10:24:19.229] <TB0> INFO: 2129080 events read in total (80481ms).
[10:24:58.049] <TB0> INFO: 3184110 events read in total (119301ms).
[10:25:36.975] <TB0> INFO: 4246780 events read in total (158227ms).
[10:26:15.929] <TB0> INFO: 5311380 events read in total (197181ms).
[10:26:51.597] <TB0> INFO: 6281600 events read in total (232849ms).
[10:26:51.624] <TB0> INFO: Test took 233726ms.
[10:27:10.179] <TB0> INFO: ROC 0 VthrComp = 98
[10:27:10.179] <TB0> INFO: ROC 1 VthrComp = 88
[10:27:10.179] <TB0> INFO: ROC 2 VthrComp = 92
[10:27:10.179] <TB0> INFO: ROC 3 VthrComp = 91
[10:27:10.179] <TB0> INFO: ROC 4 VthrComp = 89
[10:27:10.180] <TB0> INFO: ROC 5 VthrComp = 89
[10:27:10.180] <TB0> INFO: ROC 6 VthrComp = 86
[10:27:10.180] <TB0> INFO: ROC 7 VthrComp = 80
[10:27:10.180] <TB0> INFO: ROC 8 VthrComp = 85
[10:27:10.180] <TB0> INFO: ROC 9 VthrComp = 76
[10:27:10.180] <TB0> INFO: ROC 10 VthrComp = 76
[10:27:10.180] <TB0> INFO: ROC 11 VthrComp = 86
[10:27:10.180] <TB0> INFO: ROC 12 VthrComp = 87
[10:27:10.180] <TB0> INFO: ROC 13 VthrComp = 99
[10:27:10.180] <TB0> INFO: ROC 14 VthrComp = 82
[10:27:10.180] <TB0> INFO: ROC 15 VthrComp = 86
[10:27:10.181] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:27:10.181] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:27:10.188] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:27:10.188] <TB0> INFO: run 1 of 1
[10:27:10.447] <TB0> INFO: Expecting 6281600 events.
[10:27:45.652] <TB0> INFO: 754960 events read in total (34649ms).
[10:28:20.694] <TB0> INFO: 1508390 events read in total (69691ms).
[10:28:55.148] <TB0> INFO: 2262250 events read in total (104145ms).
[10:29:30.227] <TB0> INFO: 3016110 events read in total (139224ms).
[10:30:04.726] <TB0> INFO: 3765370 events read in total (173723ms).
[10:30:39.143] <TB0> INFO: 4511090 events read in total (208140ms).
[10:31:13.780] <TB0> INFO: 5255220 events read in total (242777ms).
[10:31:48.470] <TB0> INFO: 5998860 events read in total (277467ms).
[10:32:01.968] <TB0> INFO: 6281600 events read in total (290965ms).
[10:32:02.013] <TB0> INFO: Test took 291824ms.
[10:32:26.472] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 58.4516 for pixel 0/24 mean/min/max = 45.0763/31.6413/58.5112
[10:32:26.472] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 56.8469 for pixel 0/71 mean/min/max = 44.9252/32.8621/56.9883
[10:32:26.473] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 57.2981 for pixel 21/4 mean/min/max = 44.8198/32.1677/57.4718
[10:32:26.473] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 59.6058 for pixel 17/0 mean/min/max = 46.3347/32.9782/59.6912
[10:32:26.473] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 58.6732 for pixel 17/79 mean/min/max = 46.2547/33.8002/58.7092
[10:32:26.473] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 65.0497 for pixel 23/60 mean/min/max = 47.7699/30.4898/65.0499
[10:32:26.474] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 58.303 for pixel 16/56 mean/min/max = 45.1376/31.9701/58.3051
[10:32:26.474] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 58.5951 for pixel 27/1 mean/min/max = 45.7119/32.7855/58.6383
[10:32:26.474] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 57.977 for pixel 0/18 mean/min/max = 45.0836/32.042/58.1253
[10:32:26.475] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 57.3118 for pixel 4/13 mean/min/max = 46.0096/34.6717/57.3475
[10:32:26.475] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 58.1036 for pixel 5/72 mean/min/max = 46.4734/34.7506/58.1961
[10:32:26.475] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 57.2046 for pixel 0/27 mean/min/max = 44.6406/31.9723/57.309
[10:32:26.476] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 59.2673 for pixel 1/79 mean/min/max = 45.6042/31.9025/59.3059
[10:32:26.476] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 56.6414 for pixel 25/50 mean/min/max = 44.0159/31.3445/56.6872
[10:32:26.476] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 56.0285 for pixel 6/76 mean/min/max = 44.5485/33.0037/56.0932
[10:32:26.477] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 59.0152 for pixel 32/14 mean/min/max = 45.3729/31.688/59.0578
[10:32:26.477] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:32:26.565] <TB0> INFO: Expecting 514560 events.
[10:32:39.304] <TB0> INFO: 514560 events read in total (12183ms).
[10:32:39.308] <TB0> INFO: Expecting 514560 events.
[10:32:51.802] <TB0> INFO: 514560 events read in total (12075ms).
[10:32:51.808] <TB0> INFO: Expecting 514560 events.
[10:33:04.760] <TB0> INFO: 514560 events read in total (12529ms).
[10:33:04.767] <TB0> INFO: Expecting 514560 events.
[10:33:17.530] <TB0> INFO: 514560 events read in total (12349ms).
[10:33:17.540] <TB0> INFO: Expecting 514560 events.
[10:33:30.319] <TB0> INFO: 514560 events read in total (12372ms).
[10:33:30.331] <TB0> INFO: Expecting 514560 events.
[10:33:43.172] <TB0> INFO: 514560 events read in total (12431ms).
[10:33:43.186] <TB0> INFO: Expecting 514560 events.
[10:33:56.063] <TB0> INFO: 514560 events read in total (12463ms).
[10:33:56.078] <TB0> INFO: Expecting 514560 events.
[10:34:08.950] <TB0> INFO: 514560 events read in total (12460ms).
[10:34:08.968] <TB0> INFO: Expecting 514560 events.
[10:34:21.750] <TB0> INFO: 514560 events read in total (12379ms).
[10:34:21.771] <TB0> INFO: Expecting 514560 events.
[10:34:34.654] <TB0> INFO: 514560 events read in total (12473ms).
[10:34:34.675] <TB0> INFO: Expecting 514560 events.
[10:34:47.578] <TB0> INFO: 514560 events read in total (12501ms).
[10:34:47.602] <TB0> INFO: Expecting 514560 events.
[10:35:00.392] <TB0> INFO: 514560 events read in total (12384ms).
[10:35:00.417] <TB0> INFO: Expecting 514560 events.
[10:35:13.218] <TB0> INFO: 514560 events read in total (12399ms).
[10:35:13.244] <TB0> INFO: Expecting 514560 events.
[10:35:26.061] <TB0> INFO: 514560 events read in total (12413ms).
[10:35:26.089] <TB0> INFO: Expecting 514560 events.
[10:35:38.934] <TB0> INFO: 514560 events read in total (12444ms).
[10:35:38.967] <TB0> INFO: Expecting 514560 events.
[10:35:51.650] <TB0> INFO: 514560 events read in total (12281ms).
[10:35:51.684] <TB0> INFO: Test took 205207ms.
[10:35:52.594] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:35:52.602] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:35:52.602] <TB0> INFO: run 1 of 1
[10:35:52.867] <TB0> INFO: Expecting 6281600 events.
[10:36:27.749] <TB0> INFO: 741590 events read in total (34326ms).
[10:37:02.260] <TB0> INFO: 1481850 events read in total (68837ms).
[10:37:36.588] <TB0> INFO: 2222530 events read in total (103165ms).
[10:38:11.722] <TB0> INFO: 2964070 events read in total (138299ms).
[10:38:46.623] <TB0> INFO: 3700790 events read in total (173200ms).
[10:39:21.298] <TB0> INFO: 4433690 events read in total (207875ms).
[10:39:55.758] <TB0> INFO: 5165000 events read in total (242335ms).
[10:40:29.946] <TB0> INFO: 5895350 events read in total (276523ms).
[10:40:48.565] <TB0> INFO: 6281600 events read in total (295142ms).
[10:40:48.610] <TB0> INFO: Test took 296008ms.
[10:41:12.878] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.061992 .. 255.000000
[10:41:12.956] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:41:12.964] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:41:12.964] <TB0> INFO: run 1 of 1
[10:41:13.245] <TB0> INFO: Expecting 10649600 events.
[10:41:47.935] <TB0> INFO: 712270 events read in total (34134ms).
[10:42:21.871] <TB0> INFO: 1424900 events read in total (68070ms).
[10:42:56.088] <TB0> INFO: 2137520 events read in total (102287ms).
[10:43:30.560] <TB0> INFO: 2850150 events read in total (136759ms).
[10:44:04.807] <TB0> INFO: 3562770 events read in total (171006ms).
[10:44:39.069] <TB0> INFO: 4275270 events read in total (205268ms).
[10:45:13.187] <TB0> INFO: 4987880 events read in total (239386ms).
[10:45:47.012] <TB0> INFO: 5700540 events read in total (273211ms).
[10:46:21.200] <TB0> INFO: 6412880 events read in total (307399ms).
[10:46:55.314] <TB0> INFO: 7124540 events read in total (341513ms).
[10:47:29.631] <TB0> INFO: 7836200 events read in total (375830ms).
[10:48:03.653] <TB0> INFO: 8547490 events read in total (409852ms).
[10:48:38.259] <TB0> INFO: 9258260 events read in total (444458ms).
[10:49:12.508] <TB0> INFO: 9969220 events read in total (478707ms).
[10:49:45.188] <TB0> INFO: 10649600 events read in total (511387ms).
[10:49:45.275] <TB0> INFO: Test took 512311ms.
[10:50:14.868] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 17.338224 .. 46.298126
[10:50:14.945] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 7 .. 56 (-1/-1) hits flags = 528 (plus default)
[10:50:14.953] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:50:14.953] <TB0> INFO: run 1 of 1
[10:50:15.214] <TB0> INFO: Expecting 2080000 events.
[10:50:53.417] <TB0> INFO: 919180 events read in total (37641ms).
[10:51:31.059] <TB0> INFO: 1834560 events read in total (75284ms).
[10:51:41.281] <TB0> INFO: 2080000 events read in total (85506ms).
[10:51:41.299] <TB0> INFO: Test took 86347ms.
[10:51:54.781] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 22.577723 .. 43.697087
[10:51:54.859] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 12 .. 53 (-1/-1) hits flags = 528 (plus default)
[10:51:54.867] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:51:54.867] <TB0> INFO: run 1 of 1
[10:51:55.129] <TB0> INFO: Expecting 1747200 events.
[10:52:33.473] <TB0> INFO: 914350 events read in total (37788ms).
[10:53:07.926] <TB0> INFO: 1747200 events read in total (72241ms).
[10:53:07.940] <TB0> INFO: Test took 73074ms.
[10:53:22.348] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 3.796604 .. 43.423151
[10:53:22.426] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 3 .. 53 (-1/-1) hits flags = 528 (plus default)
[10:53:22.434] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:53:22.434] <TB0> INFO: run 1 of 1
[10:53:22.696] <TB0> INFO: Expecting 2121600 events.
[10:54:02.450] <TB0> INFO: 967390 events read in total (39197ms).
[10:54:41.232] <TB0> INFO: 1933840 events read in total (77979ms).
[10:54:49.120] <TB0> INFO: 2121600 events read in total (85868ms).
[10:54:49.138] <TB0> INFO: Test took 86705ms.
[10:55:03.669] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:55:03.669] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:55:03.677] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:55:03.677] <TB0> INFO: run 1 of 1
[10:55:03.956] <TB0> INFO: Expecting 1705600 events.
[10:55:41.997] <TB0> INFO: 879070 events read in total (37485ms).
[10:56:16.765] <TB0> INFO: 1705600 events read in total (72253ms).
[10:56:16.787] <TB0> INFO: Test took 73111ms.
[10:56:30.356] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C0.dat
[10:56:30.356] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C1.dat
[10:56:30.357] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C2.dat
[10:56:30.357] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C3.dat
[10:56:30.357] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C4.dat
[10:56:30.357] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C5.dat
[10:56:30.357] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C6.dat
[10:56:30.357] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C7.dat
[10:56:30.358] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C8.dat
[10:56:30.358] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C9.dat
[10:56:30.358] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C10.dat
[10:56:30.358] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C11.dat
[10:56:30.358] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C12.dat
[10:56:30.358] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C13.dat
[10:56:30.358] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C14.dat
[10:56:30.358] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C15.dat
[10:56:30.359] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C0.dat
[10:56:30.366] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C1.dat
[10:56:30.372] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C2.dat
[10:56:30.378] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C3.dat
[10:56:30.384] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C4.dat
[10:56:30.390] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C5.dat
[10:56:30.396] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C6.dat
[10:56:30.402] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C7.dat
[10:56:30.408] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C8.dat
[10:56:30.414] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C9.dat
[10:56:30.421] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C10.dat
[10:56:30.427] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C11.dat
[10:56:30.433] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C12.dat
[10:56:30.439] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C13.dat
[10:56:30.447] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C14.dat
[10:56:30.455] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C15.dat
[10:56:30.462] <TB0> INFO: PixTestTrim::trimTest() done
[10:56:30.462] <TB0> INFO: vtrim: 97 80 90 92 91 109 97 100 88 101 98 88 98 93 93 99
[10:56:30.462] <TB0> INFO: vthrcomp: 98 88 92 91 89 89 86 80 85 76 76 86 87 99 82 86
[10:56:30.462] <TB0> INFO: vcal mean: 34.94 35.00 34.98 34.84 34.94 35.02 34.97 34.96 34.98 35.00 34.99 34.95 34.99 34.96 34.98 34.94
[10:56:30.462] <TB0> INFO: vcal RMS: 0.87 0.79 0.77 0.98 0.82 1.01 0.82 0.84 0.83 0.80 0.77 0.91 0.82 0.89 0.86 0.96
[10:56:30.462] <TB0> INFO: bits mean: 9.37 9.52 9.28 9.46 8.36 9.41 10.04 9.30 9.17 9.20 8.83 9.29 9.29 10.22 9.67 9.61
[10:56:30.462] <TB0> INFO: bits RMS: 2.85 2.64 2.81 2.55 2.92 2.71 2.46 2.72 2.92 2.40 2.50 2.94 2.80 2.55 2.52 2.72
[10:56:30.468] <TB0> INFO: ----------------------------------------------------------------------
[10:56:30.468] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[10:56:30.468] <TB0> INFO: ----------------------------------------------------------------------
[10:56:30.470] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[10:56:30.478] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[10:56:30.478] <TB0> INFO: run 1 of 1
[10:56:30.753] <TB0> INFO: Expecting 4160000 events.
[10:57:12.664] <TB0> INFO: 898230 events read in total (41355ms).
[10:57:53.093] <TB0> INFO: 1788705 events read in total (81784ms).
[10:58:33.087] <TB0> INFO: 2672850 events read in total (121778ms).
[10:59:13.249] <TB0> INFO: 3552980 events read in total (161940ms).
[10:59:40.886] <TB0> INFO: 4160000 events read in total (189577ms).
[10:59:40.927] <TB0> INFO: Test took 190449ms.
[11:00:10.275] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:00:10.283] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:00:10.283] <TB0> INFO: run 1 of 1
[11:00:10.549] <TB0> INFO: Expecting 3348800 events.
[11:00:53.231] <TB0> INFO: 940750 events read in total (42126ms).
[11:01:34.043] <TB0> INFO: 1870435 events read in total (82938ms).
[11:02:15.071] <TB0> INFO: 2789830 events read in total (123966ms).
[11:02:40.022] <TB0> INFO: 3348800 events read in total (148917ms).
[11:02:40.055] <TB0> INFO: Test took 149771ms.
[11:03:04.915] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 151 (-1/-1) hits flags = 528 (plus default)
[11:03:04.923] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:03:04.923] <TB0> INFO: run 1 of 1
[11:03:05.189] <TB0> INFO: Expecting 3161600 events.
[11:03:47.580] <TB0> INFO: 963035 events read in total (41835ms).
[11:04:29.309] <TB0> INFO: 1912440 events read in total (83565ms).
[11:05:10.630] <TB0> INFO: 2851390 events read in total (124885ms).
[11:05:24.577] <TB0> INFO: 3161600 events read in total (138832ms).
[11:05:24.607] <TB0> INFO: Test took 139683ms.
[11:05:47.999] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[11:05:48.007] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:05:48.008] <TB0> INFO: run 1 of 1
[11:05:48.298] <TB0> INFO: Expecting 3182400 events.
[11:06:30.660] <TB0> INFO: 960070 events read in total (41806ms).
[11:07:12.398] <TB0> INFO: 1907025 events read in total (83545ms).
[11:07:53.600] <TB0> INFO: 2844330 events read in total (124746ms).
[11:08:08.499] <TB0> INFO: 3182400 events read in total (139645ms).
[11:08:08.533] <TB0> INFO: Test took 140526ms.
[11:08:33.048] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[11:08:33.056] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:08:33.056] <TB0> INFO: run 1 of 1
[11:08:33.319] <TB0> INFO: Expecting 3182400 events.
[11:09:15.581] <TB0> INFO: 959780 events read in total (41706ms).
[11:09:56.865] <TB0> INFO: 1906210 events read in total (82991ms).
[11:10:38.247] <TB0> INFO: 2843260 events read in total (124373ms).
[11:10:53.365] <TB0> INFO: 3182400 events read in total (139490ms).
[11:10:53.397] <TB0> INFO: Test took 140340ms.
[11:11:17.231] <TB0> INFO: PixTestTrim::trimBitTest() done
[11:11:17.233] <TB0> INFO: PixTestTrim::doTest() done, duration: 2899 seconds
[11:11:17.233] <TB0> INFO: Decoding statistics:
[11:11:17.233] <TB0> INFO: General information:
[11:11:17.233] <TB0> INFO: 16bit words read: 0
[11:11:17.233] <TB0> INFO: valid events total: 0
[11:11:17.233] <TB0> INFO: empty events: 0
[11:11:17.233] <TB0> INFO: valid events with pixels: 0
[11:11:17.233] <TB0> INFO: valid pixel hits: 0
[11:11:17.233] <TB0> INFO: Event errors: 0
[11:11:17.233] <TB0> INFO: start marker: 0
[11:11:17.233] <TB0> INFO: stop marker: 0
[11:11:17.233] <TB0> INFO: overflow: 0
[11:11:17.233] <TB0> INFO: invalid 5bit words: 0
[11:11:17.233] <TB0> INFO: invalid XOR eye diagram: 0
[11:11:17.233] <TB0> INFO: frame (failed synchr.): 0
[11:11:17.233] <TB0> INFO: idle data (no TBM trl): 0
[11:11:17.233] <TB0> INFO: no data (only TBM hdr): 0
[11:11:17.233] <TB0> INFO: TBM errors: 0
[11:11:17.233] <TB0> INFO: flawed TBM headers: 0
[11:11:17.233] <TB0> INFO: flawed TBM trailers: 0
[11:11:17.233] <TB0> INFO: event ID mismatches: 0
[11:11:17.233] <TB0> INFO: ROC errors: 0
[11:11:17.233] <TB0> INFO: missing ROC header(s): 0
[11:11:17.233] <TB0> INFO: misplaced readback start: 0
[11:11:17.233] <TB0> INFO: Pixel decoding errors: 0
[11:11:17.233] <TB0> INFO: pixel data incomplete: 0
[11:11:17.233] <TB0> INFO: pixel address: 0
[11:11:17.233] <TB0> INFO: pulse height fill bit: 0
[11:11:17.233] <TB0> INFO: buffer corruption: 0
[11:11:17.912] <TB0> INFO: ######################################################################
[11:11:17.912] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:11:17.912] <TB0> INFO: ######################################################################
[11:11:18.174] <TB0> INFO: Expecting 41600 events.
[11:11:22.158] <TB0> INFO: 41600 events read in total (3428ms).
[11:11:22.158] <TB0> INFO: Test took 4245ms.
[11:11:22.653] <TB0> INFO: Expecting 41600 events.
[11:11:26.502] <TB0> INFO: 41600 events read in total (3293ms).
[11:11:26.503] <TB0> INFO: Test took 4140ms.
[11:11:26.810] <TB0> INFO: Expecting 41600 events.
[11:11:30.696] <TB0> INFO: 41600 events read in total (3330ms).
[11:11:30.697] <TB0> INFO: Test took 4173ms.
[11:11:30.953] <TB0> INFO: Expecting 2560 events.
[11:11:31.806] <TB0> INFO: 2560 events read in total (297ms).
[11:11:31.806] <TB0> INFO: Test took 1095ms.
[11:11:32.111] <TB0> INFO: Expecting 2560 events.
[11:11:32.964] <TB0> INFO: 2560 events read in total (297ms).
[11:11:32.964] <TB0> INFO: Test took 1158ms.
[11:11:33.269] <TB0> INFO: Expecting 2560 events.
[11:11:34.121] <TB0> INFO: 2560 events read in total (296ms).
[11:11:34.121] <TB0> INFO: Test took 1157ms.
[11:11:34.426] <TB0> INFO: Expecting 2560 events.
[11:11:35.280] <TB0> INFO: 2560 events read in total (298ms).
[11:11:35.280] <TB0> INFO: Test took 1159ms.
[11:11:35.585] <TB0> INFO: Expecting 2560 events.
[11:11:36.438] <TB0> INFO: 2560 events read in total (297ms).
[11:11:36.438] <TB0> INFO: Test took 1157ms.
[11:11:36.743] <TB0> INFO: Expecting 2560 events.
[11:11:37.596] <TB0> INFO: 2560 events read in total (297ms).
[11:11:37.596] <TB0> INFO: Test took 1158ms.
[11:11:37.901] <TB0> INFO: Expecting 2560 events.
[11:11:38.756] <TB0> INFO: 2560 events read in total (299ms).
[11:11:38.756] <TB0> INFO: Test took 1159ms.
[11:11:39.061] <TB0> INFO: Expecting 2560 events.
[11:11:39.915] <TB0> INFO: 2560 events read in total (298ms).
[11:11:39.915] <TB0> INFO: Test took 1159ms.
[11:11:40.220] <TB0> INFO: Expecting 2560 events.
[11:11:41.074] <TB0> INFO: 2560 events read in total (297ms).
[11:11:41.074] <TB0> INFO: Test took 1158ms.
[11:11:41.379] <TB0> INFO: Expecting 2560 events.
[11:11:42.236] <TB0> INFO: 2560 events read in total (300ms).
[11:11:42.237] <TB0> INFO: Test took 1163ms.
[11:11:42.541] <TB0> INFO: Expecting 2560 events.
[11:11:43.397] <TB0> INFO: 2560 events read in total (299ms).
[11:11:43.398] <TB0> INFO: Test took 1161ms.
[11:11:43.703] <TB0> INFO: Expecting 2560 events.
[11:11:44.557] <TB0> INFO: 2560 events read in total (298ms).
[11:11:44.557] <TB0> INFO: Test took 1159ms.
[11:11:44.863] <TB0> INFO: Expecting 2560 events.
[11:11:45.717] <TB0> INFO: 2560 events read in total (298ms).
[11:11:45.718] <TB0> INFO: Test took 1161ms.
[11:11:46.023] <TB0> INFO: Expecting 2560 events.
[11:11:46.878] <TB0> INFO: 2560 events read in total (299ms).
[11:11:46.878] <TB0> INFO: Test took 1160ms.
[11:11:47.183] <TB0> INFO: Expecting 2560 events.
[11:11:48.035] <TB0> INFO: 2560 events read in total (296ms).
[11:11:48.035] <TB0> INFO: Test took 1156ms.
[11:11:48.341] <TB0> INFO: Expecting 2560 events.
[11:11:49.193] <TB0> INFO: 2560 events read in total (296ms).
[11:11:49.194] <TB0> INFO: Test took 1158ms.
[11:11:49.196] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:11:49.499] <TB0> INFO: Expecting 655360 events.
[11:12:06.244] <TB0> INFO: 655360 events read in total (16189ms).
[11:12:06.257] <TB0> INFO: Expecting 655360 events.
[11:12:22.836] <TB0> INFO: 655360 events read in total (16177ms).
[11:12:22.848] <TB0> INFO: Expecting 655360 events.
[11:12:39.504] <TB0> INFO: 655360 events read in total (16254ms).
[11:12:39.527] <TB0> INFO: Expecting 655360 events.
[11:12:56.185] <TB0> INFO: 655360 events read in total (16257ms).
[11:12:56.204] <TB0> INFO: Expecting 655360 events.
[11:13:13.086] <TB0> INFO: 655360 events read in total (16480ms).
[11:13:13.110] <TB0> INFO: Expecting 655360 events.
[11:13:29.685] <TB0> INFO: 655360 events read in total (16173ms).
[11:13:29.710] <TB0> INFO: Expecting 655360 events.
[11:13:46.285] <TB0> INFO: 655360 events read in total (16174ms).
[11:13:46.313] <TB0> INFO: Expecting 655360 events.
[11:14:03.068] <TB0> INFO: 655360 events read in total (16353ms).
[11:14:03.115] <TB0> INFO: Expecting 655360 events.
[11:14:19.990] <TB0> INFO: 655360 events read in total (16473ms).
[11:14:20.032] <TB0> INFO: Expecting 655360 events.
[11:14:36.725] <TB0> INFO: 655360 events read in total (16291ms).
[11:14:36.764] <TB0> INFO: Expecting 655360 events.
[11:14:53.343] <TB0> INFO: 655360 events read in total (16177ms).
[11:14:53.385] <TB0> INFO: Expecting 655360 events.
[11:15:09.983] <TB0> INFO: 655360 events read in total (16197ms).
[11:15:10.035] <TB0> INFO: Expecting 655360 events.
[11:15:26.849] <TB0> INFO: 655360 events read in total (16412ms).
[11:15:26.897] <TB0> INFO: Expecting 655360 events.
[11:15:43.797] <TB0> INFO: 655360 events read in total (16498ms).
[11:15:43.849] <TB0> INFO: Expecting 655360 events.
[11:16:00.549] <TB0> INFO: 655360 events read in total (16299ms).
[11:16:00.605] <TB0> INFO: Expecting 655360 events.
[11:16:17.375] <TB0> INFO: 655360 events read in total (16368ms).
[11:16:17.440] <TB0> INFO: Test took 268244ms.
[11:16:17.519] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:16:17.748] <TB0> INFO: Expecting 655360 events.
[11:16:34.455] <TB0> INFO: 655360 events read in total (16151ms).
[11:16:34.464] <TB0> INFO: Expecting 655360 events.
[11:16:51.322] <TB0> INFO: 655360 events read in total (16457ms).
[11:16:51.334] <TB0> INFO: Expecting 655360 events.
[11:17:07.951] <TB0> INFO: 655360 events read in total (16215ms).
[11:17:07.966] <TB0> INFO: Expecting 655360 events.
[11:17:24.649] <TB0> INFO: 655360 events read in total (16281ms).
[11:17:24.668] <TB0> INFO: Expecting 655360 events.
[11:17:41.312] <TB0> INFO: 655360 events read in total (16242ms).
[11:17:41.335] <TB0> INFO: Expecting 655360 events.
[11:17:57.921] <TB0> INFO: 655360 events read in total (16185ms).
[11:17:57.946] <TB0> INFO: Expecting 655360 events.
[11:18:14.655] <TB0> INFO: 655360 events read in total (16307ms).
[11:18:14.685] <TB0> INFO: Expecting 655360 events.
[11:18:31.441] <TB0> INFO: 655360 events read in total (16355ms).
[11:18:31.472] <TB0> INFO: Expecting 655360 events.
[11:18:48.171] <TB0> INFO: 655360 events read in total (16297ms).
[11:18:48.206] <TB0> INFO: Expecting 655360 events.
[11:19:04.922] <TB0> INFO: 655360 events read in total (16314ms).
[11:19:04.962] <TB0> INFO: Expecting 655360 events.
[11:19:21.767] <TB0> INFO: 655360 events read in total (16403ms).
[11:19:21.810] <TB0> INFO: Expecting 655360 events.
[11:19:38.606] <TB0> INFO: 655360 events read in total (16395ms).
[11:19:38.652] <TB0> INFO: Expecting 655360 events.
[11:19:55.436] <TB0> INFO: 655360 events read in total (16382ms).
[11:19:55.491] <TB0> INFO: Expecting 655360 events.
[11:20:12.288] <TB0> INFO: 655360 events read in total (16395ms).
[11:20:12.343] <TB0> INFO: Expecting 655360 events.
[11:20:29.158] <TB0> INFO: 655360 events read in total (16413ms).
[11:20:29.222] <TB0> INFO: Expecting 655360 events.
[11:20:45.870] <TB0> INFO: 655360 events read in total (16246ms).
[11:20:45.930] <TB0> INFO: Test took 268411ms.
[11:20:46.119] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.126] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.133] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.139] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.146] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.153] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.160] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:20:46.166] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[11:20:46.173] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[11:20:46.179] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.187] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.194] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.200] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.207] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.214] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.221] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.227] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.234] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.241] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:20:46.278] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:20:46.278] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:20:46.279] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:20:46.279] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:20:46.279] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:20:46.279] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:20:46.280] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:20:46.280] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:20:46.280] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:20:46.280] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:20:46.281] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:20:46.281] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:20:46.281] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:20:46.281] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:20:46.282] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:20:46.282] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:20:46.565] <TB0> INFO: Expecting 41600 events.
[11:20:50.040] <TB0> INFO: 41600 events read in total (2919ms).
[11:20:50.040] <TB0> INFO: Test took 3754ms.
[11:20:50.490] <TB0> INFO: Expecting 41600 events.
[11:20:53.980] <TB0> INFO: 41600 events read in total (2933ms).
[11:20:53.981] <TB0> INFO: Test took 3765ms.
[11:20:54.428] <TB0> INFO: Expecting 41600 events.
[11:20:57.938] <TB0> INFO: 41600 events read in total (2953ms).
[11:20:57.938] <TB0> INFO: Test took 3782ms.
[11:20:58.121] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:20:58.209] <TB0> INFO: Expecting 2560 events.
[11:20:59.063] <TB0> INFO: 2560 events read in total (298ms).
[11:20:59.063] <TB0> INFO: Test took 942ms.
[11:20:59.065] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:20:59.369] <TB0> INFO: Expecting 2560 events.
[11:21:00.225] <TB0> INFO: 2560 events read in total (300ms).
[11:21:00.225] <TB0> INFO: Test took 1160ms.
[11:21:00.227] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:00.531] <TB0> INFO: Expecting 2560 events.
[11:21:01.386] <TB0> INFO: 2560 events read in total (299ms).
[11:21:01.386] <TB0> INFO: Test took 1159ms.
[11:21:01.388] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:01.691] <TB0> INFO: Expecting 2560 events.
[11:21:02.547] <TB0> INFO: 2560 events read in total (300ms).
[11:21:02.547] <TB0> INFO: Test took 1159ms.
[11:21:02.549] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:02.852] <TB0> INFO: Expecting 2560 events.
[11:21:03.705] <TB0> INFO: 2560 events read in total (297ms).
[11:21:03.705] <TB0> INFO: Test took 1156ms.
[11:21:03.707] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:04.011] <TB0> INFO: Expecting 2560 events.
[11:21:04.866] <TB0> INFO: 2560 events read in total (299ms).
[11:21:04.866] <TB0> INFO: Test took 1159ms.
[11:21:04.868] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:05.172] <TB0> INFO: Expecting 2560 events.
[11:21:06.025] <TB0> INFO: 2560 events read in total (297ms).
[11:21:06.025] <TB0> INFO: Test took 1157ms.
[11:21:06.027] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:06.331] <TB0> INFO: Expecting 2560 events.
[11:21:07.186] <TB0> INFO: 2560 events read in total (299ms).
[11:21:07.187] <TB0> INFO: Test took 1160ms.
[11:21:07.188] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:07.492] <TB0> INFO: Expecting 2560 events.
[11:21:08.345] <TB0> INFO: 2560 events read in total (297ms).
[11:21:08.345] <TB0> INFO: Test took 1157ms.
[11:21:08.347] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:08.651] <TB0> INFO: Expecting 2560 events.
[11:21:09.503] <TB0> INFO: 2560 events read in total (296ms).
[11:21:09.503] <TB0> INFO: Test took 1156ms.
[11:21:09.505] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:09.809] <TB0> INFO: Expecting 2560 events.
[11:21:10.663] <TB0> INFO: 2560 events read in total (298ms).
[11:21:10.663] <TB0> INFO: Test took 1158ms.
[11:21:10.665] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:10.968] <TB0> INFO: Expecting 2560 events.
[11:21:11.826] <TB0> INFO: 2560 events read in total (302ms).
[11:21:11.826] <TB0> INFO: Test took 1161ms.
[11:21:11.827] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:12.132] <TB0> INFO: Expecting 2560 events.
[11:21:12.988] <TB0> INFO: 2560 events read in total (300ms).
[11:21:12.989] <TB0> INFO: Test took 1162ms.
[11:21:12.991] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:13.294] <TB0> INFO: Expecting 2560 events.
[11:21:14.147] <TB0> INFO: 2560 events read in total (297ms).
[11:21:14.147] <TB0> INFO: Test took 1156ms.
[11:21:14.149] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:14.453] <TB0> INFO: Expecting 2560 events.
[11:21:15.310] <TB0> INFO: 2560 events read in total (301ms).
[11:21:15.311] <TB0> INFO: Test took 1162ms.
[11:21:15.313] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:15.616] <TB0> INFO: Expecting 2560 events.
[11:21:16.469] <TB0> INFO: 2560 events read in total (297ms).
[11:21:16.469] <TB0> INFO: Test took 1157ms.
[11:21:16.470] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:16.774] <TB0> INFO: Expecting 2560 events.
[11:21:17.627] <TB0> INFO: 2560 events read in total (297ms).
[11:21:17.627] <TB0> INFO: Test took 1157ms.
[11:21:17.629] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:17.933] <TB0> INFO: Expecting 2560 events.
[11:21:18.787] <TB0> INFO: 2560 events read in total (298ms).
[11:21:18.787] <TB0> INFO: Test took 1158ms.
[11:21:18.789] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:19.094] <TB0> INFO: Expecting 2560 events.
[11:21:19.947] <TB0> INFO: 2560 events read in total (297ms).
[11:21:19.947] <TB0> INFO: Test took 1158ms.
[11:21:19.948] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:20.252] <TB0> INFO: Expecting 2560 events.
[11:21:21.105] <TB0> INFO: 2560 events read in total (296ms).
[11:21:21.106] <TB0> INFO: Test took 1158ms.
[11:21:21.108] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:21.411] <TB0> INFO: Expecting 2560 events.
[11:21:22.264] <TB0> INFO: 2560 events read in total (297ms).
[11:21:22.264] <TB0> INFO: Test took 1156ms.
[11:21:22.266] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:22.569] <TB0> INFO: Expecting 2560 events.
[11:21:23.423] <TB0> INFO: 2560 events read in total (298ms).
[11:21:23.423] <TB0> INFO: Test took 1157ms.
[11:21:23.424] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:23.728] <TB0> INFO: Expecting 2560 events.
[11:21:24.581] <TB0> INFO: 2560 events read in total (297ms).
[11:21:24.582] <TB0> INFO: Test took 1158ms.
[11:21:24.584] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:24.888] <TB0> INFO: Expecting 2560 events.
[11:21:25.743] <TB0> INFO: 2560 events read in total (299ms).
[11:21:25.743] <TB0> INFO: Test took 1159ms.
[11:21:25.745] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:26.049] <TB0> INFO: Expecting 2560 events.
[11:21:26.906] <TB0> INFO: 2560 events read in total (301ms).
[11:21:26.907] <TB0> INFO: Test took 1162ms.
[11:21:26.908] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:27.212] <TB0> INFO: Expecting 2560 events.
[11:21:28.066] <TB0> INFO: 2560 events read in total (297ms).
[11:21:28.066] <TB0> INFO: Test took 1158ms.
[11:21:28.068] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:28.371] <TB0> INFO: Expecting 2560 events.
[11:21:29.228] <TB0> INFO: 2560 events read in total (301ms).
[11:21:29.229] <TB0> INFO: Test took 1162ms.
[11:21:29.231] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:29.534] <TB0> INFO: Expecting 2560 events.
[11:21:30.388] <TB0> INFO: 2560 events read in total (298ms).
[11:21:30.388] <TB0> INFO: Test took 1157ms.
[11:21:30.390] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:30.693] <TB0> INFO: Expecting 2560 events.
[11:21:31.547] <TB0> INFO: 2560 events read in total (298ms).
[11:21:31.547] <TB0> INFO: Test took 1158ms.
[11:21:31.549] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:31.853] <TB0> INFO: Expecting 2560 events.
[11:21:32.706] <TB0> INFO: 2560 events read in total (297ms).
[11:21:32.706] <TB0> INFO: Test took 1157ms.
[11:21:32.708] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:33.012] <TB0> INFO: Expecting 2560 events.
[11:21:33.869] <TB0> INFO: 2560 events read in total (301ms).
[11:21:33.869] <TB0> INFO: Test took 1162ms.
[11:21:33.872] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:21:34.174] <TB0> INFO: Expecting 2560 events.
[11:21:35.027] <TB0> INFO: 2560 events read in total (297ms).
[11:21:35.027] <TB0> INFO: Test took 1156ms.
[11:21:35.477] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 617 seconds
[11:21:35.477] <TB0> INFO: PH scale (per ROC): 73 73 85 80 83 80 82 80 84 83 101 87 84 91 79 92
[11:21:35.477] <TB0> INFO: PH offset (per ROC): 164 170 148 154 164 174 160 161 152 164 140 157 171 144 160 148
[11:21:35.483] <TB0> INFO: Decoding statistics:
[11:21:35.483] <TB0> INFO: General information:
[11:21:35.483] <TB0> INFO: 16bit words read: 91912
[11:21:35.483] <TB0> INFO: valid events total: 10240
[11:21:35.483] <TB0> INFO: empty events: 7742
[11:21:35.483] <TB0> INFO: valid events with pixels: 2498
[11:21:35.483] <TB0> INFO: valid pixel hits: 2498
[11:21:35.483] <TB0> INFO: Event errors: 0
[11:21:35.483] <TB0> INFO: start marker: 0
[11:21:35.483] <TB0> INFO: stop marker: 0
[11:21:35.483] <TB0> INFO: overflow: 0
[11:21:35.483] <TB0> INFO: invalid 5bit words: 0
[11:21:35.483] <TB0> INFO: invalid XOR eye diagram: 0
[11:21:35.483] <TB0> INFO: frame (failed synchr.): 0
[11:21:35.483] <TB0> INFO: idle data (no TBM trl): 0
[11:21:35.483] <TB0> INFO: no data (only TBM hdr): 0
[11:21:35.483] <TB0> INFO: TBM errors: 0
[11:21:35.483] <TB0> INFO: flawed TBM headers: 0
[11:21:35.483] <TB0> INFO: flawed TBM trailers: 0
[11:21:35.483] <TB0> INFO: event ID mismatches: 0
[11:21:35.483] <TB0> INFO: ROC errors: 0
[11:21:35.483] <TB0> INFO: missing ROC header(s): 0
[11:21:35.483] <TB0> INFO: misplaced readback start: 0
[11:21:35.483] <TB0> INFO: Pixel decoding errors: 0
[11:21:35.483] <TB0> INFO: pixel data incomplete: 0
[11:21:35.483] <TB0> INFO: pixel address: 0
[11:21:35.483] <TB0> INFO: pulse height fill bit: 0
[11:21:35.483] <TB0> INFO: buffer corruption: 0
[11:21:35.658] <TB0> INFO: ######################################################################
[11:21:35.658] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:21:35.658] <TB0> INFO: ######################################################################
[11:21:35.668] <TB0> INFO: scanning low vcal = 10
[11:21:35.931] <TB0> INFO: Expecting 41600 events.
[11:21:39.463] <TB0> INFO: 41600 events read in total (2976ms).
[11:21:39.463] <TB0> INFO: Test took 3795ms.
[11:21:39.465] <TB0> INFO: scanning low vcal = 20
[11:21:39.768] <TB0> INFO: Expecting 41600 events.
[11:21:43.327] <TB0> INFO: 41600 events read in total (3003ms).
[11:21:43.327] <TB0> INFO: Test took 3862ms.
[11:21:43.328] <TB0> INFO: scanning low vcal = 30
[11:21:43.632] <TB0> INFO: Expecting 41600 events.
[11:21:47.189] <TB0> INFO: 41600 events read in total (3001ms).
[11:21:47.190] <TB0> INFO: Test took 3862ms.
[11:21:47.191] <TB0> INFO: scanning low vcal = 40
[11:21:47.490] <TB0> INFO: Expecting 41600 events.
[11:21:51.630] <TB0> INFO: 41600 events read in total (3584ms).
[11:21:51.631] <TB0> INFO: Test took 4440ms.
[11:21:51.633] <TB0> INFO: scanning low vcal = 50
[11:21:51.896] <TB0> INFO: Expecting 41600 events.
[11:21:56.082] <TB0> INFO: 41600 events read in total (3630ms).
[11:21:56.083] <TB0> INFO: Test took 4449ms.
[11:21:56.085] <TB0> INFO: scanning low vcal = 60
[11:21:56.355] <TB0> INFO: Expecting 41600 events.
[11:22:00.540] <TB0> INFO: 41600 events read in total (3629ms).
[11:22:00.540] <TB0> INFO: Test took 4455ms.
[11:22:00.543] <TB0> INFO: scanning low vcal = 70
[11:22:00.809] <TB0> INFO: Expecting 41600 events.
[11:22:05.029] <TB0> INFO: 41600 events read in total (3664ms).
[11:22:05.030] <TB0> INFO: Test took 4487ms.
[11:22:05.032] <TB0> INFO: scanning low vcal = 80
[11:22:05.295] <TB0> INFO: Expecting 41600 events.
[11:22:09.492] <TB0> INFO: 41600 events read in total (3641ms).
[11:22:09.492] <TB0> INFO: Test took 4460ms.
[11:22:09.494] <TB0> INFO: scanning low vcal = 90
[11:22:09.760] <TB0> INFO: Expecting 41600 events.
[11:22:13.950] <TB0> INFO: 41600 events read in total (3634ms).
[11:22:13.950] <TB0> INFO: Test took 4455ms.
[11:22:13.953] <TB0> INFO: scanning low vcal = 100
[11:22:14.223] <TB0> INFO: Expecting 41600 events.
[11:22:18.431] <TB0> INFO: 41600 events read in total (3652ms).
[11:22:18.432] <TB0> INFO: Test took 4479ms.
[11:22:18.434] <TB0> INFO: scanning low vcal = 110
[11:22:18.706] <TB0> INFO: Expecting 41600 events.
[11:22:22.969] <TB0> INFO: 41600 events read in total (3706ms).
[11:22:22.970] <TB0> INFO: Test took 4535ms.
[11:22:22.972] <TB0> INFO: scanning low vcal = 120
[11:22:23.257] <TB0> INFO: Expecting 41600 events.
[11:22:27.441] <TB0> INFO: 41600 events read in total (3628ms).
[11:22:27.442] <TB0> INFO: Test took 4470ms.
[11:22:27.444] <TB0> INFO: scanning low vcal = 130
[11:22:27.703] <TB0> INFO: Expecting 41600 events.
[11:22:31.900] <TB0> INFO: 41600 events read in total (3641ms).
[11:22:31.900] <TB0> INFO: Test took 4456ms.
[11:22:31.903] <TB0> INFO: scanning low vcal = 140
[11:22:32.166] <TB0> INFO: Expecting 41600 events.
[11:22:36.376] <TB0> INFO: 41600 events read in total (3653ms).
[11:22:36.376] <TB0> INFO: Test took 4473ms.
[11:22:36.379] <TB0> INFO: scanning low vcal = 150
[11:22:36.668] <TB0> INFO: Expecting 41600 events.
[11:22:40.882] <TB0> INFO: 41600 events read in total (3658ms).
[11:22:40.883] <TB0> INFO: Test took 4504ms.
[11:22:40.885] <TB0> INFO: scanning low vcal = 160
[11:22:41.149] <TB0> INFO: Expecting 41600 events.
[11:22:45.359] <TB0> INFO: 41600 events read in total (3654ms).
[11:22:45.360] <TB0> INFO: Test took 4475ms.
[11:22:45.362] <TB0> INFO: scanning low vcal = 170
[11:22:45.636] <TB0> INFO: Expecting 41600 events.
[11:22:49.864] <TB0> INFO: 41600 events read in total (3671ms).
[11:22:49.865] <TB0> INFO: Test took 4503ms.
[11:22:49.868] <TB0> INFO: scanning low vcal = 180
[11:22:50.127] <TB0> INFO: Expecting 41600 events.
[11:22:54.342] <TB0> INFO: 41600 events read in total (3659ms).
[11:22:54.343] <TB0> INFO: Test took 4475ms.
[11:22:54.345] <TB0> INFO: scanning low vcal = 190
[11:22:54.607] <TB0> INFO: Expecting 41600 events.
[11:22:58.796] <TB0> INFO: 41600 events read in total (3633ms).
[11:22:58.796] <TB0> INFO: Test took 4451ms.
[11:22:58.799] <TB0> INFO: scanning low vcal = 200
[11:22:59.064] <TB0> INFO: Expecting 41600 events.
[11:23:03.262] <TB0> INFO: 41600 events read in total (3641ms).
[11:23:03.262] <TB0> INFO: Test took 4463ms.
[11:23:03.265] <TB0> INFO: scanning low vcal = 210
[11:23:03.531] <TB0> INFO: Expecting 41600 events.
[11:23:07.739] <TB0> INFO: 41600 events read in total (3652ms).
[11:23:07.739] <TB0> INFO: Test took 4474ms.
[11:23:07.742] <TB0> INFO: scanning low vcal = 220
[11:23:08.003] <TB0> INFO: Expecting 41600 events.
[11:23:12.196] <TB0> INFO: 41600 events read in total (3637ms).
[11:23:12.197] <TB0> INFO: Test took 4455ms.
[11:23:12.199] <TB0> INFO: scanning low vcal = 230
[11:23:12.461] <TB0> INFO: Expecting 41600 events.
[11:23:16.704] <TB0> INFO: 41600 events read in total (3687ms).
[11:23:16.705] <TB0> INFO: Test took 4506ms.
[11:23:16.708] <TB0> INFO: scanning low vcal = 240
[11:23:17.000] <TB0> INFO: Expecting 41600 events.
[11:23:21.167] <TB0> INFO: 41600 events read in total (3611ms).
[11:23:21.168] <TB0> INFO: Test took 4460ms.
[11:23:21.170] <TB0> INFO: scanning low vcal = 250
[11:23:21.453] <TB0> INFO: Expecting 41600 events.
[11:23:25.635] <TB0> INFO: 41600 events read in total (3626ms).
[11:23:25.635] <TB0> INFO: Test took 4465ms.
[11:23:25.639] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[11:23:25.919] <TB0> INFO: Expecting 41600 events.
[11:23:30.105] <TB0> INFO: 41600 events read in total (3629ms).
[11:23:30.106] <TB0> INFO: Test took 4467ms.
[11:23:30.108] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[11:23:30.372] <TB0> INFO: Expecting 41600 events.
[11:23:34.557] <TB0> INFO: 41600 events read in total (3629ms).
[11:23:34.557] <TB0> INFO: Test took 4449ms.
[11:23:34.560] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[11:23:34.824] <TB0> INFO: Expecting 41600 events.
[11:23:39.053] <TB0> INFO: 41600 events read in total (3673ms).
[11:23:39.053] <TB0> INFO: Test took 4493ms.
[11:23:39.056] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[11:23:39.326] <TB0> INFO: Expecting 41600 events.
[11:23:43.502] <TB0> INFO: 41600 events read in total (3620ms).
[11:23:43.503] <TB0> INFO: Test took 4447ms.
[11:23:43.505] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:23:43.765] <TB0> INFO: Expecting 41600 events.
[11:23:47.948] <TB0> INFO: 41600 events read in total (3627ms).
[11:23:47.949] <TB0> INFO: Test took 4443ms.
[11:23:48.408] <TB0> INFO: PixTestGainPedestal::measure() done
[11:24:21.468] <TB0> INFO: PixTestGainPedestal::fit() done
[11:24:21.468] <TB0> INFO: non-linearity mean: 0.954 0.955 0.962 0.955 0.958 0.960 0.956 0.953 0.953 0.946 0.955 0.954 0.958 0.956 0.951 0.954
[11:24:21.468] <TB0> INFO: non-linearity RMS: 0.005 0.007 0.005 0.005 0.006 0.004 0.006 0.006 0.006 0.006 0.005 0.005 0.006 0.005 0.005 0.006
[11:24:21.468] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:24:21.488] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:24:21.507] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:24:21.527] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:24:21.546] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:24:21.565] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:24:21.585] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:24:21.604] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:24:21.624] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:24:21.643] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:24:21.663] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:24:21.683] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:24:21.702] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:24:21.721] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:24:21.741] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:24:21.760] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:24:21.778] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 166 seconds
[11:24:21.778] <TB0> INFO: Decoding statistics:
[11:24:21.778] <TB0> INFO: General information:
[11:24:21.778] <TB0> INFO: 16bit words read: 2662400
[11:24:21.778] <TB0> INFO: valid events total: 166400
[11:24:21.778] <TB0> INFO: empty events: 0
[11:24:21.778] <TB0> INFO: valid events with pixels: 166400
[11:24:21.778] <TB0> INFO: valid pixel hits: 665569
[11:24:21.778] <TB0> INFO: Event errors: 0
[11:24:21.778] <TB0> INFO: start marker: 0
[11:24:21.778] <TB0> INFO: stop marker: 0
[11:24:21.778] <TB0> INFO: overflow: 0
[11:24:21.778] <TB0> INFO: invalid 5bit words: 0
[11:24:21.778] <TB0> INFO: invalid XOR eye diagram: 0
[11:24:21.778] <TB0> INFO: frame (failed synchr.): 0
[11:24:21.778] <TB0> INFO: idle data (no TBM trl): 0
[11:24:21.778] <TB0> INFO: no data (only TBM hdr): 0
[11:24:21.779] <TB0> INFO: TBM errors: 0
[11:24:21.779] <TB0> INFO: flawed TBM headers: 0
[11:24:21.779] <TB0> INFO: flawed TBM trailers: 0
[11:24:21.779] <TB0> INFO: event ID mismatches: 0
[11:24:21.779] <TB0> INFO: ROC errors: 0
[11:24:21.779] <TB0> INFO: missing ROC header(s): 0
[11:24:21.779] <TB0> INFO: misplaced readback start: 0
[11:24:21.779] <TB0> INFO: Pixel decoding errors: 0
[11:24:21.779] <TB0> INFO: pixel data incomplete: 0
[11:24:21.779] <TB0> INFO: pixel address: 0
[11:24:21.779] <TB0> INFO: pulse height fill bit: 0
[11:24:21.779] <TB0> INFO: buffer corruption: 0
[11:24:21.787] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C15.dat
[11:24:21.788] <TB0> INFO: ######################################################################
[11:24:21.788] <TB0> INFO: PixTestReadback::doTest()
[11:24:21.788] <TB0> INFO: ######################################################################
[11:24:21.788] <TB0> INFO: ----------------------------------------------------------------------
[11:24:21.788] <TB0> INFO: PixTestReadback::CalibrateVd()
[11:24:21.788] <TB0> INFO: ----------------------------------------------------------------------
[11:24:31.244] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C0.dat
[11:24:31.244] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C1.dat
[11:24:31.245] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C2.dat
[11:24:31.245] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C3.dat
[11:24:31.245] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C4.dat
[11:24:31.245] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C5.dat
[11:24:31.245] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C6.dat
[11:24:31.245] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C7.dat
[11:24:31.245] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C8.dat
[11:24:31.245] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C9.dat
[11:24:31.245] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C10.dat
[11:24:31.245] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C11.dat
[11:24:31.245] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C12.dat
[11:24:31.245] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C13.dat
[11:24:31.245] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C14.dat
[11:24:31.245] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C15.dat
[11:24:31.273] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:24:31.273] <TB0> INFO: ----------------------------------------------------------------------
[11:24:31.273] <TB0> INFO: PixTestReadback::CalibrateVa()
[11:24:31.273] <TB0> INFO: ----------------------------------------------------------------------
[11:24:41.264] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C0.dat
[11:24:41.264] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C1.dat
[11:24:41.264] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C2.dat
[11:24:41.264] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C3.dat
[11:24:41.264] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C4.dat
[11:24:41.264] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C5.dat
[11:24:41.264] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C6.dat
[11:24:41.265] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C7.dat
[11:24:41.265] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C8.dat
[11:24:41.265] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C9.dat
[11:24:41.265] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C10.dat
[11:24:41.265] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C11.dat
[11:24:41.265] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C12.dat
[11:24:41.265] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C13.dat
[11:24:41.265] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C14.dat
[11:24:41.265] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C15.dat
[11:24:41.290] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:24:41.290] <TB0> INFO: ----------------------------------------------------------------------
[11:24:41.290] <TB0> INFO: PixTestReadback::readbackVbg()
[11:24:41.290] <TB0> INFO: ----------------------------------------------------------------------
[11:24:48.577] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:24:48.577] <TB0> INFO: ----------------------------------------------------------------------
[11:24:48.577] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[11:24:48.577] <TB0> INFO: ----------------------------------------------------------------------
[11:24:48.577] <TB0> INFO: Vbg will be calibrated using Vd calibration
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 153.3calibrated Vbg = 1.20644 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153.5calibrated Vbg = 1.20632 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 150.3calibrated Vbg = 1.20824 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156.2calibrated Vbg = 1.22155 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 152.9calibrated Vbg = 1.23287 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.3calibrated Vbg = 1.23815 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 144.3calibrated Vbg = 1.2375 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 151.1calibrated Vbg = 1.2306 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 158.4calibrated Vbg = 1.23424 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 146.9calibrated Vbg = 1.22951 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 160.5calibrated Vbg = 1.22744 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 156.2calibrated Vbg = 1.22364 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 148.9calibrated Vbg = 1.21359 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 144.5calibrated Vbg = 1.21755 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 148calibrated Vbg = 1.21666 :::*/*/*/*/
[11:24:48.577] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156.3calibrated Vbg = 1.22027 :::*/*/*/*/
[11:24:48.579] <TB0> INFO: ----------------------------------------------------------------------
[11:24:48.579] <TB0> INFO: PixTestReadback::CalibrateIa()
[11:24:48.579] <TB0> INFO: ----------------------------------------------------------------------
[11:27:23.776] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C0.dat
[11:27:23.777] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C1.dat
[11:27:23.777] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C2.dat
[11:27:23.777] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C3.dat
[11:27:23.777] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C4.dat
[11:27:23.777] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C5.dat
[11:27:23.777] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C6.dat
[11:27:23.777] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C7.dat
[11:27:23.777] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C8.dat
[11:27:23.777] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C9.dat
[11:27:23.777] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C10.dat
[11:27:23.777] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C11.dat
[11:27:23.777] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C12.dat
[11:27:23.777] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C13.dat
[11:27:23.778] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C14.dat
[11:27:23.778] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2257_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C15.dat
[11:27:23.804] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:27:23.805] <TB0> INFO: PixTestReadback::doTest() done
[11:27:23.805] <TB0> INFO: Decoding statistics:
[11:27:23.805] <TB0> INFO: General information:
[11:27:23.805] <TB0> INFO: 16bit words read: 1024
[11:27:23.805] <TB0> INFO: valid events total: 128
[11:27:23.805] <TB0> INFO: empty events: 128
[11:27:23.805] <TB0> INFO: valid events with pixels: 0
[11:27:23.805] <TB0> INFO: valid pixel hits: 0
[11:27:23.805] <TB0> INFO: Event errors: 0
[11:27:23.805] <TB0> INFO: start marker: 0
[11:27:23.805] <TB0> INFO: stop marker: 0
[11:27:23.805] <TB0> INFO: overflow: 0
[11:27:23.805] <TB0> INFO: invalid 5bit words: 0
[11:27:23.805] <TB0> INFO: invalid XOR eye diagram: 0
[11:27:23.805] <TB0> INFO: frame (failed synchr.): 0
[11:27:23.805] <TB0> INFO: idle data (no TBM trl): 0
[11:27:23.805] <TB0> INFO: no data (only TBM hdr): 0
[11:27:23.805] <TB0> INFO: TBM errors: 0
[11:27:23.805] <TB0> INFO: flawed TBM headers: 0
[11:27:23.805] <TB0> INFO: flawed TBM trailers: 0
[11:27:23.805] <TB0> INFO: event ID mismatches: 0
[11:27:23.805] <TB0> INFO: ROC errors: 0
[11:27:23.805] <TB0> INFO: missing ROC header(s): 0
[11:27:23.805] <TB0> INFO: misplaced readback start: 0
[11:27:23.805] <TB0> INFO: Pixel decoding errors: 0
[11:27:23.805] <TB0> INFO: pixel data incomplete: 0
[11:27:23.805] <TB0> INFO: pixel address: 0
[11:27:23.805] <TB0> INFO: pulse height fill bit: 0
[11:27:23.805] <TB0> INFO: buffer corruption: 0
[11:27:23.827] <TB0> INFO: Decoding statistics:
[11:27:23.827] <TB0> INFO: General information:
[11:27:23.827] <TB0> INFO: 16bit words read: 2755336
[11:27:23.827] <TB0> INFO: valid events total: 176768
[11:27:23.827] <TB0> INFO: empty events: 7870
[11:27:23.827] <TB0> INFO: valid events with pixels: 168898
[11:27:23.827] <TB0> INFO: valid pixel hits: 668067
[11:27:23.827] <TB0> INFO: Event errors: 0
[11:27:23.827] <TB0> INFO: start marker: 0
[11:27:23.827] <TB0> INFO: stop marker: 0
[11:27:23.827] <TB0> INFO: overflow: 0
[11:27:23.827] <TB0> INFO: invalid 5bit words: 0
[11:27:23.827] <TB0> INFO: invalid XOR eye diagram: 0
[11:27:23.827] <TB0> INFO: frame (failed synchr.): 0
[11:27:23.827] <TB0> INFO: idle data (no TBM trl): 0
[11:27:23.827] <TB0> INFO: no data (only TBM hdr): 0
[11:27:23.827] <TB0> INFO: TBM errors: 0
[11:27:23.827] <TB0> INFO: flawed TBM headers: 0
[11:27:23.827] <TB0> INFO: flawed TBM trailers: 0
[11:27:23.827] <TB0> INFO: event ID mismatches: 0
[11:27:23.827] <TB0> INFO: ROC errors: 0
[11:27:23.827] <TB0> INFO: missing ROC header(s): 0
[11:27:23.827] <TB0> INFO: misplaced readback start: 0
[11:27:23.827] <TB0> INFO: Pixel decoding errors: 0
[11:27:23.827] <TB0> INFO: pixel data incomplete: 0
[11:27:23.827] <TB0> INFO: pixel address: 0
[11:27:23.827] <TB0> INFO: pulse height fill bit: 0
[11:27:23.827] <TB0> INFO: buffer corruption: 0
[11:27:23.827] <TB0> INFO: enter test to run
[11:27:23.827] <TB0> INFO: test: exit no parameter change
[11:27:23.985] <TB0> QUIET: Connection to board 73 closed.
[11:27:24.065] <TB0> INFO: pXar: this is the end, my friend