MORE-Web

Test Date: 2016-11-21 17:11
Analysis date: 2016-11-21 17:13
Logfile
LogfileView
[17:11:32.980] <TB0>     INFO: *** Welcome to pxar ***

[17:11:32.980] <TB0>     INFO: *** Today: 2016/11/21

[17:11:33.106] <TB0>     INFO: *** Version: 3035-dirty

[17:11:33.106] <TB0>     INFO: readRocDacs: /Users/p/OnShellTest/results_L1minus/M1075_OnShellQuickTest_2016-11-21_17h11m_1479744679//000_OnShellQuickTest_p17//dacParameters_C0.dat .. /Users/p/OnShellTest/results_L1minus/M1075_OnShellQuickTest_2016-11-21_17h11m_1479744679//000_OnShellQuickTest_p17//dacParameters_C15.dat

[17:11:33.107] <TB0>     INFO: readTbmDacs: /Users/p/OnShellTest/results_L1minus/M1075_OnShellQuickTest_2016-11-21_17h11m_1479744679//000_OnShellQuickTest_p17//tbmParameters_C0a.dat .. /Users/p/OnShellTest/results_L1minus/M1075_OnShellQuickTest_2016-11-21_17h11m_1479744679//000_OnShellQuickTest_p17//tbmParameters_C1b.dat

[17:11:33.107] <TB0>     INFO: readMaskFile: /Users/p/OnShellTest/results_L1minus/M1075_OnShellQuickTest_2016-11-21_17h11m_1479744679//000_OnShellQuickTest_p17//defaultMaskFile.dat

[17:11:33.107] <TB0>     INFO: readTrimFile: /Users/p/OnShellTest/results_L1minus/M1075_OnShellQuickTest_2016-11-21_17h11m_1479744679//000_OnShellQuickTest_p17//trimParameters_C0.dat .. /Users/p/OnShellTest/results_L1minus/M1075_OnShellQuickTest_2016-11-21_17h11m_1479744679//000_OnShellQuickTest_p17//trimParameters_C15.dat

[17:11:33.161] <TB0>     INFO:         clk: 4

[17:11:33.161] <TB0>     INFO:         ctr: 4

[17:11:33.161] <TB0>     INFO:         sda: 19

[17:11:33.161] <TB0>     INFO:         tin: 9

[17:11:33.161] <TB0>     INFO:         level: 15

[17:11:33.161] <TB0>     INFO:         triggerdelay: 0

[17:11:33.161] <TB0>    QUIET: Instanciating API for pxar v2.7.6+75~g44d91fa

[17:11:33.161] <TB0>     INFO: Log level: INFO

[17:11:34.499] <TB0>    QUIET: Connection to board DTB_WRMN1X opened.

[17:11:34.501] <TB0>     INFO: DTB startup information

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

Board id:    42

HW version:  DTB1.2

FW version:  4.6

SW version:  4.7

Options:     LCDS2

USB id:      DTB_WRMN1X

MAC address: 40D85511802A

Hostname:    pixelDTB042

Comment:     

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

[17:11:34.503] <TB0>     INFO: RPC call hashes of host and DTB match: 486171790

[17:11:36.105] <TB0>     INFO: DUT info: 

[17:11:36.105] <TB0>     INFO: The DUT currently contains the following objects:

[17:11:36.105] <TB0>     INFO:  4 TBM Cores tbm10c (4 ON)

[17:11:36.105] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[17:11:36.105] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[17:11:36.105] <TB0>     INFO: 	TBM Core alpha (2): 7 registers set

[17:11:36.105] <TB0>     INFO: 	TBM Core beta  (3): 7 registers set

[17:11:36.106] <TB0>     INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs

[17:11:36.106] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.106] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[17:11:36.510] <TB0>     INFO: enter 'restricted' command line mode

[17:11:36.510] <TB0>     INFO: enter test to run

[17:11:36.510] <TB0>     INFO:   test: OnShellQuickTest no parameter change

[17:11:36.511] <TB0>     INFO:   running: onshellquicktest

[17:11:36.515] <TB0>     INFO: ######################################################################

[17:11:36.515] <TB0>     INFO: PixTestOnShellQuickTest::doTest()

[17:11:36.515] <TB0>     INFO: ######################################################################

[17:11:36.515] <TB0>     INFO:    ----------------------------------------------------------------------

[17:11:36.515] <TB0>     INFO:    PixTestOnShellQuickTest::signalTest() 

