[10:32:48.299] <TB1> INFO: *** Welcome to pxar ***
[10:32:48.299] <TB1> INFO: *** Today: 2016/04/25
[10:32:48.645] <TB1> INFO: *** Version: 2385
[10:32:48.645] <TB1> INFO: readRocDacs: /usr/local/data/M2216_XrayQualification_2016-04-25_11h13m_1461575587//017_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2216_XrayQualification_2016-04-25_11h13m_1461575587//017_HREfficiency_200//dacParameters35_C15.dat
[10:32:48.646] <TB1> INFO: readTbmDacs: /usr/local/data/M2216_XrayQualification_2016-04-25_11h13m_1461575587//017_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2216_XrayQualification_2016-04-25_11h13m_1461575587//017_HREfficiency_200//tbmParameters_C0b.dat
[10:32:48.646] <TB1> INFO: readMaskFile: /usr/local/data/M2216_XrayQualification_2016-04-25_11h13m_1461575587//017_HREfficiency_200//defaultMaskFile.dat
[10:32:48.646] <TB1> INFO: readTrimFile: /usr/local/data/M2216_XrayQualification_2016-04-25_11h13m_1461575587//017_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2216_XrayQualification_2016-04-25_11h13m_1461575587//017_HREfficiency_200//trimParameters35_C15.dat
[10:32:48.713] <TB1> INFO: clk: 4
[10:32:48.713] <TB1> INFO: ctr: 4
[10:32:48.713] <TB1> INFO: sda: 19
[10:32:48.713] <TB1> INFO: tin: 9
[10:32:48.713] <TB1> INFO: level: 15
[10:32:48.713] <TB1> INFO: triggerdelay: 0
[10:32:48.713] <TB1> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[10:32:48.713] <TB1> INFO: Log level: INFO
[10:32:48.727] <TB1> INFO: Found DTB DTB_WWXTQT
[10:32:48.735] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[10:32:48.737] <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:
------------------------------------------------------
[10:32:48.739] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[10:32:50.264] <TB1> INFO: DUT info:
[10:32:50.264] <TB1> INFO: The DUT currently contains the following objects:
[10:32:50.264] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:32:50.264] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:32:50.264] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:32:50.264] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:32:50.264] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.264] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.264] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.264] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.265] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.265] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.265] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.265] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.265] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.265] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.265] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.265] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.265] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.265] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.265] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.265] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:50.665] <TB1> INFO: enter 'restricted' command line mode
[10:32:50.665] <TB1> INFO: enter test to run
[10:32:50.665] <TB1> INFO: test: PixelAlive no parameter change
[10:32:50.665] <TB1> INFO: running: pixelalive
[10:32:50.669] <TB1> INFO: ######################################################################
[10:32:50.669] <TB1> INFO: PixTestAlive::doTest()
[10:32:50.669] <TB1> INFO: ######################################################################
[10:32:50.670] <TB1> INFO: ----------------------------------------------------------------------
[10:32:50.670] <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)
[10:32:50.670] <TB1> INFO: ----------------------------------------------------------------------
[10:32:50.926] <TB1> INFO: Expecting 208000 events.
[10:33:02.108] <TB1> INFO: 208000 events read in total (10623ms).
[10:33:02.109] <TB1> INFO: Test took 11437ms.
[10:33:02.334] <TB1> INFO: PixTestAlive::aliveTest() done
[10:33:02.334] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:33:02.336] <TB1> INFO: ----------------------------------------------------------------------
[10:33:02.336] <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)
[10:33:02.336] <TB1> INFO: ----------------------------------------------------------------------
[10:33:02.600] <TB1> INFO: Expecting 208000 events.
[10:33:10.112] <TB1> INFO: 208000 events read in total (6956ms).
[10:33:10.113] <TB1> INFO: Test took 7776ms.
[10:33:10.113] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:33:10.356] <TB1> INFO: PixTestAlive::maskTest() done
[10:33:10.356] <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
[10:33:10.357] <TB1> INFO: ----------------------------------------------------------------------
[10:33:10.358] <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)
[10:33:10.358] <TB1> INFO: ----------------------------------------------------------------------
[10:33:10.640] <TB1> INFO: Expecting 208000 events.
[10:33:21.948] <TB1> INFO: 208000 events read in total (10752ms).
[10:33:21.948] <TB1> INFO: Test took 11589ms.
[10:33:22.154] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[10:33:22.154] <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
[10:33:22.154] <TB1> INFO: PixTestAlive::doTest() done, duration: 31 seconds
[10:33:22.188] <TB1> INFO: enter test to run
[10:33:22.188] <TB1> INFO: test: delay setting parameters: ->2<-
[10:33:22.188] <TB1> INFO: delay test by 2 seconds...
[10:33:24.188] <TB1> INFO: enter test to run
[10:33:24.188] <TB1> INFO: test: HighRate no parameter change
[10:33:24.188] <TB1> INFO: running: highrate
[10:33:24.188] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2216_XrayQualification_2016-04-25_11h13m_1461575587//017_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2216_XrayQualification_2016-04-25_11h13m_1461575587//017_HREfficiency_200//phCalibrationFitErr35_C15.dat
[10:33:24.327] <TB1> INFO: ----------------------------------------------------------------------
[10:33:24.327] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:33:24.327] <TB1> INFO: ----------------------------------------------------------------------
[10:33:24.812] <TB1> INFO: Expecting 208000 events.
[10:33:40.906] <TB1> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[10:33:40.906] <TB1> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[10:33:40.906] <TB1> ERROR: <datapipe.cc/CheckEventID:L469> Channel 2 Event ID mismatch: local ID (186) != TBM ID (187)
[10:33:40.906] <TB1> ERROR: <datapipe.cc/CheckEventID:L469> Channel 3 Event ID mismatch: local ID (186) != TBM ID (187)
[10:33:40.906] <TB1> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a3be 8001 41e0 29d 206c 494 2465 4d1 26aa 60b 282e 71a 244c 41e0 41a 206e 4d1 26a8 71a 264f 74c 286d 85b 2448 41e0 4d1 26a5 860 244c 41e0 4d1 26ab e002 c000
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a3b8 80b1 41e1 4d1 2460 4cd 2460 4c1 286c 4d1 26a8 4d9 266c 41e1 c4 204c 4d1 26a2 65b 2a6d 41e1 53 244d 4d1 26a6 618 284f 41e1 1a 286f 13 286c da 244a 204 224f 4d1 26a5 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a3b9 80c1 41e1 440 2a4f 4d1 26af 41e1 4d1 2686 685 202d 813 2222 41e1 4d1 268f 41c1 4d1 26a6 54b 248e 6dd 2049 719 2689 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a3ba 8001 41e0 31b 2882 4d1 268a 653 2863 787 2555 a9d 2f6e 31e 275d 9fb 27f8 4d1 268f 75c 2241 41e0 1c 204e 4d1 26ad fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fc02 dc00
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a3bb 8041 40c0 10c 2268 4d3 286e 4d1 26a2 4d4 226a 50b 2445 40e0 103 2868 102 266f 4d1 268f 41e0 99 2863 205 226c 4d1 26a8 41e0 4d1 268f fff 2fff e002 c000
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a3bc 80b1 41e1 144 204f 4d1 26c6 50c 2a61 41e1 4d1 268e 68b 262f 41e1 49 2646 481 2a65 4d1 26a8 552 226e 41c1 4d1 26a5 519 2866 512 224d e002 c000
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a3bd 80c1 40c0 95 264e 4d1 2685 4d4 2a45 41e0 c4 2649 4d1 26a4 819 222f 40c0 142 2240 4d1 26aa 6e2 2662 41e0 4d1 26a6 859 2249 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000
[10:33:40.906] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!
[10:33:40.906] <TB1> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!
[10:33:40.906] <TB1> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!
[10:33:40.906] <TB1> WARNING: Channel 3 ROC 0: Readback start marker after 15 readouts!
[10:33:40.906] <TB1> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[10:33:40.906] <TB1> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[10:33:40.906] <TB1> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2c2 8000 41e0 4d1 2685 510 2844 603 2469 611 22ac 64c 206f 645 2868 41e1 5b 246b 109 2649 162 204f 143 264f 4d1 2687 812 244e 40e1 14b 226d 21c 2248 35a 2845 351 244e 4d1 268d 503 2264 40c1 24d 2249 354 284e 4d1 26a5 754 206f e002 c000
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2bc 80b0 41e1 44 248d 4d1 268a 41e0 34c 284b 4d1 2680 41e1 109 264f 149 244f 4d1 268f 509 248c 6c0 284a 41c1 2 284c 292 2a81 4d1 26a3 e002 c000
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2bd 80c0 40c0 e1 206c 491 222e 4d1 268e 654 2a4c 41e0 19 2a4f 15b 224c 4d1 2682 642 2a65 41e0 219 2463 214 2446 2db 2083 495 246f 4d1 26a4 644 2461 859 244a 40c0 2d0 2084 2c9 2881 415 2269 4d1 268f e002 c000
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2be 8000 41e0 c1 224f 2d2 2a64 4d1 268e 6d0 2448 40e2 2ca 286c 4d1 26a1 41e0 c1 262f 4d1 268f 512 264f 41e0 d1 2484 445 2865 4d1 268b fff 2fff e002 c000
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2bf 8040 40c2 14b 2a68 21b 268f 480 2444 4d1 26a0 40c1 9 2848 21 2a4f 49c 226c 4d1 26ae 682 2462 6d1 2062 858 2a48 41e2 285 2a48 2db 264f 440 2684 4d1 2688 701 2442 850 262c 41e2 4d1 26ad e002 c000
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2c0 80b0 41e1 4d1 26ae 753 2865 41e0 159 2a4f 24a 244c 2c1 2862 311 2440 4d1 26ae 644 266a 754 2422 760 224d 810 2668 41e1 141 2846 282 2462 292 2669 2d9 224e 4d1 26a7 68c 222e 85c 224f 41e1 82 2242 103 2849 20c 204f 4d1 26a8 662 284f 6c5 2245 e002 c000
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2c1 80c0 40e0 da 2660 29d 2ac1 51c 22a2 693 246a 85d 248a 41e0 141 2841 288 2461 288 2046 4d1 26a1 759 2a62 41e0 245 2a68 302 204c 4d1 268d 541 284d 41e0 48 264f 4d0 2264 4d1 2687 e002 c000
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a3c2 8001 41e0 208 204f 4d1 26a0 41e0 14c 2025 4d1 268f 41e1 4d1 26ad 41e1 151 286e 4d1 26a6 854 264f 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
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a3bc 80b1 41e1 144 204f 4d1 26c6 50c 2a61 41e1 4d1 268e 68b 262f 41e1 49 2646 481 2a65 4d1 26a8 552 226e 41c1 4d1 26a5 519 2866 512 224d e002 c000
[10:33:40.906] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a3bd 80c1 40c0 95 264e 4d1 2685 4d4 2a45 41e0 c4 2649 4d1 26a4 819 222f 40c0 142 2240 4d1 26aa 6e2 2662 41e0 4d1 26a6 859 2249 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000
[10:33:40.907] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a3be 8001 41e0 29d 206c 494 2465 4d1 26aa 60b 282e 71a 244c 41e0 41a 206e 4d1 26a8 71a 264f 74c 286d 85b 2448 41e0 4d1 26a5 860 244c 41e0 4d1 26ab e002 c000
[10:33:40.907] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a3bf 8041 40c2 1c 2245 30a 208a 4d1 26aa 6d9 2840 6cb 262e 40c2 142 2046 4d1 268d 40c2 494 2860 4d1 26a8 41e2 4d1 26a5 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000
[10:33:40.907] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a3c0 80b1 41e1 15b 284e 349 2a68 4d1 268f 41e1 9b 2a6d 214 2445 4d1 26a2 6da 2a49 41e1 4d1 26a6 41e1 122 2886 4d1 26a3 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
[10:33:40.907] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a3c1 80c1 40e1 84 2064 4d1 26ae 40c1 4d1 268c 41e1 4d1 268a 74a 2046 41e1 4d1 26a2 6d1 206f 754 286b fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000
[10:33:57.957] <TB1> INFO: 208000 events read in total (32743ms).
[10:33:57.975] <TB1> INFO: Test took 33645ms.
[10:33:59.515] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:33:59.515] <TB1> INFO: number of red-efficiency pixels: 1239 2257 1537 2676 3111 3568 3743 3694 3886 3491 3832 2655 2757 1441 1603 1401
[10:33:59.515] <TB1> INFO: number of X-ray hits detected: 399960 467361 389651 610380 682268 786910 816645 774487 874472 759430 827524 610208 623196 369919 399772 415456
[10:33:59.515] <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
[10:33:59.515] <TB1> INFO: number of Vcal hits detected: 206399 204426 204189 202351 200055 196413 194192 197317 193987 197064 194089 202219 202279 204794 205831 206138
[10:33:59.515] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.3 98.4 98.5 97.6 96.6 95.1 94.2 95.3 93.8 95.4 94.0 97.6 97.6 98.7 99.0 99.2
[10:33:59.515] <TB1> INFO: Vcal hit overall efficiency (%): 99.2 98.3 98.2 97.3 96.2 94.4 93.4 94.9 93.3 94.7 93.3 97.2 97.2 98.5 99.0 99.1
[10:33:59.515] <TB1> INFO: X-ray hit rate [MHz/cm2]: 117.2 137.0 114.2 178.9 200.0 230.6 239.4 227.0 256.3 222.6 242.6 178.9 182.7 108.4 117.2 121.8
[10:33:59.515] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[10:33:59.545] <TB1> INFO: PixTest:: pg_setup set to default.
[10:33:59.559] <TB1> INFO: enter test to run
[10:33:59.559] <TB1> INFO: test: no parameter change
[10:33:59.802] <TB1> QUIET: Connection to board 147 closed.
[10:33:59.882] <TB1> INFO: pXar: this is the end, my friend