[15:16:48.404] <TB0> INFO: *** Welcome to pxar ***
[15:16:48.404] <TB0> INFO: *** Today: 2015/09/11
[15:16:48.404] <TB0> INFO: readRocDacs: /usr/local/data/M2034_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2034_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//dacParameters35_C15.dat
[15:16:48.405] <TB0> INFO: readTbmDacs: /usr/local/data/M2034_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2034_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//tbmParameters_C0b.dat
[15:16:48.405] <TB0> INFO: readMaskFile: /usr/local/data/M2034_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//defaultMaskFile.dat
[15:16:48.405] <TB0> INFO: readTrimFile: /usr/local/data/M2034_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2034_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//trimParameters35_C15.dat
[15:16:48.475] <TB0> INFO: clk: 4
[15:16:48.475] <TB0> INFO: ctr: 4
[15:16:48.475] <TB0> INFO: sda: 19
[15:16:48.475] <TB0> INFO: tin: 9
[15:16:48.475] <TB0> INFO: level: 15
[15:16:48.475] <TB0> INFO: triggerdelay: 0
[15:16:48.475] <TB0> QUIET: Instanciating API for pxar prod-10+24~g09f6d2c
[15:16:48.475] <TB0> INFO: Log level: INFO
[15:16:48.507] <TB0> INFO: Found DTB DTB_WS6UZO
[15:16:48.516] <TB0> QUIET: Connection to board DTB_WS6UZO opened.
[15:16:48.519] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[15:16:48.521] <TB0> INFO: RPC call hashes of host and DTB match: 398089610
[15:16:50.035] <TB0> INFO: DUT info:
[15:16:50.035] <TB0> INFO: The DUT currently contains the following objects:
[15:16:50.035] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[15:16:50.035] <TB0> INFO: TBM Core alpha (0): 7 registers set
[15:16:50.035] <TB0> INFO: TBM Core beta (1): 7 registers set
[15:16:50.035] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:16:50.035] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.035] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.035] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.035] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.035] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.035] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.035] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.035] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.035] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.035] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.035] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.035] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.035] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.036] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.036] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.036] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:50.436] <TB0> INFO: enter 'restricted' command line mode
[15:16:50.436] <TB0> INFO: enter test to run
[15:16:50.436] <TB0> INFO: test: delay setting parameters: ->5<-
[15:16:50.436] <TB0> INFO: delay test by 5 seconds...
[15:16:55.436] <TB0> INFO: enter test to run
[15:16:55.436] <TB0> INFO: test: Xray no parameter change
[15:16:55.436] <TB0> INFO: running: xray
[15:16:55.437] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2034_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2034_XrayQualification_2015-09-11_16h58m_1441983510//004_HRData_150//phCalibrationFitErr35_C15.dat
[15:16:55.568] <TB0> INFO: ######################################################################
[15:16:55.568] <TB0> INFO: PixTestXray::doTest()
[15:16:55.568] <TB0> INFO: ######################################################################
[15:16:55.568] <TB0> INFO: ----------------------------------------------------------------------
[15:16:55.568] <TB0> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:16:55.568] <TB0> INFO: ----------------------------------------------------------------------
[15:16:56.535] <TB0> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:17:00.160] <TB0> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[15:17:35.838] <TB0> INFO: Resuming triggers.
[15:17:39.471] <TB0> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:18:12.876] <TB0> INFO: Resuming triggers.
[15:18:16.506] <TB0> INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[15:18:49.824] <TB0> INFO: Resuming triggers.
[15:18:53.456] <TB0> INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[15:19:26.833] <TB0> INFO: Resuming triggers.
[15:19:30.465] <TB0> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[15:20:04.076] <TB0> INFO: Resuming triggers.
[15:20:07.707] <TB0> INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[15:20:43.906] <TB0> INFO: Resuming triggers.
[15:20:47.539] <TB0> INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[15:20:51.436] <TB0> WARNING: Channel 0 ROC 3: Readback start marker after 13 readouts!
[15:20:51.436] <TB0> WARNING: Channel 0 ROC 3: Readback start marker after 3 readouts!
[15:20:51.455] <TB0> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:20:51.455] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e2 8000 4e38 14c 2a4f 682 2647 4e38 4e39 4e39 25b 2483 40b 224f 6cc 2269 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[15:20:51.455] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0dc 80b1 4e38 4e38 4e38 4e3b 2f4f 499 286a 499 2a4f 49c 2a45 4e38 2d4 2080 45d 286a fff 2fff fff 2fff fff 2fff e000 c000
[15:20:51.456] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0dd 80c0 4e38 4e38 4e38 118 2641 158 2444 4e38 fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[15:20:51.456] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0de 8000 4e38 45c 2626 71a 2a49 4e38 88 2042 4e38 58 2845 2a0 222f 4e38 d 2080 113 2a6d 4a2 2687 80d 2483 e000 c000
[15:20:51.456] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0df 8040 4e3a 618 2247 4e3a 640 2629 4e3a 2e2 204b 4e3a fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[15:20:51.456] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e0 80b1 4e38 15a 204d 4e38 158 2044 20b 286d 4e38 654 224d 4e38 220 204e 644 228a fff 2fff e000 c000
[15:20:51.456] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e1 80c0 4e38 31d 2840 450 2825 4e38 743 2069 4e38 549 226c 4e38 4ca 244f fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[15:21:23.843] <TB0> INFO: Resuming triggers.
[15:21:27.473] <TB0> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[15:22:02.348] <TB0> INFO: Resuming triggers.
[15:22:05.979] <TB0> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[15:22:41.105] <TB0> INFO: Resuming triggers.
[15:22:44.737] <TB0> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[15:23:19.981] <TB0> INFO: Resuming triggers.
[15:23:23.612] <TB0> INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[15:23:58.695] <TB0> INFO: Resuming triggers.
[15:24:02.326] <TB0> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[15:24:37.934] <TB0> INFO: Resuming triggers.
[15:24:41.563] <TB0> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[15:25:18.434] <TB0> INFO: Resuming triggers.
[15:25:22.063] <TB0> INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[15:25:57.917] <TB0> INFO: Resuming triggers.
[15:26:01.551] <TB0> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[15:26:36.791] <TB0> INFO: Resuming triggers.
[15:26:40.423] <TB0> INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[15:27:16.514] <TB0> INFO: Resuming triggers.
[15:27:20.143] <TB0> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[15:27:55.641] <TB0> INFO: Resuming triggers.
[15:27:59.271] <TB0> INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[15:28:34.817] <TB0> INFO: Resuming triggers.
[15:28:38.446] <TB0> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[15:29:15.560] <TB0> INFO: Resuming triggers.
[15:29:19.189] <TB0> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[15:29:55.114] <TB0> INFO: Resuming triggers.
[15:29:58.744] <TB0> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[15:30:34.156] <TB0> INFO: Resuming triggers.
[15:30:37.784] <TB0> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[15:31:13.731] <TB0> INFO: Resuming triggers.
[15:31:17.362] <TB0> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[15:31:52.390] <TB0> INFO: Resuming triggers.
[15:31:56.021] <TB0> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[15:32:30.629] <TB0> INFO: Resuming triggers.
[15:32:34.258] <TB0> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[15:33:10.382] <TB0> INFO: Resuming triggers.
[15:33:14.009] <TB0> INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[15:33:49.975] <TB0> INFO: Resuming triggers.
[15:33:53.600] <TB0> INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[15:34:28.347] <TB0> INFO: Resuming triggers.
[15:34:30.423] <TB0> INFO: data taking finished, elapsed time: 100 seconds.
[15:34:51.774] <TB0> INFO: PixTest:: pg_setup set to default.
[15:34:51.776] <TB0> INFO: PixTestXray::doPhRun() done
[15:34:51.776] <TB0> INFO: PixTestXray::doTest() done
[15:34:51.959] <TB0> INFO: enter test to run
[15:34:51.959] <TB0> INFO: test: no parameter change
[15:34:52.187] <TB0> QUIET: Connection to board 83 closed.
[15:34:52.269] <TB0> INFO: pXar: this is the end, my friend