[08:59:40.346] <TB3> INFO: *** Welcome to pxar ***
[08:59:40.347] <TB3> INFO: *** Today: 2016/10/11
[08:59:40.401] <TB3> INFO: *** Version: f5d3-dirty
[08:59:40.401] <TB3> INFO: readRocDacs: /usr/local/data/M2359_XrayQualification_2016-10-11_09h45m_1476171913//017_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2359_XrayQualification_2016-10-11_09h45m_1476171913//017_HREfficiency_200//dacParameters35_C15.dat
[08:59:40.402] <TB3> INFO: readTbmDacs: /usr/local/data/M2359_XrayQualification_2016-10-11_09h45m_1476171913//017_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2359_XrayQualification_2016-10-11_09h45m_1476171913//017_HREfficiency_200//tbmParameters_C0b.dat
[08:59:40.402] <TB3> INFO: readMaskFile: /usr/local/data/M2359_XrayQualification_2016-10-11_09h45m_1476171913//017_HREfficiency_200//defaultMaskFile.dat
[08:59:40.402] <TB3> INFO: readTrimFile: /usr/local/data/M2359_XrayQualification_2016-10-11_09h45m_1476171913//017_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2359_XrayQualification_2016-10-11_09h45m_1476171913//017_HREfficiency_200//trimParameters35_C15.dat
[08:59:40.477] <TB3> INFO: clk: 4
[08:59:40.477] <TB3> INFO: ctr: 4
[08:59:40.477] <TB3> INFO: sda: 19
[08:59:40.477] <TB3> INFO: tin: 9
[08:59:40.477] <TB3> INFO: level: 15
[08:59:40.477] <TB3> INFO: triggerdelay: 0
[08:59:40.477] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[08:59:40.477] <TB3> INFO: Log level: INFO
[08:59:40.489] <TB3> INFO: Found DTB DTB_WWV6Z5
[08:59:40.498] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[08:59:40.500] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[08:59:40.501] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[08:59:42.048] <TB3> INFO: DUT info:
[08:59:42.048] <TB3> INFO: The DUT currently contains the following objects:
[08:59:42.048] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[08:59:42.048] <TB3> INFO: TBM Core alpha (0): 7 registers set
[08:59:42.048] <TB3> INFO: TBM Core beta (1): 7 registers set
[08:59:42.048] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:59:42.048] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.048] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.048] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.048] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.048] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.048] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.049] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.049] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.049] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.049] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.049] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.049] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.049] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.049] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.049] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.049] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:42.449] <TB3> INFO: enter 'restricted' command line mode
[08:59:42.449] <TB3> INFO: enter test to run
[08:59:42.449] <TB3> INFO: test: PixelAlive no parameter change
[08:59:42.450] <TB3> INFO: running: pixelalive
[08:59:42.454] <TB3> INFO: ######################################################################
[08:59:42.454] <TB3> INFO: PixTestAlive::doTest()
[08:59:42.454] <TB3> INFO: ######################################################################
[08:59:42.455] <TB3> INFO: ----------------------------------------------------------------------
[08:59:42.455] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:59:42.455] <TB3> INFO: ----------------------------------------------------------------------
[08:59:42.721] <TB3> INFO: Expecting 208000 events.
[08:59:51.990] <TB3> INFO: 208000 events read in total (8710ms).
[08:59:51.992] <TB3> INFO: Test took 9534ms.
[08:59:52.255] <TB3> INFO: PixTestAlive::aliveTest() done
[08:59:52.255] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:59:52.256] <TB3> INFO: ----------------------------------------------------------------------
[08:59:52.256] <TB3> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:59:52.256] <TB3> INFO: ----------------------------------------------------------------------
[08:59:52.519] <TB3> INFO: Expecting 208000 events.
[08:59:59.164] <TB3> INFO: 208000 events read in total (6088ms).
[08:59:59.167] <TB3> INFO: Test took 6909ms.
[08:59:59.168] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:59:59.385] <TB3> INFO: PixTestAlive::maskTest() done
[08:59:59.385] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:59:59.386] <TB3> INFO: ----------------------------------------------------------------------
[08:59:59.386] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:59:59.386] <TB3> INFO: ----------------------------------------------------------------------
[08:59:59.659] <TB3> INFO: Expecting 208000 events.
[09:00:08.846] <TB3> INFO: 208000 events read in total (8630ms).
[09:00:08.847] <TB3> INFO: Test took 9458ms.
[09:00:09.062] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[09:00:09.062] <TB3> 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:00:09.062] <TB3> INFO: PixTestAlive::doTest() done, duration: 26 seconds
[09:00:09.092] <TB3> INFO: enter test to run
[09:00:09.092] <TB3> INFO: test: delay setting parameters: ->2<-
[09:00:09.092] <TB3> INFO: delay test by 2 seconds...
[09:00:11.092] <TB3> INFO: enter test to run
[09:00:11.092] <TB3> INFO: test: HighRate no parameter change
[09:00:11.092] <TB3> INFO: running: highrate
[09:00:11.092] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2359_XrayQualification_2016-10-11_09h45m_1476171913//017_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2359_XrayQualification_2016-10-11_09h45m_1476171913//017_HREfficiency_200//phCalibrationFitErr35_C15.dat
[09:00:11.273] <TB3> INFO: ----------------------------------------------------------------------
[09:00:11.273] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:00:11.273] <TB3> INFO: ----------------------------------------------------------------------
[09:00:11.759] <TB3> INFO: Expecting 208000 events.
[09:00:21.695] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 1 Number of ROCs (5) != Token Chain Length (4)
[09:00:21.696] <TB3> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[09:00:21.696] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a145 80c0 4c10 c 2464 20 2285 45a 26a2 651 2466 6c1 2066 703 2260 743 266c 4c10 49 204e 262 288e 45a 268d 4c10 c2 2642 142 2662 420 224e 45a 26ad 4c10 300 2642 45a 268e 843 2267 e002 c000
[09:00:21.696] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a13f 8040 4c02 152 2264 341 264d 45a 26a2 4c12 89 244f c8 224f 45a 2686 4c12 a0 204a 45a 268d 4c12 15c 282e 15c 2a45 2d5 2689 45a 268b e002 c000
[09:00:21.696] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a140 80b0 4c10 202 246e 45a 268a 85d 2665 4c10 83 2a4a 302 2264 45a 26a5 60c 224f 759 244d 4c10 45a 2684 4c10 85 2045 202 2061 28b 246f 45a 26a1 48a 2662 75d 2065 748 2625 e002 c000
[09:00:21.696] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a141 80c0 4c11 160 2862 303 286d 45a 26af 49d 2460 65d 2482 4c11 45a 268f 4c11 80 2448 448 262f 45a 268e 760 286e 4c11 10 204d 10 226c 162 2a65 45a 268d 48b 2a64 614 264f e002 c000
[09:00:21.696] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a142 8000 4c10 45a 268f 4cb 2662 4c11 ef8 25c8 4c10 200 2449 2d4 2666 45a 26ad 651 244f 762 2865 4c11 c8 2649 455 2442 45a 2667 4c11 41b 2865 45a 268b 560 2665 e002 c000
[09:00:21.696] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a143 8040 4c10 45a 26a5 653 284f 4c11 2dc 2a45 45a 268f 4c10 45a 2685 48d 246c 4c11 62 2840 361 208e 45a 26a4 4c3 226f e002 c000
[09:00:21.696] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a144 80b0 4c10 213 2266 2cd 226a 45a 26a6 48c 2449 489 2a69 4c10 8c 2424 45a 268f 4c10 51 2268 45a 2681 6cb 2269 703 2665 4c10 1c 2882 28c 284a 30a 2665 45a 268d 519 226e 603 2647 750 2847 e002 c000
[09:00:31.957] <TB3> INFO: 208000 events read in total (19796ms).
[09:00:31.969] <TB3> INFO: Test took 20692ms.
[09:00:33.220] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:00:33.220] <TB3> INFO: number of red-efficiency pixels: 934 839 878 2108 2441 2879 2805 2727 3081 3602 2607 2383 2639 1179 1255 1618
[09:00:33.220] <TB3> INFO: number of X-ray hits detected: 331724 315133 288661 479754 566149 635654 558884 591341 644893 677483 581058 555098 553135 350855 368648 376104
[09:00:33.220] <TB3> 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:00:33.220] <TB3> INFO: number of Vcal hits detected: 206864 207022 206408 204345 203118 201352 201755 202929 201408 196388 203032 203281 202540 205504 206477 205690
[09:00:33.220] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.5 99.6 99.4 98.5 98.0 97.2 97.4 97.8 97.1 95.0 97.9 98.0 97.7 99.0 99.3 99.0
[09:00:33.220] <TB3> INFO: Vcal hit overall efficiency (%): 99.5 99.5 99.2 98.2 97.7 96.8 97.0 97.6 96.8 94.4 97.6 97.7 97.4 98.8 99.3 98.9
[09:00:33.220] <TB3> INFO: X-ray hit rate [MHz/cm2]: 97.2 92.4 84.6 140.6 165.9 186.3 163.8 173.3 189.0 198.6 170.3 162.7 162.1 102.8 108.1 110.2
[09:00:33.220] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[09:00:33.266] <TB3> INFO: PixTest:: pg_setup set to default.
[09:00:33.277] <TB3> INFO: enter test to run
[09:00:33.277] <TB3> INFO: test: no parameter change
[09:00:33.566] <TB3> QUIET: Connection to board 121 closed.
[09:00:33.646] <TB3> INFO: pXar: this is the end, my friend