[10:11:23.513]     INFO: *** Welcome to pxar ***
[10:11:23.513]     INFO: *** Today: 2016/06/01
[10:11:23.535]     INFO: *** Version: v1.9.0-805-geb36
[10:11:23.535]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/dacParameters35_C15.dat
[10:11:23.536]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/tbmParameters_C0b.dat
[10:11:23.537]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/defaultMaskFile.dat
[10:11:23.537]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/trimParameters35_C15.dat
[10:11:23.686]     INFO:         clk: 4
[10:11:23.686]     INFO:         ctr: 4
[10:11:23.686]     INFO:         sda: 19
[10:11:23.686]     INFO:         tin: 9
[10:11:23.686]     INFO:         level: 15
[10:11:23.686]     INFO:         triggerdelay: 0
[10:11:23.686]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:11:23.686]     INFO: Log level: INFO
[10:11:23.698]    QUIET: Connection to board DTB_WRPRHI opened.
[10:11:23.701]     INFO: DTB startup information
--- DTB info------------------------------------------
Board id:    58
HW version:  DTB1.2
FW version:  4.2
SW version:  4.5
USB id:      DTB_WRPRHI
MAC address: 40D85511803A
Hostname:    pixelDTB058
Comment:     
------------------------------------------------------
[10:11:23.704]     INFO: RPC call hashes of host and DTB match: 398089610
[10:11:25.303]     INFO: DUT info: 
[10:11:25.303]     INFO: The DUT currently contains the following objects:
[10:11:25.303]     INFO:  2 TBM Cores tbm08c (2 ON)
[10:11:25.303]     INFO: 	TBM Core alpha (0): 7 registers set
[10:11:25.303]     INFO: 	TBM Core beta  (1): 7 registers set
[10:11:25.303]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:11:25.303]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.303]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.303]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.303]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.303]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.303]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.303]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.303]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.303]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.303]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.303]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.303]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.303]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.303]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.304]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.304]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:25.893]     INFO: PixTestCmd::init()
[10:11:25.901]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/phCalibrationFitErr35_C15.dat
[10:11:26.202]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/readbackCal_C15.dat
[10:11:39.043]     INFO:    ----------------------------------------------------------------------
[10:11:39.043]     INFO:    PixTestXray::doPhRun() fParRunSeconds = 60
[10:11:39.043]     INFO:    ----------------------------------------------------------------------
[10:11:40.016]     INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds,  fEventsMax = 6000000
[10:11:51.744]     INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:12:23.208]     INFO: Resuming triggers.
[10:12:34.937]     INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:13:06.419]     INFO: Resuming triggers.
[10:13:18.147]     INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[10:13:49.590]     INFO: Resuming triggers.
[10:14:01.314]     INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:14:32.756]     INFO: Resuming triggers.
[10:14:44.484]     INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[10:15:15.930]     INFO: Resuming triggers.
[10:15:17.369]     INFO: data taking finished, elapsed time: 60 seconds.
[10:15:21.607]     INFO: PixTest::       pg_setup set to default.
[10:15:21.643]     INFO: PixTestXray::doPhRun() done
[10:15:41.047]    QUIET: Connection to board 58 closed.
									 
									
									
															
						
							
						
						[10:15:49.295]     INFO: *** Welcome to pxar ***
