[15:44:15.424] <TB3> INFO: *** Welcome to pxar ***
[15:44:15.424] <TB3> INFO: *** Today: 2015/09/04
[15:44:15.424] <TB3> INFO: readRocDacs: /usr/local/data/M2102_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2102_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//dacParameters35_C15.dat
[15:44:15.425] <TB3> INFO: readTbmDacs: /usr/local/data/M2102_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2102_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//tbmParameters_C0b.dat
[15:44:15.425] <TB3> INFO: readMaskFile: /usr/local/data/M2102_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//defaultMaskFile.dat
[15:44:15.425] <TB3> INFO: readTrimFile: /usr/local/data/M2102_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2102_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//trimParameters35_C15.dat
[15:44:15.499] <TB3> INFO: clk: 4
[15:44:15.499] <TB3> INFO: ctr: 4
[15:44:15.499] <TB3> INFO: sda: 19
[15:44:15.499] <TB3> INFO: tin: 9
[15:44:15.499] <TB3> INFO: level: 15
[15:44:15.499] <TB3> INFO: triggerdelay: 0
[15:44:15.499] <TB3> QUIET: Instanciating API for pxar prod-10
[15:44:15.499] <TB3> INFO: Log level: INFO
[15:44:15.514] <TB3> INFO: Found DTB DTB_WWV6Z5
[15:44:15.533] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[15:44:15.537] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[15:44:15.540] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[15:44:17.060] <TB3> INFO: DUT info:
[15:44:17.060] <TB3> INFO: The DUT currently contains the following objects:
[15:44:17.060] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:44:17.060] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:44:17.060] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:44:17.060] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:44:17.060] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.060] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:17.461] <TB3> INFO: enter 'restricted' command line mode
[15:44:17.461] <TB3> INFO: enter test to run
[15:44:17.461] <TB3> INFO: test: Highrate no parameter change
[15:44:17.461] <TB3> INFO: running: highrate
[15:44:17.462] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2102_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2102_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[15:44:17.635] <TB3> INFO: ----------------------------------------------------------------------
[15:44:17.635] <TB3> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[15:44:17.635] <TB3> INFO: ----------------------------------------------------------------------
[15:44:17.641] <TB3> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 15 .. 75 (20/-1) hits flags = 384 (plus default)
[15:44:17.654] <TB3> INFO: dacScan step from 15 .. 34
[15:44:17.654] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[15:44:17.654] <TB3> INFO: run 1 of 1
[15:44:18.261] <TB3> INFO: Expecting 4160000 events.
[15:44:49.513] <TB3> INFO: 464500 events read in total (30722ms).
[15:45:20.295] <TB3> INFO: 930300 events read in total (61504ms).
[15:45:50.999] <TB3> INFO: 1396350 events read in total (92208ms).
[15:46:21.155] <TB3> INFO: 1861900 events read in total (122364ms).
[15:46:51.192] <TB3> INFO: 2327400 events read in total (152401ms).
[15:47:21.684] <TB3> INFO: 2792750 events read in total (182893ms).
[15:47:52.147] <TB3> INFO: 3257850 events read in total (213356ms).
[15:48:22.639] <TB3> INFO: 3722600 events read in total (243848ms).
[15:48:30.494] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 3 readouts!
[15:48:33.592] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 13 readouts!
[15:49:05.920] <TB3> INFO: 4160000 events read in total (287129ms).
[15:49:09.214] <TB3> INFO: Test took 291560ms.
[15:49:11.578] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:16.339] <TB3> INFO: dacScan step from 35 .. 54
[15:49:16.339] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[15:49:16.339] <TB3> INFO: run 1 of 1
[15:49:16.969] <TB3> INFO: Expecting 4160000 events.
[15:49:46.655] <TB3> INFO: 384850 events read in total (29142ms).
[15:50:15.774] <TB3> INFO: 770450 events read in total (58261ms).
[15:50:44.804] <TB3> INFO: 1154950 events read in total (87291ms).
[15:51:14.634] <TB3> INFO: 1538500 events read in total (117121ms).
[15:51:43.005] <TB3> INFO: 1920650 events read in total (145492ms).
[15:52:12.157] <TB3> INFO: 2301300 events read in total (174644ms).
[15:52:41.443] <TB3> INFO: 2678900 events read in total (203930ms).
[15:53:11.448] <TB3> INFO: 3053800 events read in total (233935ms).
[15:53:41.297] <TB3> INFO: 3425800 events read in total (263784ms).
[15:54:10.506] <TB3> INFO: 3795850 events read in total (292993ms).
[15:54:39.334] <TB3> INFO: 4160000 events read in total (321821ms).
[15:54:39.517] <TB3> INFO: Test took 323153ms.
[15:54:41.417] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:46.499] <TB3> INFO: dacScan step from 55 .. 74
[15:54:46.499] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[15:54:46.499] <TB3> INFO: run 1 of 1
[15:54:47.133] <TB3> INFO: Expecting 4160000 events.
[15:55:16.520] <TB3> INFO: 342800 events read in total (28846ms).
[15:55:44.970] <TB3> INFO: 686200 events read in total (57296ms).
[15:56:13.144] <TB3> INFO: 1029900 events read in total (85470ms).
[15:56:41.222] <TB3> INFO: 1373700 events read in total (113548ms).
[15:57:09.347] <TB3> INFO: 1717300 events read in total (141673ms).
[15:57:37.727] <TB3> INFO: 2061100 events read in total (170053ms).
[15:58:06.215] <TB3> INFO: 2404950 events read in total (198541ms).
[15:58:34.793] <TB3> INFO: 2748450 events read in total (227119ms).
[15:59:02.999] <TB3> INFO: 3092050 events read in total (255325ms).
[15:59:31.322] <TB3> INFO: 3435600 events read in total (283648ms).
[15:59:58.669] <TB3> INFO: 3779150 events read in total (310995ms).
[16:00:27.559] <TB3> INFO: 4122550 events read in total (339885ms).
[16:00:31.046] <TB3> INFO: 4160000 events read in total (343372ms).
[16:00:31.256] <TB3> INFO: Test took 344756ms.
[16:00:33.347] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:38.236] <TB3> INFO: dacScan step from 75 .. 75
[16:00:38.236] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:00:38.236] <TB3> INFO: run 1 of 1
[16:00:38.835] <TB3> INFO: Expecting 208000 events.
[16:00:57.254] <TB3> INFO: 208000 events read in total (17892ms).
[16:00:57.261] <TB3> INFO: Test took 19025ms.
[16:00:57.336] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:57.565] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:58.720] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:59.797] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:01:00.874] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:01:01.992] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:01:03.118] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:01:04.260] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:01:05.353] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:01:06.446] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:01:07.535] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:01:08.625] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:01:09.737] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:01:10.813] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:01:11.935] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:01:13.060] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:01:14.180] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:01:15.320] <TB3> INFO: PixTest:: pg_setup set to default.
[16:01:15.321] <TB3> INFO: vcal mean: 37.15 37.33 36.81 39.44 40.37 42.20 41.55 41.73 42.49 41.53 42.67 41.84 40.55 37.56 38.16 37.91
[16:01:15.321] <TB3> INFO: vcal RMS: 1.20 1.00 0.79 1.18 1.18 1.35 1.29 1.38 1.43 1.35 1.45 1.54 1.24 0.95 0.97 1.29
[16:01:15.321] <TB3> INFO: number of X-ray hits detected: 150906 158453 132595 251031 284220 375197 321556 359187 382970 360338 400355 337976 296745 159963 179963 174318
[16:01:15.321] <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:01:15.321] <TB3> INFO: X-ray hit rate [MHz/cm2]: 44.2 46.4 38.9 73.6 83.3 110.0 94.3 105.3 112.3 105.6 117.3 99.1 87.0 46.9 52.7 51.1
[16:01:15.321] <TB3> INFO: PixTestHighRate::doXNoiseMaps() done
[16:01:15.455] <TB3> INFO: enter test to run
[16:01:15.455] <TB3> INFO: test: no parameter change
[16:01:15.627] <TB3> QUIET: Connection to board 121 closed.
[16:01:15.706] <TB3> INFO: pXar: this is the end, my friend