[10:18:57.795] <TB1> INFO: *** Welcome to pxar ***
[10:18:57.796] <TB1> INFO: *** Today: 2016/10/05
[10:18:57.861] <TB1> INFO: *** Version: f5d3-dirty
[10:18:57.861] <TB1> INFO: readRocDacs: /usr/local/data/M2349_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2349_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//dacParameters35_C15.dat
[10:18:57.862] <TB1> INFO: readTbmDacs: /usr/local/data/M2349_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2349_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//tbmParameters_C0b.dat
[10:18:57.862] <TB1> INFO: readMaskFile: /usr/local/data/M2349_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//defaultMaskFile.dat
[10:18:57.862] <TB1> INFO: readTrimFile: /usr/local/data/M2349_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2349_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//trimParameters35_C15.dat
[10:18:57.935] <TB1> INFO: clk: 4
[10:18:57.935] <TB1> INFO: ctr: 4
[10:18:57.935] <TB1> INFO: sda: 19
[10:18:57.935] <TB1> INFO: tin: 9
[10:18:57.935] <TB1> INFO: level: 15
[10:18:57.935] <TB1> INFO: triggerdelay: 0
[10:18:57.935] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[10:18:57.935] <TB1> INFO: Log level: INFO
[10:18:57.948] <TB1> INFO: Found DTB DTB_WWXTQT
[10:18:57.958] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[10:18:57.960] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[10:18:57.962] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[10:18:59.518] <TB1> INFO: DUT info:
[10:18:59.518] <TB1> INFO: The DUT currently contains the following objects:
[10:18:59.518] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:18:59.519] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:18:59.519] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:18:59.519] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:18:59.519] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.519] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:18:59.921] <TB1> INFO: enter 'restricted' command line mode
[10:18:59.921] <TB1> INFO: enter test to run
[10:18:59.921] <TB1> INFO: test: delay setting parameters: ->5<-
[10:18:59.921] <TB1> INFO: delay test by 5 seconds...
[10:19:04.921] <TB1> INFO: enter test to run
[10:19:04.921] <TB1> INFO: test: Xray no parameter change
[10:19:04.921] <TB1> INFO: running: xray
[10:19:04.921] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2349_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2349_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//phCalibrationFitErr35_C15.dat
[10:19:05.053] <TB1> INFO: ######################################################################
[10:19:05.053] <TB1> INFO: PixTestXray::doTest()
[10:19:05.053] <TB1> INFO: ######################################################################
[10:19:05.053] <TB1> INFO: ----------------------------------------------------------------------
[10:19:05.053] <TB1> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:19:05.053] <TB1> INFO: ----------------------------------------------------------------------
[10:19:05.776] <TB1> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:19:09.795] <TB1> INFO: run duration 4 seconds, buffer almost full (81%), pausing triggers.
[10:19:34.627] <TB1> INFO: Resuming triggers.
[10:19:38.660] <TB1> INFO: run duration 8 seconds, buffer almost full (81%), pausing triggers.
[10:20:04.540] <TB1> INFO: Resuming triggers.
[10:20:08.572] <TB1> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[10:20:34.981] <TB1> INFO: Resuming triggers.
[10:20:39.011] <TB1> INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[10:21:06.516] <TB1> INFO: Resuming triggers.
[10:21:10.565] <TB1> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[10:21:37.494] <TB1> INFO: Resuming triggers.
[10:21:41.525] <TB1> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[10:22:08.068] <TB1> INFO: Resuming triggers.
[10:22:12.098] <TB1> INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[10:22:40.171] <TB1> INFO: Resuming triggers.
[10:22:44.198] <TB1> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[10:23:11.546] <TB1> INFO: Resuming triggers.
[10:23:15.575] <TB1> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[10:23:42.747] <TB1> INFO: Resuming triggers.
[10:23:46.775] <TB1> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[10:24:14.846] <TB1> INFO: Resuming triggers.
[10:24:18.874] <TB1> INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[10:24:46.740] <TB1> INFO: Resuming triggers.
[10:24:50.770] <TB1> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[10:25:17.628] <TB1> INFO: Resuming triggers.
[10:25:21.657] <TB1> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[10:25:48.675] <TB1> INFO: Resuming triggers.
[10:25:52.702] <TB1> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[10:26:19.684] <TB1> INFO: Resuming triggers.
[10:26:23.712] <TB1> INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[10:26:51.202] <TB1> INFO: Resuming triggers.
[10:26:55.229] <TB1> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[10:27:07.136] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 2 Number of ROCs (5) != Token Chain Length (4)
[10:27:07.136] <TB1> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[10:27:07.136] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a23d 80c1 4070 31c 222e 81d 244a 4060 504 2a4f 4070 88 2a4a 502 266d 4061 e000 c000
[10:27:07.136] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a237 8041 4060 d0 2042 2c8 2262 558 2461 548 2648 854 2647 4070 619 2685 40e0 242 2465 60a 2440 804 2661 4060 121 2a48 512 224a 6dd 284c 862 2065 e000 c000
[10:27:07.136] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a238 80b1 4070 11 244a 75b 2666 848 2225 4060 55d 2a40 6c4 2a67 4070 11 2665 c1 2065 4060 249 2a4d 40c 284d e000 c000
[10:27:07.136] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a239 80c1 4060 850 2629 4060 a2 2269 643 2682 40e0 311 284d 301 2040 6d1 2849 751 222a 862 284e 4060 762 266f e000 c000
[10:27:07.136] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a23a 8001 4060 4060 158 282f 295 2860 420 224e 4d8 202f 750 202f 80d 2849 4060 140 2642 344 2a4d 34c 2268 44b 244d 40f0 40f3 de0 2388 de0 2020 960 2284 e000 c000
[10:27:07.136] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a23b 8041 4060 498 2661 65d 2262 70a 2860 85c 202e 4060 22 2468 4060 4060 19 284f e000 c000
[10:27:07.136] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a23c 80b1 4070 44 202c 158 2647 348 204c 741 2260 40e0 114 2a48 719 2861 40e0 11d 204b 10a 204a 104 2649 25b 2a6a 2d3 264f 40a 2a68 640 2443 4060 1c 2462 489 2047 4da 286b e000 c000
[10:27:23.382] <TB1> INFO: Resuming triggers.
[10:27:27.408] <TB1> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[10:27:56.093] <TB1> INFO: Resuming triggers.
[10:28:00.118] <TB1> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[10:28:26.493] <TB1> INFO: Resuming triggers.
[10:28:30.515] <TB1> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[10:28:56.846] <TB1> INFO: Resuming triggers.
[10:29:00.868] <TB1> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:29:27.966] <TB1> INFO: Resuming triggers.
[10:29:31.986] <TB1> INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[10:29:58.434] <TB1> INFO: Resuming triggers.
[10:30:02.459] <TB1> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[10:30:26.337] <TB1> INFO: Resuming triggers.
[10:30:30.360] <TB1> INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:30:54.922] <TB1> INFO: Resuming triggers.
[10:30:58.941] <TB1> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[10:31:23.856] <TB1> INFO: Resuming triggers.
[10:31:27.291] <TB1> INFO: data taking finished, elapsed time: 100 seconds.
[10:31:48.885] <TB1> INFO: PixTest:: pg_setup set to default.
[10:31:48.886] <TB1> INFO: PixTestXray::doPhRun() done
[10:31:48.886] <TB1> INFO: PixTestXray::doTest() done
[10:31:49.072] <TB1> INFO: enter test to run
[10:31:49.072] <TB1> INFO: test: no parameter change
[10:31:49.308] <TB1> QUIET: Connection to board 147 closed.
[10:31:49.388] <TB1> INFO: pXar: this is the end, my friend