[17:11:36.515] <TB0>     INFO:    ----------------------------------------------------------------------

[17:11:36.515] <TB0>     INFO: PixTestCmd::init()

[17:11:36.517] <TB0>  WARNING: Pattern generator does not contain a trigger signal. No data is expected from the DUT!

[17:11:38.044] <TB0>  WARNING: Pattern generator does not contain a trigger signal. No data is expected from the DUT!

[17:11:38.424] <TB0>  WARNING: Pattern generator does not contain a trigger signal. No data is expected from the DUT!

[17:11:38.832] <TB0>     INFO:    clk  low = -202.0 mV  high=  193.0 mV   amplitude =  395.0 mVpp  (differential)

[17:11:38.832] <TB0>     INFO:    ctr  low = -249.0 mV  high=  268.0 mV   amplitude =  517.0 mVpp  (differential)

[17:11:38.832] <TB0>     INFO:    sda  low = -258.0 mV  high=  245.0 mV   amplitude =  503.0 mVpp  (differential)

[17:11:38.832] <TB0>     INFO:    rda  low =   -2.0 mV  high=    2.0 mV   amplitude =    4.0 mVpp  (differential)

[17:11:38.832] <TB0>     INFO: sdata1  low = -100.0 mV  high=   90.0 mV   amplitude =  190.0 mVpp  (differential)

[17:11:38.832] <TB0>     INFO: sdata2  low = -115.0 mV  high=   93.0 mV   amplitude =  208.0 mVpp  (differential)

[17:11:41.152] <TB0>     INFO: powercycled

[17:11:41.154] <TB0>     INFO:    ----------------------------------------------------------------------

[17:11:41.154] <TB0>     INFO:    PixTestOnShellQuickTest::programROC() 

[17:11:41.154] <TB0>     INFO:    ----------------------------------------------------------------------

[17:11:59.272] <TB0>     INFO: PixTestOnShellQuickTest::programROC() done: ROCs are all programmable

[17:11:59.272] <TB0>     INFO: IA differences per ROC:  19.3 24.2 25.8 25.8 20.9 25.8 25.0 19.3 23.4 25.8 23.4 25.8 22.5 25.0 25.0 22.5

[17:11:59.379] <TB0>     INFO:    ----------------------------------------------------------------------

[17:11:59.379] <TB0>     INFO:    PixTestOnShellQuickTest::setVana() target Ia = 24 mA/ROC

[17:11:59.379] <TB0>     INFO:    ----------------------------------------------------------------------

[17:12:09.717] <TB0>     INFO: PixTestOnShellQuickTest::setVana() done, Module Ia 387.5 mA = 24.2188 mA/ROC

[17:12:09.717] <TB0>     INFO: i(loss) [mA/ROC]:     19.3  19.3  18.5  19.3  19.3  19.3  17.7  19.3  19.3  19.3  18.5  18.5  19.3  18.5  18.5  19.3

[17:12:09.750] <TB0>     INFO:    ----------------------------------------------------------------------

[17:12:09.750] <TB0>     INFO:    PixTestOnShellQuickTest::findTiming() 

[17:12:09.750] <TB0>     INFO:    ----------------------------------------------------------------------

[17:12:09.750] <TB0>     INFO: Testing Timing: Attempt #1

[17:12:11.552] <TB0>     INFO: NErrors = 0

[17:12:11.552] <TB0>     INFO: NEvents = 10000

[17:12:11.578] <TB0>     INFO: Timings are already good, no scan needed!

[17:12:11.800] <TB0>     INFO:    ----------------------------------------------------------------------

[17:12:11.800] <TB0>     INFO:    PixTestOnShellQuickTest::findWorkingPixel()

[17:12:11.800] <TB0>     INFO:    ----------------------------------------------------------------------

[17:12:11.894] <TB0>     INFO: Expecting 231680 events.

[17:12:21.562] <TB0>     INFO: 231680 events read in total (9039ms).

[17:12:21.579] <TB0>     INFO: Test took 9773ms.

[17:12:21.855] <TB0>     INFO: Found working pixel in all ROCs: col/row = 12/22

[17:12:22.148] <TB0>     INFO:    ----------------------------------------------------------------------

[17:12:22.148] <TB0>     INFO:    PixTestOnShellQuickTest::setVthrCompCalDel()

[17:12:22.148] <TB0>     INFO:    ----------------------------------------------------------------------

[17:12:22.174] <TB0>     INFO: /1

