[16:45:25.861] <TB0> INFO: *** Welcome to pxar ***
[16:45:25.861] <TB0> INFO: *** Today: 2016/09/27
[16:45:26.222] <TB0> INFO: *** Version: f5d3-dirty
[16:45:26.222] <TB0> INFO: readRocDacs: /usr/local/data/M2333_XrayQualification_2016-09-27_17h30m_1474990249//017_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2333_XrayQualification_2016-09-27_17h30m_1474990249//017_HREfficiency_200//dacParameters35_C15.dat
[16:45:26.223] <TB0> INFO: readTbmDacs: /usr/local/data/M2333_XrayQualification_2016-09-27_17h30m_1474990249//017_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2333_XrayQualification_2016-09-27_17h30m_1474990249//017_HREfficiency_200//tbmParameters_C0b.dat
[16:45:26.223] <TB0> INFO: readMaskFile: /usr/local/data/M2333_XrayQualification_2016-09-27_17h30m_1474990249//017_HREfficiency_200//defaultMaskFile.dat
[16:45:26.223] <TB0> INFO: readTrimFile: /usr/local/data/M2333_XrayQualification_2016-09-27_17h30m_1474990249//017_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2333_XrayQualification_2016-09-27_17h30m_1474990249//017_HREfficiency_200//trimParameters35_C15.dat
[16:45:26.292] <TB0> INFO: clk: 4
[16:45:26.292] <TB0> INFO: ctr: 4
[16:45:26.292] <TB0> INFO: sda: 19
[16:45:26.292] <TB0> INFO: tin: 9
[16:45:26.292] <TB0> INFO: level: 15
[16:45:26.292] <TB0> INFO: triggerdelay: 0
[16:45:26.292] <TB0> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[16:45:26.292] <TB0> INFO: Log level: INFO
[16:45:26.305] <TB0> INFO: Found DTB DTB_WS6UZO
[16:45:26.316] <TB0> QUIET: Connection to board DTB_WS6UZO opened.
[16:45:26.318] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[16:45:26.320] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[16:45:27.844] <TB0> INFO: DUT info:
[16:45:27.844] <TB0> INFO: The DUT currently contains the following objects:
[16:45:27.844] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:45:27.844] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:45:27.844] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:45:27.844] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:45:27.844] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:27.844] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:45:28.245] <TB0> INFO: enter 'restricted' command line mode
[16:45:28.245] <TB0> INFO: enter test to run
[16:45:28.245] <TB0> INFO: test: PixelAlive no parameter change
[16:45:28.245] <TB0> INFO: running: pixelalive
[16:45:28.248] <TB0> INFO: ######################################################################
[16:45:28.248] <TB0> INFO: PixTestAlive::doTest()
[16:45:28.248] <TB0> INFO: ######################################################################
[16:45:28.250] <TB0> INFO: ----------------------------------------------------------------------
[16:45:28.250] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:45:28.250] <TB0> INFO: ----------------------------------------------------------------------
[16:45:28.506] <TB0> INFO: Expecting 208000 events.
[16:45:37.304] <TB0> INFO: 208000 events read in total (8240ms).
[16:45:37.306] <TB0> INFO: Test took 9055ms.
[16:45:37.529] <TB0> INFO: PixTestAlive::aliveTest() done
[16:45:37.529] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:45:37.530] <TB0> INFO: ----------------------------------------------------------------------
[16:45:37.530] <TB0> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:45:37.530] <TB0> INFO: ----------------------------------------------------------------------
[16:45:37.792] <TB0> INFO: Expecting 208000 events.
[16:45:44.144] <TB0> INFO: 208000 events read in total (5796ms).
[16:45:44.146] <TB0> INFO: Test took 6615ms.
[16:45:44.146] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:45:44.357] <TB0> INFO: PixTestAlive::maskTest() done
[16:45:44.357] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:45:44.359] <TB0> INFO: ----------------------------------------------------------------------
[16:45:44.359] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:45:44.359] <TB0> INFO: ----------------------------------------------------------------------
[16:45:44.635] <TB0> INFO: Expecting 208000 events.
[16:45:53.554] <TB0> INFO: 208000 events read in total (8363ms).
[16:45:53.555] <TB0> INFO: Test took 9195ms.
[16:45:53.754] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[16:45:53.754] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:45:53.754] <TB0> INFO: PixTestAlive::doTest() done, duration: 25 seconds
[16:45:53.783] <TB0> INFO: enter test to run
[16:45:53.783] <TB0> INFO: test: delay setting parameters: ->2<-
[16:45:53.783] <TB0> INFO: delay test by 2 seconds...
[16:45:55.783] <TB0> INFO: enter test to run
[16:45:55.784] <TB0> INFO: test: HighRate no parameter change
[16:45:55.784] <TB0> INFO: running: highrate
[16:45:55.784] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2333_XrayQualification_2016-09-27_17h30m_1474990249//017_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2333_XrayQualification_2016-09-27_17h30m_1474990249//017_HREfficiency_200//phCalibrationFitErr35_C15.dat
[16:45:55.936] <TB0> INFO: ----------------------------------------------------------------------
[16:45:55.936] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:45:55.936] <TB0> INFO: ----------------------------------------------------------------------
[16:45:56.423] <TB0> INFO: Expecting 208000 events.
[16:46:05.334] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 1 Number of ROCs (5) != Token Chain Length (4)
[16:46:05.335] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[16:46:05.335] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1dd 80c0 4060 40b 20a5 662 2062 75d 2265 40f0 40b 206d 613 208c 6a0 224a 40e0 40b 20af 48a 2289 40f0 219 2042 40b 20a7 503 266c 555 2a41 e002 c000
[16:46:05.335] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1d7 8040 40f0 358 284c 40b 2482 40f0 62 208c 5d 2a80 a0 2a88 40b 248a 68d 2064 40f0 252 2462 40b 24a3 755 264d 40f0 22 2065 241 2a69 35d 2440 40b 24c1 842 2247 e002 c000
[16:46:05.335] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1d8 80b0 40f1 40b 2482 854 222a 40f1 2c8 264d 415 2845 40b 24a2 643 2a80 855 244c 40f1 9a 2462 8b 2061 8a 2862 40b 24c7 655 2a81 859 264b 40f1 40 2461 11b 2865 40b 24a4 455 206f 75b 2064 862 204f 860 2649 e002 c000
[16:46:05.335] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1d9 80c0 40e1 40b 2484 444 2847 40f1 10a 2665 2c0 2462 348 264d 40b 248d 40f1 352 2260 40b 24cb 502 2a4e 643 226d 819 226d 40e1 8c 2465 14d 2664 24c 2065 40b 24ad 511 286c 549 2a69 719 224f 802 2645 e002 c000
[16:46:05.335] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1da 8000 40f0 300 2628 40b 2485 40f0 201 2069 40b 248f 720 2062 40f0 142 2a68 241 206f 40b 24c5 40f0 40f3 de8 2169 4ea 2244 e002 c000
[16:46:05.335] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1db 8040 40f0 40b 2088 494 242a 65a 284b 40e0 103 2263 212 2261 2d2 2084 40b 20a7 40e0 1b 244c a2 20a1 152 204f 40b 20c4 41d 266f 4d2 224f 51a 2264 81b 246f 40e0 10 266d 40b 208a 69c 2647 844 2243 858 2647 e002 c000
[16:46:05.335] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1dc 80b0 40f1 28d 242c 40b 2084 70d 2241 750 244e 80b 2868 805 282d 811 2647 40f1 40b 2085 644 206a 40f1 40b 208c 494 2a4f 4da 2488 643 264d 6db 2a8f 40f1 40b 208b 60b 2847 60c 2661 615 2a65 e002 c000
[16:46:17.014] <TB0> INFO: 208000 events read in total (20189ms).
[16:46:17.030] <TB0> INFO: Test took 21091ms.
[16:46:18.403] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:46:18.403] <TB0> INFO: number of red-efficiency pixels: 1259 1135 1338 2418 2393 3326 3173 3310 3242 2752 3167 2950 2291 892 967 759
[16:46:18.403] <TB0> INFO: number of X-ray hits detected: 335833 354988 333035 520252 550146 683728 670559 666961 624162 605170 663021 558286 494166 290153 302148 274010
[16:46:18.403] <TB0> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:46:18.403] <TB0> INFO: number of Vcal hits detected: 206379 206629 205044 203465 203339 198946 198978 200344 200464 202110 200322 201018 203994 206466 206828 207110
[16:46:18.403] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.3 99.4 98.8 98.1 98.1 96.2 96.3 96.6 96.7 97.5 96.8 97.0 98.3 99.4 99.5 99.6
[16:46:18.403] <TB0> INFO: Vcal hit overall efficiency (%): 99.2 99.3 98.6 97.8 97.8 95.6 95.7 96.3 96.4 97.2 96.3 96.6 98.1 99.3 99.4 99.6
[16:46:18.403] <TB0> INFO: X-ray hit rate [MHz/cm2]: 98.4 104.0 97.6 152.5 161.3 200.4 196.5 195.5 182.9 177.4 194.3 163.6 144.8 85.0 88.6 80.3
[16:46:18.403] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[16:46:18.428] <TB0> INFO: PixTest:: pg_setup set to default.
[16:46:18.440] <TB0> INFO: enter test to run
[16:46:18.440] <TB0> INFO: test: no parameter change
[16:46:18.736] <TB0> QUIET: Connection to board 83 closed.
[16:46:18.816] <TB0> INFO: pXar: this is the end, my friend