MORE-Web

Test Date: 2016-07-06 11:29
Analysis date: 2016-07-19 21:38
Logfile
LogfileView
[09:30:08.089] <TB1>     INFO: *** Welcome to pxar ***

[09:30:08.089] <TB1>     INFO: *** Today: 2016/07/06

[09:30:08.145] <TB1>     INFO: *** Version: 293a

[09:30:08.145] <TB1>     INFO: readRocDacs: /home/production/dataReception/ETH/M2121_Reception_2016-07-06_11h29m_1467797357//000_ReceptionTest_p17//dacParameters_C0.dat .. /home/production/dataReception/ETH/M2121_Reception_2016-07-06_11h29m_1467797357//000_ReceptionTest_p17//dacParameters_C15.dat

[09:30:08.160] <TB1>     INFO: readTbmDacs: /home/production/dataReception/ETH/M2121_Reception_2016-07-06_11h29m_1467797357//000_ReceptionTest_p17//tbmParameters_C0a.dat .. /home/production/dataReception/ETH/M2121_Reception_2016-07-06_11h29m_1467797357//000_ReceptionTest_p17//tbmParameters_C0b.dat

[09:30:08.162] <TB1>     INFO: readMaskFile: /home/production/dataReception/ETH/M2121_Reception_2016-07-06_11h29m_1467797357//000_ReceptionTest_p17//defaultMaskFile.dat

[09:30:08.163] <TB1>     INFO: readTrimFile: /home/production/dataReception/ETH/M2121_Reception_2016-07-06_11h29m_1467797357//000_ReceptionTest_p17//trimParameters_C0.dat .. /home/production/dataReception/ETH/M2121_Reception_2016-07-06_11h29m_1467797357//000_ReceptionTest_p17//trimParameters_C15.dat

[09:30:08.266] <TB1>     INFO:         clk: 4

[09:30:08.266] <TB1>     INFO:         ctr: 4

[09:30:08.266] <TB1>     INFO:         sda: 19

[09:30:08.266] <TB1>     INFO:         tin: 9

[09:30:08.266] <TB1>     INFO:         level: 15

[09:30:08.266] <TB1>     INFO:         triggerdelay: 0

[09:30:08.266] <TB1>    QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8

[09:30:08.266] <TB1>     INFO: Log level: INFO

[09:30:08.277] <TB1>     INFO: Found DTB DTB_WXBYFL

[09:30:08.285] <TB1>    QUIET: Connection to board DTB_WXBYFL opened.

[09:30:08.287] <TB1>     INFO: DTB startup information

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

Board id:    153

HW version:  DTB1.2

FW version:  4.4

SW version:  4.6

Options:     

USB id:      DTB_WXBYFL

MAC address: 40D855118099

Hostname:    pixelDTB153

Comment:     

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

[09:30:08.288] <TB1>     INFO: RPC call hashes of host and DTB match: 484264910

[09:30:09.824] <TB1>     INFO: DUT info: 

[09:30:09.824] <TB1>     INFO: The DUT currently contains the following objects:

[09:30:09.824] <TB1>     INFO:  2 TBM Cores tbm09c (2 ON)

[09:30:09.824] <TB1>     INFO: 	TBM Core alpha (0): 7 registers set

[09:30:09.824] <TB1>     INFO: 	TBM Core beta  (1): 7 registers set

[09:30:09.824] <TB1>     INFO: 16 ROCs psi46digv2.1 (16 ON) with 4160 pixelConfigs

[09:30:09.824] <TB1>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:09.825] <TB1>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[09:30:10.228] <TB1>     INFO: enter 'restricted' command line mode

[09:30:10.228] <TB1>     INFO: enter test to run

[09:30:10.228] <TB1>     INFO:   test: Pretest no parameter change

[09:30:10.228] <TB1>     INFO:   running: pretest

[09:30:10.235] <TB1>     INFO: ######################################################################

[09:30:10.235] <TB1>     INFO: PixTestPretest::doTest()

[09:30:10.235] <TB1>     INFO: ######################################################################

[09:30:10.236] <TB1>     INFO:    ----------------------------------------------------------------------

[09:30:10.236] <TB1>     INFO:    PixTestPretest::programROC() 

[09:30:10.236] <TB1>     INFO:    ----------------------------------------------------------------------

[09:30:28.251] <TB1>     INFO: PixTestPretest::programROC() done: ROCs 7 cannot be programmed! Error!

[09:30:28.251] <TB1>     INFO: IA differences per ROC:  16.8 16.0 16.0 13.6 15.2 16.0 16.0 -0.8 15.2 15.2 17.6 14.4 16.8 16.8 15.2 16.8

[09:30:28.327] <TB1>     INFO: ######################################################################

[09:30:28.327] <TB1>     INFO: ERROR: some ROCs are not programmable; stop

