[16:42:50.697] INFO: *** Welcome to pxar ***
[16:42:50.697] INFO: *** Today: 2016/06/15
[16:42:50.718] INFO: *** Version: v1.9.0-814-g7497
[16:42:50.718] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//dacParameters35_C15.dat
[16:42:50.763] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//tbmParameters_C0b.dat
[16:42:50.764] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//defaultMaskFile.dat
[16:42:50.777] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C15.dat
[16:42:50.867] INFO: clk: 4
[16:42:50.867] INFO: ctr: 4
[16:42:50.867] INFO: sda: 19
[16:42:50.867] INFO: tin: 9
[16:42:50.867] INFO: level: 15
[16:42:50.867] INFO: triggerdelay: 0
[16:42:50.867] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[16:42:50.867] INFO: Log level: INFO
[16:42:50.885] QUIET: Connection to board DTB_WREKRL opened.
[16:42:50.888] 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:
------------------------------------------------------
[16:42:50.891] INFO: RPC call hashes of host and DTB match: 398089610
[16:42:52.427] INFO: DUT info:
[16:42:52.427] INFO: The DUT currently contains the following objects:
[16:42:52.427] INFO: 2 TBM Cores tbm08c (2 ON)
[16:42:52.427] INFO: TBM Core alpha (0): 7 registers set
[16:42:52.427] INFO: TBM Core beta (1): 7 registers set
[16:42:52.427] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:42:52.427] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.427] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:52.853] INFO: enter 'restricted' command line mode
[16:42:52.853] INFO: enter test to run
[16:42:56.381] INFO: test: PixelAlive no parameter change
[16:42:56.381] INFO: running: pixelalive
[16:42:56.390] INFO: ----------------------------------------------------------------------
[16:42:56.390] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:42:56.390] INFO: ----------------------------------------------------------------------
[16:42:56.714] INFO: Expecting 41600 events.
[16:43:01.046] INFO: 41600 events read in total (3613ms).
[16:43:01.213] INFO: Test took 4819ms.
[16:43:01.226] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:01.487] INFO: PixTestAlive::aliveTest() done
[16:43:01.487] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[16:43:01.518] INFO: enter test to run
[16:43:31.589] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[16:43:31.589] INFO: running: highrate
[16:43:31.589] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[16:43:31.804] INFO: ----------------------------------------------------------------------
[16:43:31.804] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[16:43:31.804] INFO: ----------------------------------------------------------------------
[16:43:31.804] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[16:43:31.804] INFO: edge/corner pixel THR is adjusted
[16:43:31.804] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:43:32.763] INFO: Collecting data for 5 seconds...
[16:43:37.779] INFO: Done with hot pixel readout
[16:43:49.587] INFO: PixTest:: pg_setup set to default.
[16:43:49.587] INFO: 3 hot pixels found in step 0
[16:43:50.600] INFO: Collecting data for 5 seconds...
[16:43:55.615] INFO: Done with hot pixel readout
[16:44:07.423] INFO: PixTest:: pg_setup set to default.