[10:15:49.295]     INFO: *** Today: 2016/06/01
[10:15:49.318]     INFO: *** Version: v1.9.0-805-geb36
[10:15:49.318]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/dacParameters35_C15.dat
[10:15:49.319]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/tbmParameters_C0b.dat
[10:15:49.319]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/defaultMaskFile.dat
[10:15:49.319]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/trimParameters35_C15.dat
[10:15:49.469]     INFO:         clk: 4
[10:15:49.469]     INFO:         ctr: 4
[10:15:49.469]     INFO:         sda: 19
[10:15:49.469]     INFO:         tin: 9
[10:15:49.469]     INFO:         level: 15
[10:15:49.469]     INFO:         triggerdelay: 0
[10:15:49.469]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:15:49.469]     INFO: Log level: INFO
[10:15:49.479]    QUIET: Connection to board DTB_WRPRHI opened.
[10:15:49.483]     INFO: DTB startup information
--- DTB info------------------------------------------
Board id:    58
HW version:  DTB1.2
FW version:  4.2
SW version:  4.5
USB id:      DTB_WRPRHI
MAC address: 40D85511803A
Hostname:    pixelDTB058
Comment:     
------------------------------------------------------
[10:15:49.486]     INFO: RPC call hashes of host and DTB match: 398089610
[10:15:51.085]     INFO: DUT info: 
[10:15:51.085]     INFO: The DUT currently contains the following objects:
[10:15:51.085]     INFO:  2 TBM Cores tbm08c (2 ON)
[10:15:51.085]     INFO: 	TBM Core alpha (0): 7 registers set
[10:15:51.085]     INFO: 	TBM Core beta  (1): 7 registers set
[10:15:51.085]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:15:51.085]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.085]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:15:51.683]     INFO: PixTestCmd::init()
[10:15:51.687]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/phCalibrationFitErr35_C15.dat
[10:15:51.991]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/readbackCal_C15.dat
[10:16:08.331]     INFO: ######################################################################
[10:16:08.331]     INFO: PixTestXray::doTest()
[10:16:08.331]     INFO: ######################################################################
[10:16:08.331]     INFO:    ----------------------------------------------------------------------
[10:16:08.331]     INFO:    PixTestXray::doPhRun() fParRunSeconds = 60
[10:16:08.331]     INFO:    ----------------------------------------------------------------------
[10:16:09.304]     INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds,  fEventsMax = 6000000
[10:16:16.015]     INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:16:51.707]     INFO: Resuming triggers.
[10:16:58.431]     INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:17:34.207]     INFO: Resuming triggers.
[10:17:40.931]     INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[10:18:16.546]     INFO: Resuming triggers.
[10:18:23.269]     INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[10:18:59.022]     INFO: Resuming triggers.
[10:19:05.745]     INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:19:40.577]     INFO: Resuming triggers.
[10:19:47.303]     INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[10:20:22.164]     INFO: Resuming triggers.
[10:20:28.891]     INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:21:03.538]     INFO: Resuming triggers.
[10:21:10.266]     INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[10:21:44.933]     INFO: Resuming triggers.
[10:21:51.228]     INFO: data taking finished, elapsed time: 60 seconds.
[10:22:23.866]     INFO: PixTest::       pg_setup set to default.
[10:22:23.901]     INFO: PixTestXray::doPhRun() done
[10:22:23.901]     INFO: PixTestXray::doTest() done 
[10:23:51.450]    QUIET: Connection to board 58 closed.
									 
									
									
															
						
							
						
						[10:02:39.797]     INFO: *** Welcome to pxar ***
