[15:29:31.566] <TB2> INFO: *** Welcome to pxar ***
[15:29:31.566] <TB2> INFO: *** Today: 2015/08/13
[15:29:31.566] <TB2> INFO: readRocDacs: /usr/local/data/M2071_XrayQualification_2015-08-13_17h13m_1439478799//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2071_XrayQualification_2015-08-13_17h13m_1439478799//004_HRData_150//dacParameters35_C15.dat
[15:29:31.568] <TB2> INFO: readTbmDacs: /usr/local/data/M2071_XrayQualification_2015-08-13_17h13m_1439478799//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2071_XrayQualification_2015-08-13_17h13m_1439478799//004_HRData_150//tbmParameters_C0b.dat
[15:29:31.568] <TB2> INFO: readMaskFile: /usr/local/data/M2071_XrayQualification_2015-08-13_17h13m_1439478799//004_HRData_150//defaultMaskFile.dat
[15:29:31.568] <TB2> INFO: readTrimFile: /usr/local/data/M2071_XrayQualification_2015-08-13_17h13m_1439478799//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2071_XrayQualification_2015-08-13_17h13m_1439478799//004_HRData_150//trimParameters35_C15.dat
[15:29:31.647] <TB2> INFO: clk: 4
[15:29:31.647] <TB2> INFO: ctr: 4
[15:29:31.647] <TB2> INFO: sda: 19
[15:29:31.647] <TB2> INFO: tin: 9
[15:29:31.647] <TB2> INFO: level: 15
[15:29:31.647] <TB2> INFO: triggerdelay: 0
[15:29:31.647] <TB2> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[15:29:31.647] <TB2> INFO: Log level: INFO
[15:29:31.659] <TB2> INFO: Found DTB DTB_WXENWR
[15:29:31.671] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[15:29:31.675] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[15:29:31.677] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[15:29:33.214] <TB2> INFO: DUT info:
[15:29:33.214] <TB2> INFO: The DUT currently contains the following objects:
[15:29:33.214] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[15:29:33.214] <TB2> INFO: TBM Core alpha (0): 7 registers set
[15:29:33.214] <TB2> INFO: TBM Core beta (1): 7 registers set
[15:29:33.214] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:29:33.214] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.214] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:33.616] <TB2> INFO: enter 'restricted' command line mode
[15:29:33.616] <TB2> INFO: enter test to run
[15:29:33.616] <TB2> INFO: test: delay setting parameters: ->5<-
[15:29:33.616] <TB2> INFO: delay test by 5 seconds...
[15:29:38.616] <TB2> INFO: enter test to run
[15:29:38.616] <TB2> INFO: test: Xray no parameter change
[15:29:38.616] <TB2> INFO: running: xray
[15:29:38.617] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2071_XrayQualification_2015-08-13_17h13m_1439478799//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2071_XrayQualification_2015-08-13_17h13m_1439478799//004_HRData_150//phCalibrationFitErr35_C15.dat
[15:29:38.773] <TB2> INFO: ######################################################################
[15:29:38.773] <TB2> INFO: PixTestXray::doTest()
[15:29:38.773] <TB2> INFO: ######################################################################
[15:29:38.773] <TB2> INFO: ----------------------------------------------------------------------
[15:29:38.773] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:29:38.773] <TB2> INFO: ----------------------------------------------------------------------
[15:29:39.749] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:29:42.890] <TB2> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[15:30:03.345] <TB2> INFO: Resuming triggers.
[15:30:06.495] <TB2> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:30:09.632] <TB2> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (219) != TBM ID (76)
[15:30:09.633] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[15:30:09.633] <TB2> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (77) != TBM ID (220)
[15:30:28.620] <TB2> INFO: Resuming triggers.
[15:30:31.770] <TB2> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[15:30:53.662] <TB2> INFO: Resuming triggers.
[15:30:56.811] <TB2> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:31:20.497] <TB2> INFO: Resuming triggers.
[15:31:23.648] <TB2> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:31:46.485] <TB2> INFO: Resuming triggers.
[15:31:49.633] <TB2> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[15:32:11.528] <TB2> INFO: Resuming triggers.
[15:32:14.677] <TB2> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[15:32:34.923] <TB2> INFO: Resuming triggers.
[15:32:38.070] <TB2> INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[15:32:58.456] <TB2> INFO: Resuming triggers.
[15:33:01.604] <TB2> INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[15:33:21.467] <TB2> INFO: Resuming triggers.
[15:33:24.615] <TB2> INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[15:33:46.795] <TB2> INFO: Resuming triggers.
[15:33:49.941] <TB2> INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:34:10.886] <TB2> INFO: Resuming triggers.
[15:34:14.033] <TB2> INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[15:34:35.325] <TB2> INFO: Resuming triggers.
[15:34:38.471] <TB2> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[15:34:59.400] <TB2> INFO: Resuming triggers.
[15:35:02.546] <TB2> INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[15:35:25.050] <TB2> INFO: Resuming triggers.
[15:35:28.192] <TB2> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[15:35:49.427] <TB2> INFO: Resuming triggers.
[15:35:52.571] <TB2> INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[15:36:14.414] <TB2> INFO: Resuming triggers.
[15:36:17.558] <TB2> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[15:36:39.142] <TB2> INFO: Resuming triggers.
[15:36:42.286] <TB2> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[15:37:02.961] <TB2> INFO: Resuming triggers.
[15:37:06.108] <TB2> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[15:37:26.096] <TB2> INFO: Resuming triggers.
[15:37:29.240] <TB2> INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[15:37:49.581] <TB2> INFO: Resuming triggers.
[15:37:52.725] <TB2> INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[15:38:14.049] <TB2> INFO: Resuming triggers.
[15:38:17.195] <TB2> INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:38:41.131] <TB2> INFO: Resuming triggers.
[15:38:44.274] <TB2> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[15:38:46.766] <TB2> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!
[15:38:46.766] <TB2> WARNING: Channel 2 ROC 3: Readback start marker after 1 readouts!
[15:39:08.235] <TB2> INFO: Resuming triggers.
[15:39:11.378] <TB2> INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[15:39:34.039] <TB2> INFO: Resuming triggers.
[15:39:37.182] <TB2> INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[15:39:58.926] <TB2> INFO: Resuming triggers.
[15:40:02.067] <TB2> INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[15:40:22.285] <TB2> INFO: Resuming triggers.
[15:40:25.427] <TB2> INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[15:40:45.797] <TB2> INFO: Resuming triggers.
[15:40:48.940] <TB2> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[15:41:10.180] <TB2> INFO: Resuming triggers.
[15:41:13.322] <TB2> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[15:41:35.649] <TB2> INFO: Resuming triggers.
[15:41:38.788] <TB2> INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[15:41:59.847] <TB2> INFO: Resuming triggers.
[15:42:02.985] <TB2> INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[15:42:23.215] <TB2> INFO: Resuming triggers.
[15:42:25.800] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[15:42:41.975] <TB2> INFO: PixTest:: pg_setup set to default.
[15:42:41.977] <TB2> INFO: PixTestXray::doPhRun() done
[15:42:41.977] <TB2> INFO: PixTestXray::doTest() done
[15:42:42.193] <TB2> INFO: enter test to run
[15:42:42.193] <TB2> INFO: test: no parameter change
[15:42:42.377] <TB2> QUIET: Connection to board 162 closed.
[15:42:42.457] <TB2> INFO: pXar: this is the end, my friend