[08:08:39.412] <TB3> INFO: *** Welcome to pxar ***
[08:08:39.412] <TB3> INFO: *** Today: 2015/08/11
[08:08:39.412] <TB3> INFO: readRocDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//dacParameters35_C15.dat
[08:08:39.414] <TB3> INFO: readTbmDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//tbmParameters_C0b.dat
[08:08:39.414] <TB3> INFO: readMaskFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//defaultMaskFile.dat
[08:08:39.414] <TB3> INFO: readTrimFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//trimParameters35_C15.dat
[08:08:39.488] <TB3> INFO: clk: 4
[08:08:39.488] <TB3> INFO: ctr: 4
[08:08:39.488] <TB3> INFO: sda: 19
[08:08:39.488] <TB3> INFO: tin: 9
[08:08:39.488] <TB3> INFO: level: 15
[08:08:39.488] <TB3> INFO: triggerdelay: 0
[08:08:39.488] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[08:08:39.488] <TB3> INFO: Log level: INFO
[08:08:40.500] <TB3> INFO: Found DTB DTB_WWV6Z5
[08:08:43.521] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[08:08:43.524] <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:
------------------------------------------------------
[08:08:43.527] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[08:08:45.051] <TB3> INFO: DUT info:
[08:08:45.051] <TB3> INFO: The DUT currently contains the following objects:
[08:08:45.051] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[08:08:45.051] <TB3> INFO: TBM Core alpha (0): 7 registers set
[08:08:45.051] <TB3> INFO: TBM Core beta (1): 7 registers set
[08:08:45.051] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:08:45.051] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.051] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.051] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.051] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.051] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.051] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.051] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.051] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.452] <TB3> INFO: enter 'restricted' command line mode
[08:08:45.452] <TB3> INFO: enter test to run
[08:08:45.453] <TB3> INFO: test: Highrate no parameter change
[08:08:45.453] <TB3> INFO: running: highrate
[08:08:45.453] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[08:08:45.583] <TB3> INFO: ----------------------------------------------------------------------
[08:08:45.583] <TB3> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[08:08:45.583] <TB3> INFO: ----------------------------------------------------------------------
[08:08:45.588] <TB3> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1) hits flags = 384 (plus default)
[08:08:45.599] <TB3> INFO: dacScan step from 0 .. 0
[08:08:59.104] <TB3> INFO: Test took 13504ms.
[08:09:00.611] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:00.853] <TB3> INFO: dacScan step from 1 .. 1
[08:09:14.453] <TB3> INFO: Test took 13600ms.
[08:09:15.767] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:16.020] <TB3> INFO: dacScan step from 2 .. 2
[08:09:29.280] <TB3> INFO: Test took 13260ms.
[08:09:30.754] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:30.987] <TB3> INFO: dacScan step from 3 .. 3
[08:09:44.291] <TB3> INFO: Test took 13304ms.
[08:09:45.732] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:45.962] <TB3> INFO: dacScan step from 4 .. 4
[08:09:59.159] <TB3> INFO: Test took 13197ms.
[08:10:00.513] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:00.741] <TB3> INFO: dacScan step from 5 .. 5
[08:10:13.976] <TB3> INFO: Test took 13235ms.
[08:10:15.471] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:15.710] <TB3> INFO: dacScan step from 6 .. 6
[08:10:28.919] <TB3> INFO: Test took 13209ms.
[08:10:30.273] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:30.548] <TB3> INFO: dacScan step from 7 .. 7
[08:10:43.995] <TB3> INFO: Test took 13447ms.
[08:10:45.330] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:45.552] <TB3> INFO: dacScan step from 8 .. 8
[08:10:58.651] <TB3> INFO: Test took 13099ms.
[08:11:00.284] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:00.595] <TB3> INFO: dacScan step from 9 .. 9
[08:11:13.789] <TB3> INFO: Test took 13194ms.
[08:11:15.139] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:15.371] <TB3> INFO: dacScan step from 10 .. 10
[08:11:28.558] <TB3> INFO: Test took 13187ms.
[08:11:29.864] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:30.092] <TB3> INFO: dacScan step from 11 .. 11
[08:11:43.373] <TB3> INFO: Test took 13281ms.
[08:11:44.800] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:45.052] <TB3> INFO: dacScan step from 12 .. 12
[08:11:58.206] <TB3> INFO: Test took 13153ms.
[08:11:59.579] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:59.823] <TB3> INFO: dacScan step from 13 .. 13
[08:12:13.419] <TB3> INFO: Test took 13595ms.
[08:12:14.820] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:15.056] <TB3> INFO: dacScan step from 14 .. 14
[08:12:28.499] <TB3> INFO: Test took 13442ms.
[08:12:29.925] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:30.166] <TB3> INFO: dacScan step from 15 .. 15
[08:12:43.113] <TB3> INFO: Test took 12946ms.
[08:12:44.551] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:44.781] <TB3> INFO: dacScan step from 16 .. 16
[08:12:57.667] <TB3> INFO: Test took 12886ms.
[08:12:59.132] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:59.435] <TB3> INFO: dacScan step from 17 .. 17
[08:13:12.946] <TB3> INFO: Test took 13511ms.
[08:13:14.275] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:13:14.499] <TB3> INFO: dacScan step from 18 .. 18
[08:13:27.908] <TB3> INFO: Test took 13409ms.
[08:13:29.357] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:13:29.601] <TB3> INFO: dacScan step from 19 .. 19
[08:13:34.210] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)
[08:13:34.210] <TB3> WARNING: Channel 1 ROC 1: Readback start marker after 9 readouts!
[08:13:34.210] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)
[08:13:34.210] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)
[08:13:34.210] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[08:13:34.210] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!
[08:13:34.210] <TB3> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!
[08:13:34.210] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[08:13:34.210] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[08:13:42.829] <TB3> INFO: Test took 13227ms.
[08:13:44.270] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:13:44.504] <TB3> INFO: dacScan step from 20 .. 20
[08:13:57.605] <TB3> INFO: Test took 13101ms.
[08:13:59.150] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:13:59.379] <TB3> INFO: dacScan step from 21 .. 21
[08:14:12.175] <TB3> INFO: Test took 12796ms.
[08:14:13.574] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:13.799] <TB3> INFO: dacScan step from 22 .. 22
[08:14:26.901] <TB3> INFO: Test took 13102ms.
[08:14:28.224] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:28.448] <TB3> INFO: dacScan step from 23 .. 23
[08:14:41.740] <TB3> INFO: Test took 13292ms.
[08:14:43.086] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:43.325] <TB3> INFO: dacScan step from 24 .. 24
[08:14:56.363] <TB3> INFO: Test took 13038ms.
[08:14:57.916] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:58.155] <TB3> INFO: dacScan step from 25 .. 25
[08:15:02.582] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)
[08:15:02.582] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[08:15:11.279] <TB3> INFO: Test took 13124ms.
[08:15:12.852] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:13.092] <TB3> INFO: dacScan step from 26 .. 26
[08:15:26.468] <TB3> INFO: Test took 13375ms.
[08:15:27.817] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:28.048] <TB3> INFO: dacScan step from 27 .. 27
[08:15:41.320] <TB3> INFO: Test took 13271ms.
[08:15:42.774] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:43.000] <TB3> INFO: dacScan step from 28 .. 28
[08:15:56.378] <TB3> INFO: Test took 13377ms.
[08:15:57.880] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:58.129] <TB3> INFO: dacScan step from 29 .. 29
[08:16:11.606] <TB3> INFO: Test took 13477ms.
[08:16:13.041] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:13.274] <TB3> INFO: dacScan step from 30 .. 30
[08:16:26.396] <TB3> INFO: Test took 13121ms.
[08:16:27.724] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:27.971] <TB3> INFO: dacScan step from 31 .. 31
[08:16:40.934] <TB3> INFO: Test took 12962ms.
[08:16:42.334] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:42.569] <TB3> INFO: dacScan step from 32 .. 32
[08:16:55.373] <TB3> INFO: Test took 12803ms.
[08:16:56.778] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:57.024] <TB3> INFO: dacScan step from 33 .. 33
[08:17:10.252] <TB3> INFO: Test took 13228ms.
[08:17:11.640] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:11.868] <TB3> INFO: dacScan step from 34 .. 34
[08:17:25.269] <TB3> INFO: Test took 13400ms.
[08:17:26.870] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:27.101] <TB3> INFO: dacScan step from 35 .. 35
[08:17:40.742] <TB3> INFO: Test took 13640ms.
[08:17:42.149] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:42.378] <TB3> INFO: dacScan step from 36 .. 36
[08:17:56.008] <TB3> INFO: Test took 13629ms.
[08:17:57.514] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:57.823] <TB3> INFO: dacScan step from 37 .. 37
[08:18:11.647] <TB3> INFO: Test took 13824ms.
[08:18:13.187] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:13.426] <TB3> INFO: dacScan step from 38 .. 38
[08:18:27.081] <TB3> INFO: Test took 13655ms.
[08:18:28.520] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:28.751] <TB3> INFO: dacScan step from 39 .. 39
[08:18:42.848] <TB3> INFO: Test took 14096ms.
[08:18:44.412] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:44.714] <TB3> INFO: dacScan step from 40 .. 40
[08:18:58.966] <TB3> INFO: Test took 14252ms.
[08:19:00.435] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:00.666] <TB3> INFO: dacScan step from 41 .. 41
[08:19:05.214] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)
[08:19:05.214] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (83) != TBM ID (53)
[08:19:05.214] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (1) != Token Chain Length (4)
[08:19:05.214] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (54) != TBM ID (83)
[08:19:05.219] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!
[08:19:05.219] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 17 readouts!
[08:19:15.320] <TB3> CRITICAL: <hal.cc/MultiRocAllPixelsDacScan:L1040> Incomplete DAQ data readout! Missing -1 Events.
[08:19:29.932] <TB3> INFO: Test took 14587ms.
[08:19:31.447] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:31.688] <TB3> INFO: dacScan step from 42 .. 42
[08:19:46.859] <TB3> INFO: Test took 15170ms.
[08:19:48.450] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:48.688] <TB3> INFO: dacScan step from 43 .. 43
[08:20:03.677] <TB3> INFO: Test took 14988ms.
[08:20:05.169] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:05.417] <TB3> INFO: dacScan step from 44 .. 44
[08:20:20.947] <TB3> INFO: Test took 15530ms.
[08:20:22.562] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:22.836] <TB3> INFO: dacScan step from 45 .. 45
[08:20:38.585] <TB3> INFO: Test took 15748ms.
[08:20:40.147] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:40.417] <TB3> INFO: dacScan step from 46 .. 46
[08:20:56.278] <TB3> INFO: Test took 15860ms.
[08:20:57.794] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:58.068] <TB3> INFO: dacScan step from 47 .. 47
[08:21:14.405] <TB3> INFO: Test took 16336ms.
[08:21:15.934] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:16.173] <TB3> INFO: dacScan step from 48 .. 48
[08:21:32.918] <TB3> INFO: Test took 16744ms.
[08:21:34.397] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:34.627] <TB3> INFO: dacScan step from 49 .. 49
[08:21:51.469] <TB3> INFO: Test took 16841ms.
[08:21:53.101] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:53.373] <TB3> INFO: dacScan step from 50 .. 50
[08:22:10.164] <TB3> INFO: Test took 16790ms.
[08:22:11.937] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:12.180] <TB3> INFO: dacScan step from 51 .. 51
[08:22:28.841] <TB3> INFO: Test took 16660ms.
[08:22:30.317] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:30.551] <TB3> INFO: dacScan step from 52 .. 52
[08:22:47.074] <TB3> INFO: Test took 16523ms.
[08:22:48.648] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:48.886] <TB3> INFO: dacScan step from 53 .. 53
[08:23:05.437] <TB3> INFO: Test took 16550ms.
[08:23:07.040] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:07.271] <TB3> INFO: dacScan step from 54 .. 54
[08:23:23.574] <TB3> INFO: Test took 16302ms.
[08:23:25.435] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:25.724] <TB3> INFO: dacScan step from 55 .. 55
[08:23:42.301] <TB3> INFO: Test took 16576ms.
[08:23:43.914] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:44.160] <TB3> INFO: dacScan step from 56 .. 56
[08:24:00.123] <TB3> INFO: Test took 15962ms.
[08:24:01.648] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:01.882] <TB3> INFO: dacScan step from 57 .. 57
[08:24:18.691] <TB3> INFO: Test took 16808ms.
[08:24:20.222] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:20.453] <TB3> INFO: dacScan step from 58 .. 58
[08:24:37.229] <TB3> INFO: Test took 16775ms.
[08:24:38.813] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:39.062] <TB3> INFO: dacScan step from 59 .. 59
[08:24:55.683] <TB3> INFO: Test took 16621ms.
[08:24:57.383] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:57.630] <TB3> INFO: dacScan step from 60 .. 60
[08:25:13.892] <TB3> INFO: Test took 16261ms.
[08:25:15.402] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:15.636] <TB3> INFO: dacScan step from 61 .. 61
[08:25:32.461] <TB3> INFO: Test took 16824ms.
[08:25:33.941] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:34.173] <TB3> INFO: dacScan step from 62 .. 62
[08:25:49.951] <TB3> INFO: Test took 15778ms.
[08:25:51.464] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:51.766] <TB3> INFO: dacScan step from 63 .. 63
[08:26:08.310] <TB3> INFO: Test took 16543ms.
[08:26:09.845] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:10.095] <TB3> INFO: dacScan step from 64 .. 64
[08:26:26.445] <TB3> INFO: Test took 16349ms.
[08:26:28.439] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:28.688] <TB3> INFO: dacScan step from 65 .. 65
[08:26:45.654] <TB3> INFO: Test took 16965ms.
[08:26:47.245] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:47.495] <TB3> INFO: dacScan step from 66 .. 66
[08:27:03.770] <TB3> INFO: Test took 16274ms.
[08:27:05.490] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:05.814] <TB3> INFO: dacScan step from 67 .. 67
[08:27:22.321] <TB3> INFO: Test took 16506ms.
[08:27:23.835] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:24.072] <TB3> INFO: dacScan step from 68 .. 68
[08:27:40.195] <TB3> INFO: Test took 16122ms.
[08:27:41.668] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:41.901] <TB3> INFO: dacScan step from 69 .. 69
[08:27:58.517] <TB3> INFO: Test took 16615ms.
[08:28:00.344] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:00.584] <TB3> INFO: dacScan step from 70 .. 70
[08:28:16.741] <TB3> INFO: Test took 16156ms.
[08:28:18.224] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:18.457] <TB3> INFO: dacScan step from 71 .. 71
[08:28:35.299] <TB3> INFO: Test took 16841ms.
[08:28:36.850] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:37.096] <TB3> INFO: dacScan step from 72 .. 72
[08:28:41.604] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)
[08:28:41.604] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)
[08:28:41.604] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 31 readouts!
[08:28:53.565] <TB3> INFO: Test took 16468ms.
[08:28:55.160] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:55.397] <TB3> INFO: dacScan step from 73 .. 73
[08:29:11.613] <TB3> INFO: Test took 16215ms.
[08:29:13.272] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:13.537] <TB3> INFO: dacScan step from 74 .. 74
[08:29:29.865] <TB3> INFO: Test took 16327ms.
[08:29:31.349] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:31.581] <TB3> INFO: dacScan step from 75 .. 75
[08:29:48.417] <TB3> INFO: Test took 16835ms.
[08:29:49.952] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:50.194] <TB3> INFO: dacScan step from 76 .. 76
[08:30:07.215] <TB3> INFO: Test took 17020ms.
[08:30:08.917] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:09.171] <TB3> INFO: dacScan step from 77 .. 77
[08:30:26.137] <TB3> INFO: Test took 16965ms.
[08:30:27.737] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:27.986] <TB3> INFO: dacScan step from 78 .. 78
[08:30:44.183] <TB3> INFO: Test took 16196ms.
[08:30:45.704] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:45.942] <TB3> INFO: dacScan step from 79 .. 79
[08:31:02.229] <TB3> INFO: Test took 16286ms.
[08:31:03.866] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:04.150] <TB3> INFO: dacScan step from 80 .. 80
[08:31:20.545] <TB3> INFO: Test took 16394ms.
[08:31:22.090] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:22.326] <TB3> INFO: dacScan step from 81 .. 81
[08:31:38.766] <TB3> INFO: Test took 16439ms.
[08:31:40.434] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:40.667] <TB3> INFO: dacScan step from 82 .. 82
[08:31:57.539] <TB3> INFO: Test took 16871ms.
[08:31:59.136] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:59.370] <TB3> INFO: dacScan step from 83 .. 83
[08:32:16.000] <TB3> INFO: Test took 16629ms.
[08:32:17.598] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:17.886] <TB3> INFO: dacScan step from 84 .. 84
[08:32:34.521] <TB3> INFO: Test took 16634ms.
[08:32:36.296] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:36.597] <TB3> INFO: dacScan step from 85 .. 85
[08:32:52.739] <TB3> INFO: Test took 16141ms.
[08:32:54.444] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:54.688] <TB3> INFO: dacScan step from 86 .. 86
[08:32:59.342] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (38) != TBM ID (39)
[08:32:59.342] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (38) != TBM ID (39)
[08:32:59.342] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!
[08:32:59.342] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!
[08:32:59.342] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!
[08:32:59.342] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!
[08:32:59.342] <TB3> WARNING: Channel 3 ROC 0: Readback start marker after 15 readouts!
[08:32:59.342] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[08:32:59.342] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[08:32:59.342] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[08:33:11.155] <TB3> INFO: Test took 16466ms.
[08:33:13.133] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:13.433] <TB3> INFO: dacScan step from 87 .. 87
[08:33:30.193] <TB3> INFO: Test took 16759ms.
[08:33:31.829] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:32.091] <TB3> INFO: dacScan step from 88 .. 88
[08:33:49.001] <TB3> INFO: Test took 16909ms.
[08:33:50.558] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:50.821] <TB3> INFO: dacScan step from 89 .. 89
[08:34:07.934] <TB3> INFO: Test took 17112ms.
[08:34:09.610] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:09.862] <TB3> INFO: dacScan step from 90 .. 90
[08:34:26.534] <TB3> INFO: Test took 16671ms.
[08:34:28.589] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:28.866] <TB3> INFO: dacScan step from 91 .. 91
[08:34:44.984] <TB3> INFO: Test took 16117ms.
[08:34:46.682] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:46.916] <TB3> INFO: dacScan step from 92 .. 92
[08:35:03.076] <TB3> INFO: Test took 16159ms.
[08:35:04.751] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:04.996] <TB3> INFO: dacScan step from 93 .. 93
[08:35:21.263] <TB3> INFO: Test took 16266ms.
[08:35:22.974] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:23.240] <TB3> INFO: dacScan step from 94 .. 94
[08:35:39.899] <TB3> INFO: Test took 16658ms.
[08:35:41.760] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:42.024] <TB3> INFO: dacScan step from 95 .. 95
[08:35:59.070] <TB3> INFO: Test took 17045ms.
[08:36:00.843] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:01.096] <TB3> INFO: dacScan step from 96 .. 96
[08:36:17.218] <TB3> INFO: Test took 16121ms.
[08:36:18.801] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:19.033] <TB3> INFO: dacScan step from 97 .. 97
[08:36:35.660] <TB3> INFO: Test took 16626ms.
[08:36:37.484] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:37.725] <TB3> INFO: dacScan step from 98 .. 98
[08:36:54.216] <TB3> INFO: Test took 16490ms.
[08:36:55.944] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:56.175] <TB3> INFO: dacScan step from 99 .. 99
[08:37:12.836] <TB3> INFO: Test took 16660ms.
[08:37:14.450] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:14.682] <TB3> INFO: dacScan step from 100 .. 100
[08:37:30.869] <TB3> INFO: Test took 16186ms.
[08:37:32.530] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:32.772] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:34.221] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:35.493] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:36.747] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:38.067] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:39.393] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:40.759] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:42.102] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:43.453] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:44.822] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:46.186] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:47.562] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:48.887] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:50.202] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:51.454] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:52.743] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:54.006] <TB3> INFO: PixTest:: pg_setup set to default.
[08:37:54.007] <TB3> INFO: vcal mean: 38.02 38.22 37.79 41.15 41.40 42.69 41.38 41.83 42.78 42.99 43.11 42.22 40.96 38.54 38.72 38.65
[08:37:54.007] <TB3> INFO: vcal RMS: 1.31 1.25 1.26 1.46 1.43 1.48 1.40 1.86 1.52 1.69 1.55 1.65 1.49 1.27 1.28 1.40
[08:37:54.007] <TB3> INFO: number of X-ray hits detected: 152282 159232 148530 297860 316316 362085 328501 338970 384649 376528 392038 336381 290082 168124 180841 182789
[08:37:54.007] <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
[08:37:54.007] <TB3> INFO: X-ray hit rate [MHz/cm2]: 44.6 46.7 43.5 87.3 92.7 106.1 96.3 99.4 112.7 110.4 114.9 98.6 85.0 49.3 53.0 53.6
[08:37:54.007] <TB3> INFO: PixTestHighRate::doXNoiseMaps() done
[08:37:54.104] <TB3> INFO: enter test to run
[08:37:54.104] <TB3> INFO: test: no parameter change
[08:37:54.274] <TB3> QUIET: Connection to board 121 closed.
[08:37:54.354] <TB3> INFO: pXar: this is the end, my friend