[13:09:06.322] INFO: *** Welcome to pxar ***
[13:09:06.322] INFO: *** Today: 2016/05/02
[13:09:06.977] INFO: *** Version: v1.9.0-796-gef167-dirty
[13:09:06.977] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//dacParameters35_C15.dat
[13:09:07.039] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:09:07.039] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//defaultMaskFile.dat
[13:09:07.039] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C15.dat
[13:09:07.173] INFO: clk: 4
[13:09:07.173] INFO: ctr: 4
[13:09:07.173] INFO: sda: 19
[13:09:07.173] INFO: tin: 9
[13:09:07.173] INFO: level: 15
[13:09:07.173] INFO: triggerdelay: 0
[13:09:07.173] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[13:09:07.173] INFO: Log level: INFO
[13:09:07.192] QUIET: Connection to board DTB_WREKRL opened.
[13:09:07.196] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[13:09:07.199] INFO: RPC call hashes of host and DTB match: 398089610
[13:09:08.740] INFO: DUT info:
[13:09:08.740] INFO: The DUT currently contains the following objects:
[13:09:08.740] INFO: 2 TBM Cores tbm08c (2 ON)
[13:09:08.753] INFO: TBM Core alpha (0): 7 registers set
[13:09:08.753] INFO: TBM Core beta (1): 7 registers set
[13:09:08.753] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:09:08.753] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.753] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.753] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.753] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.753] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.753] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.753] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.753] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.753] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.754] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.754] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.754] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.754] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.754] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.754] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:08.754] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:09.199] INFO: enter 'restricted' command line mode
[13:09:09.199] INFO: enter test to run
[13:09:15.352] INFO: test: PixelAlive no parameter change
[13:09:15.352] INFO: running: pixelalive
[13:09:15.417] INFO: ----------------------------------------------------------------------
[13:09:15.417] 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:09:15.417] INFO: ----------------------------------------------------------------------
[13:09:15.735] INFO: Expecting 41600 events.
[13:09:20.069] INFO: 41600 events read in total (3615ms).
[13:09:20.237] INFO: Test took 4816ms.
[13:09:20.251] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:20.818] INFO: PixTestAlive::aliveTest() done
[13:09:20.818] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:09:20.994] INFO: enter test to run
[13:09:56.103] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:09:56.104] INFO: running: highrate
[13:09:56.104] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:09:56.372] INFO: ----------------------------------------------------------------------
[13:09:56.372] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:09:56.372] INFO: ----------------------------------------------------------------------
[13:09:56.373] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:09:56.373] INFO: edge/corner pixel THR is adjusted
[13:09:56.373] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:09:57.332] INFO: Collecting data for 5 seconds...
[13:10:02.348] INFO: Done with hot pixel readout
[13:10:13.901] INFO: PixTest:: pg_setup set to default.
[13:10:13.901] INFO: 0 hot pixels found in step 0
[13:10:13.914] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:10:13.937] INFO: PixTest::trimHotPixels() done
[13:10:14.006] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C0.dat
[13:10:14.016] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C1.dat
[13:10:14.025] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C2.dat
[13:10:14.030] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C3.dat
[13:10:14.036] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C4.dat
[13:10:14.041] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C5.dat
[13:10:14.046] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C6.dat
[13:10:14.052] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C7.dat
[13:10:14.057] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C8.dat
[13:10:14.062] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C9.dat
[13:10:14.068] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C10.dat
[13:10:14.073] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C11.dat
[13:10:14.078] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C12.dat
[13:10:14.084] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C13.dat
[13:10:14.089] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C14.dat
[13:10:14.094] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C15.dat
[13:10:14.100] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//defaultMaskFile.dat
[13:10:14.117] INFO: enter test to run
[13:10:40.463] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:10:40.463] INFO: running: highrate
[13:10:40.468] INFO: ----------------------------------------------------------------------
[13:10:40.468] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:10:40.468] INFO: ----------------------------------------------------------------------
[13:10:40.468] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:10:40.468] INFO: edge/corner pixel THR is adjusted
[13:10:40.468] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:10:41.426] INFO: Collecting data for 1 seconds...
[13:10:42.430] INFO: Done with hot pixel readout
[13:10:46.399] INFO: PixTest:: pg_setup set to default.
[13:10:46.400] INFO: 0 hot pixels found in step 0
[13:10:46.406] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:10:46.497] INFO: PixTest::trimHotPixels() done
[13:10:46.497] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C0.dat
[13:10:46.507] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C1.dat
[13:10:46.513] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C2.dat
[13:10:46.518] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C3.dat
[13:10:46.523] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C4.dat
[13:10:46.529] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C5.dat
[13:10:46.534] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C6.dat
[13:10:46.540] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C7.dat
[13:10:46.545] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C8.dat
[13:10:46.550] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C9.dat
[13:10:46.556] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C10.dat
[13:10:46.561] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C11.dat
[13:10:46.567] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C12.dat
[13:10:46.572] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C13.dat
[13:10:46.577] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C14.dat
[13:10:46.583] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//trimParameters35_C15.dat
[13:10:46.589] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-2-19_FPIXTest-17C-FNAL-160425-1233_2016-04-25_12h33m_1461605635/000_FPIXTest_p17//defaultMaskFile.dat
[13:10:46.598] INFO: enter test to run
[13:11:24.719] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:11:24.719] INFO: running: xray
[13:11:24.721] INFO: ----------------------------------------------------------------------
[13:11:24.721] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:11:24.721] INFO: ----------------------------------------------------------------------
[13:11:25.730] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:11:37.406] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:12:07.052] INFO: Resuming triggers.
[13:12:18.732] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:12:48.437] INFO: Resuming triggers.
[13:13:00.111] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:13:29.774] INFO: Resuming triggers.
[13:13:41.456] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[13:14:11.081] INFO: Resuming triggers.
[13:14:22.756] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[13:14:52.465] INFO: Resuming triggers.
[13:15:04.138] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[13:15:33.781] INFO: Resuming triggers.
[13:15:45.455] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[13:16:15.046] INFO: Resuming triggers.
[13:16:26.718] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[13:16:56.291] INFO: Resuming triggers.
[13:17:03.217] INFO: data taking finished, elapsed time: 100 seconds.
[13:17:21.043] INFO: PixTest:: pg_setup set to default.
[13:17:21.046] INFO: PixTestXray::doPhRun() done
[13:17:21.213] INFO: enter test to run
[13:19:21.659] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:19:21.660] INFO: running: xray
[13:19:21.661] INFO: ----------------------------------------------------------------------
[13:19:21.661] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:19:21.661] INFO: ----------------------------------------------------------------------
[13:19:22.625] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:19:29.414] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:20:00.188] INFO: Resuming triggers.
[13:20:06.980] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[13:20:36.143] INFO: Resuming triggers.
[13:20:42.936] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[13:21:13.657] INFO: Resuming triggers.
[13:21:20.450] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[13:21:51.238] INFO: Resuming triggers.
[13:21:58.026] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:22:28.757] INFO: Resuming triggers.
[13:22:35.547] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[13:23:06.334] INFO: Resuming triggers.
[13:23:13.122] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[13:23:43.945] INFO: Resuming triggers.
[13:23:50.733] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[13:24:21.565] INFO: Resuming triggers.
[13:24:28.351] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[13:24:59.125] INFO: Resuming triggers.
[13:25:05.911] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[13:25:36.707] INFO: Resuming triggers.
[13:25:43.495] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[13:26:14.227] INFO: Resuming triggers.
[13:26:21.014] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[13:26:51.825] INFO: Resuming triggers.
[13:26:58.613] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[13:27:29.366] INFO: Resuming triggers.
[13:27:36.151] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[13:28:06.913] INFO: Resuming triggers.
[13:28:12.198] INFO: data taking finished, elapsed time: 100 seconds.
[13:28:36.306] INFO: PixTest:: pg_setup set to default.
[13:28:36.309] INFO: PixTestXray::doPhRun() done
[13:28:36.458] INFO: enter test to run
[13:29:09.823] INFO: test: HighRate no parameter change
[13:29:09.823] INFO: running: highrate
[13:29:09.824] INFO: ----------------------------------------------------------------------
[13:29:09.824] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:29:09.824] INFO: ----------------------------------------------------------------------
[13:29:09.965] INFO: Expecting 768 events.
[13:29:11.108] INFO: 768 events read in total (418ms).
[13:29:11.108] INFO: Test took 1277ms.
[13:29:11.912] INFO: Expecting 41600 events.
[13:29:14.977] INFO: 41600 events read in total (2539ms).
[13:29:14.978] INFO: Test took 3862ms.
[13:29:15.010] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:15.742] INFO: Expecting 41600 events.
[13:29:18.913] INFO: 41600 events read in total (2644ms).
[13:29:18.913] INFO: Test took 3886ms.
[13:29:18.946] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:19.673] INFO: Expecting 41600 events.
[13:29:22.898] INFO: 41600 events read in total (2699ms).
[13:29:22.899] INFO: Test took 3937ms.
[13:29:22.931] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:23.659] INFO: Expecting 41600 events.
[13:29:26.884] INFO: 41600 events read in total (2698ms).
[13:29:26.885] INFO: Test took 3935ms.
[13:29:26.919] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:27.644] INFO: Expecting 41600 events.
[13:29:30.833] INFO: 41600 events read in total (2662ms).
[13:29:30.834] INFO: Test took 3898ms.
[13:29:30.866] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:31.596] INFO: Expecting 41600 events.
[13:29:34.815] INFO: 41600 events read in total (2692ms).
[13:29:34.816] INFO: Test took 3933ms.
[13:29:34.848] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:35.576] INFO: Expecting 41600 events.
[13:29:38.785] INFO: 41600 events read in total (2682ms).
[13:29:38.786] INFO: Test took 3920ms.
[13:29:38.818] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:39.543] INFO: Expecting 41600 events.
[13:29:42.781] INFO: 41600 events read in total (2711ms).
[13:29:42.782] INFO: Test took 3945ms.
[13:29:42.815] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:43.539] INFO: Expecting 41600 events.
[13:29:46.754] INFO: 41600 events read in total (2688ms).
[13:29:46.755] INFO: Test took 3923ms.
[13:29:46.788] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:47.515] INFO: Expecting 41600 events.
[13:29:50.750] INFO: 41600 events read in total (2708ms).
[13:29:50.751] INFO: Test took 3945ms.
[13:29:50.784] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:51.511] INFO: Expecting 41600 events.
[13:29:54.744] INFO: 41600 events read in total (2706ms).
[13:29:54.745] INFO: Test took 3944ms.
[13:29:54.777] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:55.501] INFO: Expecting 41600 events.
[13:29:58.741] INFO: 41600 events read in total (2713ms).
[13:29:58.742] INFO: Test took 3947ms.
[13:29:58.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:59.500] INFO: Expecting 41600 events.
[13:30:02.705] INFO: 41600 events read in total (2678ms).
[13:30:02.706] INFO: Test took 3913ms.
[13:30:02.738] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:03.466] INFO: Expecting 41600 events.
[13:30:06.697] INFO: 41600 events read in total (2704ms).
[13:30:06.698] INFO: Test took 3941ms.
[13:30:06.730] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:07.455] INFO: Expecting 41600 events.
[13:30:10.692] INFO: 41600 events read in total (2710ms).
[13:30:10.693] INFO: Test took 3945ms.
[13:30:10.726] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:11.450] INFO: Expecting 41600 events.
[13:30:14.670] INFO: 41600 events read in total (2693ms).
[13:30:14.671] INFO: Test took 3927ms.
[13:30:14.703] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:15.432] INFO: Expecting 41600 events.
[13:30:18.672] INFO: 41600 events read in total (2713ms).
[13:30:18.673] INFO: Test took 3952ms.
[13:30:18.705] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:19.431] INFO: Expecting 41600 events.
[13:30:22.657] INFO: 41600 events read in total (2700ms).
[13:30:22.658] INFO: Test took 3935ms.
[13:30:22.690] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:23.414] INFO: Expecting 41600 events.
[13:30:26.605] INFO: 41600 events read in total (2664ms).
[13:30:26.606] INFO: Test took 3898ms.
[13:30:26.639] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:27.365] INFO: Expecting 41600 events.
[13:30:30.012] INFO: 41600 events read in total (2120ms).
[13:30:30.013] INFO: Test took 3356ms.
[13:30:30.045] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:30.408] INFO: enter test to run
[13:32:15.230] INFO: test: HighRate no parameter change
[13:32:15.230] INFO: running: highrate
[13:32:15.231] INFO: ----------------------------------------------------------------------
[13:32:15.231] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:32:15.231] INFO: ----------------------------------------------------------------------
[13:32:15.843] INFO: Expecting 208000 events.
[13:32:27.740] INFO: 208000 events read in total (11370ms).
[13:32:27.743] INFO: Test took 12502ms.
[13:32:27.880] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:28.129] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:32:28.129] INFO: number of red-efficiency pixels: 58 38 70 134 138 153 115 101 114 91 91 99 86 61 29 29
[13:32:28.129] INFO: number of X-ray hits detected: 54782 41853 62451 101872 113277 112922 110460 83031 74261 85643 87642 76436 80441 47103 21967 26867
[13:32:28.129] 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:32:28.129] INFO: number of Vcal hits detected: 207938 207961 207928 207862 207807 207845 207880 207895 207881 207906 207909 207900 207911 207938 207971 207971
[13:32:28.129] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[13:32:28.129] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[13:32:28.129] INFO: X-ray hit rate [MHz/cm2]: 16.1 12.3 18.3 29.9 33.2 33.1 32.4 24.3 21.8 25.1 25.7 22.4 23.6 13.8 6.4 7.9
[13:32:28.129] INFO: PixTestHighRate::doXPixelAlive() done
[13:32:28.173] INFO: PixTest:: pg_setup set to default.
[13:32:28.185] INFO: enter test to run
[13:33:43.973] INFO: test: HighRate no parameter change
[13:33:43.973] INFO: running: highrate
[13:33:43.974] INFO: ----------------------------------------------------------------------
[13:33:43.974] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:33:43.974] INFO: ----------------------------------------------------------------------
[13:33:44.587] INFO: Expecting 208000 events.
[13:33:58.161] INFO: 208000 events read in total (13047ms).
[13:33:58.167] INFO: Test took 14184ms.
[13:33:58.451] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:58.747] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:33:58.747] INFO: number of red-efficiency pixels: 162 88 269 444 450 507 422 328 241 286 353 260 276 145 63 78
[13:33:58.747] INFO: number of X-ray hits detected: 114316 87671 131239 213631 237114 236678 231185 172906 155655 179257 183446 160255 169447 98969 45990 56259
[13:33:58.747] 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:33:58.747] INFO: number of Vcal hits detected: 207832 207909 207709 207515 207452 207444 207556 207646 207751 207698 207634 207728 207712 207849 207937 207922
[13:33:58.747] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[13:33:58.747] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.7 99.7 99.8 99.8 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[13:33:58.747] INFO: X-ray hit rate [MHz/cm2]: 33.5 25.7 38.5 62.6 69.5 69.4 67.8 50.7 45.6 52.5 53.8 47.0 49.7 29.0 13.5 16.5
[13:33:58.747] INFO: PixTestHighRate::doXPixelAlive() done
[13:33:58.795] INFO: PixTest:: pg_setup set to default.
[13:33:58.809] INFO: enter test to run
[13:34:20.757] INFO: test: HighRate no parameter change
[13:34:20.757] INFO: running: highrate
[13:34:20.758] INFO: ----------------------------------------------------------------------
[13:34:20.758] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:34:20.758] INFO: ----------------------------------------------------------------------
[13:34:21.372] INFO: Expecting 208000 events.
[13:34:36.783] INFO: 208000 events read in total (14884ms).
[13:34:36.790] INFO: Test took 16024ms.
[13:34:37.223] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:37.569] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:34:37.569] INFO: number of red-efficiency pixels: 369 175 610 1093 1025 1162 933 731 587 632 735 627 596 344 96 112
[13:34:37.569] INFO: number of X-ray hits detected: 173108 133379 199282 324011 359691 360343 350807 263003 238541 272296 279105 242527 257359 151108 70206 85161
[13:34:37.569] 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:34:37.569] INFO: number of Vcal hits detected: 207599 207817 207225 206639 206649 206532 206871 207085 207351 207274 207160 207287 207335 207609 207896 207883
[13:34:37.569] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.4 99.4 99.4 99.5 99.6 99.7 99.7 99.6 99.7 99.7 99.8 100.0 99.9
[13:34:37.569] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.3 99.4 99.3 99.5 99.6 99.7 99.7 99.6 99.7 99.7 99.8 100.0 99.9
[13:34:37.569] INFO: X-ray hit rate [MHz/cm2]: 50.7 39.1 58.4 95.0 105.4 105.6 102.8 77.1 69.9 79.8 81.8 71.1 75.4 44.3 20.6 25.0
[13:34:37.569] INFO: PixTestHighRate::doXPixelAlive() done
[13:34:37.618] INFO: PixTest:: pg_setup set to default.
[13:34:37.632] INFO: enter test to run
[13:34:40.573] INFO: test: exit no parameter change
[13:34:40.938] QUIET: Connection to board 33 closed.
[13:34:40.939] INFO: pXar: this is the end, my friend