[09:58:26.500] <TB1> INFO: *** Welcome to pxar ***
[09:58:26.500] <TB1> INFO: *** Today: 2015/07/13
[09:58:26.500] <TB1> INFO: readRocDacs: /usr/local/data/M2035_XrayQualification_2015-07-13_11h39m_1436780355//010_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2035_XrayQualification_2015-07-13_11h39m_1436780355//010_HRData_150//dacParameters35_C15.dat
[09:58:26.501] <TB1> INFO: readTbmDacs: /usr/local/data/M2035_XrayQualification_2015-07-13_11h39m_1436780355//010_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2035_XrayQualification_2015-07-13_11h39m_1436780355//010_HRData_150//tbmParameters_C0b.dat
[09:58:26.501] <TB1> INFO: readMaskFile: /usr/local/data/M2035_XrayQualification_2015-07-13_11h39m_1436780355//010_HRData_150//defaultMaskFile.dat
[09:58:26.501] <TB1> INFO: readTrimFile: /usr/local/data/M2035_XrayQualification_2015-07-13_11h39m_1436780355//010_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2035_XrayQualification_2015-07-13_11h39m_1436780355//010_HRData_150//trimParameters35_C15.dat
[09:58:26.573] <TB1> INFO: clk: 4
[09:58:26.573] <TB1> INFO: ctr: 4
[09:58:26.573] <TB1> INFO: sda: 19
[09:58:26.573] <TB1> INFO: tin: 9
[09:58:26.573] <TB1> INFO: level: 15
[09:58:26.573] <TB1> INFO: triggerdelay: 0
[09:58:26.573] <TB1> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[09:58:26.573] <TB1> INFO: Log level: INFO
[09:58:26.584] <TB1> INFO: Found DTB DTB_WWXJGB
[09:58:26.602] <TB1> QUIET: Connection to board DTB_WWXJGB opened.
[09:58:26.605] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 137
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWXJGB
MAC address: 40D855118089
Hostname: pixelDTB137
Comment:
------------------------------------------------------
[09:58:26.608] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[09:58:28.174] <TB1> INFO: DUT info:
[09:58:28.174] <TB1> INFO: The DUT currently contains the following objects:
[09:58:28.174] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[09:58:28.174] <TB1> INFO: TBM Core alpha (0): 7 registers set
[09:58:28.174] <TB1> INFO: TBM Core beta (1): 7 registers set
[09:58:28.174] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:58:28.174] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.174] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.174] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.174] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.174] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.174] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.175] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.175] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.175] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.175] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.175] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.175] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.175] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.175] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.175] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.175] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:28.576] <TB1> INFO: enter 'restricted' command line mode
[09:58:28.576] <TB1> INFO: enter test to run
[09:58:28.576] <TB1> INFO: test: delay setting parameters: ->5<-
[09:58:28.576] <TB1> INFO: delay test by 5 seconds...
[09:58:33.576] <TB1> INFO: enter test to run
[09:58:33.576] <TB1> INFO: test: Xray no parameter change
[09:58:33.576] <TB1> INFO: running: xray
[09:58:33.577] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2035_XrayQualification_2015-07-13_11h39m_1436780355//010_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2035_XrayQualification_2015-07-13_11h39m_1436780355//010_HRData_150//phCalibrationFitErr35_C15.dat
[09:58:33.798] <TB1> INFO: ######################################################################
[09:58:33.798] <TB1> INFO: PixTestXray::doTest()
[09:58:33.798] <TB1> INFO: ######################################################################
[09:58:33.798] <TB1> INFO: ----------------------------------------------------------------------
[09:58:33.798] <TB1> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:58:33.798] <TB1> INFO: ----------------------------------------------------------------------
[09:58:34.768] <TB1> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:58:37.866] <TB1> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[09:59:05.291] <TB1> INFO: Resuming triggers.
[09:59:08.399] <TB1> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[09:59:32.037] <TB1> INFO: Resuming triggers.
[09:59:35.147] <TB1> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[09:59:55.459] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (5) != Token Chain Length (4)
[10:00:04.140] <TB1> INFO: Resuming triggers.
[10:00:07.249] <TB1> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[10:00:33.334] <TB1> INFO: Resuming triggers.
[10:00:36.440] <TB1> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[10:01:06.132] <TB1> INFO: Resuming triggers.
[10:01:09.237] <TB1> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[10:01:33.085] <TB1> INFO: Resuming triggers.
[10:01:36.189] <TB1> INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[10:02:05.971] <TB1> INFO: Resuming triggers.
[10:02:09.076] <TB1> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[10:02:36.309] <TB1> INFO: Resuming triggers.
[10:02:39.413] <TB1> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[10:03:05.746] <TB1> INFO: Resuming triggers.
[10:03:08.851] <TB1> INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[10:03:35.555] <TB1> INFO: Resuming triggers.
[10:03:38.659] <TB1> INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:04:05.826] <TB1> INFO: Resuming triggers.
[10:04:08.929] <TB1> INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[10:04:37.720] <TB1> INFO: Resuming triggers.
[10:04:40.821] <TB1> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[10:05:10.305] <TB1> INFO: Resuming triggers.
[10:05:13.411] <TB1> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[10:05:41.928] <TB1> INFO: Resuming triggers.
[10:05:45.034] <TB1> INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:06:10.316] <TB1> INFO: Resuming triggers.
[10:06:13.422] <TB1> INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[10:06:40.803] <TB1> INFO: Resuming triggers.
[10:06:43.907] <TB1> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[10:07:13.800] <TB1> INFO: Resuming triggers.
[10:07:16.903] <TB1> INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[10:07:40.355] <TB1> INFO: Resuming triggers.
[10:07:43.457] <TB1> INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[10:08:12.287] <TB1> INFO: Resuming triggers.
[10:08:15.391] <TB1> INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[10:08:37.391] <TB1> INFO: Resuming triggers.
[10:08:40.492] <TB1> INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[10:09:03.658] <TB1> INFO: Resuming triggers.
[10:09:06.765] <TB1> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[10:09:31.211] <TB1> INFO: Resuming triggers.
[10:09:34.317] <TB1> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[10:09:58.935] <TB1> INFO: Resuming triggers.
[10:10:02.039] <TB1> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[10:10:28.981] <TB1> INFO: Resuming triggers.
[10:10:32.087] <TB1> INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[10:10:59.582] <TB1> INFO: Resuming triggers.
[10:11:02.683] <TB1> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:11:31.684] <TB1> INFO: Resuming triggers.
[10:11:34.790] <TB1> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[10:11:54.446] <TB1> INFO: Resuming triggers.
[10:11:57.549] <TB1> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[10:12:16.423] <TB1> INFO: Resuming triggers.
[10:12:19.524] <TB1> INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[10:12:38.620] <TB1> INFO: Resuming triggers.
[10:12:41.721] <TB1> INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[10:13:00.842] <TB1> INFO: Resuming triggers.
[10:13:03.945] <TB1> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[10:13:23.070] <TB1> INFO: Resuming triggers.
[10:13:26.172] <TB1> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[10:13:45.122] <TB1> INFO: Resuming triggers.
[10:13:45.886] <TB1> INFO: data taking finished, elapsed time: 100 seconds.
[10:13:50.774] <TB1> INFO: PixTest:: pg_setup set to default.
[10:13:50.776] <TB1> INFO: PixTestXray::doPhRun() done
[10:13:50.783] <TB1> INFO: PixTestXray::doTest() done
[10:13:50.965] <TB1> INFO: enter test to run
[10:13:50.965] <TB1> INFO: test: no parameter change
[10:13:51.174] <TB1> QUIET: Connection to board 137 closed.
[10:13:51.254] <TB1> INFO: pXar: this is the end, my friend