[08:12:56.214] <TB0> INFO: *** Welcome to pxar ***
[08:12:56.214] <TB0> INFO: *** Today: 2016/10/10
[08:12:57.561] <TB0> INFO: *** Version: f5d3-dirty
[08:12:57.561] <TB0> INFO: readRocDacs: /usr/local/data/M2352_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2352_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//dacParameters35_C15.dat
[08:12:57.562] <TB0> INFO: readTbmDacs: /usr/local/data/M2352_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2352_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//tbmParameters_C0b.dat
[08:12:57.562] <TB0> INFO: readMaskFile: /usr/local/data/M2352_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//defaultMaskFile.dat
[08:12:57.562] <TB0> INFO: readTrimFile: /usr/local/data/M2352_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2352_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//trimParameters35_C15.dat
[08:12:57.630] <TB0> INFO: clk: 4
[08:12:57.630] <TB0> INFO: ctr: 4
[08:12:57.630] <TB0> INFO: sda: 19
[08:12:57.630] <TB0> INFO: tin: 9
[08:12:57.630] <TB0> INFO: level: 15
[08:12:57.630] <TB0> INFO: triggerdelay: 0
[08:12:57.630] <TB0> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[08:12:57.630] <TB0> INFO: Log level: INFO
[08:12:57.643] <TB0> INFO: Found DTB DTB_WS6UZO
[08:12:57.651] <TB0> QUIET: Connection to board DTB_WS6UZO opened.
[08:12:57.653] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[08:12:57.655] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[08:12:59.184] <TB0> INFO: DUT info:
[08:12:59.184] <TB0> INFO: The DUT currently contains the following objects:
[08:12:59.184] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[08:12:59.184] <TB0> INFO: TBM Core alpha (0): 7 registers set
[08:12:59.184] <TB0> INFO: TBM Core beta (1): 7 registers set
[08:12:59.184] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:12:59.184] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.184] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:12:59.585] <TB0> INFO: enter 'restricted' command line mode
[08:12:59.585] <TB0> INFO: enter test to run
[08:12:59.585] <TB0> INFO: test: delay setting parameters: ->5<-
[08:12:59.585] <TB0> INFO: delay test by 5 seconds...
[08:13:04.585] <TB0> INFO: enter test to run
[08:13:04.585] <TB0> INFO: test: Xray no parameter change
[08:13:04.585] <TB0> INFO: running: xray
[08:13:04.585] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2352_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2352_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//phCalibrationFitErr35_C15.dat
[08:13:04.713] <TB0> INFO: ######################################################################
[08:13:04.713] <TB0> INFO: PixTestXray::doTest()
[08:13:04.713] <TB0> INFO: ######################################################################
[08:13:04.713] <TB0> INFO: ----------------------------------------------------------------------
[08:13:04.713] <TB0> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:13:04.713] <TB0> INFO: ----------------------------------------------------------------------
[08:13:05.437] <TB0> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:13:10.238] <TB0> INFO: run duration 4 seconds, buffer almost full (81%), pausing triggers.
[08:13:35.530] <TB0> INFO: Resuming triggers.
[08:13:40.346] <TB0> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[08:14:07.721] <TB0> INFO: Resuming triggers.
[08:14:12.536] <TB0> INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[08:14:40.240] <TB0> INFO: Resuming triggers.
[08:14:45.059] <TB0> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[08:15:12.220] <TB0> INFO: Resuming triggers.
[08:15:17.039] <TB0> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[08:15:43.370] <TB0> INFO: Resuming triggers.
[08:15:48.188] <TB0> INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[08:16:14.987] <TB0> INFO: Resuming triggers.
[08:16:19.802] <TB0> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[08:16:46.953] <TB0> INFO: Resuming triggers.
[08:16:51.769] <TB0> INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[08:17:18.884] <TB0> INFO: Resuming triggers.
[08:17:23.699] <TB0> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[08:17:50.379] <TB0> INFO: Resuming triggers.
[08:17:55.196] <TB0> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[08:18:21.743] <TB0> INFO: Resuming triggers.
[08:18:26.561] <TB0> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[08:18:53.282] <TB0> INFO: Resuming triggers.
[08:18:58.099] <TB0> INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[08:19:24.582] <TB0> INFO: Resuming triggers.
[08:19:29.396] <TB0> INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[08:19:55.687] <TB0> INFO: Resuming triggers.
[08:20:00.498] <TB0> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[08:20:26.441] <TB0> INFO: Resuming triggers.
[08:20:31.254] <TB0> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[08:20:58.493] <TB0> INFO: Resuming triggers.
[08:21:03.305] <TB0> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[08:21:30.674] <TB0> INFO: Resuming triggers.
[08:21:35.485] <TB0> INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[08:22:03.399] <TB0> INFO: Resuming triggers.
[08:22:08.213] <TB0> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[08:22:35.514] <TB0> INFO: Resuming triggers.
[08:22:40.328] <TB0> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[08:23:07.404] <TB0> INFO: Resuming triggers.
[08:23:12.214] <TB0> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[08:23:39.166] <TB0> INFO: Resuming triggers.
[08:23:42.978] <TB0> INFO: data taking finished, elapsed time: 100 seconds.
[08:23:53.874] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 0 Number of ROCs (5) != Token Chain Length (4)
[08:23:53.875] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[08:23:53.875] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0e1 80c0 4070 21 2a4f 400 282f 4070 6c5 2a4e 4070 244 262f 4071 e000 c000
[08:23:53.875] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0db 8040 4070 250 242d 758 2260 4070 4070 4070 810 2447 e000 c000
[08:23:53.875] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0dc 80b0 4070 118 204a 115 2882 31b 2040 4070 cc 2042 6c1 204c 4070 305 2247 759 284c 4070 d9 2a49 121 224e 2c4 2864 2c3 204f 309 204f e000 c000
[08:23:53.875] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0dd 80c0 4070 e2 2642 122 248a 4070 2dc 284a 848 2226 4070 52 2640 99 284e 4071 e000 c000
[08:23:53.875] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0de 8000 4070 6a2 2642 4070 21 2846 4070 752 2a4f 759 2a4c 4070 d 2428 1d 2646 4e0 2043 4073 e000 c000
[08:23:53.875] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0df 8040 4072 215 2242 4072 61c 266a 4072 20b 2422 4dc 2443 850 204f 4073 e000 c000
[08:23:53.875] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0e0 80b0 4070 1d 204f 55 224f 62 226f 4070 4070 4070 55 2247 e000 c000
[08:24:04.373] <TB0> INFO: PixTest:: pg_setup set to default.
[08:24:04.374] <TB0> INFO: PixTestXray::doPhRun() done
[08:24:04.374] <TB0> INFO: PixTestXray::doTest() done
[08:24:04.640] <TB0> INFO: enter test to run
[08:24:04.640] <TB0> INFO: test: no parameter change
[08:24:04.882] <TB0> QUIET: Connection to board 83 closed.
[08:24:04.962] <TB0> INFO: pXar: this is the end, my friend