MORE-Web

Test Date: 2015-06-26 09:57
Analysis date: 2015-10-28 15:42
Logfile
LogfileView
[16:26:40.541]     INFO: *** Welcome to pxar ***

[16:26:40.541]     INFO: *** Today: 2015/06/26

[16:26:40.541]     INFO: readRocDacs: /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C0.dat .. /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C15.dat

[16:26:40.541]     INFO: readTbmDacs: /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//tbmParameters_C0a.dat .. /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//tbmParameters_C0b.dat

[16:26:40.542]     INFO: readMaskFile: /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//defaultMaskFile.dat

[16:26:40.542]     INFO: readTrimFile: /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters_C0.dat .. /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters_C15.dat

[16:26:40.604]     INFO:         clk: 4

[16:26:40.604]     INFO:         ctr: 4

[16:26:40.604]     INFO:         sda: 19

[16:26:40.604]     INFO:         tin: 9

[16:26:40.604]     INFO:         level: 15

[16:26:40.604]     INFO:         triggerdelay: 0

[16:26:40.604]    QUIET: Instanciating API for pxar v2.2.4+51~gdb2a247

[16:26:40.604]     INFO: Log level: DEBUG

[16:26:40.617]    QUIET: Connection to board DTB_WV7UX2 opened.

[16:26:40.621]     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    105

HW version:  DTB1.2

FW version:  4.2

SW version:  4.2

USB id:      DTB_WV7UX2

MAC address: 40D855118069

Hostname:    pixelDTB105

Comment:     with CMOS buffer

------------------------------------------------------

[16:26:40.624]     INFO: RPC call hashes of host and DTB match: 447413373

[16:26:42.173]     INFO: DUT info: 

[16:26:42.173]     INFO: The DUT currently contains the following objects:

[16:26:42.173]     INFO:  2 TBM Cores tbm08c (2 ON)

[16:26:42.173]     INFO: 	TBM Core alpha (0): 7 registers set

[16:26:42.173]     INFO: 	TBM Core beta  (1): 7 registers set

[16:26:42.173]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[16:26:42.173]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   dumpall: checkbox(0)

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   dumpproblematic: checkbox(0)

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 5

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   vcals: 250

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   vcals: 222

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   plwidth: 35

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   pix: 11,20

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   targetia: 24

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   dumpall: checkbox(0)

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   dumpproblematic: checkbox(0)

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 5

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   vcals: 250

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   phmap: checkbox

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   dac1: caldel

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   dac1lo: 0

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   dac1hi: 255

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   dac2: vthrcomp

[16:26:42.173]    DEBUG: <PixTestParameters.cc/dump:L107>   dac2lo: 0

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   dac2hi: 255

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   pix: 11,20

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   phmap: checkbox(1)

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   allpixels: checkbox(0)

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   unmasked: checkbox(0)

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   dac: vcal

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   daclo: 0

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   dachi: 255

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   pix: 11,20

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   showfits: checkbox(0)

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   extended: checkbox(0)

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   dumphists: checkbox(0)

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   measure: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   fit: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   save: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   pix: 11,20

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   maskhotpixels: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   savemaskfile: checkbox(0)

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   maskfilename: default

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   caldelscan: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   xpixelalive: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 50

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   vcal: 200

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   xnoisemaps: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   daclo: 0

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   dachi: 100

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   dacs/step: 20

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   rundaq: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   trgfrequency(khz): 20

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   runseconds: 2

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   triggerdelay: 20

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   delaytbm: checkbox

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   filltree: checkbox

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   port: /dev/FIXME

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   voltagestart: 0

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   voltagestop: 600

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   voltagestep: 5

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   delay: 1

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   compliance(ua): 100

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   safetymarginlow: 20

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   saturationvcal: 100

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   quantilesaturation: 0.98

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   vcal: 200

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   alivetest: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   masktest: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   addressdecodingtest: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   programroc: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   targetia: 24

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   setvana: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   iterations: 100

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   settimings: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   findworkingpixel: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   setvthrcompcaldel: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   pix: 11,20

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   vcal: 250

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   deltavthrcomp: 50

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   fraccaldel: 0.5

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 5

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   savedacs: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   calibratevd: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   calibrateva: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   calibrateia: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   readbackvbg: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   getcalibratedvbg: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   usecalvd: checkbox(1)

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   usecalva: checkbox(0)

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   setvana: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   adjustvcal: checkbox(1)

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   dumpall: checkbox(0)

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   dumpproblematic: checkbox(0)

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   dumpoutputfile: checkbox(0)

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 50

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   dac: VthrComp

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   daclo: 0

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   dachi: 200

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   dacs/step: 10

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   scurves: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   fastscan: checkbox(0)

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   targetclk: 4

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   clocksdascan: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   phasescan: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   levelscan: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   tbmphasescan: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   rocdelayscan: button

[16:26:42.174]    DEBUG: <PixTestParameters.cc/dump:L107>   timingtest: button

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   saveparameters: button

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   trim: button

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   vcal: 35

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   trimbits: button

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   maskhotpixels: button

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   savemaskfile: checkbox(0)

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   maskfilename: default

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   source: Ag

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   phrun: button

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   runseconds: 100

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   trgfrequency(khz): 100

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   ratescan: button

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   vthrcompmin: 10

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   vthrcompmax: 80

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   stepseconds: 5

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   delaytbm: checkbox

[16:26:42.175]    DEBUG: <PixTestParameters.cc/dump:L107>   filltree: checkbox

[16:26:42.175]    DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31838208

[16:26:42.175]    DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2b397d0

[16:26:42.175]    DEBUG: <PixSetup.cc/init:L88>  fConfigParameters = 0x2892980

[16:26:42.175]    DEBUG: <PixSetup.cc/init:L89>        fPxarMemory = 0x7f8b49d94010

[16:26:42.175]    DEBUG: <PixSetup.cc/init:L90>         fPxarMemHi = 0x7f8b4ffff510

[16:26:42.175]    DEBUG: <PixSetup.cc/init:L106> PixSetup init done;  getCurrentRSS() = 31895552 fPxarMemory = 0x7f8b49d94010

[16:26:42.175]    DEBUG: <PixTestFactory.cc/PixTestFactory:L51> PixTestFactory::PixTestFactory()

[16:26:42.579]     INFO: enter 'restricted' command line mode

[16:26:42.579]     INFO: enter test to run

[16:26:42.579]     INFO:   test: Pretest no parameter change

[16:26:42.579]     INFO:   running: pretest

[16:26:42.584]     INFO: ######################################################################

[16:26:42.584]     INFO: PixTestPretest::doTest()

[16:26:42.584]     INFO: ######################################################################

[16:26:42.586]     INFO:    ----------------------------------------------------------------------

[16:26:42.586]     INFO:    PixTestPretest::programROC() 

[16:26:42.586]     INFO:    ----------------------------------------------------------------------

[16:27:00.606]     INFO: PixTestPretest::programROC() done: ROCs are all programmable

[16:27:00.606]     INFO: IA differences per ROC:  19.3 19.3 17.7 19.3 20.1 16.1 19.3 20.9 17.7 18.5 20.1 18.5 20.9 17.7 19.3 20.1

[16:27:00.674]     INFO:    ----------------------------------------------------------------------

[16:27:00.674]     INFO:    PixTestPretest::setVana() target Ia = 24 mA/ROC

[16:27:00.674]     INFO:    ----------------------------------------------------------------------

[16:27:00.777]    DEBUG: <PixTestPretest.cc/setVana:L254> offset current from other 15 ROCs is 68.5312 mA

[16:27:00.879]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 0 iter 0 Vana 78 Ia 23.0687 mA

[16:27:00.979]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  0 iter  1 Vana  84 Ia 24.6688 mA

[16:27:01.079]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  0 iter  2 Vana  81 Ia 24.6688 mA

[16:27:01.180]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  0 iter  3 Vana  78 Ia 23.0687 mA

[16:27:01.281]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  0 iter  4 Vana  84 Ia 25.4688 mA

[16:27:01.382]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  0 iter  5 Vana  76 Ia 23.0687 mA

[16:27:01.483]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  0 iter  6 Vana  82 Ia 24.6688 mA

[16:27:01.583]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  0 iter  7 Vana  79 Ia 23.0687 mA

[16:27:01.684]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  0 iter  8 Vana  85 Ia 25.4688 mA

[16:27:01.784]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  0 iter  9 Vana  77 Ia 23.0687 mA

[16:27:01.885]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  0 iter 10 Vana  83 Ia 25.4688 mA

[16:27:01.986]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  0 iter 11 Vana  75 Ia 23.0687 mA

[16:27:02.087]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 1 iter 0 Vana 78 Ia 23.0687 mA

[16:27:02.188]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  1 iter  1 Vana  84 Ia 24.6688 mA

[16:27:02.289]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  1 iter  2 Vana  81 Ia 23.8687 mA

[16:27:02.390]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 2 iter 0 Vana 78 Ia 22.2688 mA

[16:27:02.491]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  2 iter  1 Vana  88 Ia 24.6688 mA

[16:27:02.592]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  2 iter  2 Vana  85 Ia 23.8687 mA

[16:27:02.693]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 3 iter 0 Vana 78 Ia 23.0687 mA

[16:27:02.794]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  3 iter  1 Vana  84 Ia 24.6688 mA

[16:27:02.894]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  3 iter  2 Vana  81 Ia 23.8687 mA

[16:27:02.996]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 4 iter 0 Vana 78 Ia 23.8687 mA

[16:27:03.097]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 5 iter 0 Vana 78 Ia 19.8688 mA

[16:27:03.198]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  5 iter  1 Vana 103 Ia 24.6688 mA

[16:27:03.299]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  5 iter  2 Vana 100 Ia 23.8687 mA

[16:27:03.400]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 6 iter 0 Vana 78 Ia 23.0687 mA

[16:27:03.501]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  6 iter  1 Vana  84 Ia 24.6688 mA

[16:27:03.602]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  6 iter  2 Vana  81 Ia 23.8687 mA

[16:27:03.703]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 7 iter 0 Vana 78 Ia 24.6688 mA

[16:27:03.804]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  7 iter  1 Vana  75 Ia 23.8687 mA

[16:27:03.905]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 8 iter 0 Vana 78 Ia 21.4688 mA

[16:27:03.006]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  8 iter  1 Vana  93 Ia 24.6688 mA

[16:27:04.107]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  8 iter  2 Vana  90 Ia 23.8687 mA

[16:27:04.208]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 9 iter 0 Vana 78 Ia 22.2688 mA

[16:27:04.309]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  9 iter  1 Vana  88 Ia 24.6688 mA

[16:27:04.410]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC  9 iter  2 Vana  85 Ia 23.8687 mA

[16:27:04.512]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 10 iter 0 Vana 78 Ia 23.8687 mA

[16:27:04.613]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 11 iter 0 Vana 78 Ia 22.2688 mA

[16:27:04.714]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC 11 iter  1 Vana  88 Ia 23.8687 mA

[16:27:04.815]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 12 iter 0 Vana 78 Ia 24.6688 mA

[16:27:04.916]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC 12 iter  1 Vana  75 Ia 23.8687 mA

[16:27:05.017]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 13 iter 0 Vana 78 Ia 21.4688 mA

[16:27:05.118]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC 13 iter  1 Vana  93 Ia 24.6688 mA

[16:27:05.219]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC 13 iter  2 Vana  90 Ia 24.6688 mA

[16:27:05.320]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC 13 iter  3 Vana  87 Ia 23.8687 mA

[16:27:05.421]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 14 iter 0 Vana 78 Ia 22.2688 mA

[16:27:05.522]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC 14 iter  1 Vana  88 Ia 24.6688 mA

[16:27:05.623]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC 14 iter  2 Vana  85 Ia 24.6688 mA

[16:27:05.723]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC 14 iter  3 Vana  82 Ia 23.8687 mA

[16:27:05.825]    DEBUG: <PixTestPretest.cc/setVana:L280> ROC 15 iter 0 Vana 78 Ia 23.0687 mA

[16:27:05.926]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC 15 iter  1 Vana  84 Ia 25.4688 mA

[16:27:06.026]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC 15 iter  2 Vana  76 Ia 23.0687 mA

[16:27:06.127]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC 15 iter  3 Vana  82 Ia 24.6688 mA

[16:27:06.227]    DEBUG: <PixTestPretest.cc/setVana:L312> ROC 15 iter  4 Vana  79 Ia 23.8687 mA

[16:27:06.271]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC  0 Vana  75

[16:27:06.272]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC  1 Vana  81

[16:27:06.272]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC  2 Vana  85

[16:27:06.272]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC  3 Vana  81

[16:27:06.272]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC  4 Vana  78

[16:27:06.273]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC  5 Vana 100

[16:27:06.273]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC  6 Vana  81

[16:27:06.273]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC  7 Vana  75

[16:27:06.273]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC  8 Vana  90

[16:27:06.273]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC  9 Vana  85

