[09:32:29.780] <TB0> INFO: *** Welcome to pxar ***
[09:32:29.780] <TB0> INFO: *** Today: 2016/08/10
[09:32:30.144] <TB0> INFO: *** Version: f5d3-dirty
[09:32:30.144] <TB0> INFO: readRocDacs: /usr/local/data/M2287_XrayQualification_2016-08-10_11h12m_1470820366//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2287_XrayQualification_2016-08-10_11h12m_1470820366//005_HRData_150//dacParameters35_C15.dat
[09:32:30.145] <TB0> INFO: readTbmDacs: /usr/local/data/M2287_XrayQualification_2016-08-10_11h12m_1470820366//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2287_XrayQualification_2016-08-10_11h12m_1470820366//005_HRData_150//tbmParameters_C0b.dat
[09:32:30.145] <TB0> INFO: readMaskFile: /usr/local/data/M2287_XrayQualification_2016-08-10_11h12m_1470820366//005_HRData_150//defaultMaskFile.dat
[09:32:30.145] <TB0> INFO: readTrimFile: /usr/local/data/M2287_XrayQualification_2016-08-10_11h12m_1470820366//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2287_XrayQualification_2016-08-10_11h12m_1470820366//005_HRData_150//trimParameters35_C15.dat
[09:32:30.212] <TB0> INFO: clk: 4
[09:32:30.212] <TB0> INFO: ctr: 4
[09:32:30.212] <TB0> INFO: sda: 19
[09:32:30.212] <TB0> INFO: tin: 9
[09:32:30.212] <TB0> INFO: level: 15
[09:32:30.212] <TB0> INFO: triggerdelay: 0
[09:32:30.212] <TB0> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[09:32:30.212] <TB0> INFO: Log level: INFO
[09:32:30.225] <TB0> INFO: Found DTB DTB_WS6UZO
[09:32:30.233] <TB0> QUIET: Connection to board DTB_WS6UZO opened.
[09:32:30.235] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[09:32:30.237] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[09:32:31.765] <TB0> INFO: DUT info:
[09:32:31.766] <TB0> INFO: The DUT currently contains the following objects:
[09:32:31.766] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[09:32:31.766] <TB0> INFO: TBM Core alpha (0): 7 registers set
[09:32:31.766] <TB0> INFO: TBM Core beta (1): 7 registers set
[09:32:31.766] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:32:31.766] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:31.766] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:32:32.166] <TB0> INFO: enter 'restricted' command line mode
[09:32:32.166] <TB0> INFO: enter test to run
[09:32:32.166] <TB0> INFO: test: delay setting parameters: ->5<-
[09:32:32.166] <TB0> INFO: delay test by 5 seconds...
[09:32:37.167] <TB0> INFO: enter test to run
[09:32:37.167] <TB0> INFO: test: Xray no parameter change
[09:32:37.167] <TB0> INFO: running: xray
[09:32:37.168] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2287_XrayQualification_2016-08-10_11h12m_1470820366//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2287_XrayQualification_2016-08-10_11h12m_1470820366//005_HRData_150//phCalibrationFitErr35_C15.dat
[09:32:37.297] <TB0> INFO: ######################################################################
[09:32:37.297] <TB0> INFO: PixTestXray::doTest()
[09:32:37.297] <TB0> INFO: ######################################################################
[09:32:37.297] <TB0> INFO: ----------------------------------------------------------------------
[09:32:37.297] <TB0> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:32:37.297] <TB0> INFO: ----------------------------------------------------------------------
[09:32:38.021] <TB0> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:32:42.062] <TB0> INFO: run duration 4 seconds, buffer almost full (81%), pausing triggers.
[09:33:11.845] <TB0> INFO: Resuming triggers.
[09:33:15.890] <TB0> INFO: run duration 8 seconds, buffer almost full (81%), pausing triggers.
[09:33:45.530] <TB0> INFO: Resuming triggers.
[09:33:49.572] <TB0> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[09:34:18.898] <TB0> INFO: Resuming triggers.
[09:34:22.940] <TB0> INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[09:34:52.873] <TB0> INFO: Resuming triggers.
[09:34:56.915] <TB0> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[09:35:27.048] <TB0> INFO: Resuming triggers.
[09:35:31.088] <TB0> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[09:36:00.985] <TB0> INFO: Resuming triggers.
[09:36:05.024] <TB0> INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[09:36:34.544] <TB0> INFO: Resuming triggers.
[09:36:38.587] <TB0> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[09:37:08.711] <TB0> INFO: Resuming triggers.
[09:37:12.750] <TB0> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[09:37:42.619] <TB0> INFO: Resuming triggers.
[09:37:46.659] <TB0> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[09:38:15.595] <TB0> INFO: Resuming triggers.
[09:38:19.636] <TB0> INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[09:38:49.231] <TB0> INFO: Resuming triggers.
[09:38:53.268] <TB0> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[09:39:23.279] <TB0> INFO: Resuming triggers.
[09:39:27.316] <TB0> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[09:39:57.104] <TB0> INFO: Resuming triggers.
[09:40:01.145] <TB0> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[09:40:30.226] <TB0> INFO: Resuming triggers.
[09:40:34.266] <TB0> INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[09:41:04.140] <TB0> INFO: Resuming triggers.
[09:41:08.181] <TB0> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[09:41:38.449] <TB0> INFO: Resuming triggers.
[09:41:42.504] <TB0> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[09:42:12.547] <TB0> INFO: Resuming triggers.
[09:42:16.585] <TB0> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[09:42:17.079] <TB0> WARNING: Detected DESER400 trailer error bits: "IDLE DATA"
[09:42:17.079] <TB0> ERROR: <datapipe.cc/CheckEventID:L469> Channel 1 Event ID mismatch: local ID (59) != TBM ID (60)
[09:42:17.079] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!
[09:42:17.079] <TB0> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!
[09:42:17.079] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!
[09:42:17.079] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[09:42:17.079] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[09:42:17.079] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a142 8000 4870 82 2024 2cc 282d 514 2665 800 2626 4070 248 222c 508 244d 651 2883 640 262d 701 224a 4070 21d 2641 4071 341 2845 e000 c000
[09:42:17.079] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a13c 80b0 4870 70c 2242 4070 4070 299 226f 550 264a 6cc 2249 4071 e000 c000
[09:42:17.079] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a13d 80c0 4870 68d 204a 68c 2829 4870 6d2 2842 4072 299 2084 412 2647 760 2023 4870 2c3 224d e000 c000
[09:42:17.079] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a13e 8000 4830 4070 4d3 2069 740 2a4b 4070 12 202d 11 284d 458 2463 4070 20b 2665 20b 2a62 705 2665 e000 c000
[09:42:17.080] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a13f 8040 4072 31a 204c 821 2880 4072 149 2a4e 34d 266c 445 264f 4871 4d0 2445 715 284c 4072 459 2661 4c9 2448 4c0 244f 655 2a4f e000 c000
[09:42:17.080] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a140 80b0 4070 c8 2448 6c2 2868 4070 18 282f 5 2047 d4 2661 50c 2440 4071 218 2845 214 2a45 309 2861 315 2a4f 31d 2668 35b 2a4d 4870 34b 2460 54b 286b e000 c000
[09:42:17.080] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a141 80c0 4071 60 2266 90 2449 24b 2867 45d 2669 645 2a4d 752 2a4d 753 226d 40f1 10c 244f 40b 206a 445 2a65 51a 284c 758 202d 74a 2462 84d 2248 4070 10c 2a65 289 2068 4071 350 204d e000 c000
[09:42:46.685] <TB0> INFO: Resuming triggers.
[09:42:50.723] <TB0> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[09:43:20.289] <TB0> INFO: Resuming triggers.
[09:43:24.326] <TB0> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[09:43:53.361] <TB0> INFO: Resuming triggers.
[09:43:57.397] <TB0> INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[09:44:26.718] <TB0> INFO: Resuming triggers.
[09:44:30.754] <TB0> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[09:45:01.053] <TB0> INFO: Resuming triggers.
[09:45:05.091] <TB0> INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[09:45:35.020] <TB0> INFO: Resuming triggers.
[09:45:39.054] <TB0> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[09:46:08.356] <TB0> INFO: Resuming triggers.
[09:46:11.481] <TB0> INFO: data taking finished, elapsed time: 100 seconds.
[09:46:34.679] <TB0> INFO: PixTest:: pg_setup set to default.
[09:46:34.681] <TB0> INFO: PixTestXray::doPhRun() done
[09:46:34.681] <TB0> INFO: PixTestXray::doTest() done
[09:46:34.890] <TB0> INFO: enter test to run
[09:46:34.890] <TB0> INFO: test: no parameter change
[09:46:35.188] <TB0> QUIET: Connection to board 83 closed.
[09:46:35.268] <TB0> INFO: pXar: this is the end, my friend