[15:20:26.960] INFO: *** Welcome to pxar ***
[15:20:26.960] INFO: *** Today: 2016/08/18
[15:20:26.975] INFO: *** Version: v1.9.0-818-g96727
[15:20:26.975] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//dacParameters35_C15.dat
[15:20:26.976] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//tbmParameters_C0b.dat
[15:20:26.976] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//defaultMaskFile.dat
[15:20:26.976] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C15.dat
[15:20:27.048] INFO: clk: 4
[15:20:27.048] INFO: ctr: 4
[15:20:27.048] INFO: sda: 19
[15:20:27.048] INFO: tin: 9
[15:20:27.048] INFO: level: 15
[15:20:27.048] INFO: triggerdelay: 0
[15:20:27.048] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[15:20:27.048] INFO: Log level: INFO
[15:20:27.066] QUIET: Connection to board DTB_WREKRL opened.
[15:20:27.069] 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:
------------------------------------------------------
[15:20:27.072] INFO: RPC call hashes of host and DTB match: 398089610
[15:20:28.604] INFO: DUT info:
[15:20:28.605] INFO: The DUT currently contains the following objects:
[15:20:28.605] INFO: 2 TBM Cores tbm08c (2 ON)
[15:20:28.605] INFO: TBM Core alpha (0): 7 registers set
[15:20:28.605] INFO: TBM Core beta (1): 7 registers set
[15:20:28.605] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:20:28.605] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:28.605] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:29.008] INFO: enter 'restricted' command line mode
[15:20:29.008] INFO: enter test to run
[15:20:36.012] INFO: test: PixelAlive no parameter change
[15:20:36.012] INFO: running: pixelalive
[15:20:36.021] INFO: ----------------------------------------------------------------------
[15:20:36.021] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:20:36.021] INFO: ----------------------------------------------------------------------
[15:20:36.342] INFO: Expecting 41600 events.
[15:20:40.671] INFO: 41600 events read in total (3611ms).
[15:20:40.839] INFO: Test took 4815ms.
[15:20:40.851] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:41.116] INFO: PixTestAlive::aliveTest() done
[15:20:41.116] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:20:41.146] INFO: enter test to run
[15:21:08.276] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:21:08.276] INFO: running: highrate
[15:21:08.276] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//phCalibrationFitErr35_C15.dat
[15:21:08.491] INFO: ----------------------------------------------------------------------
[15:21:08.491] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:21:08.491] INFO: ----------------------------------------------------------------------
[15:21:08.491] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:21:08.491] INFO: edge/corner pixel THR is adjusted
[15:21:08.491] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:21:09.450] INFO: Collecting data for 5 seconds...
[15:21:14.467] INFO: Done with hot pixel readout
[15:21:25.616] INFO: PixTest:: pg_setup set to default.
[15:21:25.616] INFO: 6 hot pixels found in step 0
[15:21:26.622] INFO: Collecting data for 5 seconds...
[15:21:31.638] INFO: Done with hot pixel readout
[15:21:42.774] INFO: PixTest:: pg_setup set to default.
[15:21:42.775] INFO: 4 hot pixels found in step 1
[15:21:43.762] INFO: Collecting data for 5 seconds...
[15:21:48.779] INFO: Done with hot pixel readout
[15:22:00.030] INFO: PixTest:: pg_setup set to default.
[15:22:00.030] INFO: 1 hot pixels found in step 2
[15:22:01.018] INFO: Collecting data for 5 seconds...
[15:22:06.034] INFO: Done with hot pixel readout
[15:22:17.227] INFO: PixTest:: pg_setup set to default.
[15:22:17.228] INFO: 1 hot pixels found in step 3
[15:22:18.215] INFO: Collecting data for 5 seconds...
[15:22:23.232] INFO: Done with hot pixel readout
[15:22:34.482] INFO: PixTest:: pg_setup set to default.
[15:22:34.483] INFO: 2 hot pixels found in step 4
[15:22:35.470] INFO: Collecting data for 5 seconds...
[15:22:40.487] INFO: Done with hot pixel readout
[15:22:51.747] INFO: PixTest:: pg_setup set to default.
[15:22:51.748] INFO: 0 hot pixels found in step 5
[15:22:51.779] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:22:51.782] INFO: PixTest::trimHotPixels() done
[15:22:51.782] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C0.dat
[15:22:51.788] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C1.dat
[15:22:51.794] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C2.dat
[15:22:51.799] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C3.dat
[15:22:51.804] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C4.dat
[15:22:51.809] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C5.dat
[15:22:51.814] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C6.dat
[15:22:51.820] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C7.dat
[15:22:51.825] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C8.dat
[15:22:51.830] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C9.dat
[15:22:51.835] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C10.dat
[15:22:51.840] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C11.dat
[15:22:51.846] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C12.dat
[15:22:51.851] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C13.dat
[15:22:51.856] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C14.dat
[15:22:51.861] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C15.dat
[15:22:51.866] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//defaultMaskFile.dat
[15:22:51.880] INFO: enter test to run
[15:23:37.515] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[15:23:37.515] INFO: running: highrate
[15:23:37.520] INFO: ----------------------------------------------------------------------
[15:23:37.520] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:23:37.520] INFO: ----------------------------------------------------------------------
[15:23:37.520] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:23:37.520] INFO: edge/corner pixel THR is adjusted
[15:23:37.520] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:23:38.478] INFO: Collecting data for 1 seconds...
[15:23:39.482] INFO: Done with hot pixel readout
[15:23:43.264] INFO: PixTest:: pg_setup set to default.
[15:23:43.265] INFO: 0 hot pixels found in step 0
[15:23:43.271] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:23:43.370] INFO: PixTest::trimHotPixels() done
[15:23:43.370] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C0.dat
[15:23:43.382] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C1.dat
[15:23:43.388] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C2.dat
[15:23:43.393] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C3.dat
[15:23:43.399] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C4.dat
[15:23:43.404] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C5.dat
[15:23:43.410] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C6.dat
[15:23:43.415] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C7.dat
[15:23:43.420] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C8.dat
[15:23:43.425] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C9.dat
[15:23:43.431] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C10.dat
[15:23:43.437] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C11.dat
[15:23:43.442] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C12.dat
[15:23:43.447] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C13.dat
[15:23:43.452] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C14.dat
[15:23:43.457] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//trimParameters35_C15.dat
[15:23:43.463] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-5-09_FPIXTest-m20C-FNAL-160816-1000-150V_2016-08-16_10h00m_1471359628/000_FPIXTest_m20//defaultMaskFile.dat
[15:23:43.473] INFO: enter test to run
[15:24:17.403] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:24:17.403] INFO: running: xray
[15:24:17.404] INFO: ----------------------------------------------------------------------
[15:24:17.404] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:24:17.404] INFO: ----------------------------------------------------------------------
[15:24:18.367] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:24:30.351] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:25:01.305] INFO: Resuming triggers.
[15:25:13.292] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:25:44.791] INFO: Resuming triggers.
[15:25:56.777] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[15:26:28.287] INFO: Resuming triggers.
[15:26:40.274] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[15:27:10.916] INFO: Resuming triggers.
[15:27:22.903] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[15:27:53.199] INFO: Resuming triggers.
[15:28:05.188] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[15:28:36.228] INFO: Resuming triggers.
[15:28:48.216] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[15:29:19.093] INFO: Resuming triggers.
[15:29:31.079] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[15:30:02.011] INFO: Resuming triggers.
[15:30:06.474] INFO: data taking finished, elapsed time: 100 seconds.
[15:30:18.308] INFO: PixTest:: pg_setup set to default.
[15:30:18.312] INFO: PixTestXray::doPhRun() done
[15:30:18.453] INFO: enter test to run
[15:30:45.648] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:30:45.648] INFO: running: xray
[15:30:45.650] INFO: ----------------------------------------------------------------------
[15:30:45.650] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:30:45.650] INFO: ----------------------------------------------------------------------
[15:30:46.613] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:30:53.785] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:31:26.967] INFO: Resuming triggers.
[15:31:34.144] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[15:32:07.336] INFO: Resuming triggers.
[15:32:14.513] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[15:32:47.416] INFO: Resuming triggers.
[15:32:54.593] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[15:33:27.664] INFO: Resuming triggers.
[15:33:34.842] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[15:34:07.470] INFO: Resuming triggers.
[15:34:14.645] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[15:34:48.342] INFO: Resuming triggers.
[15:34:55.516] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[15:35:27.765] INFO: Resuming triggers.
[15:35:34.938] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[15:36:07.646] INFO: Resuming triggers.
[15:36:14.815] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[15:36:47.012] INFO: Resuming triggers.
[15:36:54.184] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[15:37:25.956] INFO: Resuming triggers.
[15:37:33.125] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[15:38:05.383] INFO: Resuming triggers.
[15:38:12.552] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[15:38:43.661] INFO: Resuming triggers.
[15:38:50.830] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[15:39:24.454] INFO: Resuming triggers.
[15:39:31.539] INFO: data taking finished, elapsed time: 100 seconds.
[15:40:04.820] INFO: PixTest:: pg_setup set to default.
[15:40:04.824] INFO: PixTestXray::doPhRun() done
[15:40:04.974] INFO: enter test to run
[15:40:36.420] INFO: test: HighRate no parameter change
[15:40:36.420] INFO: running: highrate
[15:40:36.437] INFO: ----------------------------------------------------------------------
[15:40:36.437] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:40:36.437] INFO: ----------------------------------------------------------------------
[15:40:36.620] INFO: Expecting 768 events.
[15:40:37.763] INFO: 768 events read in total (428ms).
[15:40:37.763] INFO: Test took 1289ms.
[15:40:38.566] INFO: Expecting 41600 events.
[15:40:41.646] INFO: 41600 events read in total (2553ms).
[15:40:41.647] INFO: Test took 3863ms.
[15:40:41.676] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:42.411] INFO: Expecting 41600 events.
[15:40:45.523] INFO: 41600 events read in total (2586ms).
[15:40:45.524] INFO: Test took 3831ms.
[15:40:45.555] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:46.283] INFO: Expecting 41600 events.
[15:40:49.460] INFO: 41600 events read in total (2650ms).
[15:40:49.461] INFO: Test took 3890ms.
[15:40:49.492] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:50.220] INFO: Expecting 41600 events.
[15:40:53.372] INFO: 41600 events read in total (2625ms).
[15:40:53.373] INFO: Test took 3865ms.
[15:40:53.405] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:54.130] INFO: Expecting 41600 events.
[15:40:57.286] INFO: 41600 events read in total (2629ms).
[15:40:57.287] INFO: Test took 3866ms.
[15:40:57.318] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:58.045] INFO: Expecting 41600 events.
[15:41:01.251] INFO: 41600 events read in total (2679ms).
[15:41:01.252] INFO: Test took 3917ms.
[15:41:01.286] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:01.995] INFO: Expecting 41600 events.
[15:41:05.192] INFO: 41600 events read in total (2670ms).
[15:41:05.193] INFO: Test took 3886ms.
[15:41:05.223] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:05.957] INFO: Expecting 41600 events.
[15:41:09.163] INFO: 41600 events read in total (2679ms).
[15:41:09.164] INFO: Test took 3924ms.
[15:41:09.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:09.922] INFO: Expecting 41600 events.
[15:41:13.174] INFO: 41600 events read in total (2725ms).
[15:41:13.175] INFO: Test took 3963ms.
[15:41:13.206] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:13.936] INFO: Expecting 41600 events.
[15:41:17.184] INFO: 41600 events read in total (2721ms).
[15:41:17.185] INFO: Test took 3962ms.
[15:41:17.215] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:17.946] INFO: Expecting 41600 events.
[15:41:21.214] INFO: 41600 events read in total (2741ms).
[15:41:21.215] INFO: Test took 3984ms.
[15:41:21.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:21.963] INFO: Expecting 41600 events.
[15:41:25.127] INFO: 41600 events read in total (2638ms).
[15:41:25.128] INFO: Test took 3858ms.
[15:41:25.161] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:25.887] INFO: Expecting 41600 events.
[15:41:29.078] INFO: 41600 events read in total (2665ms).
[15:41:29.078] INFO: Test took 3901ms.
[15:41:29.109] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:29.841] INFO: Expecting 41600 events.
[15:41:33.094] INFO: 41600 events read in total (2726ms).
[15:41:33.095] INFO: Test took 3970ms.
[15:41:33.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:33.853] INFO: Expecting 41600 events.
[15:41:37.096] INFO: 41600 events read in total (2716ms).
[15:41:37.097] INFO: Test took 3951ms.
[15:41:37.127] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:37.862] INFO: Expecting 41600 events.
[15:41:41.115] INFO: 41600 events read in total (2727ms).
[15:41:41.116] INFO: Test took 3972ms.
[15:41:41.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:41.876] INFO: Expecting 41600 events.
[15:41:45.120] INFO: 41600 events read in total (2717ms).
[15:41:45.121] INFO: Test took 3958ms.
[15:41:45.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:45.884] INFO: Expecting 41600 events.
[15:41:49.112] INFO: 41600 events read in total (2701ms).
[15:41:49.113] INFO: Test took 3944ms.
[15:41:49.143] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:49.874] INFO: Expecting 41600 events.
[15:41:53.075] INFO: 41600 events read in total (2675ms).
[15:41:53.076] INFO: Test took 3917ms.
[15:41:53.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:53.837] INFO: Expecting 41600 events.
[15:41:56.921] INFO: 41600 events read in total (2558ms).
[15:41:56.922] INFO: Test took 3792ms.
[15:41:56.951] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:57.317] INFO: enter test to run
[15:42:19.900] INFO: test: HighRate no parameter change
[15:42:19.900] INFO: running: highrate
[15:42:19.901] INFO: ----------------------------------------------------------------------
[15:42:19.901] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:42:19.901] INFO: ----------------------------------------------------------------------
[15:42:20.521] INFO: Expecting 208000 events.
[15:42:32.470] INFO: 208000 events read in total (11422ms).
[15:42:32.472] INFO: Test took 12563ms.
[15:42:32.599] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:32.844] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:42:32.844] INFO: number of red-efficiency pixels: 75 38 61 115 108 124 153 83 79 83 101 80 76 45 17 22
[15:42:32.844] INFO: number of X-ray hits detected: 58445 34189 53617 84943 88363 97924 101758 74912 68691 81261 83466 70347 74807 42956 18073 19090
[15:42:32.844] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:42:32.844] INFO: number of Vcal hits detected: 207923 207961 207938 207880 207892 207874 207844 207916 207920 207917 207896 207920 207923 207954 207983 207978
[15:42:32.844] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[15:42:32.844] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[15:42:32.844] INFO: X-ray hit rate [MHz/cm2]: 17.1 10.0 15.7 24.9 25.9 28.7 29.8 22.0 20.1 23.8 24.5 20.6 21.9 12.6 5.3 5.6
[15:42:32.844] INFO: PixTestHighRate::doXPixelAlive() done
[15:42:32.891] INFO: PixTest:: pg_setup set to default.
[15:42:32.907] INFO: enter test to run
[15:42:51.211] INFO: test: HighRate no parameter change
[15:42:51.211] INFO: running: highrate
[15:42:51.212] INFO: ----------------------------------------------------------------------
[15:42:51.212] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:42:51.212] INFO: ----------------------------------------------------------------------
[15:42:51.838] INFO: Expecting 208000 events.
[15:43:05.252] INFO: 208000 events read in total (12888ms).
[15:43:05.257] INFO: Test took 14035ms.
[15:43:05.519] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:05.810] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:43:05.810] INFO: number of red-efficiency pixels: 218 115 149 354 367 330 512 259 252 226 361 218 250 139 56 61
[15:43:05.810] INFO: number of X-ray hits detected: 123867 72915 113407 180854 187734 208332 217543 159382 146726 172412 177496 150004 159530 91945 38362 41472
[15:43:05.810] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:43:05.810] INFO: number of Vcal hits detected: 207770 207881 207850 207620 207612 207651 207433 207721 207737 207762 207626 207774 207739 207853 207942 207939
[15:43:05.810] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[15:43:05.810] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[15:43:05.811] INFO: X-ray hit rate [MHz/cm2]: 36.3 21.4 33.2 53.0 55.0 61.1 63.8 46.7 43.0 50.5 52.0 44.0 46.8 26.9 11.2 12.2
[15:43:05.811] INFO: PixTestHighRate::doXPixelAlive() done
[15:43:05.859] INFO: PixTest:: pg_setup set to default.
[15:43:05.874] INFO: enter test to run
[15:43:25.403] INFO: test: HighRate no parameter change
[15:43:25.403] INFO: running: highrate
[15:43:25.404] INFO: ----------------------------------------------------------------------
[15:43:25.404] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:43:25.404] INFO: ----------------------------------------------------------------------
[15:43:26.034] INFO: Expecting 208000 events.
[15:43:41.146] INFO: 208000 events read in total (14585ms).
[15:43:41.153] INFO: Test took 15738ms.
[15:43:41.536] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:41.867] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:43:41.867] INFO: number of red-efficiency pixels: 452 179 390 808 825 745 1182 573 518 549 795 421 537 310 83 64
[15:43:41.867] INFO: number of X-ray hits detected: 186658 109272 170004 269970 281445 311919 327090 241250 220593 258202 266970 227063 240980 138241 58132 62840
[15:43:41.867] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:43:41.867] INFO: number of Vcal hits detected: 207459 207811 207539 207079 207027 207146 206480 207332 207421 207390 207108 207540 207401 207627 207916 207935
[15:43:41.867] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.6 99.6 99.3 99.7 99.7 99.7 99.6 99.8 99.7 99.8 100.0 100.0
[15:43:41.867] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.8 99.6 99.5 99.6 99.3 99.7 99.7 99.7 99.6 99.8 99.7 99.8 100.0 100.0
[15:43:41.867] INFO: X-ray hit rate [MHz/cm2]: 54.7 32.0 49.8 79.1 82.5 91.4 95.9 70.7 64.7 75.7 78.3 66.6 70.6 40.5 17.0 18.4
[15:43:41.867] INFO: PixTestHighRate::doXPixelAlive() done
[15:43:41.914] INFO: PixTest:: pg_setup set to default.
[15:43:41.925] INFO: enter test to run
[15:43:45.539] INFO: test: exit no parameter change
[15:43:45.953] QUIET: Connection to board 33 closed.
[15:43:45.966] INFO: pXar: this is the end, my friend