[18:22:18.499] <TB0> INFO: *** Welcome to pxar ***
[18:22:18.499] <TB0> INFO: *** Today: 2016/11/21
[18:22:18.578] <TB0> INFO: *** Version: 3035-dirty
[18:22:18.579] <TB0> INFO: readRocDacs: /Users/p/OnShellTest/results_L1minus/M1011_OnShellQuickTest_2016-11-21_18h22m_1479748926//000_OnShellQuickTest_p17//dacParameters_C0.dat .. /Users/p/OnShellTest/results_L1minus/M1011_OnShellQuickTest_2016-11-21_18h22m_1479748926//000_OnShellQuickTest_p17//dacParameters_C15.dat
[18:22:18.579] <TB0> INFO: readTbmDacs: /Users/p/OnShellTest/results_L1minus/M1011_OnShellQuickTest_2016-11-21_18h22m_1479748926//000_OnShellQuickTest_p17//tbmParameters_C0a.dat .. /Users/p/OnShellTest/results_L1minus/M1011_OnShellQuickTest_2016-11-21_18h22m_1479748926//000_OnShellQuickTest_p17//tbmParameters_C1b.dat
[18:22:18.580] <TB0> INFO: readMaskFile: /Users/p/OnShellTest/results_L1minus/M1011_OnShellQuickTest_2016-11-21_18h22m_1479748926//000_OnShellQuickTest_p17//defaultMaskFile.dat
[18:22:18.580] <TB0> INFO: readTrimFile: /Users/p/OnShellTest/results_L1minus/M1011_OnShellQuickTest_2016-11-21_18h22m_1479748926//000_OnShellQuickTest_p17//trimParameters_C0.dat .. /Users/p/OnShellTest/results_L1minus/M1011_OnShellQuickTest_2016-11-21_18h22m_1479748926//000_OnShellQuickTest_p17//trimParameters_C15.dat
[18:22:18.632] <TB0> INFO: clk: 4
[18:22:18.632] <TB0> INFO: ctr: 4
[18:22:18.632] <TB0> INFO: sda: 19
[18:22:18.632] <TB0> INFO: tin: 9
[18:22:18.632] <TB0> INFO: level: 15
[18:22:18.632] <TB0> INFO: triggerdelay: 0
[18:22:18.632] <TB0> QUIET: Instanciating API for pxar v2.7.6+75~g44d91fa
[18:22:18.632] <TB0> INFO: Log level: INFO
[18:22:19.977] <TB0> QUIET: Connection to board DTB_WRMN1X opened.
[18:22:19.979] <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:
------------------------------------------------------
[18:22:19.981] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[18:22:21.774] <TB0> INFO: DUT info:
[18:22:21.774] <TB0> INFO: The DUT currently contains the following objects:
[18:22:21.774] <TB0> INFO: 4 TBM Cores tbm10c (4 ON)
[18:22:21.774] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:22:21.774] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:22:21.774] <TB0> INFO: TBM Core alpha (2): 7 registers set
[18:22:21.774] <TB0> INFO: TBM Core beta (3): 7 registers set
[18:22:21.774] <TB0> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[18:22:21.774] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:21.774] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:22.178] <TB0> INFO: enter 'restricted' command line mode
[18:22:22.178] <TB0> INFO: enter test to run
[18:22:22.178] <TB0> INFO: test: OnShellQuickTest no parameter change
[18:22:22.179] <TB0> INFO: running: onshellquicktest
[18:22:22.183] <TB0> INFO: ######################################################################
[18:22:22.183] <TB0> INFO: PixTestOnShellQuickTest::doTest()
[18:22:22.183] <TB0> INFO: ######################################################################
[18:22:22.183] <TB0> INFO: ----------------------------------------------------------------------
[18:22:22.183] <TB0> INFO: PixTestOnShellQuickTest::signalTest()
[18:22:22.183] <TB0> INFO: ----------------------------------------------------------------------
[18:22:22.183] <TB0> INFO: PixTestCmd::init()
[18:22:22.184] <TB0> WARNING: Pattern generator does not contain a trigger signal. No data is expected from the DUT!
[18:22:23.737] <TB0> WARNING: Pattern generator does not contain a trigger signal. No data is expected from the DUT!
[18:22:24.148] <TB0> WARNING: Pattern generator does not contain a trigger signal. No data is expected from the DUT!
[18:22:24.575] <TB0> INFO: clk low = -197.0 mV high= 188.0 mV amplitude = 385.0 mVpp (differential)
[18:22:24.575] <TB0> INFO: ctr low = -224.0 mV high= 241.0 mV amplitude = 465.0 mVpp (differential)
[18:22:24.575] <TB0> INFO: sda low = -220.0 mV high= 212.0 mV amplitude = 432.0 mVpp (differential)
[18:22:24.575] <TB0> INFO: rda low = -2.0 mV high= -2.0 mV amplitude = 0.0 mVpp (differential)
[18:22:24.575] <TB0> INFO: sdata1 low = -105.0 mV high= 93.0 mV amplitude = 198.0 mVpp (differential)
[18:22:24.575] <TB0> INFO: sdata2 low = -117.0 mV high= 95.0 mV amplitude = 212.0 mVpp (differential)
[18:22:26.958] <TB0> INFO: powercycled
[18:22:26.960] <TB0> INFO: ----------------------------------------------------------------------
[18:22:26.960] <TB0> INFO: PixTestOnShellQuickTest::programROC()
[18:22:26.960] <TB0> INFO: ----------------------------------------------------------------------
[18:22:45.137] <TB0> INFO: PixTestOnShellQuickTest::programROC() done: ROCs are all programmable
[18:22:45.137] <TB0> INFO: IA differences per ROC: 17.7 20.9 24.1 23.3 18.5 22.5 20.9 20.9 21.7 24.9 20.9 25.7 22.5 23.3 24.1 20.1
[18:22:45.253] <TB0> INFO: ----------------------------------------------------------------------
[18:22:45.253] <TB0> INFO: PixTestOnShellQuickTest::setVana() target Ia = 24 mA/ROC
[18:22:45.253] <TB0> INFO: ----------------------------------------------------------------------
[18:23:06.858] <TB0> INFO: PixTestOnShellQuickTest::setVana() done, Module Ia 388.3 mA = 24.2687 mA/ROC
[18:23:06.858] <TB0> INFO: i(loss) [mA/ROC]: 20.9 20.9 20.9 20.9 20.9 20.9 20.1 20.9 20.9 20.9 20.1 20.9 20.1 19.3 20.1 20.9
[18:23:06.895] <TB0> INFO: ----------------------------------------------------------------------
[18:23:06.895] <TB0> INFO: PixTestOnShellQuickTest::findTiming()
[18:23:06.895] <TB0> INFO: ----------------------------------------------------------------------
[18:23:06.896] <TB0> INFO: Testing Timing: Attempt #1
[18:23:08.676] <TB0> INFO: NErrors = 0
[18:23:08.676] <TB0> INFO: NEvents = 10000
[18:23:08.705] <TB0> INFO: Timings are already good, no scan needed!
[18:23:08.932] <TB0> INFO: ----------------------------------------------------------------------
[18:23:08.932] <TB0> INFO: PixTestOnShellQuickTest::findWorkingPixel()
[18:23:08.932] <TB0> INFO: ----------------------------------------------------------------------
[18:23:09.027] <TB0> INFO: Expecting 231680 events.
[18:23:18.973] <TB0> INFO: 231680 events read in total (9318ms).
[18:23:18.990] <TB0> INFO: Test took 10053ms.
[18:23:19.271] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[18:23:19.314] <TB0> INFO: ----------------------------------------------------------------------
[18:23:19.314] <TB0> INFO: PixTestOnShellQuickTest::setVthrCompCalDel()
[18:23:19.314] <TB0> INFO: ----------------------------------------------------------------------
[18:23:19.317] <TB0> INFO: /1
[18:23:19.325] <TB0> INFO: /2
[18:23:19.418] <TB0> INFO: Expecting 463360 events.
[18:23:34.257] <TB0> INFO: 463360 events read in total (14213ms).
[18:23:34.285] <TB0> INFO: Test took 14960ms.
[18:23:34.309] <TB0> INFO: /3
[18:23:34.589] <TB0> INFO: PixTestOnShellQuickTest::setVthrCompCalDel() done
[18:23:34.589] <TB0> INFO: CalDel: 65 83 87 92 63 86 74 76 86 81 79 87 81 87 94 80
[18:23:34.589] <TB0> INFO: VthrComp: 52 51 51 51 53 51 53 51 51 51 53 51 51 51 51 56
[18:23:34.594] <TB0> INFO: ----------------------------------------------------------------------
[18:23:34.594] <TB0> INFO: PixTestOnShellQuickTest::hvQuickTest()
[18:23:34.594] <TB0> INFO: ----------------------------------------------------------------------
[18:23:35.407] <TB0> INFO: Expecting 41600 events.
[18:23:40.610] <TB0> INFO: 41600 events read in total (4575ms).
[18:23:40.616] <TB0> INFO: Test took 5616ms.
[18:23:41.288] <TB0> INFO: Expecting 41600 events.
[18:23:44.752] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[18:23:44.752] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[18:23:44.752] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 0 Event ID mismatch: local ID (141) != TBM ID (142)
[18:23:44.752] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 1 Event ID mismatch: local ID (141) != TBM ID (142)
[18:23:44.752] <TB0> WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!
[18:23:44.752] <TB0> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!
[18:23:44.752] <TB0> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!
[18:23:44.752] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a092 8000 4e00 6f2 29ef 4e00 6f2 29ef e022 c000
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a08c 80b1 4701 6f2 29ef 4701 6f2 29ef e022 c000
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a08d 80c0 4300 6f2 2fef 4f20 6f2 29ef f400 d400
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a08e 8000 4600 6f2 29ef 4f00 6f2 29ef e022 c000
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a08f 8040 4302 6f2 29ef 4702 6f2 29ef e022 c000
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a090 80b1 4780 6f2 29ef 4f00 6f2 29ef e022 c000
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a091 80c0 4f01 6f2 29ef 4701 6f2 29ef e022 c000
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a192 8000 4e01 6f2 29ef 4e01 6f2 29ef e022 c000
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a18c 80b1 4701 6f2 29ef 4701 6f2 29ef e022 c000
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a18d 80c0 4300 4f20 6f2 29ef f400 d400
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a18e 8000 4600 6f2 29ef 4f00 6f2 29ef e022 c000
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a18f 8040 4302 6f2 29ef 4702 6f2 29ef e022 c000
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a190 80b1 4780 4780 6f2 29ef e022 c000
[18:23:44.753] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a191 80c0 4f01 4701 6f2 29ef e022 c000
[18:23:44.754] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[18:23:44.754] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[18:23:44.754] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 0 Event ID mismatch: local ID (154) != TBM ID (155)
[18:23:44.754] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 1 Event ID mismatch: local ID (154) != TBM ID (155)
[18:23:44.754] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[18:23:44.754] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a09e 8000 4700 6f2 2bef 4780 6f2 2bef e022 c000
[18:23:44.754] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a098 80b1 4f01 6f2 2bef 4f01 6f2 2bef e022 c000
[18:23:44.754] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a099 80c0 4701 6f2 2bef 4701 6f2 2bef e022 c000
[18:23:44.754] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a09a 8000 4780 6f2 2fef 4f20 6f2 2bef f400 d400
[18:23:44.754] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a09b 8040 4f00 6f2 2bef 4780 6f2 2bef e022 c000
[18:23:44.755] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a09c 80b1 4301 6f2 2bef 4f01 6f2 2bef e022 c000
[18:23:44.755] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a09d 80c0 4700 6f2 2bef 4700 6f2 2bef e022 c000
[18:23:44.755] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[18:23:44.755] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a19e 8000 4700 4780 6f2 2bef e022 c000
[18:23:44.755] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a198 80b1 4f01 6f2 2bef 4f01 6f2 2bef e022 c000
[18:23:44.755] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a199 80c0 4701 6f2 2bef 4701 6f2 2bef e022 c000
[18:23:44.755] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a19a 8000 4780 4780 6f2 2bef f400 d400
[18:23:44.755] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a19b 8040 4f00 6f2 2bef 4780 6f2 2bef e022 c000
[18:23:44.755] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a19c 80b1 4301 4f01 6f2 2bef e022 c000
[18:23:44.755] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a19d 80c0 4700 4700 6f2 2bef e022 c000
[18:23:44.756] <TB0> WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!
[18:23:44.756] <TB0> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!
[18:23:44.756] <TB0> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!
[18:23:44.756] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!
[18:23:44.756] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[18:23:44.756] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0a2 8000 4700 6f2 2def 4700 6f2 2def e022 c000
[18:23:44.756] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a09c 80b1 4301 6f2 2bef 4f01 6f2 2bef e022 c000
[18:23:44.756] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a09d 80c0 4700 6f2 2bef 4700 6f2 2bef e022 c000
[18:23:44.756] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a09e 8000 4700 6f2 2bef 4780 6f2 2bef e022 c000
[18:23:44.756] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a09f 8040 4702 6f2 2bef 4702 6f2 2bef e022 c000
[18:23:44.756] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0a0 80b1 4f00 6f2 2def 4f00 6f2 2def e022 c000
[18:23:44.756] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0a1 80c0 4f01 6f2 2def 4e01 6f2 2def e022 c000
[18:23:44.756] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[18:23:44.757] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1a2 8000 4701 6f2 2def 4701 6f2 2def e022 c000
[18:23:44.757] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a19c 80b1 4301 4f01 6f2 2bef e022 c000
[18:23:44.757] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a19d 80c0 4700 4700 6f2 2bef e022 c000
[18:23:44.757] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a19e 8000 4700 4780 6f2 2bef e022 c000
[18:23:44.757] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a19f 8040 4702 6f2 2bef 4702 6f2 2bef e022 c000
[18:23:44.757] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1a0 80b1 4f00 6f2 2def 4f00 6f2 2def e022 c000
[18:23:44.757] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1a1 80c0 4f01 6f2 2def 4e01 e022 c000
[18:23:46.101] <TB0> INFO: 41600 events read in total (4185ms).
[18:23:46.106] <TB0> INFO: Test took 5248ms.
[18:23:46.528] <TB0> INFO: Inefficient pixels per ROC: 1 1 0 0 1 1 0 0 0 0 1 0 0 0 0 1
[18:23:46.528] <TB0> INFO: Delta Pixel Alive with HV per ROC: 1715 355 2229 219 652 1183 2367 525 608 977 656 3445 811 795 271 1817
[18:23:46.529] <TB0> INFO: Database: Dead Pixels
[18:23:46.529] <TB0> INFO: Dead pixels per ROC: 1 1 0 0 1 0 0 0 0 0 1 0 0 0 0 1
[18:23:46.529] <TB0> INFO: Quicktest: Dead Pixels
[18:23:46.529] <TB0> INFO: Dead pixels per ROC: 1 1 0 0 1 0 0 0 0 0 1 0 0 0 0 1
[18:23:46.697] <TB0> INFO: ----------------------------------------------------------------------
[18:23:46.698] <TB0> INFO: PixTestOnShellQuickTest::bbQuickTest()
[18:23:46.698] <TB0> INFO: ----------------------------------------------------------------------
[18:23:46.698] <TB0> INFO: using Ntrig = 10, Vthrcomp = 105
[18:23:47.160] <TB0> INFO: Expecting 41600 events.
[18:23:51.780] <TB0> INFO: 41600 events read in total (3992ms).
[18:23:51.782] <TB0> INFO: Test took 5075ms.
[18:23:51.809] <TB0> INFO: using Vana from file:
[18:23:51.809] <TB0> INFO: Vana:85 86 76 73 90 81 85 71 85 72 85 76 81 81 76 86
[18:23:51.817] <TB0> INFO: using Vthrcomps from file:
[18:23:51.817] <TB0> INFO: Vthrcomp:108 100 97 95 98 102 123 95 114 94 118 114 96 116 93 115
[18:23:52.311] <TB0> INFO: Expecting 41600 events.
[18:23:57.367] <TB0> INFO: 41600 events read in total (4428ms).
[18:23:57.370] <TB0> INFO: Test took 5545ms.
[18:23:57.620] <TB0> INFO: results from database:
[18:23:57.620] <TB0> INFO: Dead bumps per ROC: 5 4 1 0 0 1 0 2 0 0 0 0 26 1 8 21
[18:23:57.620] <TB0> INFO: quicktest:
[18:23:57.620] <TB0> INFO: Dead bumps per ROC: 36 16 3 3 3 0 1 1 1 0 2 14 56 31 22 82
[18:23:57.803] <TB0> INFO: PixTestOnShellQuickTest::doTest() done, duration: 95 seconds
[18:23:57.899] <TB0> INFO: enter test to run
[18:23:57.899] <TB0> INFO: test: no parameter change
[18:23:57.908] <TB0> QUIET: Connection to board 42 closed.
[18:23:58.528] <TB0> INFO: pXar: this is the end, my friend