[10:23:15.381]     INFO: *** Welcome to pxar ***
[10:23:15.381]     INFO: *** Today: 2016/06/02
[10:23:15.403]     INFO: *** Version: v1.9.0-805-geb36
[10:23:15.403]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/dacParameters35_C15.dat
[10:23:15.404]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/tbmParameters_C0b.dat
[10:23:15.405]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/defaultMaskFile.dat
[10:23:15.405]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/trimParameters35_C15.dat
[10:23:15.553]     INFO:         clk: 4
[10:23:15.553]     INFO:         ctr: 4
[10:23:15.553]     INFO:         sda: 19
[10:23:15.553]     INFO:         tin: 9
[10:23:15.553]     INFO:         level: 15
[10:23:15.553]     INFO:         triggerdelay: 0
[10:23:15.553]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:23:15.553]     INFO: Log level: INFO
[10:23:15.564]    QUIET: Connection to board DTB_WRPRHI opened.
[10:23:15.567]     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:23:15.570]     INFO: RPC call hashes of host and DTB match: 398089610
[10:23:17.170]     INFO: DUT info: 
[10:23:17.170]     INFO: The DUT currently contains the following objects:
[10:23:17.170]     INFO:  2 TBM Cores tbm08c (2 ON)
[10:23:17.170]     INFO: 	TBM Core alpha (0): 7 registers set
[10:23:17.170]     INFO: 	TBM Core beta  (1): 7 registers set
[10:23:17.170]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:23:17.170]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.170]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:23:17.761]     INFO: PixTestCmd::init()
[10:23:17.773]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/phCalibrationFitErr35_C15.dat
[10:23:18.069]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/readbackCal_C15.dat
[10:23:28.682]     INFO:    ----------------------------------------------------------------------
[10:23:28.682]     INFO:    PixTestXray::doPhRun() fParRunSeconds = 60
[10:23:28.682]     INFO:    ----------------------------------------------------------------------
[10:23:29.654]     INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds,  fEventsMax = 6000000
[10:23:41.294]     INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:24:12.896]     INFO: Resuming triggers.
[10:24:24.538]     INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:24:56.079]     INFO: Resuming triggers.
[10:25:07.721]     INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:25:39.505]     INFO: Resuming triggers.
[10:25:51.152]     INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:26:22.719]     INFO: Resuming triggers.
[10:26:34.369]     INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[10:27:06.462]     INFO: Resuming triggers.
[10:27:08.320]     INFO: data taking finished, elapsed time: 60 seconds.
[10:27:13.908]     INFO: PixTest::       pg_setup set to default.
[10:27:13.941]     INFO: PixTestXray::doPhRun() done
[10:27:44.369]    QUIET: Connection to board 58 closed.
									 
									
									
															
						
							
						
						[10:27:51.763]     INFO: *** Welcome to pxar ***
[10:27:51.763]     INFO: *** Today: 2016/06/02
[10:27:51.785]     INFO: *** Version: v1.9.0-805-geb36
[10:27:51.785]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/dacParameters35_C15.dat
[10:27:51.786]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/tbmParameters_C0b.dat
[10:27:51.786]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/defaultMaskFile.dat
[10:27:51.786]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/trimParameters35_C15.dat
[10:27:51.935]     INFO:         clk: 4
[10:27:51.935]     INFO:         ctr: 4
[10:27:51.935]     INFO:         sda: 19
[10:27:51.935]     INFO:         tin: 9
[10:27:51.935]     INFO:         level: 15
[10:27:51.935]     INFO:         triggerdelay: 0
[10:27:51.935]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:27:51.935]     INFO: Log level: INFO
[10:27:51.946]    QUIET: Connection to board DTB_WRPRHI opened.
[10:27:51.949]     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:27:51.952]     INFO: RPC call hashes of host and DTB match: 398089610
[10:27:53.551]     INFO: DUT info: 
[10:27:53.551]     INFO: The DUT currently contains the following objects:
[10:27:53.551]     INFO:  2 TBM Cores tbm08c (2 ON)
[10:27:53.551]     INFO: 	TBM Core alpha (0): 7 registers set
[10:27:53.551]     INFO: 	TBM Core beta  (1): 7 registers set
[10:27:53.551]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:27:53.551]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:53.552]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:27:54.136]     INFO: PixTestCmd::init()
[10:27:54.143]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/phCalibrationFitErr35_C15.dat
[10:27:54.444]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/readbackCal_C15.dat
[10:28:16.306]     INFO: ######################################################################
[10:28:16.306]     INFO: PixTestXray::doTest()
[10:28:16.306]     INFO: ######################################################################
[10:28:16.306]     INFO:    ----------------------------------------------------------------------
[10:28:16.306]     INFO:    PixTestXray::doPhRun() fParRunSeconds = 60
[10:28:16.306]     INFO:    ----------------------------------------------------------------------
[10:28:17.278]     INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds,  fEventsMax = 6000000
[10:28:24.156]     INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:28:58.698]     INFO: Resuming triggers.
[10:29:05.579]     INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:29:40.200]     INFO: Resuming triggers.
[10:29:47.084]     INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[10:30:21.612]     INFO: Resuming triggers.
[10:30:28.496]     INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[10:31:02.904]     INFO: Resuming triggers.
[10:31:09.787]     INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:31:44.295]     INFO: Resuming triggers.
[10:31:51.180]     INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[10:32:26.547]     INFO: Resuming triggers.
[10:32:33.433]     INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[10:33:09.025]     INFO: Resuming triggers.
[10:33:15.913]     INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[10:33:51.886]     INFO: Resuming triggers.
[10:33:56.894]     INFO: data taking finished, elapsed time: 60 seconds.
[10:34:22.552]     INFO: PixTest::       pg_setup set to default.
[10:34:22.597]     INFO: PixTestXray::doPhRun() done
[10:34:22.597]     INFO: PixTestXray::doTest() done 
[10:35:25.911]    QUIET: Connection to board 58 closed.
									 
									
									
															
						
							
						
						[10:17:10.569]     INFO: *** Welcome to pxar ***
