[15:26:05.598] <TB1> INFO: *** Welcome to pxar ***
[15:26:05.598] <TB1> INFO: *** Today: 2015/09/04
[15:26:05.598] <TB1> INFO: readRocDacs: /usr/local/data/M2092_XrayQualification_2015-09-04_17h08m_1441379300//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2092_XrayQualification_2015-09-04_17h08m_1441379300//004_HRData_150//dacParameters35_C15.dat
[15:26:05.598] <TB1> INFO: readTbmDacs: /usr/local/data/M2092_XrayQualification_2015-09-04_17h08m_1441379300//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2092_XrayQualification_2015-09-04_17h08m_1441379300//004_HRData_150//tbmParameters_C0b.dat
[15:26:05.598] <TB1> INFO: readMaskFile: /usr/local/data/M2092_XrayQualification_2015-09-04_17h08m_1441379300//004_HRData_150//defaultMaskFile.dat
[15:26:05.598] <TB1> INFO: readTrimFile: /usr/local/data/M2092_XrayQualification_2015-09-04_17h08m_1441379300//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2092_XrayQualification_2015-09-04_17h08m_1441379300//004_HRData_150//trimParameters35_C15.dat
[15:26:05.667] <TB1> INFO: clk: 4
[15:26:05.667] <TB1> INFO: ctr: 4
[15:26:05.667] <TB1> INFO: sda: 19
[15:26:05.667] <TB1> INFO: tin: 9
[15:26:05.667] <TB1> INFO: level: 15
[15:26:05.667] <TB1> INFO: triggerdelay: 0
[15:26:05.667] <TB1> QUIET: Instanciating API for pxar prod-10
[15:26:05.667] <TB1> INFO: Log level: INFO
[15:26:05.681] <TB1> INFO: Found DTB DTB_WWXTQT
[15:26:05.696] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[15:26:05.700] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[15:26:05.702] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[15:26:07.249] <TB1> INFO: DUT info:
[15:26:07.249] <TB1> INFO: The DUT currently contains the following objects:
[15:26:07.249] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:26:07.249] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:26:07.249] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:26:07.249] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:26:07.249] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.249] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.249] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.249] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.249] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.249] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.250] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.250] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.250] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.250] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.250] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.250] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.250] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.250] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.250] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.250] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:07.651] <TB1> INFO: enter 'restricted' command line mode
[15:26:07.651] <TB1> INFO: enter test to run
[15:26:07.651] <TB1> INFO: test: delay setting parameters: ->5<-
[15:26:07.651] <TB1> INFO: delay test by 5 seconds...
[15:26:12.651] <TB1> INFO: enter test to run
[15:26:12.651] <TB1> INFO: test: Xray no parameter change
[15:26:12.651] <TB1> INFO: running: xray
[15:26:12.652] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2092_XrayQualification_2015-09-04_17h08m_1441379300//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2092_XrayQualification_2015-09-04_17h08m_1441379300//004_HRData_150//phCalibrationFitErr35_C15.dat
[15:26:12.813] <TB1> INFO: ######################################################################
[15:26:12.813] <TB1> INFO: PixTestXray::doTest()
[15:26:12.813] <TB1> INFO: ######################################################################
[15:26:12.813] <TB1> INFO: ----------------------------------------------------------------------
[15:26:12.813] <TB1> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:26:12.813] <TB1> INFO: ----------------------------------------------------------------------
[15:26:13.784] <TB1> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:26:16.856] <TB1> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[15:26:45.208] <TB1> INFO: Resuming triggers.
[15:26:48.291] <TB1> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:27:16.490] <TB1> INFO: Resuming triggers.
[15:27:19.576] <TB1> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[15:27:47.602] <TB1> INFO: Resuming triggers.
[15:27:50.686] <TB1> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:28:19.935] <TB1> INFO: Resuming triggers.
[15:28:23.020] <TB1> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:28:52.991] <TB1> INFO: Resuming triggers.
[15:28:56.076] <TB1> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[15:29:25.906] <TB1> INFO: Resuming triggers.
[15:29:28.990] <TB1> INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[15:29:58.036] <TB1> INFO: Resuming triggers.
[15:30:01.118] <TB1> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[15:30:32.007] <TB1> INFO: Resuming triggers.
[15:30:35.099] <TB1> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[15:31:06.402] <TB1> INFO: Resuming triggers.
[15:31:09.488] <TB1> INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[15:31:39.531] <TB1> INFO: Resuming triggers.
[15:31:42.611] <TB1> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[15:32:13.725] <TB1> INFO: Resuming triggers.
[15:32:16.808] <TB1> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[15:32:49.496] <TB1> INFO: Resuming triggers.
[15:32:52.581] <TB1> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[15:33:23.192] <TB1> INFO: Resuming triggers.
[15:33:26.276] <TB1> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[15:33:57.353] <TB1> INFO: Resuming triggers.
[15:34:00.434] <TB1> INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:34:32.026] <TB1> INFO: Resuming triggers.
[15:34:35.107] <TB1> INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[15:35:06.635] <TB1> INFO: Resuming triggers.
[15:35:09.714] <TB1> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[15:35:39.373] <TB1> INFO: Resuming triggers.
[15:35:42.450] <TB1> INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[15:36:12.714] <TB1> INFO: Resuming triggers.
[15:36:15.790] <TB1> INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[15:36:45.220] <TB1> INFO: Resuming triggers.
[15:36:48.299] <TB1> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[15:37:20.289] <TB1> INFO: Resuming triggers.
[15:37:23.369] <TB1> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[15:37:54.752] <TB1> INFO: Resuming triggers.
[15:37:57.832] <TB1> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[15:38:29.647] <TB1> INFO: Resuming triggers.
[15:38:32.724] <TB1> INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[15:39:05.598] <TB1> INFO: Resuming triggers.
[15:39:08.676] <TB1> INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[15:39:41.097] <TB1> INFO: Resuming triggers.
[15:39:44.173] <TB1> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[15:40:15.095] <TB1> INFO: Resuming triggers.
[15:40:18.170] <TB1> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[15:40:48.793] <TB1> INFO: Resuming triggers.
[15:40:51.868] <TB1> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[15:41:23.183] <TB1> INFO: Resuming triggers.
[15:41:26.257] <TB1> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[15:41:43.880] <TB1> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!
[15:41:43.880] <TB1> WARNING: Channel 2 ROC 3: Readback start marker after 1 readouts!
[15:41:53.365] <TB1> INFO: Resuming triggers.
[15:41:56.437] <TB1> INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[15:42:16.450] <TB1> INFO: Resuming triggers.
[15:42:19.522] <TB1> INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[15:42:39.702] <TB1> INFO: Resuming triggers.
[15:42:42.778] <TB1> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[15:43:02.848] <TB1> INFO: Resuming triggers.
[15:43:05.918] <TB1> INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[15:43:26.013] <TB1> INFO: Resuming triggers.
[15:43:27.579] <TB1> INFO: data taking finished, elapsed time: 100 seconds.
[15:43:38.303] <TB1> INFO: PixTest:: pg_setup set to default.
[15:43:38.304] <TB1> INFO: PixTestXray::doPhRun() done
[15:43:38.304] <TB1> INFO: PixTestXray::doTest() done
[15:43:38.488] <TB1> INFO: enter test to run
[15:43:38.488] <TB1> INFO: test: no parameter change
[15:43:38.697] <TB1> QUIET: Connection to board 147 closed.
[15:43:38.778] <TB1> INFO: pXar: this is the end, my friend