[14:42:44.655] <TB3> INFO: *** Welcome to pxar ***
[14:42:44.655] <TB3> INFO: *** Today: 2016/08/08
[14:42:44.747] <TB3> INFO: *** Version: 9751-dirty
[14:42:44.747] <TB3> INFO: readRocDacs: /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C0.dat .. /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C15.dat
[14:42:44.763] <TB3> INFO: readTbmDacs: /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:42:44.765] <TB3> INFO: readMaskFile: /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//defaultMaskFile.dat
[14:42:44.766] <TB3> INFO: readTrimFile: /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters_C0.dat .. /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters_C15.dat
[14:42:44.877] <TB3> INFO: clk: 4
[14:42:44.877] <TB3> INFO: ctr: 4
[14:42:44.877] <TB3> INFO: sda: 19
[14:42:44.877] <TB3> INFO: tin: 9
[14:42:44.877] <TB3> INFO: level: 15
[14:42:44.877] <TB3> INFO: triggerdelay: 0
[14:42:44.877] <TB3> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[14:42:44.877] <TB3> INFO: Log level: INFO
[14:42:44.884] <TB3> INFO: Found DTB DTB_WWVASW
[14:42:44.896] <TB3> QUIET: Connection to board DTB_WWVASW opened.
[14:42:44.898] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 126
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWVASW
MAC address: 40D85511807E
Hostname: pixelDTB126
Comment:
------------------------------------------------------
[14:42:44.900] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[14:42:46.456] <TB3> INFO: DUT info:
[14:42:46.456] <TB3> INFO: The DUT currently contains the following objects:
[14:42:46.456] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:42:46.456] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:42:46.456] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:42:46.456] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:42:46.456] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.456] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:46.858] <TB3> INFO: enter 'restricted' command line mode
[14:42:46.858] <TB3> INFO: enter test to run
[14:42:46.858] <TB3> INFO: test: pretest no parameter change
[14:42:46.858] <TB3> INFO: running: pretest
[14:42:46.864] <TB3> INFO: ----------------------------------------------------------------------
[14:42:46.864] <TB3> INFO: PixTestPretest::programROC()
[14:42:46.864] <TB3> INFO: ----------------------------------------------------------------------
[14:43:04.882] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:43:04.882] <TB3> INFO: IA differences per ROC: 19.3 18.5 17.7 18.5 18.5 18.5 18.5 18.5 18.5 20.9 20.1 19.3 19.3 19.3 20.9 17.7
[14:43:04.997] <TB3> INFO: enter test to run
[14:43:04.997] <TB3> INFO: test: pretest no parameter change
[14:43:04.997] <TB3> INFO: running: pretest
[14:43:04.998] <TB3> INFO: ----------------------------------------------------------------------
[14:43:04.998] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:43:04.998] <TB3> INFO: ----------------------------------------------------------------------
[14:43:12.816] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 381.9 mA = 23.8688 mA/ROC
[14:43:12.817] <TB3> INFO: i(loss) [mA/ROC]: 18.5 18.5 19.3 18.5 18.5 18.5 18.5 18.5 18.5 18.5 18.5 17.7 18.5 17.7 18.5 18.5
[14:43:12.862] <TB3> INFO: enter test to run
[14:43:12.862] <TB3> INFO: test: pretest no parameter change
[14:43:12.862] <TB3> INFO: running: pretest
[14:43:12.862] <TB3> INFO: ----------------------------------------------------------------------
[14:43:12.862] <TB3> INFO: PixTestPretest::findTiming()
[14:43:12.862] <TB3> INFO: ----------------------------------------------------------------------
[14:43:12.862] <TB3> INFO: PixTestCmd::init()

[14:44:43.548] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[14:44:43.549] <TB3> INFO: (success/tries = 100/100), width = 5
[14:44:43.549] <TB3> INFO: enter test to run
[14:44:43.549] <TB3> INFO: test: pretest no parameter change
[14:44:43.549] <TB3> INFO: running: pretest
[14:44:43.551] <TB3> INFO: ----------------------------------------------------------------------
[14:44:43.551] <TB3> INFO: PixTestPretest::findWorkingPixel()
[14:44:43.551] <TB3> INFO: ----------------------------------------------------------------------
[14:44:43.643] <TB3> INFO: Expecting 231680 events.


