[08:26:38.297] <TB1> INFO: *** Welcome to pxar ***
[08:26:38.297] <TB1> INFO: *** Today: 2016/10/10
[08:26:38.363] <TB1> INFO: *** Version: f5d3-dirty
[08:26:38.363] <TB1> INFO: readRocDacs: /usr/local/data/M2353_XrayQualification_2016-10-10_09h55m_1476086146//006_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2353_XrayQualification_2016-10-10_09h55m_1476086146//006_HRSCurves_100//dacParameters35_C15.dat
[08:26:38.364] <TB1> INFO: readTbmDacs: /usr/local/data/M2353_XrayQualification_2016-10-10_09h55m_1476086146//006_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2353_XrayQualification_2016-10-10_09h55m_1476086146//006_HRSCurves_100//tbmParameters_C0b.dat
[08:26:38.364] <TB1> INFO: readMaskFile: /usr/local/data/M2353_XrayQualification_2016-10-10_09h55m_1476086146//006_HRSCurves_100//defaultMaskFile.dat
[08:26:38.364] <TB1> INFO: readTrimFile: /usr/local/data/M2353_XrayQualification_2016-10-10_09h55m_1476086146//006_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2353_XrayQualification_2016-10-10_09h55m_1476086146//006_HRSCurves_100//trimParameters35_C15.dat
[08:26:38.437] <TB1> INFO: clk: 4
[08:26:38.437] <TB1> INFO: ctr: 4
[08:26:38.437] <TB1> INFO: sda: 19
[08:26:38.437] <TB1> INFO: tin: 9
[08:26:38.437] <TB1> INFO: level: 15
[08:26:38.437] <TB1> INFO: triggerdelay: 0
[08:26:38.437] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[08:26:38.437] <TB1> INFO: Log level: INFO
[08:26:38.450] <TB1> INFO: Found DTB DTB_WWXTQT
[08:26:38.461] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[08:26:38.463] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[08:26:38.465] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[08:26:40.024] <TB1> INFO: DUT info:
[08:26:40.024] <TB1> INFO: The DUT currently contains the following objects:
[08:26:40.025] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:26:40.025] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:26:40.025] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:26:40.025] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:26:40.025] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.025] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:40.426] <TB1> INFO: enter 'restricted' command line mode
[08:26:40.426] <TB1> INFO: enter test to run
[08:26:40.426] <TB1> INFO: test: Highrate no parameter change
[08:26:40.426] <TB1> INFO: running: highrate
[08:26:40.427] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2353_XrayQualification_2016-10-10_09h55m_1476086146//006_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2353_XrayQualification_2016-10-10_09h55m_1476086146//006_HRSCurves_100//phCalibrationFitErr35_C15.dat
[08:26:40.666] <TB1> INFO: ----------------------------------------------------------------------
[08:26:40.666] <TB1> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[08:26:40.666] <TB1> INFO: ----------------------------------------------------------------------
[08:26:40.673] <TB1> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 15 .. 75 (20/-1) hits flags = 896 (plus default)
[08:26:40.688] <TB1> INFO: dacScan step from 15 .. 34
[08:26:40.688] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[08:26:40.688] <TB1> INFO: run 1 of 1
[08:26:41.208] <TB1> INFO: Expecting 4160000 events.
[08:26:50.994] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 2 Number of ROCs (5) != Token Chain Length (4)
[08:26:50.994] <TB1> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[08:26:50.994] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2ad 80c1 40e1 218 2a46 40e1 15b 2085 40e1 40e1 244 2641 6e0 2665 811 206d e002 c000
[08:26:50.994] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2a7 8041 40e0 111 204e 29d 2869 44d 224a 40e0 288 2a28 31c 2465 4c1 224f 40e0 6c4 222f 704 2223 40c0 d4 266b 4d4 2a29 e002 c000
[08:26:50.994] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2a8 80b1 40e0 5c 2662 415 2465 45b 2461 40e0 21d 246c 414 2462 840 2642 40e1 61 264e 418 264d 603 2664 41e1 49b 2869 e002 c000
[08:26:50.994] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2a9 80c1 40e0 613 206a 40e0 804 264f 40e0 24a 2a4c 509 2641 40e0 a1 226f 35d 2a6d 50b 2881 75c 2847 811 2a4f e002 c000
[08:26:50.994] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2aa 8001 40e0 40e0 40e3 de0 2130 d42 2b90 9ec 2388 c50 242c 40e1 145 2665 443 226c 69c 2480 40e1 d 2847 d1 2a4f 514 2269 e002 c000
[08:26:50.994] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2ab 8041 40e1 481 246a 684 202f 751 2043 40e1 202 2242 351 224d 40c1 440 2668 40e0 10c 2847 44d 2669 e002 c000
[08:26:50.994] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2ac 80b1 40e0 24c 2264 294 204d 4d2 264c 613 2669 40e0 220 2662 68c 204d 40e0 84a 204d 40e3 209 2265 44a 2a47 44b 2263 e002 c000
[08:27:09.174] <TB1> INFO: 460400 events read in total (27562ms).
[08:27:36.844] <TB1> INFO: 922150 events read in total (55232ms).
[08:28:04.278] <TB1> INFO: 1383950 events read in total (82666ms).
[08:28:31.534] <TB1> INFO: 1845700 events read in total (109922ms).
[08:28:59.445] <TB1> INFO: 2307700 events read in total (137833ms).
[08:29:27.000] <TB1> INFO: 2769100 events read in total (165388ms).
[08:29:53.732] <TB1> INFO: 3230600 events read in total (192120ms).
[08:30:20.823] <TB1> INFO: 3691300 events read in total (219211ms).
[08:30:47.837] <TB1> INFO: 4152100 events read in total (246225ms).
[08:30:48.536] <TB1> INFO: 4160000 events read in total (246924ms).
[08:30:48.825] <TB1> INFO: Test took 248136ms.
[08:30:57.182] <TB1> INFO: dacScan step from 35 .. 54
[08:30:57.182] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[08:30:57.182] <TB1> INFO: run 1 of 1
[08:30:57.667] <TB1> INFO: Expecting 4160000 events.
[08:31:24.807] <TB1> INFO: 389250 events read in total (26720ms).
[08:31:51.321] <TB1> INFO: 778300 events read in total (53234ms).
[08:32:17.798] <TB1> INFO: 1166750 events read in total (79711ms).
[08:32:44.044] <TB1> INFO: 1553900 events read in total (105957ms).
[08:33:09.778] <TB1> INFO: 1939950 events read in total (131691ms).
[08:33:36.578] <TB1> INFO: 2323500 events read in total (158491ms).
[08:34:02.005] <TB1> INFO: 2704350 events read in total (183918ms).
[08:34:27.987] <TB1> INFO: 3081700 events read in total (209900ms).
[08:34:54.444] <TB1> INFO: 3455500 events read in total (236357ms).
[08:35:20.409] <TB1> INFO: 3827050 events read in total (262322ms).
[08:35:43.461] <TB1> INFO: 4160000 events read in total (285374ms).
[08:35:43.687] <TB1> INFO: Test took 286505ms.
[08:35:53.603] <TB1> INFO: dacScan step from 55 .. 74
[08:35:53.603] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[08:35:53.603] <TB1> INFO: run 1 of 1
[08:35:54.135] <TB1> INFO: Expecting 4160000 events.
[08:36:19.249] <TB1> INFO: 340650 events read in total (24712ms).
[08:36:44.354] <TB1> INFO: 681950 events read in total (49817ms).
[08:37:09.604] <TB1> INFO: 1023000 events read in total (75067ms).
[08:37:34.931] <TB1> INFO: 1364100 events read in total (100394ms).
[08:37:59.643] <TB1> INFO: 1705050 events read in total (125106ms).
[08:38:25.358] <TB1> INFO: 2046000 events read in total (150821ms).
[08:38:50.779] <TB1> INFO: 2387350 events read in total (176242ms).
[08:39:15.570] <TB1> INFO: 2728500 events read in total (201033ms).
[08:39:40.224] <TB1> INFO: 3069100 events read in total (225687ms).
[08:40:04.117] <TB1> INFO: 3410000 events read in total (249580ms).
[08:40:28.369] <TB1> INFO: 3750900 events read in total (273832ms).
[08:40:52.779] <TB1> INFO: 4091600 events read in total (298242ms).
[08:40:57.976] <TB1> INFO: 4160000 events read in total (303439ms).
[08:40:58.160] <TB1> INFO: Test took 304557ms.
[08:41:05.534] <TB1> INFO: dacScan step from 75 .. 75
[08:41:05.534] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[08:41:05.534] <TB1> INFO: run 1 of 1
[08:41:06.012] <TB1> INFO: Expecting 208000 events.
[08:41:22.316] <TB1> INFO: 208000 events read in total (15900ms).
[08:41:22.324] <TB1> INFO: Test took 16789ms.
[08:41:22.673] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:23.813] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:25.001] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:26.192] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:27.235] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:28.282] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:29.569] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:30.652] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:31.853] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:32.964] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:34.025] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:35.121] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:36.162] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:37.202] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:38.228] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:39.344] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:41:40.382] <TB1> INFO: PixTest:: pg_setup set to default.
[08:41:40.383] <TB1> INFO: vcal mean: 39.13 39.25 38.35 41.90 41.76 42.50 42.62 42.32 41.89 42.58 42.85 41.18 41.91 38.76 38.67 39.15
[08:41:40.383] <TB1> INFO: vcal RMS: 1.13 1.19 0.94 1.46 1.25 1.49 1.50 1.44 1.39 1.52 1.45 1.53 1.31 1.14 1.01 1.15
[08:41:40.383] <TB1> INFO: number of X-ray hits detected: 225869 234650 202114 360764 362740 405671 406292 399001 383036 421722 425459 330626 356346 199795 210840 227408
[08:41:40.383] <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
[08:41:40.383] <TB1> INFO: X-ray hit rate [MHz/cm2]: 66.2 68.8 59.2 105.7 106.3 118.9 119.1 117.0 112.3 123.6 124.7 96.9 104.4 58.6 61.8 66.7
[08:41:40.383] <TB1> INFO: PixTestHighRate::doXNoiseMaps() done
[08:41:40.499] <TB1> INFO: enter test to run
[08:41:40.499] <TB1> INFO: test: no parameter change
[08:41:40.733] <TB1> QUIET: Connection to board 147 closed.
[08:41:40.813] <TB1> INFO: pXar: this is the end, my friend