[06:49:05.826] <TB0> INFO: *** Welcome to pxar ***
[06:49:05.826] <TB0> INFO: *** Today: 2016/07/06
[06:49:06.276] <TB0> INFO: *** Version: 293a
[06:49:06.276] <TB0> INFO: readRocDacs: /home/production/dataReception/ETH/M2192_Reception_2016-07-06_08h43m_1467787430//000_ReceptionTest_p17//dacParameters_C0.dat .. /home/production/dataReception/ETH/M2192_Reception_2016-07-06_08h43m_1467787430//000_ReceptionTest_p17//dacParameters_C15.dat
[06:49:06.291] <TB0> INFO: readTbmDacs: /home/production/dataReception/ETH/M2192_Reception_2016-07-06_08h43m_1467787430//000_ReceptionTest_p17//tbmParameters_C0a.dat .. /home/production/dataReception/ETH/M2192_Reception_2016-07-06_08h43m_1467787430//000_ReceptionTest_p17//tbmParameters_C0b.dat
[06:49:06.293] <TB0> INFO: readMaskFile: /home/production/dataReception/ETH/M2192_Reception_2016-07-06_08h43m_1467787430//000_ReceptionTest_p17//defaultMaskFile.dat
[06:49:06.294] <TB0> INFO: readTrimFile: /home/production/dataReception/ETH/M2192_Reception_2016-07-06_08h43m_1467787430//000_ReceptionTest_p17//trimParameters_C0.dat .. /home/production/dataReception/ETH/M2192_Reception_2016-07-06_08h43m_1467787430//000_ReceptionTest_p17//trimParameters_C15.dat
[06:49:06.391] <TB0> INFO: clk: 4
[06:49:06.391] <TB0> INFO: ctr: 4
[06:49:06.391] <TB0> INFO: sda: 19
[06:49:06.391] <TB0> INFO: tin: 9
[06:49:06.391] <TB0> INFO: level: 15
[06:49:06.391] <TB0> INFO: triggerdelay: 0
[06:49:06.391] <TB0> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[06:49:06.391] <TB0> INFO: Log level: INFO
[06:49:06.398] <TB0> INFO: Found DTB DTB_WS6AYH
[06:49:06.410] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[06:49:06.412] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[06:49:06.414] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[06:49:07.943] <TB0> INFO: DUT info:
[06:49:07.943] <TB0> INFO: The DUT currently contains the following objects:
[06:49:07.943] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[06:49:07.943] <TB0> INFO: TBM Core alpha (0): 7 registers set
[06:49:07.943] <TB0> INFO: TBM Core beta (1): 7 registers set
[06:49:07.943] <TB0> INFO: 16 ROCs psi46digv2.1 (16 ON) with 4160 pixelConfigs
[06:49:07.943] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:07.943] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:49:08.345] <TB0> INFO: enter 'restricted' command line mode
[06:49:08.345] <TB0> INFO: enter test to run
[06:49:08.345] <TB0> INFO: test: Pretest no parameter change
[06:49:08.345] <TB0> INFO: running: pretest
[06:49:08.349] <TB0> INFO: ######################################################################
[06:49:08.349] <TB0> INFO: PixTestPretest::doTest()
[06:49:08.349] <TB0> INFO: ######################################################################
[06:49:08.350] <TB0> INFO: ----------------------------------------------------------------------
[06:49:08.350] <TB0> INFO: PixTestPretest::programROC()
[06:49:08.350] <TB0> INFO: ----------------------------------------------------------------------
[06:49:26.364] <TB0> INFO: PixTestPretest::programROC() done: ROCs 8 cannot be programmed! Error!
[06:49:26.364] <TB0> INFO: IA differences per ROC: 16.9 17.7 16.9 19.3 20.1 17.7 20.1 18.5 0.0 17.7 19.3 20.1 21.7 17.7 18.5 17.7
[06:49:26.432] <TB0> INFO: ######################################################################
[06:49:26.432] <TB0> INFO: ERROR: some ROCs are not programmable; stop
[06:49:26.432] <TB0> INFO: ######################################################################
[06:49:26.462] <TB0> INFO: enter test to run
[06:49:26.462] <TB0> INFO: test: PixelAlive no parameter change
[06:49:26.462] <TB0> INFO: running: pixelalive
[06:49:26.463] <TB0> INFO: ######################################################################
[06:49:26.463] <TB0> INFO: PixTestAlive::doTest()
[06:49:26.463] <TB0> INFO: ######################################################################
[06:49:26.464] <TB0> INFO: ----------------------------------------------------------------------
[06:49:26.464] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:49:26.464] <TB0> INFO: ----------------------------------------------------------------------
[06:49:26.753] <TB0> INFO: Expecting 41600 events.
[06:49:28.425] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:28.679] <TB0> INFO: Expecting 41600 events.
[06:49:30.350] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:30.639] <TB0> INFO: Expecting 41600 events.
[06:49:32.310] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:32.602] <TB0> INFO: Expecting 41600 events.
[06:49:34.271] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:34.553] <TB0> INFO: Expecting 41600 events.
[06:49:36.222] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:36.485] <TB0> INFO: Expecting 41600 events.
[06:49:38.157] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:38.199] <TB0> INFO: PixTestAlive::aliveTest() done with 666667 decoding errors
[06:49:38.200] <TB0> INFO: number of dead pixels (per ROC): 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160
[06:49:38.201] <TB0> INFO: ----------------------------------------------------------------------
[06:49:38.201] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:49:38.201] <TB0> INFO: ----------------------------------------------------------------------
[06:49:38.474] <TB0> INFO: Expecting 41600 events.
[06:49:40.151] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:40.414] <TB0> INFO: Expecting 41600 events.
[06:49:42.090] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:42.358] <TB0> INFO: Expecting 41600 events.
[06:49:44.035] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:44.299] <TB0> INFO: Expecting 41600 events.
[06:49:45.975] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:46.239] <TB0> INFO: Expecting 41600 events.
[06:49:47.915] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:48.180] <TB0> INFO: Expecting 41600 events.
[06:49:49.856] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:49.857] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[06:49:49.892] <TB0> INFO: PixTestAlive::maskTest() done with 666667 decoding errors
[06:49:49.892] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[06:49:49.893] <TB0> INFO: ----------------------------------------------------------------------
[06:49:49.893] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:49:49.893] <TB0> INFO: ----------------------------------------------------------------------
[06:49:50.161] <TB0> INFO: Expecting 41600 events.
[06:49:51.832] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:52.091] <TB0> INFO: Expecting 41600 events.
[06:49:53.763] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:54.036] <TB0> INFO: Expecting 41600 events.
[06:49:55.707] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:55.971] <TB0> INFO: Expecting 41600 events.
[06:49:57.642] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:57.911] <TB0> INFO: Expecting 41600 events.
[06:49:59.582] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:49:59.847] <TB0> INFO: Expecting 41600 events.
[06:50:01.518] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:50:01.553] <TB0> INFO: PixTestAlive::addressDecodingTest() done with 666667 decoding errors
[06:50:01.553] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[06:50:01.553] <TB0> INFO: PixTestAlive::doTest() done, duration: 35 seconds
[06:50:01.561] <TB0> INFO: enter test to run
[06:50:01.561] <TB0> INFO: test: BB no parameter change
[06:50:01.561] <TB0> INFO: running: bb
[06:50:01.562] <TB0> INFO: ######################################################################
[06:50:01.562] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[06:50:01.562] <TB0> INFO: ######################################################################
[06:50:01.564] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[06:50:01.576] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[06:50:01.576] <TB0> INFO: run 1 of 1
[06:50:01.836] <TB0> INFO: Expecting 3120000 events.
[06:50:03.587] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:50:03.876] <TB0> INFO: Expecting 3120000 events.
[06:50:05.628] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:50:05.907] <TB0> INFO: Expecting 3120000 events.
[06:50:07.656] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:50:07.929] <TB0> INFO: Expecting 3120000 events.
[06:50:09.679] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:50:09.947] <TB0> INFO: Expecting 3120000 events.
[06:50:11.698] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:50:11.976] <TB0> INFO: Expecting 3120000 events.
[06:50:13.726] <TB0> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.
[06:50:13.760] <TB0> INFO: PixTestBBMap::doTest() done with 666667 decoding errors: , duration: 12 seconds
[06:50:13.760] <TB0> INFO: number of dead bumps (per ROC):
[06:50:13.760] <TB0> INFO: separation cut (per ROC):
[06:50:13.760] <TB0> INFO: enter test to run
[06:50:13.760] <TB0> INFO: test: Readback no parameter change
[06:50:13.760] <TB0> INFO: running: readback
[06:50:13.761] <TB0> INFO: readReadbackCal: /home/production/dataReception/ETH/M2192_Reception_2016-07-06_08h43m_1467787430//000_ReceptionTest_p17//readbackCal_C0.dat .. /home/production/dataReception/ETH/M2192_Reception_2016-07-06_08h43m_1467787430//000_ReceptionTest_p17//readbackCal_C15.dat
[06:50:13.779] <TB0> INFO: readGainPedestalParameters /home/production/dataReception/ETH/M2192_Reception_2016-07-06_08h43m_1467787430//000_ReceptionTest_p17//phCalibrationFitErr_C0.dat .. /home/production/dataReception/ETH/M2192_Reception_2016-07-06_08h43m_1467787430//000_ReceptionTest_p17//phCalibrationFitErr_C15.dat
[06:50:13.779] <TB0> ERROR: <ConfigParameters.cc/readGainPedestalParameters:L983> cannot open /home/production/dataReception/ETH/M2192_Reception_2016-07-06_08h43m_1467787430//000_ReceptionTest_p17//phCalibrationFitErr_C0.dat for reading PH calibration constants
[06:50:13.779] <TB0> INFO: ######################################################################
[06:50:13.779] <TB0> INFO: PixTestReadback::doTest()
[06:50:13.779] <TB0> INFO: ######################################################################
[06:50:13.779] <TB0> INFO: ----------------------------------------------------------------------
[06:50:13.779] <TB0> INFO: PixTestReadback::CalibrateVd()
[06:50:13.780] <TB0> INFO: ----------------------------------------------------------------------
[06:50:25.012] <TB0> INFO: ERROR: no readback data received after 10 attempts. Aborting readback calibration
[06:50:25.012] <TB0> INFO: ----------------------------------------------------------------------
[06:50:25.012] <TB0> INFO: PixTestReadback::CalibrateVa()
[06:50:25.012] <TB0> INFO: ----------------------------------------------------------------------
[06:50:36.313] <TB0> INFO: ERROR: no readback data received after 10 attempts. Aborting readback calibration
[06:50:36.313] <TB0> INFO: ----------------------------------------------------------------------
[06:50:36.313] <TB0> INFO: PixTestReadback::readbackVbg()
[06:50:36.313] <TB0> INFO: ----------------------------------------------------------------------
[06:50:47.619] <TB0> INFO: ERROR: no readback data received after 10 attempts. Aborting readback of Vbg
[06:50:47.619] <TB0> INFO: ----------------------------------------------------------------------
[06:50:47.619] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[06:50:47.619] <TB0> INFO: ----------------------------------------------------------------------
[06:50:47.619] <TB0> INFO: Vbg will be calibrated using Vd calibration
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.619] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/
[06:50:47.684] <TB0> INFO: ----------------------------------------------------------------------
[06:50:47.684] <TB0> INFO: PixTestReadback::CalibrateIa()
[06:50:47.684] <TB0> INFO: ----------------------------------------------------------------------
[07:22:25.304] <TB0> CRITICAL: <USBInterface.libftd2xx.cc/FillBuffer:L266> Requested to read 1b, but read 0b - 1b missing!
[07:22:25.304] <TB0> CRITICAL: <USBInterface.libftd2xx.cc/FillBuffer:L273> FTD2XX error occured: io error