[10:17:10.569]     INFO: *** Today: 2016/06/02
[10:17:10.591]     INFO: *** Version: v1.9.0-805-geb36
[10:17:10.592]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/dacParameters35_C15.dat
[10:17:10.593]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/tbmParameters_C0b.dat
[10:17:10.593]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/defaultMaskFile.dat
[10:17:10.593]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/trimParameters35_C15.dat
[10:17:10.741]     INFO:         clk: 4
[10:17:10.741]     INFO:         ctr: 4
[10:17:10.741]     INFO:         sda: 19
[10:17:10.741]     INFO:         tin: 9
[10:17:10.741]     INFO:         level: 15
[10:17:10.741]     INFO:         triggerdelay: 0
[10:17:10.741]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:17:10.741]     INFO: Log level: INFO
[10:17:10.752]    QUIET: Connection to board DTB_WRPRHI opened.
[10:17:10.755]     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:17:10.758]     INFO: RPC call hashes of host and DTB match: 398089610
[10:17:12.357]     INFO: DUT info: 
[10:17:12.357]     INFO: The DUT currently contains the following objects:
[10:17:12.357]     INFO:  2 TBM Cores tbm08c (2 ON)
[10:17:12.357]     INFO: 	TBM Core alpha (0): 7 registers set
[10:17:12.357]     INFO: 	TBM Core beta  (1): 7 registers set
[10:17:12.357]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:17:12.357]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.357]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.357]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.357]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.357]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.357]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.357]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.357]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.357]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.357]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.357]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.357]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.357]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.357]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.358]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.358]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:12.947]     INFO: PixTestCmd::init()
[10:17:12.977]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/phCalibrationFitErr35_C15.dat
[10:17:13.280]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/readbackCal_C15.dat
[10:17:20.629]     INFO: ######################################################################
[10:17:20.629]     INFO: PixTestAlive::doTest()
[10:17:20.629]     INFO: ######################################################################
[10:17:20.632]     INFO:    ----------------------------------------------------------------------
[10:17:20.632]     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:17:20.632]     INFO:    ----------------------------------------------------------------------
[10:17:21.010]     INFO: Expecting 41600 events.
[10:17:25.269]     INFO: 41600 events read in total (3541ms).
[10:17:25.416]     INFO: Test took 4781ms.
[10:17:25.425]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:25.695]     INFO: PixTestAlive::aliveTest() done
[10:17:25.695]     INFO: number of dead pixels (per ROC):     0    0    0    1    0    0    0    0    0    0    0    0    0    1    0    0
[10:17:25.718]     INFO:    ----------------------------------------------------------------------
[10:17:25.718]     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:17:25.718]     INFO:    ----------------------------------------------------------------------
[10:17:26.095]     INFO: Expecting 41600 events.
[10:17:29.146]     INFO: 41600 events read in total (2336ms).
[10:17:29.147]     INFO: Test took 3427ms.
[10:17:29.147]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:29.147]     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0
[10:17:29.568]     INFO: PixTestAlive::maskTest() done
[10:17:29.568]     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:17:29.587]     INFO:    ----------------------------------------------------------------------
[10:17:29.587]     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:17:29.587]     INFO:    ----------------------------------------------------------------------
[10:17:29.963]     INFO: Expecting 41600 events.
[10:17:34.217]     INFO: 41600 events read in total (3538ms).
[10:17:34.220]     INFO: Test took 4631ms.
[10:17:34.230]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:34.601]     INFO: PixTestAlive::addressDecodingTest() done
[10:17:34.601]     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:17:34.601]     INFO: PixTestAlive::doTest() done, duration: 13 seconds
[10:18:03.946]     INFO:    ----------------------------------------------------------------------
[10:18:03.946]     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:18:03.946]     INFO:    ----------------------------------------------------------------------
[10:18:04.084]     INFO: Expecting 768 events.
[10:18:05.216]     INFO: 768 events read in total (417ms).
[10:18:05.216]     INFO: Test took 1265ms.
[10:18:06.020]     INFO: Expecting 41600 events.
[10:18:09.347]     INFO: 41600 events read in total (2800ms).
[10:18:09.353]     INFO: Test took 4131ms.
[10:18:09.459]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:10.169]     INFO: Expecting 41600 events.
[10:18:13.615]     INFO: 41600 events read in total (2919ms).
[10:18:13.621]     INFO: Test took 4117ms.
[10:18:13.721]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:14.433]     INFO: Expecting 41600 events.
[10:18:17.896]     INFO: 41600 events read in total (2937ms).
[10:18:17.900]     INFO: Test took 4133ms.
[10:18:18.001]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:18.713]     INFO: Expecting 41600 events.
[10:18:22.175]     INFO: 41600 events read in total (2936ms).
[10:18:22.179]     INFO: Test took 4132ms.
[10:18:22.281]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:22.992]     INFO: Expecting 41600 events.
[10:18:26.447]     INFO: 41600 events read in total (2928ms).
[10:18:26.451]     INFO: Test took 4123ms.
[10:18:26.549]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:27.261]     INFO: Expecting 41600 events.
[10:18:30.725]     INFO: 41600 events read in total (2937ms).
[10:18:30.729]     INFO: Test took 4133ms.
[10:18:30.829]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:31.542]     INFO: Expecting 41600 events.
[10:18:35.007]     INFO: 41600 events read in total (2938ms).
[10:18:35.011]     INFO: Test took 4134ms.
[10:18:35.113]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:35.827]     INFO: Expecting 41600 events.
[10:18:39.316]     INFO: 41600 events read in total (2962ms).
[10:18:39.320]     INFO: Test took 4158ms.
[10:18:39.421]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:40.133]     INFO: Expecting 41600 events.
[10:18:43.608]     INFO: 41600 events read in total (2948ms).
[10:18:43.612]     INFO: Test took 4144ms.
[10:18:43.713]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:44.424]     INFO: Expecting 41600 events.
[10:18:47.903]     INFO: 41600 events read in total (2952ms).
[10:18:47.907]     INFO: Test took 4148ms.
[10:18:48.010]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:48.722]     INFO: Expecting 41600 events.
[10:18:52.212]     INFO: 41600 events read in total (2963ms).
[10:18:52.216]     INFO: Test took 4159ms.
[10:18:52.316]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:53.030]     INFO: Expecting 41600 events.
[10:18:56.526]     INFO: 41600 events read in total (2970ms).
[10:18:56.530]     INFO: Test took 4166ms.
[10:18:56.630]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:57.343]     INFO: Expecting 41600 events.
[10:19:00.836]     INFO: 41600 events read in total (2967ms).
[10:19:00.840]     INFO: Test took 4163ms.
[10:19:00.942]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:01.657]     INFO: Expecting 41600 events.
[10:19:05.129]     INFO: 41600 events read in total (2945ms).
[10:19:05.133]     INFO: Test took 4144ms.
[10:19:05.231]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:05.943]     INFO: Expecting 41600 events.
[10:19:09.403]     INFO: 41600 events read in total (2934ms).
[10:19:09.408]     INFO: Test took 4130ms.
[10:19:09.507]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:10.219]     INFO: Expecting 41600 events.
[10:19:13.690]     INFO: 41600 events read in total (2945ms).
[10:19:13.694]     INFO: Test took 4140ms.
[10:19:13.793]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:14.505]     INFO: Expecting 41600 events.
[10:19:17.972]     INFO: 41600 events read in total (2941ms).
[10:19:17.976]     INFO: Test took 4136ms.
[10:19:18.075]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:18.786]     INFO: Expecting 41600 events.
[10:19:22.252]     INFO: 41600 events read in total (2939ms).
[10:19:22.256]     INFO: Test took 4135ms.
[10:19:22.355]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:23.066]     INFO: Expecting 41600 events.
[10:19:26.510]     INFO: 41600 events read in total (2917ms).
[10:19:26.514]     INFO: Test took 4113ms.
[10:19:26.615]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:27.326]     INFO: Expecting 41600 events.
[10:19:30.580]     INFO: 41600 events read in total (2727ms).
[10:19:30.584]     INFO: Test took 3923ms.
[10:19:30.680]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:36.194]     INFO:    ----------------------------------------------------------------------
[10:19:36.194]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:19:36.194]     INFO:    ----------------------------------------------------------------------
[10:19:36.875]     INFO: Expecting 208000 events.
[10:19:50.276]     INFO: 208000 events read in total (12874ms).
[10:19:50.290]     INFO: Test took 14080ms.
[10:19:50.747]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:51.148]     INFO: number of dead pixels (per ROC):     0    0    0    1    0    0    0    0    0    0    0    0    0    1    0    0
[10:19:51.148]     INFO: number of red-efficiency pixels:   144  120  187  294  378  369  354  296  378  389  357  316  344  176  106  159
[10:19:51.148]     INFO: number of X-ray hits detected:    107005 77578 128991 189350 200645 201975 195377 173506 193136 202351 203854 197128 197893 129849 76477 92450
[10:19:51.148]     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:19:51.148]     INFO: number of Vcal hits detected:  207852 207878 207802 207640 207601 207598 207625 207688 207599 207591 207614 207665 207636 207764 207890 207838
[10:19:51.148]     INFO: Vcal hit fiducial efficiency (%):  99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[10:19:51.148]     INFO: Vcal hit overall efficiency (%):  99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[10:19:51.148]     INFO: X-ray hit rate [MHz/cm2]:  31.4 22.7 37.8 55.5 58.8 59.2 57.3 50.9 56.6 59.3 59.8 57.8 58.0 38.1 22.4 27.1
[10:19:51.148]     INFO: PixTestHighRate::doXPixelAlive() done
[10:19:51.192]     INFO: PixTest::       pg_setup set to default.
[10:20:14.875]    QUIET: Connection to board 58 closed.
									 
									
									
															
						
							
						
						[10:11:40.325]     INFO: *** Welcome to pxar ***
