[13:32:59.636] <TB2> INFO: *** Welcome to pxar ***
[13:32:59.636] <TB2> INFO: *** Today: 2015/08/11
[13:32:59.636] <TB2> INFO: readRocDacs: /usr/local/data/M2066_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2066_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//dacParameters35_C15.dat
[13:32:59.637] <TB2> INFO: readTbmDacs: /usr/local/data/M2066_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2066_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//tbmParameters_C0b.dat
[13:32:59.638] <TB2> INFO: readMaskFile: /usr/local/data/M2066_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//defaultMaskFile.dat
[13:32:59.638] <TB2> INFO: readTrimFile: /usr/local/data/M2066_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2066_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//trimParameters35_C15.dat
[13:32:59.709] <TB2> INFO: clk: 4
[13:32:59.709] <TB2> INFO: ctr: 4
[13:32:59.709] <TB2> INFO: sda: 19
[13:32:59.709] <TB2> INFO: tin: 9
[13:32:59.709] <TB2> INFO: level: 15
[13:32:59.709] <TB2> INFO: triggerdelay: 0
[13:32:59.709] <TB2> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[13:32:59.709] <TB2> INFO: Log level: INFO
[13:33:00.718] <TB2> INFO: Found DTB DTB_WXENWR
[13:33:03.738] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[13:33:03.742] <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:
------------------------------------------------------
[13:33:03.745] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[13:33:05.307] <TB2> INFO: DUT info:
[13:33:05.307] <TB2> INFO: The DUT currently contains the following objects:
[13:33:05.307] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[13:33:05.307] <TB2> INFO: TBM Core alpha (0): 7 registers set
[13:33:05.307] <TB2> INFO: TBM Core beta (1): 7 registers set
[13:33:05.307] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:33:05.307] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.308] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:05.710] <TB2> INFO: enter 'restricted' command line mode
[13:33:05.710] <TB2> INFO: enter test to run
[13:33:05.710] <TB2> INFO: test: delay setting parameters: ->5<-
[13:33:05.710] <TB2> INFO: delay test by 5 seconds...
[13:33:10.710] <TB2> INFO: enter test to run
[13:33:10.710] <TB2> INFO: test: Xray no parameter change
[13:33:10.710] <TB2> INFO: running: xray
[13:33:10.711] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2066_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2066_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//phCalibrationFitErr35_C15.dat
[13:33:10.894] <TB2> INFO: ######################################################################
[13:33:10.894] <TB2> INFO: PixTestXray::doTest()
[13:33:10.894] <TB2> INFO: ######################################################################
[13:33:10.894] <TB2> INFO: ----------------------------------------------------------------------
[13:33:10.894] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:33:10.894] <TB2> INFO: ----------------------------------------------------------------------
[13:33:11.870] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:33:15.165] <TB2> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[13:33:35.582] <TB2> INFO: Resuming triggers.
[13:33:38.896] <TB2> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:33:42.177] <TB2> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (211) != TBM ID (67)
[13:33:42.177] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[13:33:42.177] <TB2> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (68) != TBM ID (212)
[13:34:00.254] <TB2> INFO: Resuming triggers.
[13:34:03.571] <TB2> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[13:34:25.444] <TB2> INFO: Resuming triggers.
[13:34:28.758] <TB2> INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[13:34:52.256] <TB2> INFO: Resuming triggers.
[13:34:55.571] <TB2> INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[13:35:18.885] <TB2> INFO: Resuming triggers.
[13:35:22.198] <TB2> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[13:35:44.253] <TB2> INFO: Resuming triggers.
[13:35:47.562] <TB2> INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:36:08.421] <TB2> INFO: Resuming triggers.
[13:36:11.732] <TB2> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[13:36:33.563] <TB2> INFO: Resuming triggers.
[13:36:36.873] <TB2> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[13:36:58.849] <TB2> INFO: Resuming triggers.
[13:37:02.158] <TB2> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:37:25.513] <TB2> INFO: Resuming triggers.
[13:37:28.824] <TB2> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[13:37:52.460] <TB2> INFO: Resuming triggers.
[13:37:55.771] <TB2> INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[13:38:18.236] <TB2> INFO: Resuming triggers.
[13:38:21.545] <TB2> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[13:38:46.656] <TB2> INFO: Resuming triggers.
[13:38:49.965] <TB2> INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[13:39:13.523] <TB2> INFO: Resuming triggers.
[13:39:16.829] <TB2> INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[13:39:39.812] <TB2> INFO: Resuming triggers.
[13:39:43.118] <TB2> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[13:40:05.435] <TB2> INFO: Resuming triggers.
[13:40:08.742] <TB2> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[13:40:29.472] <TB2> INFO: Resuming triggers.
[13:40:32.779] <TB2> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[13:40:53.580] <TB2> INFO: Resuming triggers.
[13:40:56.889] <TB2> INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[13:41:20.308] <TB2> INFO: Resuming triggers.
[13:41:23.614] <TB2> INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[13:41:46.997] <TB2> INFO: Resuming triggers.
[13:41:50.302] <TB2> INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[13:42:13.271] <TB2> INFO: Resuming triggers.
[13:42:16.576] <TB2> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[13:42:39.654] <TB2> INFO: Resuming triggers.
[13:42:42.957] <TB2> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[13:43:04.526] <TB2> INFO: Resuming triggers.
[13:43:07.830] <TB2> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[13:43:29.597] <TB2> INFO: Resuming triggers.
[13:43:32.902] <TB2> INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:43:54.460] <TB2> INFO: Resuming triggers.
[13:43:57.765] <TB2> INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[13:44:19.304] <TB2> INFO: Resuming triggers.
[13:44:22.609] <TB2> INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[13:44:43.340] <TB2> INFO: Resuming triggers.
[13:44:46.646] <TB2> INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[13:45:08.204] <TB2> INFO: Resuming triggers.
[13:45:11.509] <TB2> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[13:45:32.343] <TB2> INFO: Resuming triggers.
[13:45:35.647] <TB2> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[13:45:58.490] <TB2> INFO: Resuming triggers.
[13:45:59.325] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[13:46:05.445] <TB2> INFO: PixTest:: pg_setup set to default.
[13:46:05.447] <TB2> INFO: PixTestXray::doPhRun() done
[13:46:05.447] <TB2> INFO: PixTestXray::doTest() done
[13:46:05.627] <TB2> INFO: enter test to run
[13:46:05.627] <TB2> INFO: test: no parameter change
[13:46:05.796] <TB2> QUIET: Connection to board 162 closed.
[13:46:05.875] <TB2> INFO: pXar: this is the end, my friend