[15:38:48.466]     INFO: *** Welcome to pxar ***
[15:38:48.466]     INFO: *** Today: 2016/05/27
[15:38:48.488]     INFO: *** Version: v1.9.0-805-geb36
[15:38:48.488]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/dacParameters35_C15.dat
[15:38:48.489]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/tbmParameters_C0b.dat
[15:38:48.490]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/defaultMaskFile.dat
[15:38:48.490]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/trimParameters35_C15.dat
[15:38:48.638]     INFO:         clk: 4
[15:38:48.638]     INFO:         ctr: 4
[15:38:48.638]     INFO:         sda: 19
[15:38:48.638]     INFO:         tin: 9
[15:38:48.638]     INFO:         level: 15
[15:38:48.638]     INFO:         triggerdelay: 0
[15:38:48.638]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:38:48.638]     INFO: Log level: INFO
[15:38:48.650]    QUIET: Connection to board DTB_WRPRHI opened.
[15:38:48.653]     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:     
------------------------------------------------------
[15:38:48.656]     INFO: RPC call hashes of host and DTB match: 398089610
[15:38:50.255]     INFO: DUT info: 
[15:38:50.255]     INFO: The DUT currently contains the following objects:
[15:38:50.255]     INFO:  2 TBM Cores tbm08c (2 ON)
[15:38:50.255]     INFO: 	TBM Core alpha (0): 7 registers set
[15:38:50.255]     INFO: 	TBM Core beta  (1): 7 registers set
[15:38:50.255]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:38:50.255]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.256]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:38:50.855]     INFO: PixTestCmd::init()
[15:38:50.860]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mo112/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/phCalibrationFitErr35_C15.dat
[15:38:51.164]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/readbackCal_C15.dat
[15:39:08.663]     INFO:    ----------------------------------------------------------------------
[15:39:08.663]     INFO:    PixTestXray::doPhRun() fParRunSeconds = 60
[15:39:08.663]     INFO:    ----------------------------------------------------------------------
[15:39:09.636]     INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds,  fEventsMax = 6000000
[15:39:21.829]     INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:39:52.777]     INFO: Resuming triggers.
[15:40:04.960]     INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[15:40:36.032]     INFO: Resuming triggers.
[15:40:48.219]     INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[15:41:19.111]     INFO: Resuming triggers.
[15:41:31.299]     INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[15:42:02.178]     INFO: Resuming triggers.
[15:42:13.503]     INFO: data taking finished, elapsed time: 60 seconds.
[15:42:42.489]     INFO: PixTest::       pg_setup set to default.
[15:42:42.523]     INFO: PixTestXray::doPhRun() done
[15:43:19.037]    QUIET: Connection to board 58 closed.
									 
									
									
															
						
							
						
						[15:43:26.250]     INFO: *** Welcome to pxar ***
[15:43:26.250]     INFO: *** Today: 2016/05/27
[15:43:26.272]     INFO: *** Version: v1.9.0-805-geb36
[15:43:26.273]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/dacParameters35_C15.dat
[15:43:26.274]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/tbmParameters_C0b.dat
[15:43:26.274]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/defaultMaskFile.dat
[15:43:26.274]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/trimParameters35_C15.dat
[15:43:26.424]     INFO:         clk: 4
[15:43:26.424]     INFO:         ctr: 4
[15:43:26.424]     INFO:         sda: 19
[15:43:26.424]     INFO:         tin: 9
[15:43:26.424]     INFO:         level: 15
[15:43:26.424]     INFO:         triggerdelay: 0
[15:43:26.424]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:43:26.424]     INFO: Log level: INFO
[15:43:26.435]    QUIET: Connection to board DTB_WRPRHI opened.
[15:43:26.438]     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:     
------------------------------------------------------
[15:43:26.441]     INFO: RPC call hashes of host and DTB match: 398089610
[15:43:28.041]     INFO: DUT info: 
[15:43:28.041]     INFO: The DUT currently contains the following objects:
[15:43:28.041]     INFO:  2 TBM Cores tbm08c (2 ON)
[15:43:28.041]     INFO: 	TBM Core alpha (0): 7 registers set
[15:43:28.041]     INFO: 	TBM Core beta  (1): 7 registers set
[15:43:28.041]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:43:28.041]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.041]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.041]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.041]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.041]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.041]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.041]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.041]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.041]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.042]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.042]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.042]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.042]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.042]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.042]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.042]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:28.649]     INFO: PixTestCmd::init()
[15:43:28.653]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mo112/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/phCalibrationFitErr35_C15.dat
[15:43:28.957]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/readbackCal_C15.dat
[15:43:46.199]     INFO: ######################################################################
[15:43:46.199]     INFO: PixTestXray::doTest()
[15:43:46.199]     INFO: ######################################################################
[15:43:46.200]     INFO:    ----------------------------------------------------------------------
[15:43:46.200]     INFO:    PixTestXray::doPhRun() fParRunSeconds = 60
[15:43:46.200]     INFO:    ----------------------------------------------------------------------
[15:43:47.172]     INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds,  fEventsMax = 6000000
[15:43:54.859]     INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:44:29.819]     INFO: Resuming triggers.
[15:44:37.511]     INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:45:12.360]     INFO: Resuming triggers.
[15:45:23.317]    QUIET: Connection to board 58 closed.
									 
									
									
															
						
							
						
						[15:30:12.954]     INFO: *** Welcome to pxar ***
