[08:32:00.704] <TB2> INFO: *** Welcome to pxar ***
[08:32:00.704] <TB2> INFO: *** Today: 2016/09/20
[08:32:00.772] <TB2> INFO: *** Version: f5d3-dirty
[08:32:00.772] <TB2> INFO: readRocDacs: /usr/local/data/M2324_XrayQualification_2016-09-20_10h14m_1474359284//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2324_XrayQualification_2016-09-20_10h14m_1474359284//005_HRData_150//dacParameters35_C15.dat
[08:32:00.773] <TB2> INFO: readTbmDacs: /usr/local/data/M2324_XrayQualification_2016-09-20_10h14m_1474359284//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2324_XrayQualification_2016-09-20_10h14m_1474359284//005_HRData_150//tbmParameters_C0b.dat
[08:32:00.773] <TB2> INFO: readMaskFile: /usr/local/data/M2324_XrayQualification_2016-09-20_10h14m_1474359284//005_HRData_150//defaultMaskFile.dat
[08:32:00.773] <TB2> INFO: readTrimFile: /usr/local/data/M2324_XrayQualification_2016-09-20_10h14m_1474359284//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2324_XrayQualification_2016-09-20_10h14m_1474359284//005_HRData_150//trimParameters35_C15.dat
[08:32:00.861] <TB2> INFO: clk: 4
[08:32:00.861] <TB2> INFO: ctr: 4
[08:32:00.861] <TB2> INFO: sda: 19
[08:32:00.861] <TB2> INFO: tin: 9
[08:32:00.861] <TB2> INFO: level: 15
[08:32:00.861] <TB2> INFO: triggerdelay: 0
[08:32:00.861] <TB2> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[08:32:00.861] <TB2> INFO: Log level: INFO
[08:32:00.873] <TB2> INFO: Found DTB DTB_WXENWR
[08:32:00.884] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[08:32:00.886] <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:32:00.888] <TB2> INFO: RPC call hashes of host and DTB match: 486171790
[08:32:02.423] <TB2> INFO: DUT info:
[08:32:02.423] <TB2> INFO: The DUT currently contains the following objects:
[08:32:02.423] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:32:02.423] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:32:02.423] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:32:02.423] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:32:02.423] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.423] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:02.823] <TB2> INFO: enter 'restricted' command line mode
[08:32:02.823] <TB2> INFO: enter test to run
[08:32:02.824] <TB2> INFO: test: delay setting parameters: ->5<-
[08:32:02.824] <TB2> INFO: delay test by 5 seconds...
[08:32:07.824] <TB2> INFO: enter test to run
[08:32:07.824] <TB2> INFO: test: Xray no parameter change
[08:32:07.824] <TB2> INFO: running: xray
[08:32:07.824] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2324_XrayQualification_2016-09-20_10h14m_1474359284//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2324_XrayQualification_2016-09-20_10h14m_1474359284//005_HRData_150//phCalibrationFitErr35_C15.dat
[08:32:08.019] <TB2> INFO: ######################################################################
[08:32:08.019] <TB2> INFO: PixTestXray::doTest()
[08:32:08.019] <TB2> INFO: ######################################################################
[08:32:08.019] <TB2> INFO: ----------------------------------------------------------------------
[08:32:08.019] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:32:08.019] <TB2> INFO: ----------------------------------------------------------------------
[08:32:08.742] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:32:12.709] <TB2> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[08:32:38.977] <TB2> INFO: Resuming triggers.
[08:32:42.963] <TB2> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[08:33:08.903] <TB2> INFO: Resuming triggers.
[08:33:12.886] <TB2> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[08:33:39.393] <TB2> INFO: Resuming triggers.
[08:33:43.375] <TB2> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[08:34:09.152] <TB2> INFO: Resuming triggers.
[08:34:13.129] <TB2> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[08:34:39.036] <TB2> INFO: Resuming triggers.
[08:34:43.012] <TB2> INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[08:35:08.976] <TB2> INFO: Resuming triggers.
[08:35:12.948] <TB2> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[08:35:38.550] <TB2> INFO: Resuming triggers.
[08:35:42.519] <TB2> INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[08:36:09.389] <TB2> INFO: Resuming triggers.
[08:36:13.361] <TB2> INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[08:36:40.252] <TB2> INFO: Resuming triggers.
[08:36:44.217] <TB2> INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[08:37:10.857] <TB2> INFO: Resuming triggers.
[08:37:14.822] <TB2> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[08:37:41.446] <TB2> INFO: Resuming triggers.
[08:37:45.409] <TB2> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[08:37:46.254] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L512> Channel 2 has NoTokenPass but 2 ROCs were found
[08:37:46.254] <TB2> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[08:37:46.254] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a297 8041 4180 100 2669 104 2261 109 2a6f 15d 2a6f 4180 120 2081 155 2a6b 758 2048 84d 2a69 4180 214 2a4f 85b 2065 4180 82 2a85 618 2a65 e000 c000
[08:37:46.254] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a291 80c1 41c0 34d 264f 4d1 244c 4180 541 284a 684 2862 41c0 162 246a 309 2081 4c3 2665 4180 713 2a4f e000 c000
[08:37:46.254] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a292 8001 41c0 448 2662 4180 41c1 492 244b 41c1 0 2642 42 2065 149 226c 209 206d 70a 2a45 e000 c000
[08:37:46.254] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a293 8041 41c0 b 2846 509 2a60 520 2a60 618 222c 41c1 e1 2680 29b 208d 48d 2a46 51c 2868 68c 2645 821 264d 41c0 11 204f 43 268b c1 2047 158 2262 4181 c4 2067 11b 2683 448 264f e000 c000
[08:37:46.254] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a294 80b1 41c0 500 262e 521 2266 61a 2640 719 2442 4180 54 2840 4d 206a 248 204c 250 224f 31b 2280 e0e7 c026
[08:37:46.254] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a295 80c1 4180 11c 2a6c 140 2449 54d 224d 4180 651 2465 81d 2881 4180 93 2241 491 2667 4180 311 2445 309 2640 400 2869 e000 c000
[08:37:46.254] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a296 8001 4180 344 2645 761 284c 75b 2a41 4180 83 2269 550 2242 4180 101 2665 4d2 2849 4181 e000 c000
[08:38:12.643] <TB2> INFO: Resuming triggers.
[08:38:16.606] <TB2> INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[08:38:43.561] <TB2> INFO: Resuming triggers.
[08:38:47.521] <TB2> INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[08:39:14.188] <TB2> INFO: Resuming triggers.
[08:39:18.144] <TB2> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[08:39:45.050] <TB2> INFO: Resuming triggers.
[08:39:49.010] <TB2> INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[08:40:16.641] <TB2> INFO: Resuming triggers.
[08:40:20.598] <TB2> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[08:40:46.877] <TB2> INFO: Resuming triggers.
[08:40:50.831] <TB2> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[08:41:17.351] <TB2> INFO: Resuming triggers.
[08:41:21.305] <TB2> INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[08:41:45.741] <TB2> INFO: Resuming triggers.
[08:41:49.696] <TB2> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[08:42:13.890] <TB2> INFO: Resuming triggers.
[08:42:17.841] <TB2> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[08:42:43.094] <TB2> INFO: Resuming triggers.
[08:42:47.042] <TB2> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[08:43:12.268] <TB2> INFO: Resuming triggers.
[08:43:16.220] <TB2> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[08:43:41.909] <TB2> INFO: Resuming triggers.
[08:43:45.855] <TB2> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[08:44:11.218] <TB2> INFO: Resuming triggers.
[08:44:15.164] <TB2> INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[08:44:41.612] <TB2> INFO: Resuming triggers.
[08:44:42.630] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[08:44:49.027] <TB2> INFO: PixTest:: pg_setup set to default.
[08:44:49.028] <TB2> INFO: PixTestXray::doPhRun() done
[08:44:49.028] <TB2> INFO: PixTestXray::doTest() done
[08:44:49.208] <TB2> INFO: enter test to run
[08:44:49.208] <TB2> INFO: test: no parameter change
[08:44:49.504] <TB2> QUIET: Connection to board 162 closed.
[08:44:49.584] <TB2> INFO: pXar: this is the end, my friend