[12:03:11.593] INFO: *** Welcome to pxar ***
[12:03:11.594] INFO: *** Today: 2015/12/02
[12:03:12.518] INFO: *** Version: v2.6.1-2-g42ad
[12:03:12.518] INFO: readRocDacs: ../data/M2047/dacParameters35_C0.dat .. ../data/M2047/dacParameters35_C15.dat
[12:03:12.535] INFO: readTbmDacs: ../data/M2047/tbmParameters_C0a.dat .. ../data/M2047/tbmParameters_C0b.dat
[12:03:12.536] INFO: readMaskFile: ../data/M2047/defaultMaskFile.dat
[12:03:12.537] INFO: MASKED Roc 14 col/row: 13 69
[12:03:12.537] INFO: readTrimFile: ../data/M2047/trimParameters35_C0.dat .. ../data/M2047/trimParameters35_C15.dat
[12:03:12.683] INFO: masking Roc 14 col/row: 13 69
[12:03:13.175] INFO: clk: 4
[12:03:13.184] INFO: ctr: 4
[12:03:13.184] INFO: sda: 19
[12:03:13.184] INFO: tin: 9
[12:03:13.184] INFO: level: 15
[12:03:13.184] INFO: triggerdelay: 0
[12:03:13.184] QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[12:03:13.184] INFO: Log level: INFO
[12:03:13.198] INFO: Connected DTBs:
[12:03:13.198] INFO: 0: DTB_WS6UZO on USB
[12:03:13.207] INFO: BID = 83
[12:03:13.286] INFO: 1: DTB_WWXTQT on USB
[12:03:13.320] INFO: BID = 147
[12:03:13.400] INFO: 2: DTB_WXENWR on USB
[12:03:13.412] INFO: BID = 162
[12:03:13.588] INFO: 3: DTB_WWV6Z5 on USB
[12:03:13.600] INFO: BID = 121
[12:03:13.680] INFO: Please choose DTB (0-3):
[12:03:16.799] QUIET: Connection to board DTB_WWXTQT opened.
[12:03:16.807] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[12:03:16.810] INFO: RPC call hashes of host and DTB match: 398089610
[12:03:18.351] INFO: DUT info:
[12:03:18.351] INFO: The DUT currently contains the following objects:
[12:03:18.351] INFO: 2 TBM Cores tbm09c (2 ON)
[12:03:18.351] INFO: TBM Core alpha (0): 7 registers set
[12:03:18.351] INFO: TBM Core beta (1): 7 registers set
[12:03:18.351] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:03:18.351] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.351] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.351] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.351] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.351] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.351] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.352] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.352] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.352] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.352] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.352] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.352] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.352] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.352] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.352] INFO: ROC 14: 19 DACs set, Pixels: 1 masked, 0 active.
[12:03:18.352] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:18.955] INFO: PixTestCmd::init()
[12:03:18.977] INFO: readGainPedestalParameters ../data/M2047/phCalibrationFitErr35_C0.dat .. ../data/M2047/phCalibrationFitErr35_C15.dat
[12:03:19.315] INFO: readReadbackCal: ../data/M2047/readbackCal_C0.dat .. ../data/M2047/readbackCal_C15.dat
[12:03:55.815] INFO: ----------------------------------------------------------------------
[12:03:55.816] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:03:55.816] INFO: ----------------------------------------------------------------------
[12:03:55.816] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:03:55.816] INFO: edge/corner pixel THR is adjusted
[12:03:55.816] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:03:56.778] INFO: Collecting data for 1 seconds...
[12:03:57.780] INFO: Done with hot pixel readout
[12:04:05.499] INFO: PixTest:: pg_setup set to default.
[12:04:05.501] INFO: 3 hot pixels found in step 0
[12:04:06.473] INFO: Collecting data for 1 seconds...
[12:04:07.475] INFO: Done with hot pixel readout
[12:04:15.146] INFO: PixTest:: pg_setup set to default.
[12:04:15.148] INFO: 3 hot pixels found in step 1
[12:04:16.115] INFO: Collecting data for 1 seconds...
[12:04:17.117] INFO: Done with hot pixel readout
[12:04:24.836] INFO: PixTest:: pg_setup set to default.
[12:04:24.838] INFO: 3 hot pixels found in step 2
[12:04:25.806] INFO: Collecting data for 1 seconds...
[12:04:26.808] INFO: Done with hot pixel readout
[12:04:34.434] INFO: PixTest:: pg_setup set to default.
[12:04:34.437] INFO: 1 hot pixels found in step 3
[12:04:35.404] INFO: Collecting data for 1 seconds...
[12:04:36.406] INFO: Done with hot pixel readout
[12:04:44.085] INFO: PixTest:: pg_setup set to default.
[12:04:44.087] INFO: 2 hot pixels found in step 4
[12:04:45.052] INFO: Collecting data for 1 seconds...
[12:04:46.053] INFO: Done with hot pixel readout
[12:04:53.750] INFO: PixTest:: pg_setup set to default.
[12:04:53.752] INFO: 1 hot pixels found in step 5
[12:04:54.718] INFO: Collecting data for 1 seconds...
[12:04:55.720] INFO: Done with hot pixel readout
[12:05:03.407] INFO: PixTest:: pg_setup set to default.
[12:05:03.409] INFO: 1 hot pixels found in step 6
[12:05:04.376] INFO: Collecting data for 1 seconds...
[12:05:05.378] INFO: Done with hot pixel readout
[12:05:13.008] INFO: PixTest:: pg_setup set to default.
[12:05:13.010] INFO: 1 hot pixels found in step 7
[12:05:13.977] INFO: Collecting data for 1 seconds...
[12:05:14.978] INFO: Done with hot pixel readout
[12:05:22.683] INFO: PixTest:: pg_setup set to default.
[12:05:22.686] INFO: 1 hot pixels found in step 8
[12:05:23.654] INFO: Collecting data for 1 seconds...
[12:05:24.656] INFO: Done with hot pixel readout
[12:05:32.346] INFO: PixTest:: pg_setup set to default.
[12:05:32.348] INFO: 1 hot pixels found in step 9
[12:05:33.315] INFO: Collecting data for 1 seconds...
[12:05:34.317] INFO: Done with hot pixel readout
[12:05:42.015] INFO: PixTest:: pg_setup set to default.
[12:05:42.016] INFO: 1 hot pixels found in step 10
[12:05:42.980] INFO: Collecting data for 1 seconds...
[12:05:43.982] INFO: Done with hot pixel readout
[12:05:51.646] INFO: PixTest:: pg_setup set to default.
[12:05:51.647] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:05:51.648] INFO: 1 hot pixels found in step 11
[12:05:51.659] INFO: 1 hot pixels could not be trimmed and have been masked.
[12:05:51.703] INFO: PixTest::trimHotPixels() done
[12:05:51.953] INFO: write trim parameters into ../data/M2047/trimParameters35_C0.dat
[12:05:52.103] INFO: write trim parameters into ../data/M2047/trimParameters35_C1.dat
[12:05:52.140] INFO: write trim parameters into ../data/M2047/trimParameters35_C2.dat
[12:05:52.172] INFO: write trim parameters into ../data/M2047/trimParameters35_C3.dat
[12:05:52.200] INFO: write trim parameters into ../data/M2047/trimParameters35_C4.dat
[12:05:52.224] INFO: write trim parameters into ../data/M2047/trimParameters35_C5.dat
[12:05:52.255] INFO: write trim parameters into ../data/M2047/trimParameters35_C6.dat
[12:05:52.329] INFO: write trim parameters into ../data/M2047/trimParameters35_C7.dat
[12:05:52.349] INFO: write trim parameters into ../data/M2047/trimParameters35_C8.dat
[12:05:52.381] INFO: write trim parameters into ../data/M2047/trimParameters35_C9.dat
[12:05:52.411] INFO: write trim parameters into ../data/M2047/trimParameters35_C10.dat
[12:05:52.434] INFO: write trim parameters into ../data/M2047/trimParameters35_C11.dat
[12:05:52.454] INFO: write trim parameters into ../data/M2047/trimParameters35_C12.dat
[12:05:52.486] INFO: write trim parameters into ../data/M2047/trimParameters35_C13.dat
[12:05:52.521] INFO: write trim parameters into ../data/M2047/trimParameters35_C14.dat
[12:05:52.543] INFO: write trim parameters into ../data/M2047/trimParameters35_C15.dat
[12:05:52.569] INFO: write masked pixels into ../data/M2047/defaultMaskFile.dat
[12:06:20.800] INFO: ----------------------------------------------------------------------
[12:06:20.800] INFO: PixTest::trimHotPixels() running for 2 seconds with 100 kHz trigger rate
[12:06:20.800] INFO: ----------------------------------------------------------------------
[12:06:20.800] INFO: THR = 5, corresponding to ~ 6.66667 MHz/cm2
[12:06:20.800] INFO: edge/corner pixel THR is adjusted
[12:06:20.800] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:06:21.758] INFO: Collecting data for 2 seconds...
[12:06:23.762] INFO: Done with hot pixel readout
[12:06:28.398] INFO: PixTest:: pg_setup set to default.
[12:06:28.401] INFO: 2 hot pixels found in step 0
[12:06:29.444] INFO: Collecting data for 2 seconds...
[12:06:31.447] INFO: Done with hot pixel readout
[12:06:36.005] INFO: PixTest:: pg_setup set to default.
[12:06:36.008] INFO: 2 hot pixels found in step 1
[12:06:37.049] INFO: Collecting data for 2 seconds...
[12:06:39.052] INFO: Done with hot pixel readout
[12:06:43.592] INFO: PixTest:: pg_setup set to default.
[12:06:43.593] INFO: 2 hot pixels found in step 2
[12:06:44.635] INFO: Collecting data for 2 seconds...
[12:06:46.638] INFO: Done with hot pixel readout
[12:06:51.152] INFO: PixTest:: pg_setup set to default.
[12:06:51.154] INFO: 1 hot pixels found in step 3
[12:06:52.196] INFO: Collecting data for 2 seconds...
[12:06:54.199] INFO: Done with hot pixel readout
[12:06:58.640] INFO: PixTest:: pg_setup set to default.
[12:06:58.643] INFO: 1 hot pixels found in step 4
[12:06:59.684] INFO: Collecting data for 2 seconds...
[12:07:01.687] INFO: Done with hot pixel readout
[12:07:06.091] INFO: PixTest:: pg_setup set to default.
[12:07:06.093] INFO: 1 hot pixels found in step 5
[12:07:07.134] INFO: Collecting data for 2 seconds...
[12:07:09.138] INFO: Done with hot pixel readout
[12:07:13.512] INFO: PixTest:: pg_setup set to default.
[12:07:13.514] INFO: 1 hot pixels found in step 6
[12:07:14.554] INFO: Collecting data for 2 seconds...
[12:07:16.558] INFO: Done with hot pixel readout
[12:07:20.941] INFO: PixTest:: pg_setup set to default.
[12:07:20.943] INFO: 1 hot pixels found in step 7
[12:07:21.985] INFO: Collecting data for 2 seconds...
[12:07:23.988] INFO: Done with hot pixel readout
[12:07:28.370] INFO: PixTest:: pg_setup set to default.
[12:07:28.373] INFO: 0 hot pixels found in step 8
[12:07:28.374] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:07:28.459] INFO: PixTest::trimHotPixels() done
[12:07:28.480] INFO: write trim parameters into ../data/M2047/trimParameters35_C0.dat
[12:07:28.759] INFO: write trim parameters into ../data/M2047/trimParameters35_C1.dat
[12:07:28.807] INFO: write trim parameters into ../data/M2047/trimParameters35_C2.dat
[12:07:28.829] INFO: write trim parameters into ../data/M2047/trimParameters35_C3.dat
[12:07:28.855] INFO: write trim parameters into ../data/M2047/trimParameters35_C4.dat
[12:07:28.881] INFO: write trim parameters into ../data/M2047/trimParameters35_C5.dat
[12:07:28.904] INFO: write trim parameters into ../data/M2047/trimParameters35_C6.dat
[12:07:28.931] INFO: write trim parameters into ../data/M2047/trimParameters35_C7.dat
[12:07:28.966] INFO: write trim parameters into ../data/M2047/trimParameters35_C8.dat
[12:07:28.991] INFO: write trim parameters into ../data/M2047/trimParameters35_C9.dat
[12:07:29.013] INFO: write trim parameters into ../data/M2047/trimParameters35_C10.dat
[12:07:29.037] INFO: write trim parameters into ../data/M2047/trimParameters35_C11.dat
[12:07:29.061] INFO: write trim parameters into ../data/M2047/trimParameters35_C12.dat
[12:07:29.086] INFO: write trim parameters into ../data/M2047/trimParameters35_C13.dat
[12:07:29.111] INFO: write trim parameters into ../data/M2047/trimParameters35_C14.dat
[12:07:29.131] INFO: write trim parameters into ../data/M2047/trimParameters35_C15.dat
[12:07:29.154] INFO: write masked pixels into ../data/M2047/defaultMaskFile.dat
[12:08:04.955] INFO: ----------------------------------------------------------------------
[12:08:04.955] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:08:04.956] INFO: ----------------------------------------------------------------------
[12:08:04.956] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:08:04.956] INFO: edge/corner pixel THR is adjusted
[12:08:04.956] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:08:05.914] INFO: Collecting data for 1 seconds...
[12:08:06.916] INFO: Done with hot pixel readout
[12:08:14.527] INFO: PixTest:: pg_setup set to default.
[12:08:14.529] INFO: 1 hot pixels found in step 0
[12:08:15.492] INFO: Collecting data for 1 seconds...
[12:08:16.494] INFO: Done with hot pixel readout
[12:08:24.227] INFO: PixTest:: pg_setup set to default.
[12:08:24.229] INFO: 1 hot pixels found in step 1
[12:08:25.193] INFO: Collecting data for 1 seconds...
[12:08:26.195] INFO: Done with hot pixel readout
[12:08:33.915] INFO: PixTest:: pg_setup set to default.
[12:08:33.918] INFO: 1 hot pixels found in step 2
[12:08:34.884] INFO: Collecting data for 1 seconds...
[12:08:35.886] INFO: Done with hot pixel readout
[12:08:43.596] INFO: PixTest:: pg_setup set to default.
[12:08:43.598] INFO: 1 hot pixels found in step 3
[12:08:44.567] INFO: Collecting data for 1 seconds...
[12:08:45.569] INFO: Done with hot pixel readout
[12:08:53.262] INFO: PixTest:: pg_setup set to default.
[12:08:53.263] INFO: 1 hot pixels found in step 4
[12:08:54.231] INFO: Collecting data for 1 seconds...
[12:08:55.232] INFO: Done with hot pixel readout
[12:09:02.907] INFO: PixTest:: pg_setup set to default.
[12:09:02.908] INFO: 1 hot pixels found in step 5
[12:09:03.875] INFO: Collecting data for 1 seconds...
[12:09:04.877] INFO: Done with hot pixel readout
[12:09:06.565] ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[12:09:06.565] ERROR: <datapipe.cc/CheckEventID:L461> Channel 3 Event ID mismatch: local ID (213) != TBM ID (2)
[12:09:06.566] ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 3 Number of ROCs (0) != Token Chain Length (4)
[12:09:06.566] ERROR: <datapipe.cc/CheckEventID:L461> Channel 3 Event ID mismatch: local ID (3) != TBM ID (213)
[12:09:06.566] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:09:06.566] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a3d8 80b0 4788 30c 2a46 6ca 2a2c 6e2 2a6e 819 2867 4788 4788 4788 361 204b 841 2223 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[12:09:06.566] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a3d3 8040 4788 51 224c 40b 2267 684 2268 4789 cb 204a 4788 2c3 284a 358 2a4f 6db 2a86 4788 500 260f fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[12:09:06.566] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a3d4 80b0 4788 800 2848 4788 51b 206e 74a 264c 4788
[12:09:06.566] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a302 805a 66 2285 82e 234b 66f 4788 704 202f fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[12:09:06.566] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a3d5 80c1 4788 2a0 244a 753 2444 4788 4788 5 282f c 224a 24d 2a4b 4788 2e0 2645 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[12:09:06.566] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a3d6 8000 4788 208 2048 4788 112 206f 688 2247 4788 5 2848 861 2249 4788 31b 2265 75a 2064 760 2a47 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[12:09:06.566] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a3d7 8040 4788 2c8 2862 45b 2067 45b 226f 4788 34a 2449 34a 204c 4788 642 2a2d 4788 519 2649 55d 206d e000 c000
[12:09:12.872] INFO: PixTest:: pg_setup set to default.
[12:09:12.874] INFO: 1 hot pixels found in step 6
[12:09:13.843] INFO: Collecting data for 1 seconds...
[12:09:14.844] INFO: Done with hot pixel readout
[12:09:22.565] INFO: PixTest:: pg_setup set to default.
[12:09:22.566] INFO: 1 hot pixels found in step 7
[12:09:23.531] INFO: Collecting data for 1 seconds...
[12:09:24.533] INFO: Done with hot pixel readout
[12:09:32.255] INFO: PixTest:: pg_setup set to default.
[12:09:32.258] INFO: 1 hot pixels found in step 8
[12:09:33.225] INFO: Collecting data for 1 seconds...
[12:09:34.227] INFO: Done with hot pixel readout
[12:09:42.001] INFO: PixTest:: pg_setup set to default.
[12:09:42.002] INFO: 1 hot pixels found in step 9
[12:09:42.966] INFO: Collecting data for 1 seconds...
[12:09:43.968] INFO: Done with hot pixel readout
[12:09:51.688] INFO: PixTest:: pg_setup set to default.
[12:09:51.690] INFO: 1 hot pixels found in step 10
[12:09:52.656] INFO: Collecting data for 1 seconds...
[12:09:53.658] INFO: Done with hot pixel readout
[12:10:01.343] INFO: PixTest:: pg_setup set to default.
[12:10:01.344] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:01.345] INFO: 1 hot pixels found in step 11
[12:10:01.353] INFO: 1 hot pixels could not be trimmed and have been masked.
[12:10:01.390] INFO: PixTest::trimHotPixels() done
[12:10:01.406] INFO: write trim parameters into ../data/M2047/trimParameters35_C0.dat
[12:10:01.798] INFO: write trim parameters into ../data/M2047/trimParameters35_C1.dat
[12:10:02.016] INFO: write trim parameters into ../data/M2047/trimParameters35_C2.dat
[12:10:02.300] INFO: write trim parameters into ../data/M2047/trimParameters35_C3.dat
[12:10:02.426] INFO: write trim parameters into ../data/M2047/trimParameters35_C4.dat
[12:10:02.635] INFO: write trim parameters into ../data/M2047/trimParameters35_C5.dat
[12:10:02.795] INFO: write trim parameters into ../data/M2047/trimParameters35_C6.dat
[12:10:03.022] INFO: write trim parameters into ../data/M2047/trimParameters35_C7.dat
[12:10:03.206] INFO: write trim parameters into ../data/M2047/trimParameters35_C8.dat
[12:10:03.365] INFO: write trim parameters into ../data/M2047/trimParameters35_C9.dat
[12:10:03.659] INFO: write trim parameters into ../data/M2047/trimParameters35_C10.dat
[12:10:03.826] INFO: write trim parameters into ../data/M2047/trimParameters35_C11.dat
[12:10:04.002] INFO: write trim parameters into ../data/M2047/trimParameters35_C12.dat
[12:10:04.195] INFO: write trim parameters into ../data/M2047/trimParameters35_C13.dat
[12:10:04.412] INFO: write trim parameters into ../data/M2047/trimParameters35_C14.dat
[12:10:04.584] INFO: write trim parameters into ../data/M2047/trimParameters35_C15.dat
[12:10:04.626] INFO: write masked pixels into ../data/M2047/defaultMaskFile.dat
[12:10:18.597] QUIET: Connection to board 147 closed.