[12:18:06.093] <TB3> INFO: *** Welcome to pxar ***
[12:18:06.093] <TB3> INFO: *** Today: 2016/09/08
[12:18:06.187] <TB3> INFO: *** Version: fe94
[12:18:06.187] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C15.dat
[12:18:06.189] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//tbmParameters_C0b.dat
[12:18:06.189] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//defaultMaskFile.dat
[12:18:06.189] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters_C15.dat
[12:18:06.272] <TB3> INFO: clk: 4
[12:18:06.272] <TB3> INFO: ctr: 4
[12:18:06.272] <TB3> INFO: sda: 19
[12:18:06.272] <TB3> INFO: tin: 9
[12:18:06.272] <TB3> INFO: level: 15
[12:18:06.272] <TB3> INFO: triggerdelay: 0
[12:18:06.272] <TB3> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[12:18:06.272] <TB3> INFO: Log level: INFO
[12:18:06.279] <TB3> INFO: Found DTB DTB_WZ4I6J
[12:18:06.288] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[12:18:06.290] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[12:18:06.291] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[12:18:07.834] <TB3> INFO: DUT info:
[12:18:07.834] <TB3> INFO: The DUT currently contains the following objects:
[12:18:07.834] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[12:18:07.834] <TB3> INFO: TBM Core alpha (0): 7 registers set
[12:18:07.834] <TB3> INFO: TBM Core beta (1): 7 registers set
[12:18:07.834] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:18:07.834] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.834] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.834] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.834] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.834] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.834] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.834] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.834] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.834] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.834] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.835] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.835] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.835] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.835] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.835] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:07.835] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:18:08.236] <TB3> INFO: enter 'restricted' command line mode
[12:18:08.236] <TB3> INFO: enter test to run
[12:18:08.236] <TB3> INFO: test: pretest no parameter change
[12:18:08.236] <TB3> INFO: running: pretest
[12:18:08.240] <TB3> INFO: ----------------------------------------------------------------------
[12:18:08.240] <TB3> INFO: PixTestPretest::programROC()
[12:18:08.240] <TB3> INFO: ----------------------------------------------------------------------
[12:18:26.254] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:18:26.254] <TB3> INFO: IA differences per ROC: 18.5 20.1 18.5 21.7 20.1 20.1 20.1 17.7 19.3 19.3 20.9 21.7 19.3 19.3 18.5 20.1
[12:18:26.350] <TB3> INFO: enter test to run
[12:18:26.350] <TB3> INFO: test: pretest no parameter change
[12:18:26.350] <TB3> INFO: running: pretest
[12:18:26.351] <TB3> INFO: ----------------------------------------------------------------------
[12:18:26.351] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:18:26.351] <TB3> INFO: ----------------------------------------------------------------------
[12:18:32.032] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 377.8 mA = 23.6125 mA/ROC
[12:18:32.032] <TB3> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 18.5 19.3 18.5 19.3 19.3 18.5 19.3 19.3 19.3 18.5 19.3 18.5
[12:18:32.066] <TB3> INFO: enter test to run
[12:18:32.066] <TB3> INFO: test: pretest no parameter change
[12:18:32.066] <TB3> INFO: running: pretest
[12:18:32.066] <TB3> INFO: ----------------------------------------------------------------------
[12:18:32.066] <TB3> INFO: PixTestPretest::findTiming()
[12:18:32.066] <TB3> INFO: ----------------------------------------------------------------------
[12:18:32.066] <TB3> INFO: PixTestCmd::init()

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


