[19:02:46.013] <TB0> INFO: *** Welcome to pxar ***
[19:02:46.013] <TB0> INFO: *** Today: 2016/11/21
[19:02:46.112] <TB0> INFO: *** Version: 3035-dirty
[19:02:46.113] <TB0> INFO: readRocDacs: /Users/p/OnShellTest/results_L1minus/M1041_OnShellQuickTest_2016-11-21_19h02m_1479751353//000_OnShellQuickTest_p17//dacParameters_C0.dat .. /Users/p/OnShellTest/results_L1minus/M1041_OnShellQuickTest_2016-11-21_19h02m_1479751353//000_OnShellQuickTest_p17//dacParameters_C15.dat
[19:02:46.115] <TB0> INFO: readTbmDacs: /Users/p/OnShellTest/results_L1minus/M1041_OnShellQuickTest_2016-11-21_19h02m_1479751353//000_OnShellQuickTest_p17//tbmParameters_C0a.dat .. /Users/p/OnShellTest/results_L1minus/M1041_OnShellQuickTest_2016-11-21_19h02m_1479751353//000_OnShellQuickTest_p17//tbmParameters_C1b.dat
[19:02:46.116] <TB0> INFO: readMaskFile: /Users/p/OnShellTest/results_L1minus/M1041_OnShellQuickTest_2016-11-21_19h02m_1479751353//000_OnShellQuickTest_p17//defaultMaskFile.dat
[19:02:46.116] <TB0> INFO: readTrimFile: /Users/p/OnShellTest/results_L1minus/M1041_OnShellQuickTest_2016-11-21_19h02m_1479751353//000_OnShellQuickTest_p17//trimParameters_C0.dat .. /Users/p/OnShellTest/results_L1minus/M1041_OnShellQuickTest_2016-11-21_19h02m_1479751353//000_OnShellQuickTest_p17//trimParameters_C15.dat
[19:02:46.171] <TB0> INFO: clk: 4
[19:02:46.171] <TB0> INFO: ctr: 4
[19:02:46.171] <TB0> INFO: sda: 19
[19:02:46.171] <TB0> INFO: tin: 9
[19:02:46.171] <TB0> INFO: level: 15
[19:02:46.171] <TB0> INFO: triggerdelay: 0
[19:02:46.172] <TB0> QUIET: Instanciating API for pxar v2.7.6+75~g44d91fa
[19:02:46.173] <TB0> INFO: Log level: INFO
[19:02:47.497] <TB0> QUIET: Connection to board DTB_WRMN1X opened.
[19:02:47.499] <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:
------------------------------------------------------
[19:02:47.503] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[19:02:49.266] <TB0> INFO: DUT info:
[19:02:49.266] <TB0> INFO: The DUT currently contains the following objects:
[19:02:49.266] <TB0> INFO: 4 TBM Cores tbm10c (4 ON)
[19:02:49.266] <TB0> INFO: TBM Core alpha (0): 7 registers set
[19:02:49.266] <TB0> INFO: TBM Core beta (1): 7 registers set
[19:02:49.266] <TB0> INFO: TBM Core alpha (2): 7 registers set
[19:02:49.266] <TB0> INFO: TBM Core beta (3): 7 registers set
[19:02:49.266] <TB0> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[19:02:49.266] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.266] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.266] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.266] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.266] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.266] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.266] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.266] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.266] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.266] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.266] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.266] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.266] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.266] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.266] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.267] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[19:02:49.670] <TB0> INFO: enter 'restricted' command line mode
[19:02:49.670] <TB0> INFO: enter test to run
[19:02:49.670] <TB0> INFO: test: OnShellQuickTest no parameter change
[19:02:49.670] <TB0> INFO: running: onshellquicktest
[19:02:49.686] <TB0> INFO: ######################################################################
[19:02:49.687] <TB0> INFO: PixTestOnShellQuickTest::doTest()
[19:02:49.687] <TB0> INFO: ######################################################################
[19:02:49.687] <TB0> INFO: ----------------------------------------------------------------------
[19:02:49.687] <TB0> INFO: PixTestOnShellQuickTest::signalTest()
[19:02:49.687] <TB0> INFO: ----------------------------------------------------------------------
[19:02:49.687] <TB0> INFO: PixTestCmd::init()
[19:02:49.688] <TB0> WARNING: Pattern generator does not contain a trigger signal. No data is expected from the DUT!
[19:02:51.288] <TB0> WARNING: Pattern generator does not contain a trigger signal. No data is expected from the DUT!
[19:02:51.691] <TB0> WARNING: Pattern generator does not contain a trigger signal. No data is expected from the DUT!
[19:02:52.156] <TB0> INFO: clk low = -204.0 mV high= 194.0 mV amplitude = 398.0 mVpp (differential)
[19:02:52.156] <TB0> INFO: ctr low = -229.0 mV high= 244.0 mV amplitude = 473.0 mVpp (differential)
[19:02:52.156] <TB0> INFO: sda low = -234.0 mV high= 225.0 mV amplitude = 459.0 mVpp (differential)
[19:02:52.156] <TB0> INFO: rda low = -3.0 mV high= 2.0 mV amplitude = 5.0 mVpp (differential)
[19:02:52.156] <TB0> INFO: sdata1 low = -118.0 mV high= 108.0 mV amplitude = 226.0 mVpp (differential)
[19:02:52.156] <TB0> INFO: sdata2 low = -111.0 mV high= 90.0 mV amplitude = 201.0 mVpp (differential)
[19:02:54.538] <TB0> INFO: powercycled
[19:02:54.540] <TB0> INFO: ----------------------------------------------------------------------
[19:02:54.540] <TB0> INFO: PixTestOnShellQuickTest::programROC()
[19:02:54.540] <TB0> INFO: ----------------------------------------------------------------------
[19:03:12.672] <TB0> INFO: PixTestOnShellQuickTest::programROC() done: ROCs are all programmable
[19:03:12.672] <TB0> INFO: IA differences per ROC: 19.3 24.1 24.9 24.1 20.9 20.1 23.3 19.3 20.1 20.1 17.7 21.7 24.1 20.9 23.3 19.3
[19:03:12.765] <TB0> INFO: ----------------------------------------------------------------------
[19:03:12.765] <TB0> INFO: PixTestOnShellQuickTest::setVana() target Ia = 24 mA/ROC
[19:03:12.765] <TB0> INFO: ----------------------------------------------------------------------
[19:03:34.326] <TB0> INFO: PixTestOnShellQuickTest::setVana() done, Module Ia 383.5 mA = 23.9688 mA/ROC
[19:03:34.326] <TB0> INFO: i(loss) [mA/ROC]: 20.1 20.1 19.3 20.1 20.1 20.1 19.3 19.3 20.9 19.3 19.3 19.3 19.3 20.1 19.3 19.3
[19:03:34.464] <TB0> INFO: ----------------------------------------------------------------------
[19:03:34.464] <TB0> INFO: PixTestOnShellQuickTest::findTiming()
[19:03:34.464] <TB0> INFO: ----------------------------------------------------------------------
[19:03:34.464] <TB0> INFO: Testing Timing: Attempt #1
[19:03:36.284] <TB0> INFO: NErrors = 0
[19:03:36.284] <TB0> INFO: NEvents = 10000
[19:03:36.309] <TB0> INFO: Timings are already good, no scan needed!
[19:03:36.536] <TB0> INFO: ----------------------------------------------------------------------
[19:03:36.536] <TB0> INFO: PixTestOnShellQuickTest::findWorkingPixel()
[19:03:36.536] <TB0> INFO: ----------------------------------------------------------------------
[19:03:36.630] <TB0> INFO: Expecting 231680 events.
[19:03:44.353] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[19:03:44.354] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[19:03:44.354] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 4 Event ID mismatch: local ID (50) != TBM ID (51)
[19:03:44.354] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 5 Event ID mismatch: local ID (50) != TBM ID (51)
[19:03:44.355] <TB0> WARNING: Channel 4 ROC 1: Readback start marker after 15 readouts!
[19:03:44.355] <TB0> WARNING: Channel 4 ROC 0: Readback start marker after 15 readouts!
[19:03:44.355] <TB0> WARNING: Channel 5 ROC 0: Readback start marker after 15 readouts!
[19:03:44.355] <TB0> WARNING: Channel 5 ROC 1: Readback start marker after 15 readouts!
[19:03:44.368] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[19:03:44.368] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[19:03:44.368] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 4 Event ID mismatch: local ID (45) != TBM ID (46)
[19:03:44.368] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 5 Event ID mismatch: local ID (45) != TBM ID (46)
[19:03:44.368] <TB0> WARNING: Channel 4 ROC 0: Readback start marker after 15 readouts!
[19:03:44.368] <TB0> WARNING: Channel 5 ROC 0: Readback start marker after 15 readouts!
[19:03:44.368] <TB0> WARNING: Channel 5 ROC 1: Readback start marker after 15 readouts!
[19:03:44.368] <TB0> WARNING: Channel 4 ROC 1: Readback start marker after 15 readouts!
[19:03:44.427] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[19:03:44.427] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[19:03:44.427] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 4 Event ID mismatch: local ID (108) != TBM ID (109)
[19:03:44.427] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 5 Event ID mismatch: local ID (108) != TBM ID (109)
[19:03:44.427] <TB0> WARNING: Channel 4 ROC 0: Readback start marker after 15 readouts!
[19:03:44.427] <TB0> WARNING: Channel 5 ROC 0: Readback start marker after 15 readouts!
[19:03:44.427] <TB0> WARNING: Channel 5 ROC 1: Readback start marker after 15 readouts!
[19:03:44.427] <TB0> WARNING: Channel 4 ROC 1: Readback start marker after 31 readouts!
[19:03:44.572] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[19:03:44.572] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[19:03:44.572] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 4 Event ID mismatch: local ID (31) != TBM ID (32)
[19:03:44.572] <TB0> WARNING: Channel 4 ROC 0: Readback start marker after 15 readouts!
[19:03:44.572] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 5 Event ID mismatch: local ID (31) != TBM ID (32)
[19:03:44.572] <TB0> WARNING: Channel 5 ROC 0: Readback start marker after 15 readouts!
[19:03:44.572] <TB0> WARNING: Channel 5 ROC 1: Readback start marker after 15 readouts!
[19:03:44.572] <TB0> WARNING: Channel 4 ROC 1: Readback start marker after 15 readouts!
[19:03:44.601] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[19:03:44.601] <TB0> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[19:03:44.601] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 4 Event ID mismatch: local ID (211) != TBM ID (212)
[19:03:44.601] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 5 Event ID mismatch: local ID (211) != TBM ID (212)
[19:03:44.601] <TB0> WARNING: Channel 4 ROC 1: Readback start marker after 15 readouts!
[19:03:44.601] <TB0> WARNING: Channel 4 ROC 0: Readback start marker after 15 readouts!
[19:03:44.601] <TB0> WARNING: Channel 5 ROC 0: Readback start marker after 15 readouts!
[19:03:44.601] <TB0> WARNING: Channel 5 ROC 1: Readback start marker after 15 readouts!
[19:03:46.688] <TB0> INFO: 231680 events read in total (9430ms).
[19:03:46.709] <TB0> INFO: Test took 10167ms.
[19:03:47.002] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[19:03:47.154] <TB0> INFO: ----------------------------------------------------------------------
[19:03:47.154] <TB0> INFO: PixTestOnShellQuickTest::setVthrCompCalDel()
[19:03:47.154] <TB0> INFO: ----------------------------------------------------------------------
[19:03:47.166] <TB0> INFO: /1
[19:03:47.172] <TB0> INFO: /2
[19:03:47.264] <TB0> INFO: Expecting 463360 events.
[19:04:02.086] <TB0> INFO: 463360 events read in total (14194ms).
[19:04:02.104] <TB0> INFO: Test took 14931ms.
[19:04:02.137] <TB0> INFO: /3
[19:04:02.539] <TB0> INFO: PixTestOnShellQuickTest::setVthrCompCalDel() done
[19:04:02.539] <TB0> INFO: CalDel: 77 97 73 81 81 82 95 81 85 85 89 88 112 92 93 91
[19:04:02.539] <TB0> INFO: VthrComp: 54 54 61 51 51 53 51 51 54 55 51 51 52 51 52 51
[19:04:02.542] <TB0> INFO: ----------------------------------------------------------------------
[19:04:02.542] <TB0> INFO: PixTestOnShellQuickTest::hvQuickTest()
[19:04:02.542] <TB0> INFO: ----------------------------------------------------------------------
[19:04:03.373] <TB0> INFO: Expecting 41600 events.
[19:04:08.040] <TB0> INFO: 41600 events read in total (4034ms).
[19:04:08.044] <TB0> INFO: Test took 5080ms.
[19:04:08.720] <TB0> INFO: Expecting 41600 events.
[19:04:13.431] <TB0> INFO: 41600 events read in total (4083ms).
[19:04:13.434] <TB0> INFO: Test took 5150ms.
[19:04:13.855] <TB0> INFO: Inefficient pixels per ROC: 0 0 0 0 0 0 0 1 0 0 0 0 0 4 0 0
[19:04:13.855] <TB0> INFO: Delta Pixel Alive with HV per ROC: 1497 1379 3604 2633 2558 2504 374 479 989 2354 2720 1803 3558 1172 1208 885
[19:04:13.856] <TB0> INFO: Database: Dead Pixels
[19:04:13.856] <TB0> INFO: Dead pixels per ROC: 0 0 0 0 0 0 0 1 0 0 0 0 0 4 0 0
[19:04:13.856] <TB0> INFO: Quicktest: Dead Pixels
[19:04:13.856] <TB0> INFO: Dead pixels per ROC: 0 0 0 0 0 0 0 1 0 0 0 0 0 4 0 0
[19:04:13.894] <TB0> INFO: ----------------------------------------------------------------------
[19:04:13.894] <TB0> INFO: PixTestOnShellQuickTest::bbQuickTest()
[19:04:13.894] <TB0> INFO: ----------------------------------------------------------------------
[19:04:13.894] <TB0> INFO: using Ntrig = 10, Vthrcomp = 105
[19:04:14.328] <TB0> INFO: Expecting 41600 events.
[19:04:19.132] <TB0> INFO: 41600 events read in total (4177ms).
[19:04:19.137] <TB0> INFO: Test took 5240ms.
[19:04:19.162] <TB0> INFO: using Vana from file:
[19:04:19.162] <TB0> INFO: Vana:74 76 76 76 81 96 68 81 86 90 95 85 81 85 84 86
[19:04:19.166] <TB0> INFO: using Vthrcomps from file:
[19:04:19.166] <TB0> INFO: Vthrcomp:115 126 131 115 127 117 106 97 103 108 108 118 119 94 118 94
[19:04:19.599] <TB0> INFO: Expecting 41600 events.
[19:04:24.543] <TB0> INFO: 41600 events read in total (4315ms).
[19:04:24.545] <TB0> INFO: Test took 5373ms.
[19:04:24.787] <TB0> INFO: results from database:
[19:04:24.787] <TB0> INFO: Dead bumps per ROC: 1 0 0 0 0 0 0 3 0 0 0 2 0 1 0 3
[19:04:24.787] <TB0> INFO: quicktest:
[19:04:24.787] <TB0> INFO: Dead bumps per ROC: 1 20 62 1 14 1 0 3 0 0 0 2 0 0 5 2
[19:04:24.946] <TB0> INFO: PixTestOnShellQuickTest::doTest() done, duration: 95 seconds
[19:04:25.032] <TB0> INFO: enter test to run
[19:04:25.032] <TB0> INFO: test: no parameter change
[19:04:25.042] <TB0> QUIET: Connection to board 42 closed.
[19:04:25.963] <TB0> INFO: pXar: this is the end, my friend