[17:12:22.180] <TB0>     INFO: /2

[17:12:22.272] <TB0>     INFO: Expecting 463360 events.

[17:12:36.564] <TB0>     INFO: 463360 events read in total (13665ms).

[17:12:36.581] <TB0>     INFO: Test took 14400ms.

[17:12:36.599] <TB0>     INFO: /3

[17:12:36.876] <TB0>     INFO: PixTestOnShellQuickTest::setVthrCompCalDel() done

[17:12:36.876] <TB0>     INFO: CalDel:       95    95    97    92    93    87    95    88    97    97    97    93    87    83    89    88

[17:12:36.876] <TB0>     INFO: VthrComp:     51    53    51    51    54    51    56    51    54    51    51    51    51    54    51    53

[17:12:36.878] <TB0>     INFO:    ----------------------------------------------------------------------

[17:12:36.878] <TB0>     INFO:    PixTestOnShellQuickTest::hvQuickTest()

[17:12:36.878] <TB0>     INFO:    ----------------------------------------------------------------------

[17:12:37.632] <TB0>     INFO: Expecting 41600 events.

[17:12:41.902] <TB0>     INFO: 41600 events read in total (3643ms).

[17:12:41.903] <TB0>     INFO: Test took 4621ms.

[17:12:42.486] <TB0>     INFO: Expecting 41600 events.

[17:12:46.077] <TB0>  WARNING: Detected DESER400 trailer error bits: "CODE ERROR"

[17:12:46.078] <TB0>  WARNING: Detected DESER400 trailer error bits: "CODE ERROR"

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/CheckEventID:L485> Channel 4 Event ID mismatch:  local ID (159) !=  TBM ID (160)

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/CheckEventID:L485> Channel 5 Event ID mismatch:  local ID (159) !=  TBM ID (160)

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a4a3 8040 4e00 a24 29ef 4e00 e022 c000 

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a49d 80c0 4600 a24 29ef 4600 e022 c000 

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a49e 8000 4e00 a24 29ef 4e02 a24 29ef e022 c000 

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a49f 8040 4402 a24 29ef 4e41 e24 29ef f400 d400 

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a4a0 80b1 4e01 a24 29ef 4e01 e022 c000 

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a4a1 80c0 4e01 a24 29ef 4e00 e022 c000 

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a4a2 8000 4e00 a24 29ef 4e01 e022 c000 

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5a3 8040 4e00 a24 29ef 4e01 a24 29ef e022 c000 

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a59d 80c0 4600 a24 29ef 4601 a24 29ef e022 c000 

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a59e 8000 4e00 a24 29ef 4e01 a24 29ef e022 c000 

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a59f 8040 4402 a24 29ef 4e40 e24 29ef f400 d400 

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5a0 80b1 4e01 a24 29ef 4e00 a24 29ef e022 c000 

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5a1 80c0 4e01 a24 29ef 4e00 a24 29ef e022 c000 

[17:12:46.078] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5a2 8000 4e01 a24 29ef 4e00 a24 29ef e022 c000 

[17:12:46.079] <TB0>  WARNING: Channel 5 ROC 1: Readback start marker after 15 readouts!

[17:12:46.079] <TB0>    ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:

[17:12:46.079] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5ad 80c0 4600 a24 2bef 4601 a24 2bef e022 c000 

[17:12:46.079] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5a7 8040 4600 a24 2bef 4601 a24 2bef e022 c000 

[17:12:46.079] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5a8 80b1 4601 a24 2bef 4600 e022 c000 

[17:12:46.079] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5a9 80c0 4601 a24 2bef 4600 a24 2bef e022 c000 

[17:12:46.079] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5aa 8000 4e00 a24 2bef 4e02 e022 c000 

[17:12:46.079] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5ab 8040 4e00 a24 2bef 4601 a24 2bef e022 c000 

[17:12:46.079] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5ac 80b1 4e01 a24 2bef 4e01 e022 c000 

[17:12:46.079] <TB0>  WARNING: Channel 4 ROC 1: Readback start marker after 15 readouts!

[17:12:46.079] <TB0>  WARNING: Channel 4 ROC 0: Readback start marker after 31 readouts!

[17:12:46.079] <TB0>  WARNING: Channel 5 ROC 0: Readback start marker after 31 readouts!

[17:12:46.079] <TB0>    ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:

[17:12:46.079] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a4b2 8000 4600 a24 2def 4601 a24 2def e022 c000 

