[10:35:36.648] <TB0> INFO: *** Welcome to pxar ***
[10:35:36.648] <TB0> INFO: *** Today: 2015/09/10
[10:35:36.648] <TB0> INFO: readRocDacs: /usr/local/data/M2049_XrayQualification_2015-09-10_11h57m_1441879041//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2049_XrayQualification_2015-09-10_11h57m_1441879041//005_HRSCurves_100//dacParameters35_C15.dat
[10:35:36.648] <TB0> INFO: readTbmDacs: /usr/local/data/M2049_XrayQualification_2015-09-10_11h57m_1441879041//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2049_XrayQualification_2015-09-10_11h57m_1441879041//005_HRSCurves_100//tbmParameters_C0b.dat
[10:35:36.649] <TB0> INFO: readMaskFile: /usr/local/data/M2049_XrayQualification_2015-09-10_11h57m_1441879041//005_HRSCurves_100//defaultMaskFile.dat
[10:35:36.649] <TB0> INFO: readTrimFile: /usr/local/data/M2049_XrayQualification_2015-09-10_11h57m_1441879041//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2049_XrayQualification_2015-09-10_11h57m_1441879041//005_HRSCurves_100//trimParameters35_C15.dat
[10:35:36.716] <TB0> INFO: clk: 4
[10:35:36.716] <TB0> INFO: ctr: 4
[10:35:36.716] <TB0> INFO: sda: 19
[10:35:36.716] <TB0> INFO: tin: 9
[10:35:36.716] <TB0> INFO: level: 15
[10:35:36.716] <TB0> INFO: triggerdelay: 0
[10:35:36.716] <TB0> QUIET: Instanciating API for pxar prod-10+24~g09f6d2c
[10:35:36.716] <TB0> INFO: Log level: INFO
[10:35:37.726] <TB0> INFO: Found DTB DTB_WS6UZO
[10:35:40.746] <TB0> QUIET: Connection to board DTB_WS6UZO opened.
[10:35:40.749] <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:
------------------------------------------------------
[10:35:40.751] <TB0> INFO: RPC call hashes of host and DTB match: 398089610
[10:35:42.261] <TB0> INFO: DUT info:
[10:35:42.261] <TB0> INFO: The DUT currently contains the following objects:
[10:35:42.261] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[10:35:42.261] <TB0> INFO: TBM Core alpha (0): 7 registers set
[10:35:42.261] <TB0> INFO: TBM Core beta (1): 7 registers set
[10:35:42.261] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:35:42.261] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.261] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.261] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.261] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.261] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.261] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.261] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.261] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.261] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.261] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.261] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.261] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.261] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.262] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.262] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.262] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:35:42.662] <TB0> INFO: enter 'restricted' command line mode
[10:35:42.662] <TB0> INFO: enter test to run
[10:35:42.662] <TB0> INFO: test: Highrate no parameter change
[10:35:42.662] <TB0> INFO: running: highrate
[10:35:42.663] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2049_XrayQualification_2015-09-10_11h57m_1441879041//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2049_XrayQualification_2015-09-10_11h57m_1441879041//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[10:35:42.788] <TB0> INFO: ----------------------------------------------------------------------
[10:35:42.788] <TB0> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[10:35:42.788] <TB0> INFO: ----------------------------------------------------------------------
[10:35:42.794] <TB0> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 15 .. 75 (20/-1) hits flags = 896 (plus default)
[10:35:42.804] <TB0> INFO: dacScan step from 15 .. 34
[10:35:42.804] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[10:35:42.804] <TB0> INFO: run 1 of 1
[10:35:43.392] <TB0> INFO: Expecting 4160000 events.
[10:36:21.928] <TB0> INFO: 468600 events read in total (38006ms).
[10:36:57.888] <TB0> INFO: 939400 events read in total (73966ms).
[10:37:32.263] <TB0> INFO: 1410400 events read in total (108341ms).
[10:38:07.446] <TB0> INFO: 1881450 events read in total (143524ms).
[10:38:41.676] <TB0> INFO: 2352650 events read in total (177754ms).
[10:39:16.610] <TB0> INFO: 2823850 events read in total (212688ms).
[10:39:52.544] <TB0> INFO: 3294450 events read in total (248622ms).
[10:40:27.443] <TB0> INFO: 3765150 events read in total (283521ms).
[10:40:44.016] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L484> Channel 2 Number of ROCs (5) != Token Chain Length (4)
[10:40:44.017] <TB0> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:40:44.017] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e3 8040 4e08 0 2a22 48a 286d 48a 242f 4e09 552 204f 543 244f 4e08 4e09 e002 c000
[10:40:44.017] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2dd 80c0 4e08 30a 2246 4e08 9c 2a4f 2c5 2045 4cc 224c 60b 224e 4e09 9 264d 31d 262d 4e08 118 264f 152 2a45 285 264d e002 c000
[10:40:44.017] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2de 8000 4e09 80c 2640 4e09 10 2824 558 2246 600 260f 4e0b 650 2623 4e09 141 2641 841 2449 e002 c000
[10:40:44.017] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2df 8040 4e0b d8 2a40 4e0b 59 2047 8a 2242 28c 244f 4e09 6d4 2641 6d5 2640 75d 2a4d 4e0b 5c 2425 358 244a e002 c000
[10:40:44.017] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e0 80b1 4e09 4e09 8b 286d 643 224f 4e08 e0 2245 71c 2043 4e09 4e0b 2044 d 2062 3 2864 698 264d e002 c000
[10:40:44.017] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e1 80c0 4e08 103 206c 20a 282a 4e08 d 2842 cb 206d 4e09 99 282f 205 2842 621 244d 64c 2642 4e08 e002 c000
[10:40:44.017] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e2 8000 4e08 160 244c 848 2645 4e08 83 2466 4e08 208 2825 618 2245 4e09 118 224c 118 2649 310 224c 544 2241 e002 c000
[10:40:56.288] <TB0> INFO: 4160000 events read in total (312366ms).
[10:40:56.554] <TB0> INFO: Test took 313749ms.
[10:41:00.998] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:05.995] <TB0> INFO: dacScan step from 35 .. 54
[10:41:05.995] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[10:41:05.995] <TB0> INFO: run 1 of 1
[10:41:06.714] <TB0> INFO: Expecting 4160000 events.
[10:41:40.772] <TB0> INFO: 389100 events read in total (33525ms).
[10:42:14.984] <TB0> INFO: 778900 events read in total (67737ms).
[10:42:46.069] <TB0> INFO: 1167800 events read in total (98822ms).
[10:43:19.332] <TB0> INFO: 1555950 events read in total (132085ms).
[10:43:53.994] <TB0> INFO: 1943500 events read in total (166747ms).
[10:44:28.008] <TB0> INFO: 2329050 events read in total (200761ms).
[10:45:02.042] <TB0> INFO: 2712050 events read in total (234795ms).
[10:45:36.543] <TB0> INFO: 3092250 events read in total (269296ms).
[10:46:12.787] <TB0> INFO: 3469950 events read in total (305541ms).
[10:46:48.770] <TB0> INFO: 3845700 events read in total (341523ms).
[10:47:18.193] <TB0> INFO: 4160000 events read in total (370946ms).
[10:47:18.389] <TB0> INFO: Test took 372394ms.
[10:47:22.458] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:47:27.408] <TB0> INFO: dacScan step from 55 .. 74
[10:47:27.408] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[10:47:27.408] <TB0> INFO: run 1 of 1
[10:47:28.003] <TB0> INFO: Expecting 4160000 events.
[10:48:02.583] <TB0> INFO: 345850 events read in total (34052ms).
[10:48:38.101] <TB0> INFO: 692700 events read in total (69570ms).
[10:49:13.293] <TB0> INFO: 1039500 events read in total (104762ms).
[10:49:48.063] <TB0> INFO: 1386250 events read in total (139532ms).
[10:50:18.184] <TB0> INFO: 1733150 events read in total (169653ms).
[10:50:53.454] <TB0> INFO: 2079900 events read in total (204923ms).
[10:51:29.011] <TB0> INFO: 2426500 events read in total (240480ms).
[10:52:04.087] <TB0> INFO: 2773100 events read in total (275556ms).
[10:52:39.012] <TB0> INFO: 3119750 events read in total (310481ms).
[10:53:12.658] <TB0> INFO: 3466400 events read in total (344127ms).
[10:53:45.918] <TB0> INFO: 3813200 events read in total (377387ms).
[10:54:16.943] <TB0> INFO: 4159600 events read in total (408412ms).
[10:54:17.288] <TB0> INFO: 4160000 events read in total (408757ms).
[10:54:17.459] <TB0> INFO: Test took 410050ms.
[10:54:18.948] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:23.526] <TB0> INFO: dacScan step from 75 .. 75
[10:54:23.526] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[10:54:23.526] <TB0> INFO: run 1 of 1
[10:54:24.126] <TB0> INFO: Expecting 208000 events.
[10:54:47.464] <TB0> INFO: 208000 events read in total (22811ms).
[10:54:47.470] <TB0> INFO: Test took 23944ms.
[10:54:47.544] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:47.770] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:54:48.859] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:54:49.903] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:54:50.934] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:54:52.025] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:54:53.079] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:54:54.171] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:54:55.346] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:54:56.473] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:54:57.576] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:54:58.600] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:54:59.645] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:55:00.685] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:55:01.720] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:55:02.728] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:55:03.739] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[10:55:04.750] <TB0> INFO: PixTest:: pg_setup set to default.
[10:55:04.751] <TB0> INFO: vcal mean: 38.45 38.53 38.72 40.99 41.00 42.66 42.75 43.21 41.34 41.42 41.44 42.33 40.10 37.96 38.03 38.52
[10:55:04.751] <TB0> INFO: vcal RMS: 0.97 1.07 0.91 1.27 1.10 1.25 1.37 1.36 1.25 1.33 1.30 1.44 1.15 1.02 0.86 1.22
[10:55:04.752] <TB0> INFO: number of X-ray hits detected: 182934 198162 179059 296229 305459 367335 384596 381125 307470 324673 310277 315736 250329 150515 155712 156850
[10:55:04.752] <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
[10:55:04.752] <TB0> INFO: X-ray hit rate [MHz/cm2]: 53.6 58.1 52.5 86.8 89.5 107.7 112.7 111.7 90.1 95.2 90.9 92.5 73.4 44.1 45.6 46.0
[10:55:04.752] <TB0> INFO: PixTestHighRate::doXNoiseMaps() done
[10:55:04.849] <TB0> INFO: enter test to run
[10:55:04.849] <TB0> INFO: test: no parameter change
[10:55:05.070] <TB0> QUIET: Connection to board 83 closed.
[10:55:05.149] <TB0> INFO: pXar: this is the end, my friend