[12:54:23.610] <TB2> INFO: *** Welcome to pxar ***
[12:54:23.610] <TB2> INFO: *** Today: 2015/11/23
[12:54:23.758] <TB2> INFO: *** Version: 206d-dirty
[12:54:23.758] <TB2> INFO: readRocDacs: /usr/local/data/M2029_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2029_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//dacParameters35_C15.dat
[12:54:23.759] <TB2> INFO: readTbmDacs: /usr/local/data/M2029_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2029_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//tbmParameters_C0b.dat
[12:54:23.759] <TB2> INFO: readMaskFile: /usr/local/data/M2029_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//defaultMaskFile.dat
[12:54:23.759] <TB2> INFO: readTrimFile: /usr/local/data/M2029_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2029_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//trimParameters35_C15.dat
[12:54:23.854] <TB2> INFO: clk: 4
[12:54:23.854] <TB2> INFO: ctr: 4
[12:54:23.854] <TB2> INFO: sda: 19
[12:54:23.854] <TB2> INFO: tin: 9
[12:54:23.854] <TB2> INFO: level: 15
[12:54:23.854] <TB2> INFO: triggerdelay: 0
[12:54:23.854] <TB2> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[12:54:23.854] <TB2> INFO: Log level: INFO
[12:54:23.868] <TB2> INFO: Found DTB DTB_WXENWR
[12:54:23.887] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[12:54:23.891] <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:
------------------------------------------------------
[12:54:23.894] <TB2> INFO: RPC call hashes of host and DTB match: 398089610
[12:54:25.444] <TB2> INFO: DUT info:
[12:54:25.444] <TB2> INFO: The DUT currently contains the following objects:
[12:54:25.444] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:54:25.444] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:54:25.444] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:54:25.444] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:54:25.444] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.444] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.444] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.444] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.444] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.444] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.444] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.444] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.444] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.444] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.444] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.444] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.444] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.445] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.445] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.445] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:25.846] <TB2> INFO: enter 'restricted' command line mode
[12:54:25.846] <TB2> INFO: enter test to run
[12:54:25.846] <TB2> INFO: test: delay setting parameters: ->5<-
[12:54:25.846] <TB2> INFO: delay test by 5 seconds...
[12:54:30.846] <TB2> INFO: enter test to run
[12:54:30.846] <TB2> INFO: test: Xray no parameter change
[12:54:30.846] <TB2> INFO: running: xray
[12:54:30.853] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2029_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2029_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//phCalibrationFitErr35_C15.dat
[12:54:31.023] <TB2> INFO: ######################################################################
[12:54:31.023] <TB2> INFO: PixTestXray::doTest()
[12:54:31.023] <TB2> INFO: ######################################################################
[12:54:31.023] <TB2> INFO: ----------------------------------------------------------------------
[12:54:31.023] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:54:31.023] <TB2> INFO: ----------------------------------------------------------------------
[12:54:31.997] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:54:35.124] <TB2> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[12:55:02.504] <TB2> INFO: Resuming triggers.
[12:55:05.645] <TB2> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[12:55:31.862] <TB2> INFO: Resuming triggers.
[12:55:35.004] <TB2> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[12:56:01.468] <TB2> INFO: Resuming triggers.
[12:56:04.611] <TB2> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[12:56:31.062] <TB2> INFO: Resuming triggers.
[12:56:34.202] <TB2> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[12:56:59.884] <TB2> INFO: Resuming triggers.
[12:57:03.024] <TB2> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[12:57:29.231] <TB2> INFO: Resuming triggers.
[12:57:32.371] <TB2> INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[12:57:59.171] <TB2> INFO: Resuming triggers.
[12:58:02.311] <TB2> INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[12:58:28.998] <TB2> INFO: Resuming triggers.
[12:58:32.137] <TB2> INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[12:58:59.026] <TB2> INFO: Resuming triggers.
[12:59:02.164] <TB2> INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[12:59:28.321] <TB2> INFO: Resuming triggers.
[12:59:31.462] <TB2> INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:59:58.719] <TB2> INFO: Resuming triggers.
[13:00:01.858] <TB2> INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[13:00:29.029] <TB2> INFO: Resuming triggers.
[13:00:32.171] <TB2> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[13:00:59.434] <TB2> INFO: Resuming triggers.
[13:01:02.573] <TB2> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[13:01:28.335] <TB2> INFO: Resuming triggers.
[13:01:31.475] <TB2> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[13:01:56.554] <TB2> INFO: Resuming triggers.
[13:01:59.691] <TB2> INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[13:02:26.594] <TB2> INFO: Resuming triggers.
[13:02:29.732] <TB2> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[13:02:57.213] <TB2> INFO: Resuming triggers.
[13:03:00.350] <TB2> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[13:03:27.628] <TB2> INFO: Resuming triggers.
[13:03:30.768] <TB2> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[13:03:56.748] <TB2> INFO: Resuming triggers.
[13:03:59.888] <TB2> INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[13:04:02.262] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L477> Channel 2 has NoTokenPass but 1 ROCs were found
[13:04:02.262] <TB2> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:04:02.262] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d7 8040 4818 715 2624 742 2248 75d 2a4c 809 26c1 4818 9 202f 11 2265 30c 2860 460 2466 84c 2a29 4818 c2 2844 64b 224b 68d 2065 4818 302 2229 408 264f 4d8 2849 851 2a61 e000 c000
[13:04:02.263] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d1 80c0 4818 92 2a48 93 222d 119 2260 4818 22 2045 2c8 264f 49b 2486 484 2269 811 2868 842 222f 84b 224f 4818 6dd 2a22 4818 808 2a4d e000 c000
[13:04:02.263] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d2 8000 4818 494 2462 6c4 260f 4818 444 2664 449 2a65 4819 252 244d 541 2a49 84a 2a69 4819 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[13:04:02.263] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d3 8040 4818 34d 206e 48d 2249 51d 2060 4819 4818 53 286f c2 2a6f 4819 212 2063 fff 2fff fff 2fff e000 c000
[13:04:02.263] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d4 80b0 4818 149 2468 e0f1 c026
[13:04:02.263] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d5 80c0 4818 24a 264e 28b 2a62 2c5 222c 4d0 266e 50b 224b 4818 10d 2669 313 2869 499 286d 4d2 2281 60b 2461 4818 8 286b d 2a6d 122 2462 143 264d 4818 448 208d e000 c000
[13:04:02.263] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d6 8000 4818 4a 2046 203 2a66 284 224f 851 2269 4818 220 204f 219 2664 2d1 284c 4818 100 2a40 4e0 2061 4cc 2847 4c0 2a40 4818 205 224d 28d 2a47 50d 264b e000 c000
[13:04:26.213] <TB2> INFO: Resuming triggers.
[13:04:29.348] <TB2> INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[13:04:54.551] <TB2> INFO: Resuming triggers.
[13:04:57.687] <TB2> INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[13:05:22.771] <TB2> INFO: Resuming triggers.
[13:05:25.908] <TB2> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[13:05:52.374] <TB2> INFO: Resuming triggers.
[13:05:55.510] <TB2> INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[13:06:21.730] <TB2> INFO: Resuming triggers.
[13:06:24.867] <TB2> INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[13:06:51.656] <TB2> INFO: Resuming triggers.
[13:06:54.791] <TB2> INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[13:07:20.614] <TB2> INFO: Resuming triggers.
[13:07:23.749] <TB2> INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[13:07:49.491] <TB2> INFO: Resuming triggers.
[13:07:52.629] <TB2> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[13:08:17.824] <TB2> INFO: Resuming triggers.
[13:08:20.958] <TB2> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[13:08:47.462] <TB2> INFO: Resuming triggers.
[13:08:50.599] <TB2> INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[13:09:16.607] <TB2> INFO: Resuming triggers.
[13:09:19.739] <TB2> INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[13:09:46.149] <TB2> INFO: Resuming triggers.
[13:09:48.942] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[13:10:11.235] <TB2> INFO: PixTest:: pg_setup set to default.
[13:10:11.236] <TB2> INFO: PixTestXray::doPhRun() done
[13:10:11.236] <TB2> INFO: PixTestXray::doTest() done
[13:10:11.443] <TB2> INFO: enter test to run
[13:10:11.443] <TB2> INFO: test: no parameter change
[13:10:11.572] <TB2> QUIET: Connection to board 162 closed.
[13:10:11.652] <TB2> INFO: pXar: this is the end, my friend