[16:27:06.273]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC 10 Vana  78

[16:27:06.273]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC 11 Vana  88

[16:27:06.274]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC 12 Vana  75

[16:27:06.274]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC 13 Vana  87

[16:27:06.274]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC 14 Vana  82

[16:27:06.274]    DEBUG: <PixTestPretest.cc/setVana:L343> ROC 15 Vana  79

[16:27:06.377]     INFO: PixTestPretest::setVana() done, Module Ia 385.9 mA = 24.1188 mA/ROC

[16:27:06.378]     INFO:    ----------------------------------------------------------------------

[16:27:06.378]     INFO:    PixTestPreTest::setTimings()

[16:27:06.378]     INFO:    ----------------------------------------------------------------------

[16:27:06.378]    DEBUG: <PixTestPretest.cc/setTimings:L392> Testing Timing: Attempt #1

[16:27:07.343]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:07.343]     INFO: Decoding statistics:

[16:27:07.343]     INFO:   General information:

[16:27:07.343]     INFO: 	 16bit words read:         120

[16:27:07.343]     INFO: 	 valid events total:       10

[16:27:07.343]     INFO: 	 empty events:             10

[16:27:07.343]     INFO: 	 valid events with pixels: 0

[16:27:07.343]     INFO: 	 valid pixel hits:         0

[16:27:07.343]     INFO:   Event errors: 	           0

[16:27:07.343]     INFO: 	 start marker:             0

[16:27:07.343]     INFO: 	 stop marker:              0

[16:27:07.343]     INFO: 	 overflow:                 0

[16:27:07.343]     INFO: 	 invalid 5bit words:       0

[16:27:07.343]     INFO: 	 invalid XOR eye diagram:  0

[16:27:07.343]     INFO:   TBM errors: 		           0

[16:27:07.343]     INFO: 	 flawed TBM headers:       0

[16:27:07.343]     INFO: 	 flawed TBM trailers:      0

[16:27:07.343]     INFO: 	 event ID mismatches:      0

[16:27:07.343]     INFO:   ROC errors: 		           0

[16:27:07.343]     INFO: 	 missing ROC header(s):    0

[16:27:07.343]     INFO: 	 misplaced readback start: 0

[16:27:07.343]     INFO:   Pixel decoding errors:	   0

[16:27:07.343]     INFO: 	 pixel data incomplete:    0

[16:27:07.343]     INFO: 	 pixel address:            0

[16:27:07.343]     INFO: 	 pulse height fill bit:    0

[16:27:07.343]     INFO: 	 buffer corruption:        0

[16:27:07.343]     INFO:    ----------------------------------------------------------------------

[16:27:07.343]     INFO:    Default timings are good. No timing scan needed.

[16:27:07.343]     INFO:    ----------------------------------------------------------------------

[16:27:07.343]     INFO: Test took 965 ms.

[16:27:07.343]     INFO: PixTestPretest::setTimings() done.

[16:27:07.535]     INFO:    ----------------------------------------------------------------------

[16:27:07.535]     INFO:    PixTestPretest::findWorkingPixel()

[16:27:07.535]     INFO:    ----------------------------------------------------------------------

[16:27:14.691]     INFO: Test took 7154ms.

[16:27:14.918]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C0 OK, with vthrComp = 88 and Delta(CalDel) = 64

[16:27:14.920]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C1 OK, with vthrComp = 88 and Delta(CalDel) = 56

[16:27:14.923]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C2 OK, with vthrComp = 97 and Delta(CalDel) = 58

[16:27:14.925]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C3 OK, with vthrComp = 84 and Delta(CalDel) = 57

[16:27:14.927]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C4 OK, with vthrComp = 95 and Delta(CalDel) = 61

[16:27:14.929]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C5 OK, with vthrComp = 101 and Delta(CalDel) = 61

[16:27:14.931]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C6 OK, with vthrComp = 94 and Delta(CalDel) = 62

[16:27:14.933]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C7 OK, with vthrComp = 111 and Delta(CalDel) = 61

[16:27:14.936]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C8 OK, with vthrComp = 81 and Delta(CalDel) = 62

[16:27:14.938]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C9 OK, with vthrComp = 65 and Delta(CalDel) = 64

[16:27:14.940]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C10 OK, with vthrComp = 73 and Delta(CalDel) = 63

[16:27:14.942]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C11 OK, with vthrComp = 81 and Delta(CalDel) = 62

[16:27:14.944]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C12 OK, with vthrComp = 88 and Delta(CalDel) = 61

[16:27:14.946]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C13 OK, with vthrComp = 87 and Delta(CalDel) = 58

[16:27:14.948]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C14 OK, with vthrComp = 87 and Delta(CalDel) = 62

[16:27:14.950]    DEBUG: <PixTestPretest.cc/findWorkingPixel:L1051> fwp_c12_r22_C15 OK, with vthrComp = 98 and Delta(CalDel) = 56

[16:27:14.974]     INFO: Found working pixel in all ROCs: col/row = 12/22

[16:27:15.022]     INFO:    ----------------------------------------------------------------------

[16:27:15.022]     INFO:    PixTestPretest::setVthrCompCalDel()

[16:27:15.022]     INFO:    ----------------------------------------------------------------------

[16:27:22.208]     INFO: Test took 7180ms.

[16:27:22.259]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 143 +/- 31.5

[16:27:22.435]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 106 +/- 27

[16:27:22.437]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 113 +/- 30

[16:27:22.439]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 123 +/- 28.5

[16:27:22.441]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 134 +/- 29.5

[16:27:22.444]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 141 +/- 31

[16:27:22.446]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 142 +/- 31

[16:27:22.448]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 131 +/- 29

[16:27:22.450]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 139 +/- 30.5

[16:27:22.452]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 158 +/- 32

[16:27:22.454]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 148 +/- 31.5

[16:27:22.456]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 138 +/- 30.5

[16:27:22.458]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 144 +/- 30.5

[16:27:22.461]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 134 +/- 29

[16:27:22.463]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 144 +/- 31.5

[16:27:22.465]    DEBUG: <PixTestPretest.cc/setVthrCompCalDel:L580> CalDel: 114 +/- 29

[16:27:22.516]     INFO: PixTestPretest::setVthrCompCalDel() done

[16:27:22.516]     INFO: CalDel:      143   106   113   123   134   141   142   131   139   158   148   138   144   134   144   114

[16:27:22.516]     INFO: VthrComp:     51    51    53    51    51    51    51    51    51    51    51    51    51    51    51    51

[16:27:22.519]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C0.dat

[16:27:22.519]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C1.dat

[16:27:22.520]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C2.dat

[16:27:22.520]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C3.dat

[16:27:22.520]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C4.dat

[16:27:22.520]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C5.dat

[16:27:22.520]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C6.dat

[16:27:22.520]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C7.dat

[16:27:22.520]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C8.dat

[16:27:22.520]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C9.dat

[16:27:22.520]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C10.dat

[16:27:22.520]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C11.dat

[16:27:22.520]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C12.dat

[16:27:22.521]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C13.dat

[16:27:22.521]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C14.dat

[16:27:22.521]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters_C15.dat

[16:27:22.521]     INFO: PixTestPretest::doTest() done, duration: 39 seconds

[16:27:22.521]    DEBUG: <PixTestPretest.cc/~PixTestPretest:L134> PixTestPretest dtor

[16:27:22.590]     INFO: enter test to run

[16:27:22.590]     INFO:   test: Fulltest no parameter change

[16:27:22.590]     INFO:   running: fulltest

[16:27:22.591]    DEBUG: <PixTestFullTest.cc/init:L49> PixTestFullTest::init()

[16:27:22.591]    DEBUG: <PixTestFullTest.cc/PixTestFullTest:L20> PixTestFullTest ctor(PixSetup &a, string, TGTab *)

[16:27:22.591]     INFO: ######################################################################

[16:27:22.591]     INFO: PixTestFullTest::doTest()

[16:27:22.591]     INFO: ######################################################################

[16:27:22.591]    DEBUG: <PixTestAlive.cc/init:L77> PixTestAlive::init()

[16:27:22.591]    DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)

[16:27:22.592]     INFO: ######################################################################

[16:27:22.592]     INFO: PixTestAlive::doTest()

[16:27:22.592]     INFO: ######################################################################

[16:27:22.594]     INFO:    ----------------------------------------------------------------------

[16:27:22.594]     INFO:    PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[16:27:22.594]     INFO:    ----------------------------------------------------------------------

[16:27:22.596]    DEBUG: <PixTest.cc/efficiencyMaps:L328>       attempt #0

[16:27:26.283]     INFO: Test took 3687ms.

[16:27:26.300]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:26.300]    DEBUG: <PixTest.cc/efficiencyMaps:L339>  eff result size = 66541

[16:27:26.300]    DEBUG: <PixTest.cc/efficiencyMaps:L344> Create hists PixelAlive_C0 .. PixelAlive_C15

[16:27:26.540]     INFO: PixTestAlive::aliveTest() done

[16:27:26.540]     INFO: number of dead pixels (per ROC):     4    4    1    0    0    0    0    0    1    0    0    0    2    4    1    2

[16:27:26.540]    DEBUG: <PixTestAlive.cc/aliveTest:L188> number of red-efficiency pixels:     4    4    1    0    0    0    0    0    1    0    0    0    2    4    1    2

[16:27:26.542]     INFO:    ----------------------------------------------------------------------

[16:27:26.542]     INFO:    PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[16:27:26.542]     INFO:    ----------------------------------------------------------------------

[16:27:26.544]    DEBUG: <PixTest.cc/efficiencyMaps:L328>       attempt #0

[16:27:29.197]     INFO: Test took 2653ms.

[16:27:29.198]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:29.198]    DEBUG: <PixTest.cc/efficiencyMaps:L339>  eff result size = 0

[16:27:29.198]    DEBUG: <PixTest.cc/efficiencyMaps:L344> Create hists MaskTest_C0 .. MaskTest_C15

[16:27:29.198]     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0

[16:27:29.437]     INFO: PixTestAlive::maskTest() done

[16:27:29.437]     INFO: number of mask-defect pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0

[16:27:29.439]     INFO:    ----------------------------------------------------------------------

[16:27:29.439]     INFO:    PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[16:27:29.439]     INFO:    ----------------------------------------------------------------------

[16:27:29.441]    DEBUG: <PixTest.cc/efficiencyMaps:L328>       attempt #0

[16:27:33.132]     INFO: Test took 3691ms.

[16:27:33.150]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:33.150]    DEBUG: <PixTest.cc/efficiencyMaps:L339>  eff result size = 66541

[16:27:33.150]    DEBUG: <PixTest.cc/efficiencyMaps:L344> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15

[16:27:33.391]     INFO: PixTestAlive::addressDecodingTest() done

[16:27:33.391]     INFO: number of address-decoding pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0

[16:27:33.391]     INFO: PixTestAlive::doTest() done, duration: 10 seconds

[16:27:33.392]    DEBUG: <PixTestAlive.cc/~PixTestAlive:L109> PixTestAlive dtor

[16:27:33.396]    DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()

[16:27:33.396]    DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)

[16:27:33.397]     INFO: ######################################################################

[16:27:33.397]     INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)

[16:27:33.397]     INFO: ######################################################################

[16:27:33.402]     INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30) hits flags = 2 (plus default)

[16:27:33.413]     INFO:   dacScan step  from 0 .. 29

[16:27:33.413]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:27:51.028]     INFO: Test took 17615ms.

[16:27:51.061]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:51.061]     INFO:   dacScan step  from 30 .. 59

[16:27:51.061]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:28:09.809]     INFO: Test took 18748ms.

[16:28:09.918]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:28:09.932]     INFO:   dacScan step  from 60 .. 89

[16:28:09.932]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:28:33.564]     INFO: Test took 23632ms.

[16:28:33.822]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:28:33.862]     INFO:   dacScan step  from 90 .. 119

[16:28:33.863]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:28:57.883]     INFO: Test took 24020ms.

[16:28:58.135]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:28:58.176]     INFO:   dacScan step  from 120 .. 149

[16:28:58.176]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:29:20.771]     INFO: Test took 22595ms.

[16:29:20.962]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:29:20.991]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[16:29:22.221]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[16:29:23.472]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[16:29:24.737]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[16:29:25.968]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[16:29:27.212]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[16:29:28.442]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[16:29:29.679]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[16:29:30.944]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[16:29:32.134]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[16:29:33.282]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[16:29:34.471]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[16:29:35.716]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[16:29:36.896]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[16:29:38.088]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[16:29:39.297]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[16:29:40.557]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 259895296

[16:29:40.604]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C0_V0

[16:29:40.604]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 94 (obtained for minval = 0) start: 94 .. 114 last peak: 56.6145 last sigma: 5.81828 lcuts[0] = 74.0693 lcuts[1] = 114.797

[16:29:40.605]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C1_V0