[09:30:28.327] <TB1>     INFO: ######################################################################

[09:30:28.359] <TB1>     INFO: enter test to run

[09:30:28.359] <TB1>     INFO:   test: PixelAlive no parameter change

[09:30:28.359] <TB1>     INFO:   running: pixelalive

[09:30:28.360] <TB1>     INFO: ######################################################################

[09:30:28.360] <TB1>     INFO: PixTestAlive::doTest()

[09:30:28.360] <TB1>     INFO: ######################################################################

[09:30:28.361] <TB1>     INFO:    ----------------------------------------------------------------------

[09:30:28.361] <TB1>     INFO:    PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[09:30:28.361] <TB1>     INFO:    ----------------------------------------------------------------------

[09:30:28.624] <TB1>     INFO: Expecting 41600 events.

[09:30:30.318] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:30.599] <TB1>     INFO: Expecting 41600 events.

[09:30:32.595] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:32.859] <TB1>     INFO: Expecting 41600 events.

[09:30:34.546] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:34.811] <TB1>     INFO: Expecting 41600 events.

[09:30:36.807] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:37.069] <TB1>     INFO: Expecting 41600 events.

[09:30:38.771] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:39.038] <TB1>     INFO: Expecting 41600 events.

[09:30:40.728] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:40.766] <TB1>     INFO: PixTestAlive::aliveTest() done with 666667 decoding errors

[09:30:40.766] <TB1>     INFO: number of dead pixels (per ROC):  4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160

[09:30:40.767] <TB1>     INFO:    ----------------------------------------------------------------------

[09:30:40.767] <TB1>     INFO:    PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[09:30:40.767] <TB1>     INFO:    ----------------------------------------------------------------------

[09:30:41.042] <TB1>     INFO: Expecting 41600 events.

[09:30:42.961] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:43.226] <TB1>     INFO: Expecting 41600 events.

[09:30:45.144] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:45.412] <TB1>     INFO: Expecting 41600 events.

[09:30:47.166] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:47.432] <TB1>     INFO: Expecting 41600 events.

[09:30:49.351] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:49.614] <TB1>     INFO: Expecting 41600 events.

[09:30:51.357] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:51.621] <TB1>     INFO: Expecting 41600 events.

[09:30:53.362] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:53.363] <TB1>     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0

[09:30:53.395] <TB1>     INFO: PixTestAlive::maskTest() done with 666667 decoding errors

[09:30:53.396] <TB1>     INFO: number of mask-defect pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0

[09:30:53.397] <TB1>     INFO:    ----------------------------------------------------------------------

[09:30:53.397] <TB1>     INFO:    PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[09:30:53.397] <TB1>     INFO:    ----------------------------------------------------------------------

[09:30:53.679] <TB1>     INFO: Expecting 41600 events.

[09:30:55.371] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:55.638] <TB1>     INFO: Expecting 41600 events.

[09:30:57.323] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:57.602] <TB1>     INFO: Expecting 41600 events.

[09:30:59.282] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:30:59.556] <TB1>     INFO: Expecting 41600 events.

[09:31:01.228] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:31:01.492] <TB1>     INFO: Expecting 41600 events.

[09:31:03.178] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:31:03.441] <TB1>     INFO: Expecting 41600 events.

[09:31:05.126] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:31:05.167] <TB1>     INFO: PixTestAlive::addressDecodingTest() done with 666667 decoding errors

[09:31:05.167] <TB1>     INFO: number of address-decoding pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0

[09:31:05.167] <TB1>     INFO: PixTestAlive::doTest() done, duration: 36 seconds

[09:31:05.184] <TB1>     INFO: enter test to run

[09:31:05.184] <TB1>     INFO:   test: BB no parameter change

[09:31:05.184] <TB1>     INFO:   running: bb

[09:31:05.185] <TB1>     INFO: ######################################################################

[09:31:05.185] <TB1>     INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)

[09:31:05.186] <TB1>     INFO: ######################################################################

[09:31:05.188] <TB1>     INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)

[09:31:05.199] <TB1>     INFO:   dacScan split into 1 runs with ntrig = 5

[09:31:05.199] <TB1>     INFO:     run 1 of 1

[09:31:05.458] <TB1>     INFO: Expecting 3120000 events.

[09:31:07.217] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:31:07.486] <TB1>     INFO: Expecting 3120000 events.

[09:31:09.224] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:31:09.499] <TB1>     INFO: Expecting 3120000 events.

[09:31:11.267] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:31:11.535] <TB1>     INFO: Expecting 3120000 events.

[09:31:13.299] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:31:13.562] <TB1>     INFO: Expecting 3120000 events.

[09:31:15.297] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:31:15.572] <TB1>     INFO: Expecting 3120000 events.