[16:44:07.424] INFO: 2 hot pixels found in step 1
[16:44:08.419] INFO: Collecting data for 5 seconds...
[16:44:13.434] INFO: Done with hot pixel readout
[16:44:25.262] INFO: PixTest:: pg_setup set to default.
[16:44:25.263] INFO: 0 hot pixels found in step 2
[16:44:25.298] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:44:25.302] INFO: PixTest::trimHotPixels() done
[16:44:25.302] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C0.dat
[16:44:25.307] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C1.dat
[16:44:25.313] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C2.dat
[16:44:25.319] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C3.dat
[16:44:25.325] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C4.dat
[16:44:25.330] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C5.dat
[16:44:25.335] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C6.dat
[16:44:25.340] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C7.dat
[16:44:25.345] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C8.dat
[16:44:25.351] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C9.dat
[16:44:25.356] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C10.dat
[16:44:25.361] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C11.dat
[16:44:25.366] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C12.dat
[16:44:25.371] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C13.dat
[16:44:25.376] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C14.dat
[16:44:25.381] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C15.dat
[16:44:25.387] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//defaultMaskFile.dat
[16:44:25.397] INFO: enter test to run
[16:46:07.588] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[16:46:07.588] INFO: running: highrate
[16:46:07.592] INFO: ----------------------------------------------------------------------
[16:46:07.592] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[16:46:07.592] INFO: ----------------------------------------------------------------------
[16:46:07.592] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[16:46:07.592] INFO: edge/corner pixel THR is adjusted
[16:46:07.593] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:46:08.551] INFO: Collecting data for 1 seconds...
[16:46:09.554] INFO: Done with hot pixel readout
[16:46:13.599] INFO: PixTest:: pg_setup set to default.
[16:46:13.599] INFO: 0 hot pixels found in step 0
[16:46:13.604] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:46:13.692] INFO: PixTest::trimHotPixels() done
[16:46:13.693] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C0.dat
[16:46:13.704] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C1.dat
[16:46:13.710] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C2.dat
[16:46:13.716] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C3.dat
[16:46:13.721] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C4.dat
[16:46:13.726] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C5.dat
[16:46:13.731] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C6.dat
[16:46:13.736] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C7.dat
[16:46:13.741] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C8.dat
[16:46:13.747] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C9.dat
[16:46:13.782] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C10.dat
[16:46:13.792] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C11.dat
[16:46:13.797] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C12.dat
[16:46:13.802] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C13.dat
[16:46:13.807] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C14.dat
[16:46:13.813] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//trimParameters35_C15.dat
[16:46:13.818] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-2-26_FPIXTest-17C-FNAL-160614-0955_2016-06-14_09h56m_1465916162/000_FPIXTest_p17//defaultMaskFile.dat
[16:46:13.830] INFO: enter test to run
[16:47:07.803] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[16:47:07.803] INFO: running: xray
[16:47:07.805] INFO: ----------------------------------------------------------------------
[16:47:07.805] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:47:07.805] INFO: ----------------------------------------------------------------------
[16:47:08.768] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:47:20.059] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[16:47:49.420] INFO: Resuming triggers.
[16:48:00.711] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[16:48:30.281] INFO: Resuming triggers.
[16:48:41.570] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[16:49:11.042] INFO: Resuming triggers.
[16:49:22.335] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[16:49:51.774] INFO: Resuming triggers.
[16:50:03.067] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[16:50:32.507] INFO: Resuming triggers.
[16:50:43.801] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[16:51:13.289] INFO: Resuming triggers.
[16:51:24.583] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[16:51:54.009] INFO: Resuming triggers.
[16:52:05.302] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[16:52:34.720] INFO: Resuming triggers.
[16:52:44.697] INFO: data taking finished, elapsed time: 100 seconds.
[16:53:10.687] INFO: PixTest:: pg_setup set to default.
[16:53:10.691] INFO: PixTestXray::doPhRun() done
[16:53:10.827] INFO: enter test to run
[16:56:56.599] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[16:56:56.599] INFO: running: xray
[16:56:56.601] INFO: ----------------------------------------------------------------------
[16:56:56.601] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:56:56.601] INFO: ----------------------------------------------------------------------
[16:56:57.564] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:57:04.171] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[16:57:34.158] INFO: Resuming triggers.
[16:57:40.763] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[16:58:10.777] INFO: Resuming triggers.
[16:58:17.385] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[16:58:47.342] INFO: Resuming triggers.
[16:58:53.945] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[16:59:23.910] INFO: Resuming triggers.
[16:59:30.516] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[17:00:00.524] INFO: Resuming triggers.
[17:00:07.133] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[17:00:37.103] INFO: Resuming triggers.
[17:00:43.710] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[17:01:13.678] INFO: Resuming triggers.
[17:01:20.285] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[17:01:50.226] INFO: Resuming triggers.
[17:01:56.834] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[17:02:26.736] INFO: Resuming triggers.
[17:02:33.345] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[17:03:03.323] INFO: Resuming triggers.
[17:03:09.932] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[17:03:39.936] INFO: Resuming triggers.
[17:03:46.544] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[17:04:16.541] INFO: Resuming triggers.
[17:04:23.151] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[17:04:53.129] INFO: Resuming triggers.
[17:04:59.734] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[17:05:29.772] INFO: Resuming triggers.
[17:05:36.376] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[17:06:06.405] INFO: Resuming triggers.
[17:06:07.616] INFO: data taking finished, elapsed time: 100 seconds.
[17:06:13.435] INFO: PixTest:: pg_setup set to default.
[17:06:13.438] INFO: PixTestXray::doPhRun() done
[17:06:13.588] INFO: enter test to run
[17:07:24.115] INFO: test: HighRate no parameter change
[17:07:24.115] INFO: running: highrate
[17:07:24.116] INFO: ----------------------------------------------------------------------
[17:07:24.117] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:07:24.117] INFO: ----------------------------------------------------------------------
[17:07:24.259] INFO: Expecting 768 events.
[17:07:25.392] INFO: 768 events read in total (418ms).
[17:07:25.393] INFO: Test took 1269ms.
[17:07:26.196] INFO: Expecting 41600 events.
[17:07:29.275] INFO: 41600 events read in total (2552ms).
[17:07:29.276] INFO: Test took 3877ms.
[17:07:29.310] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:30.036] INFO: Expecting 41600 events.
[17:07:33.255] INFO: 41600 events read in total (2692ms).
[17:07:33.256] INFO: Test took 3928ms.
[17:07:33.290] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:34.015] INFO: Expecting 41600 events.
[17:07:37.239] INFO: 41600 events read in total (2697ms).
[17:07:37.240] INFO: Test took 3933ms.
[17:07:37.274] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:37.999] INFO: Expecting 41600 events.
[17:07:41.246] INFO: 41600 events read in total (2721ms).
[17:07:41.247] INFO: Test took 3953ms.
[17:07:41.282] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:42.006] INFO: Expecting 41600 events.
[17:07:45.241] INFO: 41600 events read in total (2708ms).
[17:07:45.242] INFO: Test took 3942ms.
[17:07:45.276] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:45.999] INFO: Expecting 41600 events.
[17:07:49.235] INFO: 41600 events read in total (2709ms).
[17:07:49.236] INFO: Test took 3942ms.
[17:07:49.271] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:49.993] INFO: Expecting 41600 events.
[17:07:53.242] INFO: 41600 events read in total (2722ms).
[17:07:53.243] INFO: Test took 3954ms.
[17:07:53.278] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:53.998] INFO: Expecting 41600 events.
[17:07:57.242] INFO: 41600 events read in total (2717ms).
[17:07:57.243] INFO: Test took 3946ms.
[17:07:57.277] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:57.001] INFO: Expecting 41600 events.
[17:08:01.248] INFO: 41600 events read in total (2720ms).
[17:08:01.249] INFO: Test took 3955ms.
[17:08:01.284] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:02.004] INFO: Expecting 41600 events.
[17:08:05.255] INFO: 41600 events read in total (2724ms).
[17:08:05.256] INFO: Test took 3953ms.
[17:08:05.291] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:06.011] INFO: Expecting 41600 events.
[17:08:09.276] INFO: 41600 events read in total (2738ms).
[17:08:09.277] INFO: Test took 3969ms.
[17:08:09.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:10.033] INFO: Expecting 41600 events.
[17:08:13.276] INFO: 41600 events read in total (2716ms).
[17:08:13.277] INFO: Test took 3947ms.
[17:08:13.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:14.037] INFO: Expecting 41600 events.
[17:08:17.280] INFO: 41600 events read in total (2716ms).
[17:08:17.281] INFO: Test took 3950ms.
[17:08:17.316] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:18.035] INFO: Expecting 41600 events.
[17:08:21.299] INFO: 41600 events read in total (2737ms).
[17:08:21.300] INFO: Test took 3965ms.
[17:08:21.336] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:22.056] INFO: Expecting 41600 events.
[17:08:25.297] INFO: 41600 events read in total (2714ms).
[17:08:25.298] INFO: Test took 3944ms.
[17:08:25.333] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:26.058] INFO: Expecting 41600 events.
[17:08:29.295] INFO: 41600 events read in total (2710ms).
[17:08:29.296] INFO: Test took 3944ms.
[17:08:29.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:30.055] INFO: Expecting 41600 events.
[17:08:33.303] INFO: 41600 events read in total (2721ms).
[17:08:33.304] INFO: Test took 3953ms.
[17:08:33.340] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:34.062] INFO: Expecting 41600 events.
[17:08:37.308] INFO: 41600 events read in total (2719ms).
[17:08:37.309] INFO: Test took 3951ms.
[17:08:37.344] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:38.064] INFO: Expecting 41600 events.
[17:08:41.303] INFO: 41600 events read in total (2712ms).
[17:08:41.304] INFO: Test took 3941ms.
[17:08:41.339] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:42.057] INFO: Expecting 41600 events.
[17:08:45.189] INFO: 41600 events read in total (2605ms).
[17:08:45.190] INFO: Test took 3831ms.
[17:08:45.224] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:45.586] INFO: enter test to run
[17:09:02.163] INFO: test: HighRate no parameter change
[17:09:02.163] INFO: running: highrate
[17:09:02.164] INFO: ----------------------------------------------------------------------
[17:09:02.164] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:09:02.164] INFO: ----------------------------------------------------------------------
[17:09:02.779] INFO: Expecting 208000 events.
[17:09:14.683] INFO: 208000 events read in total (11377ms).
[17:09:14.687] INFO: Test took 12514ms.
[17:09:14.837] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:15.091] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[17:09:15.091] INFO: number of red-efficiency pixels: 89 63 75 119 146 132 188 122 88 127 107 115 102 65 27 22
[17:09:15.091] INFO: number of X-ray hits detected: 72525 46136 73125 111121 116211 116937 121113 87655 79223 97932 95366 83540 86778 52270 24428 24841
[17:09:15.091] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:09:15.091] INFO: number of Vcal hits detected: 207909 207935 207922 207879 207851 207863 207805 207874 207911 207865 207890 207884 207897 207934 207924 207978
[17:09:15.091] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 99.9 100.0 100.0 100.0 100.0
[17:09:15.091] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[17:09:15.091] INFO: X-ray hit rate [MHz/cm2]: 21.3 13.5 21.4 32.6 34.1 34.3 35.5 25.7 23.2 28.7 28.0 24.5 25.4 15.3 7.2 7.3
[17:09:15.091] INFO: PixTestHighRate::doXPixelAlive() done
[17:09:15.145] INFO: PixTest:: pg_setup set to default.
[17:09:15.160] INFO: enter test to run
[17:09:53.178] INFO: test: HighRate no parameter change
[17:09:53.178] INFO: running: highrate
[17:09:53.180] INFO: ----------------------------------------------------------------------
[17:09:53.180] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:09:53.180] INFO: ----------------------------------------------------------------------
[17:09:53.801] INFO: Expecting 208000 events.
[17:10:07.445] INFO: 208000 events read in total (13118ms).
[17:10:07.451] INFO: Test took 14262ms.
[17:10:07.743] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:08.045] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[17:10:08.045] INFO: number of red-efficiency pixels: 274 138 234 353 523 413 588 338 204 298 322 232 290 173 53 61
[17:10:08.045] INFO: number of X-ray hits detected: 146953 93887 147528 223731 235776 237397 243707 177748 159786 196778 192689 170140 175965 106265 49946 51071
[17:10:08.045] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:10:08.045] INFO: number of Vcal hits detected: 207701 207855 207750 207628 207439 207557 207351 207634 207792 207673 207655 207751 207696 207822 207898 207939
[17:10:08.045] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[17:10:08.045] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.7 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[17:10:08.045] INFO: X-ray hit rate [MHz/cm2]: 43.1 27.5 43.2 65.6 69.1 69.6 71.4 52.1 46.8 57.7 56.5 49.9 51.6 31.1 14.6 15.0
[17:10:08.045] INFO: PixTestHighRate::doXPixelAlive() done
[17:10:08.093] INFO: PixTest:: pg_setup set to default.
[17:10:08.107] INFO: enter test to run
[17:10:21.570] INFO: test: HighRate no parameter change
[17:10:21.570] INFO: running: highrate
[17:10:21.571] INFO: ----------------------------------------------------------------------
[17:10:21.571] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:10:21.571] INFO: ----------------------------------------------------------------------
[17:10:22.191] INFO: Expecting 208000 events.
[17:10:37.766] INFO: 208000 events read in total (15048ms).
[17:10:37.773] INFO: Test took 16192ms.
[17:10:38.217] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:38.567] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[17:10:38.567] INFO: number of red-efficiency pixels: 603 303 532 874 1317 989 1419 820 434 680 801 511 541 385 115 117
[17:10:38.567] INFO: number of X-ray hits detected: 224252 143804 224956 341081 359001 360567 371969 270731 244804 301555 295001 258140 269119 162016 76187 78867
[17:10:38.567] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:10:38.567] INFO: number of Vcal hits detected: 207252 207652 207319 206930 206311 206799 206122 206959 207533 207203 207072 207433 207407 207570 207832 207882
[17:10:38.567] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.7 99.5 99.3 99.5 99.2 99.5 99.8 99.6 99.6 99.8 99.7 99.8 99.9 99.9
[17:10:38.567] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.7 99.5 99.2 99.4 99.1 99.5 99.8 99.6 99.6 99.7 99.7 99.8 99.9 99.9
[17:10:38.567] INFO: X-ray hit rate [MHz/cm2]: 65.7 42.2 65.9 100.0 105.2 105.7 109.0 79.4 71.8 88.4 86.5 75.7 78.9 47.5 22.3 23.1
[17:10:38.567] INFO: PixTestHighRate::doXPixelAlive() done
[17:10:38.617] INFO: PixTest:: pg_setup set to default.
[17:10:38.633] INFO: enter test to run
[17:10:53.186] INFO: test: exit no parameter change
[17:10:53.466] QUIET: Connection to board 33 closed.
[17:10:53.467] INFO: pXar: this is the end, my friend