[16:29:40.605]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 98 (obtained for minval = 0) start: 98 .. 122 last peak: 54.0494 last sigma: 6.87846 lcuts[0] = 74.6847 lcuts[1] = 122.834

[16:29:40.606]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C2_V0

[16:29:40.606]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 103 (obtained for minval = 0) start: 103 .. 127 last peak: 59.3119 last sigma: 6.79064 lcuts[0] = 79.6838 lcuts[1] = 127.218

[16:29:40.606]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C3_V0

[16:29:40.607]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 100 (obtained for minval = 0) start: 100 .. 122 last peak: 59.866 last sigma: 6.31317 lcuts[0] = 78.8055 lcuts[1] = 122.998

[16:29:40.607]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C4_V0

[16:29:40.607]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 100 (obtained for minval = 0) start: 100 .. 123 last peak: 57.0636 last sigma: 6.62402 lcuts[0] = 76.9356 lcuts[1] = 123.304

[16:29:40.608]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C5_V0

[16:29:40.608]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 101 (obtained for minval = 0) start: 101 .. 123 last peak: 61.7066 last sigma: 6.14802 lcuts[0] = 80.1507 lcuts[1] = 123.187

[16:29:40.608]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C6_V0

[16:29:40.609]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 99 (obtained for minval = 0) start: 99 .. 120 last peak: 59.4172 last sigma: 6.10073 lcuts[0] = 77.7194 lcuts[1] = 120.424

[16:29:40.609]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C7_V0

[16:29:40.609]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 107 (obtained for minval = 0) start: 107 .. 132 last peak: 62.7754 last sigma: 6.94449 lcuts[0] = 83.6089 lcuts[1] = 132.22

[16:29:40.609]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C8_V0

[16:29:40.610]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 89 (obtained for minval = 0) start: 89 .. 107 last peak: 56.1981 last sigma: 5.15515 lcuts[0] = 71.6636 lcuts[1] = 107.75

[16:29:40.610]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C9_V0

[16:29:40.610]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 77 (obtained for minval = 0) start: 77 .. 92 last peak: 49.6112 last sigma: 4.32875 lcuts[0] = 62.5974 lcuts[1] = 92.8987

[16:29:40.611]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C10_V0

[16:29:40.611]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 89 (obtained for minval = 0) start: 89 .. 107 last peak: 55.5019 last sigma: 5.23669 lcuts[0] = 71.212 lcuts[1] = 107.869

[16:29:40.611]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C11_V0

[16:29:40.612]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 98 (obtained for minval = 0) start: 98 .. 119 last peak: 59.868 last sigma: 5.98862 lcuts[0] = 77.8339 lcuts[1] = 119.754

[16:29:40.612]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C12_V0

[16:29:40.612]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 78 (obtained for minval = 0) start: 78 .. 95 last peak: 47.9239 last sigma: 4.74549 lcuts[0] = 62.1604 lcuts[1] = 95.3788

[16:29:40.613]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C13_V0

[16:29:40.613]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 80 (obtained for minval = 0) start: 80 .. 97 last peak: 49.0221 last sigma: 4.8189 lcuts[0] = 63.4788 lcuts[1] = 97.2111

[16:29:40.613]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C14_V0

[16:29:40.613]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 83 (obtained for minval = 0) start: 83 .. 103 last peak: 46.578 last sigma: 5.71276 lcuts[0] = 63.7163 lcuts[1] = 103.706

[16:29:40.614]    DEBUG: <PixTestBBMap.cc/doTest:L152> found 1 peaks in dist_thr_calSMap_VthrComp_C15_V0

[16:29:40.614]    DEBUG: <PixTestBBMap.cc/fitPeaks:L259> cut for dead bump bonds: 98 (obtained for minval = 0) start: 98 .. 120 last peak: 58.4376 last sigma: 6.23448 lcuts[0] = 77.141 lcuts[1] = 120.782

[16:29:40.616]     INFO: PixTestBBMap::doTest() done, duration: 127 seconds

[16:29:40.616]     INFO: number of dead bumps (per ROC):     6    2    2    2    0    2    0    1    2    2    2    4    1    1    3    1

[16:29:40.616]     INFO: separation cut       (per ROC):    95   99  104  101  101  102  100  108   90   78   90   99   79   81   84   99

[16:29:40.616]    DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor

[16:29:40.682]    DEBUG: <PixTestScurves.cc/setParameter:L92> set fOutputFilename = 

[16:29:40.683]     INFO: ######################################################################

[16:29:40.684]     INFO: PixTestScurves::fullTest() ntrig = 50

[16:29:40.684]     INFO: ######################################################################

[16:29:40.684]     INFO:    ----------------------------------------------------------------------

[16:29:40.684]     INFO:    PixTestScurves::scurves(Vcal), ntrig = 50

[16:29:40.684]     INFO:    ----------------------------------------------------------------------

[16:29:40.684]     INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (10) hits flags = 16 (plus default)

[16:29:40.693]     INFO:   dacScan step  from 0 .. 9

[16:29:40.693]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:30:14.134]     INFO: Test took 33441ms.

[16:30:14.201]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:30:14.201]     INFO:   dacScan step  from 10 .. 19

[16:30:14.201]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:30:47.681]     INFO: Test took 33480ms.

[16:30:47.745]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:30:47.745]     INFO:   dacScan step  from 20 .. 29

[16:30:47.745]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:31:21.208]     INFO: Test took 33463ms.

[16:31:21.273]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:31:21.273]     INFO:   dacScan step  from 30 .. 39

[16:31:21.273]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:31:54.749]     INFO: Test took 33476ms.

[16:31:54.813]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:31:54.813]     INFO:   dacScan step  from 40 .. 49

[16:31:54.813]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:32:28.289]     INFO: Test took 33476ms.

[16:32:28.354]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:32:28.354]     INFO:   dacScan step  from 50 .. 59

[16:32:28.354]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:33:01.965]     INFO: Test took 33611ms.

[16:33:02.029]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:33:02.029]     INFO:   dacScan step  from 60 .. 69

[16:33:02.029]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:33:35.681]    ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (0) != Token Chain Length (8)

[16:33:35.681]  WARNING: ROC 0: Readback start marker after 15 readouts!

[16:33:35.681]  WARNING: ROC 1: Readback start marker after 15 readouts!

[16:33:35.681]  WARNING: ROC 2: Readback start marker after 15 readouts!

[16:33:35.681]  WARNING: ROC 3: Readback start marker after 15 readouts!

[16:33:35.681]  WARNING: ROC 4: Readback start marker after 15 readouts!

[16:33:35.681]  WARNING: ROC 5: Readback start marker after 15 readouts!

[16:33:35.681]  WARNING: ROC 6: Readback start marker after 15 readouts!

[16:33:35.681]  WARNING: ROC 7: Readback start marker after 15 readouts!

[16:33:35.691]     INFO: Test took 33662ms.

[16:33:35.757]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:33:35.758]     INFO:   dacScan step  from 70 .. 79

[16:33:35.758]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:34:08.877]    ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (8)

[16:34:08.877]  WARNING: ROC 2: Readback start marker after 15 readouts!

[16:34:08.877]  WARNING: ROC 3: Readback start marker after 15 readouts!

[16:34:08.877]  WARNING: ROC 4: Readback start marker after 15 readouts!

[16:34:08.877]  WARNING: ROC 5: Readback start marker after 15 readouts!

[16:34:08.877]  WARNING: ROC 6: Readback start marker after 15 readouts!

[16:34:08.877]  WARNING: ROC 7: Readback start marker after 15 readouts!

[16:34:09.987]     INFO: Test took 34229ms.

[16:34:10.102]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:34:10.105]     INFO:   dacScan step  from 80 .. 89

[16:34:10.105]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:34:41.265]    ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (4) != Token Chain Length (8)

[16:34:41.265]  WARNING: ROC 4: Readback start marker after 15 readouts!

[16:34:41.265]  WARNING: ROC 5: Readback start marker after 15 readouts!

[16:34:41.265]  WARNING: ROC 6: Readback start marker after 15 readouts!

[16:34:41.265]  WARNING: ROC 7: Readback start marker after 15 readouts!

[16:34:49.159]     INFO: Test took 39054ms.

[16:34:49.419]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:34:49.429]     INFO:   dacScan step  from 90 .. 99

[16:34:49.429]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:35:17.962]    ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (6) != Token Chain Length (8)

[16:35:17.962]  WARNING: ROC 6: Readback start marker after 15 readouts!

[16:35:17.962]  WARNING: ROC 7: Readback start marker after 15 readouts!

[16:35:34.966]     INFO: Test took 45537ms.

[16:35:35.399]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:35:35.414]     INFO:   dacScan step  from 100 .. 109

[16:35:35.414]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:36:03.711]    ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (6) != Token Chain Length (8)

[16:36:03.711]  WARNING: ROC 6: Readback start marker after 15 readouts!

[16:36:03.712]  WARNING: ROC 7: Readback start marker after 15 readouts!

[16:36:23.781]    ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (4) != Token Chain Length (8)

[16:36:23.781]  WARNING: ROC 4: Readback start marker after 15 readouts!

[16:36:23.781]  WARNING: ROC 5: Readback start marker after 15 readouts!

[16:36:23.781]  WARNING: ROC 6: Readback start marker after 15 readouts!

[16:36:23.781]  WARNING: ROC 7: Readback start marker after 15 readouts!

[16:36:27.609]     INFO: Test took 52195ms.

[16:36:28.172]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:36:28.188]     INFO:   dacScan step  from 110 .. 119

[16:36:28.188]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:36:56.971]    ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (14) != Token Chain Length (8)

[16:36:56.971]    ERROR: <datapipe.cc/CheckEventID:L420>    Event ID mismatch:  local ID (78) !=  TBM ID (79)

[16:36:56.971]  WARNING: ROC 0: Readback start marker after 15 readouts!

[16:36:56.971]  WARNING: ROC 1: Readback start marker after 15 readouts!

[16:36:56.971]  WARNING: ROC 2: Readback start marker after 15 readouts!

[16:36:56.971]  WARNING: ROC 3: Readback start marker after 15 readouts!

[16:36:56.971]  WARNING: ROC 4: Readback start marker after 15 readouts!

[16:36:56.971]  WARNING: ROC 5: Readback start marker after 15 readouts!

[16:36:56.971]  WARNING: ROC 6: Readback start marker after 15 readouts!

[16:36:56.971]  WARNING: ROC 7: Readback start marker after 15 readouts!

[16:37:17.688]    ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (4) != Token Chain Length (8)

[16:37:17.688]  WARNING: ROC 4: Readback start marker after 15 readouts!

[16:37:17.688]  WARNING: ROC 5: Readback start marker after 15 readouts!

[16:37:17.688]  WARNING: ROC 6: Readback start marker after 15 readouts!

[16:37:17.688]  WARNING: ROC 7: Readback start marker after 15 readouts!

[16:37:23.176]     INFO: Test took 54988ms.

[16:37:23.757]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:37:23.773]     INFO:   dacScan step  from 120 .. 129

[16:37:23.773]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:37:53.102]  WARNING: ROC 7: Readback start marker after 15 readouts!

[16:37:53.102]    ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (15) != Token Chain Length (8)

[16:37:53.102]    ERROR: <datapipe.cc/CheckEventID:L420>    Event ID mismatch:  local ID (239) !=  TBM ID (240)

[16:37:53.102]  WARNING: ROC 0: Readback start marker after 31 readouts!

[16:37:53.102]  WARNING: ROC 1: Readback start marker after 31 readouts!

[16:37:53.102]  WARNING: ROC 2: Readback start marker after 31 readouts!

[16:37:53.102]  WARNING: ROC 3: Readback start marker after 31 readouts!

[16:37:53.102]  WARNING: ROC 4: Readback start marker after 31 readouts!

[16:37:53.102]  WARNING: ROC 5: Readback start marker after 31 readouts!

[16:37:53.102]  WARNING: ROC 6: Readback start marker after 31 readouts!

[16:38:14.059]    ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (14) != Token Chain Length (8)

[16:38:14.059]    ERROR: <datapipe.cc/CheckEventID:L420>    Event ID mismatch:  local ID (246) !=  TBM ID (247)

[16:38:14.061]  WARNING: ROC 0: Readback start marker after 15 readouts!

[16:38:14.061]  WARNING: ROC 1: Readback start marker after 15 readouts!

[16:38:14.061]  WARNING: ROC 2: Readback start marker after 15 readouts!

[16:38:14.061]  WARNING: ROC 3: Readback start marker after 15 readouts!

[16:38:14.061]  WARNING: ROC 4: Readback start marker after 15 readouts!

[16:38:14.061]  WARNING: ROC 5: Readback start marker after 15 readouts!

[16:38:14.061]  WARNING: ROC 6: Readback start marker after 15 readouts!

[16:38:14.061]  WARNING: ROC 7: Readback start marker after 15 readouts!

[16:38:19.675]     INFO: Test took 55902ms.

[16:38:20.252]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:38:20.268]     INFO:   dacScan step  from 130 .. 139

