[16:21:09.991] <TB1> INFO: *** Welcome to pxar ***
[16:21:09.991] <TB1> INFO: *** Today: 2016/03/04
[16:21:10.337] <TB1> INFO: *** Version: 9751
[16:21:10.337] <TB1> INFO: readRocDacs: /usr/local/data/M2178_XrayQualification_2016-03-04_16h01m_1457103714//018_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2178_XrayQualification_2016-03-04_16h01m_1457103714//018_HREfficiency_250//dacParameters35_C15.dat
[16:21:10.337] <TB1> INFO: readTbmDacs: /usr/local/data/M2178_XrayQualification_2016-03-04_16h01m_1457103714//018_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2178_XrayQualification_2016-03-04_16h01m_1457103714//018_HREfficiency_250//tbmParameters_C0b.dat
[16:21:10.338] <TB1> INFO: readMaskFile: /usr/local/data/M2178_XrayQualification_2016-03-04_16h01m_1457103714//018_HREfficiency_250//defaultMaskFile.dat
[16:21:10.338] <TB1> INFO: readTrimFile: /usr/local/data/M2178_XrayQualification_2016-03-04_16h01m_1457103714//018_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2178_XrayQualification_2016-03-04_16h01m_1457103714//018_HREfficiency_250//trimParameters35_C15.dat
[16:21:10.405] <TB1> INFO: clk: 4
[16:21:10.405] <TB1> INFO: ctr: 4
[16:21:10.405] <TB1> INFO: sda: 19
[16:21:10.405] <TB1> INFO: tin: 9
[16:21:10.405] <TB1> INFO: level: 15
[16:21:10.405] <TB1> INFO: triggerdelay: 0
[16:21:10.405] <TB1> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[16:21:10.405] <TB1> INFO: Log level: INFO
[16:21:10.418] <TB1> INFO: Found DTB DTB_WWXTQT
[16:21:10.428] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[16:21:10.430] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[16:21:10.432] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[16:21:11.961] <TB1> INFO: DUT info:
[16:21:11.961] <TB1> INFO: The DUT currently contains the following objects:
[16:21:11.961] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:21:11.961] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:21:11.961] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:21:11.961] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:21:11.961] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:11.961] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:12.362] <TB1> INFO: enter 'restricted' command line mode
[16:21:12.362] <TB1> INFO: enter test to run
[16:21:12.362] <TB1> INFO: test: PixelAlive no parameter change
[16:21:12.362] <TB1> INFO: running: pixelalive
[16:21:12.365] <TB1> INFO: ######################################################################
[16:21:12.365] <TB1> INFO: PixTestAlive::doTest()
[16:21:12.365] <TB1> INFO: ######################################################################
[16:21:12.366] <TB1> INFO: ----------------------------------------------------------------------
[16:21:12.366] <TB1> 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:21:12.366] <TB1> INFO: ----------------------------------------------------------------------
[16:21:12.623] <TB1> INFO: Expecting 208000 events.
[16:21:23.461] <TB1> INFO: 208000 events read in total (10280ms).
[16:21:23.463] <TB1> INFO: Test took 11095ms.
[16:21:23.685] <TB1> INFO: PixTestAlive::aliveTest() done
[16:21:23.685] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:21:23.686] <TB1> INFO: ----------------------------------------------------------------------
[16:21:23.686] <TB1> 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:21:23.686] <TB1> INFO: ----------------------------------------------------------------------
[16:21:23.944] <TB1> INFO: Expecting 208000 events.
[16:21:31.099] <TB1> INFO: 208000 events read in total (6599ms).
[16:21:31.100] <TB1> INFO: Test took 7413ms.
[16:21:31.101] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:21:31.342] <TB1> INFO: PixTestAlive::maskTest() done
[16:21:31.342] <TB1> 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:21:31.343] <TB1> INFO: ----------------------------------------------------------------------
[16:21:31.343] <TB1> 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:21:31.343] <TB1> INFO: ----------------------------------------------------------------------
[16:21:31.601] <TB1> INFO: Expecting 208000 events.
[16:21:42.431] <TB1> INFO: 208000 events read in total (10274ms).
[16:21:42.431] <TB1> INFO: Test took 11087ms.
[16:21:42.631] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[16:21:42.631] <TB1> 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:21:42.631] <TB1> INFO: PixTestAlive::doTest() done, duration: 30 seconds
[16:21:42.660] <TB1> INFO: enter test to run
[16:21:42.660] <TB1> INFO: test: delay setting parameters: ->2<-
[16:21:42.660] <TB1> INFO: delay test by 2 seconds...
[16:21:44.660] <TB1> INFO: enter test to run
[16:21:44.660] <TB1> INFO: test: HighRate no parameter change
[16:21:44.660] <TB1> INFO: running: highrate
[16:21:44.661] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2178_XrayQualification_2016-03-04_16h01m_1457103714//018_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2178_XrayQualification_2016-03-04_16h01m_1457103714//018_HREfficiency_250//phCalibrationFitErr35_C15.dat
[16:21:44.789] <TB1> INFO: ----------------------------------------------------------------------
[16:21:44.789] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:21:44.789] <TB1> INFO: ----------------------------------------------------------------------
[16:21:45.265] <TB1> INFO: Expecting 208000 events.
[16:21:52.984] <TB1> WARNING: Detected DESER400 trailer error bits: "IDLE DATA"
[16:21:52.984] <TB1> ERROR: <datapipe.cc/CheckEventID:L469> Channel 1 Event ID mismatch: local ID (43) != TBM ID (44)
[16:21:52.985] <TB1> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!
[16:21:52.985] <TB1> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!
[16:21:52.985] <TB1> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!
[16:21:52.985] <TB1> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[16:21:52.985] <TB1> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[16:21:52.985] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a132 8000 40f0 a0 284a 92 2466 111 228f 25c 226f 492 286f 489 2667 49b 2280 75c 2026 40f0 10a 264c 111 22c0 241 226e 2c5 2467 40f1 5 266b 9b 28a2 111 22a7 205 2481 2d1 26a5 311 246a 31b 2265 54d 206f 562 226d 6c3 2287 6d1 266f 40f1 4c 2848 cb 2a8e 111 22c0 159 2a88 28b 2680 509 2a68 601 2469 e002 c000
[16:21:52.985] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a12c 80b0 40f1 88 2044 111 22c9 241 2688 291 2286 293 226a 758 2465 40f1 102 2869 10b 2689 10b 2aa3 111 22c7 34c 2680 642 2462 811 246a 40f1 1c 2466 15 2883 2 2a8a 4 2a67 c 2680 d 228a d 2689 14 2a88 19 2a82 20 2a80 a2 206f 99 2080 85 2680 111 22a8 35a 2a86 420 228b 40f1 5b 266a 91 2284 94 2689 111 22c2 285 2489 285 2089 283 284f 45a 2884 441 286d 49c 206e 751 284a 749 2887 e002 c000
[16:21:52.985] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a12d 80c0 40f0 44 2444 9c 206c a0 2685 c2 2a4e c3 2265 111 22a2 310 2061 540 2665 40f0 15 2081 111 22c1 615 228c 6c3 206c 808 224d 40f0 1b 2468 9 2688 12 2a8d 51 2082 10d 2480 111 22c2 11b 2683 289 2882 4d0 2068 40f0 85 206f 111 22c2 208 2665 253 2480 24c 2062 2d8 2a4a 30b 22a8 318 2a68 321 2a8c 458 2483 441 2a86 560 2065 55a 2468 71a 246c 700 282e 745 266f e002 c000
[16:21:52.985] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a12e 8000 40f0 2 2460 11 2266 111 22ac 252 2a6e 405 246a 813 206f 849 2266 40f0 d1 28a6 111 22c8 545 2289 6c0 264d 40f0 111 22aa 20d 26a7 40f0 14 284c 21 226d d4 2269 108 2a6c 111 22af 2d1 2069 362 2862 34d 2062 60c 206f 64b 286c 650 224f 6d8 2a43 6db 2681 e002 c000
[16:21:52.985] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a12f 8040 40f2 111 228f 20b 2868 500 2840 60c 2a49 40f2 111 22ae 25b 226d 44a 266c 513 286b 54c 206f 40f2 81 2a8e 115 2888 110 266a 111 22a6 115 2a68 4d5 2861 40e2 59 242f 52 2460 52 224f 111 22c1 290 206f 284 288a 342 2465 74a 2085 762 266c 805 2264 849 2645 e002 c000
[16:21:52.985] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a130 80b0 40e0 111 22ad 40f0 11 2880 a 208f 4d 246f 61 2a88 111 22aa 219 2882 20b 288e 209 204f 304 2024 762 208e 40f0 10c 2a89 111 22a5 2c8 28a8 704 2040 85c 2068 859 2462 84a 286b 841 2a8a 849 226d 858 2a81 40f0 19 2863 301 248f 303 22a2 49c 2485 65c 2a4f 80a 2863 e002 c000
[16:21:52.985] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a131 80c0 40f1 111 22a8 552 2842 551 204f 658 2848 85b 2483 84c 264d 40f1 10a 242f 111 22ad 292 228c 444 2063 503 2469 6c5 2268 700 266d 711 226d 748 262e 84d 2066 848 2640 84d 2a6e 861 266e 40f1 103 266d 111 228a 290 2280 2dc 2ae3 500 2662 502 264e 51a 2666 820 20ac 808 2648 858 286e 851 206c 845 2286 85b 2689 40f1 101 2a6e 111 22c4 113 2282 28d 2469 4c3 2080 55d 2485 6c0 2640 6d9 264f e002 c000
[16:22:20.568] <TB1> INFO: 180400 events read in total (34902ms).
[16:22:26.109] <TB1> INFO: 208000 events read in total (40443ms).
[16:22:26.128] <TB1> INFO: Test took 41336ms.
[16:22:28.128] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:22:28.128] <TB1> INFO: number of red-efficiency pixels: 2299 2486 1892 4023 3882 4123 4139 4160 4149 4152 4140 3881 3938 1990 2169 2085
[16:22:28.128] <TB1> INFO: number of X-ray hits detected: 533784 566504 515075 856490 876803 1046394 1137131 1275429 1145194 1148776 1070241 927744 881874 515519 528318 534481
[16:22:28.128] <TB1> 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:22:28.128] <TB1> INFO: number of Vcal hits detected: 204027 203935 202322 186704 191819 181716 173608 161752 174304 172627 180552 190436 191932 200590 204622 204730
[16:22:28.128] <TB1> INFO: Vcal hit fiducial efficiency (%): 98.3 98.2 97.8 90.7 93.1 88.5 84.9 79.0 84.7 84.4 88.0 92.4 93.1 97.1 98.5 98.6
[16:22:28.128] <TB1> INFO: Vcal hit overall efficiency (%): 98.1 98.0 97.3 89.8 92.2 87.4 83.5 77.8 83.8 83.0 86.8 91.6 92.3 96.4 98.4 98.4
[16:22:28.128] <TB1> INFO: X-ray hit rate [MHz/cm2]: 156.5 166.0 151.0 251.0 257.0 306.7 333.3 373.8 335.7 336.7 313.7 271.9 258.5 151.1 154.9 156.7
[16:22:28.128] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[16:22:28.157] <TB1> INFO: PixTest:: pg_setup set to default.
[16:22:28.170] <TB1> INFO: enter test to run
[16:22:28.170] <TB1> INFO: test: no parameter change
[16:22:28.460] <TB1> QUIET: Connection to board 147 closed.
[16:22:28.539] <TB1> INFO: pXar: this is the end, my friend