[12:41:58.157] <TB3> INFO: *** Welcome to pxar ***
[12:41:58.157] <TB3> INFO: *** Today: 2015/08/12
[12:41:58.157] <TB3> INFO: readRocDacs: /usr/local/data/M2058_XrayQualification_2015-08-12_14h10m_1439381432//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2058_XrayQualification_2015-08-12_14h10m_1439381432//005_HRSCurves_100//dacParameters35_C15.dat
[12:41:58.157] <TB3> INFO: readTbmDacs: /usr/local/data/M2058_XrayQualification_2015-08-12_14h10m_1439381432//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2058_XrayQualification_2015-08-12_14h10m_1439381432//005_HRSCurves_100//tbmParameters_C0b.dat
[12:41:58.158] <TB3> INFO: readMaskFile: /usr/local/data/M2058_XrayQualification_2015-08-12_14h10m_1439381432//005_HRSCurves_100//defaultMaskFile.dat
[12:41:58.158] <TB3> INFO: readTrimFile: /usr/local/data/M2058_XrayQualification_2015-08-12_14h10m_1439381432//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2058_XrayQualification_2015-08-12_14h10m_1439381432//005_HRSCurves_100//trimParameters35_C15.dat
[12:41:58.264] <TB3> INFO: clk: 4
[12:41:58.264] <TB3> INFO: ctr: 4
[12:41:58.264] <TB3> INFO: sda: 19
[12:41:58.264] <TB3> INFO: tin: 9
[12:41:58.264] <TB3> INFO: level: 15
[12:41:58.264] <TB3> INFO: triggerdelay: 0
[12:41:58.264] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[12:41:58.264] <TB3> INFO: Log level: INFO
[12:41:58.278] <TB3> INFO: Found DTB DTB_WWV6Z5
[12:41:58.295] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[12:41:58.299] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[12:41:58.301] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[12:41:59.903] <TB3> INFO: DUT info:
[12:41:59.903] <TB3> INFO: The DUT currently contains the following objects:
[12:41:59.903] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[12:41:59.903] <TB3> INFO: TBM Core alpha (0): 7 registers set
[12:41:59.903] <TB3> INFO: TBM Core beta (1): 7 registers set
[12:41:59.903] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:41:59.903] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:59.903] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:00.304] <TB3> INFO: enter 'restricted' command line mode
[12:42:00.304] <TB3> INFO: enter test to run
[12:42:00.304] <TB3> INFO: test: Highrate no parameter change
[12:42:00.304] <TB3> INFO: running: highrate
[12:42:00.305] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2058_XrayQualification_2015-08-12_14h10m_1439381432//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2058_XrayQualification_2015-08-12_14h10m_1439381432//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[12:42:00.482] <TB3> INFO: ----------------------------------------------------------------------
[12:42:00.483] <TB3> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[12:42:00.483] <TB3> INFO: ----------------------------------------------------------------------
[12:42:00.488] <TB3> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1) hits flags = 384 (plus default)
[12:42:00.499] <TB3> INFO: dacScan step from 0 .. 0
[12:42:14.213] <TB3> INFO: Test took 13714ms.
[12:42:15.609] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:15.827] <TB3> INFO: dacScan step from 1 .. 1
[12:42:29.128] <TB3> INFO: Test took 13301ms.
[12:42:30.379] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:30.675] <TB3> INFO: dacScan step from 2 .. 2
[12:42:43.869] <TB3> INFO: Test took 13194ms.
[12:42:45.260] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:45.478] <TB3> INFO: dacScan step from 3 .. 3
[12:42:58.663] <TB3> INFO: Test took 13185ms.
[12:43:00.162] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:00.402] <TB3> INFO: dacScan step from 4 .. 4
[12:43:13.629] <TB3> INFO: Test took 13227ms.
[12:43:14.935] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:15.188] <TB3> INFO: dacScan step from 5 .. 5
[12:43:28.338] <TB3> INFO: Test took 13150ms.
[12:43:29.652] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:29.878] <TB3> INFO: dacScan step from 6 .. 6
[12:43:43.127] <TB3> INFO: Test took 13248ms.
[12:43:44.400] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:44.620] <TB3> INFO: dacScan step from 7 .. 7
[12:43:57.991] <TB3> INFO: Test took 13370ms.
[12:43:59.342] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:59.593] <TB3> INFO: dacScan step from 8 .. 8
[12:44:12.713] <TB3> INFO: Test took 13119ms.
[12:44:14.028] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:14.270] <TB3> INFO: dacScan step from 9 .. 9
[12:44:26.923] <TB3> INFO: Test took 12652ms.
[12:44:28.247] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:28.478] <TB3> INFO: dacScan step from 10 .. 10
[12:44:41.408] <TB3> INFO: Test took 12930ms.
[12:44:42.622] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:42.842] <TB3> INFO: dacScan step from 11 .. 11
[12:44:55.859] <TB3> INFO: Test took 13017ms.
[12:44:57.204] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:57.453] <TB3> INFO: dacScan step from 12 .. 12
[12:45:10.496] <TB3> INFO: Test took 13042ms.
[12:45:11.744] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:11.970] <TB3> INFO: dacScan step from 13 .. 13
[12:45:24.986] <TB3> INFO: Test took 13016ms.
[12:45:26.259] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:26.476] <TB3> INFO: dacScan step from 14 .. 14
[12:45:39.163] <TB3> INFO: Test took 12686ms.
[12:45:40.417] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:40.649] <TB3> INFO: dacScan step from 15 .. 15
[12:45:53.700] <TB3> INFO: Test took 13050ms.
[12:45:55.034] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:55.261] <TB3> INFO: dacScan step from 16 .. 16
[12:46:08.187] <TB3> INFO: Test took 12925ms.
[12:46:09.510] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:09.736] <TB3> INFO: dacScan step from 17 .. 17
[12:46:22.768] <TB3> INFO: Test took 13031ms.
[12:46:24.133] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:24.390] <TB3> INFO: dacScan step from 18 .. 18
[12:46:29.097] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)
[12:46:37.498] <TB3> INFO: Test took 13107ms.
[12:46:38.889] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:39.120] <TB3> INFO: dacScan step from 19 .. 19
[12:46:52.235] <TB3> INFO: Test took 13115ms.
[12:46:53.506] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:53.741] <TB3> INFO: dacScan step from 20 .. 20
[12:47:06.563] <TB3> INFO: Test took 12822ms.
[12:47:07.875] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:08.122] <TB3> INFO: dacScan step from 21 .. 21
[12:47:20.976] <TB3> INFO: Test took 12854ms.
[12:47:22.469] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:22.699] <TB3> INFO: dacScan step from 22 .. 22
[12:47:35.777] <TB3> INFO: Test took 13078ms.
[12:47:37.123] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:37.350] <TB3> INFO: dacScan step from 23 .. 23
[12:47:50.647] <TB3> INFO: Test took 13296ms.
[12:47:51.910] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:52.146] <TB3> INFO: dacScan step from 24 .. 24
[12:48:05.245] <TB3> INFO: Test took 13099ms.
[12:48:06.576] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:06.832] <TB3> INFO: dacScan step from 25 .. 25
[12:48:19.696] <TB3> INFO: Test took 12864ms.
[12:48:21.027] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:21.261] <TB3> INFO: dacScan step from 26 .. 26
[12:48:34.255] <TB3> INFO: Test took 12994ms.
[12:48:35.602] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:35.843] <TB3> INFO: dacScan step from 27 .. 27
[12:48:48.704] <TB3> INFO: Test took 12861ms.
[12:48:49.987] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:50.219] <TB3> INFO: dacScan step from 28 .. 28
[12:49:03.416] <TB3> INFO: Test took 13196ms.
[12:49:04.714] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:04.940] <TB3> INFO: dacScan step from 29 .. 29
[12:49:18.330] <TB3> INFO: Test took 13389ms.
[12:49:19.646] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:19.894] <TB3> INFO: dacScan step from 30 .. 30
[12:49:33.587] <TB3> INFO: Test took 13693ms.
[12:49:35.005] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:35.260] <TB3> INFO: dacScan step from 31 .. 31
[12:49:48.719] <TB3> INFO: Test took 13459ms.
[12:49:50.041] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:50.266] <TB3> INFO: dacScan step from 32 .. 32
[12:49:54.874] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)
[12:50:03.188] <TB3> INFO: Test took 12921ms.
[12:50:04.547] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:04.781] <TB3> INFO: dacScan step from 33 .. 33
[12:50:17.713] <TB3> INFO: Test took 12932ms.
[12:50:19.002] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:19.234] <TB3> INFO: dacScan step from 34 .. 34
[12:50:32.595] <TB3> INFO: Test took 13360ms.
[12:50:34.029] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:34.261] <TB3> INFO: dacScan step from 35 .. 35
[12:50:47.564] <TB3> INFO: Test took 13302ms.
[12:50:49.099] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:49.329] <TB3> INFO: dacScan step from 36 .. 36
[12:51:02.976] <TB3> INFO: Test took 13647ms.
[12:51:04.256] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:51:04.486] <TB3> INFO: dacScan step from 37 .. 37
[12:51:18.241] <TB3> INFO: Test took 13755ms.
[12:51:19.569] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:51:19.794] <TB3> INFO: dacScan step from 38 .. 38
[12:51:33.524] <TB3> INFO: Test took 13730ms.
[12:51:34.921] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:51:35.161] <TB3> INFO: dacScan step from 39 .. 39
[12:51:49.000] <TB3> INFO: Test took 13838ms.
[12:51:50.540] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:51:50.788] <TB3> INFO: dacScan step from 40 .. 40
[12:52:05.165] <TB3> INFO: Test took 14377ms.
[12:52:06.481] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:52:06.705] <TB3> INFO: dacScan step from 41 .. 41
[12:52:21.541] <TB3> INFO: Test took 14835ms.
[12:52:22.924] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:52:23.167] <TB3> INFO: dacScan step from 42 .. 42
[12:52:38.114] <TB3> INFO: Test took 14946ms.
[12:52:39.661] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:52:39.888] <TB3> INFO: dacScan step from 43 .. 43
[12:52:54.857] <TB3> INFO: Test took 14969ms.
[12:52:56.248] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:52:56.481] <TB3> INFO: dacScan step from 44 .. 44
[12:53:11.808] <TB3> INFO: Test took 15326ms.
[12:53:13.156] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:53:13.381] <TB3> INFO: dacScan step from 45 .. 45
[12:53:28.884] <TB3> INFO: Test took 15502ms.
[12:53:30.342] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:53:30.586] <TB3> INFO: dacScan step from 46 .. 46
[12:53:46.007] <TB3> INFO: Test took 15421ms.
[12:53:47.629] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:53:47.914] <TB3> INFO: dacScan step from 47 .. 47
[12:54:04.103] <TB3> INFO: Test took 16188ms.
[12:54:05.690] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:05.929] <TB3> INFO: dacScan step from 48 .. 48
[12:54:22.174] <TB3> INFO: Test took 16245ms.
[12:54:23.803] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:24.052] <TB3> INFO: dacScan step from 49 .. 49
[12:54:40.158] <TB3> INFO: Test took 16106ms.
[12:54:41.548] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:41.774] <TB3> INFO: dacScan step from 50 .. 50
[12:54:57.986] <TB3> INFO: Test took 16212ms.
[12:54:59.729] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:00.015] <TB3> INFO: dacScan step from 51 .. 51
[12:55:16.308] <TB3> INFO: Test took 16292ms.
[12:55:17.930] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:18.176] <TB3> INFO: dacScan step from 52 .. 52
[12:55:34.471] <TB3> INFO: Test took 16295ms.
[12:55:36.074] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:36.347] <TB3> INFO: dacScan step from 53 .. 53
[12:55:52.997] <TB3> INFO: Test took 16649ms.
[12:55:54.463] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:54.759] <TB3> INFO: dacScan step from 54 .. 54
[12:56:11.122] <TB3> INFO: Test took 16362ms.
[12:56:12.639] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:12.916] <TB3> INFO: dacScan step from 55 .. 55
[12:56:29.229] <TB3> INFO: Test took 16312ms.
[12:56:30.693] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:30.944] <TB3> INFO: dacScan step from 56 .. 56
[12:56:47.067] <TB3> INFO: Test took 16122ms.
[12:56:48.508] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:48.760] <TB3> INFO: dacScan step from 57 .. 57
[12:57:04.805] <TB3> INFO: Test took 16045ms.
[12:57:06.340] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:06.575] <TB3> INFO: dacScan step from 58 .. 58
[12:57:22.655] <TB3> INFO: Test took 16079ms.
[12:57:24.166] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:24.392] <TB3> INFO: dacScan step from 59 .. 59
[12:57:40.492] <TB3> INFO: Test took 16099ms.
[12:57:42.029] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:42.310] <TB3> INFO: dacScan step from 60 .. 60
[12:57:58.589] <TB3> INFO: Test took 16278ms.
[12:58:00.297] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:00.535] <TB3> INFO: dacScan step from 61 .. 61
[12:58:16.929] <TB3> INFO: Test took 16393ms.
[12:58:18.596] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:18.876] <TB3> INFO: dacScan step from 62 .. 62
[12:58:35.339] <TB3> INFO: Test took 16462ms.
[12:58:36.939] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:37.189] <TB3> INFO: dacScan step from 63 .. 63
[12:58:53.699] <TB3> INFO: Test took 16509ms.
[12:58:55.187] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:55.430] <TB3> INFO: dacScan step from 64 .. 64
[12:59:11.397] <TB3> INFO: Test took 15966ms.
[12:59:12.876] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:13.120] <TB3> INFO: dacScan step from 65 .. 65
[12:59:17.839] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)
[12:59:29.655] <TB3> INFO: Test took 16535ms.
[12:59:31.064] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:31.326] <TB3> INFO: dacScan step from 66 .. 66
[12:59:47.940] <TB3> INFO: Test took 16613ms.
[12:59:49.479] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:49.721] <TB3> INFO: dacScan step from 67 .. 67
[13:00:06.231] <TB3> INFO: Test took 16509ms.
[13:00:07.736] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:07.975] <TB3> INFO: dacScan step from 68 .. 68
[13:00:24.385] <TB3> INFO: Test took 16409ms.
[13:00:25.896] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:26.129] <TB3> INFO: dacScan step from 69 .. 69
[13:00:42.375] <TB3> INFO: Test took 16246ms.
[13:00:43.849] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:44.097] <TB3> INFO: dacScan step from 70 .. 70
[13:00:48.580] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)
[13:00:48.580] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)
[13:00:48.580] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!
[13:00:48.581] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[13:01:00.307] <TB3> INFO: Test took 16209ms.
[13:01:01.837] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:02.108] <TB3> INFO: dacScan step from 71 .. 71
[13:01:18.336] <TB3> INFO: Test took 16228ms.
[13:01:19.768] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:20.023] <TB3> INFO: dacScan step from 72 .. 72
[13:01:36.379] <TB3> INFO: Test took 16355ms.
[13:01:38.097] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:38.355] <TB3> INFO: dacScan step from 73 .. 73
[13:01:54.721] <TB3> INFO: Test took 16365ms.
[13:01:56.148] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:56.402] <TB3> INFO: dacScan step from 74 .. 74
[13:02:13.062] <TB3> INFO: Test took 16659ms.
[13:02:14.627] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:14.872] <TB3> INFO: dacScan step from 75 .. 75
[13:02:19.452] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 0 Event ID mismatch: local ID (175) != TBM ID (176)
[13:02:19.452] <TB3> WARNING: Channel 0 ROC 0: Readback start marker after 31 readouts!
[13:02:19.452] <TB3> WARNING: Channel 0 ROC 1: Readback start marker after 31 readouts!
[13:02:19.452] <TB3> WARNING: Channel 0 ROC 2: Readback start marker after 31 readouts!
[13:02:19.452] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 31 readouts!
[13:02:31.529] <TB3> INFO: Test took 16657ms.
[13:02:33.259] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:33.502] <TB3> INFO: dacScan step from 76 .. 76
[13:02:49.998] <TB3> INFO: Test took 16495ms.
[13:02:51.436] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:51.676] <TB3> INFO: dacScan step from 77 .. 77
[13:03:08.043] <TB3> INFO: Test took 16366ms.
[13:03:09.562] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:09.799] <TB3> INFO: dacScan step from 78 .. 78
[13:03:26.090] <TB3> INFO: Test took 16290ms.
[13:03:27.671] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:27.919] <TB3> INFO: dacScan step from 79 .. 79
[13:03:44.235] <TB3> INFO: Test took 16315ms.
[13:03:45.660] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:45.935] <TB3> INFO: dacScan step from 80 .. 80
[13:04:02.646] <TB3> INFO: Test took 16710ms.
[13:04:04.125] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:04.373] <TB3> INFO: dacScan step from 81 .. 81
[13:04:21.054] <TB3> INFO: Test took 16680ms.
[13:04:22.503] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:22.747] <TB3> INFO: dacScan step from 82 .. 82
[13:04:39.044] <TB3> INFO: Test took 16296ms.
[13:04:40.680] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:40.944] <TB3> INFO: dacScan step from 83 .. 83
[13:04:57.128] <TB3> INFO: Test took 16183ms.
[13:04:58.721] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:58.967] <TB3> INFO: dacScan step from 84 .. 84
[13:05:14.807] <TB3> INFO: Test took 15839ms.
[13:05:16.266] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:16.506] <TB3> INFO: dacScan step from 85 .. 85
[13:05:32.522] <TB3> INFO: Test took 16015ms.
[13:05:34.096] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:34.340] <TB3> INFO: dacScan step from 86 .. 86
[13:05:38.804] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (5) != Token Chain Length (4)
[13:05:50.756] <TB3> INFO: Test took 16415ms.
[13:05:52.310] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:52.567] <TB3> INFO: dacScan step from 87 .. 87
[13:05:57.055] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)
[13:05:57.055] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)
[13:05:57.056] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!
[13:05:57.056] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[13:06:09.074] <TB3> INFO: Test took 16505ms.
[13:06:10.534] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:10.802] <TB3> INFO: dacScan step from 88 .. 88
[13:06:27.408] <TB3> INFO: Test took 16605ms.
[13:06:28.900] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:29.168] <TB3> INFO: dacScan step from 89 .. 89
[13:06:45.758] <TB3> INFO: Test took 16589ms.
[13:06:47.292] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:47.532] <TB3> INFO: dacScan step from 90 .. 90
[13:07:04.345] <TB3> INFO: Test took 16812ms.
[13:07:05.814] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:07:06.084] <TB3> INFO: dacScan step from 91 .. 91
[13:07:22.654] <TB3> INFO: Test took 16569ms.
[13:07:24.151] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:07:24.401] <TB3> INFO: dacScan step from 92 .. 92
[13:07:41.125] <TB3> INFO: Test took 16723ms.
[13:07:42.885] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:07:43.147] <TB3> INFO: dacScan step from 93 .. 93
[13:07:59.714] <TB3> INFO: Test took 16565ms.
[13:08:01.181] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:01.416] <TB3> INFO: dacScan step from 94 .. 94
[13:08:17.715] <TB3> INFO: Test took 16298ms.
[13:08:19.225] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:19.477] <TB3> INFO: dacScan step from 95 .. 95
[13:08:35.283] <TB3> INFO: Test took 15805ms.
[13:08:36.752] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:36.992] <TB3> INFO: dacScan step from 96 .. 96
[13:08:52.660] <TB3> INFO: Test took 15667ms.
[13:08:54.106] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:54.347] <TB3> INFO: dacScan step from 97 .. 97
[13:09:10.003] <TB3> INFO: Test took 15655ms.
[13:09:11.415] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:11.639] <TB3> INFO: dacScan step from 98 .. 98
[13:09:27.920] <TB3> INFO: Test took 16280ms.
[13:09:29.356] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:29.611] <TB3> INFO: dacScan step from 99 .. 99
[13:09:46.359] <TB3> INFO: Test took 16747ms.
[13:09:47.886] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:48.130] <TB3> INFO: dacScan step from 100 .. 100
[13:10:04.872] <TB3> INFO: Test took 16741ms.
[13:10:06.335] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:10:06.567] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:07.920] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:09.271] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:10.545] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:11.968] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:13.406] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:14.829] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:16.298] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:17.659] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:19.011] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:20.379] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:21.751] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:23.099] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:24.426] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:25.711] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:27.005] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[13:10:28.293] <TB3> INFO: PixTest:: pg_setup set to default.
[13:10:28.294] <TB3> INFO: vcal mean: 38.75 38.12 38.13 40.91 40.29 41.30 42.24 42.39 41.98 43.13 42.11 41.73 41.24 38.93 39.40 39.50
[13:10:28.294] <TB3> INFO: vcal RMS: 1.33 1.18 1.11 1.42 1.26 1.48 2.04 1.52 1.48 1.47 1.49 1.58 1.40 1.35 1.27 1.30
[13:10:28.294] <TB3> INFO: number of X-ray hits detected: 168414 162578 145585 281814 276703 324548 328880 334364 338133 368852 344773 307823 287440 174460 180146 183876
[13:10:28.294] <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
[13:10:28.294] <TB3> INFO: X-ray hit rate [MHz/cm2]: 49.4 47.7 42.7 82.6 81.1 95.1 96.4 98.0 99.1 108.1 101.1 90.2 84.3 51.1 52.8 53.9
[13:10:28.294] <TB3> INFO: PixTestHighRate::doXNoiseMaps() done
[13:10:28.392] <TB3> INFO: enter test to run
[13:10:28.393] <TB3> INFO: test: no parameter change
[13:10:28.562] <TB3> QUIET: Connection to board 121 closed.
[13:10:28.641] <TB3> INFO: pXar: this is the end, my friend