[20:05:32.222] <TB0> INFO: *** Welcome to pxar ***
[20:05:32.222] <TB0> INFO: *** Today: 2016/11/21
[20:05:32.339] <TB0> INFO: *** Version: 3035-dirty
[20:05:32.339] <TB0> INFO: readRocDacs: /Users/p/OnShellTest/results_L1minus/M1063_OnShellQuickTest_2016-11-21_20h05m_1479755119//000_OnShellQuickTest_p17//dacParameters_C0.dat .. /Users/p/OnShellTest/results_L1minus/M1063_OnShellQuickTest_2016-11-21_20h05m_1479755119//000_OnShellQuickTest_p17//dacParameters_C15.dat
[20:05:32.340] <TB0> INFO: readTbmDacs: /Users/p/OnShellTest/results_L1minus/M1063_OnShellQuickTest_2016-11-21_20h05m_1479755119//000_OnShellQuickTest_p17//tbmParameters_C0a.dat .. /Users/p/OnShellTest/results_L1minus/M1063_OnShellQuickTest_2016-11-21_20h05m_1479755119//000_OnShellQuickTest_p17//tbmParameters_C1b.dat
[20:05:32.340] <TB0> INFO: readMaskFile: /Users/p/OnShellTest/results_L1minus/M1063_OnShellQuickTest_2016-11-21_20h05m_1479755119//000_OnShellQuickTest_p17//defaultMaskFile.dat
[20:05:32.341] <TB0> INFO: readTrimFile: /Users/p/OnShellTest/results_L1minus/M1063_OnShellQuickTest_2016-11-21_20h05m_1479755119//000_OnShellQuickTest_p17//trimParameters_C0.dat .. /Users/p/OnShellTest/results_L1minus/M1063_OnShellQuickTest_2016-11-21_20h05m_1479755119//000_OnShellQuickTest_p17//trimParameters_C15.dat
[20:05:32.387] <TB0> INFO: clk: 4
[20:05:32.387] <TB0> INFO: ctr: 4
[20:05:32.387] <TB0> INFO: sda: 19
[20:05:32.387] <TB0> INFO: tin: 9
[20:05:32.387] <TB0> INFO: level: 15
[20:05:32.387] <TB0> INFO: triggerdelay: 0
[20:05:32.388] <TB0> QUIET: Instanciating API for pxar v2.7.6+75~g44d91fa
[20:05:32.388] <TB0> INFO: Log level: INFO
[20:05:33.721] <TB0> QUIET: Connection to board DTB_WS6THK opened.
[20:05:33.725] <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:
------------------------------------------------------
[20:05:33.731] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[20:05:35.514] <TB0> INFO: DUT info:
[20:05:35.514] <TB0> INFO: The DUT currently contains the following objects:
[20:05:35.514] <TB0> INFO: 4 TBM Cores tbm10c (4 ON)
[20:05:35.515] <TB0> INFO: TBM Core alpha (0): 7 registers set
[20:05:35.515] <TB0> INFO: TBM Core beta (1): 7 registers set
[20:05:35.515] <TB0> INFO: TBM Core alpha (2): 7 registers set
[20:05:35.515] <TB0> INFO: TBM Core beta (3): 7 registers set
[20:05:35.515] <TB0> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[20:05:35.515] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.515] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[20:05:35.920] <TB0> INFO: enter 'restricted' command line mode
[20:05:35.920] <TB0> INFO: enter test to run
[20:05:35.920] <TB0> INFO: test: OnShellQuickTest no parameter change
[20:05:35.920] <TB0> INFO: running: onshellquicktest
[20:05:35.934] <TB0> INFO: ######################################################################
[20:05:35.934] <TB0> INFO: PixTestOnShellQuickTest::doTest()
[20:05:35.934] <TB0> INFO: ######################################################################
[20:05:35.935] <TB0> INFO: ----------------------------------------------------------------------
[20:05:35.935] <TB0> INFO: PixTestOnShellQuickTest::signalTest()
[20:05:35.935] <TB0> INFO: ----------------------------------------------------------------------
[20:05:35.935] <TB0> INFO: PixTestCmd::init()
[20:05:35.942] <TB0> WARNING: Pattern generator does not contain a trigger signal. No data is expected from the DUT!
[20:05:37.490] <TB0> WARNING: Pattern generator does not contain a trigger signal. No data is expected from the DUT!
[20:05:37.891] <TB0> WARNING: Pattern generator does not contain a trigger signal. No data is expected from the DUT!
[20:05:38.343] <TB0> INFO: clk low = -218.0 mV high= 204.0 mV amplitude = 422.0 mVpp (differential)
[20:05:38.343] <TB0> INFO: ctr low = -240.0 mV high= 234.0 mV amplitude = 474.0 mVpp (differential)
[20:05:38.343] <TB0> INFO: sda low = -245.0 mV high= 227.0 mV amplitude = 472.0 mVpp (differential)
[20:05:38.343] <TB0> INFO: rda low = -61.0 mV high= -58.0 mV amplitude = 3.0 mVpp (differential)
[20:05:38.343] <TB0> INFO: sdata1 low = -94.0 mV high= 81.0 mV amplitude = 175.0 mVpp (differential)
[20:05:38.343] <TB0> INFO: sdata2 low = -104.0 mV high= 86.0 mV amplitude = 190.0 mVpp (differential)
[20:05:40.713] <TB0> INFO: powercycled
[20:05:40.715] <TB0> INFO: ----------------------------------------------------------------------
[20:05:40.715] <TB0> INFO: PixTestOnShellQuickTest::programROC()
[20:05:40.715] <TB0> INFO: ----------------------------------------------------------------------
[20:05:58.856] <TB0> INFO: PixTestOnShellQuickTest::programROC() done: ROCs are all programmable
[20:05:58.857] <TB0> INFO: IA differences per ROC: 21.0 21.8 23.4 25.0 20.1 25.8 20.1 19.3 21.0 26.6 21.8 25.8 25.8 23.4 22.6 23.4
[20:05:58.990] <TB0> INFO: ----------------------------------------------------------------------
[20:05:58.990] <TB0> INFO: PixTestOnShellQuickTest::setVana() target Ia = 24 mA/ROC
[20:05:58.990] <TB0> INFO: ----------------------------------------------------------------------
[20:06:06.967] <TB0> INFO: PixTestOnShellQuickTest::setVana() done, Module Ia 383.5 mA = 23.9688 mA/ROC
[20:06:06.967] <TB0> INFO: i(loss) [mA/ROC]: 19.3 18.5 18.5 19.3 18.5 18.5 18.5 19.3 19.3 19.3 19.3 19.3 19.3 19.3 18.5 19.3
[20:06:07.004] <TB0> INFO: ----------------------------------------------------------------------
[20:06:07.004] <TB0> INFO: PixTestOnShellQuickTest::findTiming()
[20:06:07.004] <TB0> INFO: ----------------------------------------------------------------------
[20:06:07.005] <TB0> INFO: Testing Timing: Attempt #1
[20:06:08.806] <TB0> INFO: NErrors = 0
[20:06:08.806] <TB0> INFO: NEvents = 10000
[20:06:08.835] <TB0> INFO: Timings are already good, no scan needed!
[20:06:09.060] <TB0> INFO: ----------------------------------------------------------------------
[20:06:09.061] <TB0> INFO: PixTestOnShellQuickTest::findWorkingPixel()
[20:06:09.061] <TB0> INFO: ----------------------------------------------------------------------
[20:06:09.169] <TB0> INFO: Expecting 231680 events.
[20:06:19.216] <TB0> INFO: 231680 events read in total (9420ms).
[20:06:19.249] <TB0> INFO: Test took 10176ms.
[20:06:19.552] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[20:06:19.669] <TB0> INFO: ----------------------------------------------------------------------
[20:06:19.669] <TB0> INFO: PixTestOnShellQuickTest::setVthrCompCalDel()
[20:06:19.669] <TB0> INFO: ----------------------------------------------------------------------
[20:06:19.678] <TB0> INFO: /1
[20:06:19.686] <TB0> INFO: /2
[20:06:19.783] <TB0> INFO: Expecting 463360 events.
[20:06:31.698] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[20:06:31.701] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[20:06:31.701] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 4 Event ID mismatch: local ID (230) != TBM ID (231)
[20:06:31.701] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 5 Event ID mismatch: local ID (230) != TBM ID (231)
[20:06:31.701] <TB0> WARNING: Channel 4 ROC 0: Readback start marker after 15 readouts!
[20:06:31.701] <TB0> WARNING: Channel 4 ROC 1: Readback start marker after 15 readouts!
[20:06:31.701] <TB0> WARNING: Channel 5 ROC 0: Readback start marker after 15 readouts!
[20:06:31.701] <TB0> WARNING: Channel 5 ROC 1: Readback start marker after 15 readouts!
[20:06:32.516] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[20:06:32.516] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[20:06:32.516] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 4 Event ID mismatch: local ID (222) != TBM ID (223)
[20:06:32.516] <TB0> WARNING: Channel 4 ROC 0: Readback start marker after 15 readouts!
[20:06:32.516] <TB0> WARNING: Channel 4 ROC 1: Readback start marker after 15 readouts!
[20:06:32.516] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 5 Event ID mismatch: local ID (222) != TBM ID (223)
[20:06:32.516] <TB0> WARNING: Channel 5 ROC 0: Readback start marker after 15 readouts!
[20:06:32.516] <TB0> WARNING: Channel 5 ROC 1: Readback start marker after 15 readouts!
[20:06:35.791] <TB0> INFO: 463360 events read in total (15379ms).
[20:06:35.818] <TB0> INFO: Test took 16127ms.
[20:06:35.854] <TB0> INFO: /3
[20:06:36.251] <TB0> INFO: PixTestOnShellQuickTest::setVthrCompCalDel() done
[20:06:36.251] <TB0> INFO: CalDel: 84 75 99 98 90 81 93 87 89 97 76 81 87 87 95 98
[20:06:36.251] <TB0> INFO: VthrComp: 51 53 51 52 53 51 51 51 51 51 54 52 51 51 54 52
[20:06:36.258] <TB0> INFO: ----------------------------------------------------------------------
[20:06:36.258] <TB0> INFO: PixTestOnShellQuickTest::hvQuickTest()
[20:06:36.258] <TB0> INFO: ----------------------------------------------------------------------
[20:06:37.117] <TB0> INFO: Expecting 41600 events.
[20:06:42.007] <TB0> INFO: 41600 events read in total (4262ms).
[20:06:42.009] <TB0> INFO: Test took 5334ms.
[20:06:42.674] <TB0> INFO: Expecting 41600 events.
[20:06:45.165] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[20:06:45.165] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[20:06:45.165] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 4 Event ID mismatch: local ID (73) != TBM ID (74)
[20:06:45.165] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 5 Event ID mismatch: local ID (73) != TBM ID (74)
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a44d 80c0 4780 4380 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a447 8040 4f00 e4 23ef 4700 e4 23ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a448 80b1 4f81 4f81 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a449 80c0 4f01 4f81 e4 25ef f400 d400
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a44a 8000 4f00 e4 25ef 4f00 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a44b 8040 4700 e4 25ef 4700 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a44c 80b1 4781 4781 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a54d 80c0 4780 e4 25ef 4380 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a547 8040 4f00 e4 23ef 4700 e4 23ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a548 80b1 4f81 e4 25ef 4f81 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a549 80c0 4f01 6e4 25ef 4f81 e4 25ef f400 d400
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a54a 8000 4f00 e4 25ef 4f00 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a54b 8040 4700 e4 25ef 4700 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a54c 80b1 4781 4781 e4 25ef e022 c000
[20:06:45.166] <TB0> WARNING: Channel 4 ROC 0: Readback start marker after 15 readouts!
[20:06:45.166] <TB0> WARNING: Channel 4 ROC 1: Readback start marker after 15 readouts!
[20:06:45.166] <TB0> WARNING: Channel 5 ROC 0: Readback start marker after 15 readouts!
[20:06:45.166] <TB0> WARNING: Channel 5 ROC 1: Readback start marker after 15 readouts!
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a452 8000 4380 e4 27ef 4780 e4 27ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a44c 80b1 4781 4781 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a44d 80c0 4780 4380 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a44e 8000 4380 4380 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a44f 8040 4782 4782 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a450 80b1 4381 4381 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a451 80c0 4781 4781 e4 25ef e022 c000
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[20:06:45.166] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a552 8000 4381 e4 27ef 4781 e4 27ef e022 c000
[20:06:45.167] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a54c 80b1 4781 4781 e4 25ef e022 c000
[20:06:45.167] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a54d 80c0 4780 e4 25ef 4380 e4 25ef e022 c000
[20:06:45.167] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a54e 8000 4380 e4 25ef 4780 e4 25ef e022 c000
[20:06:45.167] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a54f 8040 4782 e4 25ef 4782 e4 25ef e022 c000
[20:06:45.167] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a550 80b1 4381 e4 25ef 4301 e4 25ef e022 c000
[20:06:45.167] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a551 80c0 4781 e4 25ef 4381 e4 25ef e022 c000
[20:06:47.764] <TB0> INFO: 41600 events read in total (4462ms).
[20:06:47.768] <TB0> INFO: Test took 5520ms.
[20:06:48.193] <TB0> INFO: Inefficient pixels per ROC: 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[20:06:48.193] <TB0> INFO: Delta Pixel Alive with HV per ROC: 3845 839 187 1033 1927 3023 680 1896 511 1792 960 1440 1306 338 686 1320
[20:06:48.195] <TB0> INFO: Database: Dead Pixels
[20:06:48.195] <TB0> INFO: Dead pixels per ROC: 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[20:06:48.195] <TB0> INFO: Quicktest: Dead Pixels
[20:06:48.195] <TB0> INFO: Dead pixels per ROC: 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[20:06:48.302] <TB0> INFO: ----------------------------------------------------------------------
[20:06:48.302] <TB0> INFO: PixTestOnShellQuickTest::bbQuickTest()
[20:06:48.302] <TB0> INFO: ----------------------------------------------------------------------
[20:06:48.302] <TB0> INFO: using Ntrig = 10, Vthrcomp = 105
[20:06:48.907] <TB0> INFO: Expecting 41600 events.
[20:06:54.143] <TB0> INFO: 41600 events read in total (4608ms).
[20:06:54.149] <TB0> INFO: Test took 5835ms.
[20:06:54.176] <TB0> INFO: using Vana from file:
[20:06:54.176] <TB0> INFO: Vana:69 78 78 70 78 65 78 78 74 60 74 69 69 74 78 70
[20:06:54.182] <TB0> INFO: using Vthrcomps from file:
[20:06:54.182] <TB0> INFO: Vthrcomp:115 92 96 103 97 107 106 93 95 103 112 111 114 93 117 113
[20:06:54.724] <TB0> INFO: Expecting 41600 events.
[20:07:00.235] <TB0> INFO: 41600 events read in total (4880ms).
[20:07:00.239] <TB0> INFO: Test took 6051ms.
[20:07:00.487] <TB0> INFO: results from database:
[20:07:00.487] <TB0> INFO: Dead bumps per ROC: 0 0 0 0 0 1 0 0 2 0 0 0 0 1 0 0
[20:07:00.487] <TB0> INFO: quicktest:
[20:07:00.487] <TB0> INFO: Dead bumps per ROC: 1 0 0 0 0 1 0 0 1 1 0 0 0 1 0 0
[20:07:00.593] <TB0> INFO: PixTestOnShellQuickTest::doTest() done, duration: 84 seconds
[20:07:00.707] <TB0> INFO: enter test to run
[20:07:00.707] <TB0> INFO: test: no parameter change
[20:07:00.725] <TB0> QUIET: Connection to board 81 closed.
[20:07:00.807] <TB0> INFO: pXar: this is the end, my friend