[09:26:44.698] <TB2> INFO: *** Welcome to pxar ***
[09:26:44.698] <TB2> INFO: *** Today: 2016/06/13
[09:26:45.061] <TB2> INFO: *** Version: 2385
[09:26:45.061] <TB2> INFO: readRocDacs: /usr/local/data/M2252_XrayQualification_2016-06-13_10h11m_1465805512//018_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2252_XrayQualification_2016-06-13_10h11m_1465805512//018_HREfficiency_250//dacParameters35_C15.dat
[09:26:45.062] <TB2> INFO: readTbmDacs: /usr/local/data/M2252_XrayQualification_2016-06-13_10h11m_1465805512//018_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2252_XrayQualification_2016-06-13_10h11m_1465805512//018_HREfficiency_250//tbmParameters_C0b.dat
[09:26:45.062] <TB2> INFO: readMaskFile: /usr/local/data/M2252_XrayQualification_2016-06-13_10h11m_1465805512//018_HREfficiency_250//defaultMaskFile.dat
[09:26:45.062] <TB2> INFO: readTrimFile: /usr/local/data/M2252_XrayQualification_2016-06-13_10h11m_1465805512//018_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2252_XrayQualification_2016-06-13_10h11m_1465805512//018_HREfficiency_250//trimParameters35_C15.dat
[09:26:45.131] <TB2> INFO: clk: 4
[09:26:45.131] <TB2> INFO: ctr: 4
[09:26:45.131] <TB2> INFO: sda: 19
[09:26:45.131] <TB2> INFO: tin: 9
[09:26:45.131] <TB2> INFO: level: 15
[09:26:45.131] <TB2> INFO: triggerdelay: 0
[09:26:45.131] <TB2> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[09:26:45.131] <TB2> INFO: Log level: INFO
[09:26:45.144] <TB2> INFO: Found DTB DTB_WXENWR
[09:26:45.153] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[09:26:45.155] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[09:26:45.157] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[09:26:46.691] <TB2> INFO: DUT info:
[09:26:46.691] <TB2> INFO: The DUT currently contains the following objects:
[09:26:46.691] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:46.691] <TB2> INFO: TBM Core alpha (0): 7 registers set
[09:26:46.691] <TB2> INFO: TBM Core beta (1): 7 registers set
[09:26:46.691] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:26:46.691] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.691] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.691] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.691] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.691] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.692] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.692] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.692] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.692] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.692] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.692] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.692] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.692] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.692] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.692] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:46.692] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:26:47.093] <TB2> INFO: enter 'restricted' command line mode
[09:26:47.093] <TB2> INFO: enter test to run
[09:26:47.093] <TB2> INFO: test: PixelAlive no parameter change
[09:26:47.093] <TB2> INFO: running: pixelalive
[09:26:47.096] <TB2> INFO: ######################################################################
[09:26:47.096] <TB2> INFO: PixTestAlive::doTest()
[09:26:47.096] <TB2> INFO: ######################################################################
[09:26:47.098] <TB2> INFO: ----------------------------------------------------------------------
[09:26:47.098] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:26:47.098] <TB2> INFO: ----------------------------------------------------------------------
[09:26:47.356] <TB2> INFO: Expecting 208000 events.
[09:26:58.166] <TB2> INFO: 208000 events read in total (10251ms).
[09:26:58.168] <TB2> INFO: Test took 11068ms.
[09:26:58.407] <TB2> INFO: PixTestAlive::aliveTest() done
[09:26:58.407] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[09:26:58.408] <TB2> INFO: ----------------------------------------------------------------------
[09:26:58.409] <TB2> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:26:58.409] <TB2> INFO: ----------------------------------------------------------------------
[09:26:58.672] <TB2> INFO: Expecting 208000 events.
[09:27:05.844] <TB2> INFO: 208000 events read in total (6615ms).
[09:27:05.845] <TB2> INFO: Test took 7434ms.
[09:27:05.846] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:27:06.103] <TB2> INFO: PixTestAlive::maskTest() done
[09:27:06.103] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:27:06.105] <TB2> INFO: ----------------------------------------------------------------------
[09:27:06.105] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:27:06.105] <TB2> INFO: ----------------------------------------------------------------------
[09:27:06.363] <TB2> INFO: Expecting 208000 events.
[09:27:17.126] <TB2> INFO: 208000 events read in total (10206ms).
[09:27:17.127] <TB2> INFO: Test took 11020ms.
[09:27:17.343] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[09:27:17.343] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:27:17.343] <TB2> INFO: PixTestAlive::doTest() done, duration: 30 seconds
[09:27:17.372] <TB2> INFO: enter test to run
[09:27:17.372] <TB2> INFO: test: delay setting parameters: ->2<-
[09:27:17.372] <TB2> INFO: delay test by 2 seconds...
[09:27:19.372] <TB2> INFO: enter test to run
[09:27:19.372] <TB2> INFO: test: HighRate no parameter change
[09:27:19.372] <TB2> INFO: running: highrate
[09:27:19.372] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2252_XrayQualification_2016-06-13_10h11m_1465805512//018_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2252_XrayQualification_2016-06-13_10h11m_1465805512//018_HREfficiency_250//phCalibrationFitErr35_C15.dat
[09:27:19.501] <TB2> INFO: ----------------------------------------------------------------------
[09:27:19.501] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:27:19.501] <TB2> INFO: ----------------------------------------------------------------------
[09:27:19.982] <TB2> INFO: Expecting 208000 events.
[09:27:35.681] <TB2> WARNING: Detected DESER400 trailer error bits: "IDLE DATA"
[09:27:35.682] <TB2> ERROR: <datapipe.cc/CheckEventID:L469> Channel 0 Event ID mismatch: local ID (173) != TBM ID (174)
[09:27:35.682] <TB2> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!
[09:27:35.682] <TB2> WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!
[09:27:35.682] <TB2> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!
[09:27:35.682] <TB2> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!
[09:27:35.682] <TB2> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[09:27:35.682] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0b2 8000 41c0 209 2265 460 2aa2 41c1 18 2869 200 2842 460 2ac1 4d8 2662 85b 2a85 41c1 62 2481 460 2a8f 698 2849 6c3 2445 4181 14 2045 11 2a81 118 2689 24a 2684 422 2a86 460 2aa1 e002 c000
[09:27:35.682] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0ac 80b0 4181 293 2245 460 2a89 4dd 26a0 4180 289 2465 460 2aa2 4cd 248f 544 2a46 813 2a68 41c1 5a 2a4a 460 2aa3 74a 2442 4181 51 2a67 11b 2a85 440 2481 441 226f 460 2aa5 480 2682 68d 2061 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 fff 2fff fff 2fff fff 2fff fff 2fff e002 c000
[09:27:35.682] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0ad 80c0 4080 460 2a8c 49d 2464 650 2040 4080 102 242f 21a 2a49 285 2864 460 2a8d 4180 143 2649 460 2aad 4dd 2468 748 2443 41c0 7f 2cf7 86d 2c37 861 2446 960 2416 960 2515 990 2d50 a30 2553 a61 2832 b05 2447 bff 2010 7f 2fff fff 2fff f2ff d2ff
[09:27:35.682] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0ae 8000 4180 2dc 2482 460 2aa0 4182 31d 2647 460 2ac1 549 226f 858 2a4c 859 224f 85b 2687 4080 120 204f 460 2aaa 481 2262 6ca 2845 4180 460 2a8f 64d 2480 64a 2283 fff 2fff e002 c000
[09:27:35.682] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0af 8040 41c2 460 2aa2 4080 9c 224d 460 2a8d 742 2065 85c 224f 40c2 459 2441 460 2a85 6cd 2427 6d1 264a 4182 9a 2a45 280 2449 2c4 2661 2d0 2a41 460 2aaf 854 2a44 85c 2241 fff 2fff fff 2fff fff 2fff e002 c000
[09:27:35.682] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0b0 80b0 41c0 460 2a80 41c0 460 2aa8 4080 460 2aa7 49a 284f 4180 460 2a8f 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 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000
[09:27:35.682] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a0b1 80c0 41c0 29c 202a 460 2a87 4180 2d8 2828 2c1 2249 460 2a89 54d 2262 41c0 15c 2a45 460 2aa5 719 2447 41c0 9a 264d 460 2aa5 499 2065 fff 2fff fff 2fff fff 2fff fff 2fff e002 c000
[09:27:54.444] <TB2> INFO: 184950 events read in total (34059ms).
[09:27:58.955] <TB2> INFO: 208000 events read in total (38570ms).
[09:27:58.976] <TB2> INFO: Test took 39471ms.
[09:28:00.937] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[09:28:00.937] <TB2> INFO: number of red-efficiency pixels: 2243 2888 1918 4005 3864 4115 4081 4131 4148 4127 4140 3987 4081 2087 3176 2716
[09:28:00.937] <TB2> INFO: number of X-ray hits detected: 519399 579515 520643 945274 853592 1022163 1036935 1001054 1106538 1013663 1098317 930690 867466 546231 592867 596561
[09:28:00.937] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:28:00.937] <TB2> INFO: number of Vcal hits detected: 204269 202091 200970 185864 191929 182909 180704 183833 175613 180045 175776 185945 184681 199482 201059 202153
[09:28:00.937] <TB2> INFO: Vcal hit fiducial efficiency (%): 98.4 97.4 97.3 90.3 93.1 89.0 88.2 89.0 85.5 87.9 85.9 90.5 89.8 96.7 96.9 97.5
[09:28:00.937] <TB2> INFO: Vcal hit overall efficiency (%): 98.2 97.2 96.6 89.4 92.3 87.9 86.9 88.4 84.4 86.6 84.5 89.4 88.8 95.9 96.7 97.2
[09:28:00.937] <TB2> INFO: X-ray hit rate [MHz/cm2]: 152.2 169.9 152.6 277.1 250.2 299.6 303.9 293.4 324.3 297.1 321.9 272.8 254.3 160.1 173.8 174.9
[09:28:00.937] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[09:28:00.982] <TB2> INFO: PixTest:: pg_setup set to default.
[09:28:00.994] <TB2> INFO: enter test to run
[09:28:00.994] <TB2> INFO: test: no parameter change
[09:28:01.218] <TB2> QUIET: Connection to board 162 closed.
[09:28:01.298] <TB2> INFO: pXar: this is the end, my friend