[14:03:16.993] <TB0> INFO: *** Welcome to pxar ***
[14:03:16.993] <TB0> INFO: *** Today: 2015/10/20
[14:03:17.318] <TB0> INFO: *** Version: 7db0-dirty
[14:03:17.318] <TB0> INFO: readRocDacs: /usr/local/data/M2093_XrayQualification_2015-10-20_15h25m_1445347549//006_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2093_XrayQualification_2015-10-20_15h25m_1445347549//006_HRSCurves_100//dacParameters35_C15.dat
[14:03:17.318] <TB0> INFO: readTbmDacs: /usr/local/data/M2093_XrayQualification_2015-10-20_15h25m_1445347549//006_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2093_XrayQualification_2015-10-20_15h25m_1445347549//006_HRSCurves_100//tbmParameters_C0b.dat
[14:03:17.318] <TB0> INFO: readMaskFile: /usr/local/data/M2093_XrayQualification_2015-10-20_15h25m_1445347549//006_HRSCurves_100//defaultMaskFile.dat
[14:03:17.318] <TB0> INFO: readTrimFile: /usr/local/data/M2093_XrayQualification_2015-10-20_15h25m_1445347549//006_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2093_XrayQualification_2015-10-20_15h25m_1445347549//006_HRSCurves_100//trimParameters35_C15.dat
[14:03:17.387] <TB0> INFO: clk: 4
[14:03:17.387] <TB0> INFO: ctr: 4
[14:03:17.387] <TB0> INFO: sda: 19
[14:03:17.387] <TB0> INFO: tin: 9
[14:03:17.387] <TB0> INFO: level: 15
[14:03:17.387] <TB0> INFO: triggerdelay: 0
[14:03:17.387] <TB0> QUIET: Instanciating API for pxar v2.6.0+21~ga0339e2
[14:03:17.387] <TB0> INFO: Log level: INFO
[14:03:17.398] <TB0> INFO: Found DTB DTB_WS6UZO
[14:03:17.406] <TB0> QUIET: Connection to board DTB_WS6UZO opened.
[14:03:17.410] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[14:03:17.412] <TB0> INFO: RPC call hashes of host and DTB match: 398089610
[14:03:18.922] <TB0> INFO: DUT info:
[14:03:18.922] <TB0> INFO: The DUT currently contains the following objects:
[14:03:18.922] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[14:03:18.922] <TB0> INFO: TBM Core alpha (0): 7 registers set
[14:03:18.922] <TB0> INFO: TBM Core beta (1): 7 registers set
[14:03:18.922] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:03:18.923] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:18.923] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:19.323] <TB0> INFO: enter 'restricted' command line mode
[14:03:19.323] <TB0> INFO: enter test to run
[14:03:19.324] <TB0> INFO: test: Highrate no parameter change
[14:03:19.324] <TB0> INFO: running: highrate
[14:03:19.324] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2093_XrayQualification_2015-10-20_15h25m_1445347549//006_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2093_XrayQualification_2015-10-20_15h25m_1445347549//006_HRSCurves_100//phCalibrationFitErr35_C15.dat
[14:03:19.451] <TB0> INFO: ----------------------------------------------------------------------
[14:03:19.451] <TB0> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[14:03:19.451] <TB0> INFO: ----------------------------------------------------------------------
[14:03:19.457] <TB0> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 15 .. 75 (20/-1) hits flags = 896 (plus default)
[14:03:19.467] <TB0> INFO: dacScan step from 15 .. 34
[14:03:19.467] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[14:03:19.467] <TB0> INFO: run 1 of 1
[14:03:20.056] <TB0> INFO: Expecting 4160000 events.
[14:03:57.770] <TB0> INFO: 445450 events read in total (37184ms).
[14:04:33.781] <TB0> INFO: 891750 events read in total (73195ms).
[14:05:10.117] <TB0> INFO: 1338300 events read in total (109531ms).
[14:05:45.604] <TB0> INFO: 1784750 events read in total (145018ms).
[14:06:20.508] <TB0> INFO: 2231400 events read in total (179922ms).
[14:06:55.660] <TB0> INFO: 2677700 events read in total (215074ms).
[14:07:31.133] <TB0> INFO: 3124000 events read in total (250547ms).
[14:08:07.161] <TB0> INFO: 3569650 events read in total (286575ms).
[14:08:42.380] <TB0> INFO: 4014950 events read in total (321794ms).
[14:08:55.332] <TB0> INFO: 4160000 events read in total (334746ms).
[14:08:55.651] <TB0> INFO: Test took 336184ms.
[14:08:57.282] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:02.768] <TB0> INFO: dacScan step from 35 .. 54
[14:09:02.768] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[14:09:02.768] <TB0> INFO: run 1 of 1
[14:09:03.364] <TB0> INFO: Expecting 4160000 events.
[14:09:40.965] <TB0> INFO: 362950 events read in total (37071ms).
[14:10:12.532] <TB0> INFO: 727100 events read in total (68638ms).
[14:10:36.281] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 9 readouts!
[14:10:36.281] <TB0> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:10:36.281] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11b 8040 4c39 21d 244c 4c38 21d 244c 4c39 4 2244 4 2647 21d 2481 29d 244c 742 244c 4c39 21d 244e 299 244f 4a2 2668 619 2265 e002 c000
[14:10:36.281] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a115 80c0 4c38 1d 284a 50 2448 21d 2462 21b 246a 24a 262f 4c38 21d 2442 491 2441 6d5 2669 4c38 21d 2480 50a 2460 722 2849 4c38 21d 2446 25a 2a61 e002 c000
[14:10:36.281] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a116 8000 4c38 4c38 21d 2440 4cb 2688 719 2443 805 2248 4c38 1a 2044 21d 2480 41b 2049 543 2a6d 4c38 21d 2445 200 282d e002 c000
[14:10:36.281] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a117 8040 4c38 b 2a46 21d 244a 541 242c 65b 246f 4c38 89 244d 21d 2444 849 262f 84d 2689 4c38 21d 2449 6da 2a40 754 2449 4c38 21d 242a 29b 2669 e002 c000
[14:10:36.282] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a118 80b1 4c38 122 284e 21d 2466 545 2a48 4c38 9c 242f 4c3b 2426 4c38 21d 2485 241 2662 85d 2265 4c38 9c 2a48 21d 2461 e002 c000
[14:10:36.282] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a119 80c0 4c38 14 262e 21d 2465 604 222d 4c39 21d 244e 652 246c 4c38 21d 2480 240 2a6b 50c 266a 4c38 21d 2465 e002 c000
[14:10:36.282] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11a 8000 4c39 21d 2464 4c39 21d 242f 20a 204f 415 2663 61a 2a4e 4c39 21d 2466 4c39 21d 244f 250 2243 fff 2fff e002 c000
[14:10:36.282] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 7 readouts!
[14:10:36.282] <TB0> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:10:36.282] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a122 8000 4c38 21d 2466 4c39 21d 242e 259 2043 680 280f 712 244e 4c39 21d 2481 319 2867 482 284f 4c39 14 2045 21d 2465 4c1 2248 e002 c000
[14:10:36.282] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11c 80b1 4c38 21d 244c 451 2469 4c38 21d 242f 4c38 101 2249 21d 2486 4c3 2060 801 206c 4c38 21d 2442 e002 c000
[14:10:36.282] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11d 80c0 4c38 19 244c 21d 2462 288 224e 320 2261 352 244e 4c39 48 2645 21d 2449 34d 2045 4c38 10a 2044 21d 2465 299 2249 34b 2866 4c38 5a 204f 21d 244f e002 c000
[14:10:36.282] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11e 8000 4c39 20 242c 21d 2465 6d4 2428 759 226c 4c3a 1c 242c 21d 2449 318 2a42 805 224e 4c39 c4 286a 21d 246f 2c4 2860 408 2a29 448 2849 51d 264a 653 264c 804 264d 4c39 112 2465 21d 2464 509 244a 694 2242 e002 c000
[14:10:36.282] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11f 8040 4c3a 21d 2468 40c 2462 751 2a63 4c38 21d 2445 4c3a 21d 2481 4c3a 21d 242f 620 204c 692 2044 e002 c000
[14:10:36.282] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a120 80b1 4c38 21d 2448 741 244a 4c39 21d 2447 4c38 21d 2468 70a 2a40 4c38 21d 2440 4d1 244f 4c0 2a45 e002 c000
[14:10:36.282] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a121 80c0 4c39 21d 244f 611 2a63 4c38 21d 244c 20a 204f 311 224d 60a 2864 64b 2663 4c39 21d 2480 494 2665 859 284c 4c39 108 2a6d 21d 244e 644 2029 e002 c000
[14:10:47.105] <TB0> INFO: 1090700 events read in total (103211ms).
[14:11:21.825] <TB0> INFO: 1453500 events read in total (137931ms).
[14:11:57.510] <TB0> INFO: 1815400 events read in total (173616ms).
[14:12:33.288] <TB0> INFO: 2176050 events read in total (209394ms).
[14:13:09.595] <TB0> INFO: 2534800 events read in total (245701ms).
[14:13:46.350] <TB0> INFO: 2891600 events read in total (282456ms).
[14:14:23.502] <TB0> INFO: 3245100 events read in total (319608ms).
[14:15:00.489] <TB0> INFO: 3597300 events read in total (356595ms).
[14:15:35.465] <TB0> INFO: 3948400 events read in total (391571ms).
[14:15:55.914] <TB0> INFO: 4160000 events read in total (412020ms).
[14:15:56.113] <TB0> INFO: Test took 413344ms.
[14:15:57.735] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:03.195] <TB0> INFO: dacScan step from 55 .. 74
[14:16:03.195] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[14:16:03.195] <TB0> INFO: run 1 of 1
[14:16:03.792] <TB0> INFO: Expecting 4160000 events.
[14:16:38.481] <TB0> INFO: 333100 events read in total (34161ms).
[14:17:12.218] <TB0> INFO: 666700 events read in total (67898ms).
[14:17:45.456] <TB0> INFO: 1000250 events read in total (101136ms).
[14:18:21.587] <TB0> INFO: 1334050 events read in total (137267ms).
[14:18:57.417] <TB0> INFO: 1667700 events read in total (173097ms).
[14:19:33.557] <TB0> INFO: 2001200 events read in total (209237ms).
[14:20:08.349] <TB0> INFO: 2334800 events read in total (244029ms).
[14:20:42.778] <TB0> INFO: 2668250 events read in total (278458ms).
[14:21:17.408] <TB0> INFO: 3001550 events read in total (313088ms).
[14:21:51.291] <TB0> INFO: 3334900 events read in total (346971ms).
[14:22:23.539] <TB0> INFO: 3668550 events read in total (379219ms).
[14:22:53.844] <TB0> INFO: 4001950 events read in total (409524ms).
[14:23:10.272] <TB0> INFO: 4160000 events read in total (425952ms).
[14:23:10.437] <TB0> INFO: Test took 427242ms.
[14:23:12.002] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:16.919] <TB0> INFO: dacScan step from 75 .. 75
[14:23:16.919] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[14:23:16.919] <TB0> INFO: run 1 of 1
[14:23:17.513] <TB0> INFO: Expecting 208000 events.
[14:23:42.487] <TB0> INFO: 208000 events read in total (24446ms).
[14:23:42.494] <TB0> INFO: Test took 25576ms.
[14:23:42.573] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:42.817] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:43.864] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:44.883] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:45.881] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:46.913] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:47.950] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:49.074] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:50.181] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:51.270] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:52.340] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:53.397] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:54.461] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:55.518] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:56.575] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:57.620] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:58.661] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:23:59.695] <TB0> INFO: PixTest:: pg_setup set to default.
[14:23:59.697] <TB0> INFO: vcal mean: 36.87 37.01 35.92 39.27 39.24 40.43 41.00 40.19 40.74 41.25 41.45 41.23 38.69 37.33 37.67 37.45
[14:23:59.697] <TB0> INFO: vcal RMS: 1.05 1.08 0.83 1.40 1.19 1.42 1.52 1.56 1.44 1.40 1.63 1.54 1.23 1.12 0.96 0.94
[14:23:59.697] <TB0> INFO: number of X-ray hits detected: 196818 201910 180121 304799 331688 410596 399730 428808 350035 363475 382775 314765 284367 161326 172709 165307
[14:23:59.697] <TB0> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:23:59.697] <TB0> INFO: X-ray hit rate [MHz/cm2]: 57.7 59.2 52.8 89.3 97.2 120.3 117.2 125.7 102.6 106.5 112.2 92.3 83.4 47.3 50.6 48.5
[14:23:59.697] <TB0> INFO: PixTestHighRate::doXNoiseMaps() done
[14:23:59.794] <TB0> INFO: enter test to run
[14:23:59.794] <TB0> INFO: test: no parameter change
[14:23:59.947] <TB0> QUIET: Connection to board 83 closed.
[14:24:00.026] <TB0> INFO: pXar: this is the end, my friend