[10:02:39.798]     INFO: *** Today: 2016/06/01
[10:02:39.820]     INFO: *** Version: v1.9.0-805-geb36
[10:02:39.820]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/dacParameters35_C15.dat
[10:02:39.821]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/tbmParameters_C0b.dat
[10:02:39.821]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/defaultMaskFile.dat
[10:02:39.821]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/trimParameters35_C15.dat
[10:02:39.971]     INFO:         clk: 4
[10:02:39.971]     INFO:         ctr: 4
[10:02:39.971]     INFO:         sda: 19
[10:02:39.971]     INFO:         tin: 9
[10:02:39.971]     INFO:         level: 15
[10:02:39.971]     INFO:         triggerdelay: 0
[10:02:39.971]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:02:39.971]     INFO: Log level: INFO
[10:02:39.983]    QUIET: Connection to board DTB_WRPRHI opened.
[10:02:39.986]     INFO: DTB startup information
--- DTB info------------------------------------------
Board id:    58
HW version:  DTB1.2
FW version:  4.2
SW version:  4.5
USB id:      DTB_WRPRHI
MAC address: 40D85511803A
Hostname:    pixelDTB058
Comment:     
------------------------------------------------------
[10:02:39.988]     INFO: RPC call hashes of host and DTB match: 398089610
[10:02:41.588]     INFO: DUT info: 
[10:02:41.588]     INFO: The DUT currently contains the following objects:
[10:02:41.588]     INFO:  2 TBM Cores tbm08c (2 ON)
[10:02:41.588]     INFO: 	TBM Core alpha (0): 7 registers set
[10:02:41.588]     INFO: 	TBM Core beta  (1): 7 registers set
[10:02:41.588]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:02:41.588]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:41.588]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:42.182]     INFO: PixTestCmd::init()
[10:02:42.192]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/phCalibrationFitErr35_C15.dat
[10:02:42.492]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/readbackCal_C15.dat
[10:02:46.574]     INFO: ######################################################################
[10:02:46.574]     INFO: PixTestAlive::doTest()
[10:02:46.574]     INFO: ######################################################################
[10:02:46.578]     INFO:    ----------------------------------------------------------------------
[10:02:46.578]     INFO:    PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:02:46.578]     INFO:    ----------------------------------------------------------------------
[10:02:46.955]     INFO: Expecting 41600 events.
[10:02:51.209]     INFO: 41600 events read in total (3535ms).
[10:02:51.355]     INFO: Test took 4775ms.
[10:02:51.365]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:51.633]     INFO: PixTestAlive::aliveTest() done
[10:02:51.633]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[10:02:51.655]     INFO:    ----------------------------------------------------------------------
[10:02:51.655]     INFO:    PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:02:51.655]     INFO:    ----------------------------------------------------------------------
[10:02:52.032]     INFO: Expecting 41600 events.
[10:02:55.072]     INFO: 41600 events read in total (2325ms).
[10:02:55.072]     INFO: Test took 3415ms.
[10:02:55.072]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:55.073]     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0
[10:02:55.495]     INFO: PixTestAlive::maskTest() done
[10:02:55.495]     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:02:55.515]     INFO:    ----------------------------------------------------------------------
[10:02:55.515]     INFO:    PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:02:55.515]     INFO:    ----------------------------------------------------------------------
[10:02:55.891]     INFO: Expecting 41600 events.
[10:03:00.140]     INFO: 41600 events read in total (3533ms).
[10:03:00.141]     INFO: Test took 4624ms.
[10:03:00.153]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:00.523]     INFO: PixTestAlive::addressDecodingTest() done
[10:03:00.523]     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:03:00.523]     INFO: PixTestAlive::doTest() done, duration: 13 seconds
[10:03:10.531]     INFO:    ----------------------------------------------------------------------
[10:03:10.531]     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:03:10.531]     INFO:    ----------------------------------------------------------------------
[10:03:10.670]     INFO: Expecting 768 events.
[10:03:11.802]     INFO: 768 events read in total (417ms).
[10:03:11.802]     INFO: Test took 1266ms.
[10:03:12.606]     INFO: Expecting 41600 events.
[10:03:15.914]     INFO: 41600 events read in total (2781ms).
[10:03:15.919]     INFO: Test took 4111ms.
[10:03:16.022]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:16.733]     INFO: Expecting 41600 events.
[10:03:20.139]     INFO: 41600 events read in total (2879ms).
[10:03:20.145]     INFO: Test took 4077ms.
[10:03:20.247]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:20.960]     INFO: Expecting 41600 events.
[10:03:24.421]     INFO: 41600 events read in total (2934ms).
[10:03:24.425]     INFO: Test took 4131ms.
[10:03:24.525]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:25.237]     INFO: Expecting 41600 events.
[10:03:28.680]     INFO: 41600 events read in total (2916ms).
[10:03:28.684]     INFO: Test took 4112ms.
[10:03:28.784]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:29.498]     INFO: Expecting 41600 events.
[10:03:32.951]     INFO: 41600 events read in total (2927ms).
[10:03:32.955]     INFO: Test took 4122ms.
[10:03:33.056]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:33.770]     INFO: Expecting 41600 events.
[10:03:37.228]     INFO: 41600 events read in total (2931ms).
[10:03:37.232]     INFO: Test took 4127ms.
[10:03:37.331]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:38.043]     INFO: Expecting 41600 events.
[10:03:41.519]     INFO: 41600 events read in total (2949ms).
[10:03:41.523]     INFO: Test took 4144ms.
[10:03:41.623]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:42.337]     INFO: Expecting 41600 events.
[10:03:45.804]     INFO: 41600 events read in total (2941ms).
[10:03:45.808]     INFO: Test took 4136ms.
[10:03:45.909]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:46.623]     INFO: Expecting 41600 events.
[10:03:50.078]     INFO: 41600 events read in total (2929ms).
[10:03:50.082]     INFO: Test took 4124ms.
[10:03:50.183]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:50.897]     INFO: Expecting 41600 events.
[10:03:54.377]     INFO: 41600 events read in total (2953ms).
[10:03:54.381]     INFO: Test took 4149ms.
[10:03:54.482]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:55.195]     INFO: Expecting 41600 events.
[10:03:58.653]     INFO: 41600 events read in total (2931ms).
[10:03:58.657]     INFO: Test took 4127ms.
[10:03:58.758]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:59.471]     INFO: Expecting 41600 events.
[10:04:02.930]     INFO: 41600 events read in total (2932ms).
[10:04:02.934]     INFO: Test took 4127ms.
[10:04:03.033]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:03.746]     INFO: Expecting 41600 events.
[10:04:07.210]     INFO: 41600 events read in total (2937ms).
[10:04:07.214]     INFO: Test took 4133ms.
[10:04:07.315]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:08.029]     INFO: Expecting 41600 events.
[10:04:11.492]     INFO: 41600 events read in total (2936ms).
[10:04:11.496]     INFO: Test took 4132ms.
[10:04:11.595]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:12.308]     INFO: Expecting 41600 events.
[10:04:15.773]     INFO: 41600 events read in total (2938ms).
[10:04:15.777]     INFO: Test took 4134ms.
[10:04:15.876]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:16.588]     INFO: Expecting 41600 events.
[10:04:20.044]     INFO: 41600 events read in total (2930ms).
[10:04:20.048]     INFO: Test took 4125ms.
[10:04:20.149]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:20.863]     INFO: Expecting 41600 events.
[10:04:24.322]     INFO: 41600 events read in total (2933ms).
[10:04:24.327]     INFO: Test took 4130ms.
[10:04:24.427]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:25.139]     INFO: Expecting 41600 events.
[10:04:28.604]     INFO: 41600 events read in total (2939ms).
[10:04:28.608]     INFO: Test took 4134ms.
[10:04:28.709]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:29.423]     INFO: Expecting 41600 events.
[10:04:32.880]     INFO: 41600 events read in total (2931ms).
[10:04:32.884]     INFO: Test took 4126ms.
[10:04:32.985]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:33.699]     INFO: Expecting 41600 events.
[10:04:37.007]     INFO: 41600 events read in total (2781ms).
[10:04:37.011]     INFO: Test took 3977ms.
[10:04:37.107]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:06:06.771]     INFO:    ----------------------------------------------------------------------
[10:06:06.771]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:06:06.771]     INFO:    ----------------------------------------------------------------------
[10:06:07.452]     INFO: Expecting 208000 events.
[10:06:20.823]     INFO: 208000 events read in total (12844ms).
[10:06:20.838]     INFO: Test took 14050ms.
[10:06:21.301]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:06:21.705]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[10:06:21.705]     INFO: number of red-efficiency pixels:   141  105  164  332  376  384  400  284  266  404  375  373  339  223  111  134
[10:06:21.705]     INFO: number of X-ray hits detected:    99801 74750 124102 192429 207658 203949 201989 176301 172610 212991 208339 197272 198041 129106 75662 88014
[10:06:21.705]     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:06:21.705]     INFO: number of Vcal hits detected:  207854 207894 207829 207646 207591 207591 207580 207706 207723 207575 207567 207614 207647 207760 207883 207864
[10:06:21.705]     INFO: Vcal hit fiducial efficiency (%):  99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[10:06:21.705]     INFO: Vcal hit overall efficiency (%):  99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[10:06:21.705]     INFO: X-ray hit rate [MHz/cm2]:  29.3 21.9 36.4 56.4 60.9 59.8 59.2 51.7 50.6 62.4 61.1 57.8 58.0 37.8 22.2 25.8
[10:06:21.705]     INFO: PixTestHighRate::doXPixelAlive() done
[10:06:21.751]     INFO: PixTest::       pg_setup set to default.
[10:07:38.966]    QUIET: Connection to board 58 closed.
									 
									
									
															
						
							
						
						[09:57:55.977]     INFO: *** Welcome to pxar ***
