[08:02:03.314] <TB1> INFO: *** Welcome to pxar ***
[08:02:03.314] <TB1> INFO: *** Today: 2016/10/11
[08:02:03.384] <TB1> INFO: *** Version: f5d3-dirty
[08:02:03.384] <TB1> INFO: readRocDacs: /usr/local/data/M2357_XrayQualification_2016-10-11_09h45m_1476171913//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2357_XrayQualification_2016-10-11_09h45m_1476171913//005_HRData_150//dacParameters35_C15.dat
[08:02:03.384] <TB1> INFO: readTbmDacs: /usr/local/data/M2357_XrayQualification_2016-10-11_09h45m_1476171913//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2357_XrayQualification_2016-10-11_09h45m_1476171913//005_HRData_150//tbmParameters_C0b.dat
[08:02:03.384] <TB1> INFO: readMaskFile: /usr/local/data/M2357_XrayQualification_2016-10-11_09h45m_1476171913//005_HRData_150//defaultMaskFile.dat
[08:02:03.384] <TB1> INFO: readTrimFile: /usr/local/data/M2357_XrayQualification_2016-10-11_09h45m_1476171913//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2357_XrayQualification_2016-10-11_09h45m_1476171913//005_HRData_150//trimParameters35_C15.dat
[08:02:03.472] <TB1> INFO: clk: 4
[08:02:03.472] <TB1> INFO: ctr: 4
[08:02:03.472] <TB1> INFO: sda: 19
[08:02:03.472] <TB1> INFO: tin: 9
[08:02:03.472] <TB1> INFO: level: 15
[08:02:03.472] <TB1> INFO: triggerdelay: 0
[08:02:03.472] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[08:02:03.472] <TB1> INFO: Log level: INFO
[08:02:03.486] <TB1> INFO: Found DTB DTB_WWXTQT
[08:02:03.496] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[08:02:03.498] <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:
------------------------------------------------------
[08:02:03.500] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[08:02:05.069] <TB1> INFO: DUT info:
[08:02:05.069] <TB1> INFO: The DUT currently contains the following objects:
[08:02:05.069] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:02:05.069] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:02:05.069] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:02:05.069] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:02:05.069] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.069] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:02:05.470] <TB1> INFO: enter 'restricted' command line mode
[08:02:05.470] <TB1> INFO: enter test to run
[08:02:05.470] <TB1> INFO: test: delay setting parameters: ->5<-
[08:02:05.470] <TB1> INFO: delay test by 5 seconds...
[08:02:10.470] <TB1> INFO: enter test to run
[08:02:10.470] <TB1> INFO: test: Xray no parameter change
[08:02:10.470] <TB1> INFO: running: xray
[08:02:10.470] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2357_XrayQualification_2016-10-11_09h45m_1476171913//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2357_XrayQualification_2016-10-11_09h45m_1476171913//005_HRData_150//phCalibrationFitErr35_C15.dat
[08:02:10.601] <TB1> INFO: ######################################################################
[08:02:10.601] <TB1> INFO: PixTestXray::doTest()
[08:02:10.601] <TB1> INFO: ######################################################################
[08:02:10.601] <TB1> INFO: ----------------------------------------------------------------------
[08:02:10.601] <TB1> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:02:10.601] <TB1> INFO: ----------------------------------------------------------------------
[08:02:11.323] <TB1> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:02:15.551] <TB1> INFO: run duration 4 seconds, buffer almost full (81%), pausing triggers.
[08:02:41.084] <TB1> INFO: Resuming triggers.
[08:02:45.324] <TB1> INFO: run duration 8 seconds, buffer almost full (81%), pausing triggers.
[08:03:12.606] <TB1> INFO: Resuming triggers.
[08:03:16.841] <TB1> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[08:03:28.162] <TB1> WARNING: Channel 1 ROC 1: Readback start marker after 5 readouts!
[08:03:28.162] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 1 Number of ROCs (5) != Token Chain Length (4)
[08:03:28.163] <TB1> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[08:03:28.163] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1b7 8041 41c0 161 224e 75a 2869 41c0 4d5 244a 508 2242 521 2a45 43c0 84 242c 43c0 603 2245 e000 c000
[08:03:28.163] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1b1 80c1 41c1 281 2a4d 2cb 284e 744 2260 43c1 8c 2040 80 244f 10d 2269 253 2665 43c1 149 2884 24a 2665 41c1 c9 2464 2c5 204c 35b 2263 e000 c000
[08:03:28.163] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1b2 8001 41c0 10 240c 104 2a44 61b 2881 43c0 35b 2866 351 206a 6d9 244a 41c1 45d 2061 44d 204b 810 2262 43c1 e000 c000
[08:03:28.163] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1b3 8041 41c0 60 2a44 44a 282f 440 2a45 74b 2045 41c1 d 204f 862 284f 852 2664 43c0 280 2444 4a0 244c 43c1 9b 2244 e0 224f 320 2889 602 2862 61a 2663 e000 c000
[08:03:28.163] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1b4 80b1 43c0 43c3 c49 2128 d0d 20b4 41c0 6cb 2888 43c0 8b 2245 43c0 290 2a49 e000 c000
[08:03:28.163] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1b5 80c1 41c0 10d 2864 50c 226f 541 2668 65d 2441 43c0 2c9 244f 660 224f 6d9 2846 41c0 41c0 159 2464 143 284f 545 246d e000 c000
[08:03:28.163] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1b6 8001 41c0 2cd 2a62 353 266f 61d 2842 43c0 8c 224c 2c9 2a67 4cd 242f 4cd 2642 543 2646 614 244f 43c0 648 2660 64d 226d 804 2262 43c0 154 2848 44a 246c 822 2080 844 2847 e000 c000
[08:03:42.610] <TB1> INFO: Resuming triggers.
[08:03:46.846] <TB1> INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[08:04:13.401] <TB1> INFO: Resuming triggers.
[08:04:17.631] <TB1> INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[08:04:44.899] <TB1> INFO: Resuming triggers.
[08:04:49.127] <TB1> INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[08:05:15.942] <TB1> INFO: Resuming triggers.
[08:05:20.171] <TB1> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[08:05:46.468] <TB1> INFO: Resuming triggers.
[08:05:50.693] <TB1> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[08:06:17.087] <TB1> INFO: Resuming triggers.
[08:06:21.310] <TB1> INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[08:06:48.660] <TB1> INFO: Resuming triggers.
[08:06:52.886] <TB1> INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[08:07:20.992] <TB1> INFO: Resuming triggers.
[08:07:25.211] <TB1> INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[08:07:52.661] <TB1> INFO: Resuming triggers.
[08:07:56.879] <TB1> INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[08:08:24.145] <TB1> INFO: Resuming triggers.
[08:08:28.359] <TB1> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[08:08:55.915] <TB1> INFO: Resuming triggers.
[08:09:00.125] <TB1> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[08:09:26.770] <TB1> INFO: Resuming triggers.
[08:09:30.977] <TB1> INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[08:09:57.536] <TB1> INFO: Resuming triggers.
[08:10:01.742] <TB1> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[08:10:29.250] <TB1> INFO: Resuming triggers.
[08:10:33.460] <TB1> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[08:11:00.496] <TB1> INFO: Resuming triggers.
[08:11:04.701] <TB1> INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[08:11:32.350] <TB1> INFO: Resuming triggers.
[08:11:36.553] <TB1> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[08:12:03.709] <TB1> INFO: Resuming triggers.
[08:12:07.913] <TB1> INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[08:12:33.970] <TB1> INFO: Resuming triggers.
[08:12:38.172] <TB1> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[08:13:04.541] <TB1> INFO: Resuming triggers.
[08:13:08.740] <TB1> INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[08:13:33.339] <TB1> INFO: Resuming triggers.
[08:13:37.539] <TB1> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[08:14:02.325] <TB1> INFO: Resuming triggers.
[08:14:05.414] <TB1> INFO: data taking finished, elapsed time: 100 seconds.
[08:14:24.594] <TB1> INFO: PixTest:: pg_setup set to default.
[08:14:24.595] <TB1> INFO: PixTestXray::doPhRun() done
[08:14:24.595] <TB1> INFO: PixTestXray::doTest() done
[08:14:24.778] <TB1> INFO: enter test to run
[08:14:24.778] <TB1> INFO: test: no parameter change
[08:14:25.063] <TB1> QUIET: Connection to board 147 closed.
[08:14:25.146] <TB1> INFO: pXar: this is the end, my friend