[08:13:03.954] <TB1> INFO: *** Welcome to pxar ***
[08:13:03.954] <TB1> INFO: *** Today: 2016/09/27
[08:13:04.023] <TB1> INFO: *** Version: f5d3-dirty
[08:13:04.023] <TB1> INFO: readRocDacs: /usr/local/data/M2328_XrayQualification_2016-09-27_09h42m_1474962139//006_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2328_XrayQualification_2016-09-27_09h42m_1474962139//006_HRSCurves_100//dacParameters35_C15.dat
[08:13:04.024] <TB1> INFO: readTbmDacs: /usr/local/data/M2328_XrayQualification_2016-09-27_09h42m_1474962139//006_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2328_XrayQualification_2016-09-27_09h42m_1474962139//006_HRSCurves_100//tbmParameters_C0b.dat
[08:13:04.024] <TB1> INFO: readMaskFile: /usr/local/data/M2328_XrayQualification_2016-09-27_09h42m_1474962139//006_HRSCurves_100//defaultMaskFile.dat
[08:13:04.024] <TB1> INFO: readTrimFile: /usr/local/data/M2328_XrayQualification_2016-09-27_09h42m_1474962139//006_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2328_XrayQualification_2016-09-27_09h42m_1474962139//006_HRSCurves_100//trimParameters35_C15.dat
[08:13:04.105] <TB1> INFO: clk: 4
[08:13:04.105] <TB1> INFO: ctr: 4
[08:13:04.105] <TB1> INFO: sda: 19
[08:13:04.105] <TB1> INFO: tin: 9
[08:13:04.105] <TB1> INFO: level: 15
[08:13:04.105] <TB1> INFO: triggerdelay: 0
[08:13:04.105] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[08:13:04.105] <TB1> INFO: Log level: INFO
[08:13:04.118] <TB1> INFO: Found DTB DTB_WWXTQT
[08:13:04.126] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[08:13:04.128] <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:13:04.130] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[08:13:05.683] <TB1> INFO: DUT info:
[08:13:05.683] <TB1> INFO: The DUT currently contains the following objects:
[08:13:05.683] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:13:05.683] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:13:05.683] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:13:05.683] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:13:05.683] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:05.683] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:06.084] <TB1> INFO: enter 'restricted' command line mode
[08:13:06.084] <TB1> INFO: enter test to run
[08:13:06.084] <TB1> INFO: test: Highrate no parameter change
[08:13:06.084] <TB1> INFO: running: highrate
[08:13:06.084] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2328_XrayQualification_2016-09-27_09h42m_1474962139//006_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2328_XrayQualification_2016-09-27_09h42m_1474962139//006_HRSCurves_100//phCalibrationFitErr35_C15.dat
[08:13:06.225] <TB1> INFO: ----------------------------------------------------------------------
[08:13:06.225] <TB1> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[08:13:06.225] <TB1> INFO: ----------------------------------------------------------------------
[08:13:06.231] <TB1> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 15 .. 75 (20/-1) hits flags = 896 (plus default)
[08:13:06.242] <TB1> INFO: dacScan step from 15 .. 34
[08:13:06.242] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[08:13:06.242] <TB1> INFO: run 1 of 1
[08:13:06.722] <TB1> INFO: Expecting 4160000 events.
[08:13:33.308] <TB1> INFO: 447050 events read in total (26182ms).
[08:13:59.311] <TB1> INFO: 894050 events read in total (52185ms).
[08:14:25.260] <TB1> INFO: 1340750 events read in total (78134ms).
[08:14:51.023] <TB1> INFO: 1788200 events read in total (103897ms).
[08:15:17.557] <TB1> INFO: 2235700 events read in total (130431ms).
[08:15:43.383] <TB1> INFO: 2682450 events read in total (156257ms).
[08:16:09.799] <TB1> INFO: 3129800 events read in total (182673ms).
[08:16:35.592] <TB1> INFO: 3576750 events read in total (208466ms).
[08:17:02.107] <TB1> INFO: 4023700 events read in total (234981ms).
[08:17:10.169] <TB1> INFO: 4160000 events read in total (243043ms).
[08:17:10.452] <TB1> INFO: Test took 244210ms.
[08:17:18.560] <TB1> INFO: dacScan step from 35 .. 54
[08:17:18.560] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[08:17:18.560] <TB1> INFO: run 1 of 1
[08:17:19.095] <TB1> INFO: Expecting 4160000 events.
[08:17:44.585] <TB1> INFO: 380700 events read in total (25088ms).
[08:18:10.175] <TB1> INFO: 762500 events read in total (50678ms).
[08:18:35.574] <TB1> INFO: 1143050 events read in total (76077ms).
[08:19:00.877] <TB1> INFO: 1522300 events read in total (101380ms).
[08:19:26.184] <TB1> INFO: 1900250 events read in total (126687ms).
[08:19:51.995] <TB1> INFO: 2276100 events read in total (152498ms).
[08:20:16.826] <TB1> INFO: 2648750 events read in total (177329ms).
[08:20:42.057] <TB1> INFO: 3017750 events read in total (202560ms).
[08:21:07.019] <TB1> INFO: 3382800 events read in total (227522ms).
[08:21:32.263] <TB1> INFO: 3746850 events read in total (252766ms).
[08:21:57.218] <TB1> INFO: 4110650 events read in total (277721ms).
[08:22:00.873] <TB1> INFO: 4160000 events read in total (281376ms).
[08:22:01.124] <TB1> INFO: Test took 282563ms.
[08:22:09.998] <TB1> INFO: dacScan step from 55 .. 74
[08:22:09.998] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[08:22:09.999] <TB1> INFO: run 1 of 1
[08:22:10.485] <TB1> INFO: Expecting 4160000 events.
[08:22:17.768] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 1 Number of ROCs (5) != Token Chain Length (4)
[08:22:17.768] <TB1> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[08:22:17.768] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1c4 80b1 4870 a 244c 4ca 246c 754 2849 84c 262f 4070 a 244d 4 2064 283 2062 28c 2640 4870 a 2462 41b 2a6f 800 2647 4070 a 2444 45d 2469 e002 c000
[08:22:17.768] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1be 8000 4831 a 2448 553 2667 84b 2047 4871 22 2861 a 2465 2c9 2082 55a 204f 4071 a 244b 2ca 2863 4871 a 244d 108 2a4b 55a 2069 e002 c000
[08:22:17.768] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1bf 8040 4833 a 2448 112 2668 55d 284f 811 2661 4073 a 244b 51 2a40 2db 2085 4d4 244a 548 2845 4873 a 244c 99 286c 4873 e002 c000
[08:22:17.768] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1c0 80b1 4870 a1 244c 554 204f 54b 244c 4870 a 2446 345 2865 4870 a 2466 322 246a 449 2a4c 4870 a 242b 705 2243 e002 c000
[08:22:17.768] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1c1 80c0 4831 a 244f 4071 a 244f 4871 ef0 25c2 4f2 20d2 6a3 2018 6c8 25d4 4871 18 284e a 246c 8c 284f 2cd 2461 34c 2463 49d 204f 4871 1b 2444 a 244f 9a 2268 61a 2468 601 2261 e002 c000
[08:22:17.768] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1c2 8001 4870 a 2443 20a 2a6d 253 2465 283 204a 41a 2a6c 608 2246 60c 2240 612 2a49 4870 a 244c 20c 2a82 404 204d 4071 a 2445 612 2661 642 246d 4871 12 204f a 244b e002 c000
[08:22:17.768] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1c3 8040 4070 a 244f 25b 2260 719 286d 4871 a 2448 8 264f 418 2a62 693 2281 4070 a 2463 d 226d 350 2240 481 2644 4871 a 242f e002 c000
[08:22:34.688] <TB1> INFO: 332950 events read in total (23801ms).
[08:22:58.984] <TB1> INFO: 666250 events read in total (48097ms).
[08:23:22.825] <TB1> INFO: 999550 events read in total (71938ms).
[08:23:46.974] <TB1> INFO: 1332900 events read in total (96087ms).
[08:24:10.839] <TB1> INFO: 1666000 events read in total (119952ms).
[08:24:34.793] <TB1> INFO: 1998950 events read in total (143906ms).
[08:24:59.396] <TB1> INFO: 2332000 events read in total (168509ms).
[08:25:22.827] <TB1> INFO: 2665150 events read in total (191940ms).
[08:25:46.959] <TB1> INFO: 2998300 events read in total (216072ms).
[08:26:10.682] <TB1> INFO: 3331350 events read in total (239795ms).
[08:26:34.103] <TB1> INFO: 3664650 events read in total (263216ms).
[08:26:59.326] <TB1> INFO: 3997600 events read in total (288439ms).
[08:27:11.351] <TB1> INFO: 4160000 events read in total (300464ms).
[08:27:11.602] <TB1> INFO: Test took 301602ms.
[08:27:19.168] <TB1> INFO: dacScan step from 75 .. 75
[08:27:19.168] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[08:27:19.168] <TB1> INFO: run 1 of 1
[08:27:19.646] <TB1> INFO: Expecting 208000 events.
[08:27:35.497] <TB1> INFO: 208000 events read in total (15449ms).
[08:27:35.505] <TB1> INFO: Test took 16337ms.
[08:27:35.864] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:36.971] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:37.975] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:38.963] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:39.979] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:40.998] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:42.036] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:43.066] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:44.094] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:45.130] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:46.159] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:47.193] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:48.223] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:49.242] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:50.237] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:51.228] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[08:27:52.228] <TB1> INFO: PixTest:: pg_setup set to default.
[08:27:52.230] <TB1> INFO: vcal mean: 38.97 39.12 38.98 41.90 41.79 43.53 41.96 43.18 42.90 42.55 42.74 41.91 41.88 38.75 39.27 39.20
[08:27:52.230] <TB1> INFO: vcal RMS: 1.16 1.40 1.00 1.54 1.32 1.49 1.46 1.57 1.56 1.51 1.51 1.48 1.32 1.84 1.05 1.11
[08:27:52.230] <TB1> INFO: number of X-ray hits detected: 210351 220721 212019 363846 364300 453933 389029 441205 423945 414858 438313 357491 356415 204416 223094 219543
[08:27:52.230] <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:27:52.230] <TB1> INFO: X-ray hit rate [MHz/cm2]: 61.7 64.7 62.1 106.6 106.8 133.1 114.0 129.3 124.3 121.6 128.5 104.8 104.5 59.9 65.4 64.3
[08:27:52.230] <TB1> INFO: PixTestHighRate::doXNoiseMaps() done
[08:27:52.323] <TB1> INFO: enter test to run
[08:27:52.323] <TB1> INFO: test: no parameter change
[08:27:52.547] <TB1> QUIET: Connection to board 147 closed.
[08:27:52.675] <TB1> INFO: pXar: this is the end, my friend