[17:03:51.051] <TB0> INFO: *** Welcome to pxar ***
[17:03:51.052] <TB0> INFO: *** Today: 2016/02/11
[17:03:51.397] <TB0> INFO: *** Version: 9751
[17:03:51.397] <TB0> INFO: readRocDacs: /usr/local/data/M2156_XrayQualification_2016-02-11_16h37m_1455205051//018_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2156_XrayQualification_2016-02-11_16h37m_1455205051//018_HREfficiency_250//dacParameters35_C15.dat
[17:03:51.397] <TB0> INFO: readTbmDacs: /usr/local/data/M2156_XrayQualification_2016-02-11_16h37m_1455205051//018_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2156_XrayQualification_2016-02-11_16h37m_1455205051//018_HREfficiency_250//tbmParameters_C0b.dat
[17:03:51.398] <TB0> INFO: readMaskFile: /usr/local/data/M2156_XrayQualification_2016-02-11_16h37m_1455205051//018_HREfficiency_250//defaultMaskFile.dat
[17:03:51.398] <TB0> INFO: readTrimFile: /usr/local/data/M2156_XrayQualification_2016-02-11_16h37m_1455205051//018_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2156_XrayQualification_2016-02-11_16h37m_1455205051//018_HREfficiency_250//trimParameters35_C15.dat
[17:03:51.465] <TB0> INFO: clk: 4
[17:03:51.465] <TB0> INFO: ctr: 4
[17:03:51.465] <TB0> INFO: sda: 19
[17:03:51.465] <TB0> INFO: tin: 9
[17:03:51.465] <TB0> INFO: level: 15
[17:03:51.465] <TB0> INFO: triggerdelay: 0
[17:03:51.465] <TB0> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[17:03:51.465] <TB0> INFO: Log level: INFO
[17:03:51.479] <TB0> INFO: Found DTB DTB_WS6UZO
[17:03:51.488] <TB0> QUIET: Connection to board DTB_WS6UZO opened.
[17:03:51.490] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[17:03:51.492] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[17:03:53.019] <TB0> INFO: DUT info:
[17:03:53.019] <TB0> INFO: The DUT currently contains the following objects:
[17:03:53.019] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[17:03:53.019] <TB0> INFO: TBM Core alpha (0): 7 registers set
[17:03:53.019] <TB0> INFO: TBM Core beta (1): 7 registers set
[17:03:53.019] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:03:53.019] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.019] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:53.420] <TB0> INFO: enter 'restricted' command line mode
[17:03:53.420] <TB0> INFO: enter test to run
[17:03:53.420] <TB0> INFO: test: PixelAlive no parameter change
[17:03:53.420] <TB0> INFO: running: pixelalive
[17:03:53.423] <TB0> INFO: ######################################################################
[17:03:53.423] <TB0> INFO: PixTestAlive::doTest()
[17:03:53.423] <TB0> INFO: ######################################################################
[17:03:53.424] <TB0> INFO: ----------------------------------------------------------------------
[17:03:53.424] <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)
[17:03:53.424] <TB0> INFO: ----------------------------------------------------------------------
[17:03:53.680] <TB0> INFO: Expecting 208000 events.
[17:04:04.601] <TB0> INFO: 208000 events read in total (10363ms).
[17:04:04.603] <TB0> INFO: Test took 11177ms.
[17:04:04.830] <TB0> INFO: PixTestAlive::aliveTest() done
[17:04:04.830] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0
[17:04:04.831] <TB0> INFO: ----------------------------------------------------------------------
[17:04:04.831] <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)
[17:04:04.831] <TB0> INFO: ----------------------------------------------------------------------
[17:04:05.128] <TB0> INFO: Expecting 208000 events.
[17:04:12.805] <TB0> INFO: 208000 events read in total (7121ms).
[17:04:12.806] <TB0> INFO: Test took 7973ms.
[17:04:12.806] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:04:13.050] <TB0> INFO: PixTestAlive::maskTest() done
[17:04:13.050] <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
[17:04:13.051] <TB0> INFO: ----------------------------------------------------------------------
[17:04:13.051] <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)
[17:04:13.051] <TB0> INFO: ----------------------------------------------------------------------
[17:04:13.342] <TB0> INFO: Expecting 208000 events.
[17:04:24.530] <TB0> INFO: 208000 events read in total (10632ms).
[17:04:24.531] <TB0> INFO: Test took 11478ms.
[17:04:24.729] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[17:04:24.729] <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
[17:04:24.729] <TB0> INFO: PixTestAlive::doTest() done, duration: 31 seconds
[17:04:24.770] <TB0> INFO: enter test to run
[17:04:24.770] <TB0> INFO: test: delay setting parameters: ->2<-
[17:04:24.770] <TB0> INFO: delay test by 2 seconds...
[17:04:26.770] <TB0> INFO: enter test to run
[17:04:26.770] <TB0> INFO: test: HighRate no parameter change
[17:04:26.770] <TB0> INFO: running: highrate
[17:04:26.772] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2156_XrayQualification_2016-02-11_16h37m_1455205051//018_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2156_XrayQualification_2016-02-11_16h37m_1455205051//018_HREfficiency_250//phCalibrationFitErr35_C15.dat
[17:04:26.911] <TB0> INFO: ----------------------------------------------------------------------
[17:04:26.911] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:04:26.911] <TB0> INFO: ----------------------------------------------------------------------
[17:04:27.398] <TB0> INFO: Expecting 208000 events.
[17:04:40.748] <TB0> WARNING: Detected DESER400 trailer error bits: "NO DATA"
[17:04:40.748] <TB0> ERROR: <datapipe.cc/CheckEventID:L469> Channel 1 Event ID mismatch: local ID (132) != TBM ID (133)
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a188 80b0 4871 351 2aaf 713 2084 700 264d 4871 351 2aa5 4871 29c 2864 351 2acf 4871 10 204a 292 2045 351 2aaa 754 204f 741 2a41 fff 2fff fff 2fff fff 2fff fff 2fff e002 c000
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a182 8000 4870 351 2aa2 543 206f 68c 286e 760 206d 814 22a0 4870 284 2a81 351 2aa9 419 2a6a 491 268d 518 2446 501 2869 4871 321 248c 351 2acd 440 244f 54d 2aa7 722 22a9 722 2aa1 74c 2a63 861 248f 841 22c2 850 266d 862 2264 4871 302 246e 31b 2a81 351 2aaf 713 2868 858 222b e002 c000
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a183 8040 4870 252 286d 351 2a8e 4871 a2 206c 88 284f 351 2aac 743 206d 803 286a 809 2642 810 2640 81b 264a 81b 2a6d 4870 42 244f 53 2a87 d9 2880 c2 2882 21a 2a8c 220 2265 295 2489 282 2a8f 295 2669 351 28c1 351 2acd 68a 20a3 814 2446 840 2483 862 266f 862 2a6c 4871 4da 2265 e002 c000
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a184 80b0 f135 d11a
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a185 80c0 4830 351 2aae 4870 4d 282e 4c 2085 5a 2a69 61 226d 341 2a61 351 2ac5 752 24a0 748 286c 4870 21d 206d 351 2ac7 4d4 2aa4 658 204f 655 288d 70c 2664 4870 21b 2488 200 2665 351 2aaf 6c8 244d e002 c000
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a186 8000 4830 108 224c 351 2ac1 4830 21b 2267 351 2aaa 4c8 2469 520 206a 51a 286f 612 226e 622 224f 682 2862 4870 95 208c 30a 2a81 31a 26a2 351 2acc 4870 15c 2045 351 2a8e 4cd 208d 4db 266a 54b 228e 695 224f 750 2a69 e002 c000
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a187 8040 4870 351 2ace 449 224f 4870 34d 2a49 351 2aad 4870 298 268f 351 2ac8 711 2664 718 22a7 4870 3 2a86 4 2267 19 2261 154 2865 281 2045 351 2ac2 493 2281 493 2a6f 698 2467 e002 c000
[17:04:40.748] <TB0> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!
[17:04:40.748] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!
[17:04:40.748] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!
[17:04:40.748] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a192 8000 4870 351 2a8f 4870 122 2846 351 2aaf 4871 322 20a1 34b 22a9 351 2aaf 453 2085 455 226e 55d 2886 752 206d 743 2862 74b 268f 74c 2a4c 4871 241 2a85 2e2 2485 2cb 288c 351 2aac 64b 2662 683 204f 71a 2265 801 2a6f 809 2685 e002 c000
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a18c 80b0 4831 351 2a8c 6d4 204f 702 2665 810 2a2f 811 2240 4871 351 2aa6 54b 2080 4871 351 2ac5 822 2282 4871 11d 204f 688 2a4f fff 2fff fff 2fff e002 c000
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a18d 80c0 4870 58 2a62 20b 286f 298 284a 351 2ac1 4870 62 2063 351 2ac3 4870 4b 2645 92 2089 20c 204f 25d 20a4 2d0 2862 2d0 2a4f 351 2acc 492 26a4 653 2683 4870 15 244f 3 2482 351 2aaf 352 264f 489 288d 708 2a49 709 2269 e002 c000
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a18e 8000 4870 112 2a23 354 204c 351 2aab 4d8 2467 50b 266f 752 224a 4870 254 2285 350 2083 351 2aaf 755 2869 815 2647 4870 202 2869 351 2aaf 559 2a89 662 2a88 722 2469 803 286b 4870 142 2a6a 351 2ac2 40d 2a4e 48a 2aa5 651 202d 6c0 2445 e002 c000
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a18f 8040 4872 351 2aa6 462 246f 4872 2d2 286f 2c1 2285 351 2aaf 450 2a6d 4872 99 206d 94 20a5 102 2865 351 2ac1 41d 2463 403 248f 542 286a 683 2061 4872 90 204a 8c 246a 243 204f 34c 226d 351 2aab 41a 286b e002 c000
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a190 80b0 4870 344 2a4c 351 2aa9 71c 2864 4870 5a 2441 121 2a42 31a 20a2 351 2aaf 499 224f 4dc 2086 4d0 2a66 4870 80 2a63 351 2ac7 4870 108 2642 144 248f 290 206d 351 2aa6 855 2465 855 204a 854 2461 e002 c000
[17:04:40.748] <TB0> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a191 80c0 4871 61 246e 34b 246d 340 2684 351 2ac5 360 268c 820 2a69 4871 d9 2064 e1 2a8f 242 288d 351 2aa9 4871 61 2864 351 2ac1 702 2087 84b 208f 854 2a4f 4871 351 2aa2 4dd 2867 808 224d e002 c000
[17:05:00.150] <TB0> INFO: 208000 events read in total (32351ms).
[17:05:00.166] <TB0> INFO: Test took 33251ms.
[17:05:01.773] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 1 0 0 0
[17:05:01.773] <TB0> INFO: number of red-efficiency pixels: 1286 1876 1734 3288 3784 3940 3897 4041 3652 3551 3884 3342 2485 1151 1811 1165
[17:05:01.774] <TB0> INFO: number of X-ray hits detected: 402629 421241 426287 717187 748198 888341 926397 907466 771849 763259 845394 719485 554508 350999 404264 383119
[17:05:01.774] <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
[17:05:01.774] <TB0> INFO: number of Vcal hits detected: 206376 205306 203088 199297 193391 191506 190002 190293 197521 197041 193139 198337 203452 205546 205427 206492
[17:05:01.774] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.3 98.8 98.1 96.3 93.8 92.9 92.4 92.2 95.4 95.4 93.6 96.0 98.1 99.1 98.9 99.4
[17:05:01.774] <TB0> INFO: Vcal hit overall efficiency (%): 99.2 98.7 97.6 95.8 93.0 92.1 91.3 91.5 95.0 94.7 92.9 95.4 97.8 98.8 98.8 99.3
[17:05:01.774] <TB0> INFO: X-ray hit rate [MHz/cm2]: 118.0 123.5 124.9 210.2 219.3 260.4 271.5 266.0 226.2 223.7 247.8 210.9 162.5 102.9 118.5 112.3
[17:05:01.774] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[17:05:01.804] <TB0> INFO: PixTest:: pg_setup set to default.
[17:05:01.816] <TB0> INFO: enter test to run
[17:05:01.816] <TB0> INFO: test: no parameter change
[17:05:02.083] <TB0> QUIET: Connection to board 83 closed.
[17:05:02.163] <TB0> INFO: pXar: this is the end, my friend