[12:20:11.094] <TB3> INFO: 231680 events read in total (9569ms).
[12:20:11.098] <TB3> INFO: Test took 10217ms.
[12:20:11.324] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:20:11.384] <TB3> INFO: enter test to run
[12:20:11.384] <TB3> INFO: test: pretest no parameter change
[12:20:11.384] <TB3> INFO: running: pretest
[12:20:11.385] <TB3> INFO: ----------------------------------------------------------------------
[12:20:11.385] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[12:20:11.385] <TB3> INFO: ----------------------------------------------------------------------
[12:20:11.476] <TB3> INFO: Expecting 231680 events.
[12:20:21.649] <TB3> INFO: 231680 events read in total (9617ms).
[12:20:21.652] <TB3> INFO: Test took 10265ms.
[12:20:21.893] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[12:20:21.893] <TB3> INFO: CalDel: 154 144 154 144 146 154 148 156 154 123 152 158 133 151 123 139
[12:20:21.893] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[12:20:21.934] <TB3> INFO: enter test to run
[12:20:21.934] <TB3> INFO: test: pretest no parameter change
[12:20:21.934] <TB3> INFO: running: pretest
[12:20:21.934] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C0.dat
[12:20:21.935] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C1.dat
[12:20:21.935] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C2.dat
[12:20:21.935] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C3.dat
[12:20:21.935] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C4.dat
[12:20:21.935] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C5.dat
[12:20:21.935] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C6.dat
[12:20:21.935] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C7.dat
[12:20:21.935] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C8.dat
[12:20:21.935] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C9.dat
[12:20:21.935] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C10.dat
[12:20:21.936] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C11.dat
[12:20:21.936] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C12.dat
[12:20:21.936] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C13.dat
[12:20:21.936] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C14.dat
[12:20:21.936] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C15.dat
[12:20:21.936] <TB3> INFO: enter test to run
[12:20:21.936] <TB3> INFO: test: fulltest no parameter change
[12:20:21.936] <TB3> INFO: running: fulltest
[12:20:21.936] <TB3> INFO: ######################################################################
[12:20:21.936] <TB3> INFO: PixTestFullTest::doTest()
[12:20:21.936] <TB3> INFO: ######################################################################
[12:20:21.937] <TB3> INFO: ######################################################################
[12:20:21.937] <TB3> INFO: PixTestAlive::doTest()
[12:20:21.937] <TB3> INFO: ######################################################################
[12:20:21.938] <TB3> INFO: ----------------------------------------------------------------------
[12:20:21.938] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:20:21.938] <TB3> INFO: ----------------------------------------------------------------------
[12:20:22.204] <TB3> INFO: Expecting 41600 events.
[12:20:26.001] <TB3> INFO: 41600 events read in total (3241ms).
[12:20:26.001] <TB3> INFO: Test took 4062ms.
[12:20:26.231] <TB3> INFO: PixTestAlive::aliveTest() done
[12:20:26.231] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0
[12:20:26.233] <TB3> INFO: ----------------------------------------------------------------------
[12:20:26.233] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:20:26.233] <TB3> INFO: ----------------------------------------------------------------------
[12:20:26.520] <TB3> INFO: Expecting 41600 events.
[12:20:29.500] <TB3> INFO: 41600 events read in total (2424ms).
[12:20:29.501] <TB3> INFO: Test took 3267ms.
[12:20:29.501] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:20:29.752] <TB3> INFO: PixTestAlive::maskTest() done
[12:20:29.752] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:20:29.753] <TB3> INFO: ----------------------------------------------------------------------
[12:20:29.753] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:20:29.753] <TB3> INFO: ----------------------------------------------------------------------
[12:20:30.025] <TB3> INFO: Expecting 41600 events.
[12:20:33.823] <TB3> INFO: 41600 events read in total (3242ms).
[12:20:33.823] <TB3> INFO: Test took 4068ms.
[12:20:34.052] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[12:20:34.052] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:20:34.052] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[12:20:34.052] <TB3> INFO: Decoding statistics:
[12:20:34.052] <TB3> INFO: General information:
[12:20:34.052] <TB3> INFO: 16bit words read: 0
[12:20:34.052] <TB3> INFO: valid events total: 0
[12:20:34.052] <TB3> INFO: empty events: 0
[12:20:34.052] <TB3> INFO: valid events with pixels: 0
[12:20:34.052] <TB3> INFO: valid pixel hits: 0
[12:20:34.052] <TB3> INFO: Event errors: 0
[12:20:34.052] <TB3> INFO: start marker: 0
[12:20:34.052] <TB3> INFO: stop marker: 0
[12:20:34.052] <TB3> INFO: overflow: 0
[12:20:34.052] <TB3> INFO: invalid 5bit words: 0
[12:20:34.052] <TB3> INFO: invalid XOR eye diagram: 0
[12:20:34.052] <TB3> INFO: frame (failed synchr.): 0
[12:20:34.052] <TB3> INFO: idle data (no TBM trl): 0
[12:20:34.052] <TB3> INFO: no data (only TBM hdr): 0
[12:20:34.052] <TB3> INFO: TBM errors: 0
[12:20:34.052] <TB3> INFO: flawed TBM headers: 0
[12:20:34.052] <TB3> INFO: flawed TBM trailers: 0
[12:20:34.052] <TB3> INFO: event ID mismatches: 0
[12:20:34.052] <TB3> INFO: ROC errors: 0
[12:20:34.052] <TB3> INFO: missing ROC header(s): 0
[12:20:34.052] <TB3> INFO: misplaced readback start: 0
[12:20:34.052] <TB3> INFO: Pixel decoding errors: 0
[12:20:34.052] <TB3> INFO: pixel data incomplete: 0
[12:20:34.052] <TB3> INFO: pixel address: 0
[12:20:34.052] <TB3> INFO: pulse height fill bit: 0
[12:20:34.052] <TB3> INFO: buffer corruption: 0
[12:20:34.059] <TB3> INFO: ######################################################################
[12:20:34.059] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:20:34.059] <TB3> INFO: ######################################################################
[12:20:34.062] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:20:34.073] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:20:34.073] <TB3> INFO: run 1 of 1
[12:20:34.357] <TB3> INFO: Expecting 3120000 events.
[12:21:15.796] <TB3> INFO: 861905 events read in total (40883ms).
[12:21:56.678] <TB3> INFO: 1713255 events read in total (81765ms).
[12:22:38.096] <TB3> INFO: 2578440 events read in total (123184ms).
[12:23:04.974] <TB3> INFO: 3120000 events read in total (150061ms).
[12:23:05.023] <TB3> INFO: Test took 150950ms.
[12:23:28.533] <TB3> INFO: PixTestBBMap::doTest() done with 2 decoding errors: , duration: 174 seconds
[12:23:28.533] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 0 0 1 1 0 0 0 1 2 1 1 0 1
[12:23:28.533] <TB3> INFO: separation cut (per ROC): 65 79 73 70 70 74 88 65 65 70 90 76 74 76 78 70
[12:23:28.534] <TB3> INFO: Decoding statistics:
[12:23:28.534] <TB3> INFO: General information:
[12:23:28.534] <TB3> INFO: 16bit words read: 0
[12:23:28.534] <TB3> INFO: valid events total: 0
[12:23:28.534] <TB3> INFO: empty events: 0
[12:23:28.534] <TB3> INFO: valid events with pixels: 0
[12:23:28.534] <TB3> INFO: valid pixel hits: 0
[12:23:28.534] <TB3> INFO: Event errors: 0
[12:23:28.534] <TB3> INFO: start marker: 0
[12:23:28.534] <TB3> INFO: stop marker: 0
[12:23:28.534] <TB3> INFO: overflow: 0
[12:23:28.534] <TB3> INFO: invalid 5bit words: 0
[12:23:28.534] <TB3> INFO: invalid XOR eye diagram: 0
[12:23:28.534] <TB3> INFO: frame (failed synchr.): 0
[12:23:28.534] <TB3> INFO: idle data (no TBM trl): 0
[12:23:28.534] <TB3> INFO: no data (only TBM hdr): 0
[12:23:28.534] <TB3> INFO: TBM errors: 0
[12:23:28.534] <TB3> INFO: flawed TBM headers: 0
[12:23:28.534] <TB3> INFO: flawed TBM trailers: 0
[12:23:28.534] <TB3> INFO: event ID mismatches: 0
[12:23:28.534] <TB3> INFO: ROC errors: 0
[12:23:28.534] <TB3> INFO: missing ROC header(s): 0
[12:23:28.534] <TB3> INFO: misplaced readback start: 0
[12:23:28.534] <TB3> INFO: Pixel decoding errors: 0
[12:23:28.534] <TB3> INFO: pixel data incomplete: 0
[12:23:28.534] <TB3> INFO: pixel address: 0
[12:23:28.534] <TB3> INFO: pulse height fill bit: 0
[12:23:28.534] <TB3> INFO: buffer corruption: 0
[12:23:28.608] <TB3> INFO: ######################################################################
[12:23:28.608] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:23:28.608] <TB3> INFO: ######################################################################
[12:23:28.608] <TB3> INFO: ----------------------------------------------------------------------
[12:23:28.608] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:23:28.608] <TB3> INFO: ----------------------------------------------------------------------
[12:23:28.608] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:23:28.616] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[12:23:28.616] <TB3> INFO: run 1 of 1
[12:23:28.886] <TB3> INFO: Expecting 26208000 events.
[12:24:02.216] <TB3> INFO: 861000 events read in total (32774ms).
[12:24:35.325] <TB3> INFO: 1711000 events read in total (65883ms).
[12:25:08.066] <TB3> INFO: 2556950 events read in total (98624ms).
[12:25:41.054] <TB3> INFO: 3404000 events read in total (131612ms).
[12:26:13.955] <TB3> INFO: 4249800 events read in total (164513ms).
[12:26:46.588] <TB3> INFO: 5093800 events read in total (197146ms).
[12:27:18.952] <TB3> INFO: 5938650 events read in total (229510ms).
[12:27:51.670] <TB3> INFO: 6783600 events read in total (262228ms).
[12:28:24.837] <TB3> INFO: 7628000 events read in total (295395ms).
[12:28:57.947] <TB3> INFO: 8471900 events read in total (328505ms).
[12:29:31.073] <TB3> INFO: 9316700 events read in total (361631ms).
[12:30:03.843] <TB3> INFO: 10159800 events read in total (394401ms).
[12:30:36.769] <TB3> INFO: 11002250 events read in total (427327ms).
[12:31:09.656] <TB3> INFO: 11845050 events read in total (460214ms).
[12:31:42.392] <TB3> INFO: 12686950 events read in total (492950ms).
[12:32:15.198] <TB3> INFO: 13523000 events read in total (525756ms).
[12:32:48.308] <TB3> INFO: 14357150 events read in total (558866ms).
[12:33:20.965] <TB3> INFO: 15191850 events read in total (591523ms).
[12:33:53.756] <TB3> INFO: 16022950 events read in total (624314ms).
[12:34:26.267] <TB3> INFO: 16854250 events read in total (656825ms).
[12:34:58.952] <TB3> INFO: 17684650 events read in total (689510ms).
[12:35:31.799] <TB3> INFO: 18513900 events read in total (722357ms).
[12:36:04.811] <TB3> INFO: 19342850 events read in total (755369ms).
[12:36:37.835] <TB3> INFO: 20171200 events read in total (788393ms).
[12:37:10.518] <TB3> INFO: 20999450 events read in total (821076ms).
[12:37:43.729] <TB3> INFO: 21827200 events read in total (854287ms).
[12:38:16.472] <TB3> INFO: 22652950 events read in total (887030ms).
[12:38:49.098] <TB3> INFO: 23480400 events read in total (919656ms).
[12:39:21.841] <TB3> INFO: 24307900 events read in total (952399ms).
[12:39:54.512] <TB3> INFO: 25135300 events read in total (985070ms).
[12:40:27.183] <TB3> INFO: 25969850 events read in total (1017741ms).
[12:40:36.877] <TB3> INFO: 26208000 events read in total (1027435ms).
[12:40:36.906] <TB3> INFO: Test took 1028290ms.
[12:40:37.205] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:38.706] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:40.115] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:41.669] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:43.221] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:44.829] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:46.339] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:47.795] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:49.328] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:50.893] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:52.352] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:53.766] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:55.220] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:56.671] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:58.118] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:40:59.596] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:41:01.068] <TB3> INFO: PixTestScurves::scurves() done
[12:41:01.068] <TB3> INFO: Vcal mean: 71.51 83.84 77.91 75.45 75.59 82.85 92.68 75.48 74.24 80.70 82.47 81.41 74.44 77.54 78.06 79.50
[12:41:01.068] <TB3> INFO: Vcal RMS: 4.91 4.81 4.47 4.30 4.30 4.92 5.33 4.53 4.70 4.63 4.94 4.29 4.11 4.27 4.27 3.87
[12:41:01.068] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1052 seconds
[12:41:01.068] <TB3> INFO: Decoding statistics:
[12:41:01.068] <TB3> INFO: General information:
[12:41:01.068] <TB3> INFO: 16bit words read: 0
[12:41:01.068] <TB3> INFO: valid events total: 0
[12:41:01.068] <TB3> INFO: empty events: 0
[12:41:01.068] <TB3> INFO: valid events with pixels: 0
[12:41:01.068] <TB3> INFO: valid pixel hits: 0
[12:41:01.068] <TB3> INFO: Event errors: 0
[12:41:01.068] <TB3> INFO: start marker: 0
[12:41:01.068] <TB3> INFO: stop marker: 0
[12:41:01.068] <TB3> INFO: overflow: 0
[12:41:01.068] <TB3> INFO: invalid 5bit words: 0
[12:41:01.068] <TB3> INFO: invalid XOR eye diagram: 0
[12:41:01.068] <TB3> INFO: frame (failed synchr.): 0
[12:41:01.068] <TB3> INFO: idle data (no TBM trl): 0
[12:41:01.068] <TB3> INFO: no data (only TBM hdr): 0
[12:41:01.068] <TB3> INFO: TBM errors: 0
[12:41:01.068] <TB3> INFO: flawed TBM headers: 0
[12:41:01.068] <TB3> INFO: flawed TBM trailers: 0
[12:41:01.068] <TB3> INFO: event ID mismatches: 0
[12:41:01.068] <TB3> INFO: ROC errors: 0
[12:41:01.068] <TB3> INFO: missing ROC header(s): 0
[12:41:01.068] <TB3> INFO: misplaced readback start: 0
[12:41:01.068] <TB3> INFO: Pixel decoding errors: 0
[12:41:01.068] <TB3> INFO: pixel data incomplete: 0
[12:41:01.068] <TB3> INFO: pixel address: 0
[12:41:01.068] <TB3> INFO: pulse height fill bit: 0
[12:41:01.068] <TB3> INFO: buffer corruption: 0
[12:41:01.140] <TB3> INFO: ######################################################################
[12:41:01.140] <TB3> INFO: PixTestTrim::doTest()
[12:41:01.140] <TB3> INFO: ######################################################################
[12:41:01.141] <TB3> INFO: ----------------------------------------------------------------------
[12:41:01.141] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[12:41:01.141] <TB3> INFO: ----------------------------------------------------------------------
[12:41:01.219] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[12:41:01.219] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:41:01.228] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[12:41:01.228] <TB3> INFO: run 1 of 1
[12:41:01.486] <TB3> INFO: Expecting 6281600 events.
[12:41:41.354] <TB3> INFO: 1075620 events read in total (39307ms).
[12:42:20.418] <TB3> INFO: 2144330 events read in total (78371ms).
[12:43:00.193] <TB3> INFO: 3204420 events read in total (118147ms).
[12:43:39.793] <TB3> INFO: 4272870 events read in total (157746ms).
[12:44:19.119] <TB3> INFO: 5345120 events read in total (197072ms).
[12:44:53.415] <TB3> INFO: 6281600 events read in total (231368ms).
[12:44:53.448] <TB3> INFO: Test took 232220ms.
[12:45:12.044] <TB3> INFO: ROC 0 VthrComp = 75
[12:45:12.044] <TB3> INFO: ROC 1 VthrComp = 88
[12:45:12.044] <TB3> INFO: ROC 2 VthrComp = 82
[12:45:12.044] <TB3> INFO: ROC 3 VthrComp = 83
[12:45:12.044] <TB3> INFO: ROC 4 VthrComp = 80
[12:45:12.045] <TB3> INFO: ROC 5 VthrComp = 85
[12:45:12.045] <TB3> INFO: ROC 6 VthrComp = 96
[12:45:12.045] <TB3> INFO: ROC 7 VthrComp = 77
[12:45:12.045] <TB3> INFO: ROC 8 VthrComp = 80
[12:45:12.045] <TB3> INFO: ROC 9 VthrComp = 83
[12:45:12.045] <TB3> INFO: ROC 10 VthrComp = 88
[12:45:12.045] <TB3> INFO: ROC 11 VthrComp = 87
[12:45:12.045] <TB3> INFO: ROC 12 VthrComp = 84
[12:45:12.045] <TB3> INFO: ROC 13 VthrComp = 84
[12:45:12.045] <TB3> INFO: ROC 14 VthrComp = 84
[12:45:12.045] <TB3> INFO: ROC 15 VthrComp = 87
[12:45:12.045] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[12:45:12.045] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:45:12.053] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[12:45:12.053] <TB3> INFO: run 1 of 1
[12:45:12.317] <TB3> INFO: Expecting 6281600 events.
[12:45:47.664] <TB3> INFO: 754170 events read in total (34791ms).
[12:46:22.583] <TB3> INFO: 1506190 events read in total (69710ms).
[12:46:57.396] <TB3> INFO: 2258560 events read in total (104523ms).
[12:47:32.719] <TB3> INFO: 3010880 events read in total (139846ms).
[12:48:08.128] <TB3> INFO: 3758470 events read in total (175255ms).
[12:48:42.997] <TB3> INFO: 4502490 events read in total (210124ms).
[12:49:18.551] <TB3> INFO: 5244790 events read in total (245678ms).
[12:49:53.031] <TB3> INFO: 5986480 events read in total (280158ms).
[12:50:07.099] <TB3> INFO: 6281600 events read in total (294226ms).
[12:50:07.141] <TB3> INFO: Test took 295087ms.
[12:50:30.742] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 58.9735 for pixel 0/29 mean/min/max = 46.4688/33.9462/58.9914
[12:50:30.742] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 59.324 for pixel 1/15 mean/min/max = 46.3138/33.2584/59.3692
[12:50:30.742] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 57.5656 for pixel 2/15 mean/min/max = 44.6332/31.639/57.6274
[12:50:30.743] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 55.5421 for pixel 2/12 mean/min/max = 44.3025/32.5247/56.0803
[12:50:30.743] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 56.3917 for pixel 6/73 mean/min/max = 44.401/32.1676/56.6343
[12:50:30.743] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 58.4808 for pixel 10/5 mean/min/max = 45.577/32.5964/58.5577
[12:50:30.743] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 58.2847 for pixel 2/0 mean/min/max = 45.1422/31.8901/58.3944
[12:50:30.744] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 59.3293 for pixel 5/74 mean/min/max = 46.8016/34.2622/59.3411
[12:50:30.744] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 56.71 for pixel 0/25 mean/min/max = 44.5901/32.2506/56.9296
[12:50:30.744] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 57.5329 for pixel 4/19 mean/min/max = 44.7999/31.7195/57.8802
[12:50:30.744] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 58.9946 for pixel 2/79 mean/min/max = 45.8641/32.652/59.0762
[12:50:30.744] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 57.4662 for pixel 5/1 mean/min/max = 45.0714/32.5741/57.5688
[12:50:30.745] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 54.6417 for pixel 14/7 mean/min/max = 43.2436/31.7862/54.701
[12:50:30.745] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 56.3882 for pixel 16/16 mean/min/max = 44.2605/32.0937/56.4274
[12:50:30.745] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 57.526 for pixel 0/19 mean/min/max = 44.7543/31.9065/57.602
[12:50:30.745] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 55.7549 for pixel 22/7 mean/min/max = 43.8342/31.8956/55.7728
[12:50:30.746] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:50:30.834] <TB3> INFO: Expecting 514560 events.
[12:50:43.848] <TB3> INFO: 514560 events read in total (12458ms).
[12:50:43.852] <TB3> INFO: Expecting 514560 events.
[12:50:56.808] <TB3> INFO: 514560 events read in total (12532ms).
[12:50:56.816] <TB3> INFO: Expecting 514560 events.
[12:51:09.727] <TB3> INFO: 514560 events read in total (12497ms).
[12:51:09.737] <TB3> INFO: Expecting 514560 events.
[12:51:22.751] <TB3> INFO: 514560 events read in total (12597ms).
[12:51:22.764] <TB3> INFO: Expecting 514560 events.
[12:51:35.666] <TB3> INFO: 514560 events read in total (12494ms).
[12:51:35.678] <TB3> INFO: Expecting 514560 events.
[12:51:48.539] <TB3> INFO: 514560 events read in total (12448ms).
[12:51:48.552] <TB3> INFO: Expecting 514560 events.
[12:52:01.470] <TB3> INFO: 514560 events read in total (12500ms).
[12:52:01.487] <TB3> INFO: Expecting 514560 events.
[12:52:14.303] <TB3> INFO: 514560 events read in total (12408ms).
[12:52:14.321] <TB3> INFO: Expecting 514560 events.
[12:52:27.116] <TB3> INFO: 514560 events read in total (12383ms).
[12:52:27.136] <TB3> INFO: Expecting 514560 events.
[12:52:39.996] <TB3> INFO: 514560 events read in total (12455ms).
[12:52:40.019] <TB3> INFO: Expecting 514560 events.
[12:52:52.775] <TB3> INFO: 514560 events read in total (12352ms).
[12:52:52.798] <TB3> INFO: Expecting 514560 events.
[12:53:05.621] <TB3> INFO: 514560 events read in total (12420ms).
[12:53:05.647] <TB3> INFO: Expecting 514560 events.
[12:53:18.417] <TB3> INFO: 514560 events read in total (12368ms).
[12:53:18.447] <TB3> INFO: Expecting 514560 events.
[12:53:31.211] <TB3> INFO: 514560 events read in total (12362ms).
[12:53:31.242] <TB3> INFO: Expecting 514560 events.
[12:53:44.098] <TB3> INFO: 514560 events read in total (12454ms).
[12:53:44.128] <TB3> INFO: Expecting 514560 events.
[12:53:56.901] <TB3> INFO: 514560 events read in total (12371ms).
[12:53:56.933] <TB3> INFO: Test took 206187ms.
[12:53:57.893] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:53:57.901] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[12:53:57.901] <TB3> INFO: run 1 of 1
[12:53:58.168] <TB3> INFO: Expecting 6281600 events.
[12:54:33.989] <TB3> INFO: 742710 events read in total (35265ms).
[12:55:09.215] <TB3> INFO: 1483810 events read in total (70491ms).
[12:55:44.058] <TB3> INFO: 2225090 events read in total (105334ms).
[12:56:18.843] <TB3> INFO: 2966450 events read in total (140119ms).
[12:56:53.614] <TB3> INFO: 3702820 events read in total (174890ms).
[12:57:28.497] <TB3> INFO: 4435170 events read in total (209773ms).
[12:58:03.653] <TB3> INFO: 5165460 events read in total (244929ms).
[12:58:38.537] <TB3> INFO: 5894630 events read in total (279813ms).
[12:58:56.728] <TB3> INFO: 6281600 events read in total (298004ms).
[12:58:56.768] <TB3> INFO: Test took 298867ms.
[12:59:20.293] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.071616 .. 49.156919
[12:59:20.376] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 59 (-1/-1) hits flags = 528 (plus default)
[12:59:20.384] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[12:59:20.385] <TB3> INFO: run 1 of 1
[12:59:20.647] <TB3> INFO: Expecting 2496000 events.
[12:59:58.708] <TB3> INFO: 936870 events read in total (37505ms).
[13:00:36.788] <TB3> INFO: 1869500 events read in total (75585ms).
[13:01:02.722] <TB3> INFO: 2496000 events read in total (101519ms).
[13:01:02.739] <TB3> INFO: Test took 102354ms.
[13:01:17.691] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 17.811193 .. 44.648226
[13:01:17.769] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 7 .. 54 (-1/-1) hits flags = 528 (plus default)
[13:01:17.777] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:01:17.777] <TB3> INFO: run 1 of 1
[13:01:18.043] <TB3> INFO: Expecting 1996800 events.
[13:01:56.459] <TB3> INFO: 935010 events read in total (37860ms).
[13:02:34.496] <TB3> INFO: 1867800 events read in total (75898ms).
[13:02:40.084] <TB3> INFO: 1996800 events read in total (81486ms).
[13:02:40.101] <TB3> INFO: Test took 82325ms.
[13:02:53.928] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 21.748194 .. 42.653287
[13:02:54.006] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 11 .. 52 (-1/-1) hits flags = 528 (plus default)
[13:02:54.015] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:02:54.015] <TB3> INFO: run 1 of 1
[13:02:54.276] <TB3> INFO: Expecting 1747200 events.
[13:03:32.690] <TB3> INFO: 930780 events read in total (37857ms).
[13:04:06.245] <TB3> INFO: 1747200 events read in total (71412ms).
[13:04:06.255] <TB3> INFO: Test took 72240ms.
[13:04:19.623] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 24.550124 .. 42.653287
[13:04:19.715] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 14 .. 52 (-1/-1) hits flags = 528 (plus default)
[13:04:19.723] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:04:19.723] <TB3> INFO: run 1 of 1
[13:04:20.006] <TB3> INFO: Expecting 1622400 events.
[13:04:58.429] <TB3> INFO: 909480 events read in total (37866ms).
[13:05:28.467] <TB3> INFO: 1622400 events read in total (67904ms).
[13:05:28.478] <TB3> INFO: Test took 68756ms.
[13:05:41.680] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:05:41.680] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:05:41.689] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:05:41.689] <TB3> INFO: run 1 of 1
[13:05:41.949] <TB3> INFO: Expecting 1705600 events.
[13:06:19.991] <TB3> INFO: 879880 events read in total (37485ms).
[13:06:56.062] <TB3> INFO: 1705600 events read in total (73556ms).
[13:06:56.075] <TB3> INFO: Test took 74386ms.
[13:07:09.797] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:07:09.797] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:07:09.797] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:07:09.797] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:07:09.797] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:07:09.797] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:07:09.797] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:07:09.798] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:07:09.798] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:07:09.798] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:07:09.798] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:07:09.798] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:07:09.798] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:07:09.798] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:07:09.798] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:07:09.798] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:07:09.798] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C0.dat
[13:07:09.804] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C1.dat
[13:07:09.810] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C2.dat
[13:07:09.816] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C3.dat
[13:07:09.822] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C4.dat
[13:07:09.828] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C5.dat
[13:07:09.834] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C6.dat
[13:07:09.840] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C7.dat
[13:07:09.846] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C8.dat
[13:07:09.852] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C9.dat
[13:07:09.858] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C10.dat
[13:07:09.864] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C11.dat
[13:07:09.870] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C12.dat
[13:07:09.876] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C13.dat
[13:07:09.882] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C14.dat
[13:07:09.888] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C15.dat
[13:07:09.894] <TB3> INFO: PixTestTrim::trimTest() done
[13:07:09.894] <TB3> INFO: vtrim: 92 106 103 100 87 94 103 102 89 96 87 97 83 86 91 86
[13:07:09.894] <TB3> INFO: vthrcomp: 75 88 82 83 80 85 96 77 80 83 88 87 84 84 84 87
[13:07:09.894] <TB3> INFO: vcal mean: 34.96 35.00 34.96 35.05 34.92 34.99 35.02 35.01 34.97 34.97 34.97 34.98 34.98 34.95 34.99 34.99
[13:07:09.894] <TB3> INFO: vcal RMS: 0.78 0.83 0.81 0.77 0.78 1.14 0.87 0.81 0.81 0.99 0.78 0.81 0.79 0.81 0.81 0.84
[13:07:09.894] <TB3> INFO: bits mean: 8.53 9.02 9.71 9.60 9.61 9.40 9.97 9.08 9.45 9.94 8.88 9.35 10.41 10.06 9.32 9.87
[13:07:09.894] <TB3> INFO: bits RMS: 2.74 2.69 2.72 2.64 2.66 2.65 2.55 2.52 2.70 2.58 2.88 2.73 2.42 2.48 2.87 2.61
[13:07:09.900] <TB3> INFO: ----------------------------------------------------------------------
[13:07:09.900] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[13:07:09.900] <TB3> INFO: ----------------------------------------------------------------------
[13:07:09.902] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:07:09.911] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:07:09.911] <TB3> INFO: run 1 of 1
[13:07:10.171] <TB3> INFO: Expecting 4160000 events.
[13:07:52.242] <TB3> INFO: 896015 events read in total (41515ms).
[13:08:33.671] <TB3> INFO: 1786965 events read in total (82944ms).
[13:09:14.395] <TB3> INFO: 2670635 events read in total (123668ms).
[13:09:55.368] <TB3> INFO: 3550560 events read in total (164641ms).
[13:10:24.024] <TB3> INFO: 4160000 events read in total (193298ms).
[13:10:24.066] <TB3> INFO: Test took 194155ms.
[13:10:54.120] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:10:54.128] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:10:54.128] <TB3> INFO: run 1 of 1
[13:10:54.387] <TB3> INFO: Expecting 3140800 events.
[13:11:37.500] <TB3> INFO: 961615 events read in total (42557ms).
[13:12:20.859] <TB3> INFO: 1911760 events read in total (85916ms).
[13:13:04.090] <TB3> INFO: 2852395 events read in total (129148ms).
[13:13:17.694] <TB3> INFO: 3140800 events read in total (142751ms).
[13:13:17.726] <TB3> INFO: Test took 143598ms.
[13:13:44.584] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 140 (-1/-1) hits flags = 528 (plus default)
[13:13:44.593] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:13:44.593] <TB3> INFO: run 1 of 1
[13:13:44.879] <TB3> INFO: Expecting 2932800 events.
[13:14:28.651] <TB3> INFO: 991895 events read in total (43216ms).
[13:15:12.010] <TB3> INFO: 1968460 events read in total (86575ms).
[13:15:54.043] <TB3> INFO: 2932800 events read in total (128608ms).
[13:15:54.067] <TB3> INFO: Test took 129474ms.
[13:16:17.477] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 140 (-1/-1) hits flags = 528 (plus default)
[13:16:17.485] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:16:17.485] <TB3> INFO: run 1 of 1
[13:16:17.744] <TB3> INFO: Expecting 2932800 events.
[13:17:01.332] <TB3> INFO: 990765 events read in total (43032ms).
[13:17:44.120] <TB3> INFO: 1966165 events read in total (85821ms).
[13:18:26.413] <TB3> INFO: 2932800 events read in total (128113ms).
[13:18:26.440] <TB3> INFO: Test took 128955ms.
[13:18:50.048] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 141 (-1/-1) hits flags = 528 (plus default)
[13:18:50.058] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:18:50.058] <TB3> INFO: run 1 of 1
[13:18:50.324] <TB3> INFO: Expecting 2953600 events.
[13:19:33.569] <TB3> INFO: 986140 events read in total (42689ms).
[13:20:16.970] <TB3> INFO: 1957315 events read in total (86090ms).
[13:20:59.776] <TB3> INFO: 2922045 events read in total (128896ms).
[13:21:01.519] <TB3> INFO: 2953600 events read in total (130639ms).
[13:21:01.551] <TB3> INFO: Test took 131493ms.
[13:21:26.050] <TB3> INFO: PixTestTrim::trimBitTest() done
[13:21:26.051] <TB3> INFO: PixTestTrim::doTest() done, duration: 2424 seconds
[13:21:26.051] <TB3> INFO: Decoding statistics:
[13:21:26.051] <TB3> INFO: General information:
[13:21:26.051] <TB3> INFO: 16bit words read: 0
[13:21:26.051] <TB3> INFO: valid events total: 0
[13:21:26.051] <TB3> INFO: empty events: 0
[13:21:26.051] <TB3> INFO: valid events with pixels: 0
[13:21:26.051] <TB3> INFO: valid pixel hits: 0
[13:21:26.051] <TB3> INFO: Event errors: 0
[13:21:26.051] <TB3> INFO: start marker: 0
[13:21:26.051] <TB3> INFO: stop marker: 0
[13:21:26.051] <TB3> INFO: overflow: 0
[13:21:26.051] <TB3> INFO: invalid 5bit words: 0
[13:21:26.051] <TB3> INFO: invalid XOR eye diagram: 0
[13:21:26.051] <TB3> INFO: frame (failed synchr.): 0
[13:21:26.051] <TB3> INFO: idle data (no TBM trl): 0
[13:21:26.051] <TB3> INFO: no data (only TBM hdr): 0
[13:21:26.051] <TB3> INFO: TBM errors: 0
[13:21:26.051] <TB3> INFO: flawed TBM headers: 0
[13:21:26.051] <TB3> INFO: flawed TBM trailers: 0
[13:21:26.051] <TB3> INFO: event ID mismatches: 0
[13:21:26.051] <TB3> INFO: ROC errors: 0
[13:21:26.051] <TB3> INFO: missing ROC header(s): 0
[13:21:26.051] <TB3> INFO: misplaced readback start: 0
[13:21:26.051] <TB3> INFO: Pixel decoding errors: 0
[13:21:26.051] <TB3> INFO: pixel data incomplete: 0
[13:21:26.051] <TB3> INFO: pixel address: 0
[13:21:26.051] <TB3> INFO: pulse height fill bit: 0
[13:21:26.051] <TB3> INFO: buffer corruption: 0
[13:21:26.763] <TB3> INFO: ######################################################################
[13:21:26.763] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:21:26.763] <TB3> INFO: ######################################################################
[13:21:27.025] <TB3> INFO: Expecting 41600 events.
[13:21:30.784] <TB3> INFO: 41600 events read in total (3203ms).
[13:21:30.784] <TB3> INFO: Test took 4019ms.
[13:21:31.275] <TB3> INFO: Expecting 41600 events.
[13:21:35.187] <TB3> INFO: 41600 events read in total (3356ms).
[13:21:35.187] <TB3> INFO: Test took 4198ms.
[13:21:35.488] <TB3> INFO: Expecting 41600 events.
[13:21:39.364] <TB3> INFO: 41600 events read in total (3320ms).
[13:21:39.364] <TB3> INFO: Test took 4155ms.
[13:21:39.618] <TB3> INFO: Expecting 2560 events.
[13:21:40.472] <TB3> INFO: 2560 events read in total (297ms).
[13:21:40.472] <TB3> INFO: Test took 1095ms.
[13:21:40.777] <TB3> INFO: Expecting 2560 events.
[13:21:41.630] <TB3> INFO: 2560 events read in total (297ms).
[13:21:41.630] <TB3> INFO: Test took 1158ms.
[13:21:41.935] <TB3> INFO: Expecting 2560 events.
[13:21:42.787] <TB3> INFO: 2560 events read in total (296ms).
[13:21:42.787] <TB3> INFO: Test took 1157ms.
[13:21:43.092] <TB3> INFO: Expecting 2560 events.
[13:21:43.945] <TB3> INFO: 2560 events read in total (297ms).
[13:21:43.945] <TB3> INFO: Test took 1157ms.
[13:21:44.250] <TB3> INFO: Expecting 2560 events.
[13:21:45.103] <TB3> INFO: 2560 events read in total (297ms).
[13:21:45.103] <TB3> INFO: Test took 1158ms.
[13:21:45.409] <TB3> INFO: Expecting 2560 events.
[13:21:46.267] <TB3> INFO: 2560 events read in total (302ms).
[13:21:46.267] <TB3> INFO: Test took 1163ms.
[13:21:46.572] <TB3> INFO: Expecting 2560 events.
[13:21:47.426] <TB3> INFO: 2560 events read in total (297ms).
[13:21:47.426] <TB3> INFO: Test took 1158ms.
[13:21:47.730] <TB3> INFO: Expecting 2560 events.
[13:21:48.585] <TB3> INFO: 2560 events read in total (299ms).
[13:21:48.585] <TB3> INFO: Test took 1158ms.
[13:21:48.890] <TB3> INFO: Expecting 2560 events.
[13:21:49.743] <TB3> INFO: 2560 events read in total (297ms).
[13:21:49.743] <TB3> INFO: Test took 1158ms.
[13:21:50.048] <TB3> INFO: Expecting 2560 events.
[13:21:50.899] <TB3> INFO: 2560 events read in total (295ms).
[13:21:50.899] <TB3> INFO: Test took 1156ms.
[13:21:51.204] <TB3> INFO: Expecting 2560 events.
[13:21:52.059] <TB3> INFO: 2560 events read in total (299ms).
[13:21:52.059] <TB3> INFO: Test took 1159ms.
[13:21:52.364] <TB3> INFO: Expecting 2560 events.
[13:21:53.218] <TB3> INFO: 2560 events read in total (298ms).
[13:21:53.218] <TB3> INFO: Test took 1159ms.
[13:21:53.523] <TB3> INFO: Expecting 2560 events.
[13:21:54.376] <TB3> INFO: 2560 events read in total (297ms).
[13:21:54.376] <TB3> INFO: Test took 1158ms.
[13:21:54.681] <TB3> INFO: Expecting 2560 events.
[13:21:55.540] <TB3> INFO: 2560 events read in total (303ms).
[13:21:55.540] <TB3> INFO: Test took 1163ms.
[13:21:55.846] <TB3> INFO: Expecting 2560 events.
[13:21:56.700] <TB3> INFO: 2560 events read in total (298ms).
[13:21:56.701] <TB3> INFO: Test took 1160ms.
[13:21:57.005] <TB3> INFO: Expecting 2560 events.
[13:21:57.859] <TB3> INFO: 2560 events read in total (297ms).
[13:21:57.859] <TB3> INFO: Test took 1158ms.
[13:21:57.862] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:21:58.165] <TB3> INFO: Expecting 655360 events.
[13:22:15.276] <TB3> INFO: 655360 events read in total (16555ms).
[13:22:15.286] <TB3> INFO: Expecting 655360 events.
[13:22:31.958] <TB3> INFO: 655360 events read in total (16270ms).
[13:22:31.970] <TB3> INFO: Expecting 655360 events.
[13:22:48.645] <TB3> INFO: 655360 events read in total (16273ms).
[13:22:48.662] <TB3> INFO: Expecting 655360 events.
[13:23:05.407] <TB3> INFO: 655360 events read in total (16343ms).
[13:23:05.434] <TB3> INFO: Expecting 655360 events.
[13:23:21.949] <TB3> INFO: 655360 events read in total (16114ms).
[13:23:21.973] <TB3> INFO: Expecting 655360 events.
[13:23:39.009] <TB3> INFO: 655360 events read in total (16634ms).
[13:23:39.040] <TB3> INFO: Expecting 655360 events.
[13:23:55.635] <TB3> INFO: 655360 events read in total (16193ms).
[13:23:55.665] <TB3> INFO: Expecting 655360 events.
[13:24:12.434] <TB3> INFO: 655360 events read in total (16367ms).
[13:24:12.468] <TB3> INFO: Expecting 655360 events.
[13:24:29.273] <TB3> INFO: 655360 events read in total (16404ms).
[13:24:29.313] <TB3> INFO: Expecting 655360 events.
[13:24:45.967] <TB3> INFO: 655360 events read in total (16252ms).
[13:24:46.011] <TB3> INFO: Expecting 655360 events.
[13:25:02.954] <TB3> INFO: 655360 events read in total (16541ms).
[13:25:03.001] <TB3> INFO: Expecting 655360 events.
[13:25:19.582] <TB3> INFO: 655360 events read in total (16179ms).
[13:25:19.648] <TB3> INFO: Expecting 655360 events.
[13:25:36.316] <TB3> INFO: 655360 events read in total (16266ms).
[13:25:36.366] <TB3> INFO: Expecting 655360 events.
[13:25:53.095] <TB3> INFO: 655360 events read in total (16327ms).
[13:25:53.150] <TB3> INFO: Expecting 655360 events.
[13:26:09.826] <TB3> INFO: 655360 events read in total (16274ms).
[13:26:09.881] <TB3> INFO: Expecting 655360 events.
[13:26:26.796] <TB3> INFO: 655360 events read in total (16513ms).
[13:26:26.855] <TB3> INFO: Test took 268993ms.
[13:26:26.938] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:27.167] <TB3> INFO: Expecting 655360 events.
[13:26:43.875] <TB3> INFO: 655360 events read in total (16152ms).
[13:26:43.884] <TB3> INFO: Expecting 655360 events.
[13:27:00.560] <TB3> INFO: 655360 events read in total (16275ms).
[13:27:00.574] <TB3> INFO: Expecting 655360 events.
[13:27:17.193] <TB3> INFO: 655360 events read in total (16217ms).
[13:27:17.207] <TB3> INFO: Expecting 655360 events.
[13:27:33.822] <TB3> INFO: 655360 events read in total (16213ms).
[13:27:33.841] <TB3> INFO: Expecting 655360 events.
[13:27:50.795] <TB3> INFO: 655360 events read in total (16552ms).
[13:27:50.818] <TB3> INFO: Expecting 655360 events.
[13:28:07.424] <TB3> INFO: 655360 events read in total (16205ms).
[13:28:07.449] <TB3> INFO: Expecting 655360 events.
[13:28:24.093] <TB3> INFO: 655360 events read in total (16243ms).
[13:28:24.127] <TB3> INFO: Expecting 655360 events.
[13:28:40.916] <TB3> INFO: 655360 events read in total (16387ms).
[13:28:40.960] <TB3> INFO: Expecting 655360 events.
[13:28:57.652] <TB3> INFO: 655360 events read in total (16290ms).
[13:28:57.687] <TB3> INFO: Expecting 655360 events.
[13:29:14.607] <TB3> INFO: 655360 events read in total (16518ms).
[13:29:14.648] <TB3> INFO: Expecting 655360 events.
[13:29:31.568] <TB3> INFO: 655360 events read in total (16519ms).
[13:29:31.612] <TB3> INFO: Expecting 655360 events.
[13:29:48.405] <TB3> INFO: 655360 events read in total (16392ms).
[13:29:48.451] <TB3> INFO: Expecting 655360 events.
[13:30:05.264] <TB3> INFO: 655360 events read in total (16412ms).
[13:30:05.318] <TB3> INFO: Expecting 655360 events.
[13:30:22.192] <TB3> INFO: 655360 events read in total (16472ms).
[13:30:22.244] <TB3> INFO: Expecting 655360 events.
[13:30:39.090] <TB3> INFO: 655360 events read in total (16444ms).
[13:30:39.147] <TB3> INFO: Expecting 655360 events.
[13:30:55.941] <TB3> INFO: 655360 events read in total (16393ms).
[13:30:56.006] <TB3> INFO: Test took 269068ms.
[13:30:56.206] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.213] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.220] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.227] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.234] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.241] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.248] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.255] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.262] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.269] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.276] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.283] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.290] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[13:30:56.297] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.307] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.318] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.327] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:56.364] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:30:56.364] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:30:56.364] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:30:56.364] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:30:56.365] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:30:56.365] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:30:56.365] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:30:56.365] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:30:56.365] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:30:56.365] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:30:56.365] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:30:56.365] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:30:56.365] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:30:56.365] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:30:56.365] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:30:56.366] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:30:56.630] <TB3> INFO: Expecting 41600 events.
[13:31:00.124] <TB3> INFO: 41600 events read in total (2938ms).
[13:31:00.125] <TB3> INFO: Test took 3757ms.
[13:31:00.560] <TB3> INFO: Expecting 41600 events.
[13:31:04.100] <TB3> INFO: 41600 events read in total (2983ms).
[13:31:04.101] <TB3> INFO: Test took 3801ms.
[13:31:04.546] <TB3> INFO: Expecting 41600 events.
[13:31:08.047] <TB3> INFO: 41600 events read in total (2945ms).
[13:31:08.047] <TB3> INFO: Test took 3768ms.
[13:31:08.227] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:08.316] <TB3> INFO: Expecting 2560 events.
[13:31:09.174] <TB3> INFO: 2560 events read in total (302ms).
[13:31:09.175] <TB3> INFO: Test took 948ms.
[13:31:09.177] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:09.480] <TB3> INFO: Expecting 2560 events.
[13:31:10.336] <TB3> INFO: 2560 events read in total (300ms).
[13:31:10.336] <TB3> INFO: Test took 1159ms.
[13:31:10.338] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:10.642] <TB3> INFO: Expecting 2560 events.
[13:31:11.494] <TB3> INFO: 2560 events read in total (296ms).
[13:31:11.495] <TB3> INFO: Test took 1157ms.
[13:31:11.496] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:11.801] <TB3> INFO: Expecting 2560 events.
[13:31:12.654] <TB3> INFO: 2560 events read in total (297ms).
[13:31:12.654] <TB3> INFO: Test took 1158ms.
[13:31:12.655] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:12.960] <TB3> INFO: Expecting 2560 events.
[13:31:13.813] <TB3> INFO: 2560 events read in total (297ms).
[13:31:13.813] <TB3> INFO: Test took 1158ms.
[13:31:13.814] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:14.118] <TB3> INFO: Expecting 2560 events.
[13:31:14.972] <TB3> INFO: 2560 events read in total (297ms).
[13:31:14.972] <TB3> INFO: Test took 1158ms.
[13:31:14.974] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:15.279] <TB3> INFO: Expecting 2560 events.
[13:31:16.137] <TB3> INFO: 2560 events read in total (302ms).
[13:31:16.137] <TB3> INFO: Test took 1163ms.
[13:31:16.139] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:16.444] <TB3> INFO: Expecting 2560 events.
[13:31:17.298] <TB3> INFO: 2560 events read in total (298ms).
[13:31:17.298] <TB3> INFO: Test took 1159ms.
[13:31:17.301] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:17.604] <TB3> INFO: Expecting 2560 events.
[13:31:18.459] <TB3> INFO: 2560 events read in total (299ms).
[13:31:18.459] <TB3> INFO: Test took 1158ms.
[13:31:18.461] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:18.764] <TB3> INFO: Expecting 2560 events.
[13:31:19.618] <TB3> INFO: 2560 events read in total (297ms).
[13:31:19.619] <TB3> INFO: Test took 1158ms.
[13:31:19.623] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:19.924] <TB3> INFO: Expecting 2560 events.
[13:31:20.783] <TB3> INFO: 2560 events read in total (303ms).
[13:31:20.784] <TB3> INFO: Test took 1162ms.
[13:31:20.786] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:21.089] <TB3> INFO: Expecting 2560 events.
[13:31:21.943] <TB3> INFO: 2560 events read in total (297ms).
[13:31:21.943] <TB3> INFO: Test took 1157ms.
[13:31:21.947] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:22.248] <TB3> INFO: Expecting 2560 events.
[13:31:23.107] <TB3> INFO: 2560 events read in total (303ms).
[13:31:23.107] <TB3> INFO: Test took 1160ms.
[13:31:23.109] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:23.413] <TB3> INFO: Expecting 2560 events.
[13:31:24.269] <TB3> INFO: 2560 events read in total (300ms).
[13:31:24.269] <TB3> INFO: Test took 1160ms.
[13:31:24.271] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:24.575] <TB3> INFO: Expecting 2560 events.
[13:31:25.429] <TB3> INFO: 2560 events read in total (298ms).
[13:31:25.430] <TB3> INFO: Test took 1159ms.
[13:31:25.432] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:25.735] <TB3> INFO: Expecting 2560 events.
[13:31:26.588] <TB3> INFO: 2560 events read in total (297ms).
[13:31:26.589] <TB3> INFO: Test took 1157ms.
[13:31:26.591] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:26.894] <TB3> INFO: Expecting 2560 events.
[13:31:27.749] <TB3> INFO: 2560 events read in total (299ms).
[13:31:27.750] <TB3> INFO: Test took 1159ms.
[13:31:27.752] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:28.055] <TB3> INFO: Expecting 2560 events.
[13:31:28.908] <TB3> INFO: 2560 events read in total (297ms).
[13:31:28.909] <TB3> INFO: Test took 1157ms.
[13:31:28.911] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:29.214] <TB3> INFO: Expecting 2560 events.
[13:31:30.068] <TB3> INFO: 2560 events read in total (298ms).
[13:31:30.069] <TB3> INFO: Test took 1158ms.
[13:31:30.071] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:30.375] <TB3> INFO: Expecting 2560 events.
[13:31:31.229] <TB3> INFO: 2560 events read in total (298ms).
[13:31:31.229] <TB3> INFO: Test took 1158ms.
[13:31:31.232] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:31.535] <TB3> INFO: Expecting 2560 events.
[13:31:32.388] <TB3> INFO: 2560 events read in total (297ms).
[13:31:32.388] <TB3> INFO: Test took 1157ms.
[13:31:32.390] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:32.694] <TB3> INFO: Expecting 2560 events.
[13:31:33.552] <TB3> INFO: 2560 events read in total (302ms).
[13:31:33.552] <TB3> INFO: Test took 1162ms.
[13:31:33.554] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:33.859] <TB3> INFO: Expecting 2560 events.
[13:31:34.717] <TB3> INFO: 2560 events read in total (302ms).
[13:31:34.717] <TB3> INFO: Test took 1163ms.
[13:31:34.719] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:35.023] <TB3> INFO: Expecting 2560 events.
[13:31:35.878] <TB3> INFO: 2560 events read in total (298ms).
[13:31:35.878] <TB3> INFO: Test took 1159ms.
[13:31:35.880] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:36.184] <TB3> INFO: Expecting 2560 events.
[13:31:37.043] <TB3> INFO: 2560 events read in total (302ms).
[13:31:37.043] <TB3> INFO: Test took 1163ms.
[13:31:37.046] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:37.348] <TB3> INFO: Expecting 2560 events.
[13:31:38.206] <TB3> INFO: 2560 events read in total (302ms).
[13:31:38.206] <TB3> INFO: Test took 1160ms.
[13:31:38.208] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:38.512] <TB3> INFO: Expecting 2560 events.
[13:31:39.365] <TB3> INFO: 2560 events read in total (297ms).
[13:31:39.365] <TB3> INFO: Test took 1157ms.
[13:31:39.367] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:39.672] <TB3> INFO: Expecting 2560 events.
[13:31:40.530] <TB3> INFO: 2560 events read in total (302ms).
[13:31:40.530] <TB3> INFO: Test took 1163ms.
[13:31:40.540] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:40.836] <TB3> INFO: Expecting 2560 events.
[13:31:41.695] <TB3> INFO: 2560 events read in total (303ms).
[13:31:41.695] <TB3> INFO: Test took 1156ms.
[13:31:41.697] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:42.001] <TB3> INFO: Expecting 2560 events.
[13:31:42.858] <TB3> INFO: 2560 events read in total (300ms).
[13:31:42.858] <TB3> INFO: Test took 1161ms.
[13:31:42.861] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:43.164] <TB3> INFO: Expecting 2560 events.
[13:31:44.017] <TB3> INFO: 2560 events read in total (297ms).
[13:31:44.017] <TB3> INFO: Test took 1157ms.
[13:31:44.020] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:44.323] <TB3> INFO: Expecting 2560 events.
[13:31:45.177] <TB3> INFO: 2560 events read in total (298ms).
[13:31:45.177] <TB3> INFO: Test took 1157ms.
[13:31:45.638] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 618 seconds
[13:31:45.638] <TB3> INFO: PH scale (per ROC): 80 80 85 94 84 76 86 77 90 90 81 80 86 83 98 93
[13:31:45.638] <TB3> INFO: PH offset (per ROC): 155 162 150 140 155 161 154 154 142 155 159 153 148 150 144 153
[13:31:45.643] <TB3> INFO: Decoding statistics:
[13:31:45.643] <TB3> INFO: General information:
[13:31:45.643] <TB3> INFO: 16bit words read: 91936
[13:31:45.643] <TB3> INFO: valid events total: 10240
[13:31:45.643] <TB3> INFO: empty events: 7736
[13:31:45.643] <TB3> INFO: valid events with pixels: 2504
[13:31:45.643] <TB3> INFO: valid pixel hits: 2504
[13:31:45.643] <TB3> INFO: Event errors: 0
[13:31:45.643] <TB3> INFO: start marker: 0
[13:31:45.643] <TB3> INFO: stop marker: 0
[13:31:45.643] <TB3> INFO: overflow: 0
[13:31:45.643] <TB3> INFO: invalid 5bit words: 0
[13:31:45.643] <TB3> INFO: invalid XOR eye diagram: 0
[13:31:45.643] <TB3> INFO: frame (failed synchr.): 0
[13:31:45.643] <TB3> INFO: idle data (no TBM trl): 0
[13:31:45.643] <TB3> INFO: no data (only TBM hdr): 0
[13:31:45.643] <TB3> INFO: TBM errors: 0
[13:31:45.643] <TB3> INFO: flawed TBM headers: 0
[13:31:45.643] <TB3> INFO: flawed TBM trailers: 0
[13:31:45.643] <TB3> INFO: event ID mismatches: 0
[13:31:45.643] <TB3> INFO: ROC errors: 0
[13:31:45.643] <TB3> INFO: missing ROC header(s): 0
[13:31:45.643] <TB3> INFO: misplaced readback start: 0
[13:31:45.643] <TB3> INFO: Pixel decoding errors: 0
[13:31:45.643] <TB3> INFO: pixel data incomplete: 0
[13:31:45.643] <TB3> INFO: pixel address: 0
[13:31:45.643] <TB3> INFO: pulse height fill bit: 0
[13:31:45.643] <TB3> INFO: buffer corruption: 0
[13:31:45.829] <TB3> INFO: ######################################################################
[13:31:45.829] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:31:45.829] <TB3> INFO: ######################################################################
[13:31:45.840] <TB3> INFO: scanning low vcal = 10
[13:31:46.130] <TB3> INFO: Expecting 41600 events.
[13:31:49.675] <TB3> INFO: 41600 events read in total (2988ms).
[13:31:49.675] <TB3> INFO: Test took 3835ms.
[13:31:49.676] <TB3> INFO: scanning low vcal = 20
[13:31:49.980] <TB3> INFO: Expecting 41600 events.
[13:31:53.528] <TB3> INFO: 41600 events read in total (2992ms).
[13:31:53.528] <TB3> INFO: Test took 3852ms.
[13:31:53.530] <TB3> INFO: scanning low vcal = 30
[13:31:53.875] <TB3> INFO: Expecting 41600 events.
[13:31:57.447] <TB3> INFO: 41600 events read in total (3016ms).
[13:31:57.447] <TB3> INFO: Test took 3917ms.
[13:31:57.449] <TB3> INFO: scanning low vcal = 40
[13:31:57.797] <TB3> INFO: Expecting 41600 events.
[13:32:01.936] <TB3> INFO: 41600 events read in total (3583ms).
[13:32:01.936] <TB3> INFO: Test took 4487ms.
[13:32:01.939] <TB3> INFO: scanning low vcal = 50
[13:32:02.202] <TB3> INFO: Expecting 41600 events.
[13:32:06.378] <TB3> INFO: 41600 events read in total (3620ms).
[13:32:06.379] <TB3> INFO: Test took 4440ms.
[13:32:06.381] <TB3> INFO: scanning low vcal = 60
[13:32:06.644] <TB3> INFO: Expecting 41600 events.
[13:32:10.860] <TB3> INFO: 41600 events read in total (3660ms).
[13:32:10.860] <TB3> INFO: Test took 4478ms.
[13:32:10.867] <TB3> INFO: scanning low vcal = 70
[13:32:11.159] <TB3> INFO: Expecting 41600 events.
[13:32:15.389] <TB3> INFO: 41600 events read in total (3674ms).
[13:32:15.390] <TB3> INFO: Test took 4523ms.
[13:32:15.392] <TB3> INFO: scanning low vcal = 80
[13:32:15.653] <TB3> INFO: Expecting 41600 events.
[13:32:19.848] <TB3> INFO: 41600 events read in total (3639ms).
[13:32:19.848] <TB3> INFO: Test took 4456ms.
[13:32:19.850] <TB3> INFO: scanning low vcal = 90
[13:32:20.125] <TB3> INFO: Expecting 41600 events.
[13:32:24.340] <TB3> INFO: 41600 events read in total (3659ms).
[13:32:24.341] <TB3> INFO: Test took 4491ms.
[13:32:24.344] <TB3> INFO: scanning low vcal = 100
[13:32:24.618] <TB3> INFO: Expecting 41600 events.
[13:32:28.800] <TB3> INFO: 41600 events read in total (3627ms).
[13:32:28.801] <TB3> INFO: Test took 4457ms.
[13:32:28.810] <TB3> INFO: scanning low vcal = 110
[13:32:29.077] <TB3> INFO: Expecting 41600 events.
[13:32:33.266] <TB3> INFO: 41600 events read in total (3633ms).
[13:32:33.266] <TB3> INFO: Test took 4456ms.
[13:32:33.269] <TB3> INFO: scanning low vcal = 120
[13:32:33.532] <TB3> INFO: Expecting 41600 events.
[13:32:37.733] <TB3> INFO: 41600 events read in total (3645ms).
[13:32:37.734] <TB3> INFO: Test took 4465ms.
[13:32:37.736] <TB3> INFO: scanning low vcal = 130
[13:32:38.013] <TB3> INFO: Expecting 41600 events.
[13:32:42.234] <TB3> INFO: 41600 events read in total (3665ms).
[13:32:42.234] <TB3> INFO: Test took 4498ms.
[13:32:42.245] <TB3> INFO: scanning low vcal = 140
[13:32:42.516] <TB3> INFO: Expecting 41600 events.
[13:32:46.708] <TB3> INFO: 41600 events read in total (3636ms).
[13:32:46.709] <TB3> INFO: Test took 4464ms.
[13:32:46.712] <TB3> INFO: scanning low vcal = 150
[13:32:46.978] <TB3> INFO: Expecting 41600 events.
[13:32:51.169] <TB3> INFO: 41600 events read in total (3635ms).
[13:32:51.169] <TB3> INFO: Test took 4457ms.
[13:32:51.172] <TB3> INFO: scanning low vcal = 160
[13:32:51.433] <TB3> INFO: Expecting 41600 events.
[13:32:55.615] <TB3> INFO: 41600 events read in total (3625ms).
[13:32:55.615] <TB3> INFO: Test took 4443ms.
[13:32:55.618] <TB3> INFO: scanning low vcal = 170
[13:32:55.879] <TB3> INFO: Expecting 41600 events.
[13:33:00.099] <TB3> INFO: 41600 events read in total (3664ms).
[13:33:00.099] <TB3> INFO: Test took 4481ms.
[13:33:00.106] <TB3> INFO: scanning low vcal = 180
[13:33:00.396] <TB3> INFO: Expecting 41600 events.
[13:33:04.600] <TB3> INFO: 41600 events read in total (3648ms).
[13:33:04.601] <TB3> INFO: Test took 4494ms.
[13:33:04.603] <TB3> INFO: scanning low vcal = 190
[13:33:04.883] <TB3> INFO: Expecting 41600 events.
[13:33:09.070] <TB3> INFO: 41600 events read in total (3631ms).
[13:33:09.071] <TB3> INFO: Test took 4468ms.
[13:33:09.074] <TB3> INFO: scanning low vcal = 200
[13:33:09.357] <TB3> INFO: Expecting 41600 events.
[13:33:13.578] <TB3> INFO: 41600 events read in total (3665ms).
[13:33:13.578] <TB3> INFO: Test took 4504ms.
[13:33:13.581] <TB3> INFO: scanning low vcal = 210
[13:33:13.866] <TB3> INFO: Expecting 41600 events.
[13:33:18.073] <TB3> INFO: 41600 events read in total (3651ms).
[13:33:18.073] <TB3> INFO: Test took 4492ms.
[13:33:18.089] <TB3> INFO: scanning low vcal = 220
[13:33:18.364] <TB3> INFO: Expecting 41600 events.
[13:33:22.568] <TB3> INFO: 41600 events read in total (3648ms).
[13:33:22.569] <TB3> INFO: Test took 4479ms.
[13:33:22.571] <TB3> INFO: scanning low vcal = 230
[13:33:22.834] <TB3> INFO: Expecting 41600 events.
[13:33:27.022] <TB3> INFO: 41600 events read in total (3631ms).
[13:33:27.022] <TB3> INFO: Test took 4451ms.
[13:33:27.025] <TB3> INFO: scanning low vcal = 240
[13:33:27.304] <TB3> INFO: Expecting 41600 events.
[13:33:31.505] <TB3> INFO: 41600 events read in total (3645ms).
[13:33:31.505] <TB3> INFO: Test took 4480ms.
[13:33:31.513] <TB3> INFO: scanning low vcal = 250
[13:33:31.786] <TB3> INFO: Expecting 41600 events.
[13:33:35.994] <TB3> INFO: 41600 events read in total (3652ms).
[13:33:35.995] <TB3> INFO: Test took 4482ms.
[13:33:35.999] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[13:33:36.276] <TB3> INFO: Expecting 41600 events.
[13:33:40.492] <TB3> INFO: 41600 events read in total (3660ms).
[13:33:40.493] <TB3> INFO: Test took 4494ms.
[13:33:40.495] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[13:33:40.758] <TB3> INFO: Expecting 41600 events.
[13:33:44.954] <TB3> INFO: 41600 events read in total (3639ms).
[13:33:44.954] <TB3> INFO: Test took 4459ms.
[13:33:44.957] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[13:33:45.221] <TB3> INFO: Expecting 41600 events.
[13:33:49.417] <TB3> INFO: 41600 events read in total (3640ms).
[13:33:49.418] <TB3> INFO: Test took 4461ms.
[13:33:49.424] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[13:33:49.689] <TB3> INFO: Expecting 41600 events.
[13:33:53.924] <TB3> INFO: 41600 events read in total (3679ms).
[13:33:53.924] <TB3> INFO: Test took 4500ms.
[13:33:53.927] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:33:54.192] <TB3> INFO: Expecting 41600 events.
[13:33:58.448] <TB3> INFO: 41600 events read in total (3700ms).
[13:33:58.448] <TB3> INFO: Test took 4520ms.
[13:33:59.026] <TB3> INFO: PixTestGainPedestal::measure() done
[13:34:32.381] <TB3> INFO: PixTestGainPedestal::fit() done
[13:34:32.381] <TB3> INFO: non-linearity mean: 0.947 0.955 0.952 0.953 0.959 0.953 0.959 0.956 0.955 0.956 0.956 0.958 0.956 0.955 0.955 0.954
[13:34:32.381] <TB3> INFO: non-linearity RMS: 0.006 0.005 0.006 0.006 0.005 0.006 0.005 0.004 0.006 0.005 0.005 0.004 0.006 0.005 0.005 0.006
[13:34:32.381] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[13:34:32.403] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[13:34:32.424] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[13:34:32.445] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[13:34:32.467] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[13:34:32.488] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[13:34:32.528] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[13:34:32.549] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[13:34:32.572] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[13:34:32.594] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[13:34:32.615] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[13:34:32.637] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[13:34:32.659] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[13:34:32.680] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[13:34:32.702] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[13:34:32.724] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[13:34:32.746] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 166 seconds
[13:34:32.746] <TB3> INFO: Decoding statistics:
[13:34:32.746] <TB3> INFO: General information:
[13:34:32.746] <TB3> INFO: 16bit words read: 2662400
[13:34:32.746] <TB3> INFO: valid events total: 166400
[13:34:32.746] <TB3> INFO: empty events: 0
[13:34:32.746] <TB3> INFO: valid events with pixels: 166400
[13:34:32.746] <TB3> INFO: valid pixel hits: 665593
[13:34:32.746] <TB3> INFO: Event errors: 0
[13:34:32.746] <TB3> INFO: start marker: 0
[13:34:32.746] <TB3> INFO: stop marker: 0
[13:34:32.746] <TB3> INFO: overflow: 0
[13:34:32.746] <TB3> INFO: invalid 5bit words: 0
[13:34:32.746] <TB3> INFO: invalid XOR eye diagram: 0
[13:34:32.746] <TB3> INFO: frame (failed synchr.): 0
[13:34:32.746] <TB3> INFO: idle data (no TBM trl): 0
[13:34:32.746] <TB3> INFO: no data (only TBM hdr): 0
[13:34:32.746] <TB3> INFO: TBM errors: 0
[13:34:32.746] <TB3> INFO: flawed TBM headers: 0
[13:34:32.746] <TB3> INFO: flawed TBM trailers: 0
[13:34:32.746] <TB3> INFO: event ID mismatches: 0
[13:34:32.746] <TB3> INFO: ROC errors: 0
[13:34:32.746] <TB3> INFO: missing ROC header(s): 0
[13:34:32.746] <TB3> INFO: misplaced readback start: 0
[13:34:32.746] <TB3> INFO: Pixel decoding errors: 0
[13:34:32.746] <TB3> INFO: pixel data incomplete: 0
[13:34:32.746] <TB3> INFO: pixel address: 0
[13:34:32.746] <TB3> INFO: pulse height fill bit: 0
[13:34:32.746] <TB3> INFO: buffer corruption: 0
[13:34:32.754] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C15.dat
[13:34:32.756] <TB3> INFO: ######################################################################
[13:34:32.756] <TB3> INFO: PixTestReadback::doTest()
[13:34:32.756] <TB3> INFO: ######################################################################
[13:34:32.756] <TB3> INFO: ----------------------------------------------------------------------
[13:34:32.756] <TB3> INFO: PixTestReadback::CalibrateVd()
[13:34:32.756] <TB3> INFO: ----------------------------------------------------------------------
[13:34:42.216] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C0.dat
[13:34:42.216] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C1.dat
[13:34:42.216] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C2.dat
[13:34:42.217] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C3.dat
[13:34:42.217] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C4.dat
[13:34:42.217] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C5.dat
[13:34:42.217] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C6.dat
[13:34:42.217] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C7.dat
[13:34:42.217] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C8.dat
[13:34:42.217] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C9.dat
[13:34:42.217] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C10.dat
[13:34:42.217] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C11.dat
[13:34:42.217] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C12.dat
[13:34:42.217] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C13.dat
[13:34:42.218] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C14.dat
[13:34:42.218] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C15.dat
[13:34:42.239] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:34:42.239] <TB3> INFO: ----------------------------------------------------------------------
[13:34:42.239] <TB3> INFO: PixTestReadback::CalibrateVa()
[13:34:42.239] <TB3> INFO: ----------------------------------------------------------------------
[13:34:52.920] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C0.dat
[13:34:52.920] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C1.dat
[13:34:52.920] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C2.dat
[13:34:52.920] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C3.dat
[13:34:52.920] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C4.dat
[13:34:52.921] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C5.dat
[13:34:52.921] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C6.dat
[13:34:52.921] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C7.dat
[13:34:52.921] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C8.dat
[13:34:52.921] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C9.dat
[13:34:52.921] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C10.dat
[13:34:52.921] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C11.dat
[13:34:52.921] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C12.dat
[13:34:52.921] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C13.dat
[13:34:52.921] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C14.dat
[13:34:52.921] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C15.dat
[13:34:52.951] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:34:52.951] <TB3> INFO: ----------------------------------------------------------------------
[13:34:52.951] <TB3> INFO: PixTestReadback::readbackVbg()
[13:34:52.951] <TB3> INFO: ----------------------------------------------------------------------
[13:35:00.255] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:35:00.255] <TB3> INFO: ----------------------------------------------------------------------
[13:35:00.255] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[13:35:00.255] <TB3> INFO: ----------------------------------------------------------------------
[13:35:00.255] <TB3> INFO: Vbg will be calibrated using Vd calibration
[13:35:00.255] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 156.3calibrated Vbg = 1.20592 :::*/*/*/*/
[13:35:00.255] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 156.4calibrated Vbg = 1.1982 :::*/*/*/*/
[13:35:00.255] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155.4calibrated Vbg = 1.20627 :::*/*/*/*/
[13:35:00.255] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 149.2calibrated Vbg = 1.22011 :::*/*/*/*/
[13:35:00.255] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 157calibrated Vbg = 1.2143 :::*/*/*/*/
[13:35:00.255] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 156.9calibrated Vbg = 1.21234 :::*/*/*/*/
[13:35:00.255] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155calibrated Vbg = 1.22218 :::*/*/*/*/
[13:35:00.255] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 153calibrated Vbg = 1.22247 :::*/*/*/*/
[13:35:00.255] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 147.9calibrated Vbg = 1.22432 :::*/*/*/*/
[13:35:00.255] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 160.5calibrated Vbg = 1.22332 :::*/*/*/*/
[13:35:00.255] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 142.2calibrated Vbg = 1.22292 :::*/*/*/*/
[13:35:00.255] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 145.3calibrated Vbg = 1.20934 :::*/*/*/*/
[13:35:00.255] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 153.3calibrated Vbg = 1.2031 :::*/*/*/*/
[13:35:00.255] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 153.3calibrated Vbg = 1.19487 :::*/*/*/*/
[13:35:00.256] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.7calibrated Vbg = 1.20754 :::*/*/*/*/
[13:35:00.256] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 151calibrated Vbg = 1.20134 :::*/*/*/*/
[13:35:00.258] <TB3> INFO: ----------------------------------------------------------------------
[13:35:00.258] <TB3> INFO: PixTestReadback::CalibrateIa()
[13:35:00.258] <TB3> INFO: ----------------------------------------------------------------------
[13:37:35.487] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C0.dat
[13:37:35.487] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C1.dat
[13:37:35.487] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C2.dat
[13:37:35.487] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C3.dat
[13:37:35.487] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C4.dat
[13:37:35.487] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C5.dat
[13:37:35.487] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C6.dat
[13:37:35.487] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C7.dat
[13:37:35.487] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C8.dat
[13:37:35.487] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C9.dat
[13:37:35.488] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C10.dat
[13:37:35.488] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C11.dat
[13:37:35.488] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C12.dat
[13:37:35.488] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C13.dat
[13:37:35.488] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C14.dat
[13:37:35.488] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C15.dat
[13:37:35.510] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:37:35.511] <TB3> INFO: PixTestReadback::doTest() done
[13:37:35.511] <TB3> INFO: Decoding statistics:
[13:37:35.511] <TB3> INFO: General information:
[13:37:35.511] <TB3> INFO: 16bit words read: 1024
[13:37:35.511] <TB3> INFO: valid events total: 128
[13:37:35.511] <TB3> INFO: empty events: 128
[13:37:35.511] <TB3> INFO: valid events with pixels: 0
[13:37:35.511] <TB3> INFO: valid pixel hits: 0
[13:37:35.511] <TB3> INFO: Event errors: 0
[13:37:35.511] <TB3> INFO: start marker: 0
[13:37:35.511] <TB3> INFO: stop marker: 0
[13:37:35.511] <TB3> INFO: overflow: 0
[13:37:35.511] <TB3> INFO: invalid 5bit words: 0
[13:37:35.511] <TB3> INFO: invalid XOR eye diagram: 0
[13:37:35.511] <TB3> INFO: frame (failed synchr.): 0
[13:37:35.511] <TB3> INFO: idle data (no TBM trl): 0
[13:37:35.511] <TB3> INFO: no data (only TBM hdr): 0
[13:37:35.511] <TB3> INFO: TBM errors: 0
[13:37:35.511] <TB3> INFO: flawed TBM headers: 0
[13:37:35.511] <TB3> INFO: flawed TBM trailers: 0
[13:37:35.511] <TB3> INFO: event ID mismatches: 0
[13:37:35.511] <TB3> INFO: ROC errors: 0
[13:37:35.511] <TB3> INFO: missing ROC header(s): 0
[13:37:35.511] <TB3> INFO: misplaced readback start: 0
[13:37:35.511] <TB3> INFO: Pixel decoding errors: 0
[13:37:35.511] <TB3> INFO: pixel data incomplete: 0
[13:37:35.511] <TB3> INFO: pixel address: 0
[13:37:35.511] <TB3> INFO: pulse height fill bit: 0
[13:37:35.511] <TB3> INFO: buffer corruption: 0
[13:37:35.530] <TB3> INFO: Decoding statistics:
[13:37:35.530] <TB3> INFO: General information:
[13:37:35.530] <TB3> INFO: 16bit words read: 2755360
[13:37:35.530] <TB3> INFO: valid events total: 176768
[13:37:35.530] <TB3> INFO: empty events: 7864
[13:37:35.530] <TB3> INFO: valid events with pixels: 168904
[13:37:35.530] <TB3> INFO: valid pixel hits: 668097
[13:37:35.530] <TB3> INFO: Event errors: 0
[13:37:35.530] <TB3> INFO: start marker: 0
[13:37:35.530] <TB3> INFO: stop marker: 0
[13:37:35.530] <TB3> INFO: overflow: 0
[13:37:35.530] <TB3> INFO: invalid 5bit words: 0
[13:37:35.530] <TB3> INFO: invalid XOR eye diagram: 0
[13:37:35.530] <TB3> INFO: frame (failed synchr.): 0
[13:37:35.530] <TB3> INFO: idle data (no TBM trl): 0
[13:37:35.530] <TB3> INFO: no data (only TBM hdr): 0
[13:37:35.530] <TB3> INFO: TBM errors: 0
[13:37:35.530] <TB3> INFO: flawed TBM headers: 0
[13:37:35.530] <TB3> INFO: flawed TBM trailers: 0
[13:37:35.530] <TB3> INFO: event ID mismatches: 0
[13:37:35.530] <TB3> INFO: ROC errors: 0
[13:37:35.530] <TB3> INFO: missing ROC header(s): 0
[13:37:35.530] <TB3> INFO: misplaced readback start: 0
[13:37:35.530] <TB3> INFO: Pixel decoding errors: 0
[13:37:35.530] <TB3> INFO: pixel data incomplete: 0
[13:37:35.530] <TB3> INFO: pixel address: 0
[13:37:35.530] <TB3> INFO: pulse height fill bit: 0
[13:37:35.530] <TB3> INFO: buffer corruption: 0
[13:37:35.530] <TB3> INFO: enter test to run
[13:37:35.530] <TB3> INFO: test: exit no parameter change
[13:37:35.790] <TB3> QUIET: Connection to board 170 closed.
[13:37:35.869] <TB3> INFO: pXar: this is the end, my friend