[10:25:33.625] <TB1> INFO: *** Welcome to pxar ***
[10:25:33.625] <TB1> INFO: *** Today: 2015/08/31
[10:25:33.625] <TB1> INFO: readRocDacs: /usr/local/data/M2090_XrayQualification_2015-08-31_11h49m_1441014591//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2090_XrayQualification_2015-08-31_11h49m_1441014591//005_HRSCurves_100//dacParameters35_C15.dat
[10:25:33.626] <TB1> INFO: readTbmDacs: /usr/local/data/M2090_XrayQualification_2015-08-31_11h49m_1441014591//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2090_XrayQualification_2015-08-31_11h49m_1441014591//005_HRSCurves_100//tbmParameters_C0b.dat
[10:25:33.626] <TB1> INFO: readMaskFile: /usr/local/data/M2090_XrayQualification_2015-08-31_11h49m_1441014591//005_HRSCurves_100//defaultMaskFile.dat
[10:25:33.627] <TB1> INFO: readTrimFile: /usr/local/data/M2090_XrayQualification_2015-08-31_11h49m_1441014591//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2090_XrayQualification_2015-08-31_11h49m_1441014591//005_HRSCurves_100//trimParameters35_C15.dat
[10:25:33.698] <TB1> INFO: clk: 4
[10:25:33.698] <TB1> INFO: ctr: 4
[10:25:33.698] <TB1> INFO: sda: 19
[10:25:33.698] <TB1> INFO: tin: 9
[10:25:33.698] <TB1> INFO: level: 15
[10:25:33.698] <TB1> INFO: triggerdelay: 0
[10:25:33.698] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:25:33.698] <TB1> INFO: Log level: INFO
[10:25:33.711] <TB1> INFO: Found DTB DTB_WS6UZO
[10:25:33.721] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[10:25:33.724] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[10:25:33.726] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[10:25:35.252] <TB1> INFO: DUT info:
[10:25:35.252] <TB1> INFO: The DUT currently contains the following objects:
[10:25:35.252] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:25:35.252] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:25:35.252] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:25:35.252] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:25:35.252] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.252] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:25:35.653] <TB1> INFO: enter 'restricted' command line mode
[10:25:35.653] <TB1> INFO: enter test to run
[10:25:35.654] <TB1> INFO: test: Highrate no parameter change
[10:25:35.654] <TB1> INFO: running: highrate
[10:25:35.654] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2090_XrayQualification_2015-08-31_11h49m_1441014591//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2090_XrayQualification_2015-08-31_11h49m_1441014591//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[10:25:35.834] <TB1> INFO: ----------------------------------------------------------------------
[10:25:35.834] <TB1> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[10:25:35.834] <TB1> INFO: ----------------------------------------------------------------------
[10:25:35.840] <TB1> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (20/-1) hits flags = 384 (plus default)
[10:25:35.854] <TB1> INFO: dacScan step from 0 .. 19
[10:25:35.854] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:25:35.854] <TB1> INFO: run 1 of 1
[10:25:36.462] <TB1> INFO: Expecting 4160000 events.
[10:26:09.320] <TB1> INFO: 383200 events read in total (32328ms).
[10:26:40.666] <TB1> INFO: 767550 events read in total (63674ms).
[10:27:12.933] <TB1> INFO: 1152000 events read in total (95941ms).
[10:27:44.949] <TB1> INFO: 1536450 events read in total (127957ms).
[10:28:16.029] <TB1> INFO: 1920700 events read in total (159037ms).
[10:28:49.195] <TB1> INFO: 2305300 events read in total (192203ms).
[10:29:20.493] <TB1> INFO: 2689550 events read in total (223501ms).
[10:29:51.712] <TB1> INFO: 3073600 events read in total (254720ms).
[10:30:22.055] <TB1> INFO: 3457950 events read in total (285063ms).
[10:30:53.476] <TB1> INFO: 3842300 events read in total (316485ms).
[10:31:19.043] <TB1> INFO: 4160000 events read in total (342051ms).
[10:31:19.240] <TB1> INFO: Test took 343385ms.
[10:31:23.043] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:30.108] <TB1> INFO: dacScan step from 20 .. 39
[10:31:30.108] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:31:30.108] <TB1> INFO: run 1 of 1
[10:31:30.703] <TB1> INFO: Expecting 4160000 events.
[10:32:05.097] <TB1> INFO: 381900 events read in total (33860ms).
[10:32:39.199] <TB1> INFO: 764750 events read in total (67962ms).
[10:33:12.459] <TB1> INFO: 1147450 events read in total (101222ms).
[10:33:45.992] <TB1> INFO: 1529700 events read in total (134756ms).
[10:34:19.634] <TB1> INFO: 1912650 events read in total (168397ms).
[10:34:42.418] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L473> Channel 1 Number of ROCs (5) != Token Chain Length (4)
[10:34:51.507] <TB1> INFO: 2295200 events read in total (200270ms).
[10:35:27.056] <TB1> INFO: 2677300 events read in total (235819ms).
[10:36:00.007] <TB1> INFO: 3058650 events read in total (268770ms).
[10:36:33.040] <TB1> INFO: 3439150 events read in total (301803ms).
[10:37:04.776] <TB1> INFO: 3819200 events read in total (333539ms).
[10:37:33.477] <TB1> INFO: 4160000 events read in total (362240ms).
[10:37:33.674] <TB1> INFO: Test took 363566ms.
[10:37:36.541] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:43.199] <TB1> INFO: dacScan step from 40 .. 59
[10:37:43.200] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:37:43.200] <TB1> INFO: run 1 of 1
[10:37:43.812] <TB1> INFO: Expecting 4160000 events.
[10:38:17.781] <TB1> INFO: 320750 events read in total (33441ms).
[10:38:50.788] <TB1> INFO: 642200 events read in total (66448ms).
[10:39:23.249] <TB1> INFO: 962950 events read in total (98909ms).
[10:39:55.897] <TB1> INFO: 1283250 events read in total (131557ms).
[10:40:27.230] <TB1> INFO: 1602600 events read in total (162890ms).
[10:40:55.687] <TB1> INFO: 1921000 events read in total (191347ms).
[10:41:26.939] <TB1> INFO: 2237750 events read in total (222599ms).
[10:41:56.996] <TB1> INFO: 2552650 events read in total (252656ms).
[10:42:27.856] <TB1> INFO: 2866000 events read in total (283516ms).
[10:42:58.863] <TB1> INFO: 3177250 events read in total (314523ms).
[10:43:29.795] <TB1> INFO: 3487200 events read in total (345455ms).
[10:44:01.718] <TB1> INFO: 3796150 events read in total (377378ms).
[10:44:34.398] <TB1> INFO: 4104950 events read in total (410058ms).
[10:44:39.793] <TB1> INFO: 4160000 events read in total (415453ms).
[10:44:40.067] <TB1> INFO: Test took 416867ms.
[10:44:43.250] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:50.386] <TB1> INFO: dacScan step from 60 .. 79
[10:44:50.386] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:44:50.387] <TB1> INFO: run 1 of 1
[10:44:50.988] <TB1> INFO: Expecting 4160000 events.
[10:45:21.785] <TB1> INFO: 296250 events read in total (30270ms).
[10:45:52.035] <TB1> INFO: 593150 events read in total (60521ms).
[10:46:24.034] <TB1> INFO: 890000 events read in total (92519ms).
[10:46:52.027] <TB1> INFO: 1186900 events read in total (120512ms).
[10:47:21.823] <TB1> INFO: 1484000 events read in total (150308ms).
[10:47:52.536] <TB1> INFO: 1780900 events read in total (181021ms).
[10:48:23.341] <TB1> INFO: 2078000 events read in total (211826ms).
[10:48:53.575] <TB1> INFO: 2375050 events read in total (242060ms).
[10:49:25.730] <TB1> INFO: 2671950 events read in total (274215ms).
[10:49:58.229] <TB1> INFO: 2968950 events read in total (306714ms).
[10:50:30.633] <TB1> INFO: 3265750 events read in total (339118ms).
[10:51:02.823] <TB1> INFO: 3562850 events read in total (371308ms).
[10:51:34.362] <TB1> INFO: 3859650 events read in total (402847ms).
[10:52:05.384] <TB1> INFO: 4156600 events read in total (433869ms).
[10:52:06.051] <TB1> INFO: 4160000 events read in total (434536ms).
[10:52:06.347] <TB1> INFO: Test took 435960ms.
[10:52:08.993] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:15.643] <TB1> INFO: dacScan step from 80 .. 99
[10:52:15.643] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:52:15.643] <TB1> INFO: run 1 of 1
[10:52:16.239] <TB1> INFO: Expecting 4160000 events.
[10:52:45.741] <TB1> INFO: 296200 events read in total (28963ms).
[10:53:10.792] <TB1> INFO: 592850 events read in total (54014ms).
[10:53:33.070] <TB1> INFO: 889550 events read in total (76292ms).
[10:53:55.308] <TB1> INFO: 1186350 events read in total (98530ms).
[10:54:17.781] <TB1> INFO: 1483200 events read in total (121003ms).
[10:54:40.062] <TB1> INFO: 1780000 events read in total (143284ms).
[10:55:02.461] <TB1> INFO: 2076850 events read in total (165683ms).
[10:55:24.610] <TB1> INFO: 2373650 events read in total (187832ms).
[10:55:46.455] <TB1> INFO: 2670450 events read in total (209677ms).
[10:56:09.613] <TB1> INFO: 2967250 events read in total (232835ms).
[10:56:31.986] <TB1> INFO: 3264000 events read in total (255208ms).
[10:56:53.986] <TB1> INFO: 3560900 events read in total (277208ms).
[10:57:16.150] <TB1> INFO: 3857850 events read in total (299372ms).
[10:57:38.200] <TB1> INFO: 4154500 events read in total (321422ms).
[10:57:38.869] <TB1> INFO: 4160000 events read in total (322091ms).
[10:57:39.110] <TB1> INFO: Test took 323467ms.
[10:57:41.067] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:47.249] <TB1> INFO: dacScan step from 100 .. 100
[10:57:47.249] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:57:47.249] <TB1> INFO: run 1 of 1
[10:57:47.843] <TB1> INFO: Expecting 208000 events.
[10:58:04.742] <TB1> INFO: 208000 events read in total (16372ms).
[10:58:04.751] <TB1> INFO: Test took 17501ms.
[10:58:04.849] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:05.156] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:06.474] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:07.731] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:08.965] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:10.300] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:11.613] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:13.038] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:14.422] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:15.806] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:17.173] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:18.537] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:19.929] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:21.252] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:22.551] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:23.764] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:25.006] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[10:58:26.243] <TB1> INFO: PixTest:: pg_setup set to default.
[10:58:26.244] <TB1> INFO: vcal mean: 39.48 40.41 39.49 42.88 42.84 44.67 43.80 44.85 45.30 45.16 45.78 43.87 42.41 40.35 39.99 39.82
[10:58:26.244] <TB1> INFO: vcal RMS: 1.30 1.28 0.89 1.65 1.54 1.59 1.42 1.57 1.74 1.69 1.84 1.76 1.45 1.42 1.25 1.13
[10:58:26.244] <TB1> INFO: number of X-ray hits detected: 242010 268381 224732 400575 432632 507795 468945 517482 521423 504778 515560 438956 378806 246256 263998 239096
[10:58:26.244] <TB1> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:58:26.244] <TB1> INFO: X-ray hit rate [MHz/cm2]: 70.9 78.7 65.9 117.4 126.8 148.8 137.5 151.7 152.8 148.0 151.1 128.7 111.0 72.2 77.4 70.1
[10:58:26.244] <TB1> INFO: PixTestHighRate::doXNoiseMaps() done
[10:58:26.338] <TB1> INFO: enter test to run
[10:58:26.338] <TB1> INFO: test: no parameter change
[10:58:26.503] <TB1> QUIET: Connection to board 83 closed.
[10:58:26.583] <TB1> INFO: pXar: this is the end, my friend