[13:42:34.442] INFO: *** Welcome to pxar ***
[13:42:34.443] INFO: *** Today: 2016/04/15
[13:42:34.463] INFO: *** Version: v1.9.0-796-gef167-dirty
[13:42:34.463] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//dacParameters35_C15.dat
[13:42:34.464] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:42:34.464] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//defaultMaskFile.dat
[13:42:34.464] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C15.dat
[13:42:34.532] INFO: clk: 4
[13:42:34.532] INFO: ctr: 4
[13:42:34.532] INFO: sda: 19
[13:42:34.532] INFO: tin: 9
[13:42:34.532] INFO: level: 15
[13:42:34.532] INFO: triggerdelay: 0
[13:42:34.532] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[13:42:34.532] INFO: Log level: INFO
[13:42:34.546] QUIET: Connection to board DTB_WREKRL opened.
[13:42:34.549] 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:42:34.552] INFO: RPC call hashes of host and DTB match: 398089610
[13:42:36.080] INFO: DUT info:
[13:42:36.080] INFO: The DUT currently contains the following objects:
[13:42:36.080] INFO: 2 TBM Cores tbm08c (2 ON)
[13:42:36.080] INFO: TBM Core alpha (0): 7 registers set
[13:42:36.080] INFO: TBM Core beta (1): 7 registers set
[13:42:36.080] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:42:36.080] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.080] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:36.483] INFO: enter 'restricted' command line mode
[13:42:36.483] INFO: enter test to run
[13:43:11.317] INFO: test: PixelAlive no parameter change
[13:43:11.317] INFO: running: pixelalive
[13:43:11.326] INFO: ----------------------------------------------------------------------
[13:43:11.326] 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:43:11.326] INFO: ----------------------------------------------------------------------
[13:43:11.646] INFO: Expecting 41600 events.
[13:43:15.966] INFO: 41600 events read in total (3602ms).
[13:43:16.134] INFO: Test took 4804ms.
[13:43:16.147] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:16.414] INFO: PixTestAlive::aliveTest() done
[13:43:16.414] INFO: number of dead pixels (per ROC): 0 0 0 0 0 3 0 0 0 0 0 0 0 0 0 0
[13:43:16.444] INFO: enter test to run
[13:45:04.596] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:45:04.596] INFO: running: highrate
[13:45:04.596] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:45:04.746] INFO: ----------------------------------------------------------------------
[13:45:04.746] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:45:04.746] INFO: ----------------------------------------------------------------------
[13:45:04.746] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:45:04.746] INFO: edge/corner pixel THR is adjusted
[13:45:04.746] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:45:05.705] INFO: Collecting data for 5 seconds...
[13:45:10.722] INFO: Done with hot pixel readout
[13:45:22.456] INFO: PixTest:: pg_setup set to default.
[13:45:22.457] INFO: 1 hot pixels found in step 0
[13:45:23.445] INFO: Collecting data for 5 seconds...
[13:45:28.463] INFO: Done with hot pixel readout
[13:45:39.830] INFO: PixTest:: pg_setup set to default.
[13:45:39.831] INFO: 0 hot pixels found in step 1
[13:45:39.865] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:45:39.869] INFO: PixTest::trimHotPixels() done
[13:45:39.869] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C0.dat
[13:45:39.875] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C1.dat
[13:45:39.882] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C2.dat
[13:45:39.887] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C3.dat
[13:45:39.892] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C4.dat
[13:45:39.897] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C5.dat
[13:45:39.902] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C6.dat
[13:45:39.908] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C7.dat
[13:45:39.913] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C8.dat
[13:45:39.918] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C9.dat
[13:45:39.924] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C10.dat
[13:45:39.929] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C11.dat
[13:45:39.934] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C12.dat
[13:45:39.939] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C13.dat
[13:45:39.945] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C14.dat
[13:45:39.950] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C15.dat
[13:45:39.955] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//defaultMaskFile.dat
[13:45:39.965] INFO: enter test to run
[13:46:34.891] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:46:34.891] INFO: running: highrate
[13:46:34.895] INFO: ----------------------------------------------------------------------
[13:46:34.895] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:46:34.895] INFO: ----------------------------------------------------------------------
[13:46:34.895] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:46:34.896] INFO: edge/corner pixel THR is adjusted
[13:46:34.896] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:46:35.858] INFO: Collecting data for 1 seconds...
[13:46:36.861] INFO: Done with hot pixel readout
[13:46:40.941] INFO: PixTest:: pg_setup set to default.
[13:46:40.941] INFO: 0 hot pixels found in step 0
[13:46:40.947] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:46:41.036] INFO: PixTest::trimHotPixels() done
[13:46:41.036] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C0.dat
[13:46:41.046] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C1.dat
[13:46:41.051] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C2.dat
[13:46:41.057] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C3.dat
[13:46:41.062] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C4.dat
[13:46:41.067] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C5.dat
[13:46:41.072] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C6.dat
[13:46:41.078] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C7.dat
[13:46:41.083] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C8.dat
[13:46:41.088] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C9.dat
[13:46:41.094] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C10.dat
[13:46:41.099] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C11.dat
[13:46:41.104] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C12.dat
[13:46:41.111] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C13.dat
[13:46:41.117] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C14.dat
[13:46:41.122] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//trimParameters35_C15.dat
[13:46:41.127] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-H-2-50_FPIXTest-17C-Nebraska-160324-0936_2016-03-24_09h37m_1458830221/000_FPIXTest_p17//defaultMaskFile.dat
[13:46:41.137] INFO: enter test to run
[13:47:05.930] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:47:05.930] INFO: running: xray
[13:47:05.931] INFO: ----------------------------------------------------------------------
[13:47:05.931] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:47:05.931] INFO: ----------------------------------------------------------------------
[13:47:06.892] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:47:18.482] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:47:47.232] INFO: Resuming triggers.
[13:47:58.819] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:48:27.994] INFO: Resuming triggers.
[13:48:39.580] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:49:09.027] INFO: Resuming triggers.
[13:49:20.616] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[13:49:49.990] INFO: Resuming triggers.
[13:50:01.584] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[13:50:30.880] INFO: Resuming triggers.
[13:50:42.465] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[13:51:11.828] INFO: Resuming triggers.
[13:51:23.422] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[13:51:52.789] INFO: Resuming triggers.
[13:52:04.381] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[13:52:33.779] INFO: Resuming triggers.
[13:52:41.383] INFO: data taking finished, elapsed time: 100 seconds.
[13:53:00.873] INFO: PixTest:: pg_setup set to default.
[13:53:00.876] INFO: PixTestXray::doPhRun() done
[13:53:01.046] INFO: enter test to run
[13:53:23.624] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:53:23.624] INFO: running: xray
[13:53:23.625] INFO: ----------------------------------------------------------------------
[13:53:23.625] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:53:23.625] INFO: ----------------------------------------------------------------------
[13:53:24.589] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:53:31.207] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:54:01.350] INFO: Resuming triggers.
[13:54:07.972] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[13:54:38.108] INFO: Resuming triggers.
[13:54:44.731] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[13:55:14.789] INFO: Resuming triggers.
[13:55:21.410] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[13:55:51.669] INFO: Resuming triggers.
[13:55:58.291] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[13:56:28.334] INFO: Resuming triggers.
[13:56:34.957] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[13:57:05.005] INFO: Resuming triggers.
[13:57:11.629] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[13:57:37.374] INFO: Resuming triggers.
[13:57:43.000] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[13:58:14.208] INFO: Resuming triggers.
[13:58:20.834] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[13:58:50.942] INFO: Resuming triggers.
[13:58:57.568] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[13:59:27.717] INFO: Resuming triggers.
[13:59:34.342] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[14:00:04.487] INFO: Resuming triggers.
[14:00:11.109] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[14:00:41.257] INFO: Resuming triggers.
[14:00:47.886] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[14:01:17.958] INFO: Resuming triggers.
[14:01:24.583] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[14:01:54.666] INFO: Resuming triggers.
[14:02:01.288] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[14:02:31.395] INFO: Resuming triggers.
[14:02:32.374] INFO: data taking finished, elapsed time: 100 seconds.
[14:02:37.182] INFO: PixTest:: pg_setup set to default.
[14:02:37.185] INFO: PixTestXray::doPhRun() done
[14:02:37.333] INFO: enter test to run
[14:03:14.468] INFO: test: HighRate no parameter change
[14:03:14.468] INFO: running: highrate
[14:03:14.469] INFO: ----------------------------------------------------------------------
[14:03:14.469] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:03:14.469] INFO: ----------------------------------------------------------------------
[14:03:14.609] INFO: Expecting 768 events.
[14:03:15.743] INFO: 768 events read in total (419ms).
[14:03:15.743] INFO: Test took 1268ms.
[14:03:16.546] INFO: Expecting 41600 events.
[14:03:19.660] INFO: 41600 events read in total (2587ms).
[14:03:19.661] INFO: Test took 3910ms.
[14:03:19.694] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:20.424] INFO: Expecting 41600 events.
[14:03:23.641] INFO: 41600 events read in total (2690ms).
[14:03:23.642] INFO: Test took 3932ms.
[14:03:23.675] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:24.398] INFO: Expecting 41600 events.
[14:03:27.641] INFO: 41600 events read in total (2717ms).
[14:03:27.642] INFO: Test took 3948ms.
[14:03:27.676] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:28.401] INFO: Expecting 41600 events.
[14:03:31.647] INFO: 41600 events read in total (2719ms).
[14:03:31.648] INFO: Test took 3953ms.
[14:03:31.681] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:32.407] INFO: Expecting 41600 events.
[14:03:35.630] INFO: 41600 events read in total (2697ms).
[14:03:35.631] INFO: Test took 3931ms.
[14:03:35.664] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:36.387] INFO: Expecting 41600 events.
[14:03:39.618] INFO: 41600 events read in total (2704ms).
[14:03:39.619] INFO: Test took 3936ms.
[14:03:39.653] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:40.379] INFO: Expecting 41600 events.
[14:03:43.623] INFO: 41600 events read in total (2718ms).
[14:03:43.624] INFO: Test took 3953ms.
[14:03:43.658] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:44.382] INFO: Expecting 41600 events.
[14:03:47.620] INFO: 41600 events read in total (2711ms).
[14:03:47.621] INFO: Test took 3945ms.
[14:03:47.654] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:48.380] INFO: Expecting 41600 events.
[14:03:51.627] INFO: 41600 events read in total (2720ms).
[14:03:51.628] INFO: Test took 3955ms.
[14:03:51.662] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:52.387] INFO: Expecting 41600 events.
[14:03:55.638] INFO: 41600 events read in total (2724ms).
[14:03:55.639] INFO: Test took 3959ms.
[14:03:55.673] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:56.400] INFO: Expecting 41600 events.
[14:03:59.631] INFO: 41600 events read in total (2704ms).
[14:03:59.632] INFO: Test took 3940ms.
[14:03:59.666] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:00.388] INFO: Expecting 41600 events.
[14:04:03.618] INFO: 41600 events read in total (2703ms).
[14:04:03.619] INFO: Test took 3936ms.
[14:04:03.653] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:04.379] INFO: Expecting 41600 events.
[14:04:07.621] INFO: 41600 events read in total (2715ms).
[14:04:07.622] INFO: Test took 3952ms.
[14:04:07.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:08.378] INFO: Expecting 41600 events.
[14:04:11.622] INFO: 41600 events read in total (2717ms).
[14:04:11.623] INFO: Test took 3949ms.
[14:04:11.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:12.382] INFO: Expecting 41600 events.
[14:04:15.613] INFO: 41600 events read in total (2705ms).
[14:04:15.614] INFO: Test took 3940ms.
[14:04:15.648] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:16.370] INFO: Expecting 41600 events.
[14:04:19.597] INFO: 41600 events read in total (2700ms).
[14:04:19.598] INFO: Test took 3932ms.
[14:04:19.632] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:20.354] INFO: Expecting 41600 events.
[14:04:23.590] INFO: 41600 events read in total (2709ms).
[14:04:23.592] INFO: Test took 3943ms.
[14:04:23.627] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:24.354] INFO: Expecting 41600 events.
[14:04:27.591] INFO: 41600 events read in total (2710ms).
[14:04:27.592] INFO: Test took 3946ms.
[14:04:27.626] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:28.350] INFO: Expecting 41600 events.
[14:04:31.540] INFO: 41600 events read in total (2663ms).
[14:04:31.541] INFO: Test took 3898ms.
[14:04:31.575] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:32.302] INFO: Expecting 41600 events.
[14:04:35.353] INFO: 41600 events read in total (2524ms).
[14:04:35.354] INFO: Test took 3761ms.
[14:04:35.386] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:35.753] INFO: enter test to run
[14:04:42.139] INFO: test: HighRate no parameter change
[14:04:42.139] INFO: running: highrate
[14:04:42.140] INFO: ----------------------------------------------------------------------
[14:04:42.140] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:04:42.140] INFO: ----------------------------------------------------------------------
[14:04:42.754] INFO: Expecting 208000 events.
[14:04:54.569] INFO: 208000 events read in total (11288ms).
[14:04:54.572] INFO: Test took 12423ms.
[14:04:54.712] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:54.961] INFO: number of dead pixels (per ROC): 0 0 0 0 0 3 0 0 0 0 0 0 0 0 0 0
[14:04:54.961] INFO: number of red-efficiency pixels: 70 58 93 154 141 149 150 89 66 116 112 87 112 43 23 33
[14:04:54.961] INFO: number of X-ray hits detected: 64945 43077 63301 102549 112917 115064 119550 80352 71480 99188 93565 79555 83339 48271 22270 23318
[14:04:54.961] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:04:54.961] INFO: number of Vcal hits detected: 207929 207940 207905 207840 207857 207701 207843 207909 207934 207881 207883 207912 207887 207956 207977 207967
[14:04:54.961] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0 99.9 100.0 100.0 100.0
[14:04:54.961] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[14:04:54.961] INFO: X-ray hit rate [MHz/cm2]: 19.0 12.6 18.6 30.1 33.1 33.7 35.0 23.6 21.0 29.1 27.4 23.3 24.4 14.1 6.5 6.8
[14:04:54.961] INFO: PixTestHighRate::doXPixelAlive() done
[14:04:55.009] INFO: PixTest:: pg_setup set to default.
[14:04:55.019] INFO: enter test to run
[14:05:30.339] INFO: test: HighRate no parameter change
[14:05:30.339] INFO: running: highrate
[14:05:30.340] INFO: ----------------------------------------------------------------------
[14:05:30.340] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:05:30.340] INFO: ----------------------------------------------------------------------
[14:05:30.950] INFO: Expecting 208000 events.
[14:05:44.350] INFO: 208000 events read in total (12873ms).
[14:05:44.356] INFO: Test took 14008ms.
[14:05:44.644] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:44.944] INFO: number of dead pixels (per ROC): 0 0 0 0 0 3 0 0 0 0 0 0 0 0 0 0
[14:05:44.944] INFO: number of red-efficiency pixels: 221 136 261 486 484 444 522 322 193 349 325 258 330 172 58 67
[14:05:44.944] INFO: number of X-ray hits detected: 137429 91839 133562 217319 238993 241846 251206 170654 152031 210983 197712 168569 176682 102681 47584 49954
[14:05:44.944] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:05:44.944] INFO: number of Vcal hits detected: 207752 207859 207717 207480 207476 207373 207427 207655 207800 207625 207658 207731 207658 207819 207941 207932
[14:05:44.944] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[14:05:44.944] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.7 99.8 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[14:05:44.944] INFO: X-ray hit rate [MHz/cm2]: 40.3 26.9 39.1 63.7 70.1 70.9 73.6 50.0 44.6 61.8 58.0 49.4 51.8 30.1 13.9 14.6
[14:05:44.944] INFO: PixTestHighRate::doXPixelAlive() done
[14:05:44.993] INFO: PixTest:: pg_setup set to default.
[14:05:45.008] INFO: enter test to run
[14:06:07.026] INFO: test: HighRate no parameter change
[14:06:07.027] INFO: running: highrate
[14:06:07.028] INFO: ----------------------------------------------------------------------
[14:06:07.028] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:06:07.028] INFO: ----------------------------------------------------------------------
[14:06:07.643] INFO: Expecting 208000 events.
[14:06:23.272] INFO: 208000 events read in total (15102ms).
[14:06:23.280] INFO: Test took 16244ms.
[14:06:23.747] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:24.099] INFO: number of dead pixels (per ROC): 0 0 0 0 0 3 0 0 0 0 0 0 0 0 0 0
[14:06:24.099] INFO: number of red-efficiency pixels: 416 302 627 1127 1176 1118 1168 703 441 769 728 555 792 412 103 107
[14:06:24.099] INFO: number of X-ray hits detected: 211941 140543 205573 330784 366634 372292 385247 262682 232769 322751 304567 259188 270366 157127 73160 76875
[14:06:24.099] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:06:24.099] INFO: number of Vcal hits detected: 207498 207674 207158 206559 206525 206458 206521 207099 207509 207102 207152 207372 207106 207526 207891 207891
[14:06:24.099] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.6 99.4 99.4 99.4 99.4 99.6 99.8 99.6 99.6 99.7 99.6 99.8 99.9 100.0
[14:06:24.099] INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.6 99.3 99.3 99.3 99.3 99.6 99.8 99.6 99.6 99.7 99.6 99.8 99.9 99.9
[14:06:24.099] INFO: X-ray hit rate [MHz/cm2]: 62.1 41.2 60.3 97.0 107.5 109.1 112.9 77.0 68.2 94.6 89.3 76.0 79.2 46.1 21.4 22.5
[14:06:24.099] INFO: PixTestHighRate::doXPixelAlive() done
[14:06:24.146] INFO: PixTest:: pg_setup set to default.
[14:06:24.164] INFO: enter test to run
[14:06:30.242] INFO: test: exit no parameter change
[14:06:30.591] QUIET: Connection to board 33 closed.
[14:06:30.593] INFO: pXar: this is the end, my friend