[15:43:23.992] <TB3> INFO: *** Welcome to pxar ***
[15:43:23.993] <TB3> INFO: *** Today: 2016/02/08
[15:43:24.059] <TB3> INFO: *** Version: 9751
[15:43:24.059] <TB3> INFO: readRocDacs: /usr/local/data/M2151_XrayQualification_2016-02-08_16h09m_1454944192//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2151_XrayQualification_2016-02-08_16h09m_1454944192//005_HRSCurves_100//dacParameters35_C15.dat
[15:43:24.060] <TB3> INFO: readTbmDacs: /usr/local/data/M2151_XrayQualification_2016-02-08_16h09m_1454944192//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2151_XrayQualification_2016-02-08_16h09m_1454944192//005_HRSCurves_100//tbmParameters_C0b.dat
[15:43:24.060] <TB3> INFO: readMaskFile: /usr/local/data/M2151_XrayQualification_2016-02-08_16h09m_1454944192//005_HRSCurves_100//defaultMaskFile.dat
[15:43:24.060] <TB3> INFO: readTrimFile: /usr/local/data/M2151_XrayQualification_2016-02-08_16h09m_1454944192//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2151_XrayQualification_2016-02-08_16h09m_1454944192//005_HRSCurves_100//trimParameters35_C15.dat
[15:43:24.140] <TB3> INFO: clk: 4
[15:43:24.140] <TB3> INFO: ctr: 4
[15:43:24.140] <TB3> INFO: sda: 19
[15:43:24.140] <TB3> INFO: tin: 9
[15:43:24.140] <TB3> INFO: level: 15
[15:43:24.140] <TB3> INFO: triggerdelay: 0
[15:43:24.140] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[15:43:24.140] <TB3> INFO: Log level: INFO
[15:43:24.153] <TB3> INFO: Found DTB DTB_WWV6Z5
[15:43:24.164] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[15:43:24.166] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[15:43:24.168] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[15:43:25.704] <TB3> INFO: DUT info:
[15:43:25.704] <TB3> INFO: The DUT currently contains the following objects:
[15:43:25.704] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:43:25.704] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:43:25.704] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:43:25.704] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:43:25.704] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.704] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.704] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.704] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.704] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.704] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.705] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.705] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.705] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.705] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.705] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.705] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.705] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.705] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.705] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:25.705] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:26.105] <TB3> INFO: enter 'restricted' command line mode
[15:43:26.105] <TB3> INFO: enter test to run
[15:43:26.105] <TB3> INFO: test: Highrate no parameter change
[15:43:26.105] <TB3> INFO: running: highrate
[15:43:26.106] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2151_XrayQualification_2016-02-08_16h09m_1454944192//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2151_XrayQualification_2016-02-08_16h09m_1454944192//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[15:43:26.267] <TB3> INFO: ----------------------------------------------------------------------
[15:43:26.267] <TB3> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[15:43:26.267] <TB3> INFO: ----------------------------------------------------------------------
[15:43:26.273] <TB3> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 15 .. 75 (20/-1) hits flags = 896 (plus default)
[15:43:26.286] <TB3> INFO: dacScan step from 15 .. 34
[15:43:26.286] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[15:43:26.286] <TB3> INFO: run 1 of 1
[15:43:26.776] <TB3> INFO: Expecting 4160000 events.
[15:44:00.595] <TB3> INFO: 503000 events read in total (33416ms).
[15:44:34.210] <TB3> INFO: 1008250 events read in total (67031ms).
[15:45:07.340] <TB3> INFO: 1513900 events read in total (100161ms).
[15:45:40.765] <TB3> INFO: 2019250 events read in total (133586ms).
[15:46:14.129] <TB3> INFO: 2524650 events read in total (166950ms).
[15:46:47.490] <TB3> INFO: 3029650 events read in total (200311ms).
[15:47:21.028] <TB3> INFO: 3534200 events read in total (233849ms).
[15:47:53.970] <TB3> INFO: 4038200 events read in total (266791ms).
[15:48:02.053] <TB3> INFO: 4160000 events read in total (274874ms).
[15:48:02.278] <TB3> INFO: Test took 275991ms.
[15:48:09.398] <TB3> INFO: dacScan step from 35 .. 54
[15:48:09.398] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[15:48:09.398] <TB3> INFO: run 1 of 1
[15:48:09.877] <TB3> INFO: Expecting 4160000 events.
[15:48:41.852] <TB3> INFO: 405550 events read in total (31570ms).
[15:49:13.430] <TB3> INFO: 811200 events read in total (63148ms).
[15:49:44.619] <TB3> INFO: 1216250 events read in total (94337ms).
[15:50:16.181] <TB3> INFO: 1619750 events read in total (125899ms).
[15:50:47.747] <TB3> INFO: 2022150 events read in total (157465ms).
[15:51:19.110] <TB3> INFO: 2422500 events read in total (188828ms).
[15:51:49.988] <TB3> INFO: 2820000 events read in total (219706ms).
[15:52:20.794] <TB3> INFO: 3214650 events read in total (250512ms).
[15:52:51.459] <TB3> INFO: 3606750 events read in total (281177ms).
[15:53:22.710] <TB3> INFO: 3997800 events read in total (312428ms).
[15:53:35.651] <TB3> INFO: 4160000 events read in total (325369ms).
[15:53:35.800] <TB3> INFO: Test took 326402ms.
[15:53:42.102] <TB3> INFO: dacScan step from 55 .. 74
[15:53:42.102] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[15:53:42.102] <TB3> INFO: run 1 of 1
[15:53:42.618] <TB3> INFO: Expecting 4160000 events.
[15:54:13.438] <TB3> INFO: 364250 events read in total (30418ms).
[15:54:43.876] <TB3> INFO: 729250 events read in total (60856ms).
[15:55:14.213] <TB3> INFO: 1094400 events read in total (91194ms).
[15:55:44.636] <TB3> INFO: 1459150 events read in total (121616ms).
[15:56:14.888] <TB3> INFO: 1823950 events read in total (151868ms).
[15:56:45.511] <TB3> INFO: 2188750 events read in total (182491ms).
[15:56:54.501] <TB3> WARNING: Detected DESER400 trailer error bits: "IDLE DATA"
[15:56:54.501] <TB3> ERROR: <datapipe.cc/CheckEventID:L469> Channel 2 Event ID mismatch: local ID (14) != TBM ID (15)
[15:56:54.501] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!
[15:56:54.501] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!
[15:56:54.501] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a212 8001 4810 161 2468 49b 2867 4810 41c 2a2f 49b 284b 4811 2 2841 400 2a42 49b 288a 480 246f 4810 2c2 2429 2dd 2a47 49b 286d fff 2fff e002 c000
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a20c 80b1 4810 49b 286e 4810 49b 2848 849 2848 4830 2cd 224a 49b 2888 4811 1d 242e 49b 286b e002 c000
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a20d 80c1 4810 49b 286c 4810 49b 2845 4810 49b 2889 694 2888 743 2a41 4810 49b 2863 519 2241 e002 c000
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a20e 8001 4811 354 2648 49c 2840 49b 286a 4811 49b 284d 4811 449 202f 49b 286e 81b 2a61 4811 7f 2ce4 a4d 2c36 fff 2fff fff 2fff bff 2010 7f 2fff fff 2fff f2ff d2ff
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a20f 8041 4813 9a 224a da 2a45 2dd 2467 344 202f 49b 2868 640 2827 4813 49b 2845 60a 2441 4813 49b 286c 545 204c 545 224f 4811 4b 2842 4b 244f 49b 2880 6c9 2a44 860 2663 e002 c000
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a210 80b1 4811 49b 286d 482 2265 4811 348 2266 49b 284c 759 204b 4811 60 266a 49b 286f 4810 49b 2861 e002 c000
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a211 80c1 4810 109 222f 141 2a63 40c 2826 49b 2865 4810 29a 2029 284 2a0f 49b 284f 4810 31a 242d 49b 2880 4810 49b 2869 6dc 2841 e002 c000
[15:56:54.501] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a21e 8001 4811 4d 222f 49b 2865 752 2a42 4811 49b 2848 49a 202f 642 202b 4811 352 204d 49b 2862 4811 8 202a 19 262c 49b 284f 688 282c 860 2a4b e002 c000
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a218 80b1 4810 13 2044 49b 2885 518 2446 4810 350 284b 352 2a4d 49b 284c 4810 25b 206a 49b 2885 74b 2a45 4830 15c 2a42 49b 2865 483 264f 4c3 2468 51c 242f e002 c000
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a219 80c1 4811 9 2226 453 2a42 49b 2883 4811 ca 2865 49b 284d 4811 10 242f b 2661 309 284d 358 264f 49b 286f 4810 49b 286f e002 c000
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a21a 8001 4810 49b 2862 748 244f 4810 c 2a40 49b 284f 522 264b 4810 298 2a4c 49b 288f 74b 2a28 4811 18 2045 20b 284f 209 2a4d 49b 2881 815 2643 e002 c000
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a21b 8041 4810 49b 2868 6d3 224d 4810 1c 284f 49b 2849 4810 49b 286d 4813 14 2842 db 2862 49b 2868 511 224d 514 2a45 e002 c000
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a21c 80b1 4810 354 2840 49b 2867 6cd 2a68 4810 48 2a4f 49b 284b 489 284e 71b 2266 4810 20 224d 285 2268 49b 2881 4811 31c 2a42 462 2a66 49b 2862 54c 2a29 6dd 2a4f e002 c000
[15:56:54.501] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a21d 80c1 4810 49b 2868 49c 2a4d 4810 49b 284f 4810 11b 2a2f 49b 2880 609 2481 862 2265 4830 302 2247 49b 284f e002 c000
[15:57:15.805] <TB3> INFO: 2553650 events read in total (212785ms).
[15:57:46.039] <TB3> INFO: 2918500 events read in total (243019ms).
[15:58:16.512] <TB3> INFO: 3283400 events read in total (273492ms).
[15:58:46.986] <TB3> INFO: 3648350 events read in total (303966ms).
[15:59:17.087] <TB3> INFO: 4013050 events read in total (334067ms).
[15:59:29.463] <TB3> INFO: 4160000 events read in total (346443ms).
[15:59:29.689] <TB3> INFO: Test took 347587ms.
[15:59:36.561] <TB3> INFO: dacScan step from 75 .. 75
[15:59:36.561] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[15:59:36.561] <TB3> INFO: run 1 of 1
[15:59:37.043] <TB3> INFO: Expecting 208000 events.
[15:59:55.522] <TB3> INFO: 208000 events read in total (18077ms).
[15:59:55.528] <TB3> INFO: Test took 18967ms.
[15:59:55.816] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:57.361] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:58.429] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[15:59:59.457] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:00.507] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:01.549] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:02.678] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:03.771] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:04.954] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:06.053] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:07.098] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:08.153] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:09.210] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:10.265] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:11.414] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:12.467] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:00:13.503] <TB3> INFO: PixTest:: pg_setup set to default.
[16:00:13.505] <TB3> INFO: vcal mean: 37.78 37.49 37.32 39.75 40.22 40.91 40.87 40.79 41.69 40.96 42.10 39.96 39.53 37.25 37.43 37.76
[16:00:13.505] <TB3> INFO: vcal RMS: 1.02 1.05 0.87 1.19 1.14 1.25 1.33 1.36 1.33 1.25 1.71 1.31 1.22 1.02 0.96 1.07
[16:00:13.505] <TB3> INFO: number of X-ray hits detected: 156959 150069 137687 240371 271547 311968 313659 322451 352908 321977 359817 255763 257677 142790 151460 171947
[16:00:13.505] <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:00:13.505] <TB3> INFO: X-ray hit rate [MHz/cm2]: 46.0 44.0 40.4 70.5 79.6 91.4 91.9 94.5 103.4 94.4 105.5 75.0 75.5 41.9 44.4 50.4
[16:00:13.505] <TB3> INFO: PixTestHighRate::doXNoiseMaps() done
[16:00:13.731] <TB3> INFO: enter test to run
[16:00:13.731] <TB3> INFO: test: no parameter change
[16:00:13.888] <TB3> QUIET: Connection to board 121 closed.
[16:00:13.967] <TB3> INFO: pXar: this is the end, my friend