[14:44:53.781] <TB3> INFO: 231680 events read in total (9581ms).
[14:44:53.785] <TB3> INFO: Test took 10232ms.
[14:44:54.013] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[14:44:54.093] <TB3> INFO: enter test to run
[14:44:54.093] <TB3> INFO: test: pretest no parameter change
[14:44:54.093] <TB3> INFO: running: pretest
[14:44:54.094] <TB3> INFO: ----------------------------------------------------------------------
[14:44:54.094] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[14:44:54.094] <TB3> INFO: ----------------------------------------------------------------------
[14:44:54.189] <TB3> INFO: Expecting 231680 events.
[14:45:04.443] <TB3> INFO: 231680 events read in total (9696ms).
[14:45:04.447] <TB3> INFO: Test took 10348ms.
[14:45:04.714] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[14:45:04.715] <TB3> INFO: CalDel: 151 132 134 130 151 160 140 126 143 143 144 142 140 143 154 150
[14:45:04.715] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[14:45:04.748] <TB3> INFO: enter test to run
[14:45:04.749] <TB3> INFO: test: pretest no parameter change
[14:45:04.749] <TB3> INFO: running: pretest
[14:45:04.755] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C0.dat
[14:45:04.760] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C1.dat
[14:45:04.765] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C2.dat
[14:45:04.770] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C3.dat
[14:45:04.776] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C4.dat
[14:45:04.781] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C5.dat
[14:45:04.786] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C6.dat
[14:45:04.791] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C7.dat
[14:45:04.796] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C8.dat
[14:45:04.801] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C9.dat
[14:45:04.806] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C10.dat
[14:45:04.811] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C11.dat
[14:45:04.817] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C12.dat
[14:45:04.822] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C13.dat
[14:45:04.827] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C14.dat
[14:45:04.832] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C15.dat
[14:45:04.835] <TB3> INFO: enter test to run
[14:45:04.835] <TB3> INFO: test: fulltest no parameter change
[14:45:04.835] <TB3> INFO: running: fulltest
[14:45:04.835] <TB3> INFO: ######################################################################
[14:45:04.835] <TB3> INFO: PixTestFullTest::doTest()
[14:45:04.835] <TB3> INFO: ######################################################################
[14:45:04.836] <TB3> INFO: ######################################################################
[14:45:04.836] <TB3> INFO: PixTestAlive::doTest()
[14:45:04.836] <TB3> INFO: ######################################################################
[14:45:04.838] <TB3> INFO: ----------------------------------------------------------------------
[14:45:04.838] <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)
[14:45:04.838] <TB3> INFO: ----------------------------------------------------------------------
[14:45:05.110] <TB3> INFO: Expecting 41600 events.
[14:45:08.928] <TB3> INFO: 41600 events read in total (3259ms).
[14:45:08.930] <TB3> INFO: Test took 4090ms.
[14:45:09.157] <TB3> INFO: PixTestAlive::aliveTest() done
[14:45:09.157] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:45:09.158] <TB3> INFO: ----------------------------------------------------------------------
[14:45:09.158] <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)
[14:45:09.158] <TB3> INFO: ----------------------------------------------------------------------
[14:45:09.450] <TB3> INFO: Expecting 41600 events.
[14:45:12.423] <TB3> INFO: 41600 events read in total (2417ms).
[14:45:12.423] <TB3> INFO: Test took 3262ms.
[14:45:12.424] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:45:12.687] <TB3> INFO: PixTestAlive::maskTest() done
[14:45:12.687] <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
[14:45:12.689] <TB3> INFO: ----------------------------------------------------------------------
[14:45:12.689] <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)
[14:45:12.689] <TB3> INFO: ----------------------------------------------------------------------
[14:45:12.961] <TB3> INFO: Expecting 41600 events.
[14:45:16.822] <TB3> INFO: 41600 events read in total (3304ms).
[14:45:16.822] <TB3> INFO: Test took 4131ms.
[14:45:17.070] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[14:45:17.070] <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
[14:45:17.070] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[14:45:17.070] <TB3> INFO: Decoding statistics:
[14:45:17.070] <TB3> INFO: General information:
[14:45:17.070] <TB3> INFO: 16bit words read: 0
[14:45:17.070] <TB3> INFO: valid events total: 0
[14:45:17.070] <TB3> INFO: empty events: 0
[14:45:17.070] <TB3> INFO: valid events with pixels: 0
[14:45:17.070] <TB3> INFO: valid pixel hits: 0
[14:45:17.070] <TB3> INFO: Event errors: 0
[14:45:17.070] <TB3> INFO: start marker: 0
[14:45:17.071] <TB3> INFO: stop marker: 0
[14:45:17.071] <TB3> INFO: overflow: 0
[14:45:17.071] <TB3> INFO: invalid 5bit words: 0
[14:45:17.071] <TB3> INFO: invalid XOR eye diagram: 0
[14:45:17.071] <TB3> INFO: frame (failed synchr.): 0
[14:45:17.071] <TB3> INFO: idle data (no TBM trl): 0
[14:45:17.071] <TB3> INFO: no data (only TBM hdr): 0
[14:45:17.071] <TB3> INFO: TBM errors: 0
[14:45:17.071] <TB3> INFO: flawed TBM headers: 0
[14:45:17.071] <TB3> INFO: flawed TBM trailers: 0
[14:45:17.071] <TB3> INFO: event ID mismatches: 0
[14:45:17.071] <TB3> INFO: ROC errors: 0
[14:45:17.071] <TB3> INFO: missing ROC header(s): 0
[14:45:17.071] <TB3> INFO: misplaced readback start: 0
[14:45:17.071] <TB3> INFO: Pixel decoding errors: 0
[14:45:17.071] <TB3> INFO: pixel data incomplete: 0
[14:45:17.071] <TB3> INFO: pixel address: 0
[14:45:17.071] <TB3> INFO: pulse height fill bit: 0
[14:45:17.071] <TB3> INFO: buffer corruption: 0
[14:45:17.077] <TB3> INFO: ######################################################################
[14:45:17.077] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[14:45:17.077] <TB3> INFO: ######################################################################
[14:45:17.081] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[14:45:17.094] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[14:45:17.094] <TB3> INFO: run 1 of 1
[14:45:17.385] <TB3> INFO: Expecting 3120000 events.
[14:45:59.008] <TB3> INFO: 863510 events read in total (41066ms).
[14:46:40.071] <TB3> INFO: 1710425 events read in total (82130ms).
[14:47:20.699] <TB3> INFO: 2569485 events read in total (122757ms).
[14:47:47.309] <TB3> INFO: 3120000 events read in total (149367ms).
[14:47:47.361] <TB3> INFO: Test took 150267ms.
[14:48:11.115] <TB3> INFO: PixTestBBMap::doTest() done, duration: 174 seconds
[14:48:11.116] <TB3> INFO: number of dead bumps (per ROC): 1 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:48:11.116] <TB3> INFO: separation cut (per ROC): 71 71 85 74 87 73 85 87 65 85 84 72 73 74 83 79
[14:48:11.116] <TB3> INFO: Decoding statistics:
[14:48:11.116] <TB3> INFO: General information:
[14:48:11.116] <TB3> INFO: 16bit words read: 0
[14:48:11.116] <TB3> INFO: valid events total: 0
[14:48:11.116] <TB3> INFO: empty events: 0
[14:48:11.116] <TB3> INFO: valid events with pixels: 0
[14:48:11.116] <TB3> INFO: valid pixel hits: 0
[14:48:11.116] <TB3> INFO: Event errors: 0
[14:48:11.116] <TB3> INFO: start marker: 0
[14:48:11.116] <TB3> INFO: stop marker: 0
[14:48:11.116] <TB3> INFO: overflow: 0
[14:48:11.116] <TB3> INFO: invalid 5bit words: 0
[14:48:11.116] <TB3> INFO: invalid XOR eye diagram: 0
[14:48:11.116] <TB3> INFO: frame (failed synchr.): 0
[14:48:11.116] <TB3> INFO: idle data (no TBM trl): 0
[14:48:11.116] <TB3> INFO: no data (only TBM hdr): 0
[14:48:11.116] <TB3> INFO: TBM errors: 0
[14:48:11.116] <TB3> INFO: flawed TBM headers: 0
[14:48:11.116] <TB3> INFO: flawed TBM trailers: 0
[14:48:11.116] <TB3> INFO: event ID mismatches: 0
[14:48:11.116] <TB3> INFO: ROC errors: 0
[14:48:11.116] <TB3> INFO: missing ROC header(s): 0
[14:48:11.116] <TB3> INFO: misplaced readback start: 0
[14:48:11.116] <TB3> INFO: Pixel decoding errors: 0
[14:48:11.116] <TB3> INFO: pixel data incomplete: 0
[14:48:11.116] <TB3> INFO: pixel address: 0
[14:48:11.116] <TB3> INFO: pulse height fill bit: 0
[14:48:11.116] <TB3> INFO: buffer corruption: 0
[14:48:11.199] <TB3> INFO: ######################################################################
[14:48:11.199] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:48:11.199] <TB3> INFO: ######################################################################
[14:48:11.199] <TB3> INFO: ----------------------------------------------------------------------
[14:48:11.199] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:48:11.199] <TB3> INFO: ----------------------------------------------------------------------
[14:48:11.199] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[14:48:11.208] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[14:48:11.208] <TB3> INFO: run 1 of 1
[14:48:11.506] <TB3> INFO: Expecting 26208000 events.
[14:48:45.379] <TB3> INFO: 908350 events read in total (33316ms).
[14:49:18.802] <TB3> INFO: 1801850 events read in total (66739ms).
[14:49:51.927] <TB3> INFO: 2694350 events read in total (99864ms).
[14:50:25.353] <TB3> INFO: 3587300 events read in total (133290ms).
[14:50:58.713] <TB3> INFO: 4477100 events read in total (166650ms).
[14:51:31.888] <TB3> INFO: 5366050 events read in total (199825ms).
[14:52:05.183] <TB3> INFO: 6255550 events read in total (233121ms).
[14:52:38.303] <TB3> INFO: 7142600 events read in total (266240ms).
[14:53:11.610] <TB3> INFO: 8031350 events read in total (299547ms).
[14:53:44.695] <TB3> INFO: 8919550 events read in total (332632ms).
[14:54:17.881] <TB3> INFO: 9806400 events read in total (365818ms).
[14:54:51.423] <TB3> INFO: 10692650 events read in total (399360ms).
[14:55:24.749] <TB3> INFO: 11577750 events read in total (432686ms).
[14:55:58.621] <TB3> INFO: 12461650 events read in total (466558ms).
[14:56:33.081] <TB3> INFO: 13341700 events read in total (501018ms).
[14:57:07.167] <TB3> INFO: 14216500 events read in total (535104ms).
[14:57:40.599] <TB3> INFO: 15089200 events read in total (568536ms).
[14:58:13.908] <TB3> INFO: 15961650 events read in total (601845ms).
[14:58:47.250] <TB3> INFO: 16832350 events read in total (635187ms).
[14:59:20.553] <TB3> INFO: 17702750 events read in total (668490ms).
[14:59:53.885] <TB3> INFO: 18574050 events read in total (701822ms).
[15:00:27.312] <TB3> INFO: 19442450 events read in total (735249ms).
[15:01:00.650] <TB3> INFO: 20312450 events read in total (768587ms).
[15:01:34.051] <TB3> INFO: 21180050 events read in total (801988ms).
[15:02:07.424] <TB3> INFO: 22047800 events read in total (835361ms).
[15:02:40.910] <TB3> INFO: 22914900 events read in total (868847ms).
[15:03:14.259] <TB3> INFO: 23784900 events read in total (902196ms).
[15:03:47.362] <TB3> INFO: 24654150 events read in total (935299ms).
[15:04:22.388] <TB3> INFO: 25524400 events read in total (970325ms).
[15:04:48.253] <TB3> INFO: 26208000 events read in total (996190ms).
[15:04:48.290] <TB3> INFO: Test took 997082ms.
[15:04:48.560] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:04:50.078] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:04:51.646] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:04:53.436] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:04:54.914] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:04:56.437] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:04:58.082] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:04:59.551] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:05:01.038] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:05:02.549] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:05:04.084] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:05:05.568] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:05:07.165] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:05:08.740] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:05:10.243] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:05:11.778] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:05:13.335] <TB3> INFO: PixTestScurves::scurves() done
[15:05:13.335] <TB3> INFO: Vcal mean: 82.99 86.01 90.56 85.69 91.53 86.56 92.26 96.44 89.29 91.13 94.57 80.24 83.68 88.04 96.90 91.97
[15:05:13.335] <TB3> INFO: Vcal RMS: 4.60 4.61 4.80 4.84 4.86 5.65 4.88 5.55 4.92 5.14 5.21 3.89 4.20 5.06 4.59 5.42
[15:05:13.335] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1022 seconds
[15:05:13.335] <TB3> INFO: Decoding statistics:
[15:05:13.335] <TB3> INFO: General information:
[15:05:13.335] <TB3> INFO: 16bit words read: 0
[15:05:13.335] <TB3> INFO: valid events total: 0
[15:05:13.335] <TB3> INFO: empty events: 0
[15:05:13.335] <TB3> INFO: valid events with pixels: 0
[15:05:13.335] <TB3> INFO: valid pixel hits: 0
[15:05:13.335] <TB3> INFO: Event errors: 0
[15:05:13.335] <TB3> INFO: start marker: 0
[15:05:13.335] <TB3> INFO: stop marker: 0
[15:05:13.335] <TB3> INFO: overflow: 0
[15:05:13.335] <TB3> INFO: invalid 5bit words: 0
[15:05:13.335] <TB3> INFO: invalid XOR eye diagram: 0
[15:05:13.335] <TB3> INFO: frame (failed synchr.): 0
[15:05:13.335] <TB3> INFO: idle data (no TBM trl): 0
[15:05:13.335] <TB3> INFO: no data (only TBM hdr): 0
[15:05:13.335] <TB3> INFO: TBM errors: 0
[15:05:13.335] <TB3> INFO: flawed TBM headers: 0
[15:05:13.335] <TB3> INFO: flawed TBM trailers: 0
[15:05:13.335] <TB3> INFO: event ID mismatches: 0
[15:05:13.335] <TB3> INFO: ROC errors: 0
[15:05:13.335] <TB3> INFO: missing ROC header(s): 0
[15:05:13.335] <TB3> INFO: misplaced readback start: 0
[15:05:13.335] <TB3> INFO: Pixel decoding errors: 0
[15:05:13.335] <TB3> INFO: pixel data incomplete: 0
[15:05:13.335] <TB3> INFO: pixel address: 0
[15:05:13.335] <TB3> INFO: pulse height fill bit: 0
[15:05:13.335] <TB3> INFO: buffer corruption: 0
[15:05:13.410] <TB3> INFO: ######################################################################
[15:05:13.410] <TB3> INFO: PixTestTrim::doTest()
[15:05:13.410] <TB3> INFO: ######################################################################
[15:05:13.411] <TB3> INFO: ----------------------------------------------------------------------
[15:05:13.411] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[15:05:13.411] <TB3> INFO: ----------------------------------------------------------------------
[15:05:13.497] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:05:13.497] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:05:13.507] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:05:13.507] <TB3> INFO: run 1 of 1
[15:05:13.768] <TB3> INFO: Expecting 6281600 events.
[15:05:53.839] <TB3> INFO: 1090080 events read in total (39513ms).
[15:06:33.336] <TB3> INFO: 2173270 events read in total (79010ms).
[15:07:12.408] <TB3> INFO: 3248990 events read in total (118082ms).
[15:07:51.751] <TB3> INFO: 4329500 events read in total (157425ms).
[15:08:32.871] <TB3> INFO: 5413010 events read in total (198545ms).
[15:09:04.931] <TB3> INFO: 6281600 events read in total (230605ms).
[15:09:04.961] <TB3> INFO: Test took 231454ms.
[15:09:24.177] <TB3> INFO: ROC 0 VthrComp = 87
[15:09:24.177] <TB3> INFO: ROC 1 VthrComp = 89
[15:09:24.177] <TB3> INFO: ROC 2 VthrComp = 93
[15:09:24.177] <TB3> INFO: ROC 3 VthrComp = 86
[15:09:24.178] <TB3> INFO: ROC 4 VthrComp = 94
[15:09:24.178] <TB3> INFO: ROC 5 VthrComp = 87
[15:09:24.178] <TB3> INFO: ROC 6 VthrComp = 95
[15:09:24.178] <TB3> INFO: ROC 7 VthrComp = 99
[15:09:24.178] <TB3> INFO: ROC 8 VthrComp = 87
[15:09:24.178] <TB3> INFO: ROC 9 VthrComp = 95
[15:09:24.178] <TB3> INFO: ROC 10 VthrComp = 94
[15:09:24.178] <TB3> INFO: ROC 11 VthrComp = 84
[15:09:24.179] <TB3> INFO: ROC 12 VthrComp = 88
[15:09:24.179] <TB3> INFO: ROC 13 VthrComp = 89
[15:09:24.179] <TB3> INFO: ROC 14 VthrComp = 96
[15:09:24.179] <TB3> INFO: ROC 15 VthrComp = 89
[15:09:24.179] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:09:24.179] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:09:24.188] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:09:24.188] <TB3> INFO: run 1 of 1
[15:09:24.480] <TB3> INFO: Expecting 6281600 events.
[15:10:00.479] <TB3> INFO: 754720 events read in total (35442ms).
[15:10:35.650] <TB3> INFO: 1507750 events read in total (70613ms).
[15:11:10.903] <TB3> INFO: 2261190 events read in total (105866ms).
[15:11:46.295] <TB3> INFO: 3014770 events read in total (141258ms).
[15:12:21.635] <TB3> INFO: 3763490 events read in total (176598ms).
[15:12:56.818] <TB3> INFO: 4507910 events read in total (211781ms).
[15:13:33.254] <TB3> INFO: 5250220 events read in total (248217ms).
[15:14:09.995] <TB3> INFO: 5992330 events read in total (284958ms).
[15:14:23.744] <TB3> INFO: 6281600 events read in total (298707ms).
[15:14:23.790] <TB3> INFO: Test took 299602ms.
[15:14:48.704] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 56.2579 for pixel 11/20 mean/min/max = 44.2537/32.1426/56.3649
[15:14:48.705] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 56.6942 for pixel 0/37 mean/min/max = 45.1264/33.4303/56.8224
[15:14:48.705] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 56.9395 for pixel 0/9 mean/min/max = 45.5371/33.9514/57.1228
[15:14:48.705] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 56.9751 for pixel 22/70 mean/min/max = 44.7001/32.4017/56.9986
[15:14:48.706] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 55.6615 for pixel 20/0 mean/min/max = 44.6696/33.2955/56.0437
[15:14:48.706] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 57.85 for pixel 11/24 mean/min/max = 44.8221/31.6674/57.9769
[15:14:48.706] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 55.7996 for pixel 47/79 mean/min/max = 44.0885/32.2838/55.8932
[15:14:48.707] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 58.7172 for pixel 0/9 mean/min/max = 45.2216/31.5534/58.8898
[15:14:48.707] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 59.0085 for pixel 39/79 mean/min/max = 45.9047/32.3832/59.4263
[15:14:48.708] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 56.1014 for pixel 8/79 mean/min/max = 44.2742/32.4387/56.1097
[15:14:48.708] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 60.4634 for pixel 29/1 mean/min/max = 46.3941/32.2818/60.5064
[15:14:48.708] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 56.1262 for pixel 21/6 mean/min/max = 44.355/32.5698/56.1402
[15:14:48.709] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 56.3147 for pixel 23/77 mean/min/max = 45.1109/33.722/56.4998
[15:14:48.709] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 58.6134 for pixel 2/36 mean/min/max = 46.3712/33.6764/59.066
[15:14:48.709] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 57.7316 for pixel 6/16 mean/min/max = 45.0344/32.2128/57.856
[15:14:48.710] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 60.5283 for pixel 6/56 mean/min/max = 46.7707/33.0029/60.5384
[15:14:48.710] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:14:48.803] <TB3> INFO: Expecting 514560 events.
[15:15:01.760] <TB3> INFO: 514560 events read in total (12401ms).
[15:15:01.765] <TB3> INFO: Expecting 514560 events.
[15:15:14.567] <TB3> INFO: 514560 events read in total (12382ms).
[15:15:14.574] <TB3> INFO: Expecting 514560 events.
[15:15:27.411] <TB3> INFO: 514560 events read in total (12415ms).
[15:15:27.420] <TB3> INFO: Expecting 514560 events.
[15:15:40.361] <TB3> INFO: 514560 events read in total (12521ms).
[15:15:40.375] <TB3> INFO: Expecting 514560 events.
[15:15:53.382] <TB3> INFO: 514560 events read in total (12596ms).
[15:15:53.396] <TB3> INFO: Expecting 514560 events.
[15:16:06.235] <TB3> INFO: 514560 events read in total (12429ms).
[15:16:06.250] <TB3> INFO: Expecting 514560 events.
[15:16:19.064] <TB3> INFO: 514560 events read in total (12400ms).
[15:16:19.080] <TB3> INFO: Expecting 514560 events.
[15:16:31.878] <TB3> INFO: 514560 events read in total (12388ms).
[15:16:31.897] <TB3> INFO: Expecting 514560 events.
[15:16:44.775] <TB3> INFO: 514560 events read in total (12468ms).
[15:16:44.794] <TB3> INFO: Expecting 514560 events.
[15:16:57.701] <TB3> INFO: 514560 events read in total (12498ms).
[15:16:57.724] <TB3> INFO: Expecting 514560 events.
[15:17:10.559] <TB3> INFO: 514560 events read in total (12433ms).
[15:17:10.587] <TB3> INFO: Expecting 514560 events.
[15:17:23.470] <TB3> INFO: 514560 events read in total (12480ms).
[15:17:23.497] <TB3> INFO: Expecting 514560 events.
[15:17:36.325] <TB3> INFO: 514560 events read in total (12426ms).
[15:17:36.353] <TB3> INFO: Expecting 514560 events.
[15:17:49.229] <TB3> INFO: 514560 events read in total (12473ms).
[15:17:49.261] <TB3> INFO: Expecting 514560 events.
[15:18:02.114] <TB3> INFO: 514560 events read in total (12451ms).
[15:18:02.147] <TB3> INFO: Expecting 514560 events.
[15:18:14.977] <TB3> INFO: 514560 events read in total (12427ms).
[15:18:15.013] <TB3> INFO: Test took 206303ms.
[15:18:15.967] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:18:15.975] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:18:15.975] <TB3> INFO: run 1 of 1
[15:18:16.244] <TB3> INFO: Expecting 6281600 events.
[15:18:51.793] <TB3> INFO: 742780 events read in total (34992ms).
[15:19:26.822] <TB3> INFO: 1484650 events read in total (70021ms).
[15:20:01.958] <TB3> INFO: 2226890 events read in total (105157ms).
[15:20:37.202] <TB3> INFO: 2969710 events read in total (140401ms).
[15:21:12.365] <TB3> INFO: 3707390 events read in total (175564ms).
[15:21:46.861] <TB3> INFO: 4440450 events read in total (210060ms).
[15:22:22.682] <TB3> INFO: 5170750 events read in total (245881ms).
[15:22:57.759] <TB3> INFO: 5899930 events read in total (280958ms).
[15:23:16.041] <TB3> INFO: 6281600 events read in total (299240ms).
[15:23:16.085] <TB3> INFO: Test took 300110ms.
[15:23:42.380] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.045037 .. 255.000000
[15:23:42.463] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[15:23:42.471] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:23:42.471] <TB3> INFO: run 1 of 1
[15:23:42.740] <TB3> INFO: Expecting 10649600 events.
[15:24:18.028] <TB3> INFO: 712780 events read in total (34731ms).
[15:24:52.693] <TB3> INFO: 1425620 events read in total (69396ms).
[15:25:28.238] <TB3> INFO: 2138700 events read in total (104941ms).
[15:26:04.088] <TB3> INFO: 2851550 events read in total (140791ms).
[15:26:38.813] <TB3> INFO: 3564610 events read in total (175516ms).
[15:27:13.779] <TB3> INFO: 4277740 events read in total (210482ms).
[15:27:47.965] <TB3> INFO: 4990750 events read in total (244668ms).
[15:28:22.312] <TB3> INFO: 5703490 events read in total (279015ms).
[15:28:57.290] <TB3> INFO: 6415940 events read in total (313993ms).
[15:29:31.729] <TB3> INFO: 7127490 events read in total (348432ms).
[15:30:06.321] <TB3> INFO: 7838860 events read in total (383024ms).
[15:30:41.503] <TB3> INFO: 8549630 events read in total (418206ms).
[15:31:15.859] <TB3> INFO: 9260460 events read in total (452562ms).
[15:31:51.036] <TB3> INFO: 9971040 events read in total (487739ms).
[15:32:24.290] <TB3> INFO: 10649600 events read in total (520993ms).
[15:32:24.386] <TB3> INFO: Test took 521915ms.
[15:32:55.074] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 15.247582 .. 42.841073
[15:32:55.160] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 52 (-1/-1) hits flags = 528 (plus default)
[15:32:55.168] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:32:55.168] <TB3> INFO: run 1 of 1
[15:32:55.439] <TB3> INFO: Expecting 1996800 events.
[15:33:34.494] <TB3> INFO: 963470 events read in total (38488ms).
[15:34:13.059] <TB3> INFO: 1923990 events read in total (77054ms).
[15:34:16.443] <TB3> INFO: 1996800 events read in total (80438ms).
[15:34:16.458] <TB3> INFO: Test took 81290ms.
[15:34:29.864] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 8.500000 .. 40.530837
[15:34:29.940] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 8 .. 50 (-1/-1) hits flags = 528 (plus default)
[15:34:29.947] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:34:29.947] <TB3> INFO: run 1 of 1
[15:34:30.208] <TB3> INFO: Expecting 1788800 events.
[15:35:09.569] <TB3> INFO: 967850 events read in total (38804ms).
[15:35:42.484] <TB3> INFO: 1788800 events read in total (71719ms).
[15:35:42.500] <TB3> INFO: Test took 72552ms.
[15:35:55.503] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 8.500000 .. 40.530837
[15:35:55.587] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 8 .. 50 (-1/-1) hits flags = 528 (plus default)
[15:35:55.596] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:35:55.596] <TB3> INFO: run 1 of 1
[15:35:55.866] <TB3> INFO: Expecting 1788800 events.
[15:36:35.226] <TB3> INFO: 968650 events read in total (38803ms).
[15:37:07.967] <TB3> INFO: 1788800 events read in total (71544ms).
[15:37:07.980] <TB3> INFO: Test took 72384ms.
[15:37:22.028] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:37:22.028] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:37:22.037] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:37:22.037] <TB3> INFO: run 1 of 1
[15:37:22.298] <TB3> INFO: Expecting 1705600 events.
[15:38:00.768] <TB3> INFO: 878960 events read in total (37914ms).
[15:38:35.803] <TB3> INFO: 1705600 events read in total (72949ms).
[15:38:35.819] <TB3> INFO: Test took 73782ms.
[15:38:50.490] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:38:50.495] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:38:50.500] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:38:50.504] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:38:50.509] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:38:50.513] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:38:50.536] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:38:50.543] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:38:50.547] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:38:50.551] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:38:50.555] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:38:50.558] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:38:50.563] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:38:50.567] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:38:50.571] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:38:50.576] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:38:50.581] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C0.dat
[15:38:50.595] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C1.dat
[15:38:50.607] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C2.dat
[15:38:50.619] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C3.dat
[15:38:50.630] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C4.dat
[15:38:50.643] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C5.dat
[15:38:50.656] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C6.dat
[15:38:50.673] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C7.dat
[15:38:50.684] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C8.dat
[15:38:50.697] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C9.dat
[15:38:50.709] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C10.dat
[15:38:50.727] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C11.dat
[15:38:50.742] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C12.dat
[15:38:50.762] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C13.dat
[15:38:50.777] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C14.dat
[15:38:50.788] <TB3> INFO: write trim parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C15.dat
[15:38:50.799] <TB3> INFO: PixTestTrim::trimTest() done
[15:38:50.799] <TB3> INFO: vtrim: 96 90 91 97 99 94 85 96 92 99 93 92 89 101 99 106
[15:38:50.799] <TB3> INFO: vthrcomp: 87 89 93 86 94 87 95 99 87 95 94 84 88 89 96 89
[15:38:50.799] <TB3> INFO: vcal mean: 34.97 34.95 35.01 34.97 34.97 34.96 34.97 34.93 34.95 34.95 34.96 34.96 34.96 35.03 34.96 34.93
[15:38:50.799] <TB3> INFO: vcal RMS: 0.81 0.81 0.81 1.00 0.87 0.89 0.84 0.88 0.89 0.83 0.87 0.83 0.79 0.82 0.85 1.04
[15:38:50.799] <TB3> INFO: bits mean: 9.86 9.00 8.51 9.95 10.20 9.68 9.70 9.45 9.26 9.74 9.29 9.94 9.06 9.17 9.59 9.47
[15:38:50.799] <TB3> INFO: bits RMS: 2.62 2.74 2.88 2.49 2.31 2.70 2.64 2.83 2.74 2.66 2.72 2.51 2.69 2.58 2.70 2.48
[15:38:50.807] <TB3> INFO: ----------------------------------------------------------------------
[15:38:50.807] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[15:38:50.807] <TB3> INFO: ----------------------------------------------------------------------
[15:38:50.811] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[15:38:50.823] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:38:50.823] <TB3> INFO: run 1 of 1
[15:38:51.115] <TB3> INFO: Expecting 4160000 events.
[15:39:34.352] <TB3> INFO: 938515 events read in total (42680ms).
[15:40:16.624] <TB3> INFO: 1869745 events read in total (84952ms).
[15:40:58.256] <TB3> INFO: 2791845 events read in total (126584ms).
[15:41:41.077] <TB3> INFO: 3710485 events read in total (169405ms).
[15:42:01.554] <TB3> INFO: 4160000 events read in total (189882ms).
[15:42:01.596] <TB3> INFO: Test took 190773ms.
[15:42:32.164] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 157 (-1/-1) hits flags = 528 (plus default)
[15:42:32.172] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:42:32.172] <TB3> INFO: run 1 of 1
[15:42:32.437] <TB3> INFO: Expecting 3286400 events.
[15:43:17.199] <TB3> INFO: 1006045 events read in total (44202ms).
[15:44:00.737] <TB3> INFO: 1998340 events read in total (87740ms).
[15:44:44.804] <TB3> INFO: 2978890 events read in total (131808ms).
[15:44:58.279] <TB3> INFO: 3286400 events read in total (145282ms).
[15:44:58.312] <TB3> INFO: Test took 146140ms.
[15:45:24.309] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 151 (-1/-1) hits flags = 528 (plus default)
[15:45:24.319] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:45:24.319] <TB3> INFO: run 1 of 1
[15:45:24.617] <TB3> INFO: Expecting 3161600 events.
[15:46:09.027] <TB3> INFO: 1026635 events read in total (43853ms).
[15:46:52.625] <TB3> INFO: 2036620 events read in total (87451ms).
[15:47:36.463] <TB3> INFO: 3035940 events read in total (131289ms).
[15:47:42.147] <TB3> INFO: 3161600 events read in total (136973ms).
[15:47:42.174] <TB3> INFO: Test took 137855ms.
[15:48:06.821] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:48:06.830] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:48:06.830] <TB3> INFO: run 1 of 1
[15:48:07.099] <TB3> INFO: Expecting 3140800 events.
[15:48:51.724] <TB3> INFO: 1029490 events read in total (44068ms).
[15:49:35.259] <TB3> INFO: 2042070 events read in total (87603ms).
[15:50:18.850] <TB3> INFO: 3044310 events read in total (131194ms).
[15:50:23.293] <TB3> INFO: 3140800 events read in total (135637ms).
[15:50:23.317] <TB3> INFO: Test took 136487ms.
[15:50:47.004] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 528 (plus default)
[15:50:47.012] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:50:47.012] <TB3> INFO: run 1 of 1
[15:50:47.274] <TB3> INFO: Expecting 3120000 events.
[15:51:31.640] <TB3> INFO: 1032665 events read in total (43809ms).
[15:52:15.274] <TB3> INFO: 2047595 events read in total (87443ms).
[15:52:58.802] <TB3> INFO: 3052915 events read in total (130971ms).
[15:53:01.967] <TB3> INFO: 3120000 events read in total (134136ms).
[15:53:01.991] <TB3> INFO: Test took 134978ms.
[15:53:26.423] <TB3> INFO: PixTestTrim::trimBitTest() done
[15:53:26.425] <TB3> INFO: PixTestTrim::doTest() done, duration: 2893 seconds
[15:53:26.425] <TB3> INFO: Decoding statistics:
[15:53:26.425] <TB3> INFO: General information:
[15:53:26.425] <TB3> INFO: 16bit words read: 0
[15:53:26.425] <TB3> INFO: valid events total: 0
[15:53:26.425] <TB3> INFO: empty events: 0
[15:53:26.425] <TB3> INFO: valid events with pixels: 0
[15:53:26.425] <TB3> INFO: valid pixel hits: 0
[15:53:26.425] <TB3> INFO: Event errors: 0
[15:53:26.425] <TB3> INFO: start marker: 0
[15:53:26.425] <TB3> INFO: stop marker: 0
[15:53:26.425] <TB3> INFO: overflow: 0
[15:53:26.425] <TB3> INFO: invalid 5bit words: 0
[15:53:26.425] <TB3> INFO: invalid XOR eye diagram: 0
[15:53:26.425] <TB3> INFO: frame (failed synchr.): 0
[15:53:26.425] <TB3> INFO: idle data (no TBM trl): 0
[15:53:26.425] <TB3> INFO: no data (only TBM hdr): 0
[15:53:26.425] <TB3> INFO: TBM errors: 0
[15:53:26.425] <TB3> INFO: flawed TBM headers: 0
[15:53:26.425] <TB3> INFO: flawed TBM trailers: 0
[15:53:26.425] <TB3> INFO: event ID mismatches: 0
[15:53:26.425] <TB3> INFO: ROC errors: 0
[15:53:26.425] <TB3> INFO: missing ROC header(s): 0
[15:53:26.425] <TB3> INFO: misplaced readback start: 0
[15:53:26.425] <TB3> INFO: Pixel decoding errors: 0
[15:53:26.425] <TB3> INFO: pixel data incomplete: 0
[15:53:26.425] <TB3> INFO: pixel address: 0
[15:53:26.425] <TB3> INFO: pulse height fill bit: 0
[15:53:26.425] <TB3> INFO: buffer corruption: 0
[15:53:27.105] <TB3> INFO: ######################################################################
[15:53:27.105] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[15:53:27.105] <TB3> INFO: ######################################################################
[15:53:27.373] <TB3> INFO: Expecting 41600 events.
[15:53:31.148] <TB3> INFO: 41600 events read in total (3214ms).
[15:53:31.149] <TB3> INFO: Test took 4042ms.
[15:53:31.621] <TB3> INFO: Expecting 41600 events.
[15:53:35.449] <TB3> INFO: 41600 events read in total (3271ms).
[15:53:35.450] <TB3> INFO: Test took 4096ms.
[15:53:35.750] <TB3> INFO: Expecting 41600 events.
[15:53:39.560] <TB3> INFO: 41600 events read in total (3254ms).
[15:53:39.560] <TB3> INFO: Test took 4086ms.
[15:53:39.818] <TB3> INFO: Expecting 2560 events.
[15:53:40.674] <TB3> INFO: 2560 events read in total (299ms).
[15:53:40.674] <TB3> INFO: Test took 1100ms.
[15:53:40.980] <TB3> INFO: Expecting 2560 events.
[15:53:41.835] <TB3> INFO: 2560 events read in total (298ms).
[15:53:41.835] <TB3> INFO: Test took 1160ms.
[15:53:42.143] <TB3> INFO: Expecting 2560 events.
[15:53:42.999] <TB3> INFO: 2560 events read in total (299ms).
[15:53:42.999] <TB3> INFO: Test took 1163ms.
[15:53:43.306] <TB3> INFO: Expecting 2560 events.
[15:53:44.161] <TB3> INFO: 2560 events read in total (298ms).
[15:53:44.161] <TB3> INFO: Test took 1162ms.
[15:53:44.468] <TB3> INFO: Expecting 2560 events.
[15:53:45.323] <TB3> INFO: 2560 events read in total (298ms).
[15:53:45.323] <TB3> INFO: Test took 1162ms.
[15:53:45.630] <TB3> INFO: Expecting 2560 events.
[15:53:46.485] <TB3> INFO: 2560 events read in total (298ms).
[15:53:46.485] <TB3> INFO: Test took 1162ms.
[15:53:46.792] <TB3> INFO: Expecting 2560 events.
[15:53:47.646] <TB3> INFO: 2560 events read in total (297ms).
[15:53:47.646] <TB3> INFO: Test took 1160ms.
[15:53:47.953] <TB3> INFO: Expecting 2560 events.
[15:53:48.808] <TB3> INFO: 2560 events read in total (298ms).
[15:53:48.808] <TB3> INFO: Test took 1161ms.
[15:53:49.115] <TB3> INFO: Expecting 2560 events.
[15:53:49.969] <TB3> INFO: 2560 events read in total (297ms).
[15:53:49.969] <TB3> INFO: Test took 1160ms.
[15:53:50.277] <TB3> INFO: Expecting 2560 events.
[15:53:51.131] <TB3> INFO: 2560 events read in total (297ms).
[15:53:51.131] <TB3> INFO: Test took 1161ms.
[15:53:51.438] <TB3> INFO: Expecting 2560 events.
[15:53:52.295] <TB3> INFO: 2560 events read in total (300ms).
[15:53:52.295] <TB3> INFO: Test took 1163ms.
[15:53:52.602] <TB3> INFO: Expecting 2560 events.
[15:53:53.458] <TB3> INFO: 2560 events read in total (299ms).
[15:53:53.458] <TB3> INFO: Test took 1162ms.
[15:53:53.765] <TB3> INFO: Expecting 2560 events.
[15:53:54.620] <TB3> INFO: 2560 events read in total (298ms).
[15:53:54.620] <TB3> INFO: Test took 1161ms.
[15:53:54.927] <TB3> INFO: Expecting 2560 events.
[15:53:55.781] <TB3> INFO: 2560 events read in total (297ms).
[15:53:55.781] <TB3> INFO: Test took 1160ms.
[15:53:56.088] <TB3> INFO: Expecting 2560 events.
[15:53:56.943] <TB3> INFO: 2560 events read in total (298ms).
[15:53:56.943] <TB3> INFO: Test took 1161ms.
[15:53:57.251] <TB3> INFO: Expecting 2560 events.
[15:53:58.105] <TB3> INFO: 2560 events read in total (298ms).
[15:53:58.106] <TB3> INFO: Test took 1162ms.
[15:53:58.110] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:53:58.414] <TB3> INFO: Expecting 655360 events.
[15:54:15.376] <TB3> INFO: 655360 events read in total (16405ms).
[15:54:15.385] <TB3> INFO: Expecting 655360 events.
[15:54:32.191] <TB3> INFO: 655360 events read in total (16403ms).
[15:54:32.205] <TB3> INFO: Expecting 655360 events.
[15:54:48.966] <TB3> INFO: 655360 events read in total (16359ms).
[15:54:48.983] <TB3> INFO: Expecting 655360 events.
[15:55:05.710] <TB3> INFO: 655360 events read in total (16324ms).
[15:55:05.729] <TB3> INFO: Expecting 655360 events.
[15:55:22.455] <TB3> INFO: 655360 events read in total (16324ms).
[15:55:22.478] <TB3> INFO: Expecting 655360 events.
[15:55:39.227] <TB3> INFO: 655360 events read in total (16347ms).
[15:55:39.254] <TB3> INFO: Expecting 655360 events.
[15:55:56.149] <TB3> INFO: 655360 events read in total (16493ms).
[15:55:56.179] <TB3> INFO: Expecting 655360 events.
[15:56:12.929] <TB3> INFO: 655360 events read in total (16348ms).
[15:56:12.962] <TB3> INFO: Expecting 655360 events.
[15:56:29.705] <TB3> INFO: 655360 events read in total (16341ms).
[15:56:29.743] <TB3> INFO: Expecting 655360 events.
[15:56:46.529] <TB3> INFO: 655360 events read in total (16384ms).
[15:56:46.573] <TB3> INFO: Expecting 655360 events.
[15:57:03.459] <TB3> INFO: 655360 events read in total (16483ms).
[15:57:03.505] <TB3> INFO: Expecting 655360 events.
[15:57:20.308] <TB3> INFO: 655360 events read in total (16401ms).
[15:57:20.355] <TB3> INFO: Expecting 655360 events.
[15:57:37.209] <TB3> INFO: 655360 events read in total (16452ms).
[15:57:37.266] <TB3> INFO: Expecting 655360 events.
[15:57:54.166] <TB3> INFO: 655360 events read in total (16498ms).
[15:57:54.224] <TB3> INFO: Expecting 655360 events.
[15:58:11.079] <TB3> INFO: 655360 events read in total (16452ms).
[15:58:11.139] <TB3> INFO: Expecting 655360 events.
[15:58:27.816] <TB3> INFO: 655360 events read in total (16274ms).
[15:58:27.882] <TB3> INFO: Test took 269772ms.
[15:58:27.966] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:58:28.193] <TB3> INFO: Expecting 655360 events.
[15:58:45.048] <TB3> INFO: 655360 events read in total (16298ms).
[15:58:45.057] <TB3> INFO: Expecting 655360 events.
[15:59:01.695] <TB3> INFO: 655360 events read in total (16235ms).
[15:59:01.708] <TB3> INFO: Expecting 655360 events.
[15:59:18.177] <TB3> INFO: 655360 events read in total (16067ms).
[15:59:18.194] <TB3> INFO: Expecting 655360 events.
[15:59:34.838] <TB3> INFO: 655360 events read in total (16242ms).
[15:59:34.857] <TB3> INFO: Expecting 655360 events.
[15:59:51.618] <TB3> INFO: 655360 events read in total (16358ms).
[15:59:51.641] <TB3> INFO: Expecting 655360 events.
[16:00:08.273] <TB3> INFO: 655360 events read in total (16229ms).
[16:00:08.307] <TB3> INFO: Expecting 655360 events.
[16:00:24.920] <TB3> INFO: 655360 events read in total (16210ms).
[16:00:24.950] <TB3> INFO: Expecting 655360 events.
[16:00:41.554] <TB3> INFO: 655360 events read in total (16202ms).
[16:00:41.588] <TB3> INFO: Expecting 655360 events.
[16:00:58.208] <TB3> INFO: 655360 events read in total (16218ms).
[16:00:58.246] <TB3> INFO: Expecting 655360 events.
[16:01:14.948] <TB3> INFO: 655360 events read in total (16300ms).
[16:01:14.989] <TB3> INFO: Expecting 655360 events.
[16:01:31.768] <TB3> INFO: 655360 events read in total (16376ms).
[16:01:31.814] <TB3> INFO: Expecting 655360 events.
[16:01:48.781] <TB3> INFO: 655360 events read in total (16564ms).
[16:01:48.828] <TB3> INFO: Expecting 655360 events.
[16:02:05.645] <TB3> INFO: 655360 events read in total (16414ms).
[16:02:05.732] <TB3> INFO: Expecting 655360 events.
[16:02:22.519] <TB3> INFO: 655360 events read in total (16384ms).
[16:02:22.577] <TB3> INFO: Expecting 655360 events.
[16:02:39.381] <TB3> INFO: 655360 events read in total (16402ms).
[16:02:39.444] <TB3> INFO: Expecting 655360 events.
[16:02:56.205] <TB3> INFO: 655360 events read in total (16359ms).
[16:02:56.269] <TB3> INFO: Test took 268303ms.
[16:02:56.469] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.475] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.483] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.491] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.499] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.506] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.513] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.520] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.527] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.533] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.542] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.550] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:02:56.557] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:02:56.564] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[16:02:56.571] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[16:02:56.578] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[16:02:56.585] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.592] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.599] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.608] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.615] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:56.676] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:02:56.682] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:02:56.687] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:02:56.692] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:02:56.697] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:02:56.702] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:02:56.707] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:02:56.712] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:02:56.717] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:02:56.722] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:02:56.728] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:02:56.733] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:02:56.739] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:02:56.744] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:02:56.749] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:02:56.754] <TB3> INFO: write dac parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:02:57.036] <TB3> INFO: Expecting 41600 events.
[16:03:00.540] <TB3> INFO: 41600 events read in total (2947ms).
[16:03:00.540] <TB3> INFO: Test took 3779ms.
[16:03:00.996] <TB3> INFO: Expecting 41600 events.
[16:03:04.493] <TB3> INFO: 41600 events read in total (2940ms).
[16:03:04.494] <TB3> INFO: Test took 3766ms.
[16:03:04.948] <TB3> INFO: Expecting 41600 events.
[16:03:08.452] <TB3> INFO: 41600 events read in total (2947ms).
[16:03:08.453] <TB3> INFO: Test took 3770ms.
[16:03:08.645] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:08.735] <TB3> INFO: Expecting 2560 events.
[16:03:09.590] <TB3> INFO: 2560 events read in total (298ms).
[16:03:09.590] <TB3> INFO: Test took 945ms.
[16:03:09.593] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:09.898] <TB3> INFO: Expecting 2560 events.
[16:03:10.758] <TB3> INFO: 2560 events read in total (303ms).
[16:03:10.758] <TB3> INFO: Test took 1165ms.
[16:03:10.761] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:11.066] <TB3> INFO: Expecting 2560 events.
[16:03:11.921] <TB3> INFO: 2560 events read in total (298ms).
[16:03:11.921] <TB3> INFO: Test took 1160ms.
[16:03:11.924] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:12.229] <TB3> INFO: Expecting 2560 events.
[16:03:13.086] <TB3> INFO: 2560 events read in total (300ms).
[16:03:13.087] <TB3> INFO: Test took 1163ms.
[16:03:13.089] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:13.394] <TB3> INFO: Expecting 2560 events.
[16:03:14.249] <TB3> INFO: 2560 events read in total (298ms).
[16:03:14.250] <TB3> INFO: Test took 1161ms.
[16:03:14.253] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:14.557] <TB3> INFO: Expecting 2560 events.
[16:03:15.413] <TB3> INFO: 2560 events read in total (299ms).
[16:03:15.413] <TB3> INFO: Test took 1160ms.
[16:03:15.417] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:15.721] <TB3> INFO: Expecting 2560 events.
[16:03:16.578] <TB3> INFO: 2560 events read in total (300ms).
[16:03:16.579] <TB3> INFO: Test took 1162ms.
[16:03:16.582] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:16.887] <TB3> INFO: Expecting 2560 events.
[16:03:17.743] <TB3> INFO: 2560 events read in total (299ms).
[16:03:17.743] <TB3> INFO: Test took 1161ms.
[16:03:17.745] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:18.051] <TB3> INFO: Expecting 2560 events.
[16:03:18.907] <TB3> INFO: 2560 events read in total (299ms).
[16:03:18.907] <TB3> INFO: Test took 1162ms.
[16:03:18.911] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:19.215] <TB3> INFO: Expecting 2560 events.
[16:03:20.069] <TB3> INFO: 2560 events read in total (298ms).
[16:03:20.070] <TB3> INFO: Test took 1159ms.
[16:03:20.073] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:20.378] <TB3> INFO: Expecting 2560 events.
[16:03:21.235] <TB3> INFO: 2560 events read in total (300ms).
[16:03:21.235] <TB3> INFO: Test took 1162ms.
[16:03:21.239] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:21.543] <TB3> INFO: Expecting 2560 events.
[16:03:22.398] <TB3> INFO: 2560 events read in total (298ms).
[16:03:22.398] <TB3> INFO: Test took 1159ms.
[16:03:22.400] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:22.706] <TB3> INFO: Expecting 2560 events.
[16:03:23.564] <TB3> INFO: 2560 events read in total (300ms).
[16:03:23.564] <TB3> INFO: Test took 1164ms.
[16:03:23.567] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:23.871] <TB3> INFO: Expecting 2560 events.
[16:03:24.726] <TB3> INFO: 2560 events read in total (298ms).
[16:03:24.727] <TB3> INFO: Test took 1160ms.
[16:03:24.729] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:25.035] <TB3> INFO: Expecting 2560 events.
[16:03:25.890] <TB3> INFO: 2560 events read in total (298ms).
[16:03:25.890] <TB3> INFO: Test took 1161ms.
[16:03:25.893] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:26.198] <TB3> INFO: Expecting 2560 events.
[16:03:27.054] <TB3> INFO: 2560 events read in total (299ms).
[16:03:27.054] <TB3> INFO: Test took 1161ms.
[16:03:27.057] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:27.362] <TB3> INFO: Expecting 2560 events.
[16:03:28.219] <TB3> INFO: 2560 events read in total (300ms).
[16:03:28.220] <TB3> INFO: Test took 1163ms.
[16:03:28.222] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:28.528] <TB3> INFO: Expecting 2560 events.
[16:03:29.387] <TB3> INFO: 2560 events read in total (302ms).
[16:03:29.387] <TB3> INFO: Test took 1165ms.
[16:03:29.390] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:29.695] <TB3> INFO: Expecting 2560 events.
[16:03:30.550] <TB3> INFO: 2560 events read in total (299ms).
[16:03:30.550] <TB3> INFO: Test took 1160ms.
[16:03:30.553] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:30.858] <TB3> INFO: Expecting 2560 events.
[16:03:31.713] <TB3> INFO: 2560 events read in total (298ms).
[16:03:31.713] <TB3> INFO: Test took 1160ms.
[16:03:31.716] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:32.021] <TB3> INFO: Expecting 2560 events.
[16:03:32.883] <TB3> INFO: 2560 events read in total (305ms).
[16:03:32.883] <TB3> INFO: Test took 1167ms.
[16:03:32.886] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:33.190] <TB3> INFO: Expecting 2560 events.
[16:03:34.046] <TB3> INFO: 2560 events read in total (299ms).
[16:03:34.047] <TB3> INFO: Test took 1161ms.
[16:03:34.050] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:34.353] <TB3> INFO: Expecting 2560 events.
[16:03:35.209] <TB3> INFO: 2560 events read in total (299ms).
[16:03:35.209] <TB3> INFO: Test took 1159ms.
[16:03:35.213] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:35.517] <TB3> INFO: Expecting 2560 events.
[16:03:36.377] <TB3> INFO: 2560 events read in total (303ms).
[16:03:36.377] <TB3> INFO: Test took 1164ms.
[16:03:36.380] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:36.685] <TB3> INFO: Expecting 2560 events.
[16:03:37.539] <TB3> INFO: 2560 events read in total (297ms).
[16:03:37.539] <TB3> INFO: Test took 1159ms.
[16:03:37.542] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:37.848] <TB3> INFO: Expecting 2560 events.
[16:03:38.706] <TB3> INFO: 2560 events read in total (301ms).
[16:03:38.706] <TB3> INFO: Test took 1164ms.
[16:03:38.709] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:39.013] <TB3> INFO: Expecting 2560 events.
[16:03:39.867] <TB3> INFO: 2560 events read in total (297ms).
[16:03:39.867] <TB3> INFO: Test took 1158ms.
[16:03:39.869] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:40.176] <TB3> INFO: Expecting 2560 events.
[16:03:41.032] <TB3> INFO: 2560 events read in total (299ms).
[16:03:41.032] <TB3> INFO: Test took 1163ms.
[16:03:41.035] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:41.340] <TB3> INFO: Expecting 2560 events.
[16:03:42.195] <TB3> INFO: 2560 events read in total (298ms).
[16:03:42.196] <TB3> INFO: Test took 1161ms.
[16:03:42.199] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:42.503] <TB3> INFO: Expecting 2560 events.
[16:03:43.358] <TB3> INFO: 2560 events read in total (298ms).
[16:03:43.358] <TB3> INFO: Test took 1159ms.
[16:03:43.361] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:43.667] <TB3> INFO: Expecting 2560 events.
[16:03:44.522] <TB3> INFO: 2560 events read in total (298ms).
[16:03:44.523] <TB3> INFO: Test took 1162ms.
[16:03:44.526] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:44.829] <TB3> INFO: Expecting 2560 events.
[16:03:45.686] <TB3> INFO: 2560 events read in total (300ms).
[16:03:45.686] <TB3> INFO: Test took 1160ms.
[16:03:46.181] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 619 seconds
[16:03:46.181] <TB3> INFO: PH scale (per ROC): 80 78 74 75 78 76 76 78 66 76 76 81 77 78 65 64
[16:03:46.181] <TB3> INFO: PH offset (per ROC): 173 174 176 163 182 171 171 184 161 186 176 169 164 174 188 185
[16:03:46.185] <TB3> INFO: Decoding statistics:
[16:03:46.185] <TB3> INFO: General information:
[16:03:46.185] <TB3> INFO: 16bit words read: 91940
[16:03:46.185] <TB3> INFO: valid events total: 10240
[16:03:46.185] <TB3> INFO: empty events: 7735
[16:03:46.185] <TB3> INFO: valid events with pixels: 2505
[16:03:46.185] <TB3> INFO: valid pixel hits: 2505
[16:03:46.185] <TB3> INFO: Event errors: 0
[16:03:46.185] <TB3> INFO: start marker: 0
[16:03:46.185] <TB3> INFO: stop marker: 0
[16:03:46.185] <TB3> INFO: overflow: 0
[16:03:46.185] <TB3> INFO: invalid 5bit words: 0
[16:03:46.185] <TB3> INFO: invalid XOR eye diagram: 0
[16:03:46.185] <TB3> INFO: frame (failed synchr.): 0
[16:03:46.185] <TB3> INFO: idle data (no TBM trl): 0
[16:03:46.185] <TB3> INFO: no data (only TBM hdr): 0
[16:03:46.185] <TB3> INFO: TBM errors: 0
[16:03:46.185] <TB3> INFO: flawed TBM headers: 0
[16:03:46.185] <TB3> INFO: flawed TBM trailers: 0
[16:03:46.185] <TB3> INFO: event ID mismatches: 0
[16:03:46.185] <TB3> INFO: ROC errors: 0
[16:03:46.185] <TB3> INFO: missing ROC header(s): 0
[16:03:46.185] <TB3> INFO: misplaced readback start: 0
[16:03:46.185] <TB3> INFO: Pixel decoding errors: 0
[16:03:46.185] <TB3> INFO: pixel data incomplete: 0
[16:03:46.185] <TB3> INFO: pixel address: 0
[16:03:46.185] <TB3> INFO: pulse height fill bit: 0
[16:03:46.185] <TB3> INFO: buffer corruption: 0
[16:03:46.360] <TB3> INFO: ######################################################################
[16:03:46.360] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:03:46.360] <TB3> INFO: ######################################################################
[16:03:46.370] <TB3> INFO: scanning low vcal = 10
[16:03:46.631] <TB3> INFO: Expecting 41600 events.
[16:03:50.173] <TB3> INFO: 41600 events read in total (2985ms).
[16:03:50.173] <TB3> INFO: Test took 3803ms.
[16:03:50.176] <TB3> INFO: scanning low vcal = 20
[16:03:50.480] <TB3> INFO: Expecting 41600 events.
[16:03:54.021] <TB3> INFO: 41600 events read in total (2984ms).
[16:03:54.021] <TB3> INFO: Test took 3845ms.
[16:03:54.024] <TB3> INFO: scanning low vcal = 30
[16:03:54.328] <TB3> INFO: Expecting 41600 events.
[16:03:57.881] <TB3> INFO: 41600 events read in total (2996ms).
[16:03:57.882] <TB3> INFO: Test took 3858ms.
[16:03:57.884] <TB3> INFO: scanning low vcal = 40
[16:03:58.179] <TB3> INFO: Expecting 41600 events.
[16:04:02.324] <TB3> INFO: 41600 events read in total (3588ms).
[16:04:02.325] <TB3> INFO: Test took 4441ms.
[16:04:02.328] <TB3> INFO: scanning low vcal = 50
[16:04:02.598] <TB3> INFO: Expecting 41600 events.
[16:04:06.821] <TB3> INFO: 41600 events read in total (3666ms).
[16:04:06.822] <TB3> INFO: Test took 4493ms.
[16:04:06.826] <TB3> INFO: scanning low vcal = 60
[16:04:07.088] <TB3> INFO: Expecting 41600 events.
[16:04:11.302] <TB3> INFO: 41600 events read in total (3657ms).
[16:04:11.303] <TB3> INFO: Test took 4477ms.
[16:04:11.306] <TB3> INFO: scanning low vcal = 70
[16:04:11.572] <TB3> INFO: Expecting 41600 events.
[16:04:15.791] <TB3> INFO: 41600 events read in total (3662ms).
[16:04:15.792] <TB3> INFO: Test took 4486ms.
[16:04:15.795] <TB3> INFO: scanning low vcal = 80
[16:04:16.066] <TB3> INFO: Expecting 41600 events.
[16:04:20.272] <TB3> INFO: 41600 events read in total (3649ms).
[16:04:20.273] <TB3> INFO: Test took 4478ms.
[16:04:20.276] <TB3> INFO: scanning low vcal = 90
[16:04:20.540] <TB3> INFO: Expecting 41600 events.
[16:04:24.727] <TB3> INFO: 41600 events read in total (3630ms).
[16:04:24.728] <TB3> INFO: Test took 4452ms.
[16:04:24.731] <TB3> INFO: scanning low vcal = 100
[16:04:24.992] <TB3> INFO: Expecting 41600 events.
[16:04:29.196] <TB3> INFO: 41600 events read in total (3647ms).
[16:04:29.196] <TB3> INFO: Test took 4465ms.
[16:04:29.200] <TB3> INFO: scanning low vcal = 110
[16:04:29.464] <TB3> INFO: Expecting 41600 events.
[16:04:33.678] <TB3> INFO: 41600 events read in total (3657ms).
[16:04:33.679] <TB3> INFO: Test took 4479ms.
[16:04:33.683] <TB3> INFO: scanning low vcal = 120
[16:04:33.944] <TB3> INFO: Expecting 41600 events.
[16:04:38.137] <TB3> INFO: 41600 events read in total (3636ms).
[16:04:38.138] <TB3> INFO: Test took 4455ms.
[16:04:38.141] <TB3> INFO: scanning low vcal = 130
[16:04:38.405] <TB3> INFO: Expecting 41600 events.
[16:04:42.578] <TB3> INFO: 41600 events read in total (3616ms).
[16:04:42.579] <TB3> INFO: Test took 4438ms.
[16:04:42.582] <TB3> INFO: scanning low vcal = 140
[16:04:42.843] <TB3> INFO: Expecting 41600 events.
[16:04:47.039] <TB3> INFO: 41600 events read in total (3639ms).
[16:04:47.040] <TB3> INFO: Test took 4458ms.
[16:04:47.044] <TB3> INFO: scanning low vcal = 150
[16:04:47.325] <TB3> INFO: Expecting 41600 events.
[16:04:51.518] <TB3> INFO: 41600 events read in total (3636ms).
[16:04:51.519] <TB3> INFO: Test took 4475ms.
[16:04:51.522] <TB3> INFO: scanning low vcal = 160
[16:04:51.783] <TB3> INFO: Expecting 41600 events.
[16:04:55.968] <TB3> INFO: 41600 events read in total (3628ms).
[16:04:55.969] <TB3> INFO: Test took 4447ms.
[16:04:55.972] <TB3> INFO: scanning low vcal = 170
[16:04:56.238] <TB3> INFO: Expecting 41600 events.
[16:05:00.476] <TB3> INFO: 41600 events read in total (3681ms).
[16:05:00.477] <TB3> INFO: Test took 4505ms.
[16:05:00.481] <TB3> INFO: scanning low vcal = 180
[16:05:00.742] <TB3> INFO: Expecting 41600 events.
[16:05:04.941] <TB3> INFO: 41600 events read in total (3642ms).
[16:05:04.942] <TB3> INFO: Test took 4461ms.
[16:05:04.945] <TB3> INFO: scanning low vcal = 190
[16:05:05.217] <TB3> INFO: Expecting 41600 events.
[16:05:09.389] <TB3> INFO: 41600 events read in total (3615ms).
[16:05:09.398] <TB3> INFO: Test took 4453ms.
[16:05:09.401] <TB3> INFO: scanning low vcal = 200
[16:05:09.663] <TB3> INFO: Expecting 41600 events.
[16:05:13.858] <TB3> INFO: 41600 events read in total (3638ms).
[16:05:13.858] <TB3> INFO: Test took 4456ms.
[16:05:13.862] <TB3> INFO: scanning low vcal = 210
[16:05:14.133] <TB3> INFO: Expecting 41600 events.
[16:05:18.327] <TB3> INFO: 41600 events read in total (3637ms).
[16:05:18.327] <TB3> INFO: Test took 4465ms.
[16:05:18.331] <TB3> INFO: scanning low vcal = 220
[16:05:18.598] <TB3> INFO: Expecting 41600 events.
[16:05:22.795] <TB3> INFO: 41600 events read in total (3639ms).
[16:05:22.795] <TB3> INFO: Test took 4464ms.
[16:05:22.798] <TB3> INFO: scanning low vcal = 230
[16:05:23.061] <TB3> INFO: Expecting 41600 events.
[16:05:27.266] <TB3> INFO: 41600 events read in total (3648ms).
[16:05:27.267] <TB3> INFO: Test took 4468ms.
[16:05:27.270] <TB3> INFO: scanning low vcal = 240
[16:05:27.532] <TB3> INFO: Expecting 41600 events.
[16:05:31.706] <TB3> INFO: 41600 events read in total (3617ms).
[16:05:31.707] <TB3> INFO: Test took 4437ms.
[16:05:31.710] <TB3> INFO: scanning low vcal = 250
[16:05:31.971] <TB3> INFO: Expecting 41600 events.
[16:05:36.173] <TB3> INFO: 41600 events read in total (3645ms).
[16:05:36.173] <TB3> INFO: Test took 4463ms.
[16:05:36.178] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[16:05:36.447] <TB3> INFO: Expecting 41600 events.
[16:05:40.643] <TB3> INFO: 41600 events read in total (3639ms).
[16:05:40.644] <TB3> INFO: Test took 4465ms.
[16:05:40.648] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[16:05:40.919] <TB3> INFO: Expecting 41600 events.
[16:05:45.087] <TB3> INFO: 41600 events read in total (3610ms).
[16:05:45.087] <TB3> INFO: Test took 4439ms.
[16:05:45.091] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[16:05:45.352] <TB3> INFO: Expecting 41600 events.
[16:05:49.541] <TB3> INFO: 41600 events read in total (3632ms).
[16:05:49.542] <TB3> INFO: Test took 4451ms.
[16:05:49.545] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[16:05:49.813] <TB3> INFO: Expecting 41600 events.
[16:05:53.989] <TB3> INFO: 41600 events read in total (3619ms).
[16:05:53.990] <TB3> INFO: Test took 4445ms.
[16:05:53.993] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:05:54.256] <TB3> INFO: Expecting 41600 events.
[16:05:58.468] <TB3> INFO: 41600 events read in total (3655ms).
[16:05:58.469] <TB3> INFO: Test took 4475ms.
[16:05:59.551] <TB3> INFO: PixTestGainPedestal::measure() done
[16:06:32.059] <TB3> INFO: PixTestGainPedestal::fit() done
[16:06:32.060] <TB3> INFO: non-linearity mean: 0.954 0.952 0.952 0.947 0.953 0.954 0.960 0.948 0.955 0.953 0.959 0.951 0.952 0.962 0.956 0.953
[16:06:32.060] <TB3> INFO: non-linearity RMS: 0.006 0.007 0.007 0.007 0.006 0.007 0.006 0.009 0.007 0.006 0.006 0.007 0.007 0.006 0.008 0.009
[16:06:32.060] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:06:32.103] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:06:32.160] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:06:32.197] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:06:32.235] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:06:32.273] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:06:32.317] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:06:32.356] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:06:32.396] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:06:32.435] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:06:32.474] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:06:32.515] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:06:32.554] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:06:32.593] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:06:32.633] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:06:32.673] <TB3> INFO: write gain/ped parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:06:32.714] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 166 seconds
[16:06:32.714] <TB3> INFO: Decoding statistics:
[16:06:32.714] <TB3> INFO: General information:
[16:06:32.714] <TB3> INFO: 16bit words read: 2662400
[16:06:32.714] <TB3> INFO: valid events total: 166400
[16:06:32.714] <TB3> INFO: empty events: 0
[16:06:32.714] <TB3> INFO: valid events with pixels: 166400
[16:06:32.714] <TB3> INFO: valid pixel hits: 665570
[16:06:32.714] <TB3> INFO: Event errors: 0
[16:06:32.714] <TB3> INFO: start marker: 0
[16:06:32.714] <TB3> INFO: stop marker: 0
[16:06:32.714] <TB3> INFO: overflow: 0
[16:06:32.714] <TB3> INFO: invalid 5bit words: 0
[16:06:32.714] <TB3> INFO: invalid XOR eye diagram: 0
[16:06:32.714] <TB3> INFO: frame (failed synchr.): 0
[16:06:32.715] <TB3> INFO: idle data (no TBM trl): 0
[16:06:32.715] <TB3> INFO: no data (only TBM hdr): 0
[16:06:32.715] <TB3> INFO: TBM errors: 0
[16:06:32.715] <TB3> INFO: flawed TBM headers: 0
[16:06:32.715] <TB3> INFO: flawed TBM trailers: 0
[16:06:32.715] <TB3> INFO: event ID mismatches: 0
[16:06:32.715] <TB3> INFO: ROC errors: 0
[16:06:32.715] <TB3> INFO: missing ROC header(s): 0
[16:06:32.715] <TB3> INFO: misplaced readback start: 0
[16:06:32.715] <TB3> INFO: Pixel decoding errors: 0
[16:06:32.715] <TB3> INFO: pixel data incomplete: 0
[16:06:32.715] <TB3> INFO: pixel address: 0
[16:06:32.715] <TB3> INFO: pulse height fill bit: 0
[16:06:32.715] <TB3> INFO: buffer corruption: 0
[16:06:32.723] <TB3> INFO: readReadbackCal: /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C0.dat .. /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C15.dat
[16:06:32.886] <TB3> INFO: ######################################################################
[16:06:32.886] <TB3> INFO: PixTestReadback::doTest()
[16:06:32.886] <TB3> INFO: ######################################################################
[16:06:32.886] <TB3> INFO: ----------------------------------------------------------------------
[16:06:32.886] <TB3> INFO: PixTestReadback::CalibrateVd()
[16:06:32.886] <TB3> INFO: ----------------------------------------------------------------------
[16:06:42.415] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C0.dat
[16:06:42.420] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C1.dat
[16:06:42.425] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C2.dat
[16:06:42.430] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C3.dat
[16:06:42.435] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C4.dat
[16:06:42.440] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C5.dat
[16:06:42.445] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C6.dat
[16:06:42.450] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C7.dat
[16:06:42.455] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C8.dat
[16:06:42.460] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C9.dat
[16:06:42.465] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C10.dat
[16:06:42.470] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C11.dat
[16:06:42.475] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C12.dat
[16:06:42.480] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C13.dat
[16:06:42.485] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C14.dat
[16:06:42.492] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C15.dat
[16:06:42.542] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:06:42.542] <TB3> INFO: ----------------------------------------------------------------------
[16:06:42.542] <TB3> INFO: PixTestReadback::CalibrateVa()
[16:06:42.542] <TB3> INFO: ----------------------------------------------------------------------
[16:06:52.127] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C0.dat
[16:06:52.133] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C1.dat
[16:06:52.139] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C2.dat
[16:06:52.145] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C3.dat
[16:06:52.150] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C4.dat
[16:06:52.155] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C5.dat
[16:06:52.160] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C6.dat
[16:06:52.167] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C7.dat
[16:06:52.173] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C8.dat
[16:06:52.178] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C9.dat
[16:06:52.184] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C10.dat
[16:06:52.189] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C11.dat
[16:06:52.196] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C12.dat
[16:06:52.201] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C13.dat
[16:06:52.206] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C14.dat
[16:06:52.214] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C15.dat
[16:06:52.263] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:06:52.263] <TB3> INFO: ----------------------------------------------------------------------
[16:06:52.263] <TB3> INFO: PixTestReadback::readbackVbg()
[16:06:52.263] <TB3> INFO: ----------------------------------------------------------------------
[16:06:59.624] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:06:59.625] <TB3> INFO: ----------------------------------------------------------------------
[16:06:59.625] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[16:06:59.625] <TB3> INFO: ----------------------------------------------------------------------
[16:06:59.625] <TB3> INFO: Vbg will be calibrated using Vd calibration
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 149calibrated Vbg = 1.25244 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 157.9calibrated Vbg = 1.25532 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.7calibrated Vbg = 1.25904 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 154.6calibrated Vbg = 1.25955 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 159.8calibrated Vbg = 1.26859 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 148.8calibrated Vbg = 1.26881 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 149.8calibrated Vbg = 1.27474 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 153.9calibrated Vbg = 1.26832 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 147.5calibrated Vbg = 1.27099 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 152.5calibrated Vbg = 1.26732 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 151.5calibrated Vbg = 1.26922 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 160.1calibrated Vbg = 1.2647 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 140.7calibrated Vbg = 1.25045 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 152calibrated Vbg = 1.24885 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 149.5calibrated Vbg = 1.2566 :::*/*/*/*/
[16:06:59.625] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 149calibrated Vbg = 1.25228 :::*/*/*/*/
[16:06:59.627] <TB3> INFO: ----------------------------------------------------------------------
[16:06:59.627] <TB3> INFO: PixTestReadback::CalibrateIa()
[16:06:59.627] <TB3> INFO: ----------------------------------------------------------------------
[16:09:35.609] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C0.dat
[16:09:35.615] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C1.dat
[16:09:35.620] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C2.dat
[16:09:35.628] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C3.dat
[16:09:35.633] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C4.dat
[16:09:35.639] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C5.dat
[16:09:35.644] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C6.dat
[16:09:35.649] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C7.dat
[16:09:35.654] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C8.dat
[16:09:35.659] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C9.dat
[16:09:35.665] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C10.dat
[16:09:35.670] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C11.dat
[16:09:35.675] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C12.dat
[16:09:35.680] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C13.dat
[16:09:35.686] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C14.dat
[16:09:35.691] <TB3> INFO: write readback calibration parameters into /home/production/dataCH/M2290_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C15.dat
[16:09:35.738] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:09:35.739] <TB3> INFO: PixTestReadback::doTest() done
[16:09:35.739] <TB3> INFO: Decoding statistics:
[16:09:35.739] <TB3> INFO: General information:
[16:09:35.739] <TB3> INFO: 16bit words read: 1024
[16:09:35.739] <TB3> INFO: valid events total: 128
[16:09:35.739] <TB3> INFO: empty events: 128
[16:09:35.739] <TB3> INFO: valid events with pixels: 0
[16:09:35.739] <TB3> INFO: valid pixel hits: 0
[16:09:35.739] <TB3> INFO: Event errors: 0
[16:09:35.739] <TB3> INFO: start marker: 0
[16:09:35.739] <TB3> INFO: stop marker: 0
[16:09:35.739] <TB3> INFO: overflow: 0
[16:09:35.739] <TB3> INFO: invalid 5bit words: 0
[16:09:35.739] <TB3> INFO: invalid XOR eye diagram: 0
[16:09:35.739] <TB3> INFO: frame (failed synchr.): 0
[16:09:35.739] <TB3> INFO: idle data (no TBM trl): 0
[16:09:35.739] <TB3> INFO: no data (only TBM hdr): 0
[16:09:35.739] <TB3> INFO: TBM errors: 0
[16:09:35.739] <TB3> INFO: flawed TBM headers: 0
[16:09:35.739] <TB3> INFO: flawed TBM trailers: 0
[16:09:35.739] <TB3> INFO: event ID mismatches: 0
[16:09:35.739] <TB3> INFO: ROC errors: 0
[16:09:35.739] <TB3> INFO: missing ROC header(s): 0
[16:09:35.739] <TB3> INFO: misplaced readback start: 0
[16:09:35.739] <TB3> INFO: Pixel decoding errors: 0
[16:09:35.739] <TB3> INFO: pixel data incomplete: 0
[16:09:35.739] <TB3> INFO: pixel address: 0
[16:09:35.739] <TB3> INFO: pulse height fill bit: 0
[16:09:35.739] <TB3> INFO: buffer corruption: 0
[16:09:35.758] <TB3> INFO: Decoding statistics:
[16:09:35.758] <TB3> INFO: General information:
[16:09:35.758] <TB3> INFO: 16bit words read: 2755364
[16:09:35.758] <TB3> INFO: valid events total: 176768
[16:09:35.758] <TB3> INFO: empty events: 7863
[16:09:35.758] <TB3> INFO: valid events with pixels: 168905
[16:09:35.758] <TB3> INFO: valid pixel hits: 668075
[16:09:35.758] <TB3> INFO: Event errors: 0
[16:09:35.758] <TB3> INFO: start marker: 0
[16:09:35.758] <TB3> INFO: stop marker: 0
[16:09:35.758] <TB3> INFO: overflow: 0
[16:09:35.758] <TB3> INFO: invalid 5bit words: 0
[16:09:35.758] <TB3> INFO: invalid XOR eye diagram: 0
[16:09:35.758] <TB3> INFO: frame (failed synchr.): 0
[16:09:35.758] <TB3> INFO: idle data (no TBM trl): 0
[16:09:35.758] <TB3> INFO: no data (only TBM hdr): 0
[16:09:35.758] <TB3> INFO: TBM errors: 0
[16:09:35.758] <TB3> INFO: flawed TBM headers: 0
[16:09:35.758] <TB3> INFO: flawed TBM trailers: 0
[16:09:35.758] <TB3> INFO: event ID mismatches: 0
[16:09:35.758] <TB3> INFO: ROC errors: 0
[16:09:35.758] <TB3> INFO: missing ROC header(s): 0
[16:09:35.758] <TB3> INFO: misplaced readback start: 0
[16:09:35.758] <TB3> INFO: Pixel decoding errors: 0
[16:09:35.758] <TB3> INFO: pixel data incomplete: 0
[16:09:35.758] <TB3> INFO: pixel address: 0
[16:09:35.758] <TB3> INFO: pulse height fill bit: 0
[16:09:35.758] <TB3> INFO: buffer corruption: 0
[16:09:35.758] <TB3> INFO: enter test to run
[16:09:35.758] <TB3> INFO: test: exit no parameter change
[16:09:35.802] <TB3> QUIET: Connection to board 126 closed.
[16:09:35.849] <TB3> INFO: pXar: this is the end, my friend