[16:01:02.885] <TB3> INFO: *** Welcome to pxar ***
[16:01:02.885] <TB3> INFO: *** Today: 2016/08/22
[16:01:02.955] <TB3> INFO: *** Version: f5d3-dirty
[16:01:02.955] <TB3> INFO: readRocDacs: /usr/local/data/M2294_XrayQualification_2016-08-22_17h23m_1471879389//006_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2294_XrayQualification_2016-08-22_17h23m_1471879389//006_HRSCurves_100//dacParameters35_C15.dat
[16:01:02.956] <TB3> INFO: readTbmDacs: /usr/local/data/M2294_XrayQualification_2016-08-22_17h23m_1471879389//006_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2294_XrayQualification_2016-08-22_17h23m_1471879389//006_HRSCurves_100//tbmParameters_C0b.dat
[16:01:02.956] <TB3> INFO: readMaskFile: /usr/local/data/M2294_XrayQualification_2016-08-22_17h23m_1471879389//006_HRSCurves_100//defaultMaskFile.dat
[16:01:02.956] <TB3> INFO: readTrimFile: /usr/local/data/M2294_XrayQualification_2016-08-22_17h23m_1471879389//006_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2294_XrayQualification_2016-08-22_17h23m_1471879389//006_HRSCurves_100//trimParameters35_C15.dat
[16:01:03.037] <TB3> INFO: clk: 4
[16:01:03.037] <TB3> INFO: ctr: 4
[16:01:03.037] <TB3> INFO: sda: 19
[16:01:03.037] <TB3> INFO: tin: 9
[16:01:03.037] <TB3> INFO: level: 15
[16:01:03.037] <TB3> INFO: triggerdelay: 0
[16:01:03.037] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[16:01:03.037] <TB3> INFO: Log level: INFO
[16:01:03.049] <TB3> INFO: Found DTB DTB_WWV6Z5
[16:01:03.057] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[16:01:03.059] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[16:01:03.061] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[16:01:04.617] <TB3> INFO: DUT info:
[16:01:04.617] <TB3> INFO: The DUT currently contains the following objects:
[16:01:04.617] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:01:04.617] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:01:04.617] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:01:04.617] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:01:04.617] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:04.617] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:05.018] <TB3> INFO: enter 'restricted' command line mode
[16:01:05.018] <TB3> INFO: enter test to run
[16:01:05.018] <TB3> INFO: test: Highrate no parameter change
[16:01:05.018] <TB3> INFO: running: highrate
[16:01:05.018] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2294_XrayQualification_2016-08-22_17h23m_1471879389//006_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2294_XrayQualification_2016-08-22_17h23m_1471879389//006_HRSCurves_100//phCalibrationFitErr35_C15.dat
[16:01:05.166] <TB3> INFO: ----------------------------------------------------------------------
[16:01:05.166] <TB3> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[16:01:05.166] <TB3> INFO: ----------------------------------------------------------------------
[16:01:05.172] <TB3> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 15 .. 75 (20/-1) hits flags = 896 (plus default)
[16:01:05.183] <TB3> INFO: dacScan step from 15 .. 34
[16:01:05.183] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:01:05.183] <TB3> INFO: run 1 of 1
[16:01:05.666] <TB3> INFO: Expecting 4160000 events.
[16:01:39.451] <TB3> INFO: 510850 events read in total (33381ms).
[16:02:13.143] <TB3> INFO: 1022700 events read in total (67073ms).
[16:02:46.854] <TB3> INFO: 1534300 events read in total (100784ms).
[16:03:20.354] <TB3> INFO: 2045950 events read in total (134284ms).
[16:03:54.097] <TB3> INFO: 2557650 events read in total (168027ms).
[16:04:28.240] <TB3> INFO: 3068700 events read in total (202170ms).
[16:05:01.633] <TB3> INFO: 3579300 events read in total (235563ms).
[16:05:34.971] <TB3> INFO: 4089450 events read in total (268901ms).
[16:05:39.925] <TB3> INFO: 4160000 events read in total (273855ms).
[16:05:40.203] <TB3> INFO: Test took 275020ms.
[16:05:46.132] <TB3> INFO: dacScan step from 35 .. 54
[16:05:46.132] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:05:46.132] <TB3> INFO: run 1 of 1
[16:05:46.618] <TB3> INFO: Expecting 4160000 events.
[16:06:18.614] <TB3> INFO: 409200 events read in total (31593ms).
[16:06:50.667] <TB3> INFO: 818600 events read in total (63646ms).
[16:07:22.337] <TB3> INFO: 1227150 events read in total (95316ms).
[16:07:53.762] <TB3> INFO: 1635300 events read in total (126741ms).
[16:08:25.298] <TB3> INFO: 2041650 events read in total (158277ms).
[16:08:57.019] <TB3> INFO: 2445850 events read in total (189998ms).
[16:09:25.982] <TB3> WARNING: Detected DESER400 trailer error bits: "IDLE DATA"
[16:09:25.982] <TB3> ERROR: <datapipe.cc/CheckEventID:L469> Channel 3 Event ID mismatch: local ID (119) != TBM ID (120)
[16:09:25.982] <TB3> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[16:09:25.982] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a37b 8041 4070 54d 2a65 4070 54d 2a2f 4060 22 284d 54d 2a45 4070 520 202a 505 262f 54d 2a45 fff 2fff fff 2fff e002 c000
[16:09:25.982] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a375 80c1 4070 1d 2240 29c 202f 455 2247 501 2043 54d 2a4d 4070 114 2024 101 264f 54d 2a45 4060 321 2646 54d 2a61 608 2847 4070 54d 2a4d 603 2861 e002 c000
[16:09:25.982] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a376 8001 4070 54d 2a4f 552 224c 4070 54d 2a41 4070 54d 2a48 4070 212 242f 4c9 2a44 54d 2a40 752 2a4d fff 2fff fff 2fff e002 c000
[16:09:25.982] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a377 8041 4070 54d 2a4f 4060 9b 2065 21c 202b 21b 282a 567 2fc6 3fc 2180 221 29ae d42 2bfc 2a6 2d14 b4c 2b20 fff 2fff fff 2fff fff 2fff fff 2fff bff 2010 7f 2fff fff 2fff f2ff d2ff
[16:09:25.982] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a378 80b1 4070 24c 264c 4070 54d 2a45 4060 290 2a26 54d 2a62 4070 54d 2a41 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000
[16:09:25.982] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a379 80c1 4070 9 202f 48b 284d 54d 2a67 4060 54d 2a49 4070 41c 262c 54d 2a4f 4070 54d 2a41 e002 c000
[16:09:25.982] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a37a 8001 4061 61 2a4c 54d 2a6a 4061 118 2a2e 54d 2a4a 4071 54d 2a49 4071 80 244c 154 2041 54d 2a44 64a 244d fff 2fff e002 c000
[16:09:25.982] <TB3> WARNING: Channel 3 ROC 0: Readback start marker after 15 readouts!
[16:09:25.982] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[16:09:25.982] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[16:09:25.983] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[16:09:25.983] <TB3> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[16:09:25.983] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a382 8001 4070 81 244e 54d 2a41 4070 54d 2a45 71a 202f 4071 2c8 262f 54d 2a49 4061 54d 2a46 600 2447 fff 2fff fff 2fff e002 c000
[16:09:25.983] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a37c 80b1 4061 54d 2a4c 699 264d 844 262e 4061 54d 2a2d 758 2245 4071 dc 240d 54d 2a46 4061 2d1 264f 54d 2a49 74c 2441 e002 c000
[16:09:25.983] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a37d 80c1 4071 290 2244 54d 2a61 4071 54d 2a45 4071 54d 2a4f 4071 54d 2a49 615 2a4f fff 2fff fff 2fff e002 c000
[16:09:25.983] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a37e 8001 4061 312 2a40 54d 2a63 559 2665 4071 54d 2a45 4071 208 2441 24d 204c 54d 2a69 4061 50c 2644 54d 2a45 e002 c000
[16:09:25.983] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a37f 8041 4072 4c2 202e 54d 2a60 4072 da 264b 54d 2a45 4062 54d 2a41 4072 54d 2a41 fff 2fff fff 2fff fff 2fff fff 2fff e002 c000
[16:09:25.983] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a380 80b1 4071 54d 2a4e 4071 2c4 242b 54d 2a49 4061 2c8 2443 320 2247 54d 2a44 4071 b 2447 54d 2a49 fff 2fff e002 c000
[16:09:25.983] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a381 80c1 4071 54d 2a68 68d 2a4d 4071 348 2645 54d 2a44 4071 54d 2a4a 4061 54d 2a4d fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000
[16:09:28.543] <TB3> INFO: 2846600 events read in total (221522ms).
[16:10:00.592] <TB3> INFO: 3244700 events read in total (253571ms).
[16:10:32.210] <TB3> INFO: 3640150 events read in total (285189ms).
[16:11:03.838] <TB3> INFO: 4034750 events read in total (316817ms).
[16:11:13.909] <TB3> INFO: 4160000 events read in total (326888ms).
[16:11:14.057] <TB3> INFO: Test took 327924ms.
[16:11:20.218] <TB3> INFO: dacScan step from 55 .. 74
[16:11:20.218] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:11:20.218] <TB3> INFO: run 1 of 1
[16:11:20.705] <TB3> INFO: Expecting 4160000 events.
[16:11:51.754] <TB3> INFO: 367400 events read in total (30647ms).
[16:12:22.617] <TB3> INFO: 735450 events read in total (61510ms).
[16:12:53.487] <TB3> INFO: 1103500 events read in total (92380ms).
[16:13:24.250] <TB3> INFO: 1471600 events read in total (123143ms).
[16:13:55.006] <TB3> INFO: 1839800 events read in total (153899ms).
[16:14:25.801] <TB3> INFO: 2207900 events read in total (184694ms).
[16:14:56.590] <TB3> INFO: 2576000 events read in total (215483ms).
[16:15:27.283] <TB3> INFO: 2943950 events read in total (246176ms).
[16:15:57.913] <TB3> INFO: 3311950 events read in total (276806ms).
[16:16:28.790] <TB3> INFO: 3680250 events read in total (307683ms).
[16:16:59.080] <TB3> INFO: 4048050 events read in total (337973ms).
[16:17:09.009] <TB3> INFO: 4160000 events read in total (347902ms).
[16:17:09.168] <TB3> INFO: Test took 348950ms.
[16:17:14.912] <TB3> INFO: dacScan step from 75 .. 75
[16:17:14.912] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:17:14.912] <TB3> INFO: run 1 of 1
[16:17:15.398] <TB3> INFO: Expecting 208000 events.
[16:17:34.151] <TB3> INFO: 208000 events read in total (18350ms).
[16:17:34.157] <TB3> INFO: Test took 19244ms.
[16:17:34.469] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:36.358] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:37.380] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:38.407] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:39.464] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:40.521] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:41.601] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:42.663] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:43.767] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:44.876] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:46.044] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:47.164] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:48.256] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:49.400] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:50.461] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:51.519] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:17:52.577] <TB3> INFO: PixTest:: pg_setup set to default.
[16:17:52.579] <TB3> INFO: vcal mean: 38.06 38.24 37.53 39.38 40.58 40.91 41.48 39.52 40.67 40.83 42.52 40.49 39.46 37.95 38.50 38.40
[16:17:52.579] <TB3> INFO: vcal RMS: 1.02 1.02 0.88 1.09 1.34 1.24 1.38 1.64 1.47 1.23 1.39 1.31 1.29 1.04 0.95 0.98
[16:17:52.579] <TB3> INFO: number of X-ray hits detected: 157651 163666 141624 224670 265696 306951 303622 257370 303141 303942 372613 265615 252995 152463 174749 178566
[16:17:52.579] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:17:52.579] <TB3> INFO: X-ray hit rate [MHz/cm2]: 46.2 48.0 41.5 65.9 77.9 90.0 89.0 75.4 88.9 89.1 109.2 77.9 74.2 44.7 51.2 52.3
[16:17:52.579] <TB3> INFO: PixTestHighRate::doXNoiseMaps() done
[16:17:52.808] <TB3> INFO: enter test to run
[16:17:52.809] <TB3> INFO: test: no parameter change
[16:17:53.002] <TB3> QUIET: Connection to board 121 closed.
[16:17:53.081] <TB3> INFO: pXar: this is the end, my friend