[12:43:16.059] INFO: *** Welcome to pxar ***
[12:43:16.059] INFO: *** Today: 2016/06/06
[12:43:16.082] INFO: *** Version: v1.9.0-814-g7497
[12:43:16.082] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//dacParameters35_C15.dat
[12:43:16.086] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:43:16.086] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//defaultMaskFile.dat
[12:43:16.086] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C15.dat
[12:43:16.205] INFO: clk: 4
[12:43:16.205] INFO: ctr: 4
[12:43:16.205] INFO: sda: 19
[12:43:16.205] INFO: tin: 9
[12:43:16.205] INFO: level: 15
[12:43:16.205] INFO: triggerdelay: 0
[12:43:16.205] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:43:16.205] INFO: Log level: INFO
[12:43:16.221] QUIET: Connection to board DTB_WREKRL opened.
[12:43:16.224] 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:
------------------------------------------------------
[12:43:16.227] INFO: RPC call hashes of host and DTB match: 398089610
[12:43:17.763] INFO: DUT info:
[12:43:17.763] INFO: The DUT currently contains the following objects:
[12:43:17.763] INFO: 2 TBM Cores tbm08c (2 ON)
[12:43:17.763] INFO: TBM Core alpha (0): 7 registers set
[12:43:17.763] INFO: TBM Core beta (1): 7 registers set
[12:43:17.763] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:43:17.763] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:17.764] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:18.168] INFO: enter 'restricted' command line mode
[12:43:18.168] INFO: enter test to run
[12:43:23.268] INFO: test: PixelAlive no parameter change
[12:43:23.268] INFO: running: pixelalive
[12:43:23.277] INFO: ----------------------------------------------------------------------
[12:43:23.277] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:43:23.278] INFO: ----------------------------------------------------------------------
[12:43:23.599] INFO: Expecting 41600 events.
[12:43:27.994] INFO: 41600 events read in total (3676ms).
[12:43:28.161] INFO: Test took 4881ms.
[12:43:28.174] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:28.437] INFO: PixTestAlive::aliveTest() done
[12:43:28.437] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 5 0 1 1 0 0 1 0
[12:43:28.469] INFO: enter test to run
[12:44:01.028] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:44:01.028] INFO: running: highrate
[12:44:01.029] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:44:01.273] INFO: ----------------------------------------------------------------------
[12:44:01.273] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:44:01.273] INFO: ----------------------------------------------------------------------
[12:44:01.273] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:44:01.273] INFO: edge/corner pixel THR is adjusted
[12:44:01.274] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:44:02.232] INFO: Collecting data for 5 seconds...
[12:44:07.251] INFO: Done with hot pixel readout
[12:44:18.726] INFO: PixTest:: pg_setup set to default.
[12:44:18.727] INFO: 3 hot pixels found in step 0
[12:44:19.713] INFO: Collecting data for 5 seconds...
[12:44:24.733] INFO: Done with hot pixel readout
[12:44:36.005] INFO: PixTest:: pg_setup set to default.
[12:44:36.006] INFO: 2 hot pixels found in step 1
[12:44:36.999] INFO: Collecting data for 5 seconds...
[12:44:42.019] INFO: Done with hot pixel readout
[12:44:53.671] INFO: PixTest:: pg_setup set to default.
[12:44:53.672] INFO: 1 hot pixels found in step 2
[12:44:54.660] INFO: Collecting data for 5 seconds...
[12:44:59.680] INFO: Done with hot pixel readout
[12:45:11.360] INFO: PixTest:: pg_setup set to default.
[12:45:11.361] INFO: 1 hot pixels found in step 3
[12:45:12.351] INFO: Collecting data for 5 seconds...
[12:45:17.371] INFO: Done with hot pixel readout
[12:45:28.958] INFO: PixTest:: pg_setup set to default.
[12:45:28.959] INFO: 0 hot pixels found in step 4
[12:45:28.993] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:45:28.996] INFO: PixTest::trimHotPixels() done
[12:45:28.996] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C0.dat
[12:45:29.002] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C1.dat
[12:45:29.008] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C2.dat
[12:45:29.014] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C3.dat
[12:45:29.019] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C4.dat
[12:45:29.024] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C5.dat
[12:45:29.029] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C6.dat
[12:45:29.035] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C7.dat
[12:45:29.040] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C8.dat
[12:45:29.045] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C9.dat
[12:45:29.050] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C10.dat
[12:45:29.056] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C11.dat
[12:45:29.061] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C12.dat
[12:45:29.066] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C13.dat
[12:45:29.072] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C14.dat
[12:45:29.077] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C15.dat
[12:45:29.082] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//defaultMaskFile.dat
[12:45:29.092] INFO: enter test to run
[12:46:05.827] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:46:05.827] INFO: running: highrate
[12:46:05.832] INFO: ----------------------------------------------------------------------
[12:46:05.832] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:46:05.832] INFO: ----------------------------------------------------------------------
[12:46:05.832] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:46:05.832] INFO: edge/corner pixel THR is adjusted
[12:46:05.832] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:46:06.789] INFO: Collecting data for 1 seconds...
[12:46:07.793] INFO: Done with hot pixel readout
[12:46:11.242] INFO: PixTest:: pg_setup set to default.
[12:46:11.243] INFO: 0 hot pixels found in step 0
[12:46:11.249] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:46:11.327] INFO: PixTest::trimHotPixels() done
[12:46:11.327] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C0.dat
[12:46:11.340] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C1.dat
[12:46:11.350] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C2.dat
[12:46:11.356] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C3.dat
[12:46:11.361] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C4.dat
[12:46:11.366] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C5.dat
[12:46:11.372] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C6.dat
[12:46:11.377] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C7.dat
[12:46:11.382] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C8.dat
[12:46:11.388] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C9.dat
[12:46:11.393] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C10.dat
[12:46:11.398] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C11.dat
[12:46:11.404] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C12.dat
[12:46:11.409] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C13.dat
[12:46:11.414] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C14.dat
[12:46:11.420] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C15.dat
[12:46:11.425] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-1-13_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//defaultMaskFile.dat
[12:46:11.437] INFO: enter test to run
[12:46:37.787] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:46:37.787] INFO: running: xray
[12:46:37.788] INFO: ----------------------------------------------------------------------
[12:46:37.788] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:46:37.788] INFO: ----------------------------------------------------------------------
[12:46:38.766] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:46:50.706] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:47:19.829] INFO: Resuming triggers.
[12:47:31.771] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:48:00.899] INFO: Resuming triggers.
[12:48:12.841] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[12:48:42.007] INFO: Resuming triggers.
[12:48:53.952] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[12:49:23.191] INFO: Resuming triggers.
[12:49:35.129] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[12:50:04.263] INFO: Resuming triggers.
[12:50:16.204] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[12:50:45.188] INFO: Resuming triggers.
[12:50:57.131] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[12:51:26.203] INFO: Resuming triggers.
[12:51:38.143] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[12:52:07.287] INFO: Resuming triggers.
[12:52:12.084] INFO: data taking finished, elapsed time: 100 seconds.
[12:52:24.075] INFO: PixTest:: pg_setup set to default.
[12:52:24.078] INFO: PixTestXray::doPhRun() done
[12:52:24.212] INFO: enter test to run
[12:53:05.064] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:53:05.064] INFO: running: xray
[12:53:05.065] INFO: ----------------------------------------------------------------------
[12:53:05.065] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:53:05.065] INFO: ----------------------------------------------------------------------
[12:53:06.029] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:53:13.363] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[12:53:43.482] INFO: Resuming triggers.
[12:53:50.815] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[12:54:20.661] INFO: Resuming triggers.
[12:54:27.995] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[12:54:59.118] INFO: Resuming triggers.
[12:55:06.453] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[12:55:36.499] INFO: Resuming triggers.
[12:55:43.833] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[12:56:14.849] INFO: Resuming triggers.
[12:56:22.181] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[12:56:52.305] INFO: Resuming triggers.
[12:56:59.637] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[12:57:30.282] INFO: Resuming triggers.
[12:57:37.619] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[12:58:07.520] INFO: Resuming triggers.
[12:58:14.847] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[12:58:44.630] INFO: Resuming triggers.
[12:58:51.958] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[12:59:21.375] INFO: Resuming triggers.
[12:59:28.702] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[12:59:56.947] INFO: Resuming triggers.
[13:00:04.274] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[13:00:33.836] INFO: Resuming triggers.
[13:00:41.167] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[13:01:09.152] INFO: Resuming triggers.
[13:01:14.184] INFO: data taking finished, elapsed time: 100 seconds.
[13:01:34.373] INFO: PixTest:: pg_setup set to default.
[13:01:34.376] INFO: PixTestXray::doPhRun() done
[13:01:34.552] INFO: enter test to run
[13:02:36.900] INFO: test: HighRate no parameter change
[13:02:36.900] INFO: running: highrate
[13:02:36.914] INFO: ----------------------------------------------------------------------
[13:02:36.914] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:02:36.914] INFO: ----------------------------------------------------------------------
[13:02:37.077] INFO: Expecting 768 events.
[13:02:38.212] INFO: 768 events read in total (414ms).
[13:02:38.212] INFO: Test took 1269ms.
[13:02:39.015] INFO: Expecting 41600 events.
[13:02:41.913] INFO: 41600 events read in total (2372ms).
[13:02:41.914] INFO: Test took 3684ms.
[13:02:41.944] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:42.673] INFO: Expecting 41600 events.
[13:02:45.591] INFO: 41600 events read in total (2391ms).
[13:02:45.592] INFO: Test took 3632ms.
[13:02:45.624] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:46.349] INFO: Expecting 41600 events.
[13:02:49.235] INFO: 41600 events read in total (2359ms).
[13:02:49.236] INFO: Test took 3594ms.
[13:02:49.270] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:49.997] INFO: Expecting 41600 events.
[13:02:53.122] INFO: 41600 events read in total (2598ms).
[13:02:53.123] INFO: Test took 3834ms.
[13:02:53.156] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:53.886] INFO: Expecting 41600 events.
[13:02:56.921] INFO: 41600 events read in total (2508ms).
[13:02:56.922] INFO: Test took 3750ms.
[13:02:56.953] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:57.685] INFO: Expecting 41600 events.
[13:03:00.824] INFO: 41600 events read in total (2612ms).
[13:03:00.825] INFO: Test took 3855ms.
[13:03:00.857] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:01.587] INFO: Expecting 41600 events.
[13:03:04.567] INFO: 41600 events read in total (2453ms).
[13:03:04.568] INFO: Test took 3693ms.
[13:03:04.602] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:05.330] INFO: Expecting 41600 events.
[13:03:08.392] INFO: 41600 events read in total (2535ms).
[13:03:08.393] INFO: Test took 3774ms.
[13:03:08.425] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:09.145] INFO: Expecting 41600 events.
[13:03:12.280] INFO: 41600 events read in total (2608ms).
[13:03:12.281] INFO: Test took 3839ms.
[13:03:12.313] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:13.039] INFO: Expecting 41600 events.
[13:03:16.195] INFO: 41600 events read in total (2629ms).
[13:03:16.196] INFO: Test took 3865ms.
[13:03:16.230] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:16.953] INFO: Expecting 41600 events.
[13:03:19.828] INFO: 41600 events read in total (2348ms).
[13:03:19.829] INFO: Test took 3582ms.
[13:03:19.863] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:20.586] INFO: Expecting 41600 events.
[13:03:23.646] INFO: 41600 events read in total (2533ms).
[13:03:23.647] INFO: Test took 3765ms.
[13:03:23.680] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:24.407] INFO: Expecting 41600 events.
[13:03:27.549] INFO: 41600 events read in total (2615ms).
[13:03:27.550] INFO: Test took 3853ms.
[13:03:27.584] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:28.309] INFO: Expecting 41600 events.
[13:03:31.481] INFO: 41600 events read in total (2645ms).
[13:03:31.482] INFO: Test took 3879ms.
[13:03:31.513] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:32.240] INFO: Expecting 41600 events.
[13:03:35.192] INFO: 41600 events read in total (2425ms).
[13:03:35.194] INFO: Test took 3663ms.
[13:03:35.227] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:35.955] INFO: Expecting 41600 events.
[13:03:39.136] INFO: 41600 events read in total (2655ms).
[13:03:39.137] INFO: Test took 3892ms.
[13:03:39.169] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:39.895] INFO: Expecting 41600 events.
[13:03:43.110] INFO: 41600 events read in total (2688ms).
[13:03:43.111] INFO: Test took 3926ms.
[13:03:43.143] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:43.872] INFO: Expecting 41600 events.
[13:03:46.984] INFO: 41600 events read in total (2586ms).
[13:03:46.985] INFO: Test took 3826ms.
[13:03:47.016] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:47.744] INFO: Expecting 41600 events.
[13:03:50.772] INFO: 41600 events read in total (2501ms).
[13:03:50.774] INFO: Test took 3741ms.
[13:03:50.806] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:51.530] INFO: Expecting 41600 events.
[13:03:54.568] INFO: 41600 events read in total (2512ms).
[13:03:54.569] INFO: Test took 3744ms.
[13:03:54.601] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:54.968] INFO: enter test to run
[13:05:15.226] INFO: test: HighRate no parameter change
[13:05:15.226] INFO: running: highrate
[13:05:15.253] INFO: ----------------------------------------------------------------------
[13:05:15.253] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:05:15.253] INFO: ----------------------------------------------------------------------
[13:05:15.870] INFO: Expecting 208000 events.
[13:05:27.153] INFO: 208000 events read in total (10756ms).
[13:05:27.156] INFO: Test took 11896ms.
[13:05:27.292] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:27.540] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 5 0 1 1 0 0 1 0
[13:05:27.540] INFO: number of red-efficiency pixels: 89 47 72 107 128 132 167 104 90 102 109 79 96 51 26 26
[13:05:27.540] INFO: number of X-ray hits detected: 58405 41960 61684 94725 100958 107285 109485 80315 69801 86128 88658 71836 74126 49963 20464 22059
[13:05:27.540] 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:05:27.540] INFO: number of Vcal hits detected: 207910 207953 207928 207888 207870 207865 207827 207891 207664 207896 207840 207871 207903 207949 207925 207974
[13:05:27.540] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0
[13:05:27.540] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.8 100.0 99.9 99.9 100.0 100.0 100.0 100.0
[13:05:27.540] INFO: X-ray hit rate [MHz/cm2]: 17.1 12.3 18.1 27.8 29.6 31.4 32.1 23.5 20.5 25.2 26.0 21.1 21.7 14.6 6.0 6.5
[13:05:27.540] INFO: PixTestHighRate::doXPixelAlive() done
[13:05:27.587] INFO: PixTest:: pg_setup set to default.
[13:05:27.600] INFO: enter test to run
[13:06:12.650] INFO: test: HighRate no parameter change
[13:06:12.650] INFO: running: highrate
[13:06:12.651] INFO: ----------------------------------------------------------------------
[13:06:12.651] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:06:12.651] INFO: ----------------------------------------------------------------------
[13:06:13.266] INFO: Expecting 208000 events.
[13:06:25.547] INFO: 208000 events read in total (11754ms).
[13:06:25.552] INFO: Test took 12891ms.
[13:06:25.838] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:26.132] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 5 0 1 1 0 0 1 0
[13:06:26.132] INFO: number of red-efficiency pixels: 176 121 224 302 394 483 478 317 186 250 312 151 257 112 46 36
[13:06:26.132] INFO: number of X-ray hits detected: 118293 84274 124621 191503 202871 216716 220724 163044 141442 173790 179412 144424 149312 101764 41525 44696
[13:06:26.132] 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:06:26.132] INFO: number of Vcal hits detected: 207818 207870 207752 207673 207575 207467 207479 207666 207552 207734 207609 207799 207732 207884 207904 207964
[13:06:26.132] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 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:06:26.132] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.7 99.8 99.8 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[13:06:26.133] INFO: X-ray hit rate [MHz/cm2]: 34.7 24.7 36.5 56.1 59.5 63.5 64.7 47.8 41.5 50.9 52.6 42.3 43.8 29.8 12.2 13.1
[13:06:26.133] INFO: PixTestHighRate::doXPixelAlive() done
[13:06:26.185] INFO: PixTest:: pg_setup set to default.
[13:06:26.198] INFO: enter test to run
[13:08:16.833] INFO: test: HighRate no parameter change
[13:08:16.834] INFO: running: highrate
[13:08:16.835] INFO: ----------------------------------------------------------------------
[13:08:16.835] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:08:16.835] INFO: ----------------------------------------------------------------------
[13:08:17.454] INFO: Expecting 208000 events.
[13:08:31.185] INFO: 208000 events read in total (13204ms).
[13:08:31.192] INFO: Test took 14348ms.
[13:08:31.602] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:31.936] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 5 0 1 1 0 0 1 0
[13:08:31.936] INFO: number of red-efficiency pixels: 387 236 540 570 881 1118 1086 750 373 611 559 377 482 264 95 76
[13:08:31.936] INFO: number of X-ray hits detected: 176038 125554 185268 284944 302445 321828 327700 242815 210564 259758 268029 216070 222659 150579 62163 66600
[13:08:31.937] 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:08:31.937] INFO: number of Vcal hits detected: 207562 207745 207345 207376 206944 206600 206627 207096 207353 207310 207321 207538 207480 207714 207853 207923
[13:08:31.937] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.7 99.5 99.4 99.4 99.6 99.8 99.7 99.7 99.8 99.8 99.9 100.0 100.0
[13:08:31.937] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.7 99.5 99.3 99.3 99.6 99.7 99.7 99.7 99.8 99.8 99.9 99.9 100.0
[13:08:31.937] INFO: X-ray hit rate [MHz/cm2]: 51.6 36.8 54.3 83.5 88.6 94.3 96.1 71.2 61.7 76.1 78.6 63.3 65.3 44.1 18.2 19.5
[13:08:31.937] INFO: PixTestHighRate::doXPixelAlive() done
[13:08:31.982] INFO: PixTest:: pg_setup set to default.
[13:08:31.995] INFO: enter test to run
[13:08:45.817] INFO: test: exit no parameter change
[13:08:46.493] QUIET: Connection to board 33 closed.
[13:08:46.583] INFO: pXar: this is the end, my friend