[09:57:55.977]     INFO: *** Today: 2016/06/01
[09:57:56.000]     INFO: *** Version: v1.9.0-805-geb36
[09:57:56.000]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/dacParameters35_C15.dat
[09:57:56.001]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/tbmParameters_C0b.dat
[09:57:56.001]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/defaultMaskFile.dat
[09:57:56.001]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/trimParameters35_C15.dat
[09:57:56.150]     INFO:         clk: 4
[09:57:56.150]     INFO:         ctr: 4
[09:57:56.150]     INFO:         sda: 19
[09:57:56.150]     INFO:         tin: 9
[09:57:56.150]     INFO:         level: 15
[09:57:56.150]     INFO:         triggerdelay: 0
[09:57:56.150]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[09:57:56.150]     INFO: Log level: INFO
[09:57:56.161]    QUIET: Connection to board DTB_WRPRHI opened.
[09:57:56.164]     INFO: DTB startup information
--- DTB info------------------------------------------
Board id:    58
HW version:  DTB1.2
FW version:  4.2
SW version:  4.5
USB id:      DTB_WRPRHI
MAC address: 40D85511803A
Hostname:    pixelDTB058
Comment:     
------------------------------------------------------
[09:57:56.167]     INFO: RPC call hashes of host and DTB match: 398089610
[09:57:57.766]     INFO: DUT info: 
[09:57:57.766]     INFO: The DUT currently contains the following objects:
[09:57:57.766]     INFO:  2 TBM Cores tbm08c (2 ON)
[09:57:57.766]     INFO: 	TBM Core alpha (0): 7 registers set
[09:57:57.766]     INFO: 	TBM Core beta  (1): 7 registers set
[09:57:57.766]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:57:57.766]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:57.766]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:57:58.350]     INFO: PixTestCmd::init()
[09:57:58.358]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/phCalibrationFitErr35_C15.dat
[09:57:58.658]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/readbackCal_C15.dat
[09:58:01.662]     INFO: ######################################################################
[09:58:01.662]     INFO: PixTestAlive::doTest()
[09:58:01.662]     INFO: ######################################################################
[09:58:01.665]     INFO:    ----------------------------------------------------------------------
[09:58:01.665]     INFO:    PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:58:01.665]     INFO:    ----------------------------------------------------------------------
[09:58:02.043]     INFO: Expecting 41600 events.
[09:58:06.288]     INFO: 41600 events read in total (3527ms).
[09:58:06.435]     INFO: Test took 4767ms.
[09:58:06.445]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:06.714]     INFO: PixTestAlive::aliveTest() done
[09:58:06.714]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[09:58:06.737]     INFO:    ----------------------------------------------------------------------
[09:58:06.737]     INFO:    PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:58:06.737]     INFO:    ----------------------------------------------------------------------
[09:58:07.113]     INFO: Expecting 41600 events.
[09:58:10.165]     INFO: 41600 events read in total (2337ms).
[09:58:10.166]     INFO: Test took 3427ms.
[09:58:10.166]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:10.166]     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0
[09:58:10.588]     INFO: PixTestAlive::maskTest() done
[09:58:10.588]     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:58:10.607]     INFO:    ----------------------------------------------------------------------
[09:58:10.607]     INFO:    PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:58:10.607]     INFO:    ----------------------------------------------------------------------
[09:58:10.983]     INFO: Expecting 41600 events.
[09:58:15.239]     INFO: 41600 events read in total (3541ms).
[09:58:15.240]     INFO: Test took 4631ms.
[09:58:15.252]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:15.624]     INFO: PixTestAlive::addressDecodingTest() done
[09:58:15.624]     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:58:15.624]     INFO: PixTestAlive::doTest() done, duration: 13 seconds
[09:58:28.907]     INFO:    ----------------------------------------------------------------------
[09:58:28.907]     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:58:28.907]     INFO:    ----------------------------------------------------------------------
[09:58:29.046]     INFO: Expecting 768 events.
[09:58:30.178]     INFO: 768 events read in total (417ms).
[09:58:30.179]     INFO: Test took 1266ms.
[09:58:30.982]     INFO: Expecting 41600 events.
[09:58:34.597]     INFO: 41600 events read in total (3088ms).
[09:58:34.603]     INFO: Test took 4419ms.
[09:58:34.751]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:35.479]     INFO: Expecting 41600 events.
[09:58:39.204]     INFO: 41600 events read in total (3198ms).
[09:58:39.209]     INFO: Test took 4395ms.
[09:58:39.352]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:40.082]     INFO: Expecting 41600 events.
[09:58:43.858]     INFO: 41600 events read in total (3249ms).
[09:58:43.863]     INFO: Test took 4446ms.
[09:58:44.007]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:44.740]     INFO: Expecting 41600 events.
[09:58:48.515]     INFO: 41600 events read in total (3249ms).
[09:58:48.521]     INFO: Test took 4446ms.
[09:58:48.661]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:49.393]     INFO: Expecting 41600 events.
[09:58:53.171]     INFO: 41600 events read in total (3251ms).
[09:58:53.177]     INFO: Test took 4449ms.
[09:58:53.320]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:54.052]     INFO: Expecting 41600 events.
[09:58:57.837]     INFO: 41600 events read in total (3258ms).
[09:58:57.843]     INFO: Test took 4456ms.
[09:58:57.985]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:58.716]     INFO: Expecting 41600 events.
[09:59:02.505]     INFO: 41600 events read in total (3262ms).
[09:59:02.510]     INFO: Test took 4459ms.
[09:59:02.650]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:03.383]     INFO: Expecting 41600 events.
[09:59:07.189]     INFO: 41600 events read in total (3280ms).
[09:59:07.195]     INFO: Test took 4477ms.
[09:59:07.338]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:08.071]     INFO: Expecting 41600 events.
[09:59:11.880]     INFO: 41600 events read in total (3282ms).
[09:59:11.885]     INFO: Test took 4479ms.
[09:59:12.027]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:12.759]     INFO: Expecting 41600 events.
[09:59:16.546]     INFO: 41600 events read in total (3261ms).
[09:59:16.551]     INFO: Test took 4458ms.
[09:59:16.695]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:17.427]     INFO: Expecting 41600 events.
[09:59:21.238]     INFO: 41600 events read in total (3284ms).
[09:59:21.243]     INFO: Test took 4481ms.
[09:59:21.385]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:22.118]     INFO: Expecting 41600 events.
[09:59:25.921]     INFO: 41600 events read in total (3277ms).
[09:59:25.927]     INFO: Test took 4474ms.
[09:59:26.070]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:26.802]     INFO: Expecting 41600 events.
[09:59:30.610]     INFO: 41600 events read in total (3282ms).
[09:59:30.615]     INFO: Test took 4478ms.
[09:59:30.757]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:31.489]     INFO: Expecting 41600 events.
[09:59:35.317]     INFO: 41600 events read in total (3301ms).
[09:59:35.322]     INFO: Test took 4498ms.
[09:59:35.465]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:36.197]     INFO: Expecting 41600 events.
[09:59:40.020]     INFO: 41600 events read in total (3296ms).
[09:59:40.025]     INFO: Test took 4493ms.
[09:59:40.169]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:40.901]     INFO: Expecting 41600 events.
[09:59:44.728]     INFO: 41600 events read in total (3300ms).
[09:59:44.733]     INFO: Test took 4497ms.
[09:59:44.876]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:45.608]     INFO: Expecting 41600 events.
[09:59:49.426]     INFO: 41600 events read in total (3291ms).
[09:59:49.432]     INFO: Test took 4489ms.
[09:59:49.573]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:50.305]     INFO: Expecting 41600 events.
[09:59:54.135]     INFO: 41600 events read in total (3304ms).
[09:59:54.140]     INFO: Test took 4500ms.
[09:59:54.282]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:55.015]     INFO: Expecting 41600 events.
[09:59:58.828]     INFO: 41600 events read in total (3287ms).
[09:59:58.834]     INFO: Test took 4485ms.
[09:59:58.975]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:59.706]     INFO: Expecting 41600 events.
[10:00:03.381]     INFO: 41600 events read in total (3148ms).
[10:00:03.386]     INFO: Test took 4344ms.
[10:00:03.528]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:41.235]     INFO:    ----------------------------------------------------------------------
[10:00:41.235]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:00:41.235]     INFO:    ----------------------------------------------------------------------
[10:00:41.916]     INFO: Expecting 208000 events.
[10:00:57.739]     INFO: 208000 events read in total (15297ms).
[10:00:57.760]     INFO: Test took 16510ms.
[10:00:58.460]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:58.955]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[10:00:58.955]     INFO: number of red-efficiency pixels:   236  161  369  783  816  801  737  626  578  901  804  767  747  428  187  210
[10:00:58.955]     INFO: number of X-ray hits detected:    154199 117129 193300 300739 321644 318759 313861 274255 269243 331638 324832 309116 308749 200682 117435 138002
[10:00:58.955]     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:00:58.955]     INFO: number of Vcal hits detected:  207757 207827 207587 207094 207038 207066 207157 207272 207354 206942 207082 207124 207138 207522 207804 207781
[10:00:58.955]     INFO: Vcal hit fiducial efficiency (%):  99.9 99.9 99.8 99.6 99.6 99.6 99.6 99.7 99.7 99.5 99.6 99.6 99.6 99.8 99.9 99.9
[10:00:58.955]     INFO: Vcal hit overall efficiency (%):  99.9 99.9 99.8 99.6 99.5 99.6 99.6 99.7 99.7 99.5 99.6 99.6 99.6 99.8 99.9 99.9
[10:00:58.955]     INFO: X-ray hit rate [MHz/cm2]:  45.2 34.3 56.7 88.1 94.3 93.4 92.0 80.4 78.9 97.2 95.2 90.6 90.5 58.8 34.4 40.4
[10:00:58.955]     INFO: PixTestHighRate::doXPixelAlive() done
[10:00:59.000]     INFO: PixTest::       pg_setup set to default.
[10:02:30.822]    QUIET: Connection to board 58 closed.
									 
									
									
															
						
							
						
						[09:54:03.590]     INFO: *** Welcome to pxar ***
