[12:01:30.142] <TB0> INFO: *** Welcome to pxar ***
[12:01:30.142] <TB0> INFO: *** Today: 2016/02/06
[12:01:30.637] <TB0> INFO: *** Version: 9751-dirty
[12:01:30.637] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C15.dat
[12:01:30.637] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//tbmParameters_C0b.dat
[12:01:30.637] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//defaultMaskFile.dat
[12:01:30.637] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters_C15.dat
[12:01:30.708] <TB0> INFO: clk: 4
[12:01:30.708] <TB0> INFO: ctr: 4
[12:01:30.708] <TB0> INFO: sda: 19
[12:01:30.708] <TB0> INFO: tin: 9
[12:01:30.708] <TB0> INFO: level: 15
[12:01:30.708] <TB0> INFO: triggerdelay: 0
[12:01:30.708] <TB0> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[12:01:30.708] <TB0> INFO: Log level: INFO
[12:01:30.715] <TB0> INFO: Found DTB DTB_WS6AYH
[12:01:30.727] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[12:01:30.729] <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:
------------------------------------------------------
[12:01:30.730] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[12:01:32.260] <TB0> INFO: DUT info:
[12:01:32.260] <TB0> INFO: The DUT currently contains the following objects:
[12:01:32.261] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[12:01:32.261] <TB0> INFO: TBM Core alpha (0): 7 registers set
[12:01:32.261] <TB0> INFO: TBM Core beta (1): 7 registers set
[12:01:32.261] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:01:32.261] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.261] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:01:32.661] <TB0> INFO: enter 'restricted' command line mode
[12:01:32.662] <TB0> INFO: enter test to run
[12:01:32.662] <TB0> INFO: test: pretest no parameter change
[12:01:32.662] <TB0> INFO: running: pretest
[12:01:32.665] <TB0> INFO: ----------------------------------------------------------------------
[12:01:32.665] <TB0> INFO: PixTestPretest::programROC()
[12:01:32.665] <TB0> INFO: ----------------------------------------------------------------------
[12:01:50.679] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:01:50.679] <TB0> INFO: IA differences per ROC: 21.7 16.1 17.7 19.3 18.5 17.7 19.3 20.1 20.1 17.7 19.3 18.5 17.7 18.5 19.3 19.3
[12:01:50.779] <TB0> INFO: enter test to run
[12:01:50.779] <TB0> INFO: test: pretest no parameter change
[12:01:50.779] <TB0> INFO: running: pretest
[12:01:50.780] <TB0> INFO: ----------------------------------------------------------------------
[12:01:50.780] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:01:50.780] <TB0> INFO: ----------------------------------------------------------------------
[12:02:12.047] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 387.5 mA = 24.2188 mA/ROC
[12:02:12.047] <TB0> INFO: i(loss) [mA/ROC]: 20.1 19.3 20.1 18.5 20.1 19.3 19.3 19.3 20.1 19.3 18.5 18.5 19.3 19.3 20.1 20.1
[12:02:12.076] <TB0> INFO: enter test to run
[12:02:12.076] <TB0> INFO: test: pretest no parameter change
[12:02:12.076] <TB0> INFO: running: pretest
[12:02:12.076] <TB0> INFO: ----------------------------------------------------------------------
[12:02:12.076] <TB0> INFO: PixTestPretest::findTiming()
[12:02:12.076] <TB0> INFO: ----------------------------------------------------------------------
[12:02:12.076] <TB0> INFO: PixTestCmd::init()
[12:02:13.033] <TB0> WARNING: Not unmasking DUT, not setting Calibrate bits!
[12:03:41.598] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[12:03:41.598] <TB0> INFO: (success/tries = 100/100), width = 5
[12:03:41.598] <TB0> INFO: enter test to run
[12:03:41.598] <TB0> INFO: test: pretest no parameter change
[12:03:41.598] <TB0> INFO: running: pretest
[12:03:41.599] <TB0> INFO: ----------------------------------------------------------------------
[12:03:41.599] <TB0> INFO: PixTestPretest::findWorkingPixel()
[12:03:41.600] <TB0> INFO: ----------------------------------------------------------------------
[12:03:41.690] <TB0> INFO: Expecting 231680 events.
[12:03:47.466] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[12:03:47.547] <TB0> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[12:03:51.868] <TB0> INFO: 231680 events read in total (9622ms).
[12:03:51.872] <TB0> INFO: Test took 10270ms.
[12:03:52.101] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:03:52.164] <TB0> INFO: enter test to run
[12:03:52.164] <TB0> INFO: test: pretest no parameter change
[12:03:52.164] <TB0> INFO: running: pretest
[12:03:52.165] <TB0> INFO: ----------------------------------------------------------------------
[12:03:52.165] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[12:03:52.165] <TB0> INFO: ----------------------------------------------------------------------
[12:03:52.257] <TB0> INFO: Expecting 231680 events.
[12:04:02.476] <TB0> INFO: 231680 events read in total (9663ms).
[12:04:02.480] <TB0> INFO: Test took 10311ms.
[12:04:02.725] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[12:04:02.725] <TB0> INFO: CalDel: 162 162 141 135 174 124 143 148 159 135 132 133 124 143 146 142
[12:04:02.725] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 52 51 51 51
[12:04:02.775] <TB0> INFO: enter test to run
[12:04:02.775] <TB0> INFO: test: pretest no parameter change
[12:04:02.775] <TB0> INFO: running: pretest
[12:04:02.775] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C0.dat
[12:04:02.776] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C1.dat
[12:04:02.776] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C2.dat
[12:04:02.776] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C3.dat
[12:04:02.776] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C4.dat
[12:04:02.776] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C5.dat
[12:04:02.776] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C6.dat
[12:04:02.776] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C7.dat
[12:04:02.777] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C8.dat
[12:04:02.777] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C9.dat
[12:04:02.777] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C10.dat
[12:04:02.777] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C11.dat
[12:04:02.777] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C12.dat
[12:04:02.777] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C13.dat
[12:04:02.777] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C14.dat
[12:04:02.777] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters_C15.dat
[12:04:02.778] <TB0> INFO: enter test to run
[12:04:02.778] <TB0> INFO: test: fulltest no parameter change
[12:04:02.778] <TB0> INFO: running: fulltest
[12:04:02.778] <TB0> INFO: ######################################################################
[12:04:02.778] <TB0> INFO: PixTestFullTest::doTest()
[12:04:02.778] <TB0> INFO: ######################################################################
[12:04:02.779] <TB0> INFO: ######################################################################
[12:04:02.779] <TB0> INFO: PixTestAlive::doTest()
[12:04:02.779] <TB0> INFO: ######################################################################
[12:04:02.781] <TB0> INFO: ----------------------------------------------------------------------
[12:04:02.781] <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)
[12:04:02.781] <TB0> INFO: ----------------------------------------------------------------------
[12:04:03.055] <TB0> INFO: Expecting 41600 events.
[12:04:06.846] <TB0> INFO: 41600 events read in total (3235ms).
[12:04:06.846] <TB0> INFO: Test took 4064ms.
[12:04:07.076] <TB0> INFO: PixTestAlive::aliveTest() done
[12:04:07.076] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 1 0 0
[12:04:07.077] <TB0> INFO: ----------------------------------------------------------------------
[12:04:07.077] <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)
[12:04:07.077] <TB0> INFO: ----------------------------------------------------------------------
[12:04:07.345] <TB0> INFO: Expecting 41600 events.
[12:04:10.341] <TB0> INFO: 41600 events read in total (2440ms).
[12:04:10.342] <TB0> INFO: Test took 3263ms.
[12:04:10.342] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:04:10.581] <TB0> INFO: PixTestAlive::maskTest() done
[12:04:10.581] <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
[12:04:10.583] <TB0> INFO: ----------------------------------------------------------------------
[12:04:10.583] <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)
[12:04:10.583] <TB0> INFO: ----------------------------------------------------------------------
[12:04:10.857] <TB0> INFO: Expecting 41600 events.
[12:04:14.659] <TB0> INFO: 41600 events read in total (3246ms).
[12:04:14.659] <TB0> INFO: Test took 4075ms.
[12:04:14.888] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[12:04:14.888] <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
[12:04:14.888] <TB0> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[12:04:14.888] <TB0> INFO: Decoding statistics:
[12:04:14.888] <TB0> INFO: General information:
[12:04:14.888] <TB0> INFO: 16bit words read: 0
[12:04:14.888] <TB0> INFO: valid events total: 0
[12:04:14.888] <TB0> INFO: empty events: 0
[12:04:14.888] <TB0> INFO: valid events with pixels: 0
[12:04:14.888] <TB0> INFO: valid pixel hits: 0
[12:04:14.888] <TB0> INFO: Event errors: 0
[12:04:14.888] <TB0> INFO: start marker: 0
[12:04:14.888] <TB0> INFO: stop marker: 0
[12:04:14.888] <TB0> INFO: overflow: 0
[12:04:14.888] <TB0> INFO: invalid 5bit words: 0
[12:04:14.888] <TB0> INFO: invalid XOR eye diagram: 0
[12:04:14.888] <TB0> INFO: frame (failed synchr.): 0
[12:04:14.888] <TB0> INFO: idle data (no TBM trl): 0
[12:04:14.888] <TB0> INFO: no data (only TBM hdr): 0
[12:04:14.888] <TB0> INFO: TBM errors: 0
[12:04:14.888] <TB0> INFO: flawed TBM headers: 0
[12:04:14.888] <TB0> INFO: flawed TBM trailers: 0
[12:04:14.888] <TB0> INFO: event ID mismatches: 0
[12:04:14.888] <TB0> INFO: ROC errors: 0
[12:04:14.888] <TB0> INFO: missing ROC header(s): 0
[12:04:14.888] <TB0> INFO: misplaced readback start: 0
[12:04:14.888] <TB0> INFO: Pixel decoding errors: 0
[12:04:14.888] <TB0> INFO: pixel data incomplete: 0
[12:04:14.888] <TB0> INFO: pixel address: 0
[12:04:14.888] <TB0> INFO: pulse height fill bit: 0
[12:04:14.888] <TB0> INFO: buffer corruption: 0
[12:04:14.895] <TB0> INFO: ######################################################################
[12:04:14.895] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:04:14.895] <TB0> INFO: ######################################################################
[12:04:14.897] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:04:14.908] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[12:04:14.908] <TB0> INFO: run 1 of 1
[12:04:15.169] <TB0> INFO: Expecting 3120000 events.
[12:04:57.057] <TB0> INFO: 875675 events read in total (41332ms).
[12:05:38.557] <TB0> INFO: 1742075 events read in total (82832ms).
[12:06:19.733] <TB0> INFO: 2617910 events read in total (124009ms).
[12:06:43.431] <TB0> INFO: 3120000 events read in total (147706ms).
[12:06:43.481] <TB0> INFO: Test took 148572ms.
[12:07:08.245] <TB0> INFO: PixTestBBMap::doTest() done, duration: 173 seconds
[12:07:08.245] <TB0> INFO: number of dead bumps (per ROC): 11 5 3 2 12 0 0 0 0 0 0 0 0 1 0 0
[12:07:08.245] <TB0> INFO: separation cut (per ROC): 106 85 75 96 79 98 106 85 90 94 86 93 99 100 95 103
[12:07:08.245] <TB0> INFO: Decoding statistics:
[12:07:08.245] <TB0> INFO: General information:
[12:07:08.245] <TB0> INFO: 16bit words read: 0
[12:07:08.245] <TB0> INFO: valid events total: 0
[12:07:08.245] <TB0> INFO: empty events: 0
[12:07:08.245] <TB0> INFO: valid events with pixels: 0
[12:07:08.245] <TB0> INFO: valid pixel hits: 0
[12:07:08.245] <TB0> INFO: Event errors: 0
[12:07:08.245] <TB0> INFO: start marker: 0
[12:07:08.245] <TB0> INFO: stop marker: 0
[12:07:08.245] <TB0> INFO: overflow: 0
[12:07:08.245] <TB0> INFO: invalid 5bit words: 0
[12:07:08.245] <TB0> INFO: invalid XOR eye diagram: 0
[12:07:08.245] <TB0> INFO: frame (failed synchr.): 0
[12:07:08.245] <TB0> INFO: idle data (no TBM trl): 0
[12:07:08.245] <TB0> INFO: no data (only TBM hdr): 0
[12:07:08.245] <TB0> INFO: TBM errors: 0
[12:07:08.245] <TB0> INFO: flawed TBM headers: 0
[12:07:08.245] <TB0> INFO: flawed TBM trailers: 0
[12:07:08.245] <TB0> INFO: event ID mismatches: 0
[12:07:08.245] <TB0> INFO: ROC errors: 0
[12:07:08.245] <TB0> INFO: missing ROC header(s): 0
[12:07:08.245] <TB0> INFO: misplaced readback start: 0
[12:07:08.245] <TB0> INFO: Pixel decoding errors: 0
[12:07:08.245] <TB0> INFO: pixel data incomplete: 0
[12:07:08.245] <TB0> INFO: pixel address: 0
[12:07:08.245] <TB0> INFO: pulse height fill bit: 0
[12:07:08.245] <TB0> INFO: buffer corruption: 0
[12:07:08.320] <TB0> INFO: ######################################################################
[12:07:08.320] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:07:08.320] <TB0> INFO: ######################################################################
[12:07:08.320] <TB0> INFO: ----------------------------------------------------------------------
[12:07:08.320] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:07:08.320] <TB0> INFO: ----------------------------------------------------------------------
[12:07:08.320] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:07:08.330] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[12:07:08.330] <TB0> INFO: run 1 of 1
[12:07:08.593] <TB0> INFO: Expecting 26208000 events.
[12:07:42.824] <TB0> INFO: 908900 events read in total (33675ms).
[12:08:16.492] <TB0> INFO: 1801400 events read in total (67343ms).
[12:08:50.167] <TB0> INFO: 2694250 events read in total (101018ms).
[12:09:23.951] <TB0> INFO: 3585950 events read in total (134802ms).
[12:09:57.543] <TB0> INFO: 4474000 events read in total (168394ms).
[12:10:31.434] <TB0> INFO: 5363300 events read in total (202285ms).
[12:11:05.127] <TB0> INFO: 6254950 events read in total (235978ms).
[12:11:38.451] <TB0> INFO: 7145400 events read in total (269302ms).
[12:12:12.148] <TB0> INFO: 8033900 events read in total (302999ms).
[12:12:45.716] <TB0> INFO: 8922150 events read in total (336567ms).
[12:13:19.443] <TB0> INFO: 9812400 events read in total (370294ms).
[12:13:52.951] <TB0> INFO: 10698900 events read in total (403802ms).
[12:14:26.383] <TB0> INFO: 11584350 events read in total (437234ms).
[12:14:59.469] <TB0> INFO: 12470950 events read in total (470320ms).
[12:15:33.261] <TB0> INFO: 13353400 events read in total (504112ms).
[12:16:06.517] <TB0> INFO: 14230350 events read in total (537368ms).
[12:16:39.750] <TB0> INFO: 15105400 events read in total (570601ms).
[12:17:13.015] <TB0> INFO: 15981100 events read in total (603866ms).
[12:17:47.838] <TB0> INFO: 16855400 events read in total (638689ms).
[12:18:22.243] <TB0> INFO: 17731450 events read in total (673094ms).
[12:18:56.604] <TB0> INFO: 18603600 events read in total (707455ms).
[12:19:30.145] <TB0> INFO: 19476150 events read in total (740996ms).
[12:20:03.363] <TB0> INFO: 20350500 events read in total (774214ms).
[12:20:36.758] <TB0> INFO: 21222950 events read in total (807609ms).
[12:21:10.088] <TB0> INFO: 22093700 events read in total (840939ms).
[12:21:43.766] <TB0> INFO: 22967750 events read in total (874617ms).
[12:22:17.130] <TB0> INFO: 23843700 events read in total (907981ms).
[12:22:50.622] <TB0> INFO: 24718500 events read in total (941473ms).
[12:23:24.104] <TB0> INFO: 25593200 events read in total (974955ms).
[12:23:46.822] <TB0> INFO: 26208000 events read in total (997673ms).
[12:23:46.851] <TB0> INFO: Test took 998521ms.
[12:23:47.203] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:23:48.771] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:23:50.242] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:23:51.792] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:23:53.188] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:23:54.591] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:23:56.081] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:23:57.537] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:23:59.029] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:24:00.487] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:24:01.943] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:24:03.405] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:24:04.878] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:24:06.315] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:24:07.843] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:24:09.329] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:24:10.881] <TB0> INFO: PixTestScurves::scurves() done
[12:24:10.881] <TB0> INFO: Vcal mean: 108.58 88.73 77.50 88.92 82.47 99.67 105.49 84.46 77.36 96.59 82.54 91.40 92.61 92.78 99.93 92.72
[12:24:10.881] <TB0> INFO: Vcal RMS: 5.19 5.61 4.28 5.63 4.55 5.77 5.59 4.93 4.23 5.96 5.29 5.46 5.70 6.26 5.71 5.26
[12:24:10.881] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1022 seconds
[12:24:10.881] <TB0> INFO: Decoding statistics:
[12:24:10.881] <TB0> INFO: General information:
[12:24:10.881] <TB0> INFO: 16bit words read: 0
[12:24:10.881] <TB0> INFO: valid events total: 0
[12:24:10.881] <TB0> INFO: empty events: 0
[12:24:10.881] <TB0> INFO: valid events with pixels: 0
[12:24:10.881] <TB0> INFO: valid pixel hits: 0
[12:24:10.881] <TB0> INFO: Event errors: 0
[12:24:10.881] <TB0> INFO: start marker: 0
[12:24:10.881] <TB0> INFO: stop marker: 0
[12:24:10.881] <TB0> INFO: overflow: 0
[12:24:10.881] <TB0> INFO: invalid 5bit words: 0
[12:24:10.881] <TB0> INFO: invalid XOR eye diagram: 0
[12:24:10.881] <TB0> INFO: frame (failed synchr.): 0
[12:24:10.881] <TB0> INFO: idle data (no TBM trl): 0
[12:24:10.881] <TB0> INFO: no data (only TBM hdr): 0
[12:24:10.881] <TB0> INFO: TBM errors: 0
[12:24:10.881] <TB0> INFO: flawed TBM headers: 0
[12:24:10.881] <TB0> INFO: flawed TBM trailers: 0
[12:24:10.881] <TB0> INFO: event ID mismatches: 0
[12:24:10.881] <TB0> INFO: ROC errors: 0
[12:24:10.881] <TB0> INFO: missing ROC header(s): 0
[12:24:10.881] <TB0> INFO: misplaced readback start: 0
[12:24:10.881] <TB0> INFO: Pixel decoding errors: 0
[12:24:10.881] <TB0> INFO: pixel data incomplete: 0
[12:24:10.881] <TB0> INFO: pixel address: 0
[12:24:10.881] <TB0> INFO: pulse height fill bit: 0
[12:24:10.881] <TB0> INFO: buffer corruption: 0
[12:24:10.972] <TB0> INFO: ######################################################################
[12:24:10.972] <TB0> INFO: PixTestTrim::doTest()
[12:24:10.972] <TB0> INFO: ######################################################################
[12:24:10.973] <TB0> INFO: ----------------------------------------------------------------------
[12:24:10.973] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[12:24:10.973] <TB0> INFO: ----------------------------------------------------------------------
[12:24:11.055] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[12:24:11.055] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:24:11.064] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[12:24:11.064] <TB0> INFO: run 1 of 1
[12:24:11.353] <TB0> INFO: Expecting 6281600 events.
[12:24:51.184] <TB0> INFO: 1073180 events read in total (39272ms).
[12:25:30.807] <TB0> INFO: 2140770 events read in total (78895ms).
[12:26:10.031] <TB0> INFO: 3202140 events read in total (118119ms).
[12:26:49.486] <TB0> INFO: 4270060 events read in total (157574ms).
[12:27:29.246] <TB0> INFO: 5341980 events read in total (197334ms).
[12:28:04.873] <TB0> INFO: 6281600 events read in total (232961ms).
[12:28:04.908] <TB0> INFO: Test took 233844ms.
[12:28:25.363] <TB0> INFO: ROC 0 VthrComp = 107
[12:28:25.363] <TB0> INFO: ROC 1 VthrComp = 85
[12:28:25.364] <TB0> INFO: ROC 2 VthrComp = 84
[12:28:25.364] <TB0> INFO: ROC 3 VthrComp = 94
[12:28:25.364] <TB0> INFO: ROC 4 VthrComp = 86
[12:28:25.364] <TB0> INFO: ROC 5 VthrComp = 99
[12:28:25.364] <TB0> INFO: ROC 6 VthrComp = 104
[12:28:25.364] <TB0> INFO: ROC 7 VthrComp = 87
[12:28:25.364] <TB0> INFO: ROC 8 VthrComp = 84
[12:28:25.364] <TB0> INFO: ROC 9 VthrComp = 93
[12:28:25.364] <TB0> INFO: ROC 10 VthrComp = 84
[12:28:25.365] <TB0> INFO: ROC 11 VthrComp = 92
[12:28:25.365] <TB0> INFO: ROC 12 VthrComp = 95
[12:28:25.365] <TB0> INFO: ROC 13 VthrComp = 92
[12:28:25.365] <TB0> INFO: ROC 14 VthrComp = 98
[12:28:25.365] <TB0> INFO: ROC 15 VthrComp = 98
[12:28:25.365] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[12:28:25.365] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:28:25.375] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[12:28:25.375] <TB0> INFO: run 1 of 1
[12:28:25.644] <TB0> INFO: Expecting 6281600 events.
[12:29:01.316] <TB0> INFO: 756580 events read in total (35116ms).
[12:29:36.716] <TB0> INFO: 1511340 events read in total (70516ms).
[12:30:11.844] <TB0> INFO: 2266510 events read in total (105644ms).
[12:30:47.279] <TB0> INFO: 3021920 events read in total (141079ms).
[12:31:23.136] <TB0> INFO: 3771840 events read in total (176936ms).
[12:31:58.064] <TB0> INFO: 4518690 events read in total (211864ms).
[12:32:33.115] <TB0> INFO: 5263520 events read in total (246915ms).
[12:33:08.300] <TB0> INFO: 6008600 events read in total (282100ms).
[12:33:22.297] <TB0> INFO: 6281600 events read in total (296097ms).
[12:33:22.357] <TB0> INFO: Test took 296982ms.
[12:33:48.044] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 64.5501 for pixel 23/25 mean/min/max = 49.7394/34.8331/64.6456
[12:33:48.044] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 60.1736 for pixel 4/5 mean/min/max = 46.1908/32.15/60.2316
[12:33:48.044] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 56.4294 for pixel 32/69 mean/min/max = 44.2978/31.8969/56.6988
[12:33:48.044] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 58.9264 for pixel 18/30 mean/min/max = 45.4656/32.0009/58.9303
[12:33:48.045] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 56.6666 for pixel 51/79 mean/min/max = 44.3463/31.8274/56.8652
[12:33:48.045] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 60.7561 for pixel 17/2 mean/min/max = 46.2556/31.7449/60.7663
[12:33:48.045] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 63.2487 for pixel 21/55 mean/min/max = 48.1826/33.1154/63.2499
[12:33:48.046] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 57.5711 for pixel 20/17 mean/min/max = 44.9657/32.1865/57.7449
[12:33:48.046] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 55.66 for pixel 24/79 mean/min/max = 43.7871/31.7671/55.8072
[12:33:48.046] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 60.4731 for pixel 0/0 mean/min/max = 46.2292/31.8939/60.5645
[12:33:48.046] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 60.1326 for pixel 9/10 mean/min/max = 46.1241/32.0979/60.1503
[12:33:48.047] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 59.1905 for pixel 47/75 mean/min/max = 46.0102/32.7471/59.2733
[12:33:48.047] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 59.1131 for pixel 26/32 mean/min/max = 45.4583/31.5792/59.3374
[12:33:48.047] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 62.3193 for pixel 12/0 mean/min/max = 47.0407/31.6253/62.456
[12:33:48.048] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 61.1413 for pixel 38/61 mean/min/max = 46.4245/31.648/61.2009
[12:33:48.048] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 57.4095 for pixel 4/1 mean/min/max = 44.5796/31.2604/57.8988
[12:33:48.048] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:33:48.136] <TB0> INFO: Expecting 514560 events.
[12:34:01.133] <TB0> INFO: 514560 events read in total (12441ms).
[12:34:01.138] <TB0> INFO: Expecting 514560 events.
[12:34:13.907] <TB0> INFO: 514560 events read in total (12347ms).
[12:34:13.913] <TB0> INFO: Expecting 514560 events.
[12:34:26.756] <TB0> INFO: 514560 events read in total (12417ms).
[12:34:26.766] <TB0> INFO: Expecting 514560 events.
[12:34:39.657] <TB0> INFO: 514560 events read in total (12476ms).
[12:34:39.668] <TB0> INFO: Expecting 514560 events.
[12:34:52.442] <TB0> INFO: 514560 events read in total (12364ms).
[12:34:52.453] <TB0> INFO: Expecting 514560 events.
[12:35:05.364] <TB0> INFO: 514560 events read in total (12494ms).
[12:35:05.379] <TB0> INFO: Expecting 514560 events.
[12:35:18.398] <TB0> INFO: 514560 events read in total (12607ms).
[12:35:18.415] <TB0> INFO: Expecting 514560 events.
[12:35:31.350] <TB0> INFO: 514560 events read in total (12528ms).
[12:35:31.370] <TB0> INFO: Expecting 514560 events.
[12:35:44.285] <TB0> INFO: 514560 events read in total (12511ms).
[12:35:44.305] <TB0> INFO: Expecting 514560 events.
[12:35:57.226] <TB0> INFO: 514560 events read in total (12519ms).
[12:35:57.249] <TB0> INFO: Expecting 514560 events.
[12:36:10.202] <TB0> INFO: 514560 events read in total (12551ms).
[12:36:10.225] <TB0> INFO: Expecting 514560 events.
[12:36:23.061] <TB0> INFO: 514560 events read in total (12434ms).
[12:36:23.089] <TB0> INFO: Expecting 514560 events.
[12:36:36.057] <TB0> INFO: 514560 events read in total (12567ms).
[12:36:36.087] <TB0> INFO: Expecting 514560 events.
[12:36:48.992] <TB0> INFO: 514560 events read in total (12503ms).
[12:36:49.022] <TB0> INFO: Expecting 514560 events.
[12:37:01.799] <TB0> INFO: 514560 events read in total (12375ms).
[12:37:01.834] <TB0> INFO: Expecting 514560 events.
[12:37:14.731] <TB0> INFO: 514560 events read in total (12495ms).
[12:37:14.765] <TB0> INFO: Test took 206717ms.
[12:37:15.865] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:37:15.874] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[12:37:15.874] <TB0> INFO: run 1 of 1
[12:37:16.164] <TB0> INFO: Expecting 6281600 events.
[12:37:51.843] <TB0> INFO: 741110 events read in total (35123ms).
[12:38:26.987] <TB0> INFO: 1480770 events read in total (70268ms).
[12:39:01.903] <TB0> INFO: 2221010 events read in total (105183ms).
[12:39:37.318] <TB0> INFO: 2961540 events read in total (140598ms).
[12:40:11.937] <TB0> INFO: 3696860 events read in total (175217ms).
[12:40:47.078] <TB0> INFO: 4428250 events read in total (210358ms).
[12:41:21.959] <TB0> INFO: 5157850 events read in total (245239ms).
[12:41:57.097] <TB0> INFO: 5886740 events read in total (280377ms).
[12:42:17.240] <TB0> INFO: 6281600 events read in total (300520ms).
[12:42:17.303] <TB0> INFO: Test took 301429ms.
[12:42:42.318] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.075059 .. 255.000000
[12:42:42.400] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[12:42:42.409] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[12:42:42.409] <TB0> INFO: run 1 of 1
[12:42:42.695] <TB0> INFO: Expecting 10649600 events.
[12:43:17.840] <TB0> INFO: 712330 events read in total (34589ms).
[12:43:52.181] <TB0> INFO: 1424830 events read in total (68930ms).
[12:44:26.809] <TB0> INFO: 2137350 events read in total (103558ms).
[12:45:01.498] <TB0> INFO: 2850020 events read in total (138247ms).
[12:45:35.831] <TB0> INFO: 3562470 events read in total (172580ms).
[12:46:10.688] <TB0> INFO: 4275060 events read in total (207437ms).
[12:46:45.857] <TB0> INFO: 4987770 events read in total (242606ms).
[12:47:20.540] <TB0> INFO: 5699960 events read in total (277289ms).
[12:47:55.722] <TB0> INFO: 6412040 events read in total (312471ms).
[12:48:31.326] <TB0> INFO: 7123490 events read in total (348075ms).
[12:49:06.045] <TB0> INFO: 7834870 events read in total (382794ms).
[12:49:40.408] <TB0> INFO: 8545520 events read in total (417157ms).
[12:50:15.197] <TB0> INFO: 9256170 events read in total (451946ms).
[12:50:49.716] <TB0> INFO: 9966860 events read in total (486465ms).
[12:51:23.918] <TB0> INFO: 10649600 events read in total (520667ms).
[12:51:24.016] <TB0> INFO: Test took 521607ms.
[12:51:53.144] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 16.148270 .. 45.501580
[12:51:53.249] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 6 .. 55 (-1/-1) hits flags = 528 (plus default)
[12:51:53.257] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[12:51:53.257] <TB0> INFO: run 1 of 1
[12:51:53.550] <TB0> INFO: Expecting 2080000 events.
[12:52:32.470] <TB0> INFO: 931800 events read in total (38362ms).
[12:53:11.075] <TB0> INFO: 1861320 events read in total (76967ms).
[12:53:20.357] <TB0> INFO: 2080000 events read in total (86250ms).
[12:53:20.380] <TB0> INFO: Test took 87123ms.
[12:53:34.867] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 20.744015 .. 42.111774
[12:53:34.972] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 10 .. 52 (-1/-1) hits flags = 528 (plus default)
[12:53:34.982] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[12:53:34.982] <TB0> INFO: run 1 of 1
[12:53:35.244] <TB0> INFO: Expecting 1788800 events.
[12:54:14.245] <TB0> INFO: 935490 events read in total (38445ms).
[12:54:49.784] <TB0> INFO: 1788800 events read in total (73984ms).
[12:54:49.803] <TB0> INFO: Test took 74822ms.
[12:55:02.446] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 2.798250 .. 42.100905
[12:55:02.539] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 52 (-1/-1) hits flags = 528 (plus default)
[12:55:02.552] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[12:55:02.552] <TB0> INFO: run 1 of 1
[12:55:02.833] <TB0> INFO: Expecting 2121600 events.
[12:55:42.402] <TB0> INFO: 981090 events read in total (39013ms).
[12:56:21.761] <TB0> INFO: 1961480 events read in total (78372ms).
[12:56:28.468] <TB0> INFO: 2121600 events read in total (85080ms).
[12:56:28.482] <TB0> INFO: Test took 85930ms.
[12:56:42.033] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[12:56:42.033] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[12:56:42.042] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[12:56:42.042] <TB0> INFO: run 1 of 1
[12:56:42.328] <TB0> INFO: Expecting 1705600 events.
[12:57:20.507] <TB0> INFO: 878790 events read in total (37622ms).
[12:57:56.392] <TB0> INFO: 1705600 events read in total (73508ms).
[12:57:56.415] <TB0> INFO: Test took 74374ms.
[12:58:10.313] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C0.dat
[12:58:10.313] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C1.dat
[12:58:10.313] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C2.dat
[12:58:10.313] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C3.dat
[12:58:10.313] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C4.dat
[12:58:10.313] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C5.dat
[12:58:10.313] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C6.dat
[12:58:10.313] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C7.dat
[12:58:10.314] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C8.dat
[12:58:10.314] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C9.dat
[12:58:10.314] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C10.dat
[12:58:10.314] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C11.dat
[12:58:10.314] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C12.dat
[12:58:10.314] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C13.dat
[12:58:10.314] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C14.dat
[12:58:10.314] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C15.dat
[12:58:10.314] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C0.dat
[12:58:10.320] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C1.dat
[12:58:10.326] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C2.dat
[12:58:10.332] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C3.dat
[12:58:10.338] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C4.dat
[12:58:10.344] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C5.dat
[12:58:10.350] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C6.dat
[12:58:10.356] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C7.dat
[12:58:10.362] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C8.dat
[12:58:10.369] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C9.dat
[12:58:10.375] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C10.dat
[12:58:10.381] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C11.dat
[12:58:10.387] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C12.dat
[12:58:10.393] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C13.dat
[12:58:10.399] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C14.dat
[12:58:10.405] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//trimParameters35_C15.dat
[12:58:10.411] <TB0> INFO: PixTestTrim::trimTest() done
[12:58:10.411] <TB0> INFO: vtrim: 119 96 91 109 80 100 112 101 86 87 103 93 100 103 101 105
[12:58:10.411] <TB0> INFO: vthrcomp: 107 85 84 94 86 99 104 87 84 93 84 92 95 92 98 98
[12:58:10.411] <TB0> INFO: vcal mean: 34.98 34.98 34.95 34.97 35.00 34.99 35.00 34.96 34.98 35.00 34.97 35.01 34.97 35.00 34.95 34.94
[12:58:10.411] <TB0> INFO: vcal RMS: 0.92 0.87 0.82 0.91 0.78 0.90 0.91 1.11 0.77 0.87 1.12 0.86 0.90 1.04 0.92 0.83
[12:58:10.411] <TB0> INFO: bits mean: 8.47 9.43 9.85 9.74 8.90 9.40 9.13 9.89 9.72 9.04 9.65 9.32 9.65 9.33 9.55 9.54
[12:58:10.411] <TB0> INFO: bits RMS: 2.42 2.64 2.62 2.63 3.09 2.74 2.51 2.55 2.70 2.84 2.57 2.63 2.69 2.71 2.60 2.83
[12:58:10.417] <TB0> INFO: ----------------------------------------------------------------------
[12:58:10.417] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[12:58:10.417] <TB0> INFO: ----------------------------------------------------------------------
[12:58:10.420] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[12:58:10.428] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[12:58:10.429] <TB0> INFO: run 1 of 1
[12:58:10.688] <TB0> INFO: Expecting 4160000 events.
[12:58:53.946] <TB0> INFO: 937085 events read in total (42702ms).
[12:59:36.181] <TB0> INFO: 1868580 events read in total (84938ms).
[13:00:18.199] <TB0> INFO: 2791245 events read in total (126956ms).
[13:01:00.025] <TB0> INFO: 3711745 events read in total (168781ms).
[13:01:20.836] <TB0> INFO: 4160000 events read in total (189592ms).
[13:01:20.891] <TB0> INFO: Test took 190463ms.
[13:01:52.766] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 176 (-1/-1) hits flags = 528 (plus default)
[13:01:52.775] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[13:01:52.775] <TB0> INFO: run 1 of 1
[13:01:53.039] <TB0> INFO: Expecting 3681600 events.
[13:02:36.198] <TB0> INFO: 949575 events read in total (42603ms).
[13:03:18.404] <TB0> INFO: 1891945 events read in total (84809ms).
[13:04:00.801] <TB0> INFO: 2824715 events read in total (127206ms).
[13:04:39.768] <TB0> INFO: 3681600 events read in total (166173ms).
[13:04:39.825] <TB0> INFO: Test took 167050ms.
[13:05:05.600] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 161 (-1/-1) hits flags = 528 (plus default)
[13:05:05.609] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[13:05:05.609] <TB0> INFO: run 1 of 1
[13:05:05.894] <TB0> INFO: Expecting 3369600 events.
[13:05:49.341] <TB0> INFO: 988055 events read in total (42892ms).
[13:06:32.496] <TB0> INFO: 1965085 events read in total (86046ms).
[13:07:15.442] <TB0> INFO: 2933215 events read in total (128992ms).
[13:07:35.050] <TB0> INFO: 3369600 events read in total (148600ms).
[13:07:35.090] <TB0> INFO: Test took 149481ms.
[13:08:00.420] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[13:08:00.428] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[13:08:00.428] <TB0> INFO: run 1 of 1
[13:08:00.691] <TB0> INFO: Expecting 3390400 events.
[13:08:44.341] <TB0> INFO: 984435 events read in total (43094ms).
[13:09:27.218] <TB0> INFO: 1958155 events read in total (85971ms).
[13:10:09.907] <TB0> INFO: 2922640 events read in total (128660ms).
[13:10:30.982] <TB0> INFO: 3390400 events read in total (149735ms).
[13:10:31.019] <TB0> INFO: Test took 150590ms.
[13:10:56.830] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[13:10:56.839] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[13:10:56.839] <TB0> INFO: run 1 of 1
[13:10:57.104] <TB0> INFO: Expecting 3390400 events.
[13:11:40.843] <TB0> INFO: 983820 events read in total (43183ms).
[13:12:23.807] <TB0> INFO: 1956860 events read in total (86147ms).
[13:13:06.724] <TB0> INFO: 2920895 events read in total (129064ms).
[13:13:28.561] <TB0> INFO: 3390400 events read in total (150901ms).
[13:13:28.594] <TB0> INFO: Test took 151756ms.
[13:13:54.572] <TB0> INFO: PixTestTrim::trimBitTest() done
[13:13:54.573] <TB0> INFO: PixTestTrim::doTest() done, duration: 2983 seconds
[13:13:54.573] <TB0> INFO: Decoding statistics:
[13:13:54.573] <TB0> INFO: General information:
[13:13:54.573] <TB0> INFO: 16bit words read: 0
[13:13:54.573] <TB0> INFO: valid events total: 0
[13:13:54.573] <TB0> INFO: empty events: 0
[13:13:54.573] <TB0> INFO: valid events with pixels: 0
[13:13:54.573] <TB0> INFO: valid pixel hits: 0
[13:13:54.573] <TB0> INFO: Event errors: 0
[13:13:54.573] <TB0> INFO: start marker: 0
[13:13:54.573] <TB0> INFO: stop marker: 0
[13:13:54.573] <TB0> INFO: overflow: 0
[13:13:54.573] <TB0> INFO: invalid 5bit words: 0
[13:13:54.573] <TB0> INFO: invalid XOR eye diagram: 0
[13:13:54.573] <TB0> INFO: frame (failed synchr.): 0
[13:13:54.573] <TB0> INFO: idle data (no TBM trl): 0
[13:13:54.573] <TB0> INFO: no data (only TBM hdr): 0
[13:13:54.573] <TB0> INFO: TBM errors: 0
[13:13:54.573] <TB0> INFO: flawed TBM headers: 0
[13:13:54.573] <TB0> INFO: flawed TBM trailers: 0
[13:13:54.573] <TB0> INFO: event ID mismatches: 0
[13:13:54.573] <TB0> INFO: ROC errors: 0
[13:13:54.573] <TB0> INFO: missing ROC header(s): 0
[13:13:54.573] <TB0> INFO: misplaced readback start: 0
[13:13:54.573] <TB0> INFO: Pixel decoding errors: 0
[13:13:54.573] <TB0> INFO: pixel data incomplete: 0
[13:13:54.573] <TB0> INFO: pixel address: 0
[13:13:54.573] <TB0> INFO: pulse height fill bit: 0
[13:13:54.573] <TB0> INFO: buffer corruption: 0
[13:13:55.317] <TB0> INFO: ######################################################################
[13:13:55.317] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:13:55.317] <TB0> INFO: ######################################################################
[13:13:55.618] <TB0> INFO: Expecting 41600 events.
[13:13:59.398] <TB0> INFO: 41600 events read in total (3224ms).
[13:13:59.399] <TB0> INFO: Test took 4080ms.
[13:13:59.897] <TB0> INFO: Expecting 41600 events.
[13:14:03.743] <TB0> INFO: 41600 events read in total (3290ms).
[13:14:03.743] <TB0> INFO: Test took 4139ms.
[13:14:04.036] <TB0> INFO: Expecting 41600 events.
[13:14:07.910] <TB0> INFO: 41600 events read in total (3318ms).
[13:14:07.911] <TB0> INFO: Test took 4146ms.
[13:14:08.167] <TB0> INFO: Expecting 2560 events.
[13:14:09.020] <TB0> INFO: 2560 events read in total (297ms).
[13:14:09.020] <TB0> INFO: Test took 1096ms.
[13:14:09.325] <TB0> INFO: Expecting 2560 events.
[13:14:10.178] <TB0> INFO: 2560 events read in total (297ms).
[13:14:10.178] <TB0> INFO: Test took 1158ms.
[13:14:10.483] <TB0> INFO: Expecting 2560 events.
[13:14:11.336] <TB0> INFO: 2560 events read in total (297ms).
[13:14:11.336] <TB0> INFO: Test took 1157ms.
[13:14:11.641] <TB0> INFO: Expecting 2560 events.
[13:14:12.500] <TB0> INFO: 2560 events read in total (296ms).
[13:14:12.500] <TB0> INFO: Test took 1164ms.
[13:14:12.805] <TB0> INFO: Expecting 2560 events.
[13:14:13.658] <TB0> INFO: 2560 events read in total (297ms).
[13:14:13.658] <TB0> INFO: Test took 1158ms.
[13:14:13.963] <TB0> INFO: Expecting 2560 events.
[13:14:14.817] <TB0> INFO: 2560 events read in total (298ms).
[13:14:14.817] <TB0> INFO: Test took 1159ms.
[13:14:15.122] <TB0> INFO: Expecting 2560 events.
[13:14:15.974] <TB0> INFO: 2560 events read in total (296ms).
[13:14:15.974] <TB0> INFO: Test took 1157ms.
[13:14:16.279] <TB0> INFO: Expecting 2560 events.
[13:14:17.132] <TB0> INFO: 2560 events read in total (297ms).
[13:14:17.132] <TB0> INFO: Test took 1158ms.
[13:14:17.437] <TB0> INFO: Expecting 2560 events.
[13:14:18.289] <TB0> INFO: 2560 events read in total (296ms).
[13:14:18.290] <TB0> INFO: Test took 1158ms.
[13:14:18.595] <TB0> INFO: Expecting 2560 events.
[13:14:19.449] <TB0> INFO: 2560 events read in total (296ms).
[13:14:19.449] <TB0> INFO: Test took 1159ms.
[13:14:19.754] <TB0> INFO: Expecting 2560 events.
[13:14:20.609] <TB0> INFO: 2560 events read in total (299ms).
[13:14:20.609] <TB0> INFO: Test took 1159ms.
[13:14:20.920] <TB0> INFO: Expecting 2560 events.
[13:14:21.773] <TB0> INFO: 2560 events read in total (297ms).
[13:14:21.773] <TB0> INFO: Test took 1164ms.
[13:14:22.079] <TB0> INFO: Expecting 2560 events.
[13:14:22.931] <TB0> INFO: 2560 events read in total (296ms).
[13:14:22.931] <TB0> INFO: Test took 1157ms.
[13:14:23.236] <TB0> INFO: Expecting 2560 events.
[13:14:24.089] <TB0> INFO: 2560 events read in total (297ms).
[13:14:24.090] <TB0> INFO: Test took 1158ms.
[13:14:24.395] <TB0> INFO: Expecting 2560 events.
[13:14:25.250] <TB0> INFO: 2560 events read in total (299ms).
[13:14:25.250] <TB0> INFO: Test took 1160ms.
[13:14:25.555] <TB0> INFO: Expecting 2560 events.
[13:14:26.407] <TB0> INFO: 2560 events read in total (296ms).
[13:14:26.407] <TB0> INFO: Test took 1157ms.
[13:14:26.410] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:14:26.713] <TB0> INFO: Expecting 655360 events.
[13:14:43.487] <TB0> INFO: 655360 events read in total (16217ms).
[13:14:43.496] <TB0> INFO: Expecting 655360 events.
[13:15:00.151] <TB0> INFO: 655360 events read in total (16253ms).
[13:15:00.163] <TB0> INFO: Expecting 655360 events.
[13:15:16.936] <TB0> INFO: 655360 events read in total (16371ms).
[13:15:16.955] <TB0> INFO: Expecting 655360 events.
[13:15:33.651] <TB0> INFO: 655360 events read in total (16295ms).
[13:15:33.670] <TB0> INFO: Expecting 655360 events.
[13:15:50.421] <TB0> INFO: 655360 events read in total (16350ms).
[13:15:50.443] <TB0> INFO: Expecting 655360 events.
[13:16:07.118] <TB0> INFO: 655360 events read in total (16273ms).
[13:16:07.144] <TB0> INFO: Expecting 655360 events.
[13:16:23.893] <TB0> INFO: 655360 events read in total (16347ms).
[13:16:23.923] <TB0> INFO: Expecting 655360 events.
[13:16:40.689] <TB0> INFO: 655360 events read in total (16364ms).
[13:16:40.721] <TB0> INFO: Expecting 655360 events.
[13:16:57.513] <TB0> INFO: 655360 events read in total (16391ms).
[13:16:57.549] <TB0> INFO: Expecting 655360 events.
[13:17:14.286] <TB0> INFO: 655360 events read in total (16336ms).
[13:17:14.326] <TB0> INFO: Expecting 655360 events.
[13:17:30.947] <TB0> INFO: 655360 events read in total (16219ms).
[13:17:30.994] <TB0> INFO: Expecting 655360 events.
[13:17:47.577] <TB0> INFO: 655360 events read in total (16182ms).
[13:17:47.630] <TB0> INFO: Expecting 655360 events.
[13:18:04.356] <TB0> INFO: 655360 events read in total (16325ms).
[13:18:04.429] <TB0> INFO: Expecting 655360 events.
[13:18:21.031] <TB0> INFO: 655360 events read in total (16201ms).
[13:18:21.085] <TB0> INFO: Expecting 655360 events.
[13:18:37.612] <TB0> INFO: 655360 events read in total (16125ms).
[13:18:37.677] <TB0> INFO: Expecting 655360 events.
[13:18:54.492] <TB0> INFO: 655360 events read in total (16413ms).
[13:18:54.554] <TB0> INFO: Test took 268144ms.
[13:18:54.636] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:18:54.867] <TB0> INFO: Expecting 655360 events.
[13:19:11.700] <TB0> INFO: 655360 events read in total (16277ms).
[13:19:11.709] <TB0> INFO: Expecting 655360 events.
[13:19:28.593] <TB0> INFO: 655360 events read in total (16483ms).
[13:19:28.606] <TB0> INFO: Expecting 655360 events.
[13:19:45.297] <TB0> INFO: 655360 events read in total (16289ms).
[13:19:45.313] <TB0> INFO: Expecting 655360 events.
[13:20:02.128] <TB0> INFO: 655360 events read in total (16413ms).
[13:20:02.148] <TB0> INFO: Expecting 655360 events.
[13:20:18.977] <TB0> INFO: 655360 events read in total (16427ms).
[13:20:19.000] <TB0> INFO: Expecting 655360 events.
[13:20:35.668] <TB0> INFO: 655360 events read in total (16266ms).
[13:20:35.695] <TB0> INFO: Expecting 655360 events.
[13:20:52.360] <TB0> INFO: 655360 events read in total (16264ms).
[13:20:52.391] <TB0> INFO: Expecting 655360 events.
[13:21:09.035] <TB0> INFO: 655360 events read in total (16242ms).
[13:21:09.067] <TB0> INFO: Expecting 655360 events.
[13:21:25.802] <TB0> INFO: 655360 events read in total (16333ms).
[13:21:25.838] <TB0> INFO: Expecting 655360 events.
[13:21:42.607] <TB0> INFO: 655360 events read in total (16367ms).
[13:21:42.650] <TB0> INFO: Expecting 655360 events.
[13:21:59.372] <TB0> INFO: 655360 events read in total (16318ms).
[13:21:59.416] <TB0> INFO: Expecting 655360 events.
[13:22:16.134] <TB0> INFO: 655360 events read in total (16316ms).
[13:22:16.184] <TB0> INFO: Expecting 655360 events.
[13:22:33.082] <TB0> INFO: 655360 events read in total (16496ms).
[13:22:33.140] <TB0> INFO: Expecting 655360 events.
[13:22:49.942] <TB0> INFO: 655360 events read in total (16400ms).
[13:22:49.996] <TB0> INFO: Expecting 655360 events.
[13:23:06.525] <TB0> INFO: 655360 events read in total (16128ms).
[13:23:06.588] <TB0> INFO: Expecting 655360 events.
[13:23:23.323] <TB0> INFO: 655360 events read in total (16334ms).
[13:23:23.388] <TB0> INFO: Test took 268752ms.
[13:23:23.571] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.578] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.585] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.593] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.602] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.610] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[13:23:23.617] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.624] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.631] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.638] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.645] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.651] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.658] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.665] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.672] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.679] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.686] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:23.722] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:23:23.726] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:23:23.727] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:23:23.727] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:23:23.727] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:23:23.727] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:23:23.727] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:23:23.727] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:23:23.727] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:23:23.727] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:23:23.727] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:23:23.727] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:23:23.727] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:23:23.727] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:23:23.728] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:23:23.728] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:23:24.002] <TB0> INFO: Expecting 41600 events.
[13:23:27.478] <TB0> INFO: 41600 events read in total (2920ms).
[13:23:27.479] <TB0> INFO: Test took 3749ms.
[13:23:27.916] <TB0> INFO: Expecting 41600 events.
[13:23:31.382] <TB0> INFO: 41600 events read in total (2910ms).
[13:23:31.382] <TB0> INFO: Test took 3725ms.
[13:23:31.830] <TB0> INFO: Expecting 41600 events.
[13:23:35.332] <TB0> INFO: 41600 events read in total (2946ms).
[13:23:35.333] <TB0> INFO: Test took 3774ms.
[13:23:35.514] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:35.603] <TB0> INFO: Expecting 2560 events.
[13:23:36.459] <TB0> INFO: 2560 events read in total (299ms).
[13:23:36.460] <TB0> INFO: Test took 946ms.
[13:23:36.462] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:36.765] <TB0> INFO: Expecting 2560 events.
[13:23:37.622] <TB0> INFO: 2560 events read in total (300ms).
[13:23:37.623] <TB0> INFO: Test took 1161ms.
[13:23:37.625] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:37.929] <TB0> INFO: Expecting 2560 events.
[13:23:38.782] <TB0> INFO: 2560 events read in total (297ms).
[13:23:38.782] <TB0> INFO: Test took 1157ms.
[13:23:38.784] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:39.087] <TB0> INFO: Expecting 2560 events.
[13:23:39.943] <TB0> INFO: 2560 events read in total (299ms).
[13:23:39.944] <TB0> INFO: Test took 1160ms.
[13:23:39.946] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:40.249] <TB0> INFO: Expecting 2560 events.
[13:23:41.106] <TB0> INFO: 2560 events read in total (301ms).
[13:23:41.106] <TB0> INFO: Test took 1160ms.
[13:23:41.108] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:41.412] <TB0> INFO: Expecting 2560 events.
[13:23:42.268] <TB0> INFO: 2560 events read in total (300ms).
[13:23:42.268] <TB0> INFO: Test took 1160ms.
[13:23:42.272] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:42.573] <TB0> INFO: Expecting 2560 events.
[13:23:43.433] <TB0> INFO: 2560 events read in total (304ms).
[13:23:43.434] <TB0> INFO: Test took 1162ms.
[13:23:43.436] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:43.739] <TB0> INFO: Expecting 2560 events.
[13:23:44.597] <TB0> INFO: 2560 events read in total (302ms).
[13:23:44.597] <TB0> INFO: Test took 1161ms.
[13:23:44.599] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:44.903] <TB0> INFO: Expecting 2560 events.
[13:23:45.758] <TB0> INFO: 2560 events read in total (299ms).
[13:23:45.758] <TB0> INFO: Test took 1159ms.
[13:23:45.760] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:46.064] <TB0> INFO: Expecting 2560 events.
[13:23:46.918] <TB0> INFO: 2560 events read in total (297ms).
[13:23:46.919] <TB0> INFO: Test took 1159ms.
[13:23:46.921] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:47.224] <TB0> INFO: Expecting 2560 events.
[13:23:48.082] <TB0> INFO: 2560 events read in total (301ms).
[13:23:48.082] <TB0> INFO: Test took 1161ms.
[13:23:48.084] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:48.388] <TB0> INFO: Expecting 2560 events.
[13:23:49.246] <TB0> INFO: 2560 events read in total (302ms).
[13:23:49.246] <TB0> INFO: Test took 1162ms.
[13:23:49.248] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:49.552] <TB0> INFO: Expecting 2560 events.
[13:23:50.406] <TB0> INFO: 2560 events read in total (298ms).
[13:23:50.406] <TB0> INFO: Test took 1158ms.
[13:23:50.408] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:50.712] <TB0> INFO: Expecting 2560 events.
[13:23:51.572] <TB0> INFO: 2560 events read in total (303ms).
[13:23:51.572] <TB0> INFO: Test took 1164ms.
[13:23:51.575] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:51.878] <TB0> INFO: Expecting 2560 events.
[13:23:52.736] <TB0> INFO: 2560 events read in total (301ms).
[13:23:52.737] <TB0> INFO: Test took 1162ms.
[13:23:52.739] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:53.043] <TB0> INFO: Expecting 2560 events.
[13:23:53.895] <TB0> INFO: 2560 events read in total (296ms).
[13:23:53.895] <TB0> INFO: Test took 1156ms.
[13:23:53.897] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:54.201] <TB0> INFO: Expecting 2560 events.
[13:23:55.060] <TB0> INFO: 2560 events read in total (303ms).
[13:23:55.061] <TB0> INFO: Test took 1164ms.
[13:23:55.063] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:55.366] <TB0> INFO: Expecting 2560 events.
[13:23:56.225] <TB0> INFO: 2560 events read in total (302ms).
[13:23:56.226] <TB0> INFO: Test took 1163ms.
[13:23:56.227] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:56.531] <TB0> INFO: Expecting 2560 events.
[13:23:57.385] <TB0> INFO: 2560 events read in total (298ms).
[13:23:57.385] <TB0> INFO: Test took 1158ms.
[13:23:57.387] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:57.691] <TB0> INFO: Expecting 2560 events.
[13:23:58.548] <TB0> INFO: 2560 events read in total (301ms).
[13:23:58.548] <TB0> INFO: Test took 1162ms.
[13:23:58.550] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:23:58.854] <TB0> INFO: Expecting 2560 events.
[13:23:59.707] <TB0> INFO: 2560 events read in total (297ms).
[13:23:59.708] <TB0> INFO: Test took 1158ms.
[13:23:59.710] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:00.012] <TB0> INFO: Expecting 2560 events.
[13:24:00.866] <TB0> INFO: 2560 events read in total (297ms).
[13:24:00.866] <TB0> INFO: Test took 1157ms.
[13:24:00.868] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:01.172] <TB0> INFO: Expecting 2560 events.
[13:24:02.028] <TB0> INFO: 2560 events read in total (300ms).
[13:24:02.028] <TB0> INFO: Test took 1160ms.
[13:24:02.030] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:02.333] <TB0> INFO: Expecting 2560 events.
[13:24:03.187] <TB0> INFO: 2560 events read in total (297ms).
[13:24:03.187] <TB0> INFO: Test took 1158ms.
[13:24:03.190] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:03.493] <TB0> INFO: Expecting 2560 events.
[13:24:04.351] <TB0> INFO: 2560 events read in total (302ms).
[13:24:04.352] <TB0> INFO: Test took 1162ms.
[13:24:04.353] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:04.657] <TB0> INFO: Expecting 2560 events.
[13:24:05.515] <TB0> INFO: 2560 events read in total (302ms).
[13:24:05.515] <TB0> INFO: Test took 1162ms.
[13:24:05.517] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:05.821] <TB0> INFO: Expecting 2560 events.
[13:24:06.675] <TB0> INFO: 2560 events read in total (298ms).
[13:24:06.675] <TB0> INFO: Test took 1158ms.
[13:24:06.678] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:06.980] <TB0> INFO: Expecting 2560 events.
[13:24:07.839] <TB0> INFO: 2560 events read in total (302ms).
[13:24:07.839] <TB0> INFO: Test took 1162ms.
[13:24:07.842] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:08.145] <TB0> INFO: Expecting 2560 events.
[13:24:08.999] <TB0> INFO: 2560 events read in total (298ms).
[13:24:09.000] <TB0> INFO: Test took 1158ms.
[13:24:09.002] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:09.306] <TB0> INFO: Expecting 2560 events.
[13:24:10.165] <TB0> INFO: 2560 events read in total (303ms).
[13:24:10.165] <TB0> INFO: Test took 1163ms.
[13:24:10.167] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:10.471] <TB0> INFO: Expecting 2560 events.
[13:24:11.324] <TB0> INFO: 2560 events read in total (297ms).
[13:24:11.324] <TB0> INFO: Test took 1158ms.
[13:24:11.326] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:11.630] <TB0> INFO: Expecting 2560 events.
[13:24:12.485] <TB0> INFO: 2560 events read in total (299ms).
[13:24:12.485] <TB0> INFO: Test took 1159ms.
[13:24:12.947] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 617 seconds
[13:24:12.947] <TB0> INFO: PH scale (per ROC): 69 71 91 92 80 85 80 86 89 75 81 78 82 80 80 84
[13:24:12.947] <TB0> INFO: PH offset (per ROC): 174 162 149 158 161 169 172 150 156 162 158 178 171 165 174 163
[13:24:12.955] <TB0> INFO: Decoding statistics:
[13:24:12.955] <TB0> INFO: General information:
[13:24:12.955] <TB0> INFO: 16bit words read: 91928
[13:24:12.955] <TB0> INFO: valid events total: 10240
[13:24:12.955] <TB0> INFO: empty events: 7738
[13:24:12.955] <TB0> INFO: valid events with pixels: 2502
[13:24:12.955] <TB0> INFO: valid pixel hits: 2502
[13:24:12.955] <TB0> INFO: Event errors: 0
[13:24:12.955] <TB0> INFO: start marker: 0
[13:24:12.955] <TB0> INFO: stop marker: 0
[13:24:12.955] <TB0> INFO: overflow: 0
[13:24:12.955] <TB0> INFO: invalid 5bit words: 0
[13:24:12.955] <TB0> INFO: invalid XOR eye diagram: 0
[13:24:12.955] <TB0> INFO: frame (failed synchr.): 0
[13:24:12.955] <TB0> INFO: idle data (no TBM trl): 0
[13:24:12.955] <TB0> INFO: no data (only TBM hdr): 0
[13:24:12.955] <TB0> INFO: TBM errors: 0
[13:24:12.955] <TB0> INFO: flawed TBM headers: 0
[13:24:12.955] <TB0> INFO: flawed TBM trailers: 0
[13:24:12.955] <TB0> INFO: event ID mismatches: 0
[13:24:12.955] <TB0> INFO: ROC errors: 0
[13:24:12.955] <TB0> INFO: missing ROC header(s): 0
[13:24:12.955] <TB0> INFO: misplaced readback start: 0
[13:24:12.955] <TB0> INFO: Pixel decoding errors: 0
[13:24:12.955] <TB0> INFO: pixel data incomplete: 0
[13:24:12.955] <TB0> INFO: pixel address: 0
[13:24:12.955] <TB0> INFO: pulse height fill bit: 0
[13:24:12.956] <TB0> INFO: buffer corruption: 0
[13:24:13.129] <TB0> INFO: ######################################################################
[13:24:13.129] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:24:13.129] <TB0> INFO: ######################################################################
[13:24:13.139] <TB0> INFO: scanning low vcal = 10
[13:24:13.402] <TB0> INFO: Expecting 41600 events.
[13:24:16.976] <TB0> INFO: 41600 events read in total (3018ms).
[13:24:16.977] <TB0> INFO: Test took 3838ms.
[13:24:16.979] <TB0> INFO: scanning low vcal = 20
[13:24:17.282] <TB0> INFO: Expecting 41600 events.
[13:24:20.848] <TB0> INFO: 41600 events read in total (3010ms).
[13:24:20.848] <TB0> INFO: Test took 3869ms.
[13:24:20.851] <TB0> INFO: scanning low vcal = 30
[13:24:21.153] <TB0> INFO: Expecting 41600 events.
[13:24:24.704] <TB0> INFO: 41600 events read in total (2995ms).
[13:24:24.704] <TB0> INFO: Test took 3853ms.
[13:24:24.706] <TB0> INFO: scanning low vcal = 40
[13:24:25.003] <TB0> INFO: Expecting 41600 events.
[13:24:29.114] <TB0> INFO: 41600 events read in total (3555ms).
[13:24:29.115] <TB0> INFO: Test took 4409ms.
[13:24:29.117] <TB0> INFO: scanning low vcal = 50
[13:24:29.399] <TB0> INFO: Expecting 41600 events.
[13:24:33.601] <TB0> INFO: 41600 events read in total (3646ms).
[13:24:33.601] <TB0> INFO: Test took 4484ms.
[13:24:33.604] <TB0> INFO: scanning low vcal = 60
[13:24:33.861] <TB0> INFO: Expecting 41600 events.
[13:24:38.061] <TB0> INFO: 41600 events read in total (3644ms).
[13:24:38.061] <TB0> INFO: Test took 4457ms.
[13:24:38.064] <TB0> INFO: scanning low vcal = 70
[13:24:38.328] <TB0> INFO: Expecting 41600 events.
[13:24:42.523] <TB0> INFO: 41600 events read in total (3639ms).
[13:24:42.524] <TB0> INFO: Test took 4460ms.
[13:24:42.526] <TB0> INFO: scanning low vcal = 80
[13:24:42.847] <TB0> INFO: Expecting 41600 events.
[13:24:47.039] <TB0> INFO: 41600 events read in total (3635ms).
[13:24:47.040] <TB0> INFO: Test took 4514ms.
[13:24:47.042] <TB0> INFO: scanning low vcal = 90
[13:24:47.332] <TB0> INFO: Expecting 41600 events.
[13:24:51.536] <TB0> INFO: 41600 events read in total (3648ms).
[13:24:51.536] <TB0> INFO: Test took 4493ms.
[13:24:51.539] <TB0> INFO: scanning low vcal = 100
[13:24:51.907] <TB0> INFO: Expecting 41600 events.
[13:24:56.135] <TB0> INFO: 41600 events read in total (3672ms).
[13:24:56.135] <TB0> INFO: Test took 4596ms.
[13:24:56.138] <TB0> INFO: scanning low vcal = 110
[13:24:56.414] <TB0> INFO: Expecting 41600 events.
[13:25:00.588] <TB0> INFO: 41600 events read in total (3618ms).
[13:25:00.588] <TB0> INFO: Test took 4450ms.
[13:25:00.591] <TB0> INFO: scanning low vcal = 120
[13:25:00.898] <TB0> INFO: Expecting 41600 events.
[13:25:05.081] <TB0> INFO: 41600 events read in total (3627ms).
[13:25:05.082] <TB0> INFO: Test took 4491ms.
[13:25:05.084] <TB0> INFO: scanning low vcal = 130
[13:25:05.343] <TB0> INFO: Expecting 41600 events.
[13:25:09.553] <TB0> INFO: 41600 events read in total (3654ms).
[13:25:09.553] <TB0> INFO: Test took 4468ms.
[13:25:09.556] <TB0> INFO: scanning low vcal = 140
[13:25:09.815] <TB0> INFO: Expecting 41600 events.
[13:25:14.021] <TB0> INFO: 41600 events read in total (3650ms).
[13:25:14.022] <TB0> INFO: Test took 4466ms.
[13:25:14.025] <TB0> INFO: scanning low vcal = 150
[13:25:14.288] <TB0> INFO: Expecting 41600 events.
[13:25:18.490] <TB0> INFO: 41600 events read in total (3646ms).
[13:25:18.490] <TB0> INFO: Test took 4466ms.
[13:25:18.494] <TB0> INFO: scanning low vcal = 160
[13:25:18.756] <TB0> INFO: Expecting 41600 events.
[13:25:22.976] <TB0> INFO: 41600 events read in total (3656ms).
[13:25:22.977] <TB0> INFO: Test took 4483ms.
[13:25:22.980] <TB0> INFO: scanning low vcal = 170
[13:25:23.269] <TB0> INFO: Expecting 41600 events.
[13:25:27.480] <TB0> INFO: 41600 events read in total (3655ms).
[13:25:27.481] <TB0> INFO: Test took 4501ms.
[13:25:27.484] <TB0> INFO: scanning low vcal = 180
[13:25:27.748] <TB0> INFO: Expecting 41600 events.
[13:25:31.947] <TB0> INFO: 41600 events read in total (3643ms).
[13:25:31.947] <TB0> INFO: Test took 4463ms.
[13:25:31.950] <TB0> INFO: scanning low vcal = 190
[13:25:32.216] <TB0> INFO: Expecting 41600 events.
[13:25:36.446] <TB0> INFO: 41600 events read in total (3674ms).
[13:25:36.447] <TB0> INFO: Test took 4497ms.
[13:25:36.449] <TB0> INFO: scanning low vcal = 200
[13:25:36.714] <TB0> INFO: Expecting 41600 events.
[13:25:40.954] <TB0> INFO: 41600 events read in total (3684ms).
[13:25:40.955] <TB0> INFO: Test took 4506ms.
[13:25:40.957] <TB0> INFO: scanning low vcal = 210
[13:25:41.226] <TB0> INFO: Expecting 41600 events.
[13:25:45.435] <TB0> INFO: 41600 events read in total (3653ms).
[13:25:45.436] <TB0> INFO: Test took 4478ms.
[13:25:45.438] <TB0> INFO: scanning low vcal = 220
[13:25:45.704] <TB0> INFO: Expecting 41600 events.
[13:25:49.886] <TB0> INFO: 41600 events read in total (3626ms).
[13:25:49.887] <TB0> INFO: Test took 4448ms.
[13:25:49.889] <TB0> INFO: scanning low vcal = 230
[13:25:50.153] <TB0> INFO: Expecting 41600 events.
[13:25:54.343] <TB0> INFO: 41600 events read in total (3633ms).
[13:25:54.343] <TB0> INFO: Test took 4454ms.
[13:25:54.346] <TB0> INFO: scanning low vcal = 240
[13:25:54.616] <TB0> INFO: Expecting 41600 events.
[13:25:58.850] <TB0> INFO: 41600 events read in total (3678ms).
[13:25:58.851] <TB0> INFO: Test took 4505ms.
[13:25:58.855] <TB0> INFO: scanning low vcal = 250
[13:25:59.153] <TB0> INFO: Expecting 41600 events.
[13:26:03.317] <TB0> INFO: 41600 events read in total (3608ms).
[13:26:03.318] <TB0> INFO: Test took 4463ms.
[13:26:03.321] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[13:26:03.584] <TB0> INFO: Expecting 41600 events.
[13:26:07.760] <TB0> INFO: 41600 events read in total (3620ms).
[13:26:07.760] <TB0> INFO: Test took 4439ms.
[13:26:07.762] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[13:26:08.024] <TB0> INFO: Expecting 41600 events.
[13:26:12.217] <TB0> INFO: 41600 events read in total (3637ms).
[13:26:12.218] <TB0> INFO: Test took 4455ms.
[13:26:12.223] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[13:26:12.486] <TB0> INFO: Expecting 41600 events.
[13:26:16.656] <TB0> INFO: 41600 events read in total (3614ms).
[13:26:16.656] <TB0> INFO: Test took 4433ms.
[13:26:16.659] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[13:26:16.922] <TB0> INFO: Expecting 41600 events.
[13:26:21.145] <TB0> INFO: 41600 events read in total (3667ms).
[13:26:21.146] <TB0> INFO: Test took 4487ms.
[13:26:21.148] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:26:21.407] <TB0> INFO: Expecting 41600 events.
[13:26:25.635] <TB0> INFO: 41600 events read in total (3671ms).
[13:26:25.635] <TB0> INFO: Test took 4487ms.
[13:26:26.078] <TB0> INFO: PixTestGainPedestal::measure() done
[13:26:59.609] <TB0> INFO: PixTestGainPedestal::fit() done
[13:26:59.609] <TB0> INFO: non-linearity mean: 0.963 0.962 0.957 0.961 0.957 0.960 0.958 0.956 0.948 0.958 0.962 0.958 0.960 0.953 0.966 0.951
[13:26:59.609] <TB0> INFO: non-linearity RMS: 0.005 0.006 0.005 0.006 0.005 0.005 0.006 0.006 0.006 0.005 0.005 0.007 0.006 0.007 0.005 0.006
[13:26:59.610] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[13:26:59.629] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[13:26:59.649] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[13:26:59.669] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[13:26:59.689] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[13:26:59.708] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[13:26:59.730] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[13:26:59.752] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[13:26:59.778] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[13:26:59.798] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[13:26:59.818] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[13:26:59.838] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[13:26:59.857] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[13:26:59.877] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[13:26:59.902] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[13:26:59.922] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[13:26:59.942] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 166 seconds
[13:26:59.942] <TB0> INFO: Decoding statistics:
[13:26:59.942] <TB0> INFO: General information:
[13:26:59.942] <TB0> INFO: 16bit words read: 2662400
[13:26:59.942] <TB0> INFO: valid events total: 166400
[13:26:59.942] <TB0> INFO: empty events: 0
[13:26:59.942] <TB0> INFO: valid events with pixels: 166400
[13:26:59.942] <TB0> INFO: valid pixel hits: 665563
[13:26:59.942] <TB0> INFO: Event errors: 0
[13:26:59.942] <TB0> INFO: start marker: 0
[13:26:59.942] <TB0> INFO: stop marker: 0
[13:26:59.942] <TB0> INFO: overflow: 0
[13:26:59.942] <TB0> INFO: invalid 5bit words: 0
[13:26:59.942] <TB0> INFO: invalid XOR eye diagram: 0
[13:26:59.942] <TB0> INFO: frame (failed synchr.): 0
[13:26:59.942] <TB0> INFO: idle data (no TBM trl): 0
[13:26:59.942] <TB0> INFO: no data (only TBM hdr): 0
[13:26:59.942] <TB0> INFO: TBM errors: 0
[13:26:59.942] <TB0> INFO: flawed TBM headers: 0
[13:26:59.942] <TB0> INFO: flawed TBM trailers: 0
[13:26:59.942] <TB0> INFO: event ID mismatches: 0
[13:26:59.942] <TB0> INFO: ROC errors: 0
[13:26:59.942] <TB0> INFO: missing ROC header(s): 0
[13:26:59.942] <TB0> INFO: misplaced readback start: 0
[13:26:59.942] <TB0> INFO: Pixel decoding errors: 0
[13:26:59.942] <TB0> INFO: pixel data incomplete: 0
[13:26:59.942] <TB0> INFO: pixel address: 0
[13:26:59.942] <TB0> INFO: pulse height fill bit: 0
[13:26:59.942] <TB0> INFO: buffer corruption: 0
[13:26:59.950] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C15.dat
[13:26:59.951] <TB0> INFO: ######################################################################
[13:26:59.951] <TB0> INFO: PixTestReadback::doTest()
[13:26:59.951] <TB0> INFO: ######################################################################
[13:26:59.951] <TB0> INFO: ----------------------------------------------------------------------
[13:26:59.951] <TB0> INFO: PixTestReadback::CalibrateVd()
[13:26:59.951] <TB0> INFO: ----------------------------------------------------------------------
[13:27:09.438] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C0.dat
[13:27:09.438] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C1.dat
[13:27:09.438] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C2.dat
[13:27:09.438] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C3.dat
[13:27:09.440] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C4.dat
[13:27:09.440] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C5.dat
[13:27:09.440] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C6.dat
[13:27:09.440] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C7.dat
[13:27:09.440] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C8.dat
[13:27:09.440] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C9.dat
[13:27:09.440] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C10.dat
[13:27:09.440] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C11.dat
[13:27:09.442] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C12.dat
[13:27:09.442] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C13.dat
[13:27:09.443] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C14.dat
[13:27:09.443] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C15.dat
[13:27:09.474] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[13:27:09.474] <TB0> INFO: ----------------------------------------------------------------------
[13:27:09.474] <TB0> INFO: PixTestReadback::CalibrateVa()
[13:27:09.474] <TB0> INFO: ----------------------------------------------------------------------
[13:27:18.928] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C0.dat
[13:27:18.928] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C1.dat
[13:27:18.928] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C2.dat
[13:27:18.928] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C3.dat
[13:27:18.928] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C4.dat
[13:27:18.929] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C5.dat
[13:27:18.929] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C6.dat
[13:27:18.929] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C7.dat
[13:27:18.929] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C8.dat
[13:27:18.929] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C9.dat
[13:27:18.929] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C10.dat
[13:27:18.929] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C11.dat
[13:27:18.929] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C12.dat
[13:27:18.929] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C13.dat
[13:27:18.929] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C14.dat
[13:27:18.929] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C15.dat
[13:27:18.952] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[13:27:18.952] <TB0> INFO: ----------------------------------------------------------------------
[13:27:18.952] <TB0> INFO: PixTestReadback::readbackVbg()
[13:27:18.952] <TB0> INFO: ----------------------------------------------------------------------
[13:27:26.236] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[13:27:26.236] <TB0> INFO: ----------------------------------------------------------------------
[13:27:26.236] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[13:27:26.236] <TB0> INFO: ----------------------------------------------------------------------
[13:27:26.236] <TB0> INFO: Vbg will be calibrated using Vd calibration
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.1calibrated Vbg = 1.21137 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 158.9calibrated Vbg = 1.21576 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 144.1calibrated Vbg = 1.2099 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 155.9calibrated Vbg = 1.21866 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 149.8calibrated Vbg = 1.22911 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 155.5calibrated Vbg = 1.21713 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155.9calibrated Vbg = 1.2187 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 149.3calibrated Vbg = 1.22601 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 143.7calibrated Vbg = 1.2223 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 158.4calibrated Vbg = 1.21781 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 160.2calibrated Vbg = 1.21352 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 164.6calibrated Vbg = 1.20773 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155.8calibrated Vbg = 1.21296 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 157calibrated Vbg = 1.20349 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 157.3calibrated Vbg = 1.21837 :::*/*/*/*/
[13:27:26.236] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 154calibrated Vbg = 1.21714 :::*/*/*/*/
[13:27:26.237] <TB0> INFO: ----------------------------------------------------------------------
[13:27:26.237] <TB0> INFO: PixTestReadback::CalibrateIa()
[13:27:26.237] <TB0> INFO: ----------------------------------------------------------------------
[13:30:01.513] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C0.dat
[13:30:01.513] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C1.dat
[13:30:01.513] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C2.dat
[13:30:01.513] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C3.dat
[13:30:01.513] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C4.dat
[13:30:01.513] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C5.dat
[13:30:01.513] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C6.dat
[13:30:01.513] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C7.dat
[13:30:01.513] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C8.dat
[13:30:01.514] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C9.dat
[13:30:01.514] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C10.dat
[13:30:01.514] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C11.dat
[13:30:01.514] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C12.dat
[13:30:01.514] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C13.dat
[13:30:01.514] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C14.dat
[13:30:01.514] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2148_FullQualification_2016-02-06_09h18m_1454746708//002_FulltestPxar_m20//readbackCal_C15.dat
[13:30:01.539] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[13:30:01.540] <TB0> INFO: PixTestReadback::doTest() done
[13:30:01.540] <TB0> INFO: Decoding statistics:
[13:30:01.540] <TB0> INFO: General information:
[13:30:01.540] <TB0> INFO: 16bit words read: 1024
[13:30:01.540] <TB0> INFO: valid events total: 128
[13:30:01.540] <TB0> INFO: empty events: 128
[13:30:01.540] <TB0> INFO: valid events with pixels: 0
[13:30:01.540] <TB0> INFO: valid pixel hits: 0
[13:30:01.540] <TB0> INFO: Event errors: 0
[13:30:01.540] <TB0> INFO: start marker: 0
[13:30:01.540] <TB0> INFO: stop marker: 0
[13:30:01.540] <TB0> INFO: overflow: 0
[13:30:01.540] <TB0> INFO: invalid 5bit words: 0
[13:30:01.540] <TB0> INFO: invalid XOR eye diagram: 0
[13:30:01.540] <TB0> INFO: frame (failed synchr.): 0
[13:30:01.540] <TB0> INFO: idle data (no TBM trl): 0
[13:30:01.540] <TB0> INFO: no data (only TBM hdr): 0
[13:30:01.540] <TB0> INFO: TBM errors: 0
[13:30:01.540] <TB0> INFO: flawed TBM headers: 0
[13:30:01.540] <TB0> INFO: flawed TBM trailers: 0
[13:30:01.540] <TB0> INFO: event ID mismatches: 0
[13:30:01.540] <TB0> INFO: ROC errors: 0
[13:30:01.540] <TB0> INFO: missing ROC header(s): 0
[13:30:01.540] <TB0> INFO: misplaced readback start: 0
[13:30:01.540] <TB0> INFO: Pixel decoding errors: 0
[13:30:01.540] <TB0> INFO: pixel data incomplete: 0
[13:30:01.540] <TB0> INFO: pixel address: 0
[13:30:01.540] <TB0> INFO: pulse height fill bit: 0
[13:30:01.540] <TB0> INFO: buffer corruption: 0
[13:30:01.562] <TB0> INFO: Decoding statistics:
[13:30:01.563] <TB0> INFO: General information:
[13:30:01.563] <TB0> INFO: 16bit words read: 2755352
[13:30:01.563] <TB0> INFO: valid events total: 176768
[13:30:01.563] <TB0> INFO: empty events: 7866
[13:30:01.563] <TB0> INFO: valid events with pixels: 168902
[13:30:01.563] <TB0> INFO: valid pixel hits: 668065
[13:30:01.563] <TB0> INFO: Event errors: 0
[13:30:01.563] <TB0> INFO: start marker: 0
[13:30:01.563] <TB0> INFO: stop marker: 0
[13:30:01.563] <TB0> INFO: overflow: 0
[13:30:01.563] <TB0> INFO: invalid 5bit words: 0
[13:30:01.563] <TB0> INFO: invalid XOR eye diagram: 0
[13:30:01.563] <TB0> INFO: frame (failed synchr.): 0
[13:30:01.563] <TB0> INFO: idle data (no TBM trl): 0
[13:30:01.563] <TB0> INFO: no data (only TBM hdr): 0
[13:30:01.563] <TB0> INFO: TBM errors: 0
[13:30:01.563] <TB0> INFO: flawed TBM headers: 0
[13:30:01.563] <TB0> INFO: flawed TBM trailers: 0
[13:30:01.563] <TB0> INFO: event ID mismatches: 0
[13:30:01.563] <TB0> INFO: ROC errors: 0
[13:30:01.563] <TB0> INFO: missing ROC header(s): 0
[13:30:01.563] <TB0> INFO: misplaced readback start: 0
[13:30:01.563] <TB0> INFO: Pixel decoding errors: 0
[13:30:01.563] <TB0> INFO: pixel data incomplete: 0
[13:30:01.563] <TB0> INFO: pixel address: 0
[13:30:01.563] <TB0> INFO: pulse height fill bit: 0
[13:30:01.563] <TB0> INFO: buffer corruption: 0
[13:30:01.563] <TB0> INFO: enter test to run
[13:30:01.563] <TB0> INFO: test: exit no parameter change
[13:30:01.705] <TB0> QUIET: Connection to board 73 closed.
[13:30:01.784] <TB0> INFO: pXar: this is the end, my friend