[08:16:20.024] INFO: *** Welcome to pxar ***
[08:16:20.024] INFO: *** Today: 2016/09/22
[08:16:27.272] INFO: *** Version: v1.9.0-818-g96727
[08:16:27.272] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//dacParameters35_C15.dat
[08:16:27.784] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//tbmParameters_C0b.dat
[08:16:27.784] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//defaultMaskFile.dat
[08:16:27.835] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C15.dat
[08:16:29.574] INFO: clk: 4
[08:16:29.574] INFO: ctr: 4
[08:16:29.574] INFO: sda: 19
[08:16:29.574] INFO: tin: 9
[08:16:29.574] INFO: level: 15
[08:16:29.575] INFO: triggerdelay: 0
[08:16:29.575] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[08:16:29.575] INFO: Log level: INFO
[08:16:29.593] QUIET: Connection to board DTB_WREKRL opened.
[08:16:29.597] 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:
------------------------------------------------------
[08:16:29.600] INFO: RPC call hashes of host and DTB match: 398089610
[08:16:31.180] INFO: DUT info:
[08:16:31.180] INFO: The DUT currently contains the following objects:
[08:16:31.180] INFO: 2 TBM Cores tbm08c (2 ON)
[08:16:31.180] INFO: TBM Core alpha (0): 7 registers set
[08:16:31.180] INFO: TBM Core beta (1): 7 registers set
[08:16:31.180] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:16:31.180] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.180] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.180] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.180] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.180] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.180] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.180] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.180] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.181] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.181] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.181] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.181] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.181] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.181] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.181] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.181] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:16:31.816] INFO: enter 'restricted' command line mode
[08:16:31.816] INFO: enter test to run
[08:16:40.980] INFO: test: PixelAlive no parameter change
[08:16:40.981] INFO: running: pixelalive
[08:16:40.006] INFO: ----------------------------------------------------------------------
[08:16:40.006] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:16:40.006] INFO: ----------------------------------------------------------------------
[08:16:41.323] INFO: Expecting 41600 events.
[08:16:45.602] INFO: 41600 events read in total (3561ms).
[08:16:45.767] INFO: Test took 4759ms.
[08:16:45.781] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:46.047] INFO: PixTestAlive::aliveTest() done
[08:16:46.047] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:16:46.078] INFO: enter test to run
[08:17:31.492] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[08:17:31.492] INFO: running: highrate
[08:17:31.492] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[08:17:33.235] INFO: ----------------------------------------------------------------------
[08:17:33.236] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[08:17:33.236] INFO: ----------------------------------------------------------------------
[08:17:33.236] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[08:17:33.236] INFO: edge/corner pixel THR is adjusted
[08:17:33.236] INFO: PixTestHighRate::trimHotPixels: step 0...
[08:17:34.309] INFO: Collecting data for 5 seconds...
[08:17:39.327] INFO: Done with hot pixel readout
[08:17:50.846] INFO: PixTest:: pg_setup set to default.
[08:17:50.847] INFO: 2 hot pixels found in step 0
[08:17:51.835] INFO: Collecting data for 5 seconds...
[08:17:56.852] INFO: Done with hot pixel readout
[08:18:08.202] INFO: PixTest:: pg_setup set to default.
[08:18:08.203] INFO: 1 hot pixels found in step 1
[08:18:09.196] INFO: Collecting data for 5 seconds...
[08:18:14.214] INFO: Done with hot pixel readout
[08:18:25.938] INFO: PixTest:: pg_setup set to default.
[08:18:25.939] INFO: 1 hot pixels found in step 2
[08:18:26.931] INFO: Collecting data for 5 seconds...
[08:18:31.951] INFO: Done with hot pixel readout
[08:18:43.685] INFO: PixTest:: pg_setup set to default.
[08:18:43.686] INFO: 0 hot pixels found in step 3
[08:18:43.723] INFO: 0 hot pixels could not be trimmed and have been masked.
[08:18:43.726] INFO: PixTest::trimHotPixels() done
[08:18:43.765] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C0.dat
[08:18:43.775] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C1.dat
[08:18:43.785] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C2.dat
[08:18:43.793] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C3.dat
[08:18:43.798] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C4.dat
[08:18:43.804] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C5.dat
[08:18:43.809] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C6.dat
[08:18:43.814] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C7.dat
[08:18:43.820] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C8.dat
[08:18:43.825] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C9.dat
[08:18:43.830] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C10.dat
[08:18:43.836] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C11.dat
[08:18:43.841] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C12.dat
[08:18:43.847] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C13.dat
[08:18:43.852] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C14.dat
[08:18:43.857] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C15.dat
[08:18:43.862] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//defaultMaskFile.dat
[08:18:43.876] INFO: enter test to run
[08:20:03.027] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[08:20:03.027] INFO: running: highrate
[08:20:03.032] INFO: ----------------------------------------------------------------------
[08:20:03.032] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[08:20:03.032] INFO: ----------------------------------------------------------------------
[08:20:03.032] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[08:20:03.032] INFO: edge/corner pixel THR is adjusted
[08:20:03.032] INFO: PixTestHighRate::trimHotPixels: step 0...
[08:20:03.990] INFO: Collecting data for 1 seconds...
[08:20:04.994] INFO: Done with hot pixel readout
[08:20:09.033] INFO: PixTest:: pg_setup set to default.
[08:20:09.034] INFO: 0 hot pixels found in step 0
[08:20:09.041] INFO: 0 hot pixels could not be trimmed and have been masked.
[08:20:09.131] INFO: PixTest::trimHotPixels() done
[08:20:09.131] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C0.dat
[08:20:09.143] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C1.dat
[08:20:09.155] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C2.dat
[08:20:09.162] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C3.dat
[08:20:09.168] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C4.dat
[08:20:09.173] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C5.dat
[08:20:09.179] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C6.dat
[08:20:09.184] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C7.dat
[08:20:09.190] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C8.dat
[08:20:09.195] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C9.dat
[08:20:09.201] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C10.dat
[08:20:09.206] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C11.dat
[08:20:09.212] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C12.dat
[08:20:09.217] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C13.dat
[08:20:09.223] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C14.dat
[08:20:09.228] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//trimParameters35_C15.dat
[08:20:09.233] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-29_FPIXTest-17C-FNAL-160915-1235-150V_2016-09-15_12h36m_1473960967/000_FPIXTest_p17//defaultMaskFile.dat
[08:20:09.243] INFO: enter test to run
[08:20:30.115] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[08:20:30.115] INFO: running: xray
[08:20:30.116] INFO: ----------------------------------------------------------------------
[08:20:30.116] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:20:30.116] INFO: ----------------------------------------------------------------------
[08:20:31.079] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:20:42.889] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[08:21:11.895] INFO: Resuming triggers.
[08:21:23.703] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[08:21:53.360] INFO: Resuming triggers.
[08:22:05.170] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[08:22:35.699] INFO: Resuming triggers.
[08:22:47.509] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[08:23:17.759] INFO: Resuming triggers.
[08:23:29.568] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[08:23:58.917] INFO: Resuming triggers.
[08:24:10.728] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[08:24:40.056] INFO: Resuming triggers.
[08:24:51.868] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[08:25:21.219] INFO: Resuming triggers.
[08:25:33.032] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[08:26:02.350] INFO: Resuming triggers.
[08:26:08.224] INFO: data taking finished, elapsed time: 100 seconds.
[08:26:23.063] INFO: PixTest:: pg_setup set to default.
[08:26:23.067] INFO: PixTestXray::doPhRun() done
[08:26:23.254] INFO: enter test to run
[08:27:07.416] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[08:27:07.416] INFO: running: xray
[08:27:07.417] INFO: ----------------------------------------------------------------------
[08:27:07.417] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:27:07.418] INFO: ----------------------------------------------------------------------
[08:27:08.382] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:27:15.521] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[08:27:45.781] INFO: Resuming triggers.
[08:27:52.924] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[08:28:23.262] INFO: Resuming triggers.
[08:28:30.407] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[08:29:00.714] INFO: Resuming triggers.
[08:29:07.860] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[08:29:38.906] INFO: Resuming triggers.
[08:29:46.052] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[08:30:16.836] INFO: Resuming triggers.
[08:30:23.976] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[08:30:54.188] INFO: Resuming triggers.
[08:31:01.331] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[08:31:32.279] INFO: Resuming triggers.
[08:31:39.417] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[08:32:10.260] INFO: Resuming triggers.
[08:32:17.404] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[08:32:48.289] INFO: Resuming triggers.
[08:32:55.427] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[08:33:26.266] INFO: Resuming triggers.
[08:33:33.402] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[08:34:05.103] INFO: Resuming triggers.
[08:34:12.245] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[08:34:43.534] INFO: Resuming triggers.
[08:34:50.674] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[08:35:21.705] INFO: Resuming triggers.
[08:35:28.843] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[08:36:00.066] INFO: Resuming triggers.
[08:36:00.455] INFO: data taking finished, elapsed time: 100 seconds.
[08:36:02.632] INFO: PixTest:: pg_setup set to default.
[08:36:02.635] INFO: PixTestXray::doPhRun() done
[08:36:02.785] INFO: enter test to run
[08:36:29.940] INFO: test: HighRate no parameter change
[08:36:29.941] INFO: running: highrate
[08:36:29.957] INFO: ----------------------------------------------------------------------
[08:36:29.957] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[08:36:29.957] INFO: ----------------------------------------------------------------------
[08:36:30.140] INFO: Expecting 768 events.
[08:36:31.283] INFO: 768 events read in total (428ms).
[08:36:31.284] INFO: Test took 1290ms.
[08:36:32.087] INFO: Expecting 41600 events.
[08:36:35.215] INFO: 41600 events read in total (2601ms).
[08:36:35.216] INFO: Test took 3861ms.
[08:36:35.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:35.974] INFO: Expecting 41600 events.
[08:36:39.223] INFO: 41600 events read in total (2722ms).
[08:36:39.224] INFO: Test took 3958ms.
[08:36:39.257] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:39.980] INFO: Expecting 41600 events.
[08:36:43.247] INFO: 41600 events read in total (2740ms).
[08:36:43.248] INFO: Test took 3974ms.
[08:36:43.280] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:44.009] INFO: Expecting 41600 events.
[08:36:47.229] INFO: 41600 events read in total (2694ms).
[08:36:47.230] INFO: Test took 3933ms.
[08:36:47.262] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:47.989] INFO: Expecting 41600 events.
[08:36:51.215] INFO: 41600 events read in total (2699ms).
[08:36:51.216] INFO: Test took 3937ms.
[08:36:51.248] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:51.978] INFO: Expecting 41600 events.
[08:36:55.196] INFO: 41600 events read in total (2692ms).
[08:36:55.197] INFO: Test took 3932ms.
[08:36:55.228] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:55.956] INFO: Expecting 41600 events.
[08:36:59.172] INFO: 41600 events read in total (2690ms).
[08:36:59.173] INFO: Test took 3927ms.
[08:36:59.207] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:59.934] INFO: Expecting 41600 events.
[08:37:03.167] INFO: 41600 events read in total (2706ms).
[08:37:03.168] INFO: Test took 3944ms.
[08:37:03.200] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:03.930] INFO: Expecting 41600 events.
[08:37:07.161] INFO: 41600 events read in total (2705ms).
[08:37:07.162] INFO: Test took 3944ms.
[08:37:07.194] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:07.919] INFO: Expecting 41600 events.
[08:37:11.144] INFO: 41600 events read in total (2698ms).
[08:37:11.145] INFO: Test took 3932ms.
[08:37:11.178] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:11.902] INFO: Expecting 41600 events.
[08:37:15.131] INFO: 41600 events read in total (2702ms).
[08:37:15.132] INFO: Test took 3936ms.
[08:37:15.164] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:15.893] INFO: Expecting 41600 events.
[08:37:19.126] INFO: 41600 events read in total (2706ms).
[08:37:19.127] INFO: Test took 3944ms.
[08:37:19.159] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:19.887] INFO: Expecting 41600 events.
[08:37:23.102] INFO: 41600 events read in total (2688ms).
[08:37:23.103] INFO: Test took 3926ms.
[08:37:23.135] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:23.865] INFO: Expecting 41600 events.
[08:37:27.083] INFO: 41600 events read in total (2691ms).
[08:37:27.084] INFO: Test took 3931ms.
[08:37:27.116] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:27.845] INFO: Expecting 41600 events.
[08:37:31.068] INFO: 41600 events read in total (2696ms).
[08:37:31.069] INFO: Test took 3936ms.
[08:37:31.101] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:31.831] INFO: Expecting 41600 events.
[08:37:35.061] INFO: 41600 events read in total (2703ms).
[08:37:35.061] INFO: Test took 3943ms.
[08:37:35.094] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:35.820] INFO: Expecting 41600 events.
[08:37:39.048] INFO: 41600 events read in total (2701ms).
[08:37:39.049] INFO: Test took 3937ms.
[08:37:39.081] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:39.811] INFO: Expecting 41600 events.
[08:37:43.032] INFO: 41600 events read in total (2694ms).
[08:37:43.033] INFO: Test took 3935ms.
[08:37:43.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:43.796] INFO: Expecting 41600 events.
[08:37:46.991] INFO: 41600 events read in total (2669ms).
[08:37:46.992] INFO: Test took 3908ms.
[08:37:47.024] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:47.753] INFO: Expecting 41600 events.
[08:37:50.800] INFO: 41600 events read in total (2521ms).
[08:37:50.801] INFO: Test took 3760ms.
[08:37:50.832] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:51.194] INFO: enter test to run
[08:38:21.948] INFO: test: HighRate no parameter change
[08:38:21.948] INFO: running: highrate
[08:38:21.949] INFO: ----------------------------------------------------------------------
[08:38:21.949] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[08:38:21.949] INFO: ----------------------------------------------------------------------
[08:38:22.567] INFO: Expecting 208000 events.
[08:38:34.292] INFO: 208000 events read in total (11198ms).
[08:38:34.295] INFO: Test took 12338ms.
[08:38:34.430] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:34.679] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:38:34.679] INFO: number of red-efficiency pixels: 67 52 88 123 145 113 137 101 81 121 124 107 97 65 23 22
[08:38:34.679] INFO: number of X-ray hits detected: 58914 38103 62079 99486 107357 104790 102002 75282 74120 89134 92544 76796 80023 46004 19534 25349
[08:38:34.679] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[08:38:34.679] INFO: number of Vcal hits detected: 207932 207947 207910 207875 207848 207881 207860 207898 207918 207878 207875 207891 207902 207934 207977 207978
[08:38:34.679] 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 99.9 100.0 100.0 100.0 100.0 100.0
[08:38:34.679] 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 99.9 100.0 100.0 100.0 100.0
[08:38:34.679] INFO: X-ray hit rate [MHz/cm2]: 17.3 11.2 18.2 29.2 31.5 30.7 29.9 22.1 21.7 26.1 27.1 22.5 23.5 13.5 5.7 7.4
[08:38:34.679] INFO: PixTestHighRate::doXPixelAlive() done
[08:38:34.726] INFO: PixTest:: pg_setup set to default.
[08:38:34.736] INFO: enter test to run
[08:38:51.524] INFO: test: HighRate no parameter change
[08:38:51.524] INFO: running: highrate
[08:38:51.525] INFO: ----------------------------------------------------------------------
[08:38:51.525] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[08:38:51.525] INFO: ----------------------------------------------------------------------
[08:38:52.145] INFO: Expecting 208000 events.
[08:39:05.383] INFO: 208000 events read in total (12711ms).
[08:39:05.388] INFO: Test took 13854ms.
[08:39:05.649] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:05.939] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:39:05.939] INFO: number of red-efficiency pixels: 172 120 271 420 506 343 371 282 222 361 413 273 304 154 54 53
[08:39:05.939] INFO: number of X-ray hits detected: 117567 77004 124696 199719 214993 208580 204099 150088 148744 179158 185428 154316 160237 91911 39182 51256
[08:39:05.939] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[08:39:05.939] INFO: number of Vcal hits detected: 207817 207873 207699 207542 207461 207619 207605 207698 207770 207619 207553 207716 207687 207840 207946 207946
[08:39:05.939] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[08:39:05.939] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[08:39:05.939] INFO: X-ray hit rate [MHz/cm2]: 34.5 22.6 36.5 58.5 63.0 61.1 59.8 44.0 43.6 52.5 54.4 45.2 47.0 26.9 11.5 15.0
[08:39:05.939] INFO: PixTestHighRate::doXPixelAlive() done
[08:39:05.988] INFO: PixTest:: pg_setup set to default.
[08:39:05.005] INFO: enter test to run
[08:39:36.163] INFO: test: HighRate no parameter change
[08:39:36.163] INFO: running: highrate
[08:39:36.164] INFO: ----------------------------------------------------------------------
[08:39:36.164] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[08:39:36.164] INFO: ----------------------------------------------------------------------
[08:39:36.783] INFO: Expecting 208000 events.
[08:39:51.802] INFO: 208000 events read in total (14492ms).
[08:39:51.809] INFO: Test took 15636ms.
[08:39:52.213] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:52.549] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:39:52.549] INFO: number of red-efficiency pixels: 329 237 598 1029 1196 809 830 694 422 783 920 648 682 361 84 111
[08:39:52.549] INFO: number of X-ray hits detected: 181231 118043 191850 304390 329621 320360 312915 231256 228808 275731 286059 237031 246460 142229 60533 78564
[08:39:52.549] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[08:39:52.549] INFO: number of Vcal hits detected: 207628 207744 207231 206725 206497 207031 207022 207163 207531 207083 206928 207257 207234 207591 207912 207886
[08:39:52.550] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.3 99.6 99.6 99.6 99.8 99.6 99.5 99.7 99.7 99.8 100.0 99.9
[08:39:52.550] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.4 99.3 99.5 99.5 99.6 99.8 99.6 99.5 99.6 99.6 99.8 100.0 99.9
[08:39:52.550] INFO: X-ray hit rate [MHz/cm2]: 53.1 34.6 56.2 89.2 96.6 93.9 91.7 67.8 67.1 80.8 83.8 69.5 72.2 41.7 17.7 23.0
[08:39:52.550] INFO: PixTestHighRate::doXPixelAlive() done
[08:39:52.599] INFO: PixTest:: pg_setup set to default.
[08:39:52.614] INFO: enter test to run
[08:40:02.435] INFO: test: exit no parameter change
[08:40:02.772] QUIET: Connection to board 33 closed.
[08:40:02.786] INFO: pXar: this is the end, my friend