[15:19:26.759] <TB0> INFO: *** Welcome to pxar ***
[15:19:26.759] <TB0> INFO: *** Today: 2016/10/03
[15:19:26.778] <TB0> INFO: *** Version: 3035-dirty
[15:19:26.778] <TB0> INFO: readRocDacs: /Users/p/OnShellTest/results/M2229_OnShellQuickTest_2016-10-03_15h19m_1475500752//000_OnShellQuickTest_p17//dacParameters_C0.dat .. /Users/p/OnShellTest/results/M2229_OnShellQuickTest_2016-10-03_15h19m_1475500752//000_OnShellQuickTest_p17//dacParameters_C15.dat
[15:19:26.779] <TB0> INFO: readTbmDacs: /Users/p/OnShellTest/results/M2229_OnShellQuickTest_2016-10-03_15h19m_1475500752//000_OnShellQuickTest_p17//tbmParameters_C0a.dat .. /Users/p/OnShellTest/results/M2229_OnShellQuickTest_2016-10-03_15h19m_1475500752//000_OnShellQuickTest_p17//tbmParameters_C0b.dat
[15:19:26.779] <TB0> INFO: readMaskFile: /Users/p/OnShellTest/results/M2229_OnShellQuickTest_2016-10-03_15h19m_1475500752//000_OnShellQuickTest_p17//defaultMaskFile.dat
[15:19:26.779] <TB0> INFO: readTrimFile: /Users/p/OnShellTest/results/M2229_OnShellQuickTest_2016-10-03_15h19m_1475500752//000_OnShellQuickTest_p17//trimParameters_C0.dat .. /Users/p/OnShellTest/results/M2229_OnShellQuickTest_2016-10-03_15h19m_1475500752//000_OnShellQuickTest_p17//trimParameters_C15.dat
[15:19:26.811] <TB0> INFO: clk: 4
[15:19:26.811] <TB0> INFO: ctr: 4
[15:19:26.811] <TB0> INFO: sda: 19
[15:19:26.811] <TB0> INFO: tin: 9
[15:19:26.811] <TB0> INFO: level: 15
[15:19:26.811] <TB0> INFO: triggerdelay: 0
[15:19:26.811] <TB0> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[15:19:26.811] <TB0> INFO: Log level: INFO
[15:19:28.134] <TB0> QUIET: Connection to board DTB_WS6THK opened.
[15:19:28.136] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 81
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options: LCDS2
USB id: DTB_WS6THK
MAC address: 40D855118051
Hostname: pixelDTB081
Comment:
------------------------------------------------------
[15:19:28.138] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[15:19:29.800] <TB0> INFO: DUT info:
[15:19:29.800] <TB0> INFO: The DUT currently contains the following objects:
[15:19:29.800] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[15:19:29.800] <TB0> INFO: TBM Core alpha (0): 7 registers set
[15:19:29.800] <TB0> INFO: TBM Core beta (1): 7 registers set
[15:19:29.800] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:19:29.800] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:29.800] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:30.202] <TB0> INFO: enter 'restricted' command line mode
[15:19:30.202] <TB0> INFO: enter test to run
[15:19:30.202] <TB0> INFO: test: OnShellQuickTest no parameter change
[15:19:30.202] <TB0> INFO: running: onshellquicktest
[15:19:30.207] <TB0> INFO: ######################################################################
[15:19:30.207] <TB0> INFO: PixTestOnShellQuickTest::doTest()
[15:19:30.207] <TB0> INFO: ######################################################################
[15:19:30.208] <TB0> INFO: ----------------------------------------------------------------------
[15:19:30.208] <TB0> INFO: PixTestOnShellQuickTest::programROC()
[15:19:30.208] <TB0> INFO: ----------------------------------------------------------------------
[15:19:48.319] <TB0> INFO: PixTestOnShellQuickTest::programROC() done: ROCs 0 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 cannot be programmed! Error!
[15:19:48.319] <TB0> INFO: IA differences per ROC: 0.0 0.0 0.0 0.8 0.8 0.8 0.8 0.8 0.8 0.8 0.8 0.8 0.8 0.8 0.8 0.8
[15:19:48.395] <TB0> INFO: ----------------------------------------------------------------------
[15:19:48.396] <TB0> INFO: PixTestOnShellQuickTest::setVana() target Ia = 24 mA/ROC
[15:19:48.396] <TB0> INFO: ----------------------------------------------------------------------
[15:19:55.262] <TB0> INFO: PixTestOnShellQuickTest::setVana() done, Module Ia 75.5 mA = 4.71875 mA/ROC
[15:19:55.262] <TB0> INFO: i(loss) [mA/ROC]: ->0.0<- ->0.0<- ->0.0<- ->0.0<- ->0.0<- ->0.0<- ->0.0<- ->0.0<- ->0.0<- ->0.0<- ->0.0<- ->0.0<- ->0.0<- ->0.0<- ->0.0<- ->0.0<-
[15:19:55.308] <TB0> INFO: ----------------------------------------------------------------------
[15:19:55.308] <TB0> INFO: PixTestOnShellQuickTest::findTiming()
[15:19:55.308] <TB0> INFO: ----------------------------------------------------------------------
[15:19:55.308] <TB0> INFO: Testing Timing: Attempt #1
[15:19:56.792] <TB0> INFO: NErrors = 0
[15:19:56.792] <TB0> INFO: NEvents = 0
[15:19:56.857] <TB0> INFO: Testing Timing: Attempt #2
[15:19:58.493] <TB0> INFO: NErrors = 0
[15:19:58.493] <TB0> INFO: NEvents = 0
[15:19:58.494] <TB0> INFO: Testing Timing: Attempt #3
[15:20:00.193] <TB0> INFO: NErrors = 0
[15:20:00.193] <TB0> INFO: NEvents = 0
[15:20:00.194] <TB0> INFO: Timings are not good, scanning...
[15:20:00.195] <TB0> INFO: PixTestCmd::init()
[15:20:00.921] <TB0> WARNING: Not unmasking DUT, not setting Calibrate bits!
[15:20:02.972] <TB0> INFO: > no working phases found
[15:20:02.972] <TB0> INFO: TBM phases: 160MHz: -1, 400MHz: -1, TBM delays: ROC(0/1):-1, header/trailer: -1, token: -1
[15:20:02.972] <TB0> INFO: (success/tries = -1/-1), width = -1
[15:20:02.973] <TB0> INFO: ----------------------------------------------------------------------
[15:20:02.973] <TB0> INFO: PixTestOnShellQuickTest::findWorkingPixel()
[15:20:02.973] <TB0> INFO: ----------------------------------------------------------------------
[15:20:03.069] <TB0> INFO: Expecting 231680 events.
[15:20:08.781] <TB0> CRITICAL: <hal.cc/MultiRocOnePixelDacDacScan:L1262> Incomplete DAQ data readout! Missing 46336 Events.
[15:20:08.782] <TB0> CRITICAL: <PixTestOnShellQuickTest.cc/findWorkingPixel:L624> pXar execption: Incomplete DAQ data readout in function MultiRocOnePixelDacDacScan
[15:20:08.930] <TB0> INFO: Expecting 231680 events.
[15:20:14.671] <TB0> CRITICAL: <hal.cc/MultiRocOnePixelDacDacScan:L1262> Incomplete DAQ data readout! Missing 46336 Events.
[15:20:14.671] <TB0> CRITICAL: <PixTestOnShellQuickTest.cc/findWorkingPixel:L624> pXar execption: Incomplete DAQ data readout in function MultiRocOnePixelDacDacScan
[15:20:14.819] <TB0> INFO: Expecting 231680 events.
[15:20:20.561] <TB0> CRITICAL: <hal.cc/MultiRocOnePixelDacDacScan:L1262> Incomplete DAQ data readout! Missing 46336 Events.
[15:20:20.561] <TB0> CRITICAL: <PixTestOnShellQuickTest.cc/findWorkingPixel:L624> pXar execption: Incomplete DAQ data readout in function MultiRocOnePixelDacDacScan
[15:20:20.710] <TB0> INFO: Expecting 231680 events.
[15:20:26.452] <TB0> CRITICAL: <hal.cc/MultiRocOnePixelDacDacScan:L1262> Incomplete DAQ data readout! Missing 46336 Events.
[15:20:26.452] <TB0> CRITICAL: <PixTestOnShellQuickTest.cc/findWorkingPixel:L624> pXar execption: Incomplete DAQ data readout in function MultiRocOnePixelDacDacScan
[15:20:26.600] <TB0> INFO: Expecting 231680 events.
[15:20:32.342] <TB0> CRITICAL: <hal.cc/MultiRocOnePixelDacDacScan:L1262> Incomplete DAQ data readout! Missing 46336 Events.
[15:20:32.342] <TB0> CRITICAL: <PixTestOnShellQuickTest.cc/findWorkingPixel:L624> pXar execption: Incomplete DAQ data readout in function MultiRocOnePixelDacDacScan
[15:20:32.491] <TB0> INFO: Expecting 231680 events.
[15:20:38.233] <TB0> CRITICAL: <hal.cc/MultiRocOnePixelDacDacScan:L1262> Incomplete DAQ data readout! Missing 46336 Events.
[15:20:38.233] <TB0> CRITICAL: <PixTestOnShellQuickTest.cc/findWorkingPixel:L624> pXar execption: Incomplete DAQ data readout in function MultiRocOnePixelDacDacScan
[15:20:38.382] <TB0> INFO: Expecting 231680 events.
[15:20:44.123] <TB0> CRITICAL: <hal.cc/MultiRocOnePixelDacDacScan:L1262> Incomplete DAQ data readout! Missing 46336 Events.
[15:20:44.123] <TB0> CRITICAL: <PixTestOnShellQuickTest.cc/findWorkingPixel:L624> pXar execption: Incomplete DAQ data readout in function MultiRocOnePixelDacDacScan
[15:20:44.271] <TB0> INFO: Expecting 231680 events.
[15:20:50.013] <TB0> CRITICAL: <hal.cc/MultiRocOnePixelDacDacScan:L1262> Incomplete DAQ data readout! Missing 46336 Events.
[15:20:50.013] <TB0> CRITICAL: <PixTestOnShellQuickTest.cc/findWorkingPixel:L624> pXar execption: Incomplete DAQ data readout in function MultiRocOnePixelDacDacScan
[15:20:50.162] <TB0> INFO: Expecting 231680 events.
[15:20:55.904] <TB0> CRITICAL: <hal.cc/MultiRocOnePixelDacDacScan:L1262> Incomplete DAQ data readout! Missing 46336 Events.
[15:20:55.904] <TB0> CRITICAL: <PixTestOnShellQuickTest.cc/findWorkingPixel:L624> pXar execption: Incomplete DAQ data readout in function MultiRocOnePixelDacDacScan
[15:20:56.053] <TB0> INFO: Expecting 231680 events.
[15:21:01.794] <TB0> CRITICAL: <hal.cc/MultiRocOnePixelDacDacScan:L1262> Incomplete DAQ data readout! Missing 46336 Events.
[15:21:01.794] <TB0> CRITICAL: <PixTestOnShellQuickTest.cc/findWorkingPixel:L624> pXar execption: Incomplete DAQ data readout in function MultiRocOnePixelDacDacScan
[15:21:01.794] <TB0> INFO: Found working pixel in all ROCs: col/row = 50/75
[15:21:01.881] <TB0> INFO: ----------------------------------------------------------------------
[15:21:01.881] <TB0> INFO: PixTestOnShellQuickTest::setVthrCompCalDel()
[15:21:01.881] <TB0> INFO: ----------------------------------------------------------------------
[15:21:01.884] <TB0> INFO: /1
[15:21:01.889] <TB0> INFO: /2
[15:21:01.976] <TB0> INFO: Expecting 463360 events.
[15:21:09.071] <TB0> CRITICAL: <hal.cc/MultiRocOnePixelDacDacScan:L1262> Incomplete DAQ data readout! Missing 46336 Events.
[15:21:09.072] <TB0> CRITICAL: <PixTestOnShellQuickTest.cc/setVthrCompCalDel:L790> problem with readout: Incomplete DAQ data readout in function MultiRocOnePixelDacDacScan missing 46336 events
[15:21:09.072] <TB0> INFO: /3
[15:21:09.194] <TB0> INFO: PixTestOnShellQuickTest::setVthrCompCalDel() done
[15:21:09.195] <TB0> INFO: CalDel: _ 129 _ 133 _ 128 _ 142 _ 119 _ 127 _ 131 _ 110 _ 129 _ 134 _ 129 _ 145 _ 127 _ 132 _ 170 _ 162
[15:21:09.195] <TB0> INFO: VthrComp: 49 49 49 49 49 49 49 49 49 49 49 49 49 49 49 49
[15:21:09.195] <TB0> INFO: ----------------------------------------------------------------------
[15:21:09.195] <TB0> INFO: PixTestOnShellQuickTest::hvQuickTest()
[15:21:09.195] <TB0> INFO: ----------------------------------------------------------------------
[15:21:09.895] <TB0> INFO: Expecting 41600 events.
[15:21:11.923] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:12.221] <TB0> INFO: Expecting 41600 events.
[15:21:14.249] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:14.559] <TB0> INFO: Expecting 41600 events.
[15:21:16.586] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:16.884] <TB0> INFO: Expecting 41600 events.
[15:21:18.910] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:19.209] <TB0> INFO: Expecting 41600 events.
[15:21:21.236] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:21.534] <TB0> INFO: Expecting 41600 events.
[15:21:23.561] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:23.934] <TB0> INFO: Expecting 41600 events.
[15:21:25.963] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:26.271] <TB0> INFO: Expecting 41600 events.
[15:21:28.297] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:28.609] <TB0> INFO: Expecting 41600 events.
[15:21:30.636] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:30.940] <TB0> INFO: Expecting 41600 events.
[15:21:32.968] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:33.270] <TB0> INFO: Expecting 41600 events.
[15:21:35.301] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:35.601] <TB0> INFO: Expecting 41600 events.
[15:21:37.629] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:38.035] <TB0> INFO: Inefficient pixels per ROC: 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160
[15:21:38.035] <TB0> INFO: Delta Pixel Alive with HV per ROC: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:21:38.035] <TB0> INFO: Quicktest: Dead Pixels
[15:21:38.035] <TB0> INFO: Dead pixels per ROC: 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160
[15:21:38.068] <TB0> INFO: ----------------------------------------------------------------------
[15:21:38.068] <TB0> INFO: PixTestOnShellQuickTest::bbQuickTest()
[15:21:38.068] <TB0> INFO: ----------------------------------------------------------------------
[15:21:38.068] <TB0> INFO: using Ntrig = 10, Vthrcomp = 105
[15:21:38.370] <TB0> INFO: Expecting 41600 events.
[15:21:40.397] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:40.696] <TB0> INFO: Expecting 41600 events.
[15:21:42.723] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:43.027] <TB0> INFO: Expecting 41600 events.
[15:21:45.053] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:45.351] <TB0> INFO: Expecting 41600 events.
[15:21:47.378] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:47.676] <TB0> INFO: Expecting 41600 events.
[15:21:49.703] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:50.001] <TB0> INFO: Expecting 41600 events.
[15:21:52.028] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:52.344] <TB0> INFO: Expecting 41600 events.
[15:21:54.635] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:54.941] <TB0> INFO: Expecting 41600 events.
[15:21:57.231] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:21:57.536] <TB0> INFO: Expecting 41600 events.
[15:21:59.827] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:22:00.130] <TB0> INFO: Expecting 41600 events.
[15:22:02.421] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:22:02.726] <TB0> INFO: Expecting 41600 events.
[15:22:05.017] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:22:05.324] <TB0> INFO: Expecting 41600 events.
[15:22:07.615] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L815> Incomplete DAQ data readout! Missing 4160 Events.
[15:22:07.679] <TB0> INFO: quicktest:
[15:22:07.679] <TB0> INFO: Dead bumps per ROC: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:22:07.712] <TB0> INFO: PixTestOnShellQuickTest::doTest() done, duration: 157 seconds
[15:22:07.742] <TB0> INFO: enter test to run
[15:22:07.742] <TB0> INFO: test: no parameter change
[15:22:07.774] <TB0> QUIET: Connection to board 81 closed.
[15:22:08.140] <TB0> INFO: pXar: this is the end, my friend