[16:38:20.268]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:38:50.126]    ERROR: <datapipe.cc/CheckEventID:L420>    Event ID mismatch:  local ID (116) !=  TBM ID (190)

[16:38:50.126]    ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (6) != Token Chain Length (8)

[16:38:50.126]    ERROR: <datapipe.cc/CheckEventID:L420>    Event ID mismatch:  local ID (191) !=  TBM ID (117)

[16:38:50.126]  WARNING: ROC 6: Readback start marker after 15 readouts!

[16:38:50.126]  WARNING: ROC 7: Readback start marker after 15 readouts!

[16:39:10.981]    ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (5) != Token Chain Length (8)

[16:39:10.981]  WARNING: ROC 5: Readback start marker after 15 readouts!

[16:39:10.981]  WARNING: ROC 6: Readback start marker after 15 readouts!

[16:39:10.981]  WARNING: ROC 7: Readback start marker after 15 readouts!

[16:39:16.263]     INFO: Test took 55995ms.

[16:39:16.825]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:39:16.841]     INFO:   dacScan step  from 140 .. 149

[16:39:16.841]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:39:45.962]    ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (6) != Token Chain Length (8)

[16:39:45.965]  WARNING: ROC 6: Readback start marker after 15 readouts!

[16:39:45.965]  WARNING: ROC 7: Readback start marker after 15 readouts!

[16:40:05.709]    ERROR: <datapipe.cc/CheckEventID:L420>    Event ID mismatch:  local ID (114) !=  TBM ID (190)

[16:40:05.709]    ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (6) != Token Chain Length (8)

[16:40:05.709]    ERROR: <datapipe.cc/CheckEventID:L420>    Event ID mismatch:  local ID (191) !=  TBM ID (115)

[16:40:05.709]  WARNING: ROC 6: Readback start marker after 15 readouts!

[16:40:05.709]  WARNING: ROC 7: Readback start marker after 15 readouts!

[16:40:10.734]     INFO: Test took 53893ms.

[16:40:11.293]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:40:11.309]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[16:40:11.309]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:12.530]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[16:40:12.530]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:13.741]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[16:40:13.741]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:14.946]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[16:40:14.946]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:16.164]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[16:40:16.164]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:17.392]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[16:40:17.393]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:18.602]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[16:40:18.602]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:19.835]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[16:40:19.835]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:21.062]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[16:40:21.062]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:22.296]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[16:40:22.296]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:23.551]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[16:40:23.551]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:24.793]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[16:40:24.793]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:26.019]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[16:40:26.019]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:27.282]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[16:40:27.282]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:28.520]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[16:40:28.520]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:29.755]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[16:40:29.755]     INFO: dumping ASCII scurve output file: SCurveData

[16:40:30.969]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 568729600

[16:40:31.015]     INFO: PixTestScurves::scurves() done 

[16:40:31.015]     INFO: Vcal mean:  94.60 101.62 107.96  98.21  90.86 105.51  93.95  93.05  85.33  80.38  92.38  92.56  78.70  82.58  86.66 101.21 

[16:40:31.015]     INFO: Vcal RMS:    6.90   7.08   6.44   6.41   5.60   6.39   5.29   6.20   5.79   4.43   6.24   5.49   4.97   5.11   5.49   6.13 

[16:40:31.015]     INFO: PixTestScurves::fullTest() done, duration: 650 seconds

[16:40:31.015]    DEBUG: <PixTestScurves.cc/~PixTestScurves:L141> PixTestScurves dtor

[16:40:31.076]     INFO: ######################################################################

[16:40:31.076]     INFO: PixTestTrim::doTest()

[16:40:31.076]     INFO: ######################################################################

[16:40:31.078]     INFO:    ----------------------------------------------------------------------

[16:40:31.078]     INFO:    PixTestTrim::trimTest() ntrig = 10, vcal = 35

[16:40:31.078]     INFO:    ----------------------------------------------------------------------

[16:40:31.146]     INFO: ---> VthrComp thr map (minimal VthrComp)

[16:40:31.146]     INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)

[16:40:31.155]     INFO:   dacScan step  from 0 .. 19

[16:40:31.155]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:40:43.572]     INFO: Test took 12417ms.

[16:40:43.593]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:40:43.593]     INFO:   dacScan step  from 20 .. 39

[16:40:43.594]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:40:56.027]     INFO: Test took 12433ms.

[16:40:56.048]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:40:56.048]     INFO:   dacScan step  from 40 .. 59

[16:40:56.048]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:41:08.471]     INFO: Test took 12423ms.

[16:41:08.493]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:41:08.493]     INFO:   dacScan step  from 60 .. 79

[16:41:08.493]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:41:20.935]     INFO: Test took 12442ms.

[16:41:20.958]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:41:20.958]     INFO:   dacScan step  from 80 .. 99

[16:41:20.958]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:41:33.711]     INFO: Test took 12753ms.

[16:41:33.763]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:41:33.770]     INFO:   dacScan step  from 100 .. 119

[16:41:33.770]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:41:48.751]     INFO: Test took 14981ms.

[16:41:48.899]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:41:48.925]     INFO:   dacScan step  from 120 .. 139

[16:41:48.925]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:42:05.598]     INFO: Test took 16673ms.

[16:42:05.743]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:42:05.768]     INFO:   dacScan step  from 140 .. 159

[16:42:05.768]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:42:19.013]     INFO: Test took 13245ms.

[16:42:19.076]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:42:19.084]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[16:42:20.214]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[16:42:21.389]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[16:42:22.556]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[16:42:23.714]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[16:42:24.890]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[16:42:26.054]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[16:42:27.211]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[16:42:28.391]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[16:42:29.501]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[16:42:30.583]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[16:42:31.678]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[16:42:32.827]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[16:42:33.942]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[16:42:35.076]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[16:42:36.229]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[16:42:37.396]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 568754176

[16:42:37.398]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 93.1569 minThrLimit = 93.08 minThrNLimit = 111.79 -> result = 93.1569 -> 93

[16:42:37.398]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 100.743 minThrLimit = 100.691 minThrNLimit = 123.344 -> result = 100.743 -> 100

[16:42:37.399]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 103.19 minThrLimit = 102.977 minThrNLimit = 124.865 -> result = 103.19 -> 103

[16:42:37.399]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 96.1367 minThrLimit = 96.106 minThrNLimit = 116.952 -> result = 96.1367 -> 96

[16:42:37.399]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 95.6753 minThrLimit = 95.6591 minThrNLimit = 120.423 -> result = 95.6753 -> 95

[16:42:37.400]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 98.1734 minThrLimit = 98.1667 minThrNLimit = 117.51 -> result = 98.1734 -> 98

[16:42:37.400]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 95.1138 minThrLimit = 95.0857 minThrNLimit = 117.115 -> result = 95.1138 -> 95

[16:42:37.400]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 99.4107 minThrLimit = 99.3625 minThrNLimit = 123.839 -> result = 99.4107 -> 99

[16:42:37.400]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 86.4343 minThrLimit = 86.4238 minThrNLimit = 107.548 -> result = 86.4343 -> 86

[16:42:37.401]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 81.1277 minThrLimit = 81.0191 minThrNLimit = 99.8637 -> result = 81.1277 -> 81

[16:42:37.401]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 88.561 minThrLimit = 88.5448 minThrNLimit = 106.318 -> result = 88.561 -> 88

[16:42:37.401]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 94.6307 minThrLimit = 94.6247 minThrNLimit = 116.479 -> result = 94.6307 -> 94

[16:42:37.402]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 82.5832 minThrLimit = 82.5762 minThrNLimit = 105.113 -> result = 82.5832 -> 82

[16:42:37.402]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 86.7307 minThrLimit = 86.7177 minThrNLimit = 109.231 -> result = 86.7307 -> 86

[16:42:37.402]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 91.3202 minThrLimit = 91.2972 minThrNLimit = 113.77 -> result = 91.3202 -> 91

[16:42:37.403]    DEBUG: <PixTest.cc/getMinimumVthrComp:L1208> minThr = 99.6661 minThrLimit = 99.6028 minThrNLimit = 119.77 -> result = 99.6661 -> 99

[16:42:37.403]     INFO: ROC 0 VthrComp = 93

[16:42:37.403]     INFO: ROC 1 VthrComp = 100

[16:42:37.403]     INFO: ROC 2 VthrComp = 103

[16:42:37.403]     INFO: ROC 3 VthrComp = 96

[16:42:37.403]     INFO: ROC 4 VthrComp = 95

[16:42:37.403]     INFO: ROC 5 VthrComp = 98

[16:42:37.403]     INFO: ROC 6 VthrComp = 95

[16:42:37.404]     INFO: ROC 7 VthrComp = 99

[16:42:37.404]     INFO: ROC 8 VthrComp = 86

[16:42:37.404]     INFO: ROC 9 VthrComp = 81

[16:42:37.404]     INFO: ROC 10 VthrComp = 88

[16:42:37.404]     INFO: ROC 11 VthrComp = 94

[16:42:37.404]     INFO: ROC 12 VthrComp = 82

[16:42:37.404]     INFO: ROC 13 VthrComp = 86

[16:42:37.404]     INFO: ROC 14 VthrComp = 91

[16:42:37.405]     INFO: ROC 15 VthrComp = 99

[16:42:37.405]     INFO: ---> Vcal thr map (pixel with maximum Vcal thr)

[16:42:37.405]     INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)

[16:42:37.414]     INFO:   dacScan step  from 0 .. 19

[16:42:37.414]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:42:49.832]     INFO: Test took 12418ms.

[16:42:49.853]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:42:49.853]     INFO:   dacScan step  from 20 .. 39

[16:42:49.854]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:43:02.345]     INFO: Test took 12491ms.

[16:43:02.376]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:43:02.380]     INFO:   dacScan step  from 40 .. 59

[16:43:02.380]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:43:17.471]     INFO: Test took 15091ms.

[16:43:17.620]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:43:17.646]     INFO:   dacScan step  from 60 .. 79

[16:43:17.646]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:43:34.421]     INFO: Test took 16775ms.

[16:43:34.587]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:43:34.616]     INFO:   dacScan step  from 80 .. 99

[16:43:34.616]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:43:51.354]     INFO: Test took 16738ms.

[16:43:51.518]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:43:51.547]     INFO:   dacScan step  from 100 .. 119

[16:43:51.547]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:44:08.369]     INFO: Test took 16822ms.

[16:44:08.532]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:44:08.560]     INFO:   dacScan step  from 120 .. 139

[16:44:08.560]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:44:26.825]     INFO: Test took 18265ms.

[16:44:26.988]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:44:27.017]     INFO:   dacScan step  from 140 .. 159

[16:44:27.017]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:44:43.988]     INFO: Test took 16971ms.

[16:44:44.149]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:44:44.178]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[16:44:45.530]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[16:44:46.898]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[16:44:48.275]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[16:44:49.623]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[16:44:50.969]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[16:44:52.341]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[16:44:53.690]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[16:44:55.039]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[16:44:56.388]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[16:44:57.744]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[16:44:59.102]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[16:45:00.443]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[16:45:01.773]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[16:45:03.102]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[16:45:04.456]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[16:45:05.821]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 568754176

[16:45:05.822]     INFO:    roc 0 with ID = 0  has maximal Vcal 62.8557 for pixel 6/3 mean/min/max = 47.0629/31.2387/62.887

[16:45:05.823]     INFO:    roc 1 with ID = 1  has maximal Vcal 63.3574 for pixel 0/49 mean/min/max = 47.6226/31.7918/63.4533

[16:45:05.823]     INFO:    roc 2 with ID = 2  has maximal Vcal 64.9567 for pixel 0/4 mean/min/max = 48.6063/31.9824/65.2302

[16:45:05.823]     INFO:    roc 3 with ID = 3  has maximal Vcal 63.6168 for pixel 19/21 mean/min/max = 47.2511/30.6577/63.8445

[16:45:05.824]     INFO:    roc 4 with ID = 4  has maximal Vcal 58.5781 for pixel 14/3 mean/min/max = 45.3351/32.0615/58.6088

[16:45:05.824]     INFO:    roc 5 with ID = 5  has maximal Vcal 65.7016 for pixel 7/69 mean/min/max = 48.6069/31.4075/65.8064

[16:45:05.824]     INFO:    roc 6 with ID = 6  has maximal Vcal 58.19 for pixel 17/66 mean/min/max = 45.1927/31.6405/58.7449

[16:45:05.824]     INFO:    roc 7 with ID = 7  has maximal Vcal 60.8741 for pixel 0/7 mean/min/max = 46.3088/31.7105/60.9072

[16:45:05.825]     INFO:    roc 8 with ID = 8  has maximal Vcal 59.9519 for pixel 3/3 mean/min/max = 46.0181/31.6498/60.3864

[16:45:05.825]     INFO:    roc 9 with ID = 9  has maximal Vcal 58.1657 for pixel 51/1 mean/min/max = 45.1218/32.0132/58.2305

