[08:13:16.393] <TB2> INFO: *** Welcome to pxar ***
[08:13:16.393] <TB2> INFO: *** Today: 2016/10/10
[08:13:16.473] <TB2> INFO: *** Version: f5d3-dirty
[08:13:16.473] <TB2> INFO: readRocDacs: /usr/local/data/M2354_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2354_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//dacParameters35_C15.dat
[08:13:16.473] <TB2> INFO: readTbmDacs: /usr/local/data/M2354_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2354_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//tbmParameters_C0b.dat
[08:13:16.473] <TB2> INFO: readMaskFile: /usr/local/data/M2354_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//defaultMaskFile.dat
[08:13:16.473] <TB2> INFO: readTrimFile: /usr/local/data/M2354_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2354_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//trimParameters35_C15.dat
[08:13:16.549] <TB2> INFO: clk: 4
[08:13:16.549] <TB2> INFO: ctr: 4
[08:13:16.549] <TB2> INFO: sda: 19
[08:13:16.549] <TB2> INFO: tin: 9
[08:13:16.549] <TB2> INFO: level: 15
[08:13:16.549] <TB2> INFO: triggerdelay: 0
[08:13:16.549] <TB2> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[08:13:16.550] <TB2> INFO: Log level: INFO
[08:13:16.563] <TB2> INFO: Found DTB DTB_WXENWR
[08:13:16.574] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[08:13:16.576] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[08:13:16.578] <TB2> INFO: RPC call hashes of host and DTB match: 486171790
[08:13:18.116] <TB2> INFO: DUT info:
[08:13:18.116] <TB2> INFO: The DUT currently contains the following objects:
[08:13:18.116] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:13:18.116] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:13:18.116] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:13:18.116] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:13:18.116] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.116] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.116] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.116] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.116] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.116] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.116] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.116] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.116] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.116] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.117] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.117] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.117] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.117] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.117] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.117] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:13:18.518] <TB2> INFO: enter 'restricted' command line mode
[08:13:18.518] <TB2> INFO: enter test to run
[08:13:18.518] <TB2> INFO: test: delay setting parameters: ->5<-
[08:13:18.518] <TB2> INFO: delay test by 5 seconds...
[08:13:23.518] <TB2> INFO: enter test to run
[08:13:23.518] <TB2> INFO: test: Xray no parameter change
[08:13:23.518] <TB2> INFO: running: xray
[08:13:23.518] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2354_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2354_XrayQualification_2016-10-10_09h55m_1476086146//005_HRData_150//phCalibrationFitErr35_C15.dat
[08:13:23.662] <TB2> INFO: ######################################################################
[08:13:23.662] <TB2> INFO: PixTestXray::doTest()
[08:13:23.662] <TB2> INFO: ######################################################################
[08:13:23.662] <TB2> INFO: ----------------------------------------------------------------------
[08:13:23.662] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:13:23.662] <TB2> INFO: ----------------------------------------------------------------------
[08:13:24.386] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:13:28.356] <TB2> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[08:13:53.759] <TB2> INFO: Resuming triggers.
[08:13:57.737] <TB2> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[08:14:23.373] <TB2> INFO: Resuming triggers.
[08:14:27.353] <TB2> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[08:14:53.386] <TB2> INFO: Resuming triggers.
[08:14:57.362] <TB2> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[08:15:23.714] <TB2> INFO: Resuming triggers.
[08:15:27.690] <TB2> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[08:15:53.367] <TB2> INFO: Resuming triggers.
[08:15:57.341] <TB2> INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[08:16:24.053] <TB2> INFO: Resuming triggers.
[08:16:28.030] <TB2> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[08:16:53.673] <TB2> INFO: Resuming triggers.
[08:16:57.646] <TB2> INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[08:17:24.667] <TB2> INFO: Resuming triggers.
[08:17:28.642] <TB2> INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[08:17:56.037] <TB2> INFO: Resuming triggers.
[08:18:00.013] <TB2> INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[08:18:26.305] <TB2> INFO: Resuming triggers.
[08:18:30.275] <TB2> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[08:18:56.627] <TB2> INFO: Resuming triggers.
[08:19:00.601] <TB2> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[08:19:27.021] <TB2> INFO: Resuming triggers.
[08:19:30.994] <TB2> INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[08:19:58.111] <TB2> INFO: Resuming triggers.
[08:20:02.080] <TB2> INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[08:20:29.651] <TB2> INFO: Resuming triggers.
[08:20:33.623] <TB2> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[08:21:02.096] <TB2> INFO: Resuming triggers.
[08:21:06.069] <TB2> INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[08:21:20.178] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 1 Number of ROCs (5) != Token Chain Length (4)
[08:21:20.178] <TB2> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[08:21:20.178] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1a1 80c0 4381 413 2a4c 4d3 2482 609 2040 603 206d 4301 5c 2860 24c 246c 60d 2269 620 2649 642 2a2f 802 2a40 81c 2665 861 226c 4301 415 2449 490 244c 859 2049 4301 361 2069 343 208d 45a 2449 540 2649 e000 c000
[08:21:20.178] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a19b 8040 4300 111 2861 158 2041 352 204c 4300 503 2a61 504 2240 4380 45c 2460 71d 2a65 760 2a42 4300 800 2865 e000 c000
[08:21:20.178] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a19c 80b0 4300 11 2047 4300 4300 75a 226a 4300 4a0 2265 e000 c000
[08:21:20.178] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a19d 80c0 4300 28d 2080 34b 2060 402 2247 4300 1 224c d4 2a2d 344 2642 4300 852 242d 84c 2244 4300 12 2841 91 2267 e000 c000
[08:21:20.178] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a19e 8000 4300 110 2240 160 2647 2c9 2a4d 652 2648 751 2483 4380 550 2049 654 2249 4300 4302 100 2b4c 9a2 214c c62 2948 9a2 284c ca2 2b64 c2c 23a8 9ae 2c48 4301 11 2a64 249 204c 290 2a4d 508 2229 699 2081 e000 c000
[08:21:20.178] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a19f 8040 4302 20c 2044 49a 2849 552 284c 808 2626 4302 6c9 2426 6c9 264f 4302 714 2649 860 2649 4300 159 2a45 49d 226b e000 c000
[08:21:20.178] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1a0 80b0 4300 541 2668 4300 4300 93 2069 4301 52 246d 45 2069 402 2069 448 284c 540 2846 649 2845 849 204f 840 2427 e000 c000
[08:21:33.153] <TB2> INFO: Resuming triggers.
[08:21:37.120] <TB2> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[08:22:03.573] <TB2> INFO: Resuming triggers.
[08:22:07.540] <TB2> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[08:22:34.551] <TB2> INFO: Resuming triggers.
[08:22:38.518] <TB2> INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[08:23:05.217] <TB2> INFO: Resuming triggers.
[08:23:09.184] <TB2> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[08:23:35.039] <TB2> INFO: Resuming triggers.
[08:23:39.009] <TB2> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[08:24:06.639] <TB2> INFO: Resuming triggers.
[08:24:10.607] <TB2> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[08:24:35.694] <TB2> INFO: Resuming triggers.
[08:24:39.658] <TB2> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[08:25:05.115] <TB2> INFO: Resuming triggers.
[08:25:09.078] <TB2> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[08:25:34.245] <TB2> INFO: Resuming triggers.
[08:25:38.208] <TB2> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[08:26:04.085] <TB2> INFO: Resuming triggers.
[08:26:04.895] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[08:26:09.860] <TB2> INFO: PixTest:: pg_setup set to default.
[08:26:09.861] <TB2> INFO: PixTestXray::doPhRun() done
[08:26:09.861] <TB2> INFO: PixTestXray::doTest() done
[08:26:10.040] <TB2> INFO: enter test to run
[08:26:10.040] <TB2> INFO: test: no parameter change
[08:26:10.304] <TB2> QUIET: Connection to board 162 closed.
[08:26:10.389] <TB2> INFO: pXar: this is the end, my friend