[06:40:12.394] <TB0> INFO: *** Welcome to pxar ***
[06:40:12.394] <TB0> INFO: *** Today: 2015/08/24
[06:40:12.394] <TB0> INFO: readRocDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//dacParameters35_C15.dat
[06:40:12.395] <TB0> INFO: readTbmDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//tbmParameters_C0b.dat
[06:40:12.395] <TB0> INFO: readMaskFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//defaultMaskFile.dat
[06:40:12.395] <TB0> INFO: readTrimFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//trimParameters35_C15.dat
[06:40:12.462] <TB0> INFO: clk: 4
[06:40:12.462] <TB0> INFO: ctr: 4
[06:40:12.462] <TB0> INFO: sda: 19
[06:40:12.462] <TB0> INFO: tin: 9
[06:40:12.462] <TB0> INFO: level: 15
[06:40:12.462] <TB0> INFO: triggerdelay: 0
[06:40:12.462] <TB0> QUIET: Instanciating API for pxar prod-01+80~g16e673b
[06:40:12.462] <TB0> INFO: Log level: INFO
[06:40:12.475] <TB0> INFO: Found DTB DTB_WWXTQT
[06:40:12.485] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[06:40:12.488] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[06:40:12.491] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[06:40:14.006] <TB0> INFO: DUT info:
[06:40:14.006] <TB0> INFO: The DUT currently contains the following objects:
[06:40:14.006] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[06:40:14.006] <TB0> INFO: TBM Core alpha (0): 7 registers set
[06:40:14.006] <TB0> INFO: TBM Core beta (1): 7 registers set
[06:40:14.006] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:40:14.006] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.407] <TB0> INFO: enter 'restricted' command line mode
[06:40:14.407] <TB0> INFO: enter test to run
[06:40:14.407] <TB0> INFO: test: Highrate no parameter change
[06:40:14.407] <TB0> INFO: running: highrate
[06:40:14.407] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[06:40:14.533] <TB0> INFO: ----------------------------------------------------------------------
[06:40:14.533] <TB0> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[06:40:14.533] <TB0> INFO: ----------------------------------------------------------------------
[06:40:14.539] <TB0> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1/-1) hits flags = 384 (plus default)
[06:40:14.549] <TB0> INFO: dacScan step from 0 .. 0
[06:40:14.549] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:40:14.549] <TB0> INFO: run 1 of 1
[06:40:19.088] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 1 Number of ROCs (2) != Token Chain Length (4)
[06:40:19.088] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[06:40:26.487] <TB0> INFO: Test took 11938ms.
[06:40:27.937] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:40:28.241] <TB0> INFO: dacScan step from 1 .. 1
[06:40:28.241] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:40:28.241] <TB0> INFO: run 1 of 1
[06:40:42.613] <TB0> INFO: Test took 14371ms.
[06:40:44.175] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:40:44.435] <TB0> INFO: dacScan step from 2 .. 2
[06:40:44.435] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:40:44.435] <TB0> INFO: run 1 of 1
[06:40:59.565] <TB0> INFO: Test took 15130ms.
[06:41:01.001] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:41:01.235] <TB0> INFO: dacScan step from 3 .. 3
[06:41:01.235] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:41:01.235] <TB0> INFO: run 1 of 1
[06:41:15.173] <TB0> INFO: Test took 13937ms.
[06:41:16.541] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:41:16.770] <TB0> INFO: dacScan step from 4 .. 4
[06:41:16.770] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:41:16.770] <TB0> INFO: run 1 of 1
[06:41:30.240] <TB0> INFO: Test took 13470ms.
[06:41:31.706] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:41:31.965] <TB0> INFO: dacScan step from 5 .. 5
[06:41:31.965] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:41:31.965] <TB0> INFO: run 1 of 1
[06:41:44.303] <TB0> INFO: Test took 12338ms.
[06:41:45.739] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:41:45.982] <TB0> INFO: dacScan step from 6 .. 6
[06:41:45.982] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:41:45.982] <TB0> INFO: run 1 of 1
[06:41:59.546] <TB0> INFO: Test took 13564ms.
[06:42:00.981] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:42:01.207] <TB0> INFO: dacScan step from 7 .. 7
[06:42:01.208] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:42:01.208] <TB0> INFO: run 1 of 1
[06:42:15.570] <TB0> INFO: Test took 14362ms.
[06:42:17.201] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:42:17.432] <TB0> INFO: dacScan step from 8 .. 8
[06:42:17.432] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:42:17.432] <TB0> INFO: run 1 of 1
[06:42:32.606] <TB0> INFO: Test took 15174ms.
[06:42:34.049] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:42:34.284] <TB0> INFO: dacScan step from 9 .. 9
[06:42:34.284] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:42:34.284] <TB0> INFO: run 1 of 1
[06:42:48.153] <TB0> INFO: Test took 13868ms.
[06:42:49.534] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:42:49.770] <TB0> INFO: dacScan step from 10 .. 10
[06:42:49.770] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:42:49.770] <TB0> INFO: run 1 of 1
[06:43:01.860] <TB0> INFO: Test took 12090ms.
[06:43:03.213] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:43:03.442] <TB0> INFO: dacScan step from 11 .. 11
[06:43:03.442] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:43:03.442] <TB0> INFO: run 1 of 1
[06:43:15.756] <TB0> INFO: Test took 12313ms.
[06:43:17.169] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:43:17.413] <TB0> INFO: dacScan step from 12 .. 12
[06:43:17.413] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:43:17.413] <TB0> INFO: run 1 of 1
[06:43:30.854] <TB0> INFO: Test took 13440ms.
[06:43:32.230] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:43:32.453] <TB0> INFO: dacScan step from 13 .. 13
[06:43:32.453] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:43:32.453] <TB0> INFO: run 1 of 1
[06:43:47.067] <TB0> INFO: Test took 14614ms.
[06:43:48.460] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:43:48.696] <TB0> INFO: dacScan step from 14 .. 14
[06:43:48.696] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:43:48.696] <TB0> INFO: run 1 of 1
[06:44:02.617] <TB0> INFO: Test took 13921ms.
[06:44:04.056] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:44:04.303] <TB0> INFO: dacScan step from 15 .. 15
[06:44:04.304] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:44:04.304] <TB0> INFO: run 1 of 1
[06:44:16.974] <TB0> INFO: Test took 12670ms.
[06:44:18.441] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:44:18.678] <TB0> INFO: dacScan step from 16 .. 16
[06:44:18.678] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:44:18.678] <TB0> INFO: run 1 of 1
[06:44:31.244] <TB0> INFO: Test took 12566ms.
[06:44:32.724] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:44:32.971] <TB0> INFO: dacScan step from 17 .. 17
[06:44:32.972] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:44:32.972] <TB0> INFO: run 1 of 1
[06:44:46.078] <TB0> INFO: Test took 13106ms.
[06:44:47.650] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:44:47.911] <TB0> INFO: dacScan step from 18 .. 18
[06:44:47.911] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:44:47.911] <TB0> INFO: run 1 of 1
[06:45:02.167] <TB0> INFO: Test took 14255ms.
[06:45:03.748] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:45:03.976] <TB0> INFO: dacScan step from 19 .. 19
[06:45:03.976] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:45:03.976] <TB0> INFO: run 1 of 1
[06:45:17.991] <TB0> INFO: Test took 14014ms.
[06:45:19.390] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:45:19.626] <TB0> INFO: dacScan step from 20 .. 20
[06:45:19.626] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:45:19.626] <TB0> INFO: run 1 of 1
[06:45:32.652] <TB0> INFO: Test took 13026ms.
[06:45:34.254] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:45:34.608] <TB0> INFO: dacScan step from 21 .. 21
[06:45:34.608] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:45:34.608] <TB0> INFO: run 1 of 1
[06:45:46.673] <TB0> INFO: Test took 12064ms.
[06:45:48.177] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:45:48.418] <TB0> INFO: dacScan step from 22 .. 22
[06:45:48.418] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:45:48.418] <TB0> INFO: run 1 of 1
[06:46:01.794] <TB0> INFO: Test took 13375ms.
[06:46:03.177] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:46:03.401] <TB0> INFO: dacScan step from 23 .. 23
[06:46:03.401] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:46:03.401] <TB0> INFO: run 1 of 1
[06:46:18.253] <TB0> INFO: Test took 14852ms.
[06:46:19.659] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:46:19.891] <TB0> INFO: dacScan step from 24 .. 24
[06:46:19.891] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:46:19.891] <TB0> INFO: run 1 of 1
[06:46:35.261] <TB0> INFO: Test took 15369ms.
[06:46:36.812] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:46:37.043] <TB0> INFO: dacScan step from 25 .. 25
[06:46:37.043] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:46:37.043] <TB0> INFO: run 1 of 1
[06:46:51.201] <TB0> INFO: Test took 14158ms.
[06:46:52.695] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:46:52.942] <TB0> INFO: dacScan step from 26 .. 26
[06:46:52.942] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:46:52.942] <TB0> INFO: run 1 of 1
[06:47:05.476] <TB0> INFO: Test took 12534ms.
[06:47:06.976] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:47:07.244] <TB0> INFO: dacScan step from 27 .. 27
[06:47:07.244] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:47:07.244] <TB0> INFO: run 1 of 1
[06:47:19.734] <TB0> INFO: Test took 12489ms.
[06:47:21.183] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:47:21.410] <TB0> INFO: dacScan step from 28 .. 28
[06:47:21.410] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:47:21.410] <TB0> INFO: run 1 of 1
[06:47:35.564] <TB0> INFO: Test took 14153ms.
[06:47:37.202] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:47:37.457] <TB0> INFO: dacScan step from 29 .. 29
[06:47:37.457] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:47:37.458] <TB0> INFO: run 1 of 1
[06:47:51.989] <TB0> INFO: Test took 14531ms.
[06:47:53.404] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:47:53.627] <TB0> INFO: dacScan step from 30 .. 30
[06:47:53.627] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:47:53.627] <TB0> INFO: run 1 of 1
[06:48:08.042] <TB0> INFO: Test took 14415ms.
[06:48:09.445] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:48:09.695] <TB0> INFO: dacScan step from 31 .. 31
[06:48:09.695] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:48:09.695] <TB0> INFO: run 1 of 1
[06:48:22.740] <TB0> INFO: Test took 13044ms.
[06:48:24.182] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:48:24.423] <TB0> INFO: dacScan step from 32 .. 32
[06:48:24.424] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:48:24.424] <TB0> INFO: run 1 of 1
[06:48:36.099] <TB0> INFO: Test took 11675ms.
[06:48:37.477] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:48:37.702] <TB0> INFO: dacScan step from 33 .. 33
[06:48:37.702] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:48:37.703] <TB0> INFO: run 1 of 1
[06:48:51.056] <TB0> INFO: Test took 13353ms.
[06:48:52.675] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:48:52.954] <TB0> INFO: dacScan step from 34 .. 34
[06:48:52.954] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:48:52.954] <TB0> INFO: run 1 of 1
[06:49:08.239] <TB0> INFO: Test took 15284ms.
[06:49:09.657] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:49:09.885] <TB0> INFO: dacScan step from 35 .. 35
[06:49:09.885] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:49:09.885] <TB0> INFO: run 1 of 1
[06:49:25.116] <TB0> INFO: Test took 15231ms.
[06:49:26.640] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:49:26.881] <TB0> INFO: dacScan step from 36 .. 36
[06:49:26.881] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:49:26.881] <TB0> INFO: run 1 of 1
[06:49:40.862] <TB0> INFO: Test took 13981ms.
[06:49:42.306] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:49:42.557] <TB0> INFO: dacScan step from 37 .. 37
[06:49:42.557] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:49:42.557] <TB0> INFO: run 1 of 1
[06:49:55.545] <TB0> INFO: Test took 12988ms.
[06:49:57.089] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:49:57.333] <TB0> INFO: dacScan step from 38 .. 38
[06:49:57.333] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:49:57.333] <TB0> INFO: run 1 of 1
[06:50:10.572] <TB0> INFO: Test took 13238ms.
[06:50:12.148] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:50:12.397] <TB0> INFO: dacScan step from 39 .. 39
[06:50:12.397] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:50:12.397] <TB0> INFO: run 1 of 1
[06:50:26.683] <TB0> INFO: Test took 14285ms.
[06:50:28.155] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:50:28.407] <TB0> INFO: dacScan step from 40 .. 40
[06:50:28.407] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:50:28.407] <TB0> INFO: run 1 of 1
[06:50:44.016] <TB0> INFO: Test took 15608ms.
[06:50:46.029] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:50:46.311] <TB0> INFO: dacScan step from 41 .. 41
[06:50:46.311] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:50:46.311] <TB0> INFO: run 1 of 1
[06:51:01.596] <TB0> INFO: Test took 15284ms.
[06:51:03.373] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:51:03.613] <TB0> INFO: dacScan step from 42 .. 42
[06:51:03.613] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:51:03.613] <TB0> INFO: run 1 of 1
[06:51:20.412] <TB0> INFO: Test took 16798ms.
[06:51:22.018] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:51:22.250] <TB0> INFO: dacScan step from 43 .. 43
[06:51:22.250] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:51:22.250] <TB0> INFO: run 1 of 1
[06:51:38.872] <TB0> INFO: Test took 16622ms.
[06:51:40.521] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:51:40.794] <TB0> INFO: dacScan step from 44 .. 44
[06:51:40.794] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:51:40.794] <TB0> INFO: run 1 of 1
[06:51:57.422] <TB0> INFO: Test took 16628ms.
[06:51:59.170] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:51:59.416] <TB0> INFO: dacScan step from 45 .. 45
[06:51:59.417] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:51:59.417] <TB0> INFO: run 1 of 1
[06:52:16.400] <TB0> INFO: Test took 16983ms.
[06:52:18.127] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:52:18.394] <TB0> INFO: dacScan step from 46 .. 46
[06:52:18.394] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:52:18.394] <TB0> INFO: run 1 of 1
[06:52:35.309] <TB0> INFO: Test took 16914ms.
[06:52:37.045] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:52:37.302] <TB0> INFO: dacScan step from 47 .. 47
[06:52:37.303] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:52:37.303] <TB0> INFO: run 1 of 1
[06:52:54.502] <TB0> INFO: Test took 17199ms.
[06:52:56.207] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:52:56.449] <TB0> INFO: dacScan step from 48 .. 48
[06:52:56.449] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:52:56.449] <TB0> INFO: run 1 of 1
[06:53:13.636] <TB0> INFO: Test took 17186ms.
[06:53:15.332] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:53:15.589] <TB0> INFO: dacScan step from 49 .. 49
[06:53:15.589] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:53:15.589] <TB0> INFO: run 1 of 1
[06:53:32.272] <TB0> INFO: Test took 16682ms.
[06:53:33.979] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:53:34.256] <TB0> INFO: dacScan step from 50 .. 50
[06:53:34.256] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:53:34.256] <TB0> INFO: run 1 of 1
[06:53:51.400] <TB0> INFO: Test took 17144ms.
[06:53:53.177] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:53:53.436] <TB0> INFO: dacScan step from 51 .. 51
[06:53:53.436] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:53:53.436] <TB0> INFO: run 1 of 1
[06:54:09.797] <TB0> INFO: Test took 16360ms.
[06:54:11.721] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:54:11.990] <TB0> INFO: dacScan step from 52 .. 52
[06:54:11.990] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:54:11.990] <TB0> INFO: run 1 of 1
[06:54:28.079] <TB0> INFO: Test took 16089ms.
[06:54:29.943] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:54:30.211] <TB0> INFO: dacScan step from 53 .. 53
[06:54:30.211] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:54:30.211] <TB0> INFO: run 1 of 1
[06:54:45.700] <TB0> INFO: Test took 15488ms.
[06:54:47.504] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:54:47.759] <TB0> INFO: dacScan step from 54 .. 54
[06:54:47.759] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:54:47.759] <TB0> INFO: run 1 of 1
[06:55:03.604] <TB0> INFO: Test took 15845ms.
[06:55:05.392] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:55:05.641] <TB0> INFO: dacScan step from 55 .. 55
[06:55:05.641] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:55:05.641] <TB0> INFO: run 1 of 1
[06:55:21.607] <TB0> INFO: Test took 15965ms.
[06:55:23.517] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:55:23.789] <TB0> INFO: dacScan step from 56 .. 56
[06:55:23.789] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:55:23.789] <TB0> INFO: run 1 of 1
[06:55:40.293] <TB0> INFO: Test took 16503ms.
[06:55:42.340] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:55:42.634] <TB0> INFO: dacScan step from 57 .. 57
[06:55:42.634] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:55:42.634] <TB0> INFO: run 1 of 1
[06:56:01.427] <TB0> INFO: Test took 18792ms.
[06:56:03.169] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:56:03.423] <TB0> INFO: dacScan step from 58 .. 58
[06:56:03.423] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:56:03.423] <TB0> INFO: run 1 of 1
[06:56:22.509] <TB0> INFO: Test took 19085ms.
[06:56:24.283] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:56:24.519] <TB0> INFO: dacScan step from 59 .. 59
[06:56:24.519] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:56:24.519] <TB0> INFO: run 1 of 1
[06:56:42.188] <TB0> INFO: Test took 17668ms.
[06:56:44.002] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:56:44.269] <TB0> INFO: dacScan step from 60 .. 60
[06:56:44.269] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:56:44.269] <TB0> INFO: run 1 of 1
[06:57:00.767] <TB0> INFO: Test took 16497ms.
[06:57:02.721] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:57:03.026] <TB0> INFO: dacScan step from 61 .. 61
[06:57:03.026] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:57:03.026] <TB0> INFO: run 1 of 1
[06:57:18.483] <TB0> INFO: Test took 15456ms.
[06:57:20.258] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:57:20.526] <TB0> INFO: dacScan step from 62 .. 62
[06:57:20.526] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:57:20.526] <TB0> INFO: run 1 of 1
[06:57:36.731] <TB0> INFO: Test took 16205ms.
[06:57:38.676] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:57:38.935] <TB0> INFO: dacScan step from 63 .. 63
[06:57:38.935] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:57:38.935] <TB0> INFO: run 1 of 1
[06:57:56.289] <TB0> INFO: Test took 17354ms.
[06:57:57.945] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:57:58.190] <TB0> INFO: dacScan step from 64 .. 64
[06:57:58.190] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:57:58.190] <TB0> INFO: run 1 of 1
[06:58:16.700] <TB0> INFO: Test took 18509ms.
[06:58:18.429] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:58:18.679] <TB0> INFO: dacScan step from 65 .. 65
[06:58:18.679] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:58:18.679] <TB0> INFO: run 1 of 1
[06:58:36.380] <TB0> INFO: Test took 17701ms.
[06:58:38.186] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:58:38.464] <TB0> INFO: dacScan step from 66 .. 66
[06:58:38.464] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:58:38.464] <TB0> INFO: run 1 of 1
[06:58:55.684] <TB0> INFO: Test took 17219ms.
[06:58:57.477] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:58:57.757] <TB0> INFO: dacScan step from 67 .. 67
[06:58:57.757] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:58:57.757] <TB0> INFO: run 1 of 1
[06:59:13.610] <TB0> INFO: Test took 15852ms.
[06:59:15.569] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:59:15.820] <TB0> INFO: dacScan step from 68 .. 68
[06:59:15.820] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:59:15.820] <TB0> INFO: run 1 of 1
[06:59:31.747] <TB0> INFO: Test took 15927ms.
[06:59:33.451] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:59:33.684] <TB0> INFO: dacScan step from 69 .. 69
[06:59:33.684] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:59:33.684] <TB0> INFO: run 1 of 1
[06:59:50.414] <TB0> INFO: Test took 16729ms.
[06:59:52.415] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:59:52.710] <TB0> INFO: dacScan step from 70 .. 70
[06:59:52.710] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:59:52.710] <TB0> INFO: run 1 of 1
[07:00:10.633] <TB0> INFO: Test took 17923ms.
[07:00:12.234] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:00:12.474] <TB0> INFO: dacScan step from 71 .. 71
[07:00:12.474] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:00:12.474] <TB0> INFO: run 1 of 1
[07:00:31.308] <TB0> INFO: Test took 18833ms.
[07:00:33.014] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:00:33.270] <TB0> INFO: dacScan step from 72 .. 72
[07:00:33.270] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:00:33.270] <TB0> INFO: run 1 of 1
[07:00:50.657] <TB0> INFO: Test took 17386ms.
[07:00:52.502] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:00:52.747] <TB0> INFO: dacScan step from 73 .. 73
[07:00:52.747] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:00:52.751] <TB0> INFO: run 1 of 1
[07:01:08.990] <TB0> INFO: Test took 16238ms.
[07:01:10.739] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:01:10.988] <TB0> INFO: dacScan step from 74 .. 74
[07:01:10.988] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:01:10.988] <TB0> INFO: run 1 of 1
[07:01:26.097] <TB0> INFO: Test took 15108ms.
[07:01:27.821] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:01:28.068] <TB0> INFO: dacScan step from 75 .. 75
[07:01:28.068] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:01:28.068] <TB0> INFO: run 1 of 1
[07:01:43.764] <TB0> INFO: Test took 15695ms.
[07:01:45.526] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:01:45.775] <TB0> INFO: dacScan step from 76 .. 76
[07:01:45.775] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:01:45.775] <TB0> INFO: run 1 of 1
[07:02:03.734] <TB0> INFO: Test took 17958ms.
[07:02:05.395] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:02:05.637] <TB0> INFO: dacScan step from 77 .. 77
[07:02:05.637] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:02:05.637] <TB0> INFO: run 1 of 1
[07:02:24.192] <TB0> INFO: Test took 18554ms.
[07:02:25.874] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:02:26.118] <TB0> INFO: dacScan step from 78 .. 78
[07:02:26.118] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:02:26.118] <TB0> INFO: run 1 of 1
[07:02:43.693] <TB0> INFO: Test took 17574ms.
[07:02:45.496] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:02:45.756] <TB0> INFO: dacScan step from 79 .. 79
[07:02:45.756] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:02:45.756] <TB0> INFO: run 1 of 1
[07:03:02.650] <TB0> INFO: Test took 16894ms.
[07:03:04.470] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:03:04.766] <TB0> INFO: dacScan step from 80 .. 80
[07:03:04.766] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:03:04.766] <TB0> INFO: run 1 of 1
[07:03:19.997] <TB0> INFO: Test took 15230ms.
[07:03:21.772] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:03:22.020] <TB0> INFO: dacScan step from 81 .. 81
[07:03:22.020] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:03:22.020] <TB0> INFO: run 1 of 1
[07:03:38.277] <TB0> INFO: Test took 16256ms.
[07:03:40.136] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:03:40.383] <TB0> INFO: dacScan step from 82 .. 82
[07:03:40.384] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:03:40.384] <TB0> INFO: run 1 of 1
[07:03:57.860] <TB0> INFO: Test took 17476ms.
[07:03:59.628] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:03:59.875] <TB0> INFO: dacScan step from 83 .. 83
[07:03:59.875] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:03:59.875] <TB0> INFO: run 1 of 1
[07:04:18.044] <TB0> INFO: Test took 18168ms.
[07:04:19.814] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:04:20.068] <TB0> INFO: dacScan step from 84 .. 84
[07:04:20.068] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:04:20.068] <TB0> INFO: run 1 of 1
[07:04:37.948] <TB0> INFO: Test took 17880ms.
[07:04:39.799] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:04:40.064] <TB0> INFO: dacScan step from 85 .. 85
[07:04:40.064] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:04:40.064] <TB0> INFO: run 1 of 1
[07:04:56.968] <TB0> INFO: Test took 16903ms.
[07:04:58.697] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:04:58.952] <TB0> INFO: dacScan step from 86 .. 86
[07:04:58.952] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:04:58.952] <TB0> INFO: run 1 of 1
[07:05:14.378] <TB0> INFO: Test took 15425ms.
[07:05:16.147] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:05:16.405] <TB0> INFO: dacScan step from 87 .. 87
[07:05:16.405] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:05:16.405] <TB0> INFO: run 1 of 1
[07:05:32.280] <TB0> INFO: Test took 15874ms.
[07:05:34.093] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:05:34.349] <TB0> INFO: dacScan step from 88 .. 88
[07:05:34.349] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:05:34.349] <TB0> INFO: run 1 of 1
[07:05:51.135] <TB0> INFO: Test took 16785ms.
[07:05:52.769] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:05:53.025] <TB0> INFO: dacScan step from 89 .. 89
[07:05:53.025] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:05:53.025] <TB0> INFO: run 1 of 1
[07:06:11.611] <TB0> INFO: Test took 18584ms.
[07:06:13.560] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:06:13.796] <TB0> INFO: dacScan step from 90 .. 90
[07:06:13.796] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:06:13.796] <TB0> INFO: run 1 of 1
[07:06:31.907] <TB0> INFO: Test took 18110ms.
[07:06:33.640] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:06:33.873] <TB0> INFO: dacScan step from 91 .. 91
[07:06:33.873] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:06:33.873] <TB0> INFO: run 1 of 1
[07:06:51.127] <TB0> INFO: Test took 17253ms.
[07:06:52.863] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:06:53.104] <TB0> INFO: dacScan step from 92 .. 92
[07:06:53.105] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:06:53.105] <TB0> INFO: run 1 of 1
[07:07:08.158] <TB0> INFO: Test took 15053ms.
[07:07:09.797] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:07:10.049] <TB0> INFO: dacScan step from 93 .. 93
[07:07:10.049] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:07:10.049] <TB0> INFO: run 1 of 1
[07:07:25.702] <TB0> INFO: Test took 15652ms.
[07:07:27.392] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:07:27.649] <TB0> INFO: dacScan step from 94 .. 94
[07:07:27.649] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:07:27.649] <TB0> INFO: run 1 of 1
[07:07:44.346] <TB0> INFO: Test took 16696ms.
[07:07:46.387] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:07:46.673] <TB0> INFO: dacScan step from 95 .. 95
[07:07:46.680] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:07:46.680] <TB0> INFO: run 1 of 1
[07:08:03.261] <TB0> INFO: Test took 16580ms.
[07:08:04.824] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:08:05.129] <TB0> INFO: dacScan step from 96 .. 96
[07:08:05.129] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:08:05.129] <TB0> INFO: run 1 of 1
[07:08:24.694] <TB0> INFO: Test took 19564ms.
[07:08:26.368] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:08:26.686] <TB0> INFO: dacScan step from 97 .. 97
[07:08:26.686] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:08:26.686] <TB0> INFO: run 1 of 1
[07:08:44.014] <TB0> INFO: Test took 17327ms.
[07:08:46.009] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:08:46.264] <TB0> INFO: dacScan step from 98 .. 98
[07:08:46.264] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:08:46.264] <TB0> INFO: run 1 of 1
[07:09:02.896] <TB0> INFO: Test took 16631ms.
[07:09:04.540] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:09:04.795] <TB0> INFO: dacScan step from 99 .. 99
[07:09:04.795] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:09:04.795] <TB0> INFO: run 1 of 1
[07:09:20.315] <TB0> INFO: Test took 15519ms.
[07:09:21.896] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:09:22.129] <TB0> INFO: dacScan step from 100 .. 100
[07:09:22.129] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:09:22.129] <TB0> INFO: run 1 of 1
[07:09:38.656] <TB0> INFO: Test took 16526ms.
[07:09:40.269] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:09:40.498] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:41.948] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:43.422] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:44.816] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:46.252] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:47.601] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:49.156] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:50.592] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:52.091] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:53.515] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:55.024] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:56.577] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:57.967] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:59.510] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:00.844] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:02.199] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:03.613] <TB0> INFO: PixTest:: pg_setup set to default.
[07:10:03.614] <TB0> INFO: vcal mean: 38.76 38.01 37.70 40.39 41.40 42.56 42.42 42.87 41.91 42.14 41.59 40.75 40.06 37.58 37.98 37.77
[07:10:03.614] <TB0> INFO: vcal RMS: 1.30 1.24 1.02 2.07 1.59 1.45 1.54 1.50 1.49 1.50 1.54 1.53 1.32 1.30 1.29 1.30
[07:10:03.614] <TB0> INFO: number of X-ray hits detected: 184073 181110 163356 283760 331824 386035 370330 394085 356166 359426 351706 299243 269402 148352 161610 154065
[07:10:03.614] <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
[07:10:03.614] <TB0> INFO: X-ray hit rate [MHz/cm2]: 54.0 53.1 47.9 83.2 97.3 113.1 108.5 115.5 104.4 105.4 103.1 87.7 79.0 43.5 47.4 45.2
[07:10:03.614] <TB0> INFO: PixTestHighRate::doXNoiseMaps() done
[07:10:03.734] <TB0> INFO: enter test to run
[07:10:03.734] <TB0> INFO: test: no parameter change
[07:10:04.010] <TB0> QUIET: Connection to board 147 closed.
[07:10:04.089] <TB0> INFO: pXar: this is the end, my friend