[17:12:46.080] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a4ac 80b1 4e01 a24 2bef 4e00 a24 2bef e022 c000 

[17:12:46.080] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a4ad 80c0 4600 a24 2bef 4600 e022 c000 

[17:12:46.080] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a4ae 8000 4600 a24 2bef 4e02 a24 2bef e022 c000 

[17:12:46.080] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a4af 8040 4c02 a24 2bef 4e01 a24 2bef e022 c000 

[17:12:46.080] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a4b0 80b1 4601 a24 2def 4601 a24 2def e022 c000 

[17:12:46.080] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a4b1 80c0 4601 a24 2def 4600 a24 2def e022 c000 

[17:12:46.080] <TB0>    ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:

[17:12:46.081] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5b2 8000 4601 a24 2def 4600 a24 2def e022 c000 

[17:12:46.081] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5ac 80b1 4e01 a24 2bef 4e01 e022 c000 

[17:12:46.081] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5ad 80c0 4600 a24 2bef 4601 a24 2bef e022 c000 

[17:12:46.081] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5ae 8000 4600 a24 2bef 4e01 a24 2bef e022 c000 

[17:12:46.081] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5af 8040 4c02 a24 2bef 4e00 a24 2bef e022 c000 

[17:12:46.081] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5b0 80b1 4601 a24 2def 4600 a24 2def e022 c000 

[17:12:46.081] <TB0>    ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a5b1 80c0 4601 a24 2def 4600 a24 2def e022 c000 

[17:12:46.707] <TB0>     INFO: 41600 events read in total (3595ms).

[17:12:46.709] <TB0>     INFO: Test took 4571ms.

[17:12:47.123] <TB0>     INFO: Inefficient pixels per ROC: 0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  

[17:12:47.123] <TB0>     INFO: Delta Pixel Alive with HV per ROC: 607  930  1168  154  910  2474  3963  1463  3206  534  1073  577  798  2926  706  2141  

[17:12:47.124] <TB0>     INFO: Database: Dead Pixels

[17:12:47.124] <TB0>     INFO:   Dead pixels per ROC: 0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  

[17:12:47.124] <TB0>     INFO: Quicktest: Dead Pixels

[17:12:47.124] <TB0>     INFO:   Dead pixels per ROC: 0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  0  

[17:12:47.152] <TB0>     INFO:    ----------------------------------------------------------------------

[17:12:47.152] <TB0>     INFO:    PixTestOnShellQuickTest::bbQuickTest()

[17:12:47.152] <TB0>     INFO:    ----------------------------------------------------------------------

[17:12:47.152] <TB0>     INFO: using Ntrig = 10, Vthrcomp = 105

[17:12:47.502] <TB0>     INFO: Expecting 41600 events.

[17:12:51.768] <TB0>     INFO: 41600 events read in total (3638ms).

[17:12:51.769] <TB0>     INFO: Test took 4614ms.

[17:12:51.784] <TB0>     INFO: using Vana from file: 

[17:12:51.784] <TB0>     INFO: Vana:83  74  74  70  78  70  64  83  69  65  70  70  83  70  70  74  

[17:12:51.786] <TB0>     INFO: using Vthrcomps from file: 

[17:12:51.786] <TB0>     INFO: Vthrcomp:101  112  94  107  100  99  124  99  121  92  95  95  94  109  108  108  

[17:12:52.143] <TB0>     INFO: Expecting 41600 events.

[17:12:56.835] <TB0>     INFO: 41600 events read in total (4065ms).

[17:12:56.837] <TB0>     INFO: Test took 5042ms.

[17:12:57.075] <TB0>     INFO: results from database:

[17:12:57.075] <TB0>     INFO: Dead bumps per ROC: 1  0  1  0  0  1  0  3  0  2  0  1  1  1  2  2  

[17:12:57.075] <TB0>     INFO: quicktest:

[17:12:57.075] <TB0>     INFO: Dead bumps per ROC: 1  1  1  0  0  1  11  5  5  2  0  1  0  1  2  3  

[17:12:57.107] <TB0>     INFO: PixTestOnShellQuickTest::doTest() done, duration: 80 seconds

[17:12:57.174] <TB0>     INFO: enter test to run

[17:12:57.174] <TB0>     INFO:   test:  no parameter change

[17:12:57.202] <TB0>    QUIET: Connection to board 42 closed.

[17:12:57.569] <TB0>     INFO: pXar: this is the end, my friend
v1.1.1-5-g98789dd on branch psi46master