[16:45:05.825]     INFO:    roc 10 with ID = 10  has maximal Vcal 63.7069 for pixel 5/46 mean/min/max = 48.1085/32.1298/64.0872

[16:45:05.825]     INFO:    roc 11 with ID = 11  has maximal Vcal 58.9585 for pixel 27/10 mean/min/max = 45.9429/32.7023/59.1836

[16:45:05.826]     INFO:    roc 12 with ID = 12  has maximal Vcal 58.542 for pixel 8/15 mean/min/max = 45.571/32.1575/58.9845

[16:45:05.826]     INFO:    roc 13 with ID = 13  has maximal Vcal 57.3892 for pixel 44/1 mean/min/max = 45.0538/32.5733/57.5343

[16:45:05.826]     INFO:    roc 14 with ID = 14  has maximal Vcal 58.9375 for pixel 17/73 mean/min/max = 45.6531/32.2237/59.0825

[16:45:05.826]     INFO:    roc 15 with ID = 15  has maximal Vcal 62.3256 for pixel 17/1 mean/min/max = 47.1593/31.9611/62.3575

[16:45:05.827]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[16:46:20.117]     INFO: Test took 74290ms.

[16:46:20.891]    DEBUG: <PixTestTrim.cc/trimTest:L304>  vtrim: vcal = 35.8901 < 35 for itrim = 121; old thr = 34.0617 ... break

[16:46:20.916]    DEBUG: <PixTestTrim.cc/trimTest:L304>  vtrim: vcal = 35.0302 < 35 for itrim = 121; old thr = 32.1438 ... break

[16:46:20.929]    DEBUG: <PixTestTrim.cc/trimTest:L304>  vtrim: vcal = 35.352 < 35 for itrim = 106; old thr = 33.3675 ... break

[16:46:20.956]    DEBUG: <PixTestTrim.cc/trimTest:L304>  vtrim: vcal = 35.1291 < 35 for itrim = 126; old thr = 33.9918 ... break

[16:46:20.987]    DEBUG: <PixTestTrim.cc/trimTest:L309> vtrim: vcal = 35.2369 < 35 for itrim+1 = 110; old thr = 34.9997 ... break

[16:46:21.008]    DEBUG: <PixTestTrim.cc/trimTest:L309> vtrim: vcal = 35.9697 < 35 for itrim+1 = 130; old thr = 34.8128 ... break

[16:46:21.031]    DEBUG: <PixTestTrim.cc/trimTest:L304>  vtrim: vcal = 35.3097 < 35 for itrim = 108; old thr = 33.3608 ... break

[16:46:21.057]    DEBUG: <PixTestTrim.cc/trimTest:L304>  vtrim: vcal = 36.5842 < 35 for itrim = 120; old thr = 33.0012 ... break

[16:46:21.089]    DEBUG: <PixTestTrim.cc/trimTest:L309> vtrim: vcal = 37.0737 < 35 for itrim+1 = 109; old thr = 33.8226 ... break

[16:46:21.114]    DEBUG: <PixTestTrim.cc/trimTest:L304>  vtrim: vcal = 35.3937 < 35 for itrim = 93; old thr = 33.637 ... break

[16:46:21.139]    DEBUG: <PixTestTrim.cc/trimTest:L309> vtrim: vcal = 36.0438 < 35 for itrim+1 = 117; old thr = 34.6995 ... break

[16:46:21.142]    DEBUG: <PixTestTrim.cc/trimTest:L309> vtrim: vcal = 255 < 35 for itrim+1 = 185; old thr = 16.4807 ... break

[16:46:21.173]    DEBUG: <PixTestTrim.cc/trimTest:L309> vtrim: vcal = 35.09 < 35 for itrim+1 = 104; old thr = 34.9113 ... break

[16:46:21.211]    DEBUG: <PixTestTrim.cc/trimTest:L304>  vtrim: vcal = 35.0973 < 35 for itrim = 110; old thr = 33.8986 ... break

[16:46:21.239]    DEBUG: <PixTestTrim.cc/trimTest:L304>  vtrim: vcal = 36.1385 < 35 for itrim = 104; old thr = 33.0858 ... break

[16:46:21.268]    DEBUG: <PixTestTrim.cc/trimTest:L304>  vtrim: vcal = 35.3313 < 35 for itrim = 129; old thr = 34.2419 ... break

[16:46:21.331]     INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)

[16:46:21.339]     INFO:   dacScan step  from 0 .. 19

[16:46:21.340]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:46:39.635]     INFO: Test took 18295ms.

[16:46:39.691]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:46:39.694]     INFO:   dacScan step  from 20 .. 39

[16:46:39.694]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:47:02.094]     INFO: Test took 22400ms.

[16:47:02.332]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:47:02.358]     INFO:   dacScan step  from 40 .. 59

[16:47:02.358]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:47:29.190]     INFO: Test took 26832ms.

[16:47:29.469]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:47:29.499]     INFO:   dacScan step  from 60 .. 79

[16:47:29.499]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:47:56.399]     INFO: Test took 26900ms.

[16:47:56.677]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:47:56.707]     INFO:   dacScan step  from 80 .. 99

[16:47:56.707]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:48:23.555]     INFO: Test took 26848ms.

[16:48:23.834]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:48:23.863]     INFO:   dacScan step  from 100 .. 119

[16:48:23.863]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:48:50.778]     INFO: Test took 26915ms.

[16:48:51.058]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:48:51.087]     INFO:   dacScan step  from 120 .. 139

[16:48:51.087]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:49:19.527]     INFO: Test took 28440ms.

[16:49:19.806]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:49:19.835]     INFO:   dacScan step  from 140 .. 159

[16:49:19.835]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:49:46.904]     INFO: Test took 27069ms.

[16:49:47.183]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:49:47.212]     INFO:   dacScan step  from 160 .. 179

[16:49:47.212]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:50:14.189]     INFO: Test took 26977ms.

[16:50:14.468]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:50:14.498]     INFO:   dacScan step  from 180 .. 199

[16:50:14.498]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:50:42.208]     INFO: Test took 27710ms.

[16:50:42.484]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:50:42.513]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[16:50:43.912]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[16:50:45.318]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[16:50:46.745]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[16:50:48.113]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[16:50:49.487]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[16:50:50.899]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[16:50:52.266]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[16:50:53.659]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[16:50:55.022]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[16:50:56.402]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[16:50:57.785]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[16:50:59.032]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[16:51:00.393]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[16:51:01.750]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[16:51:03.134]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[16:51:04.530]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 573288448

[16:51:04.532]     INFO: ---> TrimStepCorr4 extremal thresholds: 0.015847 .. 255.000000

[16:51:04.594]     INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20) hits flags = 16 (plus default)

[16:51:04.602]     INFO:   dacScan step  from 0 .. 19

[16:51:04.603]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:51:15.858]     INFO: Test took 11255ms.

[16:51:15.879]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:51:15.879]     INFO:   dacScan step  from 20 .. 39

[16:51:15.880]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:51:28.008]     INFO: Test took 12128ms.

[16:51:28.091]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:51:28.107]     INFO:   dacScan step  from 40 .. 59

[16:51:28.107]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:51:42.652]     INFO: Test took 14545ms.

[16:51:42.799]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:51:42.828]     INFO:   dacScan step  from 60 .. 79

[16:51:42.828]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:51:57.504]     INFO: Test took 14676ms.

[16:51:57.646]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:51:57.674]     INFO:   dacScan step  from 80 .. 99

[16:51:57.674]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:52:12.336]     INFO: Test took 14662ms.

[16:52:12.479]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:52:12.507]     INFO:   dacScan step  from 100 .. 119

[16:52:12.508]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:52:27.257]     INFO: Test took 14749ms.

[16:52:27.399]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:52:27.427]     INFO:   dacScan step  from 120 .. 139

[16:52:27.427]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:52:43.631]     INFO: Test took 16204ms.

[16:52:43.774]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:52:43.802]     INFO:   dacScan step  from 140 .. 159

[16:52:43.802]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:52:58.699]     INFO: Test took 14897ms.

[16:52:58.841]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:52:58.868]     INFO:   dacScan step  from 160 .. 179

[16:52:58.868]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:53:13.696]     INFO: Test took 14828ms.

[16:53:13.837]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:53:13.864]     INFO:   dacScan step  from 180 .. 199

[16:53:13.864]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:53:29.431]     INFO: Test took 15566ms.

[16:53:29.574]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:53:29.602]     INFO:   dacScan step  from 200 .. 219

[16:53:29.602]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:53:44.269]     INFO: Test took 14667ms.

[16:53:44.411]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:53:44.439]     INFO:   dacScan step  from 220 .. 239

[16:53:44.440]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:53:59.110]     INFO: Test took 14670ms.

[16:53:59.251]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:53:59.278]     INFO:   dacScan step  from 240 .. 255

[16:53:59.279]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:54:11.526]     INFO: Test took 12247ms.

[16:54:11.640]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:54:11.662]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[16:54:13.378]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[16:54:15.092]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[16:54:16.820]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[16:54:18.506]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[16:54:20.197]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[16:54:21.902]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[16:54:23.604]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[16:54:25.300]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[16:54:26.991]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[16:54:28.679]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[16:54:30.378]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[16:54:32.056]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[16:54:33.732]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[16:54:35.421]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[16:54:37.118]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[16:54:38.827]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 573292544

[16:54:38.894]     INFO: ---> TrimStepCorr2 extremal thresholds: 10.771555 .. 66.569092

[16:54:38.956]     INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 0 .. 76 (20) hits flags = 16 (plus default)

[16:54:38.964]     INFO:   dacScan step  from 0 .. 19

[16:54:38.965]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:54:50.218]     INFO: Test took 11253ms.

[16:54:50.238]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:54:50.238]     INFO:   dacScan step  from 20 .. 39

[16:54:50.238]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:55:02.297]     INFO: Test took 12059ms.

[16:55:02.374]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:55:02.391]     INFO:   dacScan step  from 40 .. 59

[16:55:02.391]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:55:17.042]     INFO: Test took 14651ms.

[16:55:17.188]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:55:17.217]     INFO:   dacScan step  from 60 .. 76

[16:55:17.217]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:55:30.063]     INFO: Test took 12846ms.

[16:55:30.185]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:55:30.209]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[16:55:31.217]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[16:55:32.224]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[16:55:33.238]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[16:55:34.240]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[16:55:35.245]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[16:55:36.248]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[16:55:37.255]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[16:55:38.257]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[16:55:39.258]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[16:55:40.259]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[16:55:41.264]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[16:55:42.269]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[16:55:43.274]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[16:55:44.282]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[16:55:45.289]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[16:55:46.296]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 573415424

[16:55:46.363]     INFO: ---> TrimStepCorr1a extremal thresholds: 10.771555 .. 65.166217

[16:55:46.425]     INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 0 .. 75 (20) hits flags = 16 (plus default)

[16:55:46.433]     INFO:   dacScan step  from 0 .. 19

[16:55:46.434]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:55:57.678]     INFO: Test took 11244ms.

[16:55:57.698]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:55:57.698]     INFO:   dacScan step  from 20 .. 39

[16:55:57.699]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:56:09.718]     INFO: Test took 12019ms.

[16:56:09.791]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:56:09.806]     INFO:   dacScan step  from 40 .. 59

[16:56:09.806]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:56:24.473]     INFO: Test took 14667ms.

[16:56:24.619]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:56:24.647]     INFO:   dacScan step  from 60 .. 75

[16:56:24.648]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:56:36.911]     INFO: Test took 12263ms.

[16:56:37.025]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:56:37.048]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[16:56:38.044]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[16:56:39.037]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[16:56:40.038]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[16:56:41.038]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[16:56:42.040]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[16:56:43.038]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[16:56:44.046]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[16:56:45.049]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[16:56:46.053]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[16:56:47.049]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[16:56:48.054]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[16:56:49.057]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[16:56:50.048]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[16:56:51.039]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[16:56:52.038]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[16:56:53.040]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 573415424

[16:56:53.107]     INFO: ---> TrimStepCorr1b extremal thresholds: 1.173917 .. 61.549522

[16:56:53.169]     INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 1 .. 71 (20) hits flags = 16 (plus default)

[16:56:53.177]     INFO:   dacScan step  from 1 .. 20

[16:56:53.178]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:57:04.435]     INFO: Test took 11257ms.

[16:57:04.456]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:57:04.456]     INFO:   dacScan step  from 21 .. 40

[16:57:04.456]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:57:16.614]     INFO: Test took 12158ms.

[16:57:16.696]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:57:16.712]     INFO:   dacScan step  from 41 .. 60

[16:57:16.712]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:57:31.403]     INFO: Test took 14691ms.

[16:57:31.549]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:57:31.577]     INFO:   dacScan step  from 61 .. 71

[16:57:31.577]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:57:40.781]     INFO: Test took 9204ms.