[09:54:03.590]     INFO: *** Today: 2016/06/01
[09:54:03.613]     INFO: *** Version: v1.9.0-805-geb36
[09:54:03.613]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/dacParameters35_C15.dat
[09:54:03.614]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/tbmParameters_C0b.dat
[09:54:03.614]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/defaultMaskFile.dat
[09:54:03.614]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/trimParameters35_C15.dat
[09:54:03.763]     INFO:         clk: 4
[09:54:03.763]     INFO:         ctr: 4
[09:54:03.763]     INFO:         sda: 19
[09:54:03.763]     INFO:         tin: 9
[09:54:03.763]     INFO:         level: 15
[09:54:03.763]     INFO:         triggerdelay: 0
[09:54:03.763]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[09:54:03.763]     INFO: Log level: INFO
[09:54:03.775]    QUIET: Connection to board DTB_WRPRHI opened.
[09:54:03.778]     INFO: DTB startup information
--- DTB info------------------------------------------
Board id:    58
HW version:  DTB1.2
FW version:  4.2
SW version:  4.5
USB id:      DTB_WRPRHI
MAC address: 40D85511803A
Hostname:    pixelDTB058
Comment:     
------------------------------------------------------
[09:54:03.781]     INFO: RPC call hashes of host and DTB match: 398089610
[09:54:05.380]     INFO: DUT info: 
[09:54:05.380]     INFO: The DUT currently contains the following objects:
[09:54:05.380]     INFO:  2 TBM Cores tbm08c (2 ON)
[09:54:05.380]     INFO: 	TBM Core alpha (0): 7 registers set
[09:54:05.380]     INFO: 	TBM Core beta  (1): 7 registers set
[09:54:05.380]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:54:05.380]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.380]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:54:05.981]     INFO: PixTestCmd::init()
[09:54:05.985]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/phCalibrationFitErr35_C15.dat
[09:54:06.284]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mytnj/readbackCal_C15.dat
[09:54:15.398]     INFO: ######################################################################
[09:54:15.398]     INFO: PixTestAlive::doTest()
[09:54:15.398]     INFO: ######################################################################
[09:54:15.401]     INFO:    ----------------------------------------------------------------------
[09:54:15.401]     INFO:    PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:54:15.401]     INFO:    ----------------------------------------------------------------------
[09:54:15.779]     INFO: Expecting 41600 events.
[09:54:20.034]     INFO: 41600 events read in total (3536ms).
[09:54:20.180]     INFO: Test took 4776ms.
[09:54:20.190]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:54:20.458]     INFO: PixTestAlive::aliveTest() done
[09:54:20.459]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[09:54:20.481]     INFO:    ----------------------------------------------------------------------
[09:54:20.481]     INFO:    PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:54:20.481]     INFO:    ----------------------------------------------------------------------
[09:54:20.857]     INFO: Expecting 41600 events.
[09:54:23.896]     INFO: 41600 events read in total (2324ms).
[09:54:23.896]     INFO: Test took 3413ms.
[09:54:23.896]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:54:23.897]     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0
[09:54:24.319]     INFO: PixTestAlive::maskTest() done
[09:54:24.319]     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:54:24.339]     INFO:    ----------------------------------------------------------------------
[09:54:24.339]     INFO:    PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:54:24.339]     INFO:    ----------------------------------------------------------------------
[09:54:24.715]     INFO: Expecting 41600 events.
[09:54:28.968]     INFO: 41600 events read in total (3537ms).
[09:54:28.970]     INFO: Test took 4629ms.
[09:54:28.980]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:54:29.354]     INFO: PixTestAlive::addressDecodingTest() done
[09:54:29.354]     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:54:29.354]     INFO: PixTestAlive::doTest() done, duration: 13 seconds
[09:54:33.955]     INFO:    ----------------------------------------------------------------------
[09:54:33.955]     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:54:33.955]     INFO:    ----------------------------------------------------------------------
[09:54:34.094]     INFO: Expecting 768 events.
[09:54:35.226]     INFO: 768 events read in total (417ms).
[09:54:35.227]     INFO: Test took 1267ms.
[09:54:36.030]     INFO: Expecting 41600 events.
[09:54:40.070]     INFO: 41600 events read in total (3514ms).
[09:54:40.077]     INFO: Test took 4845ms.
[09:54:40.278]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:54:41.028]     INFO: Expecting 41600 events.
[09:54:45.144]     INFO: 41600 events read in total (3590ms).
[09:54:45.150]     INFO: Test took 4788ms.
[09:54:45.344]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:54:46.093]     INFO: Expecting 41600 events.
[09:54:50.232]     INFO: 41600 events read in total (3612ms).
[09:54:50.239]     INFO: Test took 4811ms.
[09:54:50.430]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:54:51.181]     INFO: Expecting 41600 events.
[09:54:55.330]     INFO: 41600 events read in total (3622ms).
[09:54:55.337]     INFO: Test took 4821ms.
[09:54:55.528]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:54:56.278]     INFO: Expecting 41600 events.
[09:55:00.442]     INFO: 41600 events read in total (3637ms).
[09:55:00.449]     INFO: Test took 4835ms.
[09:55:00.638]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:01.389]     INFO: Expecting 41600 events.
[09:55:05.559]     INFO: 41600 events read in total (3644ms).
[09:55:05.566]     INFO: Test took 4842ms.
[09:55:05.759]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:06.510]     INFO: Expecting 41600 events.
[09:55:10.683]     INFO: 41600 events read in total (3646ms).
[09:55:10.690]     INFO: Test took 4845ms.
[09:55:10.881]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:11.633]     INFO: Expecting 41600 events.
[09:55:15.827]     INFO: 41600 events read in total (3668ms).
[09:55:15.834]     INFO: Test took 4866ms.
[09:55:16.027]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:16.780]     INFO: Expecting 41600 events.
[09:55:20.965]     INFO: 41600 events read in total (3658ms).
[09:55:20.972]     INFO: Test took 4857ms.
[09:55:21.166]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:21.919]     INFO: Expecting 41600 events.
[09:55:26.115]     INFO: 41600 events read in total (3669ms).
[09:55:26.121]     INFO: Test took 4867ms.
[09:55:26.313]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:27.063]     INFO: Expecting 41600 events.
[09:55:31.244]     INFO: 41600 events read in total (3654ms).
[09:55:31.250]     INFO: Test took 4852ms.
[09:55:31.440]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:32.192]     INFO: Expecting 41600 events.
[09:55:36.366]     INFO: 41600 events read in total (3647ms).
[09:55:36.389]     INFO: Test took 4863ms.
[09:55:36.582]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:37.334]     INFO: Expecting 41600 events.
[09:55:41.518]     INFO: 41600 events read in total (3658ms).
[09:55:41.525]     INFO: Test took 4856ms.
[09:55:41.717]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:42.468]     INFO: Expecting 41600 events.
[09:55:46.661]     INFO: 41600 events read in total (3667ms).
[09:55:46.668]     INFO: Test took 4865ms.
[09:55:46.858]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:47.610]     INFO: Expecting 41600 events.
[09:55:51.794]     INFO: 41600 events read in total (3657ms).
[09:55:51.800]     INFO: Test took 4854ms.
[09:55:51.991]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:52.743]     INFO: Expecting 41600 events.
[09:55:56.937]     INFO: 41600 events read in total (3668ms).
[09:55:56.944]     INFO: Test took 4866ms.
[09:55:57.137]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:57.890]     INFO: Expecting 41600 events.
[09:56:02.078]     INFO: 41600 events read in total (3662ms).
[09:56:02.085]     INFO: Test took 4860ms.
[09:56:02.277]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:03.028]     INFO: Expecting 41600 events.
[09:56:07.216]     INFO: 41600 events read in total (3661ms).
[09:56:07.223]     INFO: Test took 4859ms.
[09:56:07.412]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:08.165]     INFO: Expecting 41600 events.
[09:56:12.330]     INFO: 41600 events read in total (3639ms).
[09:56:12.336]     INFO: Test took 4837ms.
[09:56:12.525]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:13.278]     INFO: Expecting 41600 events.
[09:56:17.292]     INFO: 41600 events read in total (3487ms).
[09:56:17.299]     INFO: Test took 4686ms.
[09:56:17.489]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:29.003]     INFO:    ----------------------------------------------------------------------
[09:56:29.003]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:56:29.003]     INFO:    ----------------------------------------------------------------------
[09:56:29.684]     INFO: Expecting 208000 events.
[09:56:48.384]     INFO: 208000 events read in total (18173ms).
[09:56:48.412]     INFO: Test took 19393ms.
[09:56:49.377]     INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:49.955]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[09:56:49.955]     INFO: number of red-efficiency pixels:   528  320  661 1335 1573 1501 1343 1134 1076 1680 1519 1393 1383  723  391  424
[09:56:49.955]     INFO: number of X-ray hits detected:    210256 158730 262178 408271 436639 430021 424411 372258 365104 448656 439125 418044 416230 272600 160334 186130
[09:56:49.955]     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:56:49.955]     INFO: number of Vcal hits detected:  207425 207654 207147 206261 205861 205969 206251 206524 206641 205661 205830 206142 206156 207081 207563 207542
[09:56:49.955]     INFO: Vcal hit fiducial efficiency (%):  99.7 99.8 99.6 99.2 99.1 99.1 99.2 99.3 99.4 99.0 99.1 99.2 99.2 99.6 99.8 99.8
[09:56:49.955]     INFO: Vcal hit overall efficiency (%):  99.7 99.8 99.6 99.2 99.0 99.0 99.2 99.3 99.3 98.9 99.0 99.1 99.1 99.6 99.8 99.8
[09:56:49.955]     INFO: X-ray hit rate [MHz/cm2]:  61.6 46.5 76.8 119.7 128.0 126.0 124.4 109.1 107.0 131.5 128.7 122.5 122.0 79.9 47.0 54.6
[09:56:49.955]     INFO: PixTestHighRate::doXPixelAlive() done
[09:56:50.001]     INFO: PixTest::       pg_setup set to default.
[09:57:36.034]    QUIET: Connection to board 58 closed.