[10:11:40.325]     INFO: *** Today: 2016/06/02
[10:11:40.348]     INFO: *** Version: v1.9.0-805-geb36
[10:11:40.348]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/dacParameters35_C15.dat
[10:11:40.349]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/tbmParameters_C0b.dat
[10:11:40.349]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/defaultMaskFile.dat
[10:11:40.349]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/trimParameters35_C15.dat
[10:11:40.498]     INFO:         clk: 4
[10:11:40.498]     INFO:         ctr: 4
[10:11:40.498]     INFO:         sda: 19
[10:11:40.498]     INFO:         tin: 9
[10:11:40.498]     INFO:         level: 15
[10:11:40.498]     INFO:         triggerdelay: 0
[10:11:40.498]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:11:40.498]     INFO: Log level: INFO
[10:11:40.509]    QUIET: Connection to board DTB_WRPRHI opened.
[10:11:40.513]     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:40.515]     INFO: RPC call hashes of host and DTB match: 398089610
[10:11:42.115]     INFO: DUT info: 
[10:11:42.115]     INFO: The DUT currently contains the following objects:
[10:11:42.115]     INFO:  2 TBM Cores tbm08c (2 ON)
[10:11:42.115]     INFO: 	TBM Core alpha (0): 7 registers set
[10:11:42.115]     INFO: 	TBM Core beta  (1): 7 registers set
[10:11:42.115]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:11:42.115]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.115]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.116]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.116]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.116]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.116]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.116]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.116]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.116]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.116]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.116]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.116]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.116]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.116]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.116]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.116]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:42.708]     INFO: PixTestCmd::init()
[10:11:42.732]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/phCalibrationFitErr35_C15.dat
[10:11:43.035]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/readbackCal_C15.dat
[10:11:50.869]     INFO: ######################################################################
[10:11:50.869]     INFO: PixTestAlive::doTest()
[10:11:50.869]     INFO: ######################################################################
[10:11:50.872]     INFO:    ----------------------------------------------------------------------
[10:11:50.872]     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:11:50.872]     INFO:    ----------------------------------------------------------------------
[10:11:51.251]     INFO: Expecting 41600 events.
[10:11:55.511]     INFO: 41600 events read in total (3542ms).
[10:11:55.658]     INFO: Test took 4783ms.
[10:11:55.667]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:55.943]     INFO: PixTestAlive::aliveTest() done
[10:11:55.943]     INFO: number of dead pixels (per ROC):     0    0    0    1    0    0    0    0    0    0    0    0    0    1    0    0
[10:11:55.965]     INFO:    ----------------------------------------------------------------------
[10:11:55.965]     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:11:55.965]     INFO:    ----------------------------------------------------------------------
[10:11:56.341]     INFO: Expecting 41600 events.
[10:11:59.396]     INFO: 41600 events read in total (2339ms).
[10:11:59.396]     INFO: Test took 3429ms.
[10:11:59.396]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:59.397]     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0
[10:11:59.821]     INFO: PixTestAlive::maskTest() done
[10:11:59.821]     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:11:59.841]     INFO:    ----------------------------------------------------------------------
[10:11:59.841]     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:11:59.841]     INFO:    ----------------------------------------------------------------------
[10:12:00.218]     INFO: Expecting 41600 events.
[10:12:04.472]     INFO: 41600 events read in total (3538ms).
[10:12:04.474]     INFO: Test took 4631ms.
[10:12:04.485]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:04.856]     INFO: PixTestAlive::addressDecodingTest() done
[10:12:04.856]     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:12:04.856]     INFO: PixTestAlive::doTest() done, duration: 13 seconds
[10:12:44.066]     INFO:    ----------------------------------------------------------------------
[10:12:44.066]     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:12:44.066]     INFO:    ----------------------------------------------------------------------
[10:12:44.205]     INFO: Expecting 768 events.
[10:12:45.337]     INFO: 768 events read in total (417ms).
[10:12:45.337]     INFO: Test took 1266ms.
[10:12:46.141]     INFO: Expecting 41600 events.
[10:12:49.754]     INFO: 41600 events read in total (3086ms).
[10:12:49.759]     INFO: Test took 4416ms.
[10:12:49.900]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:50.625]     INFO: Expecting 41600 events.
[10:12:54.339]     INFO: 41600 events read in total (3187ms).
[10:12:54.344]     INFO: Test took 4383ms.
[10:12:54.479]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:55.207]     INFO: Expecting 41600 events.
[10:12:58.932]     INFO: 41600 events read in total (3198ms).
[10:12:58.937]     INFO: Test took 4394ms.
[10:12:59.071]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:59.800]     INFO: Expecting 41600 events.
[10:13:03.531]     INFO: 41600 events read in total (3204ms).
[10:13:03.536]     INFO: Test took 4401ms.
[10:13:03.670]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:04.398]     INFO: Expecting 41600 events.
[10:13:08.149]     INFO: 41600 events read in total (3224ms).
[10:13:08.154]     INFO: Test took 4420ms.
[10:13:08.291]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:09.020]     INFO: Expecting 41600 events.
[10:13:12.769]     INFO: 41600 events read in total (3223ms).
[10:13:12.774]     INFO: Test took 4419ms.
[10:13:12.911]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:13.639]     INFO: Expecting 41600 events.
[10:13:17.409]     INFO: 41600 events read in total (3243ms).
[10:13:17.414]     INFO: Test took 4439ms.
[10:13:17.549]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:18.279]     INFO: Expecting 41600 events.
[10:13:22.049]     INFO: 41600 events read in total (3244ms).
[10:13:22.054]     INFO: Test took 4440ms.
[10:13:22.188]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:22.918]     INFO: Expecting 41600 events.
[10:13:26.675]     INFO: 41600 events read in total (3231ms).
[10:13:26.680]     INFO: Test took 4427ms.
[10:13:26.818]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:27.547]     INFO: Expecting 41600 events.
[10:13:31.318]     INFO: 41600 events read in total (3244ms).
[10:13:31.323]     INFO: Test took 4441ms.
[10:13:31.460]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:32.189]     INFO: Expecting 41600 events.
[10:13:36.043]     INFO: 41600 events read in total (3328ms).
[10:13:36.048]     INFO: Test took 4524ms.
[10:13:36.186]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:36.919]     INFO: Expecting 41600 events.
[10:13:40.719]     INFO: 41600 events read in total (3274ms).
[10:13:40.724]     INFO: Test took 4472ms.
[10:13:40.859]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:41.588]     INFO: Expecting 41600 events.
[10:13:45.406]     INFO: 41600 events read in total (3291ms).
[10:13:45.415]     INFO: Test took 4493ms.
[10:13:45.568]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:46.309]     INFO: Expecting 41600 events.
[10:13:50.130]     INFO: 41600 events read in total (3294ms).
[10:13:50.135]     INFO: Test took 4500ms.
[10:13:50.270]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:50.999]     INFO: Expecting 41600 events.
[10:13:54.766]     INFO: 41600 events read in total (3241ms).
[10:13:54.772]     INFO: Test took 4439ms.
[10:13:54.910]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:55.640]     INFO: Expecting 41600 events.
[10:13:59.428]     INFO: 41600 events read in total (3261ms).
[10:13:59.433]     INFO: Test took 4458ms.
[10:13:59.569]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:00.299]     INFO: Expecting 41600 events.
[10:14:04.083]     INFO: 41600 events read in total (3257ms).
[10:14:04.088]     INFO: Test took 4455ms.
[10:14:04.227]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:04.957]     INFO: Expecting 41600 events.
[10:14:08.712]     INFO: 41600 events read in total (3228ms).
[10:14:08.717]     INFO: Test took 4426ms.
[10:14:08.854]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:09.584]     INFO: Expecting 41600 events.
[10:14:13.311]     INFO: 41600 events read in total (3200ms).
[10:14:13.317]     INFO: Test took 4398ms.
[10:14:13.452]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:14.181]     INFO: Expecting 41600 events.
[10:14:17.721]     INFO: 41600 events read in total (3013ms).
[10:14:17.726]     INFO: Test took 4210ms.
[10:14:17.860]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:00.210]     INFO:    ----------------------------------------------------------------------
[10:15:00.210]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:15:00.210]     INFO:    ----------------------------------------------------------------------
[10:15:00.891]     INFO: Expecting 208000 events.
[10:15:16.351]     INFO: 208000 events read in total (14933ms).
[10:15:16.370]     INFO: Test took 16145ms.
[10:15:17.033]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:17.514]     INFO: number of dead pixels (per ROC):     0    0    0    1    0    0    0    0    0    0    0    0    0    1    0    0
[10:15:17.514]     INFO: number of red-efficiency pixels:   260  171  386  621  670  723  660  558  577  629  735  666  640  339  182  211
[10:15:17.515]     INFO: number of X-ray hits detected:    157112 113529 189030 279339 294556 297008 286465 253296 283222 296185 299659 290400 290639 190746 112140 136161
[10:15:17.515]     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:15:17.515]     INFO: number of Vcal hits detected:  207727 207824 207561 207266 207246 207187 207264 207393 207372 207280 207170 207225 207281 207577 207807 207771
[10:15:17.515]     INFO: Vcal hit fiducial efficiency (%):  99.9 99.9 99.8 99.7 99.7 99.6 99.7 99.7 99.7 99.7 99.6 99.7 99.7 99.8 99.9 99.9
[10:15:17.515]     INFO: Vcal hit overall efficiency (%):  99.9 99.9 99.8 99.6 99.6 99.6 99.6 99.7 99.7 99.7 99.6 99.6 99.7 99.8 99.9 99.9
[10:15:17.515]     INFO: X-ray hit rate [MHz/cm2]:  46.1 33.3 55.4 81.9 86.3 87.1 84.0 74.2 83.0 86.8 87.8 85.1 85.2 55.9 32.9 39.9
[10:15:17.515]     INFO: PixTestHighRate::doXPixelAlive() done
[10:15:17.560]     INFO: PixTest::       pg_setup set to default.
[10:17:01.061]    QUIET: Connection to board 58 closed.
									 
									
									
															
						
							
						
						[10:08:20.004]     INFO: *** Welcome to pxar ***