[09:31:17.334] <TB1> CRITICAL: <hal.cc/addCondensedData:L2037> Error in DAQ: Detected DESER400 failure. Aborting test.

[09:31:17.368] <TB1>     INFO: PixTestBBMap::doTest() done with 666667 decoding errors: , duration: 12 seconds

[09:31:17.368] <TB1>     INFO: number of dead bumps (per ROC): 

[09:31:17.368] <TB1>     INFO: separation cut       (per ROC): 

[09:31:17.368] <TB1>     INFO: enter test to run

[09:31:17.368] <TB1>     INFO:   test: Readback no parameter change

[09:31:17.368] <TB1>     INFO:   running: readback

[09:31:17.368] <TB1>     INFO: readReadbackCal: /home/production/dataReception/ETH/M2121_Reception_2016-07-06_11h29m_1467797357//000_ReceptionTest_p17//readbackCal_C0.dat .. /home/production/dataReception/ETH/M2121_Reception_2016-07-06_11h29m_1467797357//000_ReceptionTest_p17//readbackCal_C15.dat

[09:31:17.386] <TB1>     INFO: readGainPedestalParameters /home/production/dataReception/ETH/M2121_Reception_2016-07-06_11h29m_1467797357//000_ReceptionTest_p17//phCalibrationFitErr_C0.dat .. /home/production/dataReception/ETH/M2121_Reception_2016-07-06_11h29m_1467797357//000_ReceptionTest_p17//phCalibrationFitErr_C15.dat

[09:31:17.387] <TB1>    ERROR: <ConfigParameters.cc/readGainPedestalParameters:L983> cannot open /home/production/dataReception/ETH/M2121_Reception_2016-07-06_11h29m_1467797357//000_ReceptionTest_p17//phCalibrationFitErr_C0.dat for reading PH calibration constants

[09:31:17.387] <TB1>     INFO: ######################################################################

[09:31:17.387] <TB1>     INFO: PixTestReadback::doTest()

[09:31:17.387] <TB1>     INFO: ######################################################################

[09:31:17.387] <TB1>     INFO:    ----------------------------------------------------------------------

[09:31:17.387] <TB1>     INFO:    PixTestReadback::CalibrateVd()

[09:31:17.387] <TB1>     INFO:    ----------------------------------------------------------------------

[09:31:28.613] <TB1>     INFO: ERROR: no readback data received after 10 attempts. Aborting readback calibration

[09:31:28.614] <TB1>     INFO:    ----------------------------------------------------------------------

[09:31:28.614] <TB1>     INFO:    PixTestReadback::CalibrateVa()

[09:31:28.614] <TB1>     INFO:    ----------------------------------------------------------------------

[09:31:39.911] <TB1>     INFO: ERROR: no readback data received after 10 attempts. Aborting readback calibration

[09:31:39.912] <TB1>     INFO:    ----------------------------------------------------------------------

[09:31:39.912] <TB1>     INFO:    PixTestReadback::readbackVbg()

[09:31:39.912] <TB1>     INFO:    ----------------------------------------------------------------------

[09:31:51.231] <TB1>     INFO: ERROR: no readback data received after 10 attempts. Aborting readback of Vbg

[09:31:51.231] <TB1>     INFO:    ----------------------------------------------------------------------

[09:31:51.231] <TB1>     INFO:    PixTestReadback::getCalibratedVbg()

[09:31:51.231] <TB1>     INFO:    ----------------------------------------------------------------------

[09:31:51.231] <TB1>     INFO: Vbg will be calibrated using Vd calibration

[09:31:51.231] <TB1>     INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.231] <TB1>     INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.231] <TB1>     INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.231] <TB1>     INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.232] <TB1>     INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.232] <TB1>     INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.232] <TB1>     INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.232] <TB1>     INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.232] <TB1>     INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.232] <TB1>     INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.232] <TB1>     INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.232] <TB1>     INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.232] <TB1>     INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.232] <TB1>     INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.232] <TB1>     INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.232] <TB1>     INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 0calibrated Vbg = -0.5 :::*/*/*/*/

[09:31:51.295] <TB1>     INFO:    ----------------------------------------------------------------------

[09:31:51.295] <TB1>     INFO:    PixTestReadback::CalibrateIa()

[09:31:51.295] <TB1>     INFO:    ----------------------------------------------------------------------

[10:01:21.676] <TB1> CRITICAL: <USBInterface.libftd2xx.cc/FillBuffer:L266> Requested to read 1b, but read 0b - 1b missing!

[10:01:21.677] <TB1> CRITICAL: <USBInterface.libftd2xx.cc/FillBuffer:L273> FTD2XX error occured: io error
MoReWeb-v1.0.2-10-gbc52a17 on branch psi46master