[13:23:48.029] INFO: *** Welcome to pxar ***
[13:23:48.029] INFO: *** Today: 2016/04/12
[13:23:48.048] INFO: *** Version: v1.9.0-796-gef167-dirty
[13:23:48.048] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//dacParameters35_C15.dat
[13:23:48.049] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:23:48.049] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//defaultMaskFile.dat
[13:23:48.049] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C15.dat
[13:23:48.126] INFO: clk: 4
[13:23:48.126] INFO: ctr: 4
[13:23:48.126] INFO: sda: 19
[13:23:48.126] INFO: tin: 9
[13:23:48.126] INFO: level: 15
[13:23:48.126] INFO: triggerdelay: 0
[13:23:48.127] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[13:23:48.127] INFO: Log level: INFO
[13:23:48.144] QUIET: Connection to board DTB_WREKRL opened.
[13:23:48.148] 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:23:48.150] INFO: RPC call hashes of host and DTB match: 398089610
[13:23:49.685] INFO: DUT info:
[13:23:49.685] INFO: The DUT currently contains the following objects:
[13:23:49.685] INFO: 2 TBM Cores tbm08c (2 ON)
[13:23:49.685] INFO: TBM Core alpha (0): 7 registers set
[13:23:49.685] INFO: TBM Core beta (1): 7 registers set
[13:23:49.685] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:23:49.685] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.685] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.685] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.685] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.685] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.685] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.685] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.685] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.685] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.685] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.685] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.685] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.685] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.686] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.686] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:49.686] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:50.089] INFO: enter 'restricted' command line mode
[13:23:50.089] INFO: enter test to run
[13:23:58.063] INFO: test: PixelAlive no parameter change
[13:23:58.063] INFO: running: pixelalive
[13:23:58.072] INFO: ----------------------------------------------------------------------
[13:23:58.072] 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:23:58.072] INFO: ----------------------------------------------------------------------
[13:23:58.393] INFO: Expecting 41600 events.
[13:24:02.729] INFO: 41600 events read in total (3617ms).
[13:24:02.896] INFO: Test took 4820ms.
[13:24:02.910] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:03.172] INFO: PixTestAlive::aliveTest() done
[13:24:03.172] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0
[13:24:03.206] INFO: enter test to run
[13:25:05.222] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:25:05.223] INFO: running: highrate
[13:25:05.223] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:25:05.428] INFO: ----------------------------------------------------------------------
[13:25:05.428] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:25:05.428] INFO: ----------------------------------------------------------------------
[13:25:05.428] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:25:05.428] INFO: edge/corner pixel THR is adjusted
[13:25:05.428] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:25:06.388] INFO: Collecting data for 5 seconds...
[13:25:11.406] INFO: Done with hot pixel readout
[13:25:23.256] INFO: PixTest:: pg_setup set to default.
[13:25:23.257] INFO: 4 hot pixels found in step 0
[13:25:24.248] INFO: Collecting data for 5 seconds...
[13:25:29.265] INFO: Done with hot pixel readout
[13:25:41.192] INFO: PixTest:: pg_setup set to default.
[13:25:41.193] INFO: 2 hot pixels found in step 1
[13:25:42.188] INFO: Collecting data for 5 seconds...
[13:25:47.205] INFO: Done with hot pixel readout
[13:25:59.184] INFO: PixTest:: pg_setup set to default.
[13:25:59.185] INFO: 4 hot pixels found in step 2
[13:26:00.179] INFO: Collecting data for 5 seconds...
[13:26:05.194] INFO: Done with hot pixel readout
[13:26:17.158] INFO: PixTest:: pg_setup set to default.
[13:26:17.159] INFO: 4 hot pixels found in step 3
[13:26:18.153] INFO: Collecting data for 5 seconds...
[13:26:23.170] INFO: Done with hot pixel readout
[13:26:35.124] INFO: PixTest:: pg_setup set to default.
[13:26:35.125] INFO: 3 hot pixels found in step 4
[13:26:36.119] INFO: Collecting data for 5 seconds...
[13:26:41.136] INFO: Done with hot pixel readout
[13:26:53.124] INFO: PixTest:: pg_setup set to default.
[13:26:53.125] INFO: 0 hot pixels found in step 5
[13:26:53.163] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:26:53.167] INFO: PixTest::trimHotPixels() done
[13:26:53.167] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C0.dat
[13:26:53.172] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C1.dat
[13:26:53.178] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C2.dat
[13:26:53.183] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C3.dat
[13:26:53.189] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C4.dat
[13:26:53.194] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C5.dat
[13:26:53.199] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C6.dat
[13:26:53.204] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C7.dat
[13:26:53.209] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C8.dat
[13:26:53.215] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C9.dat
[13:26:53.220] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C10.dat
[13:26:53.225] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C11.dat
[13:26:53.230] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C12.dat
[13:26:53.236] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C13.dat
[13:26:53.241] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C14.dat
[13:26:53.246] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C15.dat
[13:26:53.252] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//defaultMaskFile.dat
[13:26:53.261] INFO: enter test to run
[13:27:46.837] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:27:46.837] INFO: running: highrate
[13:27:46.842] INFO: ----------------------------------------------------------------------
[13:27:46.842] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:27:46.842] INFO: ----------------------------------------------------------------------
[13:27:46.842] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:27:46.842] INFO: edge/corner pixel THR is adjusted
[13:27:46.842] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:27:47.800] INFO: Collecting data for 1 seconds...
[13:27:48.804] INFO: Done with hot pixel readout
[13:27:52.873] INFO: PixTest:: pg_setup set to default.
[13:27:52.873] INFO: 0 hot pixels found in step 0
[13:27:52.879] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:27:52.966] INFO: PixTest::trimHotPixels() done
[13:27:52.967] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C0.dat
[13:27:52.979] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C1.dat
[13:27:52.988] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C2.dat
[13:27:52.993] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C3.dat
[13:27:52.998] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C4.dat
[13:27:52.003] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C5.dat
[13:27:53.009] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C6.dat
[13:27:53.014] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C7.dat
[13:27:53.019] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C8.dat
[13:27:53.024] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C9.dat
[13:27:53.030] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C10.dat
[13:27:53.035] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C11.dat
[13:27:53.040] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C12.dat
[13:27:53.045] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C13.dat
[13:27:53.051] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C14.dat
[13:27:53.056] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//trimParameters35_C15.dat
[13:27:53.062] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-4-05_FPIXTest-17C-FNAL-160408-0921_2016-04-08_09h21m_1460125289/000_FPIXTest_p17//defaultMaskFile.dat
[13:27:53.071] INFO: enter test to run
[13:28:22.429] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:28:22.429] INFO: running: xray
[13:28:22.430] INFO: ----------------------------------------------------------------------
[13:28:22.430] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:28:22.430] INFO: ----------------------------------------------------------------------
[13:28:23.393] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:28:34.795] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:29:04.089] INFO: Resuming triggers.
[13:29:15.490] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[13:29:44.912] INFO: Resuming triggers.
[13:29:56.312] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:30:25.570] INFO: Resuming triggers.
[13:30:36.968] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[13:31:06.282] INFO: Resuming triggers.
[13:31:17.679] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[13:31:47.029] INFO: Resuming triggers.
[13:31:58.426] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[13:32:27.832] INFO: Resuming triggers.
[13:32:39.227] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[13:33:07.919] INFO: Resuming triggers.
[13:33:19.318] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[13:33:48.588] INFO: Resuming triggers.
[13:33:57.708] INFO: data taking finished, elapsed time: 100 seconds.
[13:34:21.384] INFO: PixTest:: pg_setup set to default.
[13:34:21.387] INFO: PixTestXray::doPhRun() done
[13:34:21.524] INFO: enter test to run
[13:35:03.474] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:35:03.474] INFO: running: xray
[13:35:03.475] INFO: ----------------------------------------------------------------------
[13:35:03.475] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:35:03.475] INFO: ----------------------------------------------------------------------
[13:35:04.439] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:35:10.974] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:35:40.679] INFO: Resuming triggers.
[13:35:47.217] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[13:36:17.287] INFO: Resuming triggers.
[13:36:23.827] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[13:36:53.711] INFO: Resuming triggers.
[13:37:00.249] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[13:37:29.958] INFO: Resuming triggers.
[13:37:36.500] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[13:38:06.677] INFO: Resuming triggers.
[13:38:13.216] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[13:38:43.170] INFO: Resuming triggers.
[13:38:49.712] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[13:39:19.717] INFO: Resuming triggers.
[13:39:26.258] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[13:39:56.303] INFO: Resuming triggers.
[13:40:02.843] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[13:40:32.763] INFO: Resuming triggers.
[13:40:39.302] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[13:41:09.355] INFO: Resuming triggers.
[13:41:15.890] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[13:41:45.959] INFO: Resuming triggers.
[13:41:52.494] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[13:42:22.517] INFO: Resuming triggers.
[13:42:29.053] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[13:42:54.347] INFO: Resuming triggers.
[13:43:00.880] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[13:43:30.841] INFO: Resuming triggers.
[13:43:37.376] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[13:44:07.342] INFO: Resuming triggers.
[13:44:09.604] INFO: data taking finished, elapsed time: 100 seconds.
[13:44:20.328] INFO: PixTest:: pg_setup set to default.
[13:44:20.331] INFO: PixTestXray::doPhRun() done
[13:44:20.482] INFO: enter test to run
[13:44:45.742] INFO: test: HighRate no parameter change
[13:44:45.742] INFO: running: highrate
[13:44:45.743] INFO: ----------------------------------------------------------------------
[13:44:45.743] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:44:45.743] INFO: ----------------------------------------------------------------------
[13:44:45.883] INFO: Expecting 768 events.
[13:44:47.017] INFO: 768 events read in total (418ms).
[13:44:47.017] INFO: Test took 1268ms.
[13:44:47.820] INFO: Expecting 41600 events.
[13:44:50.920] INFO: 41600 events read in total (2573ms).
[13:44:50.921] INFO: Test took 3898ms.
[13:44:50.954] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:51.684] INFO: Expecting 41600 events.
[13:44:54.891] INFO: 41600 events read in total (2680ms).
[13:44:54.892] INFO: Test took 3920ms.
[13:44:54.925] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:55.651] INFO: Expecting 41600 events.
[13:44:58.886] INFO: 41600 events read in total (2708ms).
[13:44:58.887] INFO: Test took 3944ms.
[13:44:58.921] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:59.642] INFO: Expecting 41600 events.
[13:45:02.883] INFO: 41600 events read in total (2714ms).
[13:45:02.884] INFO: Test took 3944ms.
[13:45:02.918] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:03.642] INFO: Expecting 41600 events.
[13:45:06.878] INFO: 41600 events read in total (2709ms).
[13:45:06.879] INFO: Test took 3944ms.
[13:45:06.913] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:07.633] INFO: Expecting 41600 events.
[13:45:10.862] INFO: 41600 events read in total (2702ms).
[13:45:10.863] INFO: Test took 3932ms.
[13:45:10.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:11.621] INFO: Expecting 41600 events.
[13:45:14.838] INFO: 41600 events read in total (2690ms).
[13:45:14.839] INFO: Test took 3923ms.
[13:45:14.874] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:15.598] INFO: Expecting 41600 events.
[13:45:18.846] INFO: 41600 events read in total (2721ms).
[13:45:18.847] INFO: Test took 3955ms.
[13:45:18.881] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:19.607] INFO: Expecting 41600 events.
[13:45:22.865] INFO: 41600 events read in total (2731ms).
[13:45:22.866] INFO: Test took 3967ms.
[13:45:22.899] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:23.625] INFO: Expecting 41600 events.
[13:45:26.859] INFO: 41600 events read in total (2707ms).
[13:45:26.860] INFO: Test took 3943ms.
[13:45:26.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:27.619] INFO: Expecting 41600 events.
[13:45:30.855] INFO: 41600 events read in total (2709ms).
[13:45:30.856] INFO: Test took 3944ms.
[13:45:30.890] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:31.614] INFO: Expecting 41600 events.
[13:45:34.850] INFO: 41600 events read in total (2710ms).
[13:45:34.851] INFO: Test took 3943ms.
[13:45:34.884] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:35.607] INFO: Expecting 41600 events.
[13:45:38.851] INFO: 41600 events read in total (2717ms).
[13:45:38.852] INFO: Test took 3949ms.
[13:45:38.886] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:39.612] INFO: Expecting 41600 events.
[13:45:42.843] INFO: 41600 events read in total (2704ms).
[13:45:42.844] INFO: Test took 3939ms.
[13:45:42.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:43.599] INFO: Expecting 41600 events.
[13:45:46.840] INFO: 41600 events read in total (2714ms).
[13:45:46.841] INFO: Test took 3943ms.
[13:45:46.875] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:47.599] INFO: Expecting 41600 events.
[13:45:50.824] INFO: 41600 events read in total (2698ms).
[13:45:50.825] INFO: Test took 3933ms.
[13:45:50.859] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:51.583] INFO: Expecting 41600 events.
[13:45:54.808] INFO: 41600 events read in total (2698ms).
[13:45:54.809] INFO: Test took 3933ms.
[13:45:54.843] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:55.568] INFO: Expecting 41600 events.
[13:45:58.814] INFO: 41600 events read in total (2719ms).
[13:45:58.815] INFO: Test took 3954ms.
[13:45:58.849] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:59.571] INFO: Expecting 41600 events.
[13:46:02.787] INFO: 41600 events read in total (2689ms).
[13:46:02.788] INFO: Test took 3922ms.
[13:46:02.821] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:03.547] INFO: Expecting 41600 events.
[13:46:06.648] INFO: 41600 events read in total (2574ms).
[13:46:06.649] INFO: Test took 3809ms.
[13:46:06.682] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:07.047] INFO: enter test to run
[13:46:19.277] INFO: test: HighRate no parameter change
[13:46:19.277] INFO: running: highrate
[13:46:19.278] INFO: ----------------------------------------------------------------------
[13:46:19.278] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:46:19.278] INFO: ----------------------------------------------------------------------
[13:46:19.894] INFO: Expecting 208000 events.
[13:46:31.745] INFO: 208000 events read in total (11324ms).
[13:46:31.748] INFO: Test took 12461ms.
[13:46:31.890] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:32.141] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:46:32.141] INFO: number of red-efficiency pixels: 82 44 95 148 152 156 148 89 83 117 132 99 108 59 21 36
[13:46:32.141] INFO: number of X-ray hits detected: 69039 43744 66944 109039 112741 115820 116466 78848 74630 94010 93721 84010 84917 49816 23481 24024
[13:46:32.141] 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:46:32.141] INFO: number of Vcal hits detected: 207916 207956 207903 207849 207846 207842 207850 207908 207917 207880 207865 207901 207887 207940 207977 207963
[13:46:32.141] 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 99.9 100.0 100.0 100.0
[13:46:32.141] 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
[13:46:32.141] INFO: X-ray hit rate [MHz/cm2]: 20.2 12.8 19.6 32.0 33.0 33.9 34.1 23.1 21.9 27.6 27.5 24.6 24.9 14.6 6.9 7.0
[13:46:32.142] INFO: PixTestHighRate::doXPixelAlive() done
[13:46:32.189] INFO: PixTest:: pg_setup set to default.
[13:46:32.203] INFO: enter test to run
[13:47:12.469] INFO: test: HighRate no parameter change
[13:47:12.469] INFO: running: highrate
[13:47:12.470] INFO: ----------------------------------------------------------------------
[13:47:12.470] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:47:12.470] INFO: ----------------------------------------------------------------------
[13:47:13.089] INFO: Expecting 208000 events.
[13:47:26.724] INFO: 208000 events read in total (13108ms).
[13:47:26.729] INFO: Test took 14250ms.
[13:47:27.033] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:27.337] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:47:27.337] INFO: number of red-efficiency pixels: 280 132 331 396 680 628 651 297 272 432 393 356 347 229 53 68
[13:47:27.337] INFO: number of X-ray hits detected: 149452 94786 145670 236387 242806 250753 254503 171492 163294 204473 206154 182896 185738 107765 51626 52186
[13:47:27.337] 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:47:27.337] INFO: number of Vcal hits detected: 207696 207864 207627 207573 207241 207296 207264 207690 207717 207541 207573 207616 207613 207758 207946 207932
[13:47:27.337] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.7 99.9 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[13:47:27.337] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.6 99.7 99.6 99.9 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[13:47:27.337] INFO: X-ray hit rate [MHz/cm2]: 43.8 27.8 42.7 69.3 71.2 73.5 74.6 50.3 47.9 59.9 60.4 53.6 54.4 31.6 15.1 15.3
[13:47:27.337] INFO: PixTestHighRate::doXPixelAlive() done
[13:47:27.386] INFO: PixTest:: pg_setup set to default.
[13:47:27.403] INFO: enter test to run
[13:47:44.389] INFO: test: HighRate no parameter change
[13:47:44.389] INFO: running: highrate
[13:47:44.390] INFO: ----------------------------------------------------------------------
[13:47:44.390] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:47:44.390] INFO: ----------------------------------------------------------------------
[13:47:44.000] INFO: Expecting 208000 events.
[13:48:00.743] INFO: 208000 events read in total (15216ms).
[13:48:00.751] INFO: Test took 16353ms.
[13:48:01.234] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:01.591] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:48:01.592] INFO: number of red-efficiency pixels: 631 286 685 942 1433 1434 1474 604 531 1007 890 776 787 422 94 113
[13:48:01.592] INFO: number of X-ray hits detected: 224844 143389 220055 355702 366826 375447 381014 259556 245627 306374 308808 275305 279035 161449 77852 79412
[13:48:01.592] 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:48:01.592] INFO: number of Vcal hits detected: 207211 207692 207074 206879 206122 206039 205989 207279 207385 206786 206948 207073 207077 207507 207904 207882
[13:48:01.592] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.6 99.5 99.2 99.2 99.1 99.7 99.7 99.5 99.5 99.6 99.6 99.8 100.0 99.9
[13:48:01.592] INFO: Vcal hit overall efficiency (%): 99.6 99.9 99.6 99.5 99.1 99.1 99.0 99.7 99.7 99.4 99.5 99.6 99.6 99.8 100.0 99.9
[13:48:01.592] INFO: X-ray hit rate [MHz/cm2]: 65.9 42.0 64.5 104.3 107.5 110.0 111.7 76.1 72.0 89.8 90.5 80.7 81.8 47.3 22.8 23.3
[13:48:01.592] INFO: PixTestHighRate::doXPixelAlive() done
[13:48:01.637] INFO: PixTest:: pg_setup set to default.
[13:48:01.654] INFO: enter test to run
[13:48:15.708] INFO: test: exit no parameter change
[13:48:15.001] QUIET: Connection to board 33 closed.
[13:48:15.002] INFO: pXar: this is the end, my friend