[10:03:37.819] INFO: *** Welcome to pxar ***
[10:03:37.819] INFO: *** Today: 2016/05/05
[10:03:37.834] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:03:37.834] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//dacParameters35_C15.dat
[10:03:38.338] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:03:38.338] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//defaultMaskFile.dat
[10:03:38.352] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C15.dat
[10:03:39.671] INFO: clk: 4
[10:03:39.671] INFO: ctr: 4
[10:03:39.671] INFO: sda: 19
[10:03:39.671] INFO: tin: 9
[10:03:39.671] INFO: level: 15
[10:03:39.671] INFO: triggerdelay: 0
[10:03:39.671] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:03:39.671] INFO: Log level: INFO
[10:03:39.689] QUIET: Connection to board DTB_WREK4U opened.
[10:03:39.692] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[10:03:39.695] INFO: RPC call hashes of host and DTB match: 398089610
[10:03:41.225] INFO: DUT info:
[10:03:41.225] INFO: The DUT currently contains the following objects:
[10:03:41.225] INFO: 2 TBM Cores tbm08c (2 ON)
[10:03:41.225] INFO: TBM Core alpha (0): 7 registers set
[10:03:41.225] INFO: TBM Core beta (1): 7 registers set
[10:03:41.225] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:03:41.225] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.225] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.225] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.225] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.225] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.225] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.225] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.225] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.225] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.226] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.226] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.226] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.226] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.226] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.226] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.226] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:41.641] INFO: enter 'restricted' command line mode
[10:03:41.641] INFO: enter test to run
[10:04:00.063] INFO: test: PixelAlive no parameter change
[10:04:00.063] INFO: running: pixelalive
[10:04:00.072] INFO: ----------------------------------------------------------------------
[10:04:00.072] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:04:00.072] INFO: ----------------------------------------------------------------------
[10:04:00.390] INFO: Expecting 41600 events.
[10:04:04.683] INFO: 41600 events read in total (3574ms).
[10:04:04.853] INFO: Test took 4778ms.
[10:04:04.864] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:05.125] INFO: PixTestAlive::aliveTest() done with 10 decoding errors
[10:04:05.125] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 3 1 0 1 0 0 1 0 0 0
[10:04:05.159] INFO: enter test to run
[10:04:42.166] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:04:42.167] INFO: running: highrate
[10:04:42.167] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:04:44.666] INFO: ----------------------------------------------------------------------
[10:04:44.666] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:04:44.666] INFO: ----------------------------------------------------------------------
[10:04:44.666] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:04:44.666] INFO: edge/corner pixel THR is adjusted
[10:04:44.666] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:04:45.625] INFO: Collecting data for 5 seconds...
[10:04:50.638] INFO: Done with hot pixel readout
[10:05:00.614] INFO: PixTest:: pg_setup set to default.
[10:05:00.615] INFO: 0 hot pixels found in step 0
[10:05:00.628] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:05:00.641] INFO: PixTest::trimHotPixels() done
[10:05:00.682] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C0.dat
[10:05:00.687] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C1.dat
[10:05:00.693] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C2.dat
[10:05:00.698] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C3.dat
[10:05:00.703] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C4.dat
[10:05:00.709] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C5.dat
[10:05:00.714] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C6.dat
[10:05:00.719] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C7.dat
[10:05:00.725] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C8.dat
[10:05:00.730] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C9.dat
[10:05:00.736] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C10.dat
[10:05:00.741] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C11.dat
[10:05:00.747] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C12.dat
[10:05:00.752] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C13.dat
[10:05:00.757] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C14.dat
[10:05:00.763] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C15.dat
[10:05:00.768] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//defaultMaskFile.dat
[10:05:00.778] INFO: enter test to run
[10:05:21.110] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:05:21.110] INFO: running: highrate
[10:05:21.115] INFO: ----------------------------------------------------------------------
[10:05:21.115] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:05:21.115] INFO: ----------------------------------------------------------------------
[10:05:21.115] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:05:21.115] INFO: edge/corner pixel THR is adjusted
[10:05:21.115] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:05:22.074] INFO: Collecting data for 1 seconds...
[10:05:23.078] INFO: Done with hot pixel readout
[10:05:26.746] INFO: PixTest:: pg_setup set to default.
[10:05:26.747] INFO: 0 hot pixels found in step 0
[10:05:26.752] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:05:26.852] INFO: PixTest::trimHotPixels() done
[10:05:26.853] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C0.dat
[10:05:26.860] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C1.dat
[10:05:26.865] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C2.dat
[10:05:26.871] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C3.dat
[10:05:26.876] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C4.dat
[10:05:26.881] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C5.dat
[10:05:26.887] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C6.dat
[10:05:26.892] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C7.dat
[10:05:26.897] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C8.dat
[10:05:26.903] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C9.dat
[10:05:26.908] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C10.dat
[10:05:26.914] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C11.dat
[10:05:26.921] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C12.dat
[10:05:26.926] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C13.dat
[10:05:26.931] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C14.dat
[10:05:26.937] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//trimParameters35_C15.dat
[10:05:26.942] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-24_FPIXTest-17C-FNAL-160428-0957_2016-04-28_09h57m_1461855468/000_FPIXTest_p17//defaultMaskFile.dat
[10:05:26.951] INFO: enter test to run
[10:06:31.150] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:06:31.150] INFO: running: xray
[10:06:31.151] INFO: ----------------------------------------------------------------------
[10:06:31.151] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:06:31.151] INFO: ----------------------------------------------------------------------
[10:06:32.115] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:06:44.229] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[10:07:13.630] INFO: Resuming triggers.
[10:07:25.745] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[10:07:55.381] INFO: Resuming triggers.
[10:08:07.494] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[10:08:36.833] INFO: Resuming triggers.
[10:08:48.946] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[10:09:18.252] INFO: Resuming triggers.
[10:09:30.370] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[10:09:59.965] INFO: Resuming triggers.
[10:10:12.080] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[10:10:40.892] INFO: Resuming triggers.
[10:10:53.009] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[10:11:22.152] INFO: Resuming triggers.
[10:11:34.269] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[10:12:03.529] INFO: Resuming triggers.
[10:12:06.952] INFO: data taking finished, elapsed time: 100 seconds.
[10:12:15.559] INFO: PixTest:: pg_setup set to default.
[10:12:15.562] INFO: PixTestXray::doPhRun() done
[10:12:15.695] INFO: enter test to run
[10:12:34.707] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:12:34.707] INFO: running: xray
[10:12:34.708] INFO: ----------------------------------------------------------------------
[10:12:34.708] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:12:34.708] INFO: ----------------------------------------------------------------------
[10:12:35.674] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:12:42.842] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[10:13:13.240] INFO: Resuming triggers.
[10:13:20.408] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[10:13:50.714] INFO: Resuming triggers.
[10:13:57.879] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[10:14:28.345] INFO: Resuming triggers.
[10:14:35.512] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[10:15:05.822] INFO: Resuming triggers.
[10:15:12.988] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[10:15:43.334] INFO: Resuming triggers.
[10:15:50.499] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[10:16:20.872] INFO: Resuming triggers.
[10:16:28.034] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[10:16:58.443] INFO: Resuming triggers.
[10:17:05.606] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:17:36.817] INFO: Resuming triggers.
[10:17:43.977] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[10:18:15.334] INFO: Resuming triggers.
[10:18:22.496] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[10:18:53.877] INFO: Resuming triggers.
[10:19:01.040] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[10:19:31.370] INFO: Resuming triggers.
[10:19:38.534] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[10:20:08.745] INFO: Resuming triggers.
[10:20:15.910] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:20:46.468] INFO: Resuming triggers.
[10:20:53.633] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[10:21:24.781] INFO: Resuming triggers.
[10:21:24.808] INFO: data taking finished, elapsed time: 100 seconds.
[10:21:25.478] INFO: PixTest:: pg_setup set to default.
[10:21:25.481] INFO: PixTestXray::doPhRun() done
[10:21:25.635] INFO: enter test to run
[10:23:06.311] INFO: test: HighRate no parameter change
[10:23:06.311] INFO: running: highrate
[10:23:06.312] INFO: ----------------------------------------------------------------------
[10:23:06.312] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:23:06.312] INFO: ----------------------------------------------------------------------
[10:23:06.454] INFO: Expecting 768 events.
[10:23:07.587] INFO: 768 events read in total (418ms).
[10:23:07.588] INFO: Test took 1269ms.
[10:23:08.391] INFO: Expecting 41600 events.
[10:23:11.504] INFO: 41600 events read in total (2586ms).
[10:23:11.505] INFO: Test took 3910ms.
[10:23:11.536] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:12.269] INFO: Expecting 41600 events.
[10:23:15.468] INFO: 41600 events read in total (2673ms).
[10:23:15.469] INFO: Test took 3916ms.
[10:23:15.502] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:16.230] INFO: Expecting 41600 events.
[10:23:19.424] INFO: 41600 events read in total (2667ms).
[10:23:19.424] INFO: Test took 3906ms.
[10:23:19.456] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:20.184] INFO: Expecting 41600 events.
[10:23:23.387] INFO: 41600 events read in total (2676ms).
[10:23:23.388] INFO: Test took 3916ms.
[10:23:23.418] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:24.150] INFO: Expecting 41600 events.
[10:23:27.358] INFO: 41600 events read in total (2681ms).
[10:23:27.359] INFO: Test took 3925ms.
[10:23:27.390] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:28.122] INFO: Expecting 41600 events.
[10:23:31.328] INFO: 41600 events read in total (2679ms).
[10:23:31.329] INFO: Test took 3922ms.
[10:23:31.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:32.090] INFO: Expecting 41600 events.
[10:23:35.304] INFO: 41600 events read in total (2687ms).
[10:23:35.305] INFO: Test took 3928ms.
[10:23:35.337] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:36.067] INFO: Expecting 41600 events.
[10:23:39.275] INFO: 41600 events read in total (2681ms).
[10:23:39.276] INFO: Test took 3921ms.
[10:23:39.307] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:40.038] INFO: Expecting 41600 events.
[10:23:43.256] INFO: 41600 events read in total (2691ms).
[10:23:43.257] INFO: Test took 3934ms.
[10:23:43.288] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:44.016] INFO: Expecting 41600 events.
[10:23:47.227] INFO: 41600 events read in total (2685ms).
[10:23:47.228] INFO: Test took 3923ms.
[10:23:47.259] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:47.986] INFO: Expecting 41600 events.
[10:23:51.202] INFO: 41600 events read in total (2690ms).
[10:23:51.203] INFO: Test took 3926ms.
[10:23:51.234] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:51.963] INFO: Expecting 41600 events.
[10:23:55.173] INFO: 41600 events read in total (2683ms).
[10:23:55.174] INFO: Test took 3922ms.
[10:23:55.204] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:55.935] INFO: Expecting 41600 events.
[10:23:59.156] INFO: 41600 events read in total (2694ms).
[10:23:59.156] INFO: Test took 3934ms.
[10:23:59.187] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:59.915] INFO: Expecting 41600 events.
[10:24:03.131] INFO: 41600 events read in total (2689ms).
[10:24:03.132] INFO: Test took 3927ms.
[10:24:03.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:03.895] INFO: Expecting 41600 events.
[10:24:07.111] INFO: 41600 events read in total (2689ms).
[10:24:07.112] INFO: Test took 3931ms.
[10:24:07.142] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:07.870] INFO: Expecting 41600 events.
[10:24:11.087] INFO: 41600 events read in total (2690ms).
[10:24:11.088] INFO: Test took 3930ms.
[10:24:11.119] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:11.852] INFO: Expecting 41600 events.
[10:24:15.068] INFO: 41600 events read in total (2689ms).
[10:24:15.069] INFO: Test took 3934ms.
[10:24:15.100] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:15.829] INFO: Expecting 41600 events.
[10:24:19.050] INFO: 41600 events read in total (2694ms).
[10:24:19.051] INFO: Test took 3934ms.
[10:24:19.081] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:19.809] INFO: Expecting 41600 events.
[10:24:22.991] INFO: 41600 events read in total (2655ms).
[10:24:22.992] INFO: Test took 3893ms.
[10:24:23.023] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:23.755] INFO: Expecting 41600 events.
[10:24:26.804] INFO: 41600 events read in total (2523ms).
[10:24:26.805] INFO: Test took 3765ms.
[10:24:26.835] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:27.204] INFO: enter test to run
[10:24:44.302] INFO: test: HighRate no parameter change
[10:24:44.302] INFO: running: highrate
[10:24:44.303] INFO: ----------------------------------------------------------------------
[10:24:44.303] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:24:44.303] INFO: ----------------------------------------------------------------------
[10:24:44.914] INFO: Expecting 208000 events.
[10:24:56.600] INFO: 208000 events read in total (11159ms).
[10:24:56.603] INFO: Test took 12292ms.
[10:24:56.734] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:56.980] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 3 1 0 1 0 0 0 0 0 0
[10:24:56.980] INFO: number of red-efficiency pixels: 72 52 66 90 107 120 102 91 87 115 81 98 101 65 26 26
[10:24:56.980] INFO: number of X-ray hits detected: 61013 43028 61564 90053 101144 96711 96016 69277 67355 85331 81887 72304 84642 53317 19421 20867
[10:24:56.980] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:24:56.980] INFO: number of Vcal hits detected: 207925 207947 207932 207908 207890 207878 207750 207860 207911 207835 207919 207898 207833 207933 207974 207974
[10:24:56.980] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0 99.9 100.0 100.0 99.9 100.0 100.0 100.0
[10:24:56.980] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 100.0 100.0 99.9 100.0 100.0 100.0
[10:24:56.980] INFO: X-ray hit rate [MHz/cm2]: 17.9 12.6 18.0 26.4 29.6 28.3 28.1 20.3 19.7 25.0 24.0 21.2 24.8 15.6 5.7 6.1
[10:24:56.980] INFO: PixTestHighRate::doXPixelAlive() done
[10:24:57.031] INFO: PixTest:: pg_setup set to default.
[10:24:57.042] INFO: enter test to run
[10:25:14.814] INFO: test: HighRate no parameter change
[10:25:14.814] INFO: running: highrate
[10:25:14.815] INFO: ----------------------------------------------------------------------
[10:25:14.815] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:25:14.815] INFO: ----------------------------------------------------------------------
[10:25:15.435] INFO: Expecting 208000 events.
[10:25:28.589] INFO: 208000 events read in total (12628ms).
[10:25:28.594] INFO: Test took 13769ms.
[10:25:28.857] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:25:29.144] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 3 1 0 1 0 0 0 0 0 0
[10:25:29.145] INFO: number of red-efficiency pixels: 179 137 192 325 333 242 293 209 201 335 216 255 303 191 40 54
[10:25:29.145] INFO: number of X-ray hits detected: 122736 86787 123503 182074 203340 194445 192629 140466 136750 172533 166480 146178 171535 108179 40124 42511
[10:25:29.145] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:25:29.145] INFO: number of Vcal hits detected: 207811 207860 207798 207657 207648 207739 207547 207736 207789 207597 207777 207721 207390 207794 207960 207944
[10:25:29.145] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.8 99.9 100.0 100.0
[10:25:29.145] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.9 99.8 99.9 99.9 99.8 99.9 99.9 99.7 99.9 100.0 100.0
[10:25:29.145] INFO: X-ray hit rate [MHz/cm2]: 36.0 25.4 36.2 53.4 59.6 57.0 56.5 41.2 40.1 50.6 48.8 42.8 50.3 31.7 11.8 12.5
[10:25:29.145] INFO: PixTestHighRate::doXPixelAlive() done
[10:25:29.192] INFO: PixTest:: pg_setup set to default.
[10:25:29.210] INFO: enter test to run
[10:25:42.421] INFO: test: HighRate no parameter change
[10:25:42.421] INFO: running: highrate
[10:25:42.423] INFO: ----------------------------------------------------------------------
[10:25:42.423] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:25:42.423] INFO: ----------------------------------------------------------------------
[10:25:43.038] INFO: Expecting 208000 events.
[10:25:57.959] INFO: 208000 events read in total (14394ms).
[10:25:57.965] INFO: Test took 15533ms.
[10:25:58.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:25:58.695] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 3 1 0 1 0 0 0 0 0 0
[10:25:58.695] INFO: number of red-efficiency pixels: 428 236 430 783 848 666 704 532 457 705 476 561 630 453 78 66
[10:25:58.695] INFO: number of X-ray hits detected: 189091 133404 191357 281166 315824 300807 299779 217349 212428 266303 257301 224760 265168 166128 61741 66229
[10:25:58.695] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:25:58.695] INFO: number of Vcal hits detected: 207525 207742 207506 207117 206999 207257 207053 207352 207506 207139 207488 207358 206090 207472 207920 207933
[10:25:58.695] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.6 99.7 99.7 99.7 99.8 99.6 99.8 99.7 99.4 99.8 100.0 100.0
[10:25:58.695] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.6 99.5 99.6 99.5 99.7 99.8 99.6 99.8 99.7 99.1 99.7 100.0 100.0
[10:25:58.695] INFO: X-ray hit rate [MHz/cm2]: 55.4 39.1 56.1 82.4 92.6 88.2 87.9 63.7 62.3 78.1 75.4 65.9 77.7 48.7 18.1 19.4
[10:25:58.695] INFO: PixTestHighRate::doXPixelAlive() done
[10:25:58.741] INFO: PixTest:: pg_setup set to default.
[10:25:58.757] INFO: enter test to run
[10:26:02.789] INFO: test: exit no parameter change
[10:26:03.086] QUIET: Connection to board 32 closed.
[10:26:03.088] INFO: pXar: this is the end, my friend