[16:08:45.136] INFO: *** Welcome to pxar ***
[16:08:45.136] INFO: *** Today: 2016/06/01
[16:08:45.155] INFO: *** Version: v1.9.0-796-gef167-dirty
[16:08:45.155] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//dacParameters35_C15.dat
[16:08:45.206] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//tbmParameters_C0b.dat
[16:08:45.207] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//defaultMaskFile.dat
[16:08:45.214] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C15.dat
[16:08:45.309] INFO: clk: 4
[16:08:45.309] INFO: ctr: 4
[16:08:45.309] INFO: sda: 19
[16:08:45.309] INFO: tin: 9
[16:08:45.309] INFO: level: 15
[16:08:45.309] INFO: triggerdelay: 0
[16:08:45.310] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[16:08:45.310] INFO: Log level: INFO
[16:08:45.328] QUIET: Connection to board DTB_WREK4U opened.
[16:08:45.332] 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:
------------------------------------------------------
[16:08:45.334] INFO: RPC call hashes of host and DTB match: 398089610
[16:08:46.868] INFO: DUT info:
[16:08:46.868] INFO: The DUT currently contains the following objects:
[16:08:46.868] INFO: 2 TBM Cores tbm08c (2 ON)
[16:08:46.868] INFO: TBM Core alpha (0): 7 registers set
[16:08:46.868] INFO: TBM Core beta (1): 7 registers set
[16:08:46.868] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:08:46.869] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:46.869] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:47.273] INFO: enter 'restricted' command line mode
[16:08:47.273] INFO: enter test to run
[16:08:49.693] INFO: test: PixelAlive no parameter change
[16:08:49.693] INFO: running: pixelalive
[16:08:49.703] INFO: ----------------------------------------------------------------------
[16:08:49.703] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:08:49.703] INFO: ----------------------------------------------------------------------
[16:08:50.020] INFO: Expecting 41600 events.
[16:08:54.348] INFO: 41600 events read in total (3609ms).
[16:08:54.517] INFO: Test took 4812ms.
[16:08:54.528] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:54.790] INFO: PixTestAlive::aliveTest() done
[16:08:54.790] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[16:08:54.829] INFO: enter test to run
[16:09:14.021] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[16:09:14.021] INFO: running: highrate
[16:09:14.022] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[16:09:14.239] INFO: ----------------------------------------------------------------------
[16:09:14.239] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[16:09:14.239] INFO: ----------------------------------------------------------------------
[16:09:14.239] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[16:09:14.239] INFO: edge/corner pixel THR is adjusted
[16:09:14.239] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:09:15.199] INFO: Collecting data for 5 seconds...
[16:09:20.214] INFO: Done with hot pixel readout
[16:09:31.990] INFO: PixTest:: pg_setup set to default.
[16:09:31.991] INFO: 0 hot pixels found in step 0
[16:09:32.003] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:09:32.007] INFO: PixTest::trimHotPixels() done
[16:09:32.053] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C0.dat
[16:09:32.058] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C1.dat
[16:09:32.063] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C2.dat
[16:09:32.068] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C3.dat
[16:09:32.074] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C4.dat
[16:09:32.079] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C5.dat
[16:09:32.084] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C6.dat
[16:09:32.089] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C7.dat
[16:09:32.094] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C8.dat
[16:09:32.099] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C9.dat
[16:09:32.105] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C10.dat
[16:09:32.110] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C11.dat
[16:09:32.115] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C12.dat
[16:09:32.120] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C13.dat
[16:09:32.125] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C14.dat
[16:09:32.130] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C15.dat
[16:09:32.135] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//defaultMaskFile.dat
[16:09:32.145] INFO: enter test to run
[16:09:48.781] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[16:09:48.781] INFO: running: highrate
[16:09:48.785] INFO: ----------------------------------------------------------------------
[16:09:48.785] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[16:09:48.785] INFO: ----------------------------------------------------------------------
[16:09:48.785] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[16:09:48.785] INFO: edge/corner pixel THR is adjusted
[16:09:48.785] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:09:49.744] INFO: Collecting data for 1 seconds...
[16:09:50.747] INFO: Done with hot pixel readout
[16:09:54.776] INFO: PixTest:: pg_setup set to default.
[16:09:54.777] INFO: 0 hot pixels found in step 0
[16:09:54.782] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:09:54.873] INFO: PixTest::trimHotPixels() done
[16:09:54.873] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C0.dat
[16:09:54.885] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C1.dat
[16:09:54.890] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C2.dat
[16:09:54.895] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C3.dat
[16:09:54.901] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C4.dat
[16:09:54.906] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C5.dat
[16:09:54.911] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C6.dat
[16:09:54.916] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C7.dat
[16:09:54.921] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C8.dat
[16:09:54.927] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C9.dat
[16:09:54.932] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C10.dat
[16:09:54.937] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C11.dat
[16:09:54.942] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C12.dat
[16:09:54.947] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C13.dat
[16:09:54.952] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C14.dat
[16:09:54.958] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C15.dat
[16:09:54.963] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-1-06_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//defaultMaskFile.dat
[16:09:54.979] INFO: enter test to run
[16:10:11.813] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[16:10:11.813] INFO: running: xray
[16:10:11.814] INFO: ----------------------------------------------------------------------
[16:10:11.814] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:10:11.814] INFO: ----------------------------------------------------------------------
[16:10:12.776] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:10:24.053] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[16:10:53.449] INFO: Resuming triggers.
[16:11:04.728] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[16:11:34.235] INFO: Resuming triggers.
[16:11:45.509] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[16:12:14.882] INFO: Resuming triggers.
[16:12:26.157] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[16:12:55.552] INFO: Resuming triggers.
[16:13:06.828] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[16:13:36.276] INFO: Resuming triggers.
[16:13:47.552] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[16:14:16.996] INFO: Resuming triggers.
[16:14:28.273] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[16:14:57.662] INFO: Resuming triggers.
[16:15:08.942] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[16:15:38.291] INFO: Resuming triggers.
[16:15:48.381] INFO: data taking finished, elapsed time: 100 seconds.
[16:16:14.908] INFO: PixTest:: pg_setup set to default.
[16:16:14.911] INFO: PixTestXray::doPhRun() done
[16:16:15.095] INFO: enter test to run
[16:16:33.178] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[16:16:33.178] INFO: running: xray
[16:16:33.179] INFO: ----------------------------------------------------------------------
[16:16:33.179] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:16:33.179] INFO: ----------------------------------------------------------------------
[16:16:34.143] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:16:40.704] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[16:17:10.691] INFO: Resuming triggers.
[16:17:17.257] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[16:17:47.376] INFO: Resuming triggers.
[16:17:53.938] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[16:18:23.976] INFO: Resuming triggers.
[16:18:30.542] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[16:19:00.418] INFO: Resuming triggers.
[16:19:06.981] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[16:19:37.017] INFO: Resuming triggers.
[16:19:43.578] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[16:20:13.583] INFO: Resuming triggers.
[16:20:20.147] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[16:20:50.208] INFO: Resuming triggers.
[16:20:56.773] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[16:21:26.775] INFO: Resuming triggers.
[16:21:33.335] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[16:22:03.290] INFO: Resuming triggers.
[16:22:09.849] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[16:22:39.835] INFO: Resuming triggers.
[16:22:46.393] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[16:23:16.504] INFO: Resuming triggers.
[16:23:23.064] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[16:23:53.046] INFO: Resuming triggers.
[16:23:59.607] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[16:24:29.642] INFO: Resuming triggers.
[16:24:36.205] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[16:25:06.259] INFO: Resuming triggers.
[16:25:12.820] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[16:25:42.784] INFO: Resuming triggers.
[16:25:44.661] INFO: data taking finished, elapsed time: 100 seconds.
[16:25:53.561] INFO: PixTest:: pg_setup set to default.
[16:25:53.564] INFO: PixTestXray::doPhRun() done
[16:25:53.714] INFO: enter test to run
[16:27:53.629] INFO: test: HighRate no parameter change
[16:27:53.629] INFO: running: highrate
[16:27:53.630] INFO: ----------------------------------------------------------------------
[16:27:53.630] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:27:53.630] INFO: ----------------------------------------------------------------------
[16:27:53.770] INFO: Expecting 768 events.
[16:27:54.904] INFO: 768 events read in total (418ms).
[16:27:54.905] INFO: Test took 1269ms.
[16:27:55.708] INFO: Expecting 41600 events.
[16:27:58.815] INFO: 41600 events read in total (2580ms).
[16:27:58.816] INFO: Test took 3905ms.
[16:27:58.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:59.576] INFO: Expecting 41600 events.
[16:28:02.776] INFO: 41600 events read in total (2674ms).
[16:28:02.777] INFO: Test took 3909ms.
[16:28:02.811] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:03.533] INFO: Expecting 41600 events.
[16:28:06.751] INFO: 41600 events read in total (2691ms).
[16:28:06.752] INFO: Test took 3923ms.
[16:28:06.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:07.510] INFO: Expecting 41600 events.
[16:28:10.746] INFO: 41600 events read in total (2709ms).
[16:28:10.747] INFO: Test took 3943ms.
[16:28:10.781] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:11.503] INFO: Expecting 41600 events.
[16:28:14.732] INFO: 41600 events read in total (2702ms).
[16:28:14.733] INFO: Test took 3934ms.
[16:28:14.768] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:15.493] INFO: Expecting 41600 events.
[16:28:18.725] INFO: 41600 events read in total (2707ms).
[16:28:18.727] INFO: Test took 3941ms.
[16:28:18.760] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:19.483] INFO: Expecting 41600 events.
[16:28:22.723] INFO: 41600 events read in total (2713ms).
[16:28:22.724] INFO: Test took 3946ms.
[16:28:22.758] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:23.480] INFO: Expecting 41600 events.
[16:28:26.718] INFO: 41600 events read in total (2711ms).
[16:28:26.719] INFO: Test took 3943ms.
[16:28:26.753] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:27.475] INFO: Expecting 41600 events.
[16:28:30.716] INFO: 41600 events read in total (2714ms).
[16:28:30.717] INFO: Test took 3945ms.
[16:28:30.751] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:31.476] INFO: Expecting 41600 events.
[16:28:34.714] INFO: 41600 events read in total (2711ms).
[16:28:34.715] INFO: Test took 3947ms.
[16:28:34.749] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:35.475] INFO: Expecting 41600 events.
[16:28:38.719] INFO: 41600 events read in total (2717ms).
[16:28:38.720] INFO: Test took 3952ms.
[16:28:38.755] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:39.478] INFO: Expecting 41600 events.
[16:28:42.717] INFO: 41600 events read in total (2712ms).
[16:28:42.718] INFO: Test took 3945ms.
[16:28:42.752] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:43.477] INFO: Expecting 41600 events.
[16:28:46.724] INFO: 41600 events read in total (2720ms).
[16:28:46.725] INFO: Test took 3954ms.
[16:28:46.759] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:47.484] INFO: Expecting 41600 events.
[16:28:50.722] INFO: 41600 events read in total (2711ms).
[16:28:50.723] INFO: Test took 3945ms.
[16:28:50.757] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:51.483] INFO: Expecting 41600 events.
[16:28:54.725] INFO: 41600 events read in total (2716ms).
[16:28:54.726] INFO: Test took 3951ms.
[16:28:54.761] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:55.481] INFO: Expecting 41600 events.
[16:28:58.723] INFO: 41600 events read in total (2715ms).
[16:28:58.724] INFO: Test took 3944ms.
[16:28:58.758] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:59.480] INFO: Expecting 41600 events.
[16:29:02.719] INFO: 41600 events read in total (2712ms).
[16:29:02.720] INFO: Test took 3943ms.
[16:29:02.754] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:03.476] INFO: Expecting 41600 events.
[16:29:06.702] INFO: 41600 events read in total (2699ms).
[16:29:06.703] INFO: Test took 3932ms.
[16:29:06.736] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:07.460] INFO: Expecting 41600 events.
[16:29:10.676] INFO: 41600 events read in total (2689ms).
[16:29:10.677] INFO: Test took 3923ms.
[16:29:10.712] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:11.436] INFO: Expecting 41600 events.
[16:29:14.536] INFO: 41600 events read in total (2573ms).
[16:29:14.537] INFO: Test took 3806ms.
[16:29:14.570] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:14.934] INFO: enter test to run
[16:30:02.012] INFO: test: HighRate no parameter change
[16:30:02.012] INFO: running: highrate
[16:30:02.013] INFO: ----------------------------------------------------------------------
[16:30:02.013] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:30:02.013] INFO: ----------------------------------------------------------------------
[16:30:02.631] INFO: Expecting 208000 events.
[16:30:14.497] INFO: 208000 events read in total (11339ms).
[16:30:14.500] INFO: Test took 12478ms.
[16:30:14.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:14.896] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[16:30:14.897] INFO: number of red-efficiency pixels: 58 54 81 105 141 167 155 131 86 126 139 76 87 55 15 36
[16:30:14.897] INFO: number of X-ray hits detected: 58831 43741 67036 100134 105064 119575 125389 88558 88274 106603 99256 79635 79331 49860 20711 25683
[16:30:14.897] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:30:14.897] INFO: number of Vcal hits detected: 207942 207946 207919 207845 207856 207829 207839 207864 207912 207871 207859 207923 207910 207945 207984 207964
[16:30:14.897] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[16:30:14.897] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[16:30:14.897] INFO: X-ray hit rate [MHz/cm2]: 17.2 12.8 19.6 29.4 30.8 35.0 36.8 26.0 25.9 31.2 29.1 23.3 23.3 14.6 6.1 7.5
[16:30:14.897] INFO: PixTestHighRate::doXPixelAlive() done
[16:30:14.947] INFO: PixTest:: pg_setup set to default.
[16:30:14.962] INFO: enter test to run
[16:30:29.476] INFO: test: HighRate no parameter change
[16:30:29.476] INFO: running: highrate
[16:30:29.477] INFO: ----------------------------------------------------------------------
[16:30:29.477] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:30:29.477] INFO: ----------------------------------------------------------------------
[16:30:30.096] INFO: Expecting 208000 events.
[16:30:43.661] INFO: 208000 events read in total (13038ms).
[16:30:43.666] INFO: Test took 14180ms.
[16:30:43.969] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:44.275] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[16:30:44.275] INFO: number of red-efficiency pixels: 154 148 309 368 464 588 543 424 315 412 476 255 309 161 62 63
[16:30:44.275] INFO: number of X-ray hits detected: 124935 92955 141784 213048 223618 253563 265499 188726 187881 225485 211455 169660 168705 105856 43795 54612
[16:30:44.275] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:30:44.275] INFO: number of Vcal hits detected: 207845 207839 207669 207564 207505 207360 207404 207530 207662 207552 207485 207732 207683 207833 207935 207936
[16:30:44.275] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.7 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[16:30:44.276] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.7 99.7 99.8 99.8 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[16:30:44.276] INFO: X-ray hit rate [MHz/cm2]: 36.6 27.2 41.6 62.4 65.5 74.3 77.8 55.3 55.1 66.1 62.0 49.7 49.4 31.0 12.8 16.0
[16:30:44.276] INFO: PixTestHighRate::doXPixelAlive() done
[16:30:44.327] INFO: PixTest:: pg_setup set to default.
[16:30:44.341] INFO: enter test to run
[16:31:04.444] INFO: test: HighRate no parameter change
[16:31:04.444] INFO: running: highrate
[16:31:04.445] INFO: ----------------------------------------------------------------------
[16:31:04.445] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:31:04.445] INFO: ----------------------------------------------------------------------
[16:31:05.065] INFO: Expecting 208000 events.
[16:31:20.731] INFO: 208000 events read in total (15139ms).
[16:31:20.739] INFO: Test took 16285ms.
[16:31:21.202] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:21.554] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[16:31:21.554] INFO: number of red-efficiency pixels: 304 283 685 744 1147 1459 1248 955 637 937 1081 476 690 305 73 104
[16:31:21.554] INFO: number of X-ray hits detected: 189077 139978 213521 321539 335179 380991 398645 284041 282595 340833 316776 255018 253776 158463 65733 81993
[16:31:21.554] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:31:21.554] INFO: number of Vcal hits detected: 207675 207690 207060 207068 206531 205981 206369 206693 207292 206858 206655 207474 207211 207663 207927 207893
[16:31:21.554] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.6 99.6 99.4 99.1 99.3 99.4 99.7 99.5 99.4 99.8 99.7 99.9 100.0 99.9
[16:31:21.554] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.5 99.6 99.3 99.0 99.2 99.4 99.7 99.5 99.4 99.7 99.6 99.8 100.0 99.9
[16:31:21.554] INFO: X-ray hit rate [MHz/cm2]: 55.4 41.0 62.6 94.2 98.2 111.7 116.8 83.3 82.8 99.9 92.8 74.7 74.4 46.4 19.3 24.0
[16:31:21.554] INFO: PixTestHighRate::doXPixelAlive() done
[16:31:21.603] INFO: PixTest:: pg_setup set to default.
[16:31:21.615] INFO: enter test to run
[16:31:42.803] INFO: test: exit no parameter change
[16:31:43.130] QUIET: Connection to board 32 closed.
[16:31:43.132] INFO: pXar: this is the end, my friend