[10:08:20.004]     INFO: *** Today: 2016/06/02
[10:08:20.026]     INFO: *** Version: v1.9.0-805-geb36
[10:08:20.026]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/dacParameters35_C15.dat
[10:08:20.027]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/tbmParameters_C0b.dat
[10:08:20.028]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/defaultMaskFile.dat
[10:08:20.028]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/trimParameters35_C15.dat
[10:08:20.176]     INFO:         clk: 4
[10:08:20.176]     INFO:         ctr: 4
[10:08:20.176]     INFO:         sda: 19
[10:08:20.176]     INFO:         tin: 9
[10:08:20.176]     INFO:         level: 15
[10:08:20.176]     INFO:         triggerdelay: 0
[10:08:20.176]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:08:20.176]     INFO: Log level: INFO
[10:08:20.189]    QUIET: Connection to board DTB_WRPRHI opened.
[10:08:20.192]     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:08:20.194]     INFO: RPC call hashes of host and DTB match: 398089610
[10:08:21.793]     INFO: DUT info: 
[10:08:21.794]     INFO: The DUT currently contains the following objects:
[10:08:21.794]     INFO:  2 TBM Cores tbm08c (2 ON)
[10:08:21.794]     INFO: 	TBM Core alpha (0): 7 registers set
[10:08:21.794]     INFO: 	TBM Core beta  (1): 7 registers set
[10:08:21.794]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:08:21.794]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:21.794]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:22.389]     INFO: PixTestCmd::init()
[10:08:22.407]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/phCalibrationFitErr35_C15.dat
[10:08:22.709]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/myqnk/readbackCal_C15.dat
[10:08:28.029]     INFO: ######################################################################
[10:08:28.029]     INFO: PixTestAlive::doTest()
[10:08:28.029]     INFO: ######################################################################
[10:08:28.032]     INFO:    ----------------------------------------------------------------------
[10:08:28.032]     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:08:28.032]     INFO:    ----------------------------------------------------------------------
[10:08:28.411]     INFO: Expecting 41600 events.
[10:08:32.673]     INFO: 41600 events read in total (3544ms).
[10:08:32.820]     INFO: Test took 4786ms.
[10:08:32.830]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:08:33.104]     INFO: PixTestAlive::aliveTest() done
[10:08:33.104]     INFO: number of dead pixels (per ROC):     0    0    0    1    0    0    0    0    0    0    0    0    0    1    0    0
[10:08:33.127]     INFO:    ----------------------------------------------------------------------
[10:08:33.127]     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:08:33.127]     INFO:    ----------------------------------------------------------------------
[10:08:33.504]     INFO: Expecting 41600 events.
[10:08:36.545]     INFO: 41600 events read in total (2326ms).
[10:08:36.546]     INFO: Test took 3417ms.
[10:08:36.546]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:08:36.547]     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0
[10:08:36.968]     INFO: PixTestAlive::maskTest() done
[10:08:36.968]     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:08:36.986]     INFO:    ----------------------------------------------------------------------
[10:08:36.986]     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:08:36.986]     INFO:    ----------------------------------------------------------------------
[10:08:37.362]     INFO: Expecting 41600 events.
[10:08:41.608]     INFO: 41600 events read in total (3531ms).
[10:08:41.609]     INFO: Test took 4621ms.
[10:08:41.619]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:08:41.991]     INFO: PixTestAlive::addressDecodingTest() done
[10:08:41.991]     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:08:41.991]     INFO: PixTestAlive::doTest() done, duration: 13 seconds
[10:08:45.674]     INFO:    ----------------------------------------------------------------------
[10:08:45.674]     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:08:45.674]     INFO:    ----------------------------------------------------------------------
[10:08:45.813]     INFO: Expecting 768 events.
[10:08:46.945]     INFO: 768 events read in total (417ms).
[10:08:46.945]     INFO: Test took 1266ms.
[10:08:47.749]     INFO: Expecting 41600 events.
[10:08:51.663]     INFO: 41600 events read in total (3388ms).
[10:08:51.670]     INFO: Test took 4719ms.
[10:08:51.862]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:08:52.605]     INFO: Expecting 41600 events.
[10:08:56.637]     INFO: 41600 events read in total (3505ms).
[10:08:56.644]     INFO: Test took 4704ms.
[10:08:56.830]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:08:57.580]     INFO: Expecting 41600 events.
[10:09:01.632]     INFO: 41600 events read in total (3525ms).
[10:09:01.638]     INFO: Test took 4723ms.
[10:09:01.820]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:09:02.567]     INFO: Expecting 41600 events.
[10:09:06.629]     INFO: 41600 events read in total (3535ms).
[10:09:06.636]     INFO: Test took 4734ms.
[10:09:06.818]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:09:07.566]     INFO: Expecting 41600 events.
[10:09:11.634]     INFO: 41600 events read in total (3541ms).
[10:09:11.640]     INFO: Test took 4738ms.
[10:09:11.818]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:09:12.565]     INFO: Expecting 41600 events.
[10:09:16.643]     INFO: 41600 events read in total (3551ms).
[10:09:16.649]     INFO: Test took 4749ms.
[10:09:16.834]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:09:17.582]     INFO: Expecting 41600 events.
[10:09:21.684]     INFO: 41600 events read in total (3576ms).
[10:09:21.690]     INFO: Test took 4773ms.
[10:09:21.873]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:09:22.623]     INFO: Expecting 41600 events.
[10:09:26.728]     INFO: 41600 events read in total (3578ms).
[10:09:26.734]     INFO: Test took 4776ms.
[10:09:26.920]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:09:27.667]     INFO: Expecting 41600 events.
[10:09:31.765]     INFO: 41600 events read in total (3572ms).
[10:09:31.772]     INFO: Test took 4770ms.
[10:09:31.955]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:09:32.703]     INFO: Expecting 41600 events.
[10:09:36.798]     INFO: 41600 events read in total (3569ms).
[10:09:36.805]     INFO: Test took 4767ms.
[10:09:36.990]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:09:37.739]     INFO: Expecting 41600 events.
[10:09:41.828]     INFO: 41600 events read in total (3562ms).
[10:09:41.834]     INFO: Test took 4760ms.
[10:09:42.017]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:09:42.764]     INFO: Expecting 41600 events.
[10:09:46.875]     INFO: 41600 events read in total (3584ms).
[10:09:46.881]     INFO: Test took 4782ms.
[10:09:47.065]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:09:47.813]     INFO: Expecting 41600 events.
[10:09:51.939]     INFO: 41600 events read in total (3599ms).
[10:09:51.945]     INFO: Test took 4797ms.
[10:09:52.128]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:09:52.877]     INFO: Expecting 41600 events.
[10:09:56.975]     INFO: 41600 events read in total (3571ms).
[10:09:56.982]     INFO: Test took 4770ms.
[10:09:57.166]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:09:57.915]     INFO: Expecting 41600 events.
[10:10:02.010]     INFO: 41600 events read in total (3569ms).
[10:10:02.016]     INFO: Test took 4766ms.
[10:10:02.204]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:10:02.954]     INFO: Expecting 41600 events.
[10:10:07.058]     INFO: 41600 events read in total (3578ms).
[10:10:07.065]     INFO: Test took 4778ms.
[10:10:07.245]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:10:07.993]     INFO: Expecting 41600 events.
[10:10:12.092]     INFO: 41600 events read in total (3573ms).
[10:10:12.098]     INFO: Test took 4770ms.
[10:10:12.283]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:10:13.043]     INFO: Expecting 41600 events.
[10:10:17.141]     INFO: 41600 events read in total (3572ms).
[10:10:17.148]     INFO: Test took 4781ms.
[10:10:17.332]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:10:18.080]     INFO: Expecting 41600 events.
[10:10:22.145]     INFO: 41600 events read in total (3538ms).
[10:10:22.151]     INFO: Test took 4736ms.
[10:10:22.333]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:10:23.080]     INFO: Expecting 41600 events.
[10:10:26.935]     INFO: 41600 events read in total (3328ms).
[10:10:26.941]     INFO: Test took 4526ms.
[10:10:27.124]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:10:37.298]     INFO:    ----------------------------------------------------------------------
[10:10:37.298]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:10:37.298]     INFO:    ----------------------------------------------------------------------
[10:10:37.980]     INFO: Expecting 208000 events.
[10:10:56.016]     INFO: 208000 events read in total (17509ms).
[10:10:56.043]     INFO: Test took 18729ms.
[10:10:56.950]     INFO: Fetched DAQ statistics. Counters are being reset now.
[10:10:57.519]     INFO: number of dead pixels (per ROC):     0    0    0    1    0    0    0    0    0    0    0    0    0    1    0    0
[10:10:57.519]     INFO: number of red-efficiency pixels:   349  285  697 1134 1267 1280 1205  986 1106 1281 1273 1215 1161  660  233  362
[10:10:57.519]     INFO: number of X-ray hits detected:    214348 154602 257309 377720 400689 403702 388745 344337 385001 402314 408767 393133 395460 258966 153739 185072
[10:10:57.519]     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:10:57.519]     INFO: number of Vcal hits detected:  207632 207688 207108 206549 206416 206373 206466 206777 206636 206339 206386 206426 206532 207145 207746 207615
[10:10:57.519]     INFO: Vcal hit fiducial efficiency (%):  99.8 99.9 99.6 99.4 99.3 99.3 99.3 99.5 99.4 99.3 99.3 99.3 99.4 99.6 99.9 99.8
[10:10:57.519]     INFO: Vcal hit overall efficiency (%):  99.8 99.9 99.6 99.3 99.2 99.2 99.3 99.4 99.3 99.2 99.2 99.2 99.3 99.6 99.9 99.8
[10:10:57.519]     INFO: X-ray hit rate [MHz/cm2]:  62.8 45.3 75.4 110.7 117.4 118.3 113.9 100.9 112.8 117.9 119.8 115.2 115.9 75.9 45.1 54.2
[10:10:57.519]     INFO: PixTestHighRate::doXPixelAlive() done
[10:10:57.564]     INFO: PixTest::       pg_setup set to default.
[10:11:34.760]    QUIET: Connection to board 58 closed.