[14:29:02.287] <TB2> INFO: *** Welcome to pxar ***
[14:29:02.287] <TB2> INFO: *** Today: 2015/12/08
[14:29:02.411] <TB2> INFO: *** Version: c80f-dirty
[14:29:02.412] <TB2> INFO: readRocDacs: /usr/local/data/M2123_XrayQualification_2015-12-08_13h49m_1449578982//018_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2123_XrayQualification_2015-12-08_13h49m_1449578982//018_HREfficiency_250//dacParameters35_C15.dat
[14:29:02.413] <TB2> INFO: readTbmDacs: /usr/local/data/M2123_XrayQualification_2015-12-08_13h49m_1449578982//018_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2123_XrayQualification_2015-12-08_13h49m_1449578982//018_HREfficiency_250//tbmParameters_C0b.dat
[14:29:02.413] <TB2> INFO: readMaskFile: /usr/local/data/M2123_XrayQualification_2015-12-08_13h49m_1449578982//018_HREfficiency_250//defaultMaskFile.dat
[14:29:02.413] <TB2> INFO: readTrimFile: /usr/local/data/M2123_XrayQualification_2015-12-08_13h49m_1449578982//018_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2123_XrayQualification_2015-12-08_13h49m_1449578982//018_HREfficiency_250//trimParameters35_C15.dat
[14:29:02.483] <TB2> INFO: clk: 4
[14:29:02.484] <TB2> INFO: ctr: 4
[14:29:02.484] <TB2> INFO: sda: 19
[14:29:02.484] <TB2> INFO: tin: 9
[14:29:02.484] <TB2> INFO: level: 15
[14:29:02.484] <TB2> INFO: triggerdelay: 0
[14:29:02.484] <TB2> QUIET: Instanciating API for pxar prod-12
[14:29:02.484] <TB2> INFO: Log level: INFO
[14:29:02.496] <TB2> INFO: Found DTB DTB_WXENWR
[14:29:02.513] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[14:29:02.516] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[14:29:02.519] <TB2> INFO: RPC call hashes of host and DTB match: 398089610
[14:29:04.038] <TB2> INFO: DUT info:
[14:29:04.038] <TB2> INFO: The DUT currently contains the following objects:
[14:29:04.038] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[14:29:04.038] <TB2> INFO: TBM Core alpha (0): 7 registers set
[14:29:04.038] <TB2> INFO: TBM Core beta (1): 7 registers set
[14:29:04.038] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:29:04.038] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.038] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:29:04.439] <TB2> INFO: enter 'restricted' command line mode
[14:29:04.439] <TB2> INFO: enter test to run
[14:29:04.440] <TB2> INFO: test: PixelAlive no parameter change
[14:29:04.440] <TB2> INFO: running: pixelalive
[14:29:04.446] <TB2> INFO: ######################################################################
[14:29:04.446] <TB2> INFO: PixTestAlive::doTest()
[14:29:04.446] <TB2> INFO: ######################################################################
[14:29:04.448] <TB2> INFO: ----------------------------------------------------------------------
[14:29:04.448] <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)
[14:29:04.448] <TB2> INFO: ----------------------------------------------------------------------
[14:29:04.769] <TB2> INFO: Expecting 208000 events.
[14:29:15.740] <TB2> INFO: 208000 events read in total (10252ms).
[14:29:15.751] <TB2> INFO: Test took 11301ms.
[14:29:15.757] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:16.036] <TB2> INFO: PixTestAlive::aliveTest() done
[14:29:16.036] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[14:29:16.038] <TB2> INFO: ----------------------------------------------------------------------
[14:29:16.038] <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)
[14:29:16.038] <TB2> INFO: ----------------------------------------------------------------------
[14:29:16.375] <TB2> INFO: Expecting 208000 events.
[14:29:23.669] <TB2> INFO: 208000 events read in total (6578ms).
[14:29:23.670] <TB2> INFO: Test took 7629ms.
[14:29:23.670] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:23.670] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:29:23.994] <TB2> INFO: PixTestAlive::maskTest() done
[14:29:23.994] <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
[14:29:23.996] <TB2> INFO: ----------------------------------------------------------------------
[14:29:23.996] <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)
[14:29:23.996] <TB2> INFO: ----------------------------------------------------------------------
[14:29:24.327] <TB2> INFO: Expecting 208000 events.
[14:29:35.564] <TB2> INFO: 208000 events read in total (10520ms).
[14:29:35.565] <TB2> INFO: Test took 11567ms.
[14:29:35.571] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:35.814] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[14:29:35.814] <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
[14:29:35.814] <TB2> INFO: PixTestAlive::doTest() done, duration: 31 seconds
[14:29:35.854] <TB2> INFO: enter test to run
[14:29:35.854] <TB2> INFO: test: delay setting parameters: ->2<-
[14:29:35.854] <TB2> INFO: delay test by 2 seconds...
[14:29:37.854] <TB2> INFO: enter test to run
[14:29:37.854] <TB2> INFO: test: HighRate no parameter change
[14:29:37.854] <TB2> INFO: running: highrate
[14:29:37.855] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2123_XrayQualification_2015-12-08_13h49m_1449578982//018_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2123_XrayQualification_2015-12-08_13h49m_1449578982//018_HREfficiency_250//phCalibrationFitErr35_C15.dat
[14:29:38.022] <TB2> INFO: ----------------------------------------------------------------------
[14:29:38.022] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:29:38.022] <TB2> INFO: ----------------------------------------------------------------------
[14:29:38.638] <TB2> INFO: Expecting 208000 events.
[14:29:54.467] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (5) != Token Chain Length (4)
[14:29:54.467] <TB2> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:29:54.467] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a119 80c0 43e9 408 2a2a 4d1 2482 80b 2447 800 2449 43e9 62 204c 43 2288 54 264b 120 268d 2dc 2462 4d1 24a5 514 2244 722 246f 855 2442 851 224c 853 2241 860 2644 43e9 c8 2049 ca 2645 4d1 2482 43e8 155 2864 220 208c 25c 204f 40a 2280 40a 266b e002 c000
[14:29:54.467] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a113 8040 43e8 4b 2664 4dc 242e 4d1 2462 819 244d 851 2a62 858 2645 43e9 4d1 24ab 544 2467 600 2661 43e8 11c 2080 348 284f 4d1 248d 43e8 142 2247 2c0 2663 454 266c 4d1 24c5 71a 2643 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000
[14:29:54.467] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a114 80b1 43e8 80 246c 102 224f 4d1 2462 6d4 2428 6c3 2462 43e8 42 2066 28a 2a44 419 2885 414 228f 818 2a4d 848 2665 84d 2667 43e8 44b 226c 4d1 24a1 50a 204b 43e8 9a 2462 dd 224f 20a 226a 305 2869 49b 246c 489 206d 4d1 24a4 742 206a e002 c000
[14:29:54.467] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a115 80c0 43e8 35a 2040 359 2863 4d1 248f 43e8 515 2247 55a 246a 43e8 4a 2864 91 206b 89 2482 e2 2664 459 2685 4d1 284f 4d1 2486 4d9 2660 64b 2264 6e2 2a4d 860 2861 43e8 4 2045 141 2247 4d1 24ad 520 284f 521 264f 54a 286c 542 248a 542 2a6d 80b 2a64 81c 2261 852 2849 852 2a4f e002 c000
[14:29:54.467] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a116 8000 43e8 345 242f 34a 224d 4d1 246f 54a 246f 685 224e 43e8 20b 2287 2c5 2449 4d1 248f 75b 2665 84b 224f 858 2649 43e8 c9 2643 301 2a65 702 2041 43e8 4a 2a8c 43eb 2a49 413 2485 418 2a6f 419 226d 559 2a69 e002 c000
[14:29:54.467] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a117 8040 43e8 34c 2426 4d1 246f 4cc 282f 43e8 5b 2465 52 2865 44c 244f 4d1 24a2 43e8 4d1 2485 610 284f 689 244d 6e2 2a4b 43e9 91 206c 88 2869 144 264d 318 204e 322 2661 4d2 2849 4d1 24a8 852 2a49 e002 c000
[14:29:54.467] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a118 80b1 43e9 34b 2a6c 4d1 246f 843 2689 43e9 315 208c 4d1 24ad 4cc 2068 43e9 15 2469 c 204d a 266c 19 2a85 4d1 248c 74a 2a45 43e9 285 2465 280 2469 4d1 24ac 542 2281 642 2a4d 740 262e e002 c000
[14:30:11.541] <TB2> INFO: 197100 events read in total (32375ms).
[14:30:13.597] <TB2> INFO: 208000 events read in total (34431ms).
[14:30:13.614] <TB2> INFO: Test took 35587ms.
[14:30:15.035] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:30:15.673] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[14:30:15.673] <TB2> INFO: number of red-efficiency pixels: 2763 2445 1993 3658 3742 4040 3976 4070 4102 3983 4069 3954 3899 1944 2761 2445
[14:30:15.673] <TB2> INFO: number of X-ray hits detected: 532613 542857 513434 801637 843589 958548 925646 940853 1012094 915694 967570 904569 809658 470598 529697 550469
[14:30:15.673] <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
[14:30:15.674] <TB2> INFO: number of Vcal hits detected: 202558 204046 200357 195440 193425 187103 187796 188910 183750 189023 186704 189442 192056 200605 202839 203394
[14:30:15.674] <TB2> INFO: Vcal hit fiducial efficiency (%): 97.6 98.2 97.0 94.8 93.8 91.0 91.4 91.5 89.3 91.9 90.8 92.0 93.2 97.0 97.7 98.1
[14:30:15.674] <TB2> INFO: Vcal hit overall efficiency (%): 97.4 98.1 96.3 94.0 93.0 90.0 90.3 90.8 88.3 90.9 89.8 91.1 92.3 96.4 97.5 97.8
[14:30:15.674] <TB2> INFO: X-ray hit rate [MHz/cm2]: 156.1 159.1 150.5 235.0 247.3 281.0 271.3 275.8 296.7 268.4 283.6 265.1 237.3 137.9 155.3 161.3
[14:30:15.674] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[14:30:15.720] <TB2> INFO: PixTest:: pg_setup set to default.
[14:30:15.738] <TB2> INFO: enter test to run
[14:30:15.738] <TB2> INFO: test: no parameter change
[14:30:15.845] <TB2> QUIET: Connection to board 162 closed.
[14:30:15.925] <TB2> INFO: pXar: this is the end, my friend