[13:38:06.558] INFO: *** Welcome to pxar ***
[13:38:06.558] INFO: *** Today: 2016/04/07
[13:38:07.045] INFO: *** Version: v1.9.0-796-gef167-dirty
[13:38:07.045] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//dacParameters35_C15.dat
[13:38:07.096] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:38:07.097] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//defaultMaskFile.dat
[13:38:07.106] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C15.dat
[13:38:07.207] INFO: clk: 4
[13:38:07.207] INFO: ctr: 4
[13:38:07.207] INFO: sda: 19
[13:38:07.207] INFO: tin: 9
[13:38:07.207] INFO: level: 15
[13:38:07.207] INFO: triggerdelay: 0
[13:38:07.207] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[13:38:07.207] INFO: Log level: INFO
[13:38:07.227] QUIET: Connection to board DTB_WREKRL opened.
[13:38:07.230] 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:38:07.233] INFO: RPC call hashes of host and DTB match: 398089610
[13:38:08.765] INFO: DUT info:
[13:38:08.765] INFO: The DUT currently contains the following objects:
[13:38:08.765] INFO: 2 TBM Cores tbm08c (2 ON)
[13:38:08.765] INFO: TBM Core alpha (0): 7 registers set
[13:38:08.765] INFO: TBM Core beta (1): 7 registers set
[13:38:08.765] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:38:08.765] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.765] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.765] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.765] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.765] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.765] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.765] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.766] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.766] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.766] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.766] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.766] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.766] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.766] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.766] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:08.766] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:09.253] INFO: enter 'restricted' command line mode
[13:38:09.253] INFO: enter test to run
[13:38:15.308] INFO: test: PixelAlive no parameter change
[13:38:15.308] INFO: running: pixelalive
[13:38:15.346] INFO: ----------------------------------------------------------------------
[13:38:15.346] 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:38:15.346] INFO: ----------------------------------------------------------------------
[13:38:15.667] INFO: Expecting 41600 events.
[13:38:20.036] INFO: 41600 events read in total (3650ms).
[13:38:20.207] INFO: Test took 4858ms.
[13:38:20.217] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:20.481] INFO: PixTestAlive::aliveTest() done
[13:38:20.481] INFO: number of dead pixels (per ROC): 1 0 2 0 0 0 0 0 0 0 0 0 0 2 0 0
[13:38:20.597] INFO: enter test to run
[13:38:47.908] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:38:47.908] INFO: running: highrate
[13:38:47.908] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:38:48.107] INFO: ----------------------------------------------------------------------
[13:38:48.107] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:38:48.107] INFO: ----------------------------------------------------------------------
[13:38:48.107] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:38:48.107] INFO: edge/corner pixel THR is adjusted
[13:38:48.107] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:38:49.088] INFO: Collecting data for 5 seconds...
[13:38:54.105] INFO: Done with hot pixel readout
[13:39:05.794] INFO: PixTest:: pg_setup set to default.
[13:39:05.795] INFO: 0 hot pixels found in step 0
[13:39:05.807] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:39:05.811] INFO: PixTest::trimHotPixels() done
[13:39:05.853] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C0.dat
[13:39:05.858] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C1.dat
[13:39:05.887] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C2.dat
[13:39:05.892] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C3.dat
[13:39:05.898] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C4.dat
[13:39:05.903] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C5.dat
[13:39:05.908] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C6.dat
[13:39:05.914] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C7.dat
[13:39:05.919] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C8.dat
[13:39:05.924] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C9.dat
[13:39:05.929] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C10.dat
[13:39:05.935] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C11.dat
[13:39:05.940] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C12.dat
[13:39:05.945] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C13.dat
[13:39:05.951] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C14.dat
[13:39:05.956] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C15.dat
[13:39:05.961] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//defaultMaskFile.dat
[13:39:05.979] INFO: enter test to run
[13:39:29.460] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:39:29.460] INFO: running: highrate
[13:39:29.464] INFO: ----------------------------------------------------------------------
[13:39:29.464] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:39:29.464] INFO: ----------------------------------------------------------------------
[13:39:29.464] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:39:29.464] INFO: edge/corner pixel THR is adjusted
[13:39:29.464] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:39:30.422] INFO: Collecting data for 1 seconds...
[13:39:31.426] INFO: Done with hot pixel readout
[13:39:35.551] INFO: PixTest:: pg_setup set to default.
[13:39:35.552] INFO: 0 hot pixels found in step 0
[13:39:35.558] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:39:35.648] INFO: PixTest::trimHotPixels() done
[13:39:35.649] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C0.dat
[13:39:35.659] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C1.dat
[13:39:35.664] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C2.dat
[13:39:35.670] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C3.dat
[13:39:35.675] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C4.dat
[13:39:35.680] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C5.dat
[13:39:35.685] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C6.dat
[13:39:35.690] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C7.dat
[13:39:35.696] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C8.dat
[13:39:35.701] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C9.dat
[13:39:35.706] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C10.dat
[13:39:35.711] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C11.dat
[13:39:35.716] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C12.dat
[13:39:35.722] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C13.dat
[13:39:35.727] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C14.dat
[13:39:35.732] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//trimParameters35_C15.dat
[13:39:35.737] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-1-39_FPIXTest-17C-Nebraska-160330-1423_2016-03-30_14h24m_1459365847/000_FPIXTest_p17//defaultMaskFile.dat
[13:39:35.747] INFO: enter test to run
[13:40:08.027] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:40:08.027] INFO: running: xray
[13:40:08.028] INFO: ----------------------------------------------------------------------
[13:40:08.028] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:40:08.028] INFO: ----------------------------------------------------------------------
[13:40:08.992] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:40:20.435] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:40:49.643] INFO: Resuming triggers.
[13:41:01.083] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[13:41:30.399] INFO: Resuming triggers.
[13:41:41.843] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:42:10.962] INFO: Resuming triggers.
[13:42:22.402] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[13:42:51.592] INFO: Resuming triggers.
[13:43:03.035] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[13:43:32.375] INFO: Resuming triggers.
[13:43:43.818] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[13:44:12.719] INFO: Resuming triggers.
[13:44:24.162] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[13:44:53.556] INFO: Resuming triggers.
[13:45:04.998] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[13:45:34.322] INFO: Resuming triggers.
[13:45:43.117] INFO: data taking finished, elapsed time: 100 seconds.
[13:46:05.815] INFO: PixTest:: pg_setup set to default.
[13:46:05.818] INFO: PixTestXray::doPhRun() done
[13:46:05.996] INFO: enter test to run
[13:47:07.808] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:47:07.808] INFO: running: xray
[13:47:07.809] INFO: ----------------------------------------------------------------------
[13:47:07.809] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:47:07.809] INFO: ----------------------------------------------------------------------
[13:47:08.777] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:47:15.206] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:47:45.321] INFO: Resuming triggers.
[13:47:51.754] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[13:48:21.892] INFO: Resuming triggers.
[13:48:28.324] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[13:48:58.342] INFO: Resuming triggers.
[13:49:04.770] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[13:49:34.593] INFO: Resuming triggers.
[13:49:41.022] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[13:50:06.387] INFO: Resuming triggers.
[13:50:12.818] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[13:50:37.886] INFO: Resuming triggers.
[13:50:44.313] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[13:51:09.338] INFO: Resuming triggers.
[13:51:15.766] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[13:51:40.888] INFO: Resuming triggers.
[13:51:47.317] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[13:52:12.510] INFO: Resuming triggers.
[13:52:18.934] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[13:52:43.918] INFO: Resuming triggers.
[13:52:50.343] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[13:53:15.358] INFO: Resuming triggers.
[13:53:21.788] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[13:53:47.009] INFO: Resuming triggers.
[13:53:53.438] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[13:54:18.491] INFO: Resuming triggers.
[13:54:24.921] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[13:54:50.004] INFO: Resuming triggers.
[13:54:56.432] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[13:55:21.505] INFO: Resuming triggers.
[13:55:25.291] INFO: data taking finished, elapsed time: 100 seconds.
[13:55:40.425] INFO: PixTest:: pg_setup set to default.
[13:55:40.428] INFO: PixTestXray::doPhRun() done
[13:55:40.578] INFO: enter test to run
[13:56:15.556] INFO: test: HighRate no parameter change
[13:56:15.556] INFO: running: highrate
[13:56:15.575] INFO: ----------------------------------------------------------------------
[13:56:15.575] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:56:15.575] INFO: ----------------------------------------------------------------------
[13:56:15.738] INFO: Expecting 768 events.
[13:56:16.867] INFO: 768 events read in total (413ms).
[13:56:16.868] INFO: Test took 1263ms.
[13:56:17.670] INFO: Expecting 41600 events.
[13:56:20.352] INFO: 41600 events read in total (2155ms).
[13:56:20.353] INFO: Test took 3464ms.
[13:56:20.387] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:21.119] INFO: Expecting 41600 events.
[13:56:23.935] INFO: 41600 events read in total (2290ms).
[13:56:23.935] INFO: Test took 3530ms.
[13:56:23.970] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:24.699] INFO: Expecting 41600 events.
[13:56:27.491] INFO: 41600 events read in total (2266ms).
[13:56:27.492] INFO: Test took 3505ms.
[13:56:27.525] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:28.254] INFO: Expecting 41600 events.
[13:56:31.053] INFO: 41600 events read in total (2272ms).
[13:56:31.054] INFO: Test took 3511ms.
[13:56:31.089] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:31.815] INFO: Expecting 41600 events.
[13:56:34.638] INFO: 41600 events read in total (2296ms).
[13:56:34.638] INFO: Test took 3532ms.
[13:56:34.673] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:35.400] INFO: Expecting 41600 events.
[13:56:38.208] INFO: 41600 events read in total (2281ms).
[13:56:38.209] INFO: Test took 3519ms.
[13:56:38.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:38.971] INFO: Expecting 41600 events.
[13:56:41.794] INFO: 41600 events read in total (2297ms).
[13:56:41.795] INFO: Test took 3534ms.
[13:56:41.829] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:42.556] INFO: Expecting 41600 events.
[13:56:45.388] INFO: 41600 events read in total (2305ms).
[13:56:45.389] INFO: Test took 3542ms.
[13:56:45.423] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:46.150] INFO: Expecting 41600 events.
[13:56:48.959] INFO: 41600 events read in total (2282ms).
[13:56:48.960] INFO: Test took 3518ms.
[13:56:48.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:49.720] INFO: Expecting 41600 events.
[13:56:52.521] INFO: 41600 events read in total (2274ms).
[13:56:52.522] INFO: Test took 3510ms.
[13:56:52.556] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:53.285] INFO: Expecting 41600 events.
[13:56:56.116] INFO: 41600 events read in total (2305ms).
[13:56:56.117] INFO: Test took 3544ms.
[13:56:56.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:56.879] INFO: Expecting 41600 events.
[13:56:59.688] INFO: 41600 events read in total (2283ms).
[13:56:59.689] INFO: Test took 3520ms.
[13:56:59.724] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:00.449] INFO: Expecting 41600 events.
[13:57:03.255] INFO: 41600 events read in total (2279ms).
[13:57:03.256] INFO: Test took 3514ms.
[13:57:03.290] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:04.018] INFO: Expecting 41600 events.
[13:57:06.871] INFO: 41600 events read in total (2327ms).
[13:57:06.872] INFO: Test took 3564ms.
[13:57:06.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:07.633] INFO: Expecting 41600 events.
[13:57:10.434] INFO: 41600 events read in total (2274ms).
[13:57:10.434] INFO: Test took 3510ms.
[13:57:10.469] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:11.196] INFO: Expecting 41600 events.
[13:57:14.200] INFO: 41600 events read in total (2477ms).
[13:57:14.201] INFO: Test took 3715ms.
[13:57:14.235] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:14.956] INFO: Expecting 41600 events.
[13:57:17.779] INFO: 41600 events read in total (2296ms).
[13:57:17.780] INFO: Test took 3526ms.
[13:57:17.815] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:18.542] INFO: Expecting 41600 events.
[13:57:21.386] INFO: 41600 events read in total (2317ms).
[13:57:21.387] INFO: Test took 3555ms.
[13:57:21.421] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:22.148] INFO: Expecting 41600 events.
[13:57:25.151] INFO: 41600 events read in total (2476ms).
[13:57:25.152] INFO: Test took 3713ms.
[13:57:25.186] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:25.914] INFO: Expecting 41600 events.
[13:57:28.626] INFO: 41600 events read in total (2186ms).
[13:57:28.627] INFO: Test took 3423ms.
[13:57:28.661] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:29.020] INFO: enter test to run
[13:58:28.011] INFO: test: HighRate no parameter change
[13:58:28.011] INFO: running: highrate
[13:58:28.012] INFO: ----------------------------------------------------------------------
[13:58:28.012] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:58:28.012] INFO: ----------------------------------------------------------------------
[13:58:28.625] INFO: Expecting 208000 events.
[13:58:38.505] INFO: 208000 events read in total (9354ms).
[13:58:38.508] INFO: Test took 10488ms.
[13:58:38.652] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:38.901] INFO: number of dead pixels (per ROC): 1 0 2 0 0 0 0 0 0 0 0 0 0 2 0 0
[13:58:38.901] INFO: number of red-efficiency pixels: 68 39 102 110 152 136 167 97 110 120 112 93 99 79 36 31
[13:58:38.901] INFO: number of X-ray hits detected: 58970 40719 69066 101984 110970 112509 115356 81365 75261 92829 95734 81428 85117 55495 22544 25962
[13:58:38.901] 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:58:38.901] INFO: number of Vcal hits detected: 207882 207960 207797 207888 207846 207859 207831 207903 207890 207874 207884 207906 207898 207822 207963 207968
[13:58:38.901] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[13:58:38.901] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0
[13:58:38.902] INFO: X-ray hit rate [MHz/cm2]: 17.3 11.9 20.2 29.9 32.5 33.0 33.8 23.8 22.1 27.2 28.1 23.9 24.9 16.3 6.6 7.6
[13:58:38.902] INFO: PixTestHighRate::doXPixelAlive() done
[13:58:38.951] INFO: PixTest:: pg_setup set to default.
[13:58:38.959] INFO: enter test to run
[13:59:16.802] INFO: test: HighRate no parameter change
[13:59:16.802] INFO: running: highrate
[13:59:16.803] INFO: ----------------------------------------------------------------------
[13:59:16.803] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:59:16.803] INFO: ----------------------------------------------------------------------
[13:59:17.412] INFO: Expecting 208000 events.
[13:59:28.772] INFO: 208000 events read in total (10833ms).
[13:59:28.778] INFO: Test took 11968ms.
[13:59:29.093] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:29.401] INFO: number of dead pixels (per ROC): 1 0 2 0 0 0 0 0 0 0 0 0 0 2 0 0
[13:59:29.401] INFO: number of red-efficiency pixels: 209 123 312 455 563 427 607 361 277 420 428 324 369 262 69 70
[13:59:29.401] INFO: number of X-ray hits detected: 129569 89152 151875 222532 242748 245410 252717 179287 164045 203298 209011 178769 187119 122152 49592 57464
[13:59:29.401] 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:59:29.401] INFO: number of Vcal hits detected: 207733 207873 207555 207506 207390 207538 207332 207620 207710 207550 207539 207653 207607 207608 207931 207927
[13:59:29.401] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.7 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[13:59:29.401] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.8 99.7 99.8 99.9 99.8 99.8 99.8 99.8 99.8 100.0 100.0
[13:59:29.401] INFO: X-ray hit rate [MHz/cm2]: 38.0 26.1 44.5 65.2 71.2 71.9 74.1 52.6 48.1 59.6 61.3 52.4 54.8 35.8 14.5 16.8
[13:59:29.401] INFO: PixTestHighRate::doXPixelAlive() done
[13:59:29.450] INFO: PixTest:: pg_setup set to default.
[13:59:29.467] INFO: enter test to run
[14:00:19.058] INFO: test: HighRate no parameter change
[14:00:19.058] INFO: running: highrate
[14:00:19.059] INFO: ----------------------------------------------------------------------
[14:00:19.059] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:00:19.059] INFO: ----------------------------------------------------------------------
[14:00:19.690] INFO: Expecting 208000 events.
[14:00:33.666] INFO: 208000 events read in total (13449ms).
[14:00:33.674] INFO: Test took 14607ms.
[14:00:34.183] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:34.545] INFO: number of dead pixels (per ROC): 1 0 2 0 0 0 0 0 0 0 0 0 0 2 0 0
[14:00:34.545] INFO: number of red-efficiency pixels: 510 281 765 1194 1353 1091 1439 819 633 1029 1042 779 871 586 102 116
[14:00:34.545] INFO: number of X-ray hits detected: 199539 136806 232531 342546 373585 379159 387751 276158 253538 313556 321620 275126 287831 188007 76642 89416
[14:00:34.545] 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:00:34.545] INFO: number of Vcal hits detected: 207361 207688 206867 206518 206252 206638 206039 206972 207270 206755 206727 207061 206965 207172 207894 207881
[14:00:34.545] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.5 99.3 99.2 99.4 99.1 99.5 99.7 99.4 99.4 99.6 99.5 99.7 100.0 99.9
[14:00:34.545] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.5 99.3 99.2 99.3 99.1 99.5 99.6 99.4 99.4 99.5 99.5 99.6 99.9 99.9
[14:00:34.545] INFO: X-ray hit rate [MHz/cm2]: 58.5 40.1 68.2 100.4 109.5 111.1 113.7 80.9 74.3 91.9 94.3 80.6 84.4 55.1 22.5 26.2
[14:00:34.545] INFO: PixTestHighRate::doXPixelAlive() done
[14:00:34.590] INFO: PixTest:: pg_setup set to default.
[14:00:34.599] INFO: enter test to run
[14:00:45.146] INFO: test: exit no parameter change
[14:00:45.553] QUIET: Connection to board 33 closed.
[14:00:45.562] INFO: pXar: this is the end, my friend