[16:57:40.860]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:57:40.877]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[16:57:41.849]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[16:57:42.819]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[16:57:43.783]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[16:57:44.748]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[16:57:45.707]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[16:57:46.677]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[16:57:47.645]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[16:57:48.610]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[16:57:49.581]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[16:57:50.547]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[16:57:51.518]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[16:57:52.489]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[16:57:53.457]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[16:57:54.426]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[16:57:55.397]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[16:57:56.366]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 573415424

[16:57:56.433]     INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55

[16:57:56.433]     INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20) hits flags = 16 (plus default)

[16:57:56.442]     INFO:   dacScan step  from 15 .. 34

[16:57:56.442]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:58:14.941]     INFO: Test took 18499ms.

[16:58:15.011]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:58:15.020]     INFO:   dacScan step  from 35 .. 54

[16:58:15.020]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:58:41.215]     INFO: Test took 26195ms.

[16:58:41.502]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:58:41.532]     INFO:   dacScan step  from 55 .. 55

[16:58:41.532]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:58:45.261]     INFO: Test took 3729ms.

[16:58:45.276]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:58:45.280]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[16:58:46.015]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[16:58:46.751]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[16:58:47.489]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[16:58:48.228]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[16:58:48.971]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[16:58:49.706]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[16:58:50.443]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[16:58:51.179]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[16:58:51.917]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[16:58:52.657]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[16:58:53.393]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[16:58:54.128]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[16:58:54.868]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[16:58:55.610]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[16:58:56.349]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[16:58:57.088]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 573419520

[16:58:57.141]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C0.dat

[16:58:57.141]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C1.dat

[16:58:57.141]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C2.dat

[16:58:57.141]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C3.dat

[16:58:57.141]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C4.dat

[16:58:57.141]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C5.dat

[16:58:57.141]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C6.dat

[16:58:57.141]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C7.dat

[16:58:57.141]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C8.dat

[16:58:57.141]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C9.dat

[16:58:57.141]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C10.dat

[16:58:57.141]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C11.dat

[16:58:57.142]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C12.dat

[16:58:57.142]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C13.dat

[16:58:57.142]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C14.dat

[16:58:57.142]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C15.dat

[16:58:57.142]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C0.dat

[16:58:57.147]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C1.dat

[16:58:57.153]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C2.dat

[16:58:57.158]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C3.dat

[16:58:57.163]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C4.dat

[16:58:57.169]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C5.dat

[16:58:57.174]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C6.dat

[16:58:57.179]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C7.dat

[16:58:57.185]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C8.dat

[16:58:57.190]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C9.dat

[16:58:57.195]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C10.dat

[16:58:57.201]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C11.dat

[16:58:57.206]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C12.dat

[16:58:57.211]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C13.dat

[16:58:57.217]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C14.dat

[16:58:57.222]     INFO: write trim parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//trimParameters35_C15.dat

[16:58:57.227]     INFO: PixTestTrim::trimTest() done

[16:58:57.227]     INFO: vtrim:     121 121 106 126 110 130 108 120 109  93 117 185 104 110 104 129 

[16:58:57.227]     INFO: vthrcomp:   93 100 103  96  95  98  95  99  86  81  88  94  82  86  91  99 

[16:58:57.227]     INFO: vcal mean:  34.98  35.06  35.08  35.01  35.04  35.06  35.07  35.02  34.99  34.98  35.06  34.98  35.02  35.05  35.07  34.98 

[16:58:57.227]     INFO: vcal RMS:    1.49   1.58   1.37   1.33   1.04   1.36   1.07   1.06   1.20   1.02   1.29   2.20   1.25   1.51   1.07   1.35 

[16:58:57.227]     INFO: bits mean:   9.57   9.52   9.15   9.86   9.83   9.58  10.08   9.15   9.28   9.42   9.45  11.90   9.45   9.81   9.79   9.49 

[16:58:57.227]     INFO: bits RMS:    2.59   2.58   2.62   2.53   2.57   2.50   2.48   2.87   2.80   2.72   2.50   1.48   2.70   2.50   2.53   2.65 

[16:58:57.233]     INFO:    ----------------------------------------------------------------------

[16:58:57.233]     INFO:    PixTestTrim::trimBitTest() ntrig = 10, vtrims = 255 240 150 100

[16:58:57.233]     INFO:    ----------------------------------------------------------------------

[16:58:57.237]    DEBUG: <PixTestTrim.cc/trimBitTest:L515> trimBitTest determine threshold map without trims 

[16:58:57.237]     INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)

[16:58:57.246]     INFO:   dacScan step  from 0 .. 19

[16:58:57.246]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:59:15.376]     INFO: Test took 18130ms.

[16:59:15.412]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:59:15.412]     INFO:   dacScan step  from 20 .. 39

[16:59:15.412]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:59:33.700]     INFO: Test took 18288ms.

[16:59:33.755]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:59:33.758]     INFO:   dacScan step  from 40 .. 59

[16:59:33.758]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[16:59:57.152]     INFO: Test took 23394ms.

[16:59:57.402]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:59:57.429]     INFO:   dacScan step  from 60 .. 79

[16:59:57.429]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:00:24.334]     INFO: Test took 26905ms.

[17:00:24.608]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:00:24.637]     INFO:   dacScan step  from 80 .. 99

[17:00:24.637]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:00:51.527]     INFO: Test took 26890ms.

[17:00:51.797]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:00:51.824]     INFO:   dacScan step  from 100 .. 119

[17:00:51.825]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:01:18.787]     INFO: Test took 26962ms.

[17:01:19.058]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:01:19.087]     INFO:   dacScan step  from 120 .. 139

[17:01:19.087]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:01:47.583]     INFO: Test took 28496ms.

[17:01:47.854]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:01:47.882]     INFO:   dacScan step  from 140 .. 159

[17:01:47.882]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:02:14.981]     INFO: Test took 27100ms.

[17:02:15.253]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:02:15.282]     INFO:   dacScan step  from 160 .. 179

[17:02:15.282]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:02:42.403]     INFO: Test took 27121ms.

[17:02:42.674]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:02:42.703]     INFO:   dacScan step  from 180 .. 199

[17:02:42.703]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:03:10.472]     INFO: Test took 27769ms.

[17:03:10.746]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:03:10.774]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[17:03:12.167]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[17:03:13.571]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[17:03:14.992]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[17:03:16.366]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[17:03:17.735]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[17:03:19.157]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[17:03:20.542]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[17:03:21.915]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[17:03:23.283]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[17:03:24.649]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[17:03:26.047]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[17:03:27.416]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[17:03:28.775]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[17:03:30.132]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[17:03:31.524]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[17:03:32.923]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 573431808

[17:03:32.924]    DEBUG: <PixTestTrim.cc/trimBitTest:L530> trimBitTest initDUT with trim bits = 14

[17:03:32.987]    DEBUG: <PixTestTrim.cc/trimBitTest:L536> trimBitTest threshold map with trim = 14

[17:03:32.987]     INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 167 (20) hits flags = 16 (plus default)

[17:03:32.996]     INFO:   dacScan step  from 0 .. 19

[17:03:32.996]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:03:51.118]     INFO: Test took 18122ms.

[17:03:51.154]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:03:51.154]     INFO:   dacScan step  from 20 .. 39

[17:03:51.154]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:04:09.916]     INFO: Test took 18762ms.

[17:04:09.003]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:04:10.012]     INFO:   dacScan step  from 40 .. 59

[17:04:10.012]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:04:34.951]     INFO: Test took 24939ms.

[17:04:35.224]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:04:35.253]     INFO:   dacScan step  from 60 .. 79

[17:04:35.254]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:05:02.174]     INFO: Test took 26920ms.

[17:05:02.453]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:05:02.481]     INFO:   dacScan step  from 80 .. 99

[17:05:02.481]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:05:29.347]     INFO: Test took 26866ms.

[17:05:29.623]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:05:29.650]     INFO:   dacScan step  from 100 .. 119

[17:05:29.650]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:05:56.731]     INFO: Test took 27081ms.

[17:05:56.001]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:05:57.030]     INFO:   dacScan step  from 120 .. 139

[17:05:57.030]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:06:25.534]     INFO: Test took 28504ms.

[17:06:25.809]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:06:25.837]     INFO:   dacScan step  from 140 .. 159

[17:06:25.837]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:06:53.040]     INFO: Test took 27203ms.

[17:06:53.313]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:06:53.341]     INFO:   dacScan step  from 160 .. 167

[17:06:53.341]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:07:05.575]     INFO: Test took 12234ms.

[17:07:05.684]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:07:05.697]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[17:07:07.013]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[17:07:08.331]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[17:07:09.662]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[17:07:10.964]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[17:07:12.266]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[17:07:13.589]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[17:07:14.896]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[17:07:16.199]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[17:07:17.501]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[17:07:18.811]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[17:07:20.126]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[17:07:21.433]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[17:07:22.735]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[17:07:24.044]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[17:07:25.357]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[17:07:26.675]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 573431808

[17:07:26.675]    DEBUG: <PixTestTrim.cc/trimBitTest:L530> trimBitTest initDUT with trim bits = 13

[17:07:26.739]    DEBUG: <PixTestTrim.cc/trimBitTest:L536> trimBitTest threshold map with trim = 13

[17:07:26.739]     INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 148 (20) hits flags = 16 (plus default)

[17:07:26.748]     INFO:   dacScan step  from 0 .. 19

[17:07:26.748]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:07:44.892]     INFO: Test took 18144ms.

[17:07:44.930]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:07:44.930]     INFO:   dacScan step  from 20 .. 39

[17:07:44.930]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:08:04.570]     INFO: Test took 19640ms.

[17:08:04.701]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:08:04.716]     INFO:   dacScan step  from 40 .. 59

[17:08:04.716]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:08:30.753]     INFO: Test took 26036ms.

[17:08:31.035]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:08:31.074]     INFO:   dacScan step  from 60 .. 79

[17:08:31.074]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:08:58.138]     INFO: Test took 27064ms.

[17:08:58.410]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:08:58.438]     INFO:   dacScan step  from 80 .. 99

[17:08:58.438]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:09:25.420]     INFO: Test took 26982ms.

[17:09:25.692]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:09:25.720]     INFO:   dacScan step  from 100 .. 119

[17:09:25.720]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:09:52.913]     INFO: Test took 27193ms.

[17:09:53.184]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:09:53.213]     INFO:   dacScan step  from 120 .. 139

[17:09:53.213]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:10:21.742]     INFO: Test took 28529ms.

[17:10:22.011]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:10:22.039]     INFO:   dacScan step  from 140 .. 148

[17:10:22.039]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:10:35.629]     INFO: Test took 13590ms.

[17:10:35.750]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:10:35.765]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[17:10:37.010]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[17:10:38.262]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[17:10:39.518]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[17:10:40.762]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[17:10:42.009]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[17:10:43.271]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[17:10:44.523]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[17:10:45.780]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[17:10:47.026]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[17:10:48.275]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[17:10:49.523]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[17:10:50.768]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[17:10:51.002]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[17:10:53.243]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[17:10:54.487]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[17:10:55.735]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 573435904

[17:10:55.736]    DEBUG: <PixTestTrim.cc/trimBitTest:L530> trimBitTest initDUT with trim bits = 11

[17:10:55.798]    DEBUG: <PixTestTrim.cc/trimBitTest:L536> trimBitTest threshold map with trim = 11

[17:10:55.798]     INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 132 (20) hits flags = 16 (plus default)

[17:10:55.807]     INFO:   dacScan step  from 0 .. 19

[17:10:55.807]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:11:13.946]     INFO: Test took 18139ms.

[17:11:13.985]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:11:13.985]     INFO:   dacScan step  from 20 .. 39

[17:11:13.985]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:11:34.294]     INFO: Test took 20309ms.

[17:11:34.452]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:11:34.471]     INFO:   dacScan step  from 40 .. 59

[17:11:34.471]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:12:00.899]     INFO: Test took 26428ms.

[17:12:01.182]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:12:01.211]     INFO:   dacScan step  from 60 .. 79

[17:12:01.211]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:12:28.211]     INFO: Test took 27000ms.

[17:12:28.484]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:12:28.513]     INFO:   dacScan step  from 80 .. 99

[17:12:28.513]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:12:55.383]     INFO: Test took 26870ms.

[17:12:55.655]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:12:55.683]     INFO:   dacScan step  from 100 .. 119

[17:12:55.683]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:13:22.677]     INFO: Test took 26994ms.

[17:13:22.948]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:13:22.976]     INFO:   dacScan step  from 120 .. 132

[17:13:22.977]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:13:41.986]     INFO: Test took 19009ms.

[17:13:42.164]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:13:42.184]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[17:13:43.363]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[17:13:44.543]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[17:13:45.733]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[17:13:46.922]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[17:13:48.112]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[17:13:49.302]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[17:13:50.474]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[17:13:51.646]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[17:13:52.819]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[17:13:53.996]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[17:13:55.179]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[17:13:56.356]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[17:13:57.530]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[17:13:58.705]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[17:13:59.890]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[17:14:01.083]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 573435904

