[14:11:14.968] INFO: *** Welcome to pxar ***
[14:11:14.968] INFO: *** Today: 2016/05/13
[14:11:14.990] INFO: *** Version: v1.9.0-805-geb36
[14:11:14.990] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/dacParameters35_C15.dat
[14:11:14.991] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/tbmParameters_C0b.dat
[14:11:14.991] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/defaultMaskFile.dat
[14:11:14.991] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/trimParameters35_C15.dat
[14:11:15.143] INFO: clk: 4
[14:11:15.143] INFO: ctr: 4
[14:11:15.143] INFO: sda: 19
[14:11:15.143] INFO: tin: 9
[14:11:15.143] INFO: level: 15
[14:11:15.143] INFO: triggerdelay: 0
[14:11:15.143] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[14:11:15.143] INFO: Log level: INFO
[14:11:15.155] QUIET: Connection to board DTB_WRPRHI opened.
[14:11:15.158] 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:
------------------------------------------------------
[14:11:15.161] INFO: RPC call hashes of host and DTB match: 398089610
[14:11:16.760] INFO: DUT info:
[14:11:16.760] INFO: The DUT currently contains the following objects:
[14:11:16.760] INFO: 2 TBM Cores tbm08c (2 ON)
[14:11:16.760] INFO: TBM Core alpha (0): 7 registers set
[14:11:16.760] INFO: TBM Core beta (1): 7 registers set
[14:11:16.760] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:11:16.760] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.760] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.760] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.760] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.761] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.761] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.761] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.761] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.761] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.761] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.761] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.761] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.761] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.761] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.761] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:16.761] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:17.351] INFO: PixTestCmd::init()
[14:11:17.362] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mm323/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/phCalibrationFitErr35_C15.dat
[14:11:17.669] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/readbackCal_C15.dat
[14:11:45.689] INFO: ----------------------------------------------------------------------
[14:11:45.689] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[14:11:45.689] INFO: ----------------------------------------------------------------------
[14:11:46.661] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[14:11:59.047] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[14:12:29.906] INFO: Resuming triggers.
[14:12:42.291] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[14:13:13.226] INFO: Resuming triggers.
[14:13:25.613] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[14:13:56.378] INFO: Resuming triggers.
[14:14:08.769] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[14:14:39.243] INFO: Resuming triggers.
[14:14:49.770] INFO: data taking finished, elapsed time: 60 seconds.
[14:15:15.542] INFO: PixTest:: pg_setup set to default.
[14:15:15.577] INFO: PixTestXray::doPhRun() done
[14:15:42.321] QUIET: Connection to board 58 closed.
[14:04:17.334] INFO: *** Welcome to pxar ***
[14:04:17.334] INFO: *** Today: 2016/05/13
[14:04:17.357] INFO: *** Version: v1.9.0-805-geb36
[14:04:17.358] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/dacParameters35_C15.dat
[14:04:17.359] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/tbmParameters_C0b.dat
[14:04:17.359] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/defaultMaskFile.dat
[14:04:17.359] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/trimParameters35_C15.dat
[14:04:17.511] INFO: clk: 4
[14:04:17.511] INFO: ctr: 4
[14:04:17.511] INFO: sda: 19
[14:04:17.511] INFO: tin: 9
[14:04:17.511] INFO: level: 15
[14:04:17.511] INFO: triggerdelay: 0
[14:04:17.511] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[14:04:17.511] INFO: Log level: INFO
[14:04:17.523] QUIET: Connection to board DTB_WRPRHI opened.
[14:04:17.526] 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:
------------------------------------------------------
[14:04:17.529] INFO: RPC call hashes of host and DTB match: 398089610
[14:04:19.129] INFO: DUT info:
[14:04:19.129] INFO: The DUT currently contains the following objects:
[14:04:19.129] INFO: 2 TBM Cores tbm08c (2 ON)
[14:04:19.129] INFO: TBM Core alpha (0): 7 registers set
[14:04:19.129] INFO: TBM Core beta (1): 7 registers set
[14:04:19.129] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:04:19.129] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.129] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:19.718] INFO: PixTestCmd::init()
[14:04:19.729] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mm323/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/phCalibrationFitErr35_C15.dat
[14:04:20.035] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/readbackCal_C15.dat
[14:04:42.881] INFO: ----------------------------------------------------------------------
[14:04:42.881] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[14:04:42.881] INFO: ----------------------------------------------------------------------
[14:04:43.853] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[14:04:51.232] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[14:05:26.505] INFO: Resuming triggers.
[14:05:33.889] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[14:06:08.810] INFO: Resuming triggers.
[14:06:16.193] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[14:06:51.227] INFO: Resuming triggers.
[14:06:58.615] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[14:07:33.583] INFO: Resuming triggers.
[14:07:40.971] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[14:08:16.126] INFO: Resuming triggers.
[14:08:23.510] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[14:08:58.635] INFO: Resuming triggers.
[14:09:06.020] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[14:09:41.025] INFO: Resuming triggers.
[14:09:48.412] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[14:10:23.520] INFO: Resuming triggers.
[14:10:24.524] INFO: data taking finished, elapsed time: 60 seconds.
[14:10:29.656] INFO: PixTest:: pg_setup set to default.
[14:10:29.692] INFO: PixTestXray::doPhRun() done
[14:11:07.945] QUIET: Connection to board 58 closed.
[13:56:12.440] INFO: *** Welcome to pxar ***
[13:56:12.440] INFO: *** Today: 2016/05/13
[13:56:12.463] INFO: *** Version: v1.9.0-805-geb36
[13:56:12.463] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/dacParameters35_C15.dat
[13:56:12.464] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/tbmParameters_C0b.dat
[13:56:12.464] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/defaultMaskFile.dat
[13:56:12.464] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/trimParameters35_C15.dat
[13:56:12.613] INFO: clk: 4
[13:56:12.613] INFO: ctr: 4
[13:56:12.613] INFO: sda: 19
[13:56:12.613] INFO: tin: 9
[13:56:12.613] INFO: level: 15
[13:56:12.613] INFO: triggerdelay: 0
[13:56:12.613] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[13:56:12.613] INFO: Log level: INFO
[13:56:12.624] QUIET: Connection to board DTB_WRPRHI opened.
[13:56:12.627] 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:
------------------------------------------------------
[13:56:12.630] INFO: RPC call hashes of host and DTB match: 398089610
[13:56:14.230] INFO: DUT info:
[13:56:14.230] INFO: The DUT currently contains the following objects:
[13:56:14.230] INFO: 2 TBM Cores tbm08c (2 ON)
[13:56:14.230] INFO: TBM Core alpha (0): 7 registers set
[13:56:14.230] INFO: TBM Core beta (1): 7 registers set
[13:56:14.230] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:56:14.230] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.230] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:14.824] INFO: PixTestCmd::init()
[13:56:14.832] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mm323/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/phCalibrationFitErr35_C15.dat
[13:56:15.139] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/readbackCal_C15.dat
[13:56:29.596] INFO: ######################################################################
[13:56:29.596] INFO: PixTestAlive::doTest()
[13:56:29.596] INFO: ######################################################################
[13:56:29.600] INFO: ----------------------------------------------------------------------
[13:56:29.600] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:56:29.600] INFO: ----------------------------------------------------------------------
[13:56:29.978] INFO: Expecting 41600 events.
[13:56:34.230] INFO: 41600 events read in total (3534ms).
[13:56:34.379] INFO: Test took 4777ms.
[13:56:34.389] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:34.667] INFO: PixTestAlive::aliveTest() done
[13:56:34.667] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 2 1 0 0 0 0
[13:56:34.689] INFO: ----------------------------------------------------------------------
[13:56:34.689] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:56:34.689] INFO: ----------------------------------------------------------------------
[13:56:35.066] INFO: Expecting 41600 events.
[13:56:38.109] INFO: 41600 events read in total (2328ms).
[13:56:38.110] INFO: Test took 3419ms.
[13:56:38.110] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:38.110] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:56:38.533] INFO: PixTestAlive::maskTest() done
[13:56:38.533] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:56:38.551] INFO: ----------------------------------------------------------------------
[13:56:38.551] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:56:38.551] INFO: ----------------------------------------------------------------------
[13:56:38.926] INFO: Expecting 41600 events.
[13:56:43.172] INFO: 41600 events read in total (3530ms).
[13:56:43.174] INFO: Test took 4621ms.
[13:56:43.183] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:43.556] INFO: PixTestAlive::addressDecodingTest() done
[13:56:43.556] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:56:43.556] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[13:57:12.361] INFO: ----------------------------------------------------------------------
[13:57:12.361] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:57:12.361] INFO: ----------------------------------------------------------------------
[13:57:12.499] INFO: Expecting 768 events.
[13:57:13.632] INFO: 768 events read in total (418ms).
[13:57:13.632] INFO: Test took 1266ms.
[13:57:14.436] INFO: Expecting 41600 events.
[13:57:17.685] INFO: 41600 events read in total (2723ms).
[13:57:17.690] INFO: Test took 4052ms.
[13:57:17.776] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:18.481] INFO: Expecting 41600 events.
[13:57:21.812] INFO: 41600 events read in total (2805ms).
[13:57:21.816] INFO: Test took 4000ms.
[13:57:21.898] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:22.604] INFO: Expecting 41600 events.
[13:57:25.988] INFO: 41600 events read in total (2858ms).
[13:57:25.992] INFO: Test took 4053ms.
[13:57:26.075] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:26.784] INFO: Expecting 41600 events.
[13:57:30.219] INFO: 41600 events read in total (2909ms).
[13:57:30.224] INFO: Test took 4107ms.
[13:57:30.313] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:31.029] INFO: Expecting 41600 events.
[13:57:34.485] INFO: 41600 events read in total (2929ms).
[13:57:34.491] INFO: Test took 4132ms.
[13:57:34.584] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:35.293] INFO: Expecting 41600 events.
[13:57:38.695] INFO: 41600 events read in total (2875ms).
[13:57:38.699] INFO: Test took 4073ms.
[13:57:38.782] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:39.489] INFO: Expecting 41600 events.
[13:57:42.873] INFO: 41600 events read in total (2857ms).
[13:57:42.877] INFO: Test took 4053ms.
[13:57:42.959] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:43.671] INFO: Expecting 41600 events.
[13:57:47.155] INFO: 41600 events read in total (2957ms).
[13:57:47.160] INFO: Test took 4159ms.
[13:57:47.247] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:47.957] INFO: Expecting 41600 events.
[13:57:51.374] INFO: 41600 events read in total (2890ms).
[13:57:51.378] INFO: Test took 4087ms.
[13:57:51.462] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:52.172] INFO: Expecting 41600 events.
[13:57:55.628] INFO: 41600 events read in total (2929ms).
[13:57:55.633] INFO: Test took 4128ms.
[13:57:55.720] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:56.433] INFO: Expecting 41600 events.
[13:57:59.813] INFO: 41600 events read in total (2854ms).
[13:57:59.817] INFO: Test took 4054ms.
[13:57:59.899] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:00.606] INFO: Expecting 41600 events.
[13:58:03.998] INFO: 41600 events read in total (2865ms).
[13:58:04.002] INFO: Test took 4061ms.
[13:58:04.087] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:04.796] INFO: Expecting 41600 events.
[13:58:08.176] INFO: 41600 events read in total (2853ms).
[13:58:08.180] INFO: Test took 4050ms.
[13:58:08.263] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:08.972] INFO: Expecting 41600 events.
[13:58:12.341] INFO: 41600 events read in total (2842ms).
[13:58:12.344] INFO: Test took 4038ms.
[13:58:12.429] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:13.137] INFO: Expecting 41600 events.
[13:58:16.515] INFO: 41600 events read in total (2852ms).
[13:58:16.518] INFO: Test took 4046ms.
[13:58:16.601] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:17.310] INFO: Expecting 41600 events.
[13:58:20.685] INFO: 41600 events read in total (2849ms).
[13:58:20.689] INFO: Test took 4045ms.
[13:58:20.772] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:21.480] INFO: Expecting 41600 events.
[13:58:24.871] INFO: 41600 events read in total (2864ms).
[13:58:24.875] INFO: Test took 4060ms.
[13:58:24.959] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:25.667] INFO: Expecting 41600 events.
[13:58:29.056] INFO: 41600 events read in total (2862ms).
[13:58:29.059] INFO: Test took 4057ms.
[13:58:29.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:29.850] INFO: Expecting 41600 events.
[13:58:33.247] INFO: 41600 events read in total (2870ms).
[13:58:33.250] INFO: Test took 4067ms.
[13:58:33.333] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:34.041] INFO: Expecting 41600 events.
[13:58:37.197] INFO: 41600 events read in total (2629ms).
[13:58:37.201] INFO: Test took 3825ms.
[13:58:37.282] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:42.721] INFO: ----------------------------------------------------------------------
[13:58:42.721] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:58:42.721] INFO: ----------------------------------------------------------------------
[13:58:43.403] INFO: Expecting 208000 events.
[13:58:56.330] INFO: 208000 events read in total (12400ms).
[13:58:56.343] INFO: Test took 13606ms.
[13:58:56.739] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:57.135] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 2 1 0 0 0 0
[13:58:57.135] INFO: number of red-efficiency pixels: 101 88 180 197 233 255 231 232 280 371 260 236 256 179 115 101
[13:58:57.135] INFO: number of X-ray hits detected: 75365 62439 105286 157106 168896 165488 171535 146050 163444 173887 175111 161201 164462 109283 65932 78209
[13:58:57.135] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:58:57.135] INFO: number of Vcal hits detected: 207897 207909 207816 207796 207755 207732 207742 207757 207708 207560 207632 207705 207736 207815 207885 207898
[13:58:57.135] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 100.0
[13:58:57.135] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 99.9 100.0
[13:58:57.135] INFO: X-ray hit rate [MHz/cm2]: 22.1 18.3 30.9 46.0 49.5 48.5 50.3 42.8 47.9 51.0 51.3 47.2 48.2 32.0 19.3 22.9
[13:58:57.135] INFO: PixTestHighRate::doXPixelAlive() done
[13:58:57.183] INFO: PixTest:: pg_setup set to default.
[13:59:18.977] INFO: ----------------------------------------------------------------------
[13:59:18.977] INFO: PixTestHighRate::runDaq() running for 2 seconds
[13:59:18.977] INFO: ----------------------------------------------------------------------
[13:59:20.121] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[13:59:22.125] INFO: data taking finished, elapsed time: 2 seconds.
[13:59:23.555] INFO: PixTest:: pg_setup set to default.
[13:59:23.693] INFO: Pixels without X-ray hits (per ROC): 798 1237 718 158 173 170 162 303 137 169 160 266 163 685 1175 832
[13:59:23.694] INFO: PixTestHighRate::doRunDaq() done
[14:00:09.007] QUIET: Connection to board 58 closed.
[13:52:31.854] INFO: *** Welcome to pxar ***
[13:52:31.854] INFO: *** Today: 2016/05/13
[13:52:31.877] INFO: *** Version: v1.9.0-805-geb36
[13:52:31.877] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/dacParameters35_C15.dat
[13:52:31.878] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/tbmParameters_C0b.dat
[13:52:31.878] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/defaultMaskFile.dat
[13:52:31.878] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/trimParameters35_C15.dat
[13:52:32.026] INFO: clk: 4
[13:52:32.026] INFO: ctr: 4
[13:52:32.026] INFO: sda: 19
[13:52:32.026] INFO: tin: 9
[13:52:32.027] INFO: level: 15
[13:52:32.027] INFO: triggerdelay: 0
[13:52:32.027] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[13:52:32.027] INFO: Log level: INFO
[13:52:32.038] QUIET: Connection to board DTB_WRPRHI opened.
[13:52:32.041] 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:
------------------------------------------------------
[13:52:32.044] INFO: RPC call hashes of host and DTB match: 398089610
[13:52:33.643] INFO: DUT info:
[13:52:33.643] INFO: The DUT currently contains the following objects:
[13:52:33.643] INFO: 2 TBM Cores tbm08c (2 ON)
[13:52:33.643] INFO: TBM Core alpha (0): 7 registers set
[13:52:33.643] INFO: TBM Core beta (1): 7 registers set
[13:52:33.643] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:52:33.643] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:33.644] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:34.237] INFO: PixTestCmd::init()
[13:52:34.249] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mm323/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/phCalibrationFitErr35_C15.dat
[13:52:34.551] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/readbackCal_C15.dat
[13:52:45.220] INFO: ######################################################################
[13:52:45.220] INFO: PixTestAlive::doTest()
[13:52:45.220] INFO: ######################################################################
[13:52:45.223] INFO: ----------------------------------------------------------------------
[13:52:45.223] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:52:45.223] INFO: ----------------------------------------------------------------------
[13:52:45.601] INFO: Expecting 41600 events.
[13:52:49.876] INFO: 41600 events read in total (3556ms).
[13:52:50.023] INFO: Test took 4797ms.
[13:52:50.032] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:50.305] INFO: PixTestAlive::aliveTest() done
[13:52:50.305] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 2 1 0 0 0 0
[13:52:50.327] INFO: ----------------------------------------------------------------------
[13:52:50.327] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:52:50.327] INFO: ----------------------------------------------------------------------
[13:52:50.704] INFO: Expecting 41600 events.
[13:52:53.747] INFO: 41600 events read in total (2328ms).
[13:52:53.748] INFO: Test took 3419ms.
[13:52:53.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:53.748] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:52:54.171] INFO: PixTestAlive::maskTest() done
[13:52:54.171] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:52:54.190] INFO: ----------------------------------------------------------------------
[13:52:54.190] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:52:54.190] INFO: ----------------------------------------------------------------------
[13:52:54.567] INFO: Expecting 41600 events.
[13:52:58.822] INFO: 41600 events read in total (3540ms).
[13:52:58.823] INFO: Test took 4631ms.
[13:52:58.834] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:59.207] INFO: PixTestAlive::addressDecodingTest() done
[13:52:59.207] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:52:59.207] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[13:53:07.745] INFO: ----------------------------------------------------------------------
[13:53:07.745] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:53:07.745] INFO: ----------------------------------------------------------------------
[13:53:07.884] INFO: Expecting 768 events.
[13:53:09.016] INFO: 768 events read in total (417ms).
[13:53:09.016] INFO: Test took 1266ms.
[13:53:09.819] INFO: Expecting 41600 events.
[13:53:13.341] INFO: 41600 events read in total (2995ms).
[13:53:13.346] INFO: Test took 4324ms.
[13:53:13.473] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:14.193] INFO: Expecting 41600 events.
[13:53:17.810] INFO: 41600 events read in total (3090ms).
[13:53:17.814] INFO: Test took 4286ms.
[13:53:17.935] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:18.657] INFO: Expecting 41600 events.
[13:53:22.293] INFO: 41600 events read in total (3109ms).
[13:53:22.298] INFO: Test took 4307ms.
[13:53:22.422] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:23.144] INFO: Expecting 41600 events.
[13:53:26.778] INFO: 41600 events read in total (3107ms).
[13:53:26.783] INFO: Test took 4304ms.
[13:53:26.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:27.626] INFO: Expecting 41600 events.
[13:53:31.270] INFO: 41600 events read in total (3117ms).
[13:53:31.275] INFO: Test took 4314ms.
[13:53:31.402] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:32.125] INFO: Expecting 41600 events.
[13:53:35.769] INFO: 41600 events read in total (3117ms).
[13:53:35.774] INFO: Test took 4314ms.
[13:53:35.898] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:36.621] INFO: Expecting 41600 events.
[13:53:40.284] INFO: 41600 events read in total (3136ms).
[13:53:40.289] INFO: Test took 4333ms.
[13:53:40.414] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:41.138] INFO: Expecting 41600 events.
[13:53:44.797] INFO: 41600 events read in total (3133ms).
[13:53:44.802] INFO: Test took 4330ms.
[13:53:44.927] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:45.650] INFO: Expecting 41600 events.
[13:53:49.325] INFO: 41600 events read in total (3148ms).
[13:53:49.329] INFO: Test took 4344ms.
[13:53:49.455] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:50.179] INFO: Expecting 41600 events.
[13:53:53.833] INFO: 41600 events read in total (3128ms).
[13:53:53.837] INFO: Test took 4323ms.
[13:53:53.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:54.687] INFO: Expecting 41600 events.
[13:53:58.363] INFO: 41600 events read in total (3149ms).
[13:53:58.368] INFO: Test took 4346ms.
[13:53:58.494] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:59.218] INFO: Expecting 41600 events.
[13:54:02.883] INFO: 41600 events read in total (3139ms).
[13:54:02.887] INFO: Test took 4336ms.
[13:54:03.012] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:03.735] INFO: Expecting 41600 events.
[13:54:07.392] INFO: 41600 events read in total (3130ms).
[13:54:07.396] INFO: Test took 4327ms.
[13:54:07.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:08.243] INFO: Expecting 41600 events.
[13:54:11.895] INFO: 41600 events read in total (3125ms).
[13:54:11.900] INFO: Test took 4323ms.
[13:54:12.024] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:12.747] INFO: Expecting 41600 events.
[13:54:16.410] INFO: 41600 events read in total (3137ms).
[13:54:16.415] INFO: Test took 4334ms.
[13:54:16.538] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:17.260] INFO: Expecting 41600 events.
[13:54:20.914] INFO: 41600 events read in total (3127ms).
[13:54:20.919] INFO: Test took 4324ms.
[13:54:21.043] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:21.767] INFO: Expecting 41600 events.
[13:54:25.430] INFO: 41600 events read in total (3136ms).
[13:54:25.435] INFO: Test took 4333ms.
[13:54:25.559] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:26.283] INFO: Expecting 41600 events.
[13:54:29.946] INFO: 41600 events read in total (3137ms).
[13:54:29.951] INFO: Test took 4334ms.
[13:54:30.076] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:30.799] INFO: Expecting 41600 events.
[13:54:34.414] INFO: 41600 events read in total (3088ms).
[13:54:34.419] INFO: Test took 4285ms.
[13:54:34.541] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:35.266] INFO: Expecting 41600 events.
[13:54:38.692] INFO: 41600 events read in total (2899ms).
[13:54:38.696] INFO: Test took 4095ms.
[13:54:38.816] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:44.073] INFO: ----------------------------------------------------------------------
[13:54:44.073] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:54:44.073] INFO: ----------------------------------------------------------------------
[13:54:44.754] INFO: Expecting 208000 events.
[13:54:59.412] INFO: 208000 events read in total (14131ms).
[13:54:59.430] INFO: Test took 15341ms.
[13:55:00.021] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:00.477] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 2 1 0 0 0 0
[13:55:00.477] INFO: number of red-efficiency pixels: 167 151 289 448 514 521 564 473 533 591 544 514 503 312 161 161
[13:55:00.477] INFO: number of X-ray hits detected: 116699 96428 163007 243921 261703 257802 266611 227028 252668 269917 273100 249434 256443 170339 102924 121829
[13:55:00.477] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:55:00.477] INFO: number of Vcal hits detected: 207831 207840 207690 207520 207436 207425 207390 207465 207416 207353 207297 207375 207448 207656 207799 207834
[13:55:00.477] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.7 99.8 99.7 99.7 99.7 99.7 99.8 99.8 99.9 99.9
[13:55:00.477] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.8 99.9 99.9
[13:55:00.477] INFO: X-ray hit rate [MHz/cm2]: 34.2 28.3 47.8 71.5 76.7 75.6 78.1 66.5 74.1 79.1 80.0 73.1 75.2 49.9 30.2 35.7
[13:55:00.477] INFO: PixTestHighRate::doXPixelAlive() done
[13:55:00.523] INFO: PixTest:: pg_setup set to default.
[13:55:29.465] INFO: ----------------------------------------------------------------------
[13:55:29.465] INFO: PixTestHighRate::runDaq() running for 2 seconds
[13:55:29.465] INFO: ----------------------------------------------------------------------
[13:55:30.611] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[13:55:32.615] INFO: data taking finished, elapsed time: 2 seconds.
[13:55:34.287] INFO: PixTest:: pg_setup set to default.
[13:55:34.407] INFO: Pixels without X-ray hits (per ROC): 529 921 583 107 103 92 92 186 90 68 108 174 116 521 850 533
[13:55:34.407] INFO: PixTestHighRate::doRunDaq() done
[13:55:55.353] QUIET: Connection to board 58 closed.
[13:48:48.928] INFO: *** Welcome to pxar ***
[13:48:48.928] INFO: *** Today: 2016/05/13
[13:48:48.950] INFO: *** Version: v1.9.0-805-geb36
[13:48:48.951] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/dacParameters35_C15.dat
[13:48:48.952] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/tbmParameters_C0b.dat
[13:48:48.952] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/defaultMaskFile.dat
[13:48:48.952] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/trimParameters35_C15.dat
[13:48:49.101] INFO: clk: 4
[13:48:49.101] INFO: ctr: 4
[13:48:49.101] INFO: sda: 19
[13:48:49.101] INFO: tin: 9
[13:48:49.101] INFO: level: 15
[13:48:49.101] INFO: triggerdelay: 0
[13:48:49.102] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[13:48:49.102] INFO: Log level: INFO
[13:48:49.114] QUIET: Connection to board DTB_WRPRHI opened.
[13:48:49.117] 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:
------------------------------------------------------
[13:48:49.120] INFO: RPC call hashes of host and DTB match: 398089610
[13:48:50.720] INFO: DUT info:
[13:48:50.720] INFO: The DUT currently contains the following objects:
[13:48:50.720] INFO: 2 TBM Cores tbm08c (2 ON)
[13:48:50.720] INFO: TBM Core alpha (0): 7 registers set
[13:48:50.720] INFO: TBM Core beta (1): 7 registers set
[13:48:50.720] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:48:50.720] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.720] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.720] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.720] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.720] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.720] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.720] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.720] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.721] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.721] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.721] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.721] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.721] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.721] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.721] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:50.721] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:51.317] INFO: PixTestCmd::init()
[13:48:51.322] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mm323/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/phCalibrationFitErr35_C15.dat
[13:48:51.628] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mm323/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mm323/readbackCal_C15.dat
[13:49:02.020] INFO: ######################################################################
[13:49:02.020] INFO: PixTestAlive::doTest()
[13:49:02.020] INFO: ######################################################################
[13:49:02.023] INFO: ----------------------------------------------------------------------
[13:49:02.023] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:49:02.023] INFO: ----------------------------------------------------------------------
[13:49:02.401] INFO: Expecting 41600 events.
[13:49:06.669] INFO: 41600 events read in total (3550ms).
[13:49:06.817] INFO: Test took 4791ms.
[13:49:06.826] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:07.101] INFO: PixTestAlive::aliveTest() done
[13:49:07.101] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 2 1 0 0 0 0
[13:49:07.128] INFO: ----------------------------------------------------------------------
[13:49:07.128] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:49:07.128] INFO: ----------------------------------------------------------------------
[13:49:07.504] INFO: Expecting 41600 events.
[13:49:10.564] INFO: 41600 events read in total (2345ms).
[13:49:10.564] INFO: Test took 3434ms.
[13:49:10.565] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:10.565] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:49:10.988] INFO: PixTestAlive::maskTest() done
[13:49:10.988] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:49:11.006] INFO: ----------------------------------------------------------------------
[13:49:11.006] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:49:11.006] INFO: ----------------------------------------------------------------------
[13:49:11.384] INFO: Expecting 41600 events.
[13:49:15.645] INFO: 41600 events read in total (3546ms).
[13:49:15.646] INFO: Test took 4638ms.
[13:49:15.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:16.027] INFO: PixTestAlive::addressDecodingTest() done
[13:49:16.027] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:49:16.027] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[13:49:31.265] INFO: ----------------------------------------------------------------------
[13:49:31.265] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:49:31.265] INFO: ----------------------------------------------------------------------
[13:49:31.404] INFO: Expecting 768 events.
[13:49:32.536] INFO: 768 events read in total (417ms).
[13:49:32.536] INFO: Test took 1265ms.
[13:49:33.340] INFO: Expecting 41600 events.
[13:49:37.160] INFO: 41600 events read in total (3293ms).
[13:49:37.166] INFO: Test took 4624ms.
[13:49:37.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:38.069] INFO: Expecting 41600 events.
[13:49:41.972] INFO: 41600 events read in total (3376ms).
[13:49:41.977] INFO: Test took 4573ms.
[13:49:42.145] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:42.883] INFO: Expecting 41600 events.
[13:49:46.797] INFO: 41600 events read in total (3388ms).
[13:49:46.803] INFO: Test took 4586ms.
[13:49:46.965] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:47.701] INFO: Expecting 41600 events.
[13:49:51.638] INFO: 41600 events read in total (3410ms).
[13:49:51.643] INFO: Test took 4607ms.
[13:49:51.809] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:52.547] INFO: Expecting 41600 events.
[13:49:56.489] INFO: 41600 events read in total (3415ms).
[13:49:56.496] INFO: Test took 4614ms.
[13:49:56.661] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:57.398] INFO: Expecting 41600 events.
[13:50:01.343] INFO: 41600 events read in total (3418ms).
[13:50:01.349] INFO: Test took 4616ms.
[13:50:01.515] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:02.252] INFO: Expecting 41600 events.
[13:50:06.202] INFO: 41600 events read in total (3423ms).
[13:50:06.209] INFO: Test took 4622ms.
[13:50:06.376] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:07.114] INFO: Expecting 41600 events.
[13:50:11.067] INFO: 41600 events read in total (3427ms).
[13:50:11.075] INFO: Test took 4626ms.
[13:50:11.239] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:11.976] INFO: Expecting 41600 events.
[13:50:15.929] INFO: 41600 events read in total (3426ms).
[13:50:15.937] INFO: Test took 4626ms.
[13:50:16.101] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:16.840] INFO: Expecting 41600 events.
[13:50:20.808] INFO: 41600 events read in total (3442ms).
[13:50:20.816] INFO: Test took 4642ms.
[13:50:20.982] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:21.722] INFO: Expecting 41600 events.
[13:50:25.684] INFO: 41600 events read in total (3436ms).
[13:50:25.692] INFO: Test took 4636ms.
[13:50:25.857] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:26.596] INFO: Expecting 41600 events.
[13:50:30.558] INFO: 41600 events read in total (3436ms).
[13:50:30.566] INFO: Test took 4636ms.
[13:50:30.731] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:31.468] INFO: Expecting 41600 events.
[13:50:35.412] INFO: 41600 events read in total (3417ms).
[13:50:35.418] INFO: Test took 4615ms.
[13:50:35.581] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:36.318] INFO: Expecting 41600 events.
[13:50:40.272] INFO: 41600 events read in total (3427ms).
[13:50:40.279] INFO: Test took 4626ms.
[13:50:40.447] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:41.186] INFO: Expecting 41600 events.
[13:50:45.147] INFO: 41600 events read in total (3435ms).
[13:50:45.155] INFO: Test took 4634ms.
[13:50:45.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:46.057] INFO: Expecting 41600 events.
[13:50:50.007] INFO: 41600 events read in total (3423ms).
[13:50:50.015] INFO: Test took 4623ms.
[13:50:50.180] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:50.919] INFO: Expecting 41600 events.
[13:50:54.880] INFO: 41600 events read in total (3435ms).
[13:50:54.888] INFO: Test took 4634ms.
[13:50:55.052] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:55.791] INFO: Expecting 41600 events.
[13:50:59.754] INFO: 41600 events read in total (3436ms).
[13:50:59.761] INFO: Test took 4636ms.
[13:50:59.925] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:00.664] INFO: Expecting 41600 events.
[13:51:04.573] INFO: 41600 events read in total (3382ms).
[13:51:04.579] INFO: Test took 4580ms.
[13:51:04.743] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:05.481] INFO: Expecting 41600 events.
[13:51:09.155] INFO: 41600 events read in total (3147ms).
[13:51:09.161] INFO: Test took 4345ms.
[13:51:09.317] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:13.329] INFO: ----------------------------------------------------------------------
[13:51:13.329] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:51:13.329] INFO: ----------------------------------------------------------------------
[13:51:14.011] INFO: Expecting 208000 events.
[13:51:30.969] INFO: 208000 events read in total (16431ms).
[13:51:30.994] INFO: Test took 17649ms.
[13:51:31.853] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:32.387] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 2 1 0 0 0 0
[13:51:32.387] INFO: number of red-efficiency pixels: 223 194 497 887 932 977 1092 893 947 1108 1110 1003 956 529 211 267
[13:51:32.387] INFO: number of X-ray hits detected: 159508 132049 223013 334161 356566 351100 362968 311375 346023 367560 370171 341703 349394 231986 140769 166403
[13:51:32.387] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:51:32.387] INFO: number of Vcal hits detected: 207764 207782 207373 206964 206887 206819 206659 206934 206875 206554 206553 206725 206856 207377 207774 207702
[13:51:32.387] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.7 99.6 99.5 99.5 99.4 99.5 99.5 99.4 99.4 99.5 99.5 99.7 99.9 99.9
[13:51:32.387] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.5 99.5 99.4 99.4 99.5 99.5 99.3 99.3 99.4 99.5 99.7 99.9 99.9
[13:51:32.387] INFO: X-ray hit rate [MHz/cm2]: 46.8 38.7 65.4 97.9 104.5 102.9 106.4 91.3 101.4 107.7 108.5 100.2 102.4 68.0 41.3 48.8
[13:51:32.387] INFO: PixTestHighRate::doXPixelAlive() done
[13:51:32.434] INFO: PixTest:: pg_setup set to default.
[13:51:47.481] INFO: ----------------------------------------------------------------------
[13:51:47.481] INFO: PixTestHighRate::runDaq() running for 2 seconds
[13:51:47.481] INFO: ----------------------------------------------------------------------
[13:51:48.633] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[13:51:50.636] INFO: data taking finished, elapsed time: 2 seconds.
[13:51:52.585] INFO: PixTest:: pg_setup set to default.
[13:51:52.685] INFO: Pixels without X-ray hits (per ROC): 326 718 458 61 74 58 70 142 62 54 57 136 74 404 664 327
[13:51:52.685] INFO: PixTestHighRate::doRunDaq() done
[13:52:12.795] QUIET: Connection to board 58 closed.