[15:44:05.457] <TB2> INFO: *** Welcome to pxar ***
[15:44:05.457] <TB2> INFO: *** Today: 2015/09/04
[15:44:05.457] <TB2> INFO: readRocDacs: /usr/local/data/M2101_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2101_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//dacParameters35_C15.dat
[15:44:05.458] <TB2> INFO: readTbmDacs: /usr/local/data/M2101_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2101_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//tbmParameters_C0b.dat
[15:44:05.458] <TB2> INFO: readMaskFile: /usr/local/data/M2101_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//defaultMaskFile.dat
[15:44:05.458] <TB2> INFO: readTrimFile: /usr/local/data/M2101_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2101_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//trimParameters35_C15.dat
[15:44:05.534] <TB2> INFO: clk: 4
[15:44:05.534] <TB2> INFO: ctr: 4
[15:44:05.534] <TB2> INFO: sda: 19
[15:44:05.534] <TB2> INFO: tin: 9
[15:44:05.534] <TB2> INFO: level: 15
[15:44:05.534] <TB2> INFO: triggerdelay: 0
[15:44:05.534] <TB2> QUIET: Instanciating API for pxar prod-10
[15:44:05.534] <TB2> INFO: Log level: INFO
[15:44:05.547] <TB2> INFO: Found DTB DTB_WXENWR
[15:44:05.564] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[15:44:05.568] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[15:44:05.570] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[15:44:07.113] <TB2> INFO: DUT info:
[15:44:07.113] <TB2> INFO: The DUT currently contains the following objects:
[15:44:07.113] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[15:44:07.113] <TB2> INFO: TBM Core alpha (0): 7 registers set
[15:44:07.113] <TB2> INFO: TBM Core beta (1): 7 registers set
[15:44:07.113] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:44:07.113] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.113] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.113] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.113] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.113] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.113] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.114] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.114] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.114] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.114] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.114] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.114] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.114] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.114] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.114] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.114] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:44:07.515] <TB2> INFO: enter 'restricted' command line mode
[15:44:07.515] <TB2> INFO: enter test to run
[15:44:07.515] <TB2> INFO: test: Highrate no parameter change
[15:44:07.515] <TB2> INFO: running: highrate
[15:44:07.516] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2101_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2101_XrayQualification_2015-09-04_17h08m_1441379300//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[15:44:07.713] <TB2> INFO: ----------------------------------------------------------------------
[15:44:07.713] <TB2> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[15:44:07.713] <TB2> INFO: ----------------------------------------------------------------------
[15:44:07.720] <TB2> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 15 .. 75 (20/-1) hits flags = 384 (plus default)
[15:44:07.732] <TB2> INFO: dacScan step from 15 .. 34
[15:44:07.732] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[15:44:07.732] <TB2> INFO: run 1 of 1
[15:44:08.358] <TB2> INFO: Expecting 4160000 events.
[15:44:33.744] <TB2> INFO: 416600 events read in total (24854ms).
[15:44:59.197] <TB2> INFO: 834400 events read in total (50307ms).
[15:45:24.614] <TB2> INFO: 1251800 events read in total (75724ms).
[15:45:50.382] <TB2> INFO: 1669200 events read in total (101492ms).
[15:46:15.997] <TB2> INFO: 2086350 events read in total (127107ms).
[15:46:41.376] <TB2> INFO: 2503850 events read in total (152486ms).
[15:47:06.641] <TB2> INFO: 2921100 events read in total (177751ms).
[15:47:32.332] <TB2> INFO: 3338150 events read in total (203442ms).
[15:47:57.847] <TB2> INFO: 3754800 events read in total (228957ms).
[15:48:22.812] <TB2> INFO: 4160000 events read in total (253922ms).
[15:48:23.477] <TB2> INFO: Test took 255745ms.
[15:48:56.251] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:04.293] <TB2> INFO: dacScan step from 35 .. 54
[15:49:04.293] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[15:49:04.461] <TB2> INFO: run 1 of 1
[15:49:05.792] <TB2> INFO: Expecting 4160000 events.
[15:49:29.721] <TB2> INFO: 356800 events read in total (23304ms).
[15:49:54.982] <TB2> INFO: 714450 events read in total (48565ms).
[15:50:18.736] <TB2> INFO: 1071250 events read in total (72319ms).
[15:50:42.416] <TB2> INFO: 1426950 events read in total (95999ms).
[15:51:07.346] <TB2> INFO: 1782050 events read in total (120929ms).
[15:51:31.959] <TB2> INFO: 2135650 events read in total (145542ms).
[15:51:56.346] <TB2> INFO: 2487350 events read in total (169929ms).
[15:52:20.110] <TB2> INFO: 2836300 events read in total (193693ms).
[15:52:45.227] <TB2> INFO: 3182550 events read in total (218810ms).
[15:53:09.864] <TB2> INFO: 3526650 events read in total (243447ms).
[15:53:34.796] <TB2> INFO: 3869600 events read in total (268379ms).
[15:53:54.709] <TB2> INFO: 4160000 events read in total (288292ms).
[15:53:54.953] <TB2> INFO: Test took 289926ms.
[15:53:58.917] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:04.983] <TB2> INFO: dacScan step from 55 .. 74
[15:54:04.983] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[15:54:05.050] <TB2> INFO: run 1 of 1
[15:54:05.774] <TB2> INFO: Expecting 4160000 events.
[15:54:29.731] <TB2> INFO: 315700 events read in total (23418ms).
[15:54:52.117] <TB2> INFO: 632000 events read in total (45804ms).
[15:54:56.783] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 2 Number of ROCs (5) != Token Chain Length (4)
[15:55:15.944] <TB2> INFO: 948250 events read in total (69631ms).
[15:55:39.926] <TB2> INFO: 1264600 events read in total (93613ms).
[15:56:02.170] <TB2> INFO: 1580950 events read in total (115857ms).
[15:56:26.130] <TB2> INFO: 1897300 events read in total (139817ms).
[15:56:49.361] <TB2> INFO: 2213500 events read in total (163048ms).
[15:57:12.392] <TB2> INFO: 2529600 events read in total (186079ms).
[15:57:35.784] <TB2> INFO: 2845650 events read in total (209472ms).
[15:57:59.423] <TB2> INFO: 3161800 events read in total (233110ms).
[15:58:23.159] <TB2> INFO: 3478050 events read in total (256846ms).
[15:58:46.845] <TB2> INFO: 3794350 events read in total (280532ms).
[15:59:10.165] <TB2> INFO: 4110350 events read in total (303852ms).
[15:59:14.367] <TB2> INFO: 4160000 events read in total (308054ms).
[15:59:14.597] <TB2> INFO: Test took 309442ms.
[15:59:16.688] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:22.951] <TB2> INFO: dacScan step from 75 .. 75
[15:59:22.951] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[15:59:22.951] <TB2> INFO: run 1 of 1
[15:59:23.581] <TB2> INFO: Expecting 208000 events.
[15:59:40.403] <TB2> INFO: 208000 events read in total (16293ms).
[15:59:40.412] <TB2> INFO: Test took 17460ms.
[15:59:40.516] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:40.877] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:43.353] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:44.550] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:45.783] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:47.008] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:48.199] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:49.403] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:50.659] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:51.926] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:53.114] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:54.387] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:55.635] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:56.968] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:58.054] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:59.185] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:00.512] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:01.882] <TB2> INFO: PixTest:: pg_setup set to default.
[16:00:01.884] <TB2> INFO: vcal mean: 38.80 39.03 38.52 41.82 42.28 43.23 43.77 44.15 42.75 43.02 42.84 43.07 41.22 38.59 39.54 38.27
[16:00:01.884] <TB2> INFO: vcal RMS: 1.15 1.20 0.83 1.75 1.29 1.42 1.56 1.63 1.46 1.52 1.43 1.61 1.29 1.11 1.05 1.18
[16:00:01.884] <TB2> INFO: number of X-ray hits detected: 227872 228265 200100 352234 386689 443455 447656 472377 409924 441966 425202 409078 347595 201246 237057 198314
[16:00:01.884] <TB2> 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:00:01.884] <TB2> INFO: X-ray hit rate [MHz/cm2]: 66.8 66.9 58.7 103.2 113.3 130.0 131.2 138.5 120.2 129.5 124.6 119.9 101.9 59.0 69.5 58.1
[16:00:01.884] <TB2> INFO: PixTestHighRate::doXNoiseMaps() done
[16:00:02.597] <TB2> INFO: enter test to run
[16:00:02.606] <TB2> INFO: test: no parameter change
[16:00:02.846] <TB2> QUIET: Connection to board 162 closed.
[16:00:02.938] <TB2> INFO: pXar: this is the end, my friend