[17:14:01.083]    DEBUG: <PixTestTrim.cc/trimBitTest:L530> trimBitTest initDUT with trim bits = 7

[17:14:01.147]    DEBUG: <PixTestTrim.cc/trimBitTest:L536> trimBitTest threshold map with trim = 7

[17:14:01.147]     INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 103 (20) hits flags = 16 (plus default)

[17:14:01.155]     INFO:   dacScan step  from 0 .. 19

[17:14:01.156]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:14:19.330]     INFO: Test took 18174ms.

[17:14:19.372]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:14:19.374]     INFO:   dacScan step  from 20 .. 39

[17:14:19.374]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:14:40.905]     INFO: Test took 21531ms.

[17:14:41.108]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:14:41.132]     INFO:   dacScan step  from 40 .. 59

[17:14:41.132]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:15:07.780]     INFO: Test took 26648ms.

[17:15:08.059]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:15:08.088]     INFO:   dacScan step  from 60 .. 79

[17:15:08.088]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:15:35.039]     INFO: Test took 26951ms.

[17:15:35.313]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:15:35.341]     INFO:   dacScan step  from 80 .. 99

[17:15:35.341]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:16:02.291]     INFO: Test took 26950ms.

[17:16:02.566]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:16:02.595]     INFO:   dacScan step  from 100 .. 103

[17:16:02.595]    DEBUG: <PixTest.cc/dacScan:L1518>       attempt #0

[17:16:09.959]     INFO: Test took 7364ms.

[17:16:10.015]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:16:10.023]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 0

[17:16:11.056]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 1

[17:16:12.089]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 2

[17:16:13.123]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 3

[17:16:14.158]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 4

[17:16:15.187]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 5

[17:16:16.211]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 6

[17:16:17.238]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 7

[17:16:18.261]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 8

[17:16:19.284]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 9

[17:16:20.320]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 10

[17:16:21.371]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 11

[17:16:22.414]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 12

[17:16:23.467]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 13

[17:16:24.502]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 14

[17:16:25.567]    DEBUG: <PixTest.cc/scurveAna:L1579> analyzing ROC 15

[17:16:26.613]    DEBUG: <PixTest.cc/scurveMaps:L251> PixTest::scurveMaps end: getCurrentRSS() = 573435904

[17:16:26.729]     INFO: PixTestTrim::trimBitTest() done 

[17:16:26.731]     INFO: PixTestTrim::doTest() done, duration: 2155 seconds

[17:16:26.731]    DEBUG: <PixTestTrim.cc/~PixTestTrim:L104> PixTestTrim dtor

[17:16:27.308]    DEBUG: <PixTest.cc/setTestParameter:L569>  setting  ntrig to new value 10

[17:16:27.308]    DEBUG: <PixTestPhOptimization.cc/setParameter:L37>   setting fParNtrig  ->10<- from sval = 10

[17:16:27.308]    DEBUG: <PixTestPhOptimization.cc/setParameter:L42>   setting fSafetyMarginLow  ->20<- from sval = 20

[17:16:27.308]    DEBUG: <PixTestPhOptimization.cc/setParameter:L48>   setting fVcalMax  ->100<- from sval = 100

[17:16:27.308]    DEBUG: <PixTestPhOptimization.cc/setParameter:L53>   setting fQuantMax  ->0.98<- from sval = 0.98

[17:16:27.309]     INFO: ######################################################################

[17:16:27.309]     INFO: PixTestPhOptimization::doTest() Ntrig = 10

[17:16:27.309]     INFO: ######################################################################

[17:16:27.310]    DEBUG: <PixTest.cc/efficiencyMaps:L328>       attempt #0

[17:16:30.001]     INFO: Test took 3691ms.

[17:16:31.019]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:16:31.019]    DEBUG: <PixTest.cc/efficiencyMaps:L339>  eff result size = 66526

[17:16:31.019]    DEBUG: <PixTest.cc/efficiencyMaps:L344> Create hists PixelAlive_C0 .. PixelAlive_C15

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [0, 0, 0] has eff 0/10

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [0, 0, 0]

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [0, 0, 1] has eff 0/10

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [0, 0, 1]

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [0, 28, 49] has eff 0/10

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [0, 28, 49]

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [1, 16, 9] has eff 0/10

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [1, 16, 9]

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [1, 17, 9] has eff 0/10

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [1, 17, 9]

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [1, 16, 10] has eff 0/10

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [1, 16, 10]

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [1, 17, 10] has eff 0/10

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [1, 17, 10]

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [2, 45, 60] has eff 0/10

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [2, 45, 60]

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [2, 42, 75] has eff 0/10

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [2, 42, 75]

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [3, 48, 41] has eff 0/10

[17:16:31.022]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [3, 48, 41]

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [3, 27, 55] has eff 0/10

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [3, 27, 55]

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [5, 43, 67] has eff 0/10

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [5, 43, 67]

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [5, 43, 68] has eff 0/10

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [5, 43, 68]

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [8, 0, 70] has eff 0/10

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [8, 0, 70]

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [10, 42, 20] has eff 6/10

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [10, 42, 20]

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [10, 39, 42] has eff 0/10

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [10, 39, 42]

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [10, 35, 57] has eff 6/10

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [10, 35, 57]

[17:16:31.023]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [10, 43, 73] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [10, 43, 73]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [11, 37, 5] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [11, 37, 5]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [11, 16, 16] has eff 5/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [11, 16, 16]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [11, 27, 20] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [11, 27, 20]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [11, 28, 22] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [11, 28, 22]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [11, 9, 28] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [11, 9, 28]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [11, 44, 28] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [11, 44, 28]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [11, 37, 35] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [11, 37, 35]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [11, 26, 37] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [11, 26, 37]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [11, 45, 38] has eff 5/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [11, 45, 38]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [11, 40, 46] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [11, 40, 46]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [11, 41, 46] has eff 7/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [11, 41, 46]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [11, 31, 55] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [11, 31, 55]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [11, 0, 57] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [11, 0, 57]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [12, 21, 22] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [12, 21, 22]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [12, 30, 49] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [12, 30, 49]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [13, 49, 14] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [13, 49, 14]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [13, 2, 73] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [13, 2, 73]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [13, 18, 76] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [13, 18, 76]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [13, 19, 76] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [13, 19, 76]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [15, 50, 0] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [15, 50, 0]

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L227> Pixel [15, 51, 0] has eff 0/10

[17:16:31.024]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L231> bad Pixel found and blacklisted: [15, 51, 0]

[17:16:31.029]    DEBUG: <PixTestPhOptimization.cc/BlacklistPixels:L239> Number of bad pixels found: 39

[17:16:31.029]    DEBUG: <PixTestPhOptimization.cc/doTest:L124> **********Ph range will be optimised on the whole ROC***********

[17:16:31.029]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L276> ROC type is newer than digv2

[17:16:31.029]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L277> ROC type is psi46digv21respin

[17:16:31.219]    DEBUG: <PixTest.cc/phMaps:L264>       attempt #0

[17:16:34.908]     INFO: Test took 3689ms.

[17:16:34.968]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:16:34.968]    DEBUG: <PixTest.cc/phMaps:L275>  eff result size = 66528

[17:16:34.968]    DEBUG: <PixTest.cc/phMaps:L282> Create hists maxphmap_C0 .. maxphmap_C15

[17:16:34.972]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 205.439

[17:16:34.972]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [3 ,6] phvalue 206

[17:16:34.972]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 202.025

[17:16:34.972]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [4 ,6] phvalue 203

[17:16:34.972]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 202.046

[17:16:34.972]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [6 ,5] phvalue 203

[17:16:34.972]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 204.129

[17:16:34.972]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [3 ,34] phvalue 204

[17:16:34.972]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 193.932

[17:16:34.972]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [4 ,15] phvalue 193

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 200.585

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [4 ,5] phvalue 201

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 215.042

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [6 ,8] phvalue 216

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 209.177

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [3 ,7] phvalue 209

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 200.708

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [3 ,15] phvalue 200

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 194.794

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [4 ,6] phvalue 195

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 212.663

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [3 ,7] phvalue 213

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 216.732

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [3 ,15] phvalue 216

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 211.271

[17:16:34.973]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [3 ,5] phvalue 212

[17:16:34.974]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 196.609

[17:16:34.974]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [3 ,6] phvalue 197

[17:16:34.974]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 193.752

[17:16:34.974]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [4 ,6] phvalue 193

[17:16:34.974]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L347> maxph quantile 205.793

[17:16:34.974]    DEBUG: <PixTestPhOptimization.cc/GetMaxPhPixel:L371> Max pixel is [3 ,5] phvalue 205

[17:16:34.974]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L427> ROC type is newer than digv2

[17:16:34.974]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L428> ROC type is psi46digv21respin

[17:16:34.974]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L450> init_phScale=150, flag_minPh = 0, minph = 0

[17:16:34.982]    DEBUG: <PixTest.cc/phMaps:L264>       attempt #0

[17:16:38.683]     INFO: Test took 3701ms.

[17:16:38.744]     INFO: Fetched DAQ statistics. Counters are being reset now.

[17:16:38.744]    DEBUG: <PixTest.cc/phMaps:L275>  eff result size = 66528

[17:16:38.744]    DEBUG: <PixTest.cc/phMaps:L282> Create hists minphmap_C0 .. minphmap_C15

[17:16:38.747]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L457> result size 0

[17:16:38.747]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L484> done. init_phScale=155, flag_minPh = 1, minph = 72minph_roc = 4

[17:16:38.747]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 88.7034

[17:16:38.747]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [4 ,44] phvalue 89

[17:16:38.747]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 93.662

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [3 ,44] phvalue 94

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 78.0509

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [3 ,6] phvalue 78

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 89.0906

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [3 ,44] phvalue 90

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 76.3858

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [3 ,12] phvalue 77

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 77.8357

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [8 ,13] phvalue 77

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 96.2005

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [3 ,13] phvalue 97

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 89.9141

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [4 ,14] phvalue 89

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 84.4688

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [12 ,66] phvalue 85

[17:16:38.748]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 84.4036

[17:16:38.749]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [3 ,6] phvalue 85

[17:16:38.749]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 86.7777

[17:16:38.749]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [6 ,31] phvalue 87

[17:16:38.749]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 99.5515

[17:16:38.749]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [14 ,67] phvalue 99

[17:16:38.749]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 90.6593

[17:16:38.749]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [4 ,67] phvalue 90

[17:16:38.749]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 81.3255

[17:16:38.749]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [8 ,50] phvalue 82

[17:16:38.749]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 78.7344

[17:16:38.749]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [6 ,42] phvalue 79

[17:16:38.749]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L502> minph quantile 0.02 78.0591

[17:16:38.749]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L525> Min pixel is [8 ,18] phvalue 79

[17:16:38.751]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 4, 44, 0 0

[17:16:38.751]     INFO: The DUT currently contains the following objects:

[17:16:38.751]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:38.751]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:38.751]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:38.751]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:38.752]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:38.752]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: Test took 1091ms.

[17:16:39.843]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:39.843]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 3, 44, 1 1

[17:16:39.843]     INFO: The DUT currently contains the following objects:

[17:16:39.843]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:39.843]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:39.843]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:39.843]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:39.843]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:39.843]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.934]     INFO: Test took 1091ms.

[17:16:40.935]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:40.935]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 3, 6, 2 2

[17:16:40.935]     INFO: The DUT currently contains the following objects:

[17:16:40.935]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:40.935]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:40.935]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:40.935]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:40.935]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:40.935]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.026]     INFO: Test took 1091ms.

[17:16:42.027]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:42.027]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 3, 44, 3 3

[17:16:42.027]     INFO: The DUT currently contains the following objects:

[17:16:42.027]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:42.027]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:42.027]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:42.027]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:42.027]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:42.027]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.118]     INFO: Test took 1091ms.

[17:16:43.119]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:43.119]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 3, 12, 4 4

[17:16:43.119]     INFO: The DUT currently contains the following objects:

[17:16:43.119]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:43.119]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:43.119]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:43.119]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:43.119]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:43.119]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.210]     INFO: Test took 1091ms.

[17:16:44.210]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:44.211]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 8, 13, 5 5

[17:16:44.211]     INFO: The DUT currently contains the following objects:

[17:16:44.211]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:44.211]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:44.211]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:44.211]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:44.211]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:44.211]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.301]     INFO: Test took 1090ms.

[17:16:45.302]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:45.302]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 3, 13, 6 6

[17:16:45.302]     INFO: The DUT currently contains the following objects:

[17:16:45.302]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:45.302]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:45.302]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:45.302]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:45.302]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:45.302]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.393]     INFO: Test took 1091ms.

[17:16:46.394]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:46.394]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 4, 14, 7 7

[17:16:46.394]     INFO: The DUT currently contains the following objects:

[17:16:46.394]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:46.394]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:46.394]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:46.394]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:46.394]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:46.394]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.485]     INFO: Test took 1091ms.

[17:16:47.486]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:47.486]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 12, 66, 8 8

[17:16:47.486]     INFO: The DUT currently contains the following objects:

[17:16:47.486]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:47.486]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:47.486]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:47.486]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:47.486]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:47.486]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.577]     INFO: Test took 1091ms.

[17:16:48.577]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:48.577]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 3, 6, 9 9

[17:16:48.577]     INFO: The DUT currently contains the following objects:

[17:16:48.577]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:48.577]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:48.577]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:48.578]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:48.578]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:48.578]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.668]     INFO: Test took 1090ms.

[17:16:49.669]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:49.669]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 6, 31, 10 10

[17:16:49.669]     INFO: The DUT currently contains the following objects:

[17:16:49.669]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:49.669]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:49.669]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:49.669]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:49.669]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:49.669]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.760]     INFO: Test took 1091ms.

[17:16:50.760]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:50.760]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 14, 67, 11 11

[17:16:50.760]     INFO: The DUT currently contains the following objects:

[17:16:50.760]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:50.760]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:50.760]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:50.760]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:50.760]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.760]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.760]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.760]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.760]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.760]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.761]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.761]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.761]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.761]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.761]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.761]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.761]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.761]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.761]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:50.761]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.851]     INFO: Test took 1090ms.

[17:16:51.852]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:51.852]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 4, 67, 12 12

[17:16:51.852]     INFO: The DUT currently contains the following objects:

[17:16:51.852]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:51.852]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:51.852]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:51.852]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:51.852]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:51.852]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.943]     INFO: Test took 1091ms.

[17:16:52.943]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:52.943]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 8, 50, 13 13

[17:16:52.943]     INFO: The DUT currently contains the following objects:

[17:16:52.943]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:52.943]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:52.943]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:52.944]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:52.944]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:52.944]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.034]     INFO: Test took 1090ms.

[17:16:54.035]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:54.035]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 6, 42, 14 14

[17:16:54.035]     INFO: The DUT currently contains the following objects:

[17:16:54.035]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:54.035]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:54.035]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:54.035]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:54.035]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:54.035]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.126]     INFO: Test took 1091ms.

[17:16:55.126]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:55.127]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L591> enabling pixels 8, 18, 15 15

[17:16:55.127]     INFO: The DUT currently contains the following objects:

[17:16:55.127]     INFO:  2 TBM Cores tbm08c (2 ON)

[17:16:55.127]     INFO: 	TBM Core alpha (0): 7 registers set

[17:16:55.127]     INFO: 	TBM Core beta  (1): 7 registers set

[17:16:55.127]     INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs

[17:16:55.127]     INFO: 	ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:55.127]     INFO: 	ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.

[17:16:56.217]     INFO: Test took 1090ms.

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/GetMinPhPixel:L611> size of results 256

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 35 on ROC0

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 30 on ROC1

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 33 on ROC2

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 32 on ROC3

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 28 on ROC4

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 33 on ROC5

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 33 on ROC6

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 28 on ROC7

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 32 on ROC8

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 30 on ROC9

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 31 on ROC10

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 31 on ROC11

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 32 on ROC12

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 31 on ROC13

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 33 on ROC14

[17:16:56.218]    DEBUG: <PixTestPhOptimization.cc/doTest:L132> vcal min 31 on ROC15

[17:16:56.222]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:19:46.022]     INFO: Test took 169800ms.

[17:19:47.527]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:22:36.842]     INFO: Test took 169315ms.

[17:22:38.439]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.439]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip0

[17:22:38.439]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.439]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip1

[17:22:38.439]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.440]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip2

[17:22:38.440]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.440]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip3

[17:22:38.440]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.440]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip4

[17:22:38.440]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.441]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip5

[17:22:38.441]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.441]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip6

[17:22:38.441]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.441]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip7

[17:22:38.441]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.442]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip8

[17:22:38.442]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.442]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip9

[17:22:38.442]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.442]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip10

[17:22:38.442]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.443]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip11

[17:22:38.443]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.443]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip12

[17:22:38.443]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.443]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip13

[17:22:38.443]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.444]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip14

[17:22:38.444]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L927> before assigning th2_sol to vector component

[17:22:38.444]    DEBUG: <PixTestPhOptimization.cc/optimiseOnMapsNew:L934> after assigning th2_sol to vector component, chip15

[17:22:38.444]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.450]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.455]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.461]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.466]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.472]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.477]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.483]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.488]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.494]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.499]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.505]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.510]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.516]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.521]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.527]     INFO: safety margin for low PH: adding 0, margin is now 20

[17:22:38.533]    DEBUG: <PixTestPhOptimization.cc/doTest:L172> optimisation done

[17:22:38.581]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C0.dat

[17:22:38.581]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C1.dat

[17:22:38.581]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C2.dat

[17:22:38.581]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C3.dat

[17:22:38.581]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C4.dat

[17:22:38.582]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C5.dat

[17:22:38.582]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C6.dat

[17:22:38.582]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C7.dat

[17:22:38.582]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C8.dat

[17:22:38.582]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C9.dat

[17:22:38.582]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C10.dat

[17:22:38.582]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C11.dat

[17:22:38.582]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C12.dat

[17:22:38.582]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C13.dat

[17:22:38.582]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C14.dat

[17:22:38.582]     INFO: write dac parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//dacParameters35_C15.dat

[17:22:42.274]     INFO: Test took 3687ms.

[17:22:46.208]     INFO: Test took 3657ms.

[17:22:50.177]     INFO: Test took 3691ms.

[17:22:50.460]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:22:51.361]     INFO: Test took 901ms.

[17:22:51.364]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:22:52.455]     INFO: Test took 1091ms.

[17:22:52.458]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:22:53.548]     INFO: Test took 1090ms.

[17:22:53.551]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:22:54.641]     INFO: Test took 1090ms.

[17:22:54.644]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:22:55.734]     INFO: Test took 1090ms.

[17:22:55.737]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:22:56.827]     INFO: Test took 1090ms.

[17:22:56.830]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:22:57.922]     INFO: Test took 1092ms.

[17:22:57.925]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:22:59.015]     INFO: Test took 1090ms.

[17:22:59.018]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:00.109]     INFO: Test took 1091ms.

[17:23:00.112]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:01.202]     INFO: Test took 1090ms.

[17:23:01.205]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:02.296]     INFO: Test took 1091ms.

[17:23:02.299]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:03.389]     INFO: Test took 1090ms.

[17:23:03.392]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:04.482]     INFO: Test took 1090ms.

[17:23:04.485]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:05.575]     INFO: Test took 1090ms.

[17:23:05.578]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:06.668]     INFO: Test took 1090ms.

[17:23:06.671]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:07.761]     INFO: Test took 1090ms.

[17:23:07.764]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:08.854]     INFO: Test took 1090ms.

[17:23:08.857]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:09.947]     INFO: Test took 1090ms.

[17:23:09.950]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:11.040]     INFO: Test took 1090ms.

[17:23:11.043]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:12.132]     INFO: Test took 1090ms.

[17:23:12.135]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:13.229]     INFO: Test took 1094ms.

[17:23:13.232]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:14.323]     INFO: Test took 1091ms.

[17:23:14.326]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:15.416]     INFO: Test took 1091ms.

[17:23:15.419]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:16.509]     INFO: Test took 1090ms.

[17:23:16.512]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:17.602]     INFO: Test took 1090ms.

[17:23:17.605]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:18.695]     INFO: Test took 1090ms.

[17:23:18.698]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:19.788]     INFO: Test took 1090ms.

[17:23:19.791]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:20.881]     INFO: Test took 1090ms.

[17:23:20.884]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:21.974]     INFO: Test took 1090ms.

[17:23:21.977]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:23.067]     INFO: Test took 1090ms.

[17:23:23.070]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:24.160]     INFO: Test took 1090ms.

[17:23:24.163]     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[17:23:25.254]     INFO: Test took 1091ms.

[17:23:25.840]     INFO: PixTestPhOptimization::doTest() done, duration: 418 seconds

[17:23:25.840]     INFO: PH scale (per ROC):    71  69  73  73  85  79  82  81  77  74  83  85  84  83  76  76

[17:23:25.840]     INFO: PH offset (per ROC):  166 161 174 164 169 171 154 160 167 167 161 153 159 163 172 173

[17:23:25.984]     INFO: ######################################################################

[17:23:25.984]     INFO: PixTestGainPedestal::fullTest() ntrig = 10

[17:23:25.984]     INFO: ######################################################################

[17:23:25.984]    DEBUG: <PixTestGainPedestal.cc/measure:L188>  using FLAGS = 16

[17:23:25.994]     INFO: scanning low vcal = 50

[17:23:29.716]     INFO: Test took 3721ms.

[17:23:29.773]     INFO: scanning low vcal = 100

[17:23:33.510]     INFO: Test took 3737ms.

[17:23:33.567]     INFO: scanning low vcal = 150

[17:23:37.305]     INFO: Test took 3738ms.

[17:23:37.362]     INFO: scanning low vcal = 200

[17:23:41.104]     INFO: Test took 3742ms.

[17:23:41.162]     INFO: scanning low vcal = 250

[17:23:44.900]     INFO: Test took 3738ms.

[17:23:44.960]     INFO: scanning high vcal = 30 (= 210 in low range)

[17:23:48.694]     INFO: Test took 3734ms.

[17:23:48.751]     INFO: scanning high vcal = 50 (= 350 in low range)

[17:23:52.495]     INFO: Test took 3744ms.

[17:23:52.552]     INFO: scanning high vcal = 70 (= 490 in low range)

[17:23:56.290]     INFO: Test took 3738ms.

[17:23:56.347]     INFO: scanning high vcal = 90 (= 630 in low range)

[17:24:00.083]     INFO: Test took 3736ms.

[17:24:00.140]     INFO: scanning high vcal = 200 (= 1400 in low range)

[17:24:03.881]     INFO: Test took 3741ms.

[17:24:04.198]     INFO: PixTestGainPedestal::measure() done 

[17:24:04.200]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C0

[17:24:04.200]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C1

[17:24:04.200]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C2

[17:24:04.201]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C3

[17:24:04.201]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C4

[17:24:04.201]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C5

[17:24:04.201]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C6

[17:24:04.201]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C7

[17:24:04.201]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C8

[17:24:04.201]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C9

[17:24:04.201]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C10

[17:24:04.202]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C11

[17:24:04.202]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C12

[17:24:04.202]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C13

[17:24:04.202]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C14

[17:24:04.202]    DEBUG: <PixTestGainPedestal.cc/fit:L377> Create hist gainPedestalP1_C15

[17:24:25.775]     INFO: PixTestGainPedestal::fit() done

[17:24:25.775]     INFO: non-linearity mean:  0.953 0.952 0.962 0.955 0.960 0.962 0.956 0.954 0.952 0.957 0.957 0.957 0.951 0.962 0.955 0.950

[17:24:25.775]     INFO: non-linearity RMS:   0.007 0.006 0.007 0.006 0.006 0.008 0.006 0.005 0.006 0.006 0.005 0.008 0.005 0.006 0.006 0.008

[17:24:25.775]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C0.dat

[17:24:25.792]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C1.dat

[17:24:25.808]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C2.dat

[17:24:25.825]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C3.dat

[17:24:25.841]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C4.dat

[17:24:25.857]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C5.dat

[17:24:25.874]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C6.dat

[17:24:25.890]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C7.dat

[17:24:25.907]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C8.dat

[17:24:25.923]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C9.dat

[17:24:25.939]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C10.dat

[17:24:25.956]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C11.dat

[17:24:25.972]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C12.dat

[17:24:25.988]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C13.dat

[17:24:25.005]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C14.dat

[17:24:26.021]     INFO: write gain/ped parameters into /home/pixel_dev/elcomandante/trunk/DATA/ThermalCycling/M3017_FullQualification_2015-06-26_09h57m_1435305433//005_Fulltest_m20//phCalibrationFitErr35_C15.dat

[17:24:26.038]     INFO: PixTestGainPedestal::doTest() done, duration: 60 seconds

[17:24:26.038]    DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L121> PixTestGainPedestal dtor

[17:24:26.044]    DEBUG: <PixTestFullTest.cc/~PixTestFullTest:L78> PixTestFullTest dtor

[17:24:26.044]     INFO: enter test to run

[17:24:26.044]     INFO:   test: q no parameter change

[17:24:26.044]    DEBUG: <PixMonitor.cc/dumpSummaries:L34> PixMonitor::dumpSummaries

[17:24:26.177]    QUIET: Connection to board 105 closed.

[17:24:26.178]     INFO: pXar: this is the end, my friend

[17:24:26.178]    DEBUG: <PixSetup.cc/~PixSetup:L68> PixSetup free fPxarMemory
MoReWeb-v0.6.7-17-g62372b6 on branch psi46master