[08:43:25.876] <TB1> INFO: *** Welcome to pxar ***
[08:43:25.876] <TB1> INFO: *** Today: 2016/09/26
[08:43:25.987] <TB1> INFO: *** Version: fe94
[08:43:25.987] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C15.dat
[08:43:25.988] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:43:25.988] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//defaultMaskFile.dat
[08:43:25.988] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters_C15.dat
[08:43:26.059] <TB1> INFO: clk: 4
[08:43:26.059] <TB1> INFO: ctr: 4
[08:43:26.059] <TB1> INFO: sda: 19
[08:43:26.059] <TB1> INFO: tin: 9
[08:43:26.059] <TB1> INFO: level: 15
[08:43:26.059] <TB1> INFO: triggerdelay: 0
[08:43:26.059] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[08:43:26.059] <TB1> INFO: Log level: INFO
[08:43:26.065] <TB1> INFO: Found DTB DTB_WXBYFL
[08:43:26.076] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[08:43:26.078] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[08:43:26.079] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[08:43:27.591] <TB1> INFO: DUT info:
[08:43:27.591] <TB1> INFO: The DUT currently contains the following objects:
[08:43:27.591] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:43:27.591] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:43:27.591] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:43:27.591] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:43:27.591] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.591] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.591] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.591] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.591] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.591] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.591] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.591] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.591] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.591] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.591] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.592] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.592] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.592] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.592] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.592] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:27.992] <TB1> INFO: enter 'restricted' command line mode
[08:43:27.992] <TB1> INFO: enter test to run
[08:43:27.992] <TB1> INFO: test: pretest no parameter change
[08:43:27.992] <TB1> INFO: running: pretest
[08:43:27.999] <TB1> INFO: ----------------------------------------------------------------------
[08:43:27.999] <TB1> INFO: PixTestPretest::programROC()
[08:43:27.999] <TB1> INFO: ----------------------------------------------------------------------
[08:43:46.014] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:43:46.014] <TB1> INFO: IA differences per ROC: 20.9 19.3 17.7 18.5 19.3 17.7 20.1 16.1 20.9 18.5 20.9 20.1 20.1 20.1 17.7 20.1
[08:43:46.103] <TB1> INFO: enter test to run
[08:43:46.103] <TB1> INFO: test: pretest no parameter change
[08:43:46.103] <TB1> INFO: running: pretest
[08:43:46.104] <TB1> INFO: ----------------------------------------------------------------------
[08:43:46.104] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:43:46.104] <TB1> INFO: ----------------------------------------------------------------------
[08:43:51.990] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 379.4 mA = 23.7125 mA/ROC
[08:43:51.990] <TB1> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 19.3 19.3 18.5 19.3 18.5 19.3 19.3 19.3 19.3 18.5 19.3 19.3
[08:43:52.020] <TB1> INFO: enter test to run
[08:43:52.020] <TB1> INFO: test: pretest no parameter change
[08:43:52.020] <TB1> INFO: running: pretest
[08:43:52.020] <TB1> INFO: ----------------------------------------------------------------------
[08:43:52.020] <TB1> INFO: PixTestPretest::findTiming()
[08:43:52.020] <TB1> INFO: ----------------------------------------------------------------------
[08:43:52.020] <TB1> INFO: PixTestCmd::init()
[08:43:52.855] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[08:45:23.738] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[08:45:23.738] <TB1> INFO: (success/tries = 100/100), width = 5
[08:45:23.739] <TB1> INFO: enter test to run
[08:45:23.739] <TB1> INFO: test: pretest no parameter change
[08:45:23.739] <TB1> INFO: running: pretest
[08:45:23.741] <TB1> INFO: ----------------------------------------------------------------------
[08:45:23.741] <TB1> INFO: PixTestPretest::findWorkingPixel()
[08:45:23.741] <TB1> INFO: ----------------------------------------------------------------------
[08:45:23.835] <TB1> INFO: Expecting 231680 events.
[08:45:29.437] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[08:45:29.561] <TB1> ERROR: <datapipe.cc/CheckEventID:L485> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[08:45:33.035] <TB1> INFO: 231680 events read in total (8643ms).
[08:45:33.043] <TB1> INFO: Test took 9296ms.
[08:45:33.255] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:45:33.320] <TB1> INFO: enter test to run
[08:45:33.320] <TB1> INFO: test: pretest no parameter change
[08:45:33.320] <TB1> INFO: running: pretest
[08:45:33.321] <TB1> INFO: ----------------------------------------------------------------------
[08:45:33.321] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[08:45:33.321] <TB1> INFO: ----------------------------------------------------------------------
[08:45:33.414] <TB1> INFO: Expecting 231680 events.
[08:45:42.659] <TB1> INFO: 231680 events read in total (8688ms).
[08:45:42.664] <TB1> INFO: Test took 9339ms.
[08:45:42.877] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[08:45:42.877] <TB1> INFO: CalDel: 152 129 152 143 145 157 138 129 152 164 158 148 143 139 140 158
[08:45:42.877] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:45:42.911] <TB1> INFO: enter test to run
[08:45:42.911] <TB1> INFO: test: pretest no parameter change
[08:45:42.911] <TB1> INFO: running: pretest
[08:45:42.911] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C0.dat
[08:45:42.911] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C1.dat
[08:45:42.911] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C2.dat
[08:45:42.911] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C3.dat
[08:45:42.911] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C4.dat
[08:45:42.911] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C5.dat
[08:45:42.912] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C6.dat
[08:45:42.912] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C7.dat
[08:45:42.912] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C8.dat
[08:45:42.912] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C9.dat
[08:45:42.912] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C10.dat
[08:45:42.912] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C11.dat
[08:45:42.912] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C12.dat
[08:45:42.912] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C13.dat
[08:45:42.912] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C14.dat
[08:45:42.912] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C15.dat
[08:45:42.913] <TB1> INFO: enter test to run
[08:45:42.913] <TB1> INFO: test: fulltest no parameter change
[08:45:42.913] <TB1> INFO: running: fulltest
[08:45:42.913] <TB1> INFO: ######################################################################
[08:45:42.913] <TB1> INFO: PixTestFullTest::doTest()
[08:45:42.913] <TB1> INFO: ######################################################################
[08:45:42.914] <TB1> INFO: ######################################################################
[08:45:42.914] <TB1> INFO: PixTestAlive::doTest()
[08:45:42.914] <TB1> INFO: ######################################################################
[08:45:42.915] <TB1> INFO: ----------------------------------------------------------------------
[08:45:42.915] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:45:42.915] <TB1> INFO: ----------------------------------------------------------------------
[08:45:43.180] <TB1> INFO: Expecting 41600 events.
[08:45:46.661] <TB1> INFO: 41600 events read in total (2925ms).
[08:45:46.662] <TB1> INFO: Test took 3746ms.
[08:45:46.878] <TB1> INFO: PixTestAlive::aliveTest() done
[08:45:46.878] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 4 0 0
[08:45:46.880] <TB1> INFO: ----------------------------------------------------------------------
[08:45:46.880] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:45:46.880] <TB1> INFO: ----------------------------------------------------------------------
[08:45:47.147] <TB1> INFO: Expecting 41600 events.
[08:45:49.862] <TB1> INFO: 41600 events read in total (2159ms).
[08:45:49.862] <TB1> INFO: Test took 2980ms.
[08:45:49.862] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:45:50.102] <TB1> INFO: PixTestAlive::maskTest() done
[08:45:50.102] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:45:50.103] <TB1> INFO: ----------------------------------------------------------------------
[08:45:50.104] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:45:50.104] <TB1> INFO: ----------------------------------------------------------------------
[08:45:50.391] <TB1> INFO: Expecting 41600 events.
[08:45:53.862] <TB1> INFO: 41600 events read in total (2915ms).
[08:45:53.862] <TB1> INFO: Test took 3757ms.
[08:45:54.075] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[08:45:54.075] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:45:54.075] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[08:45:54.075] <TB1> INFO: Decoding statistics:
[08:45:54.075] <TB1> INFO: General information:
[08:45:54.075] <TB1> INFO: 16bit words read: 0
[08:45:54.075] <TB1> INFO: valid events total: 0
[08:45:54.075] <TB1> INFO: empty events: 0
[08:45:54.075] <TB1> INFO: valid events with pixels: 0
[08:45:54.075] <TB1> INFO: valid pixel hits: 0
[08:45:54.075] <TB1> INFO: Event errors: 0
[08:45:54.075] <TB1> INFO: start marker: 0
[08:45:54.075] <TB1> INFO: stop marker: 0
[08:45:54.075] <TB1> INFO: overflow: 0
[08:45:54.076] <TB1> INFO: invalid 5bit words: 0
[08:45:54.076] <TB1> INFO: invalid XOR eye diagram: 0
[08:45:54.076] <TB1> INFO: frame (failed synchr.): 0
[08:45:54.076] <TB1> INFO: idle data (no TBM trl): 0
[08:45:54.076] <TB1> INFO: no data (only TBM hdr): 0
[08:45:54.076] <TB1> INFO: TBM errors: 0
[08:45:54.076] <TB1> INFO: flawed TBM headers: 0
[08:45:54.076] <TB1> INFO: flawed TBM trailers: 0
[08:45:54.076] <TB1> INFO: event ID mismatches: 0
[08:45:54.076] <TB1> INFO: ROC errors: 0
[08:45:54.076] <TB1> INFO: missing ROC header(s): 0
[08:45:54.076] <TB1> INFO: misplaced readback start: 0
[08:45:54.076] <TB1> INFO: Pixel decoding errors: 0
[08:45:54.076] <TB1> INFO: pixel data incomplete: 0
[08:45:54.076] <TB1> INFO: pixel address: 0
[08:45:54.076] <TB1> INFO: pulse height fill bit: 0
[08:45:54.076] <TB1> INFO: buffer corruption: 0
[08:45:54.083] <TB1> INFO: ######################################################################
[08:45:54.083] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:45:54.083] <TB1> INFO: ######################################################################
[08:45:54.085] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:45:54.096] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:45:54.096] <TB1> INFO: run 1 of 1
[08:45:54.361] <TB1> INFO: Expecting 3120000 events.
[08:46:31.337] <TB1> INFO: 859230 events read in total (36419ms).
[08:47:07.814] <TB1> INFO: 1700385 events read in total (72896ms).
[08:47:44.437] <TB1> INFO: 2551920 events read in total (109519ms).
[08:48:09.084] <TB1> INFO: 3120000 events read in total (134166ms).
[08:48:09.160] <TB1> INFO: Test took 135065ms.
[08:48:32.598] <TB1> INFO: PixTestBBMap::doTest() done, duration: 158 seconds
[08:48:32.598] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 2 0 0
[08:48:32.598] <TB1> INFO: separation cut (per ROC): 82 78 82 85 79 80 88 85 70 79 85 87 76 75 71 87
[08:48:32.598] <TB1> INFO: Decoding statistics:
[08:48:32.598] <TB1> INFO: General information:
[08:48:32.598] <TB1> INFO: 16bit words read: 0
[08:48:32.598] <TB1> INFO: valid events total: 0
[08:48:32.598] <TB1> INFO: empty events: 0
[08:48:32.598] <TB1> INFO: valid events with pixels: 0
[08:48:32.598] <TB1> INFO: valid pixel hits: 0
[08:48:32.598] <TB1> INFO: Event errors: 0
[08:48:32.598] <TB1> INFO: start marker: 0
[08:48:32.598] <TB1> INFO: stop marker: 0
[08:48:32.598] <TB1> INFO: overflow: 0
[08:48:32.598] <TB1> INFO: invalid 5bit words: 0
[08:48:32.598] <TB1> INFO: invalid XOR eye diagram: 0
[08:48:32.598] <TB1> INFO: frame (failed synchr.): 0
[08:48:32.598] <TB1> INFO: idle data (no TBM trl): 0
[08:48:32.598] <TB1> INFO: no data (only TBM hdr): 0
[08:48:32.598] <TB1> INFO: TBM errors: 0
[08:48:32.598] <TB1> INFO: flawed TBM headers: 0
[08:48:32.598] <TB1> INFO: flawed TBM trailers: 0
[08:48:32.598] <TB1> INFO: event ID mismatches: 0
[08:48:32.598] <TB1> INFO: ROC errors: 0
[08:48:32.598] <TB1> INFO: missing ROC header(s): 0
[08:48:32.598] <TB1> INFO: misplaced readback start: 0
[08:48:32.598] <TB1> INFO: Pixel decoding errors: 0
[08:48:32.598] <TB1> INFO: pixel data incomplete: 0
[08:48:32.598] <TB1> INFO: pixel address: 0
[08:48:32.598] <TB1> INFO: pulse height fill bit: 0
[08:48:32.598] <TB1> INFO: buffer corruption: 0
[08:48:32.682] <TB1> INFO: ######################################################################
[08:48:32.682] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:48:32.682] <TB1> INFO: ######################################################################
[08:48:32.682] <TB1> INFO: ----------------------------------------------------------------------
[08:48:32.682] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:48:32.682] <TB1> INFO: ----------------------------------------------------------------------
[08:48:32.682] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[08:48:32.693] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[08:48:32.693] <TB1> INFO: run 1 of 1
[08:48:32.998] <TB1> INFO: Expecting 36608000 events.
[08:49:01.976] <TB1> INFO: 812050 events read in total (28422ms).
[08:49:30.797] <TB1> INFO: 1609300 events read in total (57243ms).
[08:50:00.854] <TB1> INFO: 2407950 events read in total (87300ms).
[08:50:31.109] <TB1> INFO: 3206350 events read in total (117555ms).
[08:50:59.175] <TB1> INFO: 4003500 events read in total (145621ms).
[08:51:27.525] <TB1> INFO: 4802700 events read in total (173971ms).
[08:51:55.623] <TB1> INFO: 5600350 events read in total (202069ms).
[08:52:23.895] <TB1> INFO: 6399650 events read in total (230341ms).
[08:52:52.110] <TB1> INFO: 7194850 events read in total (258556ms).
[08:53:20.244] <TB1> INFO: 7993500 events read in total (286690ms).
[08:53:48.314] <TB1> INFO: 8790550 events read in total (314760ms).
[08:54:16.375] <TB1> INFO: 9587900 events read in total (342821ms).
[08:54:44.654] <TB1> INFO: 10384950 events read in total (371100ms).
[08:55:12.706] <TB1> INFO: 11183100 events read in total (399152ms).
[08:55:40.574] <TB1> INFO: 11980150 events read in total (427020ms).
[08:56:08.565] <TB1> INFO: 12779050 events read in total (455011ms).
[08:56:37.004] <TB1> INFO: 13575900 events read in total (483450ms).
[08:57:05.167] <TB1> INFO: 14371650 events read in total (511613ms).
[08:57:33.084] <TB1> INFO: 15167900 events read in total (539530ms).
[08:58:01.216] <TB1> INFO: 15963100 events read in total (567662ms).
[08:58:29.481] <TB1> INFO: 16760700 events read in total (595927ms).
[08:58:57.571] <TB1> INFO: 17557200 events read in total (624017ms).
[08:59:25.707] <TB1> INFO: 18352350 events read in total (652153ms).
[08:59:54.062] <TB1> INFO: 19145400 events read in total (680508ms).
[09:00:21.950] <TB1> INFO: 19938200 events read in total (708396ms).
[09:00:49.904] <TB1> INFO: 20728900 events read in total (736350ms).
[09:01:18.256] <TB1> INFO: 21519250 events read in total (764702ms).
[09:01:46.259] <TB1> INFO: 22307750 events read in total (792706ms).
[09:02:14.368] <TB1> INFO: 23097150 events read in total (820814ms).
[09:02:42.539] <TB1> INFO: 23885450 events read in total (848985ms).
[09:03:10.780] <TB1> INFO: 24675000 events read in total (877226ms).
[09:03:38.595] <TB1> INFO: 25464850 events read in total (905041ms).
[09:04:07.137] <TB1> INFO: 26254950 events read in total (933583ms).
[09:04:35.486] <TB1> INFO: 27042350 events read in total (961932ms).
[09:05:03.762] <TB1> INFO: 27830950 events read in total (990208ms).
[09:05:32.200] <TB1> INFO: 28620800 events read in total (1018646ms).
[09:06:00.476] <TB1> INFO: 29408800 events read in total (1046922ms).
[09:06:28.987] <TB1> INFO: 30198900 events read in total (1075433ms).
[09:06:57.320] <TB1> INFO: 30988000 events read in total (1103766ms).
[09:07:25.541] <TB1> INFO: 31776300 events read in total (1131987ms).
[09:07:53.827] <TB1> INFO: 32566850 events read in total (1160273ms).
[09:08:21.735] <TB1> INFO: 33356750 events read in total (1188181ms).
[09:08:50.061] <TB1> INFO: 34145750 events read in total (1216507ms).
[09:09:18.689] <TB1> INFO: 34934200 events read in total (1245135ms).
[09:09:47.016] <TB1> INFO: 35723950 events read in total (1273462ms).
[09:10:15.428] <TB1> INFO: 36522400 events read in total (1301874ms).
[09:10:18.782] <TB1> INFO: 36608000 events read in total (1305228ms).
[09:10:18.847] <TB1> INFO: Test took 1306154ms.
[09:10:19.372] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:21.122] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:23.079] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:25.060] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:26.830] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:28.760] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:30.795] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:32.624] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:34.616] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:36.473] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:38.319] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:40.033] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:41.939] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:43.955] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:45.704] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:47.551] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:10:49.393] <TB1> INFO: PixTestScurves::scurves() done
[09:10:49.393] <TB1> INFO: Vcal mean: 84.52 77.97 82.66 89.32 76.92 82.78 86.18 92.49 80.24 83.72 81.99 84.33 82.95 78.87 75.62 88.95
[09:10:49.393] <TB1> INFO: Vcal RMS: 4.63 4.34 4.44 4.97 3.84 4.31 4.67 4.90 4.09 4.85 4.75 5.02 4.59 4.53 4.11 4.93
[09:10:49.394] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1336 seconds
[09:10:49.394] <TB1> INFO: Decoding statistics:
[09:10:49.394] <TB1> INFO: General information:
[09:10:49.394] <TB1> INFO: 16bit words read: 0
[09:10:49.394] <TB1> INFO: valid events total: 0
[09:10:49.394] <TB1> INFO: empty events: 0
[09:10:49.394] <TB1> INFO: valid events with pixels: 0
[09:10:49.394] <TB1> INFO: valid pixel hits: 0
[09:10:49.394] <TB1> INFO: Event errors: 0
[09:10:49.394] <TB1> INFO: start marker: 0
[09:10:49.394] <TB1> INFO: stop marker: 0
[09:10:49.394] <TB1> INFO: overflow: 0
[09:10:49.394] <TB1> INFO: invalid 5bit words: 0
[09:10:49.394] <TB1> INFO: invalid XOR eye diagram: 0
[09:10:49.394] <TB1> INFO: frame (failed synchr.): 0
[09:10:49.394] <TB1> INFO: idle data (no TBM trl): 0
[09:10:49.394] <TB1> INFO: no data (only TBM hdr): 0
[09:10:49.394] <TB1> INFO: TBM errors: 0
[09:10:49.394] <TB1> INFO: flawed TBM headers: 0
[09:10:49.394] <TB1> INFO: flawed TBM trailers: 0
[09:10:49.394] <TB1> INFO: event ID mismatches: 0
[09:10:49.394] <TB1> INFO: ROC errors: 0
[09:10:49.394] <TB1> INFO: missing ROC header(s): 0
[09:10:49.394] <TB1> INFO: misplaced readback start: 0
[09:10:49.394] <TB1> INFO: Pixel decoding errors: 0
[09:10:49.394] <TB1> INFO: pixel data incomplete: 0
[09:10:49.394] <TB1> INFO: pixel address: 0
[09:10:49.394] <TB1> INFO: pulse height fill bit: 0
[09:10:49.394] <TB1> INFO: buffer corruption: 0
[09:10:49.467] <TB1> INFO: ######################################################################
[09:10:49.467] <TB1> INFO: PixTestTrim::doTest()
[09:10:49.467] <TB1> INFO: ######################################################################
[09:10:49.468] <TB1> INFO: ----------------------------------------------------------------------
[09:10:49.468] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[09:10:49.468] <TB1> INFO: ----------------------------------------------------------------------
[09:10:49.588] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:10:49.588] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:10:49.597] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:10:49.597] <TB1> INFO: run 1 of 1
[09:10:49.863] <TB1> INFO: Expecting 6281600 events.
[09:11:26.738] <TB1> INFO: 1099650 events read in total (36313ms).
[09:12:03.003] <TB1> INFO: 2189720 events read in total (72578ms).
[09:12:38.296] <TB1> INFO: 3270830 events read in total (107871ms).
[09:13:13.946] <TB1> INFO: 4357260 events read in total (143521ms).
[09:13:49.488] <TB1> INFO: 5445940 events read in total (179063ms).
[09:14:17.083] <TB1> INFO: 6281600 events read in total (206658ms).
[09:14:17.149] <TB1> INFO: Test took 207551ms.
[09:14:36.667] <TB1> INFO: ROC 0 VthrComp = 94
[09:14:36.667] <TB1> INFO: ROC 1 VthrComp = 87
[09:14:36.667] <TB1> INFO: ROC 2 VthrComp = 90
[09:14:36.667] <TB1> INFO: ROC 3 VthrComp = 96
[09:14:36.667] <TB1> INFO: ROC 4 VthrComp = 84
[09:14:36.667] <TB1> INFO: ROC 5 VthrComp = 88
[09:14:36.668] <TB1> INFO: ROC 6 VthrComp = 95
[09:14:36.668] <TB1> INFO: ROC 7 VthrComp = 97
[09:14:36.668] <TB1> INFO: ROC 8 VthrComp = 87
[09:14:36.668] <TB1> INFO: ROC 9 VthrComp = 89
[09:14:36.668] <TB1> INFO: ROC 10 VthrComp = 88
[09:14:36.668] <TB1> INFO: ROC 11 VthrComp = 89
[09:14:36.668] <TB1> INFO: ROC 12 VthrComp = 91
[09:14:36.668] <TB1> INFO: ROC 13 VthrComp = 85
[09:14:36.668] <TB1> INFO: ROC 14 VthrComp = 81
[09:14:36.668] <TB1> INFO: ROC 15 VthrComp = 94
[09:14:36.669] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[09:14:36.669] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:14:36.676] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:14:36.676] <TB1> INFO: run 1 of 1
[09:14:36.936] <TB1> INFO: Expecting 6281600 events.
[09:15:08.569] <TB1> INFO: 756160 events read in total (31076ms).
[09:15:39.174] <TB1> INFO: 1510870 events read in total (61681ms).
[09:16:10.164] <TB1> INFO: 2266270 events read in total (92671ms).
[09:16:40.664] <TB1> INFO: 3022610 events read in total (123171ms).
[09:17:11.658] <TB1> INFO: 3775100 events read in total (154165ms).
[09:17:42.367] <TB1> INFO: 4523720 events read in total (184874ms).
[09:18:13.212] <TB1> INFO: 5271080 events read in total (215719ms).
[09:18:43.866] <TB1> INFO: 6018210 events read in total (246373ms).
[09:18:55.204] <TB1> INFO: 6281600 events read in total (257711ms).
[09:18:55.260] <TB1> INFO: Test took 258584ms.
[09:19:21.260] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 56.5102 for pixel 0/21 mean/min/max = 44.6782/32.5093/56.8471
[09:19:21.260] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 57.8384 for pixel 28/0 mean/min/max = 45.15/32.455/57.845
[09:19:21.261] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 56.3811 for pixel 30/1 mean/min/max = 44.9535/33.3046/56.6024
[09:19:21.261] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 56.8331 for pixel 15/58 mean/min/max = 44.2847/31.6276/56.9418
[09:19:21.261] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 54.9236 for pixel 28/3 mean/min/max = 43.8299/32.687/54.9729
[09:19:21.261] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 57.322 for pixel 14/69 mean/min/max = 45.3466/33.3107/57.3826
[09:19:21.262] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 56.8971 for pixel 6/34 mean/min/max = 44.8912/32.7773/57.0052
[09:19:21.262] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 57.8472 for pixel 32/13 mean/min/max = 44.7922/31.6432/57.9413
[09:19:21.262] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 56.0449 for pixel 24/72 mean/min/max = 43.9797/31.6851/56.2742
[09:19:21.262] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 58.3598 for pixel 10/6 mean/min/max = 45.8364/33.1356/58.5371
[09:19:21.262] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 57.5751 for pixel 1/41 mean/min/max = 45.126/32.6176/57.6345
[09:19:21.263] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 58.6912 for pixel 20/14 mean/min/max = 45.6944/32.671/58.7179
[09:19:21.263] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 58.2759 for pixel 0/76 mean/min/max = 45.2815/32.2194/58.3436
[09:19:21.263] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 55.3062 for pixel 5/56 mean/min/max = 43.8205/32.1707/55.4703
[09:19:21.263] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 56.5749 for pixel 2/7 mean/min/max = 44.8952/32.9478/56.8427
[09:19:21.264] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.5152 for pixel 20/13 mean/min/max = 44.5006/31.4787/57.5225
[09:19:21.264] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:21.353] <TB1> INFO: Expecting 514560 events.
[09:19:32.510] <TB1> INFO: 514560 events read in total (10601ms).
[09:19:32.519] <TB1> INFO: Expecting 514560 events.
[09:19:43.657] <TB1> INFO: 514560 events read in total (10736ms).
[09:19:43.667] <TB1> INFO: Expecting 514560 events.
[09:19:54.424] <TB1> INFO: 514560 events read in total (10355ms).
[09:19:54.436] <TB1> INFO: Expecting 514560 events.
[09:20:05.172] <TB1> INFO: 514560 events read in total (10334ms).
[09:20:05.187] <TB1> INFO: Expecting 514560 events.
[09:20:15.897] <TB1> INFO: 514560 events read in total (10309ms).
[09:20:15.916] <TB1> INFO: Expecting 514560 events.
[09:20:26.583] <TB1> INFO: 514560 events read in total (10265ms).
[09:20:26.604] <TB1> INFO: Expecting 514560 events.
[09:20:37.316] <TB1> INFO: 514560 events read in total (10310ms).
[09:20:37.342] <TB1> INFO: Expecting 514560 events.
[09:20:47.989] <TB1> INFO: 514560 events read in total (10245ms).
[09:20:48.014] <TB1> INFO: Expecting 514560 events.
[09:20:58.704] <TB1> INFO: 514560 events read in total (10288ms).
[09:20:58.733] <TB1> INFO: Expecting 514560 events.
[09:21:09.421] <TB1> INFO: 514560 events read in total (10286ms).
[09:21:09.453] <TB1> INFO: Expecting 514560 events.
[09:21:20.123] <TB1> INFO: 514560 events read in total (10268ms).
[09:21:20.158] <TB1> INFO: Expecting 514560 events.
[09:21:30.918] <TB1> INFO: 514560 events read in total (10358ms).
[09:21:30.957] <TB1> INFO: Expecting 514560 events.
[09:21:41.679] <TB1> INFO: 514560 events read in total (10320ms).
[09:21:41.719] <TB1> INFO: Expecting 514560 events.
[09:21:52.445] <TB1> INFO: 514560 events read in total (10324ms).
[09:21:52.489] <TB1> INFO: Expecting 514560 events.
[09:22:03.137] <TB1> INFO: 514560 events read in total (10246ms).
[09:22:03.182] <TB1> INFO: Expecting 514560 events.
[09:22:13.838] <TB1> INFO: 514560 events read in total (10254ms).
[09:22:13.897] <TB1> INFO: Test took 172633ms.
[09:22:15.117] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:22:15.126] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:22:15.126] <TB1> INFO: run 1 of 1
[09:22:15.416] <TB1> INFO: Expecting 6281600 events.
[09:22:46.414] <TB1> INFO: 744940 events read in total (30442ms).
[09:23:16.750] <TB1> INFO: 1490020 events read in total (60778ms).
[09:23:47.569] <TB1> INFO: 2237180 events read in total (91597ms).
[09:24:18.633] <TB1> INFO: 2985830 events read in total (122661ms).
[09:24:49.300] <TB1> INFO: 3730860 events read in total (153328ms).
[09:25:19.866] <TB1> INFO: 4473810 events read in total (183894ms).
[09:25:50.619] <TB1> INFO: 5215730 events read in total (214647ms).
[09:26:21.367] <TB1> INFO: 5956910 events read in total (245395ms).
[09:26:35.354] <TB1> INFO: 6281600 events read in total (259382ms).
[09:26:35.422] <TB1> INFO: Test took 260297ms.
[09:27:01.039] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 10.898523 .. 52.375669
[09:27:01.318] <TB1> INFO: Expecting 208000 events.
[09:27:11.063] <TB1> INFO: 208000 events read in total (9188ms).
[09:27:11.064] <TB1> INFO: Test took 10024ms.
[09:27:11.158] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 62 (-1/-1) hits flags = 528 (plus default)
[09:27:11.166] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:27:11.166] <TB1> INFO: run 1 of 1
[09:27:11.434] <TB1> INFO: Expecting 2620800 events.
[09:27:45.853] <TB1> INFO: 967550 events read in total (33863ms).
[09:28:20.289] <TB1> INFO: 1924550 events read in total (68299ms).
[09:28:45.827] <TB1> INFO: 2620800 events read in total (93837ms).
[09:28:45.850] <TB1> INFO: Test took 94684ms.
[09:29:00.315] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 24.051351 .. 48.029645
[09:29:00.582] <TB1> INFO: Expecting 208000 events.
[09:29:10.441] <TB1> INFO: 208000 events read in total (9303ms).
[09:29:10.441] <TB1> INFO: Test took 10124ms.
[09:29:10.528] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 14 .. 58 (-1/-1) hits flags = 528 (plus default)
[09:29:10.536] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:29:10.536] <TB1> INFO: run 1 of 1
[09:29:10.801] <TB1> INFO: Expecting 1872000 events.
[09:29:45.009] <TB1> INFO: 911380 events read in total (33652ms).
[09:30:18.184] <TB1> INFO: 1806280 events read in total (66827ms).
[09:30:20.950] <TB1> INFO: 1872000 events read in total (69593ms).
[09:30:20.972] <TB1> INFO: Test took 70435ms.
[09:30:35.182] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 25.574224 .. 45.507569
[09:30:35.445] <TB1> INFO: Expecting 208000 events.
[09:30:45.417] <TB1> INFO: 208000 events read in total (9416ms).
[09:30:45.418] <TB1> INFO: Test took 10235ms.
[09:30:45.506] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:30:45.515] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:30:45.515] <TB1> INFO: run 1 of 1
[09:30:45.778] <TB1> INFO: Expecting 1705600 events.
[09:31:20.081] <TB1> INFO: 921110 events read in total (33747ms).
[09:31:49.406] <TB1> INFO: 1705600 events read in total (63072ms).
[09:31:49.424] <TB1> INFO: Test took 63909ms.
[09:32:02.844] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 28.362107 .. 45.507569
[09:32:03.105] <TB1> INFO: Expecting 208000 events.
[09:32:13.083] <TB1> INFO: 208000 events read in total (9422ms).
[09:32:13.084] <TB1> INFO: Test took 10239ms.
[09:32:13.208] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 18 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:32:13.219] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:32:13.219] <TB1> INFO: run 1 of 1
[09:32:13.503] <TB1> INFO: Expecting 1580800 events.
[09:32:47.405] <TB1> INFO: 898480 events read in total (33346ms).
[09:33:13.112] <TB1> INFO: 1580800 events read in total (59053ms).
[09:33:13.130] <TB1> INFO: Test took 59912ms.
[09:33:26.716] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:33:26.716] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:33:26.725] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:33:26.725] <TB1> INFO: run 1 of 1
[09:33:26.985] <TB1> INFO: Expecting 1705600 events.
[09:34:01.292] <TB1> INFO: 916840 events read in total (33750ms).
[09:34:30.540] <TB1> INFO: 1705600 events read in total (62998ms).
[09:34:30.561] <TB1> INFO: Test took 63836ms.
[09:34:43.950] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:34:43.963] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:34:43.965] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:34:43.965] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:34:43.965] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:34:43.965] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:34:43.965] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:34:43.965] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:34:43.965] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:34:43.966] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:34:43.966] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:34:43.966] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:34:43.966] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:34:43.966] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:34:43.966] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:34:43.966] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:34:43.966] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:34:43.975] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:34:43.981] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:34:43.987] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:34:43.994] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:34:44.000] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:34:44.006] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:34:44.012] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:34:44.019] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:34:44.025] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:34:44.031] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:34:44.037] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:34:44.043] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:34:44.050] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:34:44.056] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:34:44.062] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:34:44.068] <TB1> INFO: PixTestTrim::trimTest() done
[09:34:44.068] <TB1> INFO: vtrim: 95 112 97 115 100 103 111 95 108 98 103 114 104 94 91 102
[09:34:44.068] <TB1> INFO: vthrcomp: 94 87 90 96 84 88 95 97 87 89 88 89 91 85 81 94
[09:34:44.068] <TB1> INFO: vcal mean: 35.85 35.97 36.85 35.68 36.69 37.11 35.83 36.42 35.96 36.96 37.02 36.60 36.00 35.81 36.50 35.89
[09:34:44.068] <TB1> INFO: vcal RMS: 1.29 1.39 2.09 1.15 1.89 2.20 1.27 1.65 1.31 2.18 2.19 1.97 1.45 1.75 1.80 1.32
[09:34:44.068] <TB1> INFO: bits mean: 7.50 7.63 6.69 8.57 7.76 6.69 7.76 7.54 8.13 6.83 6.84 7.14 7.54 8.01 6.75 7.91
[09:34:44.068] <TB1> INFO: bits RMS: 2.58 2.40 2.46 2.29 2.08 2.33 2.48 2.42 2.37 2.37 2.41 2.39 2.61 2.41 2.53 2.50
[09:34:44.075] <TB1> INFO: ----------------------------------------------------------------------
[09:34:44.075] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[09:34:44.075] <TB1> INFO: ----------------------------------------------------------------------
[09:34:44.078] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[09:34:44.089] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:34:44.089] <TB1> INFO: run 1 of 1
[09:34:44.377] <TB1> INFO: Expecting 4160000 events.
[09:35:23.265] <TB1> INFO: 971065 events read in total (38332ms).
[09:36:01.620] <TB1> INFO: 1934590 events read in total (76687ms).
[09:36:39.398] <TB1> INFO: 2885715 events read in total (114465ms).
[09:37:16.973] <TB1> INFO: 3835725 events read in total (152040ms).
[09:37:30.083] <TB1> INFO: 4160000 events read in total (165150ms).
[09:37:30.134] <TB1> INFO: Test took 166045ms.
[09:38:00.298] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 182 (-1/-1) hits flags = 528 (plus default)
[09:38:00.306] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:38:00.306] <TB1> INFO: run 1 of 1
[09:38:00.568] <TB1> INFO: Expecting 3806400 events.
[09:38:39.138] <TB1> INFO: 975335 events read in total (38013ms).
[09:39:16.798] <TB1> INFO: 1941315 events read in total (75673ms).
[09:39:54.945] <TB1> INFO: 2894135 events read in total (113820ms).
[09:40:31.894] <TB1> INFO: 3806400 events read in total (150769ms).
[09:40:31.962] <TB1> INFO: Test took 151656ms.
[09:41:02.080] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 166 (-1/-1) hits flags = 528 (plus default)
[09:41:02.088] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:41:02.088] <TB1> INFO: run 1 of 1
[09:41:02.372] <TB1> INFO: Expecting 3473600 events.
[09:41:41.888] <TB1> INFO: 1023110 events read in total (38960ms).
[09:42:20.536] <TB1> INFO: 2031140 events read in total (77608ms).
[09:42:59.642] <TB1> INFO: 3026865 events read in total (116714ms).
[09:43:17.336] <TB1> INFO: 3473600 events read in total (134408ms).
[09:43:17.384] <TB1> INFO: Test took 135296ms.
[09:43:43.670] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 166 (-1/-1) hits flags = 528 (plus default)
[09:43:43.679] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:43:43.679] <TB1> INFO: run 1 of 1
[09:43:43.942] <TB1> INFO: Expecting 3473600 events.
[09:44:23.443] <TB1> INFO: 1024020 events read in total (38944ms).
[09:45:02.157] <TB1> INFO: 2032340 events read in total (77658ms).
[09:45:40.958] <TB1> INFO: 3028620 events read in total (116459ms).
[09:45:58.603] <TB1> INFO: 3473600 events read in total (134104ms).
[09:45:58.654] <TB1> INFO: Test took 134976ms.
[09:46:23.787] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[09:46:23.795] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:46:23.795] <TB1> INFO: run 1 of 1
[09:46:24.055] <TB1> INFO: Expecting 3536000 events.
[09:47:03.488] <TB1> INFO: 1015260 events read in total (38877ms).
[09:47:42.082] <TB1> INFO: 2015495 events read in total (77471ms).
[09:48:20.745] <TB1> INFO: 3003700 events read in total (116134ms).
[09:48:42.024] <TB1> INFO: 3536000 events read in total (137413ms).
[09:48:42.075] <TB1> INFO: Test took 138281ms.
[09:49:10.061] <TB1> INFO: PixTestTrim::trimBitTest() done
[09:49:10.062] <TB1> INFO: PixTestTrim::doTest() done, duration: 2300 seconds
[09:49:10.063] <TB1> INFO: Decoding statistics:
[09:49:10.063] <TB1> INFO: General information:
[09:49:10.063] <TB1> INFO: 16bit words read: 0
[09:49:10.063] <TB1> INFO: valid events total: 0
[09:49:10.063] <TB1> INFO: empty events: 0
[09:49:10.063] <TB1> INFO: valid events with pixels: 0
[09:49:10.063] <TB1> INFO: valid pixel hits: 0
[09:49:10.063] <TB1> INFO: Event errors: 0
[09:49:10.063] <TB1> INFO: start marker: 0
[09:49:10.063] <TB1> INFO: stop marker: 0
[09:49:10.063] <TB1> INFO: overflow: 0
[09:49:10.063] <TB1> INFO: invalid 5bit words: 0
[09:49:10.063] <TB1> INFO: invalid XOR eye diagram: 0
[09:49:10.063] <TB1> INFO: frame (failed synchr.): 0
[09:49:10.063] <TB1> INFO: idle data (no TBM trl): 0
[09:49:10.063] <TB1> INFO: no data (only TBM hdr): 0
[09:49:10.063] <TB1> INFO: TBM errors: 0
[09:49:10.063] <TB1> INFO: flawed TBM headers: 0
[09:49:10.063] <TB1> INFO: flawed TBM trailers: 0
[09:49:10.063] <TB1> INFO: event ID mismatches: 0
[09:49:10.063] <TB1> INFO: ROC errors: 0
[09:49:10.063] <TB1> INFO: missing ROC header(s): 0
[09:49:10.063] <TB1> INFO: misplaced readback start: 0
[09:49:10.063] <TB1> INFO: Pixel decoding errors: 0
[09:49:10.063] <TB1> INFO: pixel data incomplete: 0
[09:49:10.063] <TB1> INFO: pixel address: 0
[09:49:10.063] <TB1> INFO: pulse height fill bit: 0
[09:49:10.063] <TB1> INFO: buffer corruption: 0
[09:49:10.868] <TB1> INFO: ######################################################################
[09:49:10.868] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[09:49:10.868] <TB1> INFO: ######################################################################
[09:49:11.137] <TB1> INFO: Expecting 41600 events.
[09:49:14.867] <TB1> INFO: 41600 events read in total (3174ms).
[09:49:14.868] <TB1> INFO: Test took 3996ms.
[09:49:15.393] <TB1> INFO: Expecting 41600 events.
[09:49:19.220] <TB1> INFO: 41600 events read in total (3271ms).
[09:49:19.220] <TB1> INFO: Test took 4169ms.
[09:49:19.577] <TB1> INFO: Expecting 41600 events.
[09:49:23.162] <TB1> INFO: 41600 events read in total (3029ms).
[09:49:23.163] <TB1> INFO: Test took 3915ms.
[09:49:23.424] <TB1> INFO: Expecting 2560 events.
[09:49:24.279] <TB1> INFO: 2560 events read in total (299ms).
[09:49:24.279] <TB1> INFO: Test took 1097ms.
[09:49:24.584] <TB1> INFO: Expecting 2560 events.
[09:49:25.436] <TB1> INFO: 2560 events read in total (296ms).
[09:49:25.436] <TB1> INFO: Test took 1157ms.
[09:49:25.740] <TB1> INFO: Expecting 2560 events.
[09:49:26.595] <TB1> INFO: 2560 events read in total (299ms).
[09:49:26.595] <TB1> INFO: Test took 1159ms.
[09:49:26.900] <TB1> INFO: Expecting 2560 events.
[09:49:27.754] <TB1> INFO: 2560 events read in total (298ms).
[09:49:27.755] <TB1> INFO: Test took 1159ms.
[09:49:28.060] <TB1> INFO: Expecting 2560 events.
[09:49:28.914] <TB1> INFO: 2560 events read in total (298ms).
[09:49:28.915] <TB1> INFO: Test took 1160ms.
[09:49:29.219] <TB1> INFO: Expecting 2560 events.
[09:49:30.071] <TB1> INFO: 2560 events read in total (295ms).
[09:49:30.071] <TB1> INFO: Test took 1156ms.
[09:49:30.376] <TB1> INFO: Expecting 2560 events.
[09:49:31.233] <TB1> INFO: 2560 events read in total (301ms).
[09:49:31.233] <TB1> INFO: Test took 1161ms.
[09:49:31.537] <TB1> INFO: Expecting 2560 events.
[09:49:32.395] <TB1> INFO: 2560 events read in total (301ms).
[09:49:32.395] <TB1> INFO: Test took 1162ms.
[09:49:32.699] <TB1> INFO: Expecting 2560 events.
[09:49:33.554] <TB1> INFO: 2560 events read in total (299ms).
[09:49:33.554] <TB1> INFO: Test took 1159ms.
[09:49:33.859] <TB1> INFO: Expecting 2560 events.
[09:49:34.711] <TB1> INFO: 2560 events read in total (296ms).
[09:49:34.711] <TB1> INFO: Test took 1157ms.
[09:49:35.016] <TB1> INFO: Expecting 2560 events.
[09:49:35.872] <TB1> INFO: 2560 events read in total (300ms).
[09:49:35.872] <TB1> INFO: Test took 1161ms.
[09:49:36.177] <TB1> INFO: Expecting 2560 events.
[09:49:37.032] <TB1> INFO: 2560 events read in total (299ms).
[09:49:37.032] <TB1> INFO: Test took 1159ms.
[09:49:37.336] <TB1> INFO: Expecting 2560 events.
[09:49:38.193] <TB1> INFO: 2560 events read in total (300ms).
[09:49:38.193] <TB1> INFO: Test took 1161ms.
[09:49:38.498] <TB1> INFO: Expecting 2560 events.
[09:49:39.353] <TB1> INFO: 2560 events read in total (299ms).
[09:49:39.353] <TB1> INFO: Test took 1159ms.
[09:49:39.657] <TB1> INFO: Expecting 2560 events.
[09:49:40.516] <TB1> INFO: 2560 events read in total (302ms).
[09:49:40.516] <TB1> INFO: Test took 1163ms.
[09:49:40.821] <TB1> INFO: Expecting 2560 events.
[09:49:41.676] <TB1> INFO: 2560 events read in total (299ms).
[09:49:41.677] <TB1> INFO: Test took 1160ms.
[09:49:41.680] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:49:41.982] <TB1> INFO: Expecting 655360 events.
[09:49:56.244] <TB1> INFO: 655360 events read in total (13706ms).
[09:49:56.255] <TB1> INFO: Expecting 655360 events.
[09:50:09.970] <TB1> INFO: 655360 events read in total (13313ms).
[09:50:09.987] <TB1> INFO: Expecting 655360 events.
[09:50:23.836] <TB1> INFO: 655360 events read in total (13448ms).
[09:50:23.856] <TB1> INFO: Expecting 655360 events.
[09:50:37.724] <TB1> INFO: 655360 events read in total (13466ms).
[09:50:37.747] <TB1> INFO: Expecting 655360 events.
[09:50:51.547] <TB1> INFO: 655360 events read in total (13398ms).
[09:50:51.575] <TB1> INFO: Expecting 655360 events.
[09:51:05.294] <TB1> INFO: 655360 events read in total (13317ms).
[09:51:05.324] <TB1> INFO: Expecting 655360 events.
[09:51:19.098] <TB1> INFO: 655360 events read in total (13372ms).
[09:51:19.133] <TB1> INFO: Expecting 655360 events.
[09:51:32.866] <TB1> INFO: 655360 events read in total (13331ms).
[09:51:32.906] <TB1> INFO: Expecting 655360 events.
[09:51:46.732] <TB1> INFO: 655360 events read in total (13424ms).
[09:51:46.780] <TB1> INFO: Expecting 655360 events.
[09:52:00.617] <TB1> INFO: 655360 events read in total (13435ms).
[09:52:00.665] <TB1> INFO: Expecting 655360 events.
[09:52:14.441] <TB1> INFO: 655360 events read in total (13374ms).
[09:52:14.502] <TB1> INFO: Expecting 655360 events.
[09:52:28.256] <TB1> INFO: 655360 events read in total (13353ms).
[09:52:28.314] <TB1> INFO: Expecting 655360 events.
[09:52:42.126] <TB1> INFO: 655360 events read in total (13410ms).
[09:52:42.196] <TB1> INFO: Expecting 655360 events.
[09:52:55.887] <TB1> INFO: 655360 events read in total (13289ms).
[09:52:55.950] <TB1> INFO: Expecting 655360 events.
[09:53:09.762] <TB1> INFO: 655360 events read in total (13410ms).
[09:53:09.844] <TB1> INFO: Expecting 655360 events.
[09:53:23.525] <TB1> INFO: 655360 events read in total (13279ms).
[09:53:23.593] <TB1> INFO: Test took 221913ms.
[09:53:23.672] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:53:23.901] <TB1> INFO: Expecting 655360 events.
[09:53:37.875] <TB1> INFO: 655360 events read in total (13418ms).
[09:53:37.886] <TB1> INFO: Expecting 655360 events.
[09:53:51.786] <TB1> INFO: 655360 events read in total (13498ms).
[09:53:51.800] <TB1> INFO: Expecting 655360 events.
[09:54:05.647] <TB1> INFO: 655360 events read in total (13445ms).
[09:54:05.665] <TB1> INFO: Expecting 655360 events.
[09:54:19.388] <TB1> INFO: 655360 events read in total (13321ms).
[09:54:19.410] <TB1> INFO: Expecting 655360 events.
[09:54:33.176] <TB1> INFO: 655360 events read in total (13365ms).
[09:54:33.204] <TB1> INFO: Expecting 655360 events.
[09:54:46.886] <TB1> INFO: 655360 events read in total (13280ms).
[09:54:46.921] <TB1> INFO: Expecting 655360 events.
[09:55:00.520] <TB1> INFO: 655360 events read in total (13198ms).
[09:55:00.555] <TB1> INFO: Expecting 655360 events.
[09:55:14.246] <TB1> INFO: 655360 events read in total (13289ms).
[09:55:14.287] <TB1> INFO: Expecting 655360 events.
[09:55:28.079] <TB1> INFO: 655360 events read in total (13390ms).
[09:55:28.122] <TB1> INFO: Expecting 655360 events.
[09:55:41.945] <TB1> INFO: 655360 events read in total (13421ms).
[09:55:41.993] <TB1> INFO: Expecting 655360 events.
[09:55:55.821] <TB1> INFO: 655360 events read in total (13426ms).
[09:55:55.877] <TB1> INFO: Expecting 655360 events.
[09:56:09.602] <TB1> INFO: 655360 events read in total (13323ms).
[09:56:09.663] <TB1> INFO: Expecting 655360 events.
[09:56:23.494] <TB1> INFO: 655360 events read in total (13430ms).
[09:56:23.558] <TB1> INFO: Expecting 655360 events.
[09:56:37.315] <TB1> INFO: 655360 events read in total (13355ms).
[09:56:37.381] <TB1> INFO: Expecting 655360 events.
[09:56:51.102] <TB1> INFO: 655360 events read in total (13320ms).
[09:56:51.175] <TB1> INFO: Expecting 655360 events.
[09:57:04.902] <TB1> INFO: 655360 events read in total (13325ms).
[09:57:04.976] <TB1> INFO: Test took 221304ms.
[09:57:05.166] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.173] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.185] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[09:57:05.192] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[09:57:05.198] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[09:57:05.205] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[09:57:05.211] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[09:57:05.218] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[09:57:05.224] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[09:57:05.231] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[09:57:05.238] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.244] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.251] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[09:57:05.257] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[09:57:05.264] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[09:57:05.271] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[09:57:05.277] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[09:57:05.284] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[09:57:05.291] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.297] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[09:57:05.304] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[09:57:05.310] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[09:57:05.317] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[09:57:05.323] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[09:57:05.330] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[09:57:05.337] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.343] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.350] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.357] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[09:57:05.364] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[09:57:05.370] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[09:57:05.377] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[09:57:05.383] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.390] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.397] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.403] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.410] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.416] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.423] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.430] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:05.470] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:57:05.470] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:57:05.470] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:57:05.470] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:57:05.470] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:57:05.470] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:57:05.470] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:57:05.470] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:57:05.470] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:57:05.470] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:57:05.471] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:57:05.471] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:57:05.471] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:57:05.471] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:57:05.493] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:57:05.493] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:57:05.757] <TB1> INFO: Expecting 41600 events.
[09:57:08.900] <TB1> INFO: 41600 events read in total (2586ms).
[09:57:08.901] <TB1> INFO: Test took 3405ms.
[09:57:09.352] <TB1> INFO: Expecting 41600 events.
[09:57:12.424] <TB1> INFO: 41600 events read in total (2516ms).
[09:57:12.425] <TB1> INFO: Test took 3344ms.
[09:57:12.871] <TB1> INFO: Expecting 41600 events.
[09:57:15.996] <TB1> INFO: 41600 events read in total (2569ms).
[09:57:15.997] <TB1> INFO: Test took 3392ms.
[09:57:16.181] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:16.270] <TB1> INFO: Expecting 2560 events.
[09:57:17.123] <TB1> INFO: 2560 events read in total (297ms).
[09:57:17.123] <TB1> INFO: Test took 942ms.
[09:57:17.125] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:17.428] <TB1> INFO: Expecting 2560 events.
[09:57:18.282] <TB1> INFO: 2560 events read in total (297ms).
[09:57:18.282] <TB1> INFO: Test took 1157ms.
[09:57:18.284] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:18.588] <TB1> INFO: Expecting 2560 events.
[09:57:19.440] <TB1> INFO: 2560 events read in total (296ms).
[09:57:19.440] <TB1> INFO: Test took 1156ms.
[09:57:19.441] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:19.745] <TB1> INFO: Expecting 2560 events.
[09:57:20.597] <TB1> INFO: 2560 events read in total (296ms).
[09:57:20.597] <TB1> INFO: Test took 1156ms.
[09:57:20.599] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:20.903] <TB1> INFO: Expecting 2560 events.
[09:57:21.757] <TB1> INFO: 2560 events read in total (298ms).
[09:57:21.757] <TB1> INFO: Test took 1158ms.
[09:57:21.759] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:22.063] <TB1> INFO: Expecting 2560 events.
[09:57:22.915] <TB1> INFO: 2560 events read in total (296ms).
[09:57:22.915] <TB1> INFO: Test took 1156ms.
[09:57:22.918] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:23.222] <TB1> INFO: Expecting 2560 events.
[09:57:24.074] <TB1> INFO: 2560 events read in total (296ms).
[09:57:24.074] <TB1> INFO: Test took 1156ms.
[09:57:24.077] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:24.379] <TB1> INFO: Expecting 2560 events.
[09:57:25.232] <TB1> INFO: 2560 events read in total (296ms).
[09:57:25.232] <TB1> INFO: Test took 1155ms.
[09:57:25.235] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:25.538] <TB1> INFO: Expecting 2560 events.
[09:57:26.392] <TB1> INFO: 2560 events read in total (298ms).
[09:57:26.393] <TB1> INFO: Test took 1158ms.
[09:57:26.396] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:26.696] <TB1> INFO: Expecting 2560 events.
[09:57:27.549] <TB1> INFO: 2560 events read in total (296ms).
[09:57:27.550] <TB1> INFO: Test took 1154ms.
[09:57:27.552] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:27.854] <TB1> INFO: Expecting 2560 events.
[09:57:28.706] <TB1> INFO: 2560 events read in total (296ms).
[09:57:28.706] <TB1> INFO: Test took 1154ms.
[09:57:28.708] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:29.011] <TB1> INFO: Expecting 2560 events.
[09:57:29.863] <TB1> INFO: 2560 events read in total (296ms).
[09:57:29.864] <TB1> INFO: Test took 1157ms.
[09:57:29.865] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:30.169] <TB1> INFO: Expecting 2560 events.
[09:57:31.023] <TB1> INFO: 2560 events read in total (298ms).
[09:57:31.023] <TB1> INFO: Test took 1158ms.
[09:57:31.025] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:31.328] <TB1> INFO: Expecting 2560 events.
[09:57:32.185] <TB1> INFO: 2560 events read in total (301ms).
[09:57:32.185] <TB1> INFO: Test took 1160ms.
[09:57:32.187] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:32.490] <TB1> INFO: Expecting 2560 events.
[09:57:33.343] <TB1> INFO: 2560 events read in total (297ms).
[09:57:33.343] <TB1> INFO: Test took 1156ms.
[09:57:33.344] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:33.648] <TB1> INFO: Expecting 2560 events.
[09:57:34.505] <TB1> INFO: 2560 events read in total (300ms).
[09:57:34.505] <TB1> INFO: Test took 1162ms.
[09:57:34.507] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:34.811] <TB1> INFO: Expecting 2560 events.
[09:57:35.664] <TB1> INFO: 2560 events read in total (297ms).
[09:57:35.664] <TB1> INFO: Test took 1157ms.
[09:57:35.665] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:35.970] <TB1> INFO: Expecting 2560 events.
[09:57:36.831] <TB1> INFO: 2560 events read in total (305ms).
[09:57:36.832] <TB1> INFO: Test took 1167ms.
[09:57:36.834] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:37.136] <TB1> INFO: Expecting 2560 events.
[09:57:37.995] <TB1> INFO: 2560 events read in total (302ms).
[09:57:37.995] <TB1> INFO: Test took 1161ms.
[09:57:37.998] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:38.300] <TB1> INFO: Expecting 2560 events.
[09:57:39.156] <TB1> INFO: 2560 events read in total (300ms).
[09:57:39.157] <TB1> INFO: Test took 1159ms.
[09:57:39.160] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:39.461] <TB1> INFO: Expecting 2560 events.
[09:57:40.322] <TB1> INFO: 2560 events read in total (305ms).
[09:57:40.322] <TB1> INFO: Test took 1163ms.
[09:57:40.324] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:40.627] <TB1> INFO: Expecting 2560 events.
[09:57:41.485] <TB1> INFO: 2560 events read in total (301ms).
[09:57:41.486] <TB1> INFO: Test took 1162ms.
[09:57:41.489] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:41.790] <TB1> INFO: Expecting 2560 events.
[09:57:42.642] <TB1> INFO: 2560 events read in total (295ms).
[09:57:42.642] <TB1> INFO: Test took 1154ms.
[09:57:42.644] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:42.948] <TB1> INFO: Expecting 2560 events.
[09:57:43.803] <TB1> INFO: 2560 events read in total (299ms).
[09:57:43.804] <TB1> INFO: Test took 1160ms.
[09:57:43.808] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:44.108] <TB1> INFO: Expecting 2560 events.
[09:57:44.962] <TB1> INFO: 2560 events read in total (297ms).
[09:57:44.962] <TB1> INFO: Test took 1154ms.
[09:57:44.964] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:45.268] <TB1> INFO: Expecting 2560 events.
[09:57:46.122] <TB1> INFO: 2560 events read in total (297ms).
[09:57:46.122] <TB1> INFO: Test took 1158ms.
[09:57:46.124] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:46.427] <TB1> INFO: Expecting 2560 events.
[09:57:47.285] <TB1> INFO: 2560 events read in total (301ms).
[09:57:47.285] <TB1> INFO: Test took 1161ms.
[09:57:47.288] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:47.590] <TB1> INFO: Expecting 2560 events.
[09:57:48.442] <TB1> INFO: 2560 events read in total (296ms).
[09:57:48.443] <TB1> INFO: Test took 1155ms.
[09:57:48.445] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:48.749] <TB1> INFO: Expecting 2560 events.
[09:57:49.601] <TB1> INFO: 2560 events read in total (296ms).
[09:57:49.602] <TB1> INFO: Test took 1157ms.
[09:57:49.603] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:49.907] <TB1> INFO: Expecting 2560 events.
[09:57:50.762] <TB1> INFO: 2560 events read in total (299ms).
[09:57:50.762] <TB1> INFO: Test took 1159ms.
[09:57:50.764] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:51.068] <TB1> INFO: Expecting 2560 events.
[09:57:51.924] <TB1> INFO: 2560 events read in total (301ms).
[09:57:51.925] <TB1> INFO: Test took 1161ms.
[09:57:51.927] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:52.231] <TB1> INFO: Expecting 2560 events.
[09:57:53.091] <TB1> INFO: 2560 events read in total (304ms).
[09:57:53.092] <TB1> INFO: Test took 1166ms.
[09:57:53.570] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 522 seconds
[09:57:53.570] <TB1> INFO: PH scale (per ROC): 73 80 75 79 78 79 81 80 75 77 77 75 74 79 70 75
[09:57:53.570] <TB1> INFO: PH offset (per ROC): 173 176 177 176 176 168 170 158 174 172 166 175 163 181 173 180
[09:57:53.574] <TB1> INFO: Decoding statistics:
[09:57:53.574] <TB1> INFO: General information:
[09:57:53.574] <TB1> INFO: 16bit words read: 86918
[09:57:53.574] <TB1> INFO: valid events total: 10240
[09:57:53.574] <TB1> INFO: empty events: 7741
[09:57:53.574] <TB1> INFO: valid events with pixels: 2499
[09:57:53.574] <TB1> INFO: valid pixel hits: 2499
[09:57:53.574] <TB1> INFO: Event errors: 0
[09:57:53.574] <TB1> INFO: start marker: 0
[09:57:53.574] <TB1> INFO: stop marker: 0
[09:57:53.574] <TB1> INFO: overflow: 0
[09:57:53.574] <TB1> INFO: invalid 5bit words: 0
[09:57:53.574] <TB1> INFO: invalid XOR eye diagram: 0
[09:57:53.575] <TB1> INFO: frame (failed synchr.): 0
[09:57:53.575] <TB1> INFO: idle data (no TBM trl): 0
[09:57:53.575] <TB1> INFO: no data (only TBM hdr): 0
[09:57:53.575] <TB1> INFO: TBM errors: 0
[09:57:53.575] <TB1> INFO: flawed TBM headers: 0
[09:57:53.575] <TB1> INFO: flawed TBM trailers: 0
[09:57:53.575] <TB1> INFO: event ID mismatches: 0
[09:57:53.575] <TB1> INFO: ROC errors: 0
[09:57:53.575] <TB1> INFO: missing ROC header(s): 0
[09:57:53.575] <TB1> INFO: misplaced readback start: 0
[09:57:53.575] <TB1> INFO: Pixel decoding errors: 0
[09:57:53.575] <TB1> INFO: pixel data incomplete: 0
[09:57:53.575] <TB1> INFO: pixel address: 0
[09:57:53.575] <TB1> INFO: pulse height fill bit: 0
[09:57:53.575] <TB1> INFO: buffer corruption: 0
[09:57:53.761] <TB1> INFO: ######################################################################
[09:57:53.761] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[09:57:53.761] <TB1> INFO: ######################################################################
[09:57:53.771] <TB1> INFO: scanning low vcal = 10
[09:57:54.049] <TB1> INFO: Expecting 41600 events.
[09:57:57.435] <TB1> INFO: 41600 events read in total (2829ms).
[09:57:57.435] <TB1> INFO: Test took 3664ms.
[09:57:57.437] <TB1> INFO: scanning low vcal = 20
[09:57:57.726] <TB1> INFO: Expecting 41600 events.
[09:58:01.116] <TB1> INFO: 41600 events read in total (2834ms).
[09:58:01.116] <TB1> INFO: Test took 3679ms.
[09:58:01.117] <TB1> INFO: scanning low vcal = 30
[09:58:01.414] <TB1> INFO: Expecting 41600 events.
[09:58:04.836] <TB1> INFO: 41600 events read in total (2865ms).
[09:58:04.837] <TB1> INFO: Test took 3720ms.
[09:58:04.838] <TB1> INFO: scanning low vcal = 40
[09:58:05.124] <TB1> INFO: Expecting 41600 events.
[09:58:08.932] <TB1> INFO: 41600 events read in total (3251ms).
[09:58:08.933] <TB1> INFO: Test took 4095ms.
[09:58:08.936] <TB1> INFO: scanning low vcal = 50
[09:58:09.201] <TB1> INFO: Expecting 41600 events.
[09:58:13.203] <TB1> INFO: 41600 events read in total (3445ms).
[09:58:13.204] <TB1> INFO: Test took 4268ms.
[09:58:13.207] <TB1> INFO: scanning low vcal = 60
[09:58:13.488] <TB1> INFO: Expecting 41600 events.
[09:58:17.478] <TB1> INFO: 41600 events read in total (3434ms).
[09:58:17.479] <TB1> INFO: Test took 4271ms.
[09:58:17.488] <TB1> INFO: scanning low vcal = 70
[09:58:17.776] <TB1> INFO: Expecting 41600 events.
[09:58:21.749] <TB1> INFO: 41600 events read in total (3417ms).
[09:58:21.750] <TB1> INFO: Test took 4262ms.
[09:58:21.754] <TB1> INFO: scanning low vcal = 80
[09:58:22.016] <TB1> INFO: Expecting 41600 events.
[09:58:25.976] <TB1> INFO: 41600 events read in total (3404ms).
[09:58:25.977] <TB1> INFO: Test took 4223ms.
[09:58:25.979] <TB1> INFO: scanning low vcal = 90
[09:58:26.245] <TB1> INFO: Expecting 41600 events.
[09:58:30.186] <TB1> INFO: 41600 events read in total (3385ms).
[09:58:30.187] <TB1> INFO: Test took 4208ms.
[09:58:30.190] <TB1> INFO: scanning low vcal = 100
[09:58:30.451] <TB1> INFO: Expecting 41600 events.
[09:58:34.403] <TB1> INFO: 41600 events read in total (3395ms).
[09:58:34.404] <TB1> INFO: Test took 4214ms.
[09:58:34.406] <TB1> INFO: scanning low vcal = 110
[09:58:34.670] <TB1> INFO: Expecting 41600 events.
[09:58:38.629] <TB1> INFO: 41600 events read in total (3403ms).
[09:58:38.630] <TB1> INFO: Test took 4224ms.
[09:58:38.642] <TB1> INFO: scanning low vcal = 120
[09:58:38.920] <TB1> INFO: Expecting 41600 events.
[09:58:42.884] <TB1> INFO: 41600 events read in total (3407ms).
[09:58:42.884] <TB1> INFO: Test took 4241ms.
[09:58:42.887] <TB1> INFO: scanning low vcal = 130
[09:58:43.161] <TB1> INFO: Expecting 41600 events.
[09:58:47.122] <TB1> INFO: 41600 events read in total (3405ms).
[09:58:47.123] <TB1> INFO: Test took 4236ms.
[09:58:47.125] <TB1> INFO: scanning low vcal = 140
[09:58:47.410] <TB1> INFO: Expecting 41600 events.
[09:58:51.354] <TB1> INFO: 41600 events read in total (3388ms).
[09:58:51.354] <TB1> INFO: Test took 4229ms.
[09:58:51.357] <TB1> INFO: scanning low vcal = 150
[09:58:51.619] <TB1> INFO: Expecting 41600 events.
[09:58:55.579] <TB1> INFO: 41600 events read in total (3403ms).
[09:58:55.580] <TB1> INFO: Test took 4223ms.
[09:58:55.583] <TB1> INFO: scanning low vcal = 160
[09:58:55.843] <TB1> INFO: Expecting 41600 events.
[09:58:59.822] <TB1> INFO: 41600 events read in total (3423ms).
[09:58:59.822] <TB1> INFO: Test took 4239ms.
[09:58:59.825] <TB1> INFO: scanning low vcal = 170
[09:59:00.110] <TB1> INFO: Expecting 41600 events.
[09:59:04.052] <TB1> INFO: 41600 events read in total (3386ms).
[09:59:04.052] <TB1> INFO: Test took 4227ms.
[09:59:04.056] <TB1> INFO: scanning low vcal = 180
[09:59:04.319] <TB1> INFO: Expecting 41600 events.
[09:59:08.282] <TB1> INFO: 41600 events read in total (3406ms).
[09:59:08.283] <TB1> INFO: Test took 4227ms.
[09:59:08.285] <TB1> INFO: scanning low vcal = 190
[09:59:08.549] <TB1> INFO: Expecting 41600 events.
[09:59:12.530] <TB1> INFO: 41600 events read in total (3424ms).
[09:59:12.532] <TB1> INFO: Test took 4247ms.
[09:59:12.546] <TB1> INFO: scanning low vcal = 200
[09:59:12.809] <TB1> INFO: Expecting 41600 events.
[09:59:16.770] <TB1> INFO: 41600 events read in total (3405ms).
[09:59:16.772] <TB1> INFO: Test took 4226ms.
[09:59:16.775] <TB1> INFO: scanning low vcal = 210
[09:59:17.063] <TB1> INFO: Expecting 41600 events.
[09:59:21.031] <TB1> INFO: 41600 events read in total (3412ms).
[09:59:21.033] <TB1> INFO: Test took 4258ms.
[09:59:21.035] <TB1> INFO: scanning low vcal = 220
[09:59:21.295] <TB1> INFO: Expecting 41600 events.
[09:59:25.301] <TB1> INFO: 41600 events read in total (3449ms).
[09:59:25.302] <TB1> INFO: Test took 4267ms.
[09:59:25.305] <TB1> INFO: scanning low vcal = 230
[09:59:25.582] <TB1> INFO: Expecting 41600 events.
[09:59:29.576] <TB1> INFO: 41600 events read in total (3438ms).
[09:59:29.578] <TB1> INFO: Test took 4273ms.
[09:59:29.590] <TB1> INFO: scanning low vcal = 240
[09:59:29.854] <TB1> INFO: Expecting 41600 events.
[09:59:33.858] <TB1> INFO: 41600 events read in total (3448ms).
[09:59:33.859] <TB1> INFO: Test took 4268ms.
[09:59:33.862] <TB1> INFO: scanning low vcal = 250
[09:59:34.147] <TB1> INFO: Expecting 41600 events.
[09:59:38.096] <TB1> INFO: 41600 events read in total (3393ms).
[09:59:38.098] <TB1> INFO: Test took 4235ms.
[09:59:38.102] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[09:59:38.366] <TB1> INFO: Expecting 41600 events.
[09:59:42.348] <TB1> INFO: 41600 events read in total (3426ms).
[09:59:42.350] <TB1> INFO: Test took 4248ms.
[09:59:42.352] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[09:59:42.616] <TB1> INFO: Expecting 41600 events.
[09:59:46.590] <TB1> INFO: 41600 events read in total (3417ms).
[09:59:46.592] <TB1> INFO: Test took 4240ms.
[09:59:46.604] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[09:59:46.889] <TB1> INFO: Expecting 41600 events.
[09:59:50.826] <TB1> INFO: 41600 events read in total (3381ms).
[09:59:50.827] <TB1> INFO: Test took 4223ms.
[09:59:50.830] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[09:59:51.093] <TB1> INFO: Expecting 41600 events.
[09:59:55.037] <TB1> INFO: 41600 events read in total (3387ms).
[09:59:55.039] <TB1> INFO: Test took 4209ms.
[09:59:55.042] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[09:59:55.328] <TB1> INFO: Expecting 41600 events.
[09:59:59.308] <TB1> INFO: 41600 events read in total (3423ms).
[09:59:59.309] <TB1> INFO: Test took 4267ms.
[09:59:59.868] <TB1> INFO: PixTestGainPedestal::measure() done
[10:00:33.030] <TB1> INFO: PixTestGainPedestal::fit() done
[10:00:33.030] <TB1> INFO: non-linearity mean: 0.952 0.966 0.959 0.960 0.955 0.953 0.954 0.960 0.955 0.956 0.951 0.959 0.951 0.960 0.949 0.954
[10:00:33.030] <TB1> INFO: non-linearity RMS: 0.009 0.004 0.006 0.004 0.005 0.008 0.006 0.005 0.008 0.006 0.008 0.008 0.007 0.007 0.008 0.007
[10:00:33.030] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[10:00:33.051] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[10:00:33.072] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[10:00:33.092] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[10:00:33.112] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[10:00:33.131] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[10:00:33.150] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[10:00:33.168] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[10:00:33.187] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[10:00:33.206] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[10:00:33.225] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[10:00:33.244] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[10:00:33.262] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[10:00:33.283] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[10:00:33.303] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[10:00:33.322] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[10:00:33.341] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 159 seconds
[10:00:33.341] <TB1> INFO: Decoding statistics:
[10:00:33.341] <TB1> INFO: General information:
[10:00:33.341] <TB1> INFO: 16bit words read: 2662362
[10:00:33.341] <TB1> INFO: valid events total: 166400
[10:00:33.341] <TB1> INFO: empty events: 0
[10:00:33.341] <TB1> INFO: valid events with pixels: 166400
[10:00:33.341] <TB1> INFO: valid pixel hits: 665581
[10:00:33.341] <TB1> INFO: Event errors: 0
[10:00:33.341] <TB1> INFO: start marker: 0
[10:00:33.341] <TB1> INFO: stop marker: 0
[10:00:33.341] <TB1> INFO: overflow: 0
[10:00:33.341] <TB1> INFO: invalid 5bit words: 0
[10:00:33.341] <TB1> INFO: invalid XOR eye diagram: 0
[10:00:33.341] <TB1> INFO: frame (failed synchr.): 0
[10:00:33.341] <TB1> INFO: idle data (no TBM trl): 0
[10:00:33.341] <TB1> INFO: no data (only TBM hdr): 0
[10:00:33.341] <TB1> INFO: TBM errors: 0
[10:00:33.341] <TB1> INFO: flawed TBM headers: 0
[10:00:33.341] <TB1> INFO: flawed TBM trailers: 0
[10:00:33.341] <TB1> INFO: event ID mismatches: 0
[10:00:33.341] <TB1> INFO: ROC errors: 0
[10:00:33.341] <TB1> INFO: missing ROC header(s): 0
[10:00:33.341] <TB1> INFO: misplaced readback start: 0
[10:00:33.341] <TB1> INFO: Pixel decoding errors: 0
[10:00:33.341] <TB1> INFO: pixel data incomplete: 0
[10:00:33.341] <TB1> INFO: pixel address: 0
[10:00:33.341] <TB1> INFO: pulse height fill bit: 0
[10:00:33.341] <TB1> INFO: buffer corruption: 0
[10:00:33.349] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C15.dat
[10:00:33.395] <TB1> INFO: ######################################################################
[10:00:33.395] <TB1> INFO: PixTestReadback::doTest()
[10:00:33.395] <TB1> INFO: ######################################################################
[10:00:33.395] <TB1> INFO: ----------------------------------------------------------------------
[10:00:33.395] <TB1> INFO: PixTestReadback::CalibrateVd()
[10:00:33.395] <TB1> INFO: ----------------------------------------------------------------------
[10:00:42.887] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C0.dat
[10:00:42.887] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C1.dat
[10:00:42.887] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C2.dat
[10:00:42.887] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C3.dat
[10:00:42.887] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C4.dat
[10:00:42.887] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C5.dat
[10:00:42.887] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C6.dat
[10:00:42.887] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C7.dat
[10:00:42.888] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C8.dat
[10:00:42.888] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C9.dat
[10:00:42.888] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C10.dat
[10:00:42.888] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C11.dat
[10:00:42.888] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C12.dat
[10:00:42.888] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C13.dat
[10:00:42.888] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C14.dat
[10:00:42.888] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C15.dat
[10:00:42.920] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[10:00:42.921] <TB1> INFO: ----------------------------------------------------------------------
[10:00:42.921] <TB1> INFO: PixTestReadback::CalibrateVa()
[10:00:42.921] <TB1> INFO: ----------------------------------------------------------------------
[10:00:53.696] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C0.dat
[10:00:53.706] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C1.dat
[10:00:53.706] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C2.dat
[10:00:53.706] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C3.dat
[10:00:53.706] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C4.dat
[10:00:53.706] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C5.dat
[10:00:53.706] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C6.dat
[10:00:53.706] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C7.dat
[10:00:53.707] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C8.dat
[10:00:53.707] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C9.dat
[10:00:53.707] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C10.dat
[10:00:53.707] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C11.dat
[10:00:53.707] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C12.dat
[10:00:53.707] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C13.dat
[10:00:53.707] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C14.dat
[10:00:53.707] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C15.dat
[10:00:53.739] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[10:00:53.739] <TB1> INFO: ----------------------------------------------------------------------
[10:00:53.739] <TB1> INFO: PixTestReadback::readbackVbg()
[10:00:53.739] <TB1> INFO: ----------------------------------------------------------------------
[10:01:01.059] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[10:01:01.059] <TB1> INFO: ----------------------------------------------------------------------
[10:01:01.059] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[10:01:01.059] <TB1> INFO: ----------------------------------------------------------------------
[10:01:01.059] <TB1> INFO: Vbg will be calibrated using Vd calibration
[10:01:01.059] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 149.7calibrated Vbg = 1.20144 :::*/*/*/*/
[10:01:01.059] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 155calibrated Vbg = 1.20027 :::*/*/*/*/
[10:01:01.059] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.5calibrated Vbg = 1.20344 :::*/*/*/*/
[10:01:01.059] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 151.5calibrated Vbg = 1.20202 :::*/*/*/*/
[10:01:01.059] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 148.3calibrated Vbg = 1.21613 :::*/*/*/*/
[10:01:01.059] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152.3calibrated Vbg = 1.20698 :::*/*/*/*/
[10:01:01.059] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 154.2calibrated Vbg = 1.21562 :::*/*/*/*/
[10:01:01.059] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 155.8calibrated Vbg = 1.21022 :::*/*/*/*/
[10:01:01.059] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.8calibrated Vbg = 1.20998 :::*/*/*/*/
[10:01:01.059] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 146.5calibrated Vbg = 1.20744 :::*/*/*/*/
[10:01:01.060] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 151calibrated Vbg = 1.21193 :::*/*/*/*/
[10:01:01.060] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 143.3calibrated Vbg = 1.20381 :::*/*/*/*/
[10:01:01.060] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 149.3calibrated Vbg = 1.19838 :::*/*/*/*/
[10:01:01.060] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 141.7calibrated Vbg = 1.19685 :::*/*/*/*/
[10:01:01.060] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 147.1calibrated Vbg = 1.20317 :::*/*/*/*/
[10:01:01.060] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 155.8calibrated Vbg = 1.20276 :::*/*/*/*/
[10:01:01.062] <TB1> INFO: ----------------------------------------------------------------------
[10:01:01.062] <TB1> INFO: PixTestReadback::CalibrateIa()
[10:01:01.062] <TB1> INFO: ----------------------------------------------------------------------
[10:03:36.404] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C0.dat
[10:03:36.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C1.dat
[10:03:36.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C2.dat
[10:03:36.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C3.dat
[10:03:36.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C4.dat
[10:03:36.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C5.dat
[10:03:36.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C6.dat
[10:03:36.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C7.dat
[10:03:36.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C8.dat
[10:03:36.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C9.dat
[10:03:36.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C10.dat
[10:03:36.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C11.dat
[10:03:36.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C12.dat
[10:03:36.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C13.dat
[10:03:36.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C14.dat
[10:03:36.406] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C15.dat
[10:03:36.431] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[10:03:36.433] <TB1> INFO: PixTestReadback::doTest() done
[10:03:36.433] <TB1> INFO: Decoding statistics:
[10:03:36.433] <TB1> INFO: General information:
[10:03:36.433] <TB1> INFO: 16bit words read: 1024
[10:03:36.433] <TB1> INFO: valid events total: 128
[10:03:36.433] <TB1> INFO: empty events: 128
[10:03:36.433] <TB1> INFO: valid events with pixels: 0
[10:03:36.433] <TB1> INFO: valid pixel hits: 0
[10:03:36.433] <TB1> INFO: Event errors: 0
[10:03:36.433] <TB1> INFO: start marker: 0
[10:03:36.433] <TB1> INFO: stop marker: 0
[10:03:36.433] <TB1> INFO: overflow: 0
[10:03:36.433] <TB1> INFO: invalid 5bit words: 0
[10:03:36.433] <TB1> INFO: invalid XOR eye diagram: 0
[10:03:36.433] <TB1> INFO: frame (failed synchr.): 0
[10:03:36.433] <TB1> INFO: idle data (no TBM trl): 0
[10:03:36.433] <TB1> INFO: no data (only TBM hdr): 0
[10:03:36.433] <TB1> INFO: TBM errors: 0
[10:03:36.433] <TB1> INFO: flawed TBM headers: 0
[10:03:36.433] <TB1> INFO: flawed TBM trailers: 0
[10:03:36.433] <TB1> INFO: event ID mismatches: 0
[10:03:36.433] <TB1> INFO: ROC errors: 0
[10:03:36.433] <TB1> INFO: missing ROC header(s): 0
[10:03:36.433] <TB1> INFO: misplaced readback start: 0
[10:03:36.433] <TB1> INFO: Pixel decoding errors: 0
[10:03:36.433] <TB1> INFO: pixel data incomplete: 0
[10:03:36.433] <TB1> INFO: pixel address: 0
[10:03:36.433] <TB1> INFO: pulse height fill bit: 0
[10:03:36.433] <TB1> INFO: buffer corruption: 0
[10:03:36.459] <TB1> INFO: Decoding statistics:
[10:03:36.459] <TB1> INFO: General information:
[10:03:36.459] <TB1> INFO: 16bit words read: 2750304
[10:03:36.459] <TB1> INFO: valid events total: 176768
[10:03:36.459] <TB1> INFO: empty events: 7869
[10:03:36.459] <TB1> INFO: valid events with pixels: 168899
[10:03:36.459] <TB1> INFO: valid pixel hits: 668080
[10:03:36.459] <TB1> INFO: Event errors: 0
[10:03:36.459] <TB1> INFO: start marker: 0
[10:03:36.459] <TB1> INFO: stop marker: 0
[10:03:36.459] <TB1> INFO: overflow: 0
[10:03:36.459] <TB1> INFO: invalid 5bit words: 0
[10:03:36.459] <TB1> INFO: invalid XOR eye diagram: 0
[10:03:36.459] <TB1> INFO: frame (failed synchr.): 0
[10:03:36.459] <TB1> INFO: idle data (no TBM trl): 0
[10:03:36.459] <TB1> INFO: no data (only TBM hdr): 0
[10:03:36.459] <TB1> INFO: TBM errors: 0
[10:03:36.459] <TB1> INFO: flawed TBM headers: 0
[10:03:36.459] <TB1> INFO: flawed TBM trailers: 0
[10:03:36.459] <TB1> INFO: event ID mismatches: 0
[10:03:36.459] <TB1> INFO: ROC errors: 0
[10:03:36.459] <TB1> INFO: missing ROC header(s): 0
[10:03:36.459] <TB1> INFO: misplaced readback start: 0
[10:03:36.459] <TB1> INFO: Pixel decoding errors: 0
[10:03:36.459] <TB1> INFO: pixel data incomplete: 0
[10:03:36.459] <TB1> INFO: pixel address: 0
[10:03:36.459] <TB1> INFO: pulse height fill bit: 0
[10:03:36.459] <TB1> INFO: buffer corruption: 0
[10:03:36.459] <TB1> INFO: enter test to run
[10:03:36.459] <TB1> INFO: test: exit no parameter change
[10:03:36.621] <TB1> QUIET: Connection to board 153 closed.
[10:03:36.701] <TB1> INFO: pXar: this is the end, my friend