[15:17:07.753] <TB2> INFO: *** Welcome to pxar ***
[15:17:07.753] <TB2> INFO: *** Today: 2015/09/11
[15:17:07.753] <TB2> INFO: readRocDacs: /usr/local/data/M2076_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2076_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//dacParameters35_C15.dat
[15:17:07.755] <TB2> INFO: readTbmDacs: /usr/local/data/M2076_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2076_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//tbmParameters_C0b.dat
[15:17:07.755] <TB2> INFO: readMaskFile: /usr/local/data/M2076_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//defaultMaskFile.dat
[15:17:07.755] <TB2> INFO: readTrimFile: /usr/local/data/M2076_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2076_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//trimParameters35_C15.dat
[15:17:07.827] <TB2> INFO: clk: 4
[15:17:07.827] <TB2> INFO: ctr: 4
[15:17:07.827] <TB2> INFO: sda: 19
[15:17:07.827] <TB2> INFO: tin: 9
[15:17:07.827] <TB2> INFO: level: 15
[15:17:07.827] <TB2> INFO: triggerdelay: 0
[15:17:07.827] <TB2> QUIET: Instanciating API for pxar prod-10+24~g09f6d2c
[15:17:07.827] <TB2> INFO: Log level: INFO
[15:17:07.839] <TB2> INFO: Found DTB DTB_WXENWR
[15:17:07.851] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[15:17:07.854] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[15:17:07.857] <TB2> INFO: RPC call hashes of host and DTB match: 398089610
[15:17:09.403] <TB2> INFO: DUT info:
[15:17:09.403] <TB2> INFO: The DUT currently contains the following objects:
[15:17:09.403] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[15:17:09.403] <TB2> INFO: TBM Core alpha (0): 7 registers set
[15:17:09.403] <TB2> INFO: TBM Core beta (1): 7 registers set
[15:17:09.403] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:17:09.403] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.403] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:09.804] <TB2> INFO: enter 'restricted' command line mode
[15:17:09.804] <TB2> INFO: enter test to run
[15:17:09.805] <TB2> INFO: test: delay setting parameters: ->5<-
[15:17:09.805] <TB2> INFO: delay test by 5 seconds...
[15:17:14.805] <TB2> INFO: enter test to run
[15:17:14.805] <TB2> INFO: test: Xray no parameter change
[15:17:14.805] <TB2> INFO: running: xray
[15:17:14.805] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2076_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2076_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//phCalibrationFitErr35_C15.dat
[15:17:14.953] <TB2> INFO: ######################################################################
[15:17:14.953] <TB2> INFO: PixTestXray::doTest()
[15:17:14.953] <TB2> INFO: ######################################################################
[15:17:14.953] <TB2> INFO: ----------------------------------------------------------------------
[15:17:14.953] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:17:14.953] <TB2> INFO: ----------------------------------------------------------------------
[15:17:15.926] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:17:19.022] <TB2> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[15:17:46.096] <TB2> INFO: Resuming triggers.
[15:17:49.213] <TB2> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:18:16.433] <TB2> INFO: Resuming triggers.
[15:18:19.548] <TB2> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[15:18:46.539] <TB2> INFO: Resuming triggers.
[15:18:49.671] <TB2> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:19:14.968] <TB2> INFO: Resuming triggers.
[15:19:18.083] <TB2> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:19:43.722] <TB2> INFO: Resuming triggers.
[15:19:46.838] <TB2> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[15:20:13.912] <TB2> INFO: Resuming triggers.
[15:20:17.032] <TB2> INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[15:20:45.750] <TB2> INFO: Resuming triggers.
[15:20:48.865] <TB2> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[15:21:16.371] <TB2> INFO: Resuming triggers.
[15:21:19.486] <TB2> INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[15:21:46.113] <TB2> INFO: Resuming triggers.
[15:21:49.227] <TB2> INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[15:22:14.768] <TB2> INFO: Resuming triggers.
[15:22:17.882] <TB2> INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:22:45.551] <TB2> INFO: Resuming triggers.
[15:22:48.666] <TB2> INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[15:23:15.695] <TB2> INFO: Resuming triggers.
[15:23:18.810] <TB2> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[15:23:44.673] <TB2> INFO: Resuming triggers.
[15:23:47.791] <TB2> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[15:24:13.925] <TB2> INFO: Resuming triggers.
[15:24:17.039] <TB2> INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:24:44.890] <TB2> INFO: Resuming triggers.
[15:24:48.004] <TB2> INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[15:25:16.598] <TB2> INFO: Resuming triggers.
[15:25:19.713] <TB2> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[15:25:46.534] <TB2> INFO: Resuming triggers.
[15:25:49.648] <TB2> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[15:26:16.497] <TB2> INFO: Resuming triggers.
[15:26:19.607] <TB2> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[15:26:45.731] <TB2> INFO: Resuming triggers.
[15:26:48.845] <TB2> INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[15:27:16.278] <TB2> INFO: Resuming triggers.
[15:27:19.391] <TB2> INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[15:27:46.307] <TB2> INFO: Resuming triggers.
[15:27:49.420] <TB2> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[15:28:15.261] <TB2> INFO: Resuming triggers.
[15:28:18.372] <TB2> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[15:28:45.179] <TB2> INFO: Resuming triggers.
[15:28:48.289] <TB2> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[15:29:16.834] <TB2> INFO: Resuming triggers.
[15:29:19.947] <TB2> INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[15:29:46.992] <TB2> INFO: Resuming triggers.
[15:29:50.103] <TB2> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[15:30:01.199] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L484> Channel 2 Number of ROCs (2) != Token Chain Length (4)
[15:30:01.199] <TB2> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:30:01.199] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d7 8040 40e8 c4 2040 602 2848 655 2a66 6c8 2644 40e8 9 204e 2cd 2a6f 490 246a 851 286d 40e8 d0 224c 285 2a86 4dd 2469 512 2061 504 204e 548 2065 851 2463 40e8 5 264e 62 2a69 140 2685 e000 c000
[15:30:01.199] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d1 80c0 40e8 92 284a ca 2268 510 2665 40e8 5a 2868 52 2481 141 246b 41b 246f 410 2a4d 44c 2880 44c 2462 40e8 121 2a61 348 284d 40e8 12 282f e000 c000
[15:30:01.199] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d2 8000 40e8 40e8 11 286d 5 2081 148 2a6f 40e9 40e8 14 244e 54b 2468 6cb 2286 fff 2fff fff 2fff e000 c000
[15:30:01.199] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d3 8040 40e8 40 2648 c5 204e 28d 2a6d 4c4 282c 40e9 dd 264f 2d0 2a46 745 2a4c 40e8 40e8 419 2a48 60a 2842 e000 c000
[15:30:01.199] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d4 80b1 40e8 6ff 29cf 7f8 2202 44a 254d 82e 2648 a2f 2748 48 40e8 12 248c c9 2249 649 264f 40e8 9 2a68 402 2062 508 2849 e000 c000
[15:30:01.199] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d5 80c0 40e8 118 2848 285 2048 299 2245 29d 2246 401 2a4a 501 206e 40e8 40e8 1c 2a4f c3 2685 483 248d 642 2841 40e8 43 2a6f 2c9 224f 85c 264e e000 c000
[15:30:01.199] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d6 8000 40e8 9b 204f 250 226d 4d8 282c 515 2483 551 244c 540 2668 6c9 202f 70c 2825 40e8 8c 2a29 10c 2269 34b 2664 351 2245 65a 224f 40e8 c5 2a80 c8 2283 da 226e 518 2061 652 2a42 659 2a65 40e8 40 2662 551 2266 622 2a6f 848 264a e000 c000
[15:30:17.076] <TB2> INFO: Resuming triggers.
[15:30:20.190] <TB2> INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[15:30:46.467] <TB2> INFO: Resuming triggers.
[15:30:49.576] <TB2> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[15:31:17.088] <TB2> INFO: Resuming triggers.
[15:31:20.198] <TB2> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[15:31:47.661] <TB2> INFO: Resuming triggers.
[15:31:50.771] <TB2> INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[15:32:17.043] <TB2> INFO: Resuming triggers.
[15:32:20.153] <TB2> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[15:32:46.730] <TB2> INFO: Resuming triggers.
[15:32:49.839] <TB2> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[15:33:16.021] <TB2> INFO: Resuming triggers.
[15:33:16.456] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[15:33:20.817] <TB2> INFO: PixTest:: pg_setup set to default.
[15:33:20.819] <TB2> INFO: PixTestXray::doPhRun() done
[15:33:20.819] <TB2> INFO: PixTestXray::doTest() done
[15:33:21.019] <TB2> INFO: enter test to run
[15:33:21.019] <TB2> INFO: test: no parameter change
[15:33:21.305] <TB2> QUIET: Connection to board 162 closed.
[15:33:21.390] <TB2> INFO: pXar: this is the end, my friend