[15:30:12.954]     INFO: *** Today: 2016/05/27
[15:30:12.977]     INFO: *** Version: v1.9.0-805-geb36
[15:30:12.977]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/dacParameters35_C15.dat
[15:30:12.978]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/tbmParameters_C0b.dat
[15:30:12.978]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/defaultMaskFile.dat
[15:30:12.978]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/trimParameters35_C15.dat
[15:30:13.129]     INFO:         clk: 4
[15:30:13.129]     INFO:         ctr: 4
[15:30:13.129]     INFO:         sda: 19
[15:30:13.129]     INFO:         tin: 9
[15:30:13.129]     INFO:         level: 15
[15:30:13.129]     INFO:         triggerdelay: 0
[15:30:13.129]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:30:13.129]     INFO: Log level: INFO
[15:30:13.140]    QUIET: Connection to board DTB_WRPRHI opened.
[15:30:13.144]     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:     
------------------------------------------------------
[15:30:13.146]     INFO: RPC call hashes of host and DTB match: 398089610
[15:30:14.746]     INFO: DUT info: 
[15:30:14.746]     INFO: The DUT currently contains the following objects:
[15:30:14.746]     INFO:  2 TBM Cores tbm08c (2 ON)
[15:30:14.746]     INFO: 	TBM Core alpha (0): 7 registers set
[15:30:14.746]     INFO: 	TBM Core beta  (1): 7 registers set
[15:30:14.746]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:30:14.746]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:14.746]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:15.349]     INFO: PixTestCmd::init()
[15:30:15.353]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mo112/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/phCalibrationFitErr35_C15.dat
[15:30:15.655]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/readbackCal_C15.dat
[15:30:23.786]     INFO: ######################################################################
[15:30:23.787]     INFO: PixTestAlive::doTest()
[15:30:23.787]     INFO: ######################################################################
[15:30:23.790]     INFO:    ----------------------------------------------------------------------
[15:30:23.790]     INFO:    PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:30:23.790]     INFO:    ----------------------------------------------------------------------
[15:30:24.167]     INFO: Expecting 41600 events.
[15:30:28.439]     INFO: 41600 events read in total (3554ms).
[15:30:28.586]     INFO: Test took 4794ms.
[15:30:28.596]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:28.864]     INFO: PixTestAlive::aliveTest() done
[15:30:28.864]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[15:30:28.888]     INFO:    ----------------------------------------------------------------------
[15:30:28.888]     INFO:    PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:30:28.888]     INFO:    ----------------------------------------------------------------------
[15:30:29.264]     INFO: Expecting 41600 events.
[15:30:32.312]     INFO: 41600 events read in total (2333ms).
[15:30:32.312]     INFO: Test took 3422ms.
[15:30:32.312]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:32.313]     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0
[15:30:32.735]     INFO: PixTestAlive::maskTest() done
[15:30:32.735]     INFO: number of mask-defect pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[15:30:32.753]     INFO:    ----------------------------------------------------------------------
[15:30:32.753]     INFO:    PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:30:32.753]     INFO:    ----------------------------------------------------------------------
[15:30:33.130]     INFO: Expecting 41600 events.
[15:30:37.395]     INFO: 41600 events read in total (3550ms).
[15:30:37.397]     INFO: Test took 4642ms.
[15:30:37.407]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:37.780]     INFO: PixTestAlive::addressDecodingTest() done
[15:30:37.780]     INFO: number of address-decoding pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[15:30:37.780]     INFO: PixTestAlive::doTest() done, duration: 13 seconds
[15:31:06.151]     INFO:    ----------------------------------------------------------------------
[15:31:06.151]     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:31:06.151]     INFO:    ----------------------------------------------------------------------
[15:31:06.290]     INFO: Expecting 768 events.
[15:31:07.422]     INFO: 768 events read in total (417ms).
[15:31:07.422]     INFO: Test took 1265ms.
[15:31:08.226]     INFO: Expecting 41600 events.
[15:31:11.447]     INFO: 41600 events read in total (2695ms).
[15:31:11.452]     INFO: Test took 4024ms.
[15:31:11.539]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:12.245]     INFO: Expecting 41600 events.
[15:31:15.602]     INFO: 41600 events read in total (2830ms).
[15:31:15.606]     INFO: Test took 4027ms.
[15:31:15.691]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:16.399]     INFO: Expecting 41600 events.
[15:31:19.785]     INFO: 41600 events read in total (2860ms).
[15:31:19.789]     INFO: Test took 4057ms.
[15:31:19.874]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:20.582]     INFO: Expecting 41600 events.
[15:31:23.969]     INFO: 41600 events read in total (2861ms).
[15:31:23.973]     INFO: Test took 4057ms.
[15:31:24.058]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:24.765]     INFO: Expecting 41600 events.
[15:31:28.160]     INFO: 41600 events read in total (2868ms).
[15:31:28.164]     INFO: Test took 4064ms.
[15:31:28.249]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:28.956]     INFO: Expecting 41600 events.
[15:31:32.353]     INFO: 41600 events read in total (2871ms).
[15:31:32.357]     INFO: Test took 4066ms.
[15:31:32.441]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:33.149]     INFO: Expecting 41600 events.
[15:31:36.564]     INFO: 41600 events read in total (2888ms).
[15:31:36.568]     INFO: Test took 4085ms.
[15:31:36.654]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:37.362]     INFO: Expecting 41600 events.
[15:31:40.781]     INFO: 41600 events read in total (2892ms).
[15:31:40.785]     INFO: Test took 4088ms.
[15:31:40.868]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:41.575]     INFO: Expecting 41600 events.
[15:31:44.985]     INFO: 41600 events read in total (2883ms).
[15:31:44.989]     INFO: Test took 4078ms.
[15:31:45.074]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:45.782]     INFO: Expecting 41600 events.
[15:31:49.197]     INFO: 41600 events read in total (2888ms).
[15:31:49.201]     INFO: Test took 4084ms.
[15:31:49.285]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:49.994]     INFO: Expecting 41600 events.
[15:31:53.413]     INFO: 41600 events read in total (2892ms).
[15:31:53.417]     INFO: Test took 4089ms.
[15:31:53.502]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:54.210]     INFO: Expecting 41600 events.
[15:31:57.621]     INFO: 41600 events read in total (2884ms).
[15:31:57.625]     INFO: Test took 4080ms.
[15:31:57.709]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:58.417]     INFO: Expecting 41600 events.
[15:32:01.851]     INFO: 41600 events read in total (2907ms).
[15:32:01.855]     INFO: Test took 4103ms.
[15:32:01.939]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:02.647]     INFO: Expecting 41600 events.
[15:32:06.054]     INFO: 41600 events read in total (2880ms).
[15:32:06.058]     INFO: Test took 4076ms.
[15:32:06.143]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:06.850]     INFO: Expecting 41600 events.
[15:32:10.276]     INFO: 41600 events read in total (2899ms).
[15:32:10.280]     INFO: Test took 4095ms.
[15:32:10.365]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:11.073]     INFO: Expecting 41600 events.
[15:32:14.492]     INFO: 41600 events read in total (2892ms).
[15:32:14.496]     INFO: Test took 4088ms.
[15:32:14.581]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:15.289]     INFO: Expecting 41600 events.
[15:32:18.685]     INFO: 41600 events read in total (2869ms).
[15:32:18.689]     INFO: Test took 4064ms.
[15:32:18.773]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:19.481]     INFO: Expecting 41600 events.
[15:32:22.896]     INFO: 41600 events read in total (2888ms).
[15:32:22.900]     INFO: Test took 4084ms.
[15:32:22.985]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:23.693]     INFO: Expecting 41600 events.
[15:32:27.103]     INFO: 41600 events read in total (2883ms).
[15:32:27.107]     INFO: Test took 4079ms.
[15:32:27.193]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:27.903]     INFO: Expecting 41600 events.
[15:32:31.174]     INFO: 41600 events read in total (2744ms).
[15:32:31.178]     INFO: Test took 3940ms.
[15:32:31.263]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:37.503]     INFO:    ----------------------------------------------------------------------
[15:32:37.503]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:32:37.503]     INFO:    ----------------------------------------------------------------------
[15:32:38.184]     INFO: Expecting 208000 events.
[15:32:51.165]     INFO: 208000 events read in total (12454ms).
[15:32:51.178]     INFO: Test took 13659ms.
[15:32:51.589]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:51.969]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[15:32:51.969]     INFO: number of red-efficiency pixels:    60   97  185  239  366  291  300  272  281  287  286  259  378  159  103   63
[15:32:51.969]     INFO: number of X-ray hits detected:    43972 68009 114764 168024 180598 177237 173321 167596 183597 185245 185368 175618 179772 119858 69938 40959
[15:32:51.969]     INFO: number of triggers sent (total per ROC):  208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:32:51.969]     INFO: number of Vcal hits detected:  207938 207902 207807 207750 207608 207695 207682 207713 207711 207699 207700 207728 207596 207832 207891 207934
[15:32:51.969]     INFO: Vcal hit fiducial efficiency (%):  100.0 100.0 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 100.0
[15:32:51.969]     INFO: Vcal hit overall efficiency (%):  100.0 100.0 99.9 99.9 99.8 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 100.0
[15:32:51.969]     INFO: X-ray hit rate [MHz/cm2]:  12.9 19.9 33.6 49.2 52.9 51.9 50.8 49.1 53.8 54.3 54.3 51.5 52.7 35.1 20.5 12.0
[15:32:51.969]     INFO: PixTestHighRate::doXPixelAlive() done
[15:32:52.015]     INFO: PixTest::       pg_setup set to default.
[15:33:31.065]    QUIET: Connection to board 58 closed.
									 
									
									
															
						
							
						
						[15:27:17.296]     INFO: *** Welcome to pxar ***
[15:27:17.296]     INFO: *** Today: 2016/05/27
[15:27:17.318]     INFO: *** Version: v1.9.0-805-geb36
[15:27:17.319]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/dacParameters35_C15.dat
[15:27:17.320]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/tbmParameters_C0b.dat
[15:27:17.320]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/defaultMaskFile.dat
[15:27:17.320]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/trimParameters35_C15.dat
[15:27:17.471]     INFO:         clk: 4
[15:27:17.471]     INFO:         ctr: 4
[15:27:17.471]     INFO:         sda: 19
[15:27:17.471]     INFO:         tin: 9
[15:27:17.471]     INFO:         level: 15
[15:27:17.471]     INFO:         triggerdelay: 0
[15:27:17.471]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:27:17.471]     INFO: Log level: INFO
[15:27:17.485]    QUIET: Connection to board DTB_WRPRHI opened.
[15:27:17.488]     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:     
------------------------------------------------------
[15:27:17.491]     INFO: RPC call hashes of host and DTB match: 398089610
[15:27:19.091]     INFO: DUT info: 
[15:27:19.091]     INFO: The DUT currently contains the following objects:
[15:27:19.091]     INFO:  2 TBM Cores tbm08c (2 ON)
[15:27:19.091]     INFO: 	TBM Core alpha (0): 7 registers set
[15:27:19.091]     INFO: 	TBM Core beta  (1): 7 registers set
[15:27:19.091]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:27:19.091]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.091]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:27:19.680]     INFO: PixTestCmd::init()
[15:27:19.694]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mo112/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/phCalibrationFitErr35_C15.dat
[15:27:20.014]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/readbackCal_C15.dat
[15:27:23.290]     INFO: ######################################################################
[15:27:23.291]     INFO: PixTestAlive::doTest()
[15:27:23.291]     INFO: ######################################################################
[15:27:23.294]     INFO:    ----------------------------------------------------------------------
[15:27:23.294]     INFO:    PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:27:23.294]     INFO:    ----------------------------------------------------------------------
[15:27:23.672]     INFO: Expecting 41600 events.
[15:27:27.933]     INFO: 41600 events read in total (3542ms).
[15:27:28.080]     INFO: Test took 4784ms.
[15:27:28.090]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:28.358]     INFO: PixTestAlive::aliveTest() done
[15:27:28.358]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[15:27:28.381]     INFO:    ----------------------------------------------------------------------
[15:27:28.381]     INFO:    PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:27:28.381]     INFO:    ----------------------------------------------------------------------
[15:27:28.757]     INFO: Expecting 41600 events.
[15:27:31.810]     INFO: 41600 events read in total (2338ms).
[15:27:31.811]     INFO: Test took 3428ms.
[15:27:31.811]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:31.811]     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0
[15:27:32.233]     INFO: PixTestAlive::maskTest() done
[15:27:32.233]     INFO: number of mask-defect pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[15:27:32.251]     INFO:    ----------------------------------------------------------------------
[15:27:32.251]     INFO:    PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:27:32.251]     INFO:    ----------------------------------------------------------------------
[15:27:32.630]     INFO: Expecting 41600 events.
[15:27:36.880]     INFO: 41600 events read in total (3535ms).
[15:27:36.882]     INFO: Test took 4629ms.
[15:27:36.893]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:37.266]     INFO: PixTestAlive::addressDecodingTest() done
[15:27:37.266]     INFO: number of address-decoding pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[15:27:37.266]     INFO: PixTestAlive::doTest() done, duration: 13 seconds
[15:27:39.927]     INFO:    ----------------------------------------------------------------------
[15:27:39.927]     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:27:39.927]     INFO:    ----------------------------------------------------------------------
[15:27:40.066]     INFO: Expecting 768 events.
[15:27:41.198]     INFO: 768 events read in total (417ms).
[15:27:41.198]     INFO: Test took 1265ms.
[15:27:42.002]     INFO: Expecting 41600 events.
[15:27:45.514]     INFO: 41600 events read in total (2985ms).
[15:27:45.518]     INFO: Test took 4314ms.
[15:27:45.652]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:46.373]     INFO: Expecting 41600 events.
[15:27:49.993]     INFO: 41600 events read in total (3093ms).
[15:27:49.997]     INFO: Test took 4289ms.
[15:27:50.128]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:50.853]     INFO: Expecting 41600 events.
[15:27:54.511]     INFO: 41600 events read in total (3131ms).
[15:27:54.516]     INFO: Test took 4328ms.
[15:27:54.645]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:55.369]     INFO: Expecting 41600 events.
[15:27:59.033]     INFO: 41600 events read in total (3137ms).
[15:27:59.038]     INFO: Test took 4334ms.
[15:27:59.168]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:59.893]     INFO: Expecting 41600 events.
[15:28:03.557]     INFO: 41600 events read in total (3138ms).
[15:28:03.562]     INFO: Test took 4335ms.
[15:28:03.692]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:04.416]     INFO: Expecting 41600 events.
[15:28:08.086]     INFO: 41600 events read in total (3144ms).
[15:28:08.091]     INFO: Test took 4340ms.
[15:28:08.222]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:08.946]     INFO: Expecting 41600 events.
[15:28:12.630]     INFO: 41600 events read in total (3157ms).
[15:28:12.634]     INFO: Test took 4353ms.
[15:28:12.766]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:13.490]     INFO: Expecting 41600 events.
[15:28:17.169]     INFO: 41600 events read in total (3152ms).
[15:28:17.174]     INFO: Test took 4349ms.
[15:28:17.303]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:18.027]     INFO: Expecting 41600 events.
[15:28:21.707]     INFO: 41600 events read in total (3153ms).
[15:28:21.712]     INFO: Test took 4351ms.
[15:28:21.842]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:22.566]     INFO: Expecting 41600 events.
[15:28:26.244]     INFO: 41600 events read in total (3152ms).
[15:28:26.249]     INFO: Test took 4348ms.
[15:28:26.379]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:27.104]     INFO: Expecting 41600 events.
[15:28:30.786]     INFO: 41600 events read in total (3156ms).
[15:28:30.791]     INFO: Test took 4352ms.
[15:28:30.922]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:31.647]     INFO: Expecting 41600 events.
[15:28:35.331]     INFO: 41600 events read in total (3158ms).
[15:28:35.336]     INFO: Test took 4354ms.
[15:28:35.468]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:36.192]     INFO: Expecting 41600 events.
[15:28:39.872]     INFO: 41600 events read in total (3153ms).
[15:28:39.877]     INFO: Test took 4350ms.
[15:28:40.006]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:40.730]     INFO: Expecting 41600 events.
[15:28:44.445]     INFO: 41600 events read in total (3188ms).
[15:28:44.450]     INFO: Test took 4385ms.
[15:28:44.578]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:45.303]     INFO: Expecting 41600 events.
[15:28:48.984]     INFO: 41600 events read in total (3154ms).
[15:28:48.988]     INFO: Test took 4350ms.
[15:28:49.117]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:49.842]     INFO: Expecting 41600 events.
[15:28:53.541]     INFO: 41600 events read in total (3172ms).
[15:28:53.546]     INFO: Test took 4369ms.
[15:28:53.673]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:54.398]     INFO: Expecting 41600 events.
[15:28:58.081]     INFO: 41600 events read in total (3156ms).
[15:28:58.086]     INFO: Test took 4353ms.
[15:28:58.216]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:58.941]     INFO: Expecting 41600 events.
[15:29:02.625]     INFO: 41600 events read in total (3158ms).
[15:29:02.630]     INFO: Test took 4354ms.
[15:29:02.759]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:03.484]     INFO: Expecting 41600 events.
[15:29:07.156]     INFO: 41600 events read in total (3146ms).
[15:29:07.160]     INFO: Test took 4342ms.
[15:29:07.289]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:08.014]     INFO: Expecting 41600 events.
[15:29:11.545]     INFO: 41600 events read in total (3005ms).
[15:29:11.550]     INFO: Test took 4201ms.
[15:29:11.675]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:17.247]     INFO:    ----------------------------------------------------------------------
[15:29:17.247]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:29:17.247]     INFO:    ----------------------------------------------------------------------
[15:29:17.928]     INFO: Expecting 208000 events.
[15:29:32.859]     INFO: 208000 events read in total (14404ms).
[15:29:32.877]     INFO: Test took 15613ms.
[15:29:33.494]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:33.954]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[15:29:33.954]     INFO: number of red-efficiency pixels:   110  153  370  576  623  611  548  624  612  652  699  587  601  349  146  112
[15:29:33.954]     INFO: number of X-ray hits detected:    69765 107663 181026 263520 284758 279770 272270 263994 289253 291242 290388 276237 283306 188273 109846 63901
[15:29:33.954]     INFO: number of triggers sent (total per ROC):  208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:29:33.954]     INFO: number of Vcal hits detected:  207884 207845 207586 207366 207307 207320 207394 207311 207313 207259 207198 207348 207329 207625 207849 207885
[15:29:33.954]     INFO: Vcal hit fiducial efficiency (%):  99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.6 99.7 99.7 99.8 99.9 99.9
[15:29:33.954]     INFO: Vcal hit overall efficiency (%):  99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.7 99.7 99.6 99.6 99.7 99.7 99.8 99.9 99.9
[15:29:33.954]     INFO: X-ray hit rate [MHz/cm2]:  20.4 31.6 53.1 77.2 83.5 82.0 79.8 77.4 84.8 85.4 85.1 81.0 83.0 55.2 32.2 18.7
[15:29:33.954]     INFO: PixTestHighRate::doXPixelAlive() done
[15:29:33.999]     INFO: PixTest::       pg_setup set to default.
[15:29:53.715]    QUIET: Connection to board 58 closed.
									 
									
									
															
						
							
						
						[15:23:59.949]     INFO: *** Welcome to pxar ***
[15:23:59.949]     INFO: *** Today: 2016/05/27
[15:23:59.971]     INFO: *** Version: v1.9.0-805-geb36
[15:23:59.971]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/dacParameters35_C15.dat
[15:23:59.972]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/tbmParameters_C0b.dat
[15:23:59.972]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/defaultMaskFile.dat
[15:23:59.972]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/trimParameters35_C15.dat
[15:24:00.122]     INFO:         clk: 4
[15:24:00.123]     INFO:         ctr: 4
[15:24:00.123]     INFO:         sda: 19
[15:24:00.123]     INFO:         tin: 9
[15:24:00.123]     INFO:         level: 15
[15:24:00.123]     INFO:         triggerdelay: 0
[15:24:00.123]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:24:00.123]     INFO: Log level: INFO
[15:24:00.134]    QUIET: Connection to board DTB_WRPRHI opened.
[15:24:00.137]     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:     
------------------------------------------------------
[15:24:00.140]     INFO: RPC call hashes of host and DTB match: 398089610
[15:24:01.739]     INFO: DUT info: 
[15:24:01.739]     INFO: The DUT currently contains the following objects:
[15:24:01.739]     INFO:  2 TBM Cores tbm08c (2 ON)
[15:24:01.739]     INFO: 	TBM Core alpha (0): 7 registers set
[15:24:01.739]     INFO: 	TBM Core beta  (1): 7 registers set
[15:24:01.739]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:24:01.739]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.739]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.739]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.739]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.739]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.739]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.739]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.739]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.739]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.739]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.740]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.740]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.740]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.740]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.740]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:01.740]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:24:02.327]     INFO: PixTestCmd::init()
[15:24:02.337]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mo112/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/phCalibrationFitErr35_C15.dat
[15:24:02.639]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mo112/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo112/readbackCal_C15.dat
[15:24:08.626]     INFO: ######################################################################
[15:24:08.627]     INFO: PixTestAlive::doTest()
[15:24:08.627]     INFO: ######################################################################
[15:24:08.630]     INFO:    ----------------------------------------------------------------------
[15:24:08.630]     INFO:    PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:24:08.630]     INFO:    ----------------------------------------------------------------------
[15:24:09.008]     INFO: Expecting 41600 events.
[15:24:13.265]     INFO: 41600 events read in total (3539ms).
[15:24:13.412]     INFO: Test took 4780ms.
[15:24:13.422]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:13.693]     INFO: PixTestAlive::aliveTest() done
[15:24:13.693]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[15:24:13.715]     INFO:    ----------------------------------------------------------------------
[15:24:13.715]     INFO:    PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:24:13.715]     INFO:    ----------------------------------------------------------------------
[15:24:14.092]     INFO: Expecting 41600 events.
[15:24:17.149]     INFO: 41600 events read in total (2342ms).
[15:24:17.150]     INFO: Test took 3433ms.
[15:24:17.150]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:17.150]     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0
[15:24:17.575]     INFO: PixTestAlive::maskTest() done
[15:24:17.575]     INFO: number of mask-defect pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[15:24:17.594]     INFO:    ----------------------------------------------------------------------
[15:24:17.594]     INFO:    PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:24:17.594]     INFO:    ----------------------------------------------------------------------
[15:24:17.970]     INFO: Expecting 41600 events.
[15:24:22.225]     INFO: 41600 events read in total (3540ms).
[15:24:22.226]     INFO: Test took 4630ms.
[15:24:22.236]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:22.606]     INFO: PixTestAlive::addressDecodingTest() done
[15:24:22.606]     INFO: number of address-decoding pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[15:24:22.607]     INFO: PixTestAlive::doTest() done, duration: 13 seconds
[15:24:41.607]     INFO:    ----------------------------------------------------------------------
[15:24:41.607]     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:24:41.607]     INFO:    ----------------------------------------------------------------------
[15:24:41.747]     INFO: Expecting 768 events.
[15:24:42.880]     INFO: 768 events read in total (418ms).
[15:24:42.880]     INFO: Test took 1267ms.
[15:24:43.683]     INFO: Expecting 41600 events.
[15:24:47.455]     INFO: 41600 events read in total (3245ms).
[15:24:47.461]     INFO: Test took 4576ms.
[15:24:47.626]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:48.361]     INFO: Expecting 41600 events.
[15:24:52.238]     INFO: 41600 events read in total (3350ms).
[15:24:52.244]     INFO: Test took 4548ms.
[15:24:52.404]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:53.139]     INFO: Expecting 41600 events.
[15:24:57.050]     INFO: 41600 events read in total (3384ms).
[15:24:57.056]     INFO: Test took 4582ms.
[15:24:57.217]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:57.953]     INFO: Expecting 41600 events.
[15:25:01.860]     INFO: 41600 events read in total (3380ms).
[15:25:01.866]     INFO: Test took 4578ms.
[15:25:02.028]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:02.764]     INFO: Expecting 41600 events.
[15:25:06.672]     INFO: 41600 events read in total (3381ms).
[15:25:06.678]     INFO: Test took 4579ms.
[15:25:06.838]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:07.573]     INFO: Expecting 41600 events.
[15:25:11.495]     INFO: 41600 events read in total (3395ms).
[15:25:11.501]     INFO: Test took 4593ms.
[15:25:11.663]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:12.401]     INFO: Expecting 41600 events.
[15:25:16.342]     INFO: 41600 events read in total (3414ms).
[15:25:16.349]     INFO: Test took 4615ms.
[15:25:16.510]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:17.246]     INFO: Expecting 41600 events.
[15:25:21.186]     INFO: 41600 events read in total (3413ms).
[15:25:21.193]     INFO: Test took 4612ms.
[15:25:21.358]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:22.097]     INFO: Expecting 41600 events.
[15:25:26.033]     INFO: 41600 events read in total (3409ms).
[15:25:26.040]     INFO: Test took 4608ms.
[15:25:26.204]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:26.941]     INFO: Expecting 41600 events.
[15:25:30.886]     INFO: 41600 events read in total (3419ms).
[15:25:30.893]     INFO: Test took 4617ms.
[15:25:31.055]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:31.792]     INFO: Expecting 41600 events.
[15:25:35.735]     INFO: 41600 events read in total (3416ms).
[15:25:35.742]     INFO: Test took 4615ms.
[15:25:35.903]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:36.640]     INFO: Expecting 41600 events.
[15:25:40.581]     INFO: 41600 events read in total (3414ms).
[15:25:40.588]     INFO: Test took 4613ms.
[15:25:40.751]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:41.488]     INFO: Expecting 41600 events.
[15:25:45.444]     INFO: 41600 events read in total (3429ms).
[15:25:45.450]     INFO: Test took 4627ms.
[15:25:45.610]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:46.347]     INFO: Expecting 41600 events.
[15:25:50.294]     INFO: 41600 events read in total (3420ms).
[15:25:50.301]     INFO: Test took 4619ms.
[15:25:50.464]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:51.201]     INFO: Expecting 41600 events.
[15:25:55.158]     INFO: 41600 events read in total (3430ms).
[15:25:55.165]     INFO: Test took 4629ms.
[15:25:55.326]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:56.063]     INFO: Expecting 41600 events.
[15:26:00.017]     INFO: 41600 events read in total (3427ms).
[15:26:00.024]     INFO: Test took 4626ms.
[15:26:00.184]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:00.922]     INFO: Expecting 41600 events.
[15:26:04.878]     INFO: 41600 events read in total (3429ms).
[15:26:04.884]     INFO: Test took 4627ms.
[15:26:05.046]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:05.783]     INFO: Expecting 41600 events.
[15:26:09.713]     INFO: 41600 events read in total (3403ms).
[15:26:09.720]     INFO: Test took 4602ms.
[15:26:09.883]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:10.621]     INFO: Expecting 41600 events.
[15:26:14.583]     INFO: 41600 events read in total (3435ms).
[15:26:14.589]     INFO: Test took 4633ms.
[15:26:14.753]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:15.490]     INFO: Expecting 41600 events.
[15:26:19.295]     INFO: 41600 events read in total (3278ms).
[15:26:19.301]     INFO: Test took 4477ms.
[15:26:19.466]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:29.879]     INFO:    ----------------------------------------------------------------------
[15:26:29.879]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:26:29.879]     INFO:    ----------------------------------------------------------------------
[15:26:30.561]     INFO: Expecting 208000 events.
[15:26:47.429]     INFO: 208000 events read in total (16341ms).
[15:26:47.452]     INFO: Test took 17557ms.
[15:26:48.266]     INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:48.787]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[15:26:48.787]     INFO: number of red-efficiency pixels:   155  216  555  939 1011 1013  930  998 1067 1048 1050  982 1081  615  238  136
[15:26:48.787]     INFO: number of X-ray hits detected:    90028 138605 232797 340398 366394 359974 349851 341490 371766 374000 374640 353919 363782 243086 141173 82867
[15:26:48.787]     INFO: number of triggers sent (total per ROC):  208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:26:48.787]     INFO: number of Vcal hits detected:  207834 207771 207354 206897 206794 206721 206872 206801 206602 206714 206669 206800 206674 207277 207746 207854
[15:26:48.787]     INFO: Vcal hit fiducial efficiency (%):  99.9 99.9 99.7 99.5 99.5 99.4 99.5 99.5 99.4 99.4 99.4 99.5 99.4 99.7 99.9 99.9
[15:26:48.787]     INFO: Vcal hit overall efficiency (%):  99.9 99.9 99.7 99.5 99.4 99.4 99.5 99.4 99.3 99.4 99.4 99.4 99.4 99.7 99.9 99.9
[15:26:48.787]     INFO: X-ray hit rate [MHz/cm2]:  26.4 40.6 68.2 99.8 107.4 105.5 102.5 100.1 109.0 109.6 109.8 103.7 106.6 71.3 41.4 24.3
[15:26:48.787]     INFO: PixTestHighRate::doXPixelAlive() done
[15:26:48.833]     INFO: PixTest::       pg_setup set to default.
[15:27:03.276]    QUIET: Connection to board 58 closed.