[11:49:30.992] INFO: *** Welcome to pxar ***
[11:49:30.992] INFO: *** Today: 2016/05/18
[11:49:31.537] INFO: *** Version: v1.9.0-796-gef167-dirty
[11:49:31.537] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//dacParameters35_C15.dat
[11:49:31.579] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:49:31.579] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//defaultMaskFile.dat
[11:49:31.579] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C15.dat
[11:49:31.705] INFO: clk: 4
[11:49:31.705] INFO: ctr: 4
[11:49:31.705] INFO: sda: 19
[11:49:31.705] INFO: tin: 9
[11:49:31.705] INFO: level: 15
[11:49:31.705] INFO: triggerdelay: 0
[11:49:31.705] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[11:49:31.705] INFO: Log level: INFO
[11:49:31.722] QUIET: Connection to board DTB_WREKRL opened.
[11:49:31.725] 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:
------------------------------------------------------
[11:49:31.728] INFO: RPC call hashes of host and DTB match: 398089610
[11:49:33.266] INFO: DUT info:
[11:49:33.266] INFO: The DUT currently contains the following objects:
[11:49:33.266] INFO: 2 TBM Cores tbm08c (2 ON)
[11:49:33.280] INFO: TBM Core alpha (0): 7 registers set
[11:49:33.280] INFO: TBM Core beta (1): 7 registers set
[11:49:33.280] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:49:33.280] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.280] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.280] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.280] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.280] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.280] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.280] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.280] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.280] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.280] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.280] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.280] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.280] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.281] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.281] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.281] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:33.726] INFO: enter 'restricted' command line mode
[11:49:33.726] INFO: enter test to run
[11:49:39.240] INFO: test: PixelAlive no parameter change
[11:49:39.240] INFO: running: pixelalive
[11:49:39.314] INFO: ----------------------------------------------------------------------
[11:49:39.314] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:49:39.315] INFO: ----------------------------------------------------------------------
[11:49:39.640] INFO: Expecting 41600 events.
[11:49:43.984] INFO: 41600 events read in total (3625ms).
[11:49:44.154] INFO: Test took 4836ms.
[11:49:44.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:49:44.839] INFO: PixTestAlive::aliveTest() done
[11:49:44.839] INFO: number of dead pixels (per ROC): 2 0 3 1 0 0 1 0 0 0 0 0 0 0 0 0
[11:49:44.902] INFO: enter test to run
[11:50:40.583] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:50:40.583] INFO: running: highrate
[11:50:40.584] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:50:40.853] INFO: ----------------------------------------------------------------------
[11:50:40.853] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:50:40.853] INFO: ----------------------------------------------------------------------
[11:50:40.853] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[11:50:40.853] INFO: edge/corner pixel THR is adjusted
[11:50:40.853] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:50:41.820] INFO: Collecting data for 5 seconds...
[11:50:46.837] INFO: Done with hot pixel readout
[11:50:57.263] INFO: PixTest:: pg_setup set to default.
[11:50:57.264] INFO: 0 hot pixels found in step 0
[11:50:57.276] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:50:57.280] INFO: PixTest::trimHotPixels() done
[11:50:57.299] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C0.dat
[11:50:57.305] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C1.dat
[11:50:57.310] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C2.dat
[11:50:57.315] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C3.dat
[11:50:57.320] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C4.dat
[11:50:57.325] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C5.dat
[11:50:57.331] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C6.dat
[11:50:57.336] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C7.dat
[11:50:57.341] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C8.dat
[11:50:57.346] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C9.dat
[11:50:57.352] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C10.dat
[11:50:57.357] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C11.dat
[11:50:57.362] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C12.dat
[11:50:57.367] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C13.dat
[11:50:57.373] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C14.dat
[11:50:57.378] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C15.dat
[11:50:57.383] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//defaultMaskFile.dat
[11:50:57.393] INFO: enter test to run
[11:51:39.879] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:51:39.879] INFO: running: highrate
[11:51:39.883] INFO: ----------------------------------------------------------------------
[11:51:39.883] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:51:39.883] INFO: ----------------------------------------------------------------------
[11:51:39.883] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:51:39.883] INFO: edge/corner pixel THR is adjusted
[11:51:39.883] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:51:40.841] INFO: Collecting data for 1 seconds...
[11:51:41.845] INFO: Done with hot pixel readout
[11:51:45.114] INFO: PixTest:: pg_setup set to default.
[11:51:45.115] INFO: 0 hot pixels found in step 0
[11:51:45.121] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:51:45.219] INFO: PixTest::trimHotPixels() done
[11:51:45.219] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C0.dat
[11:51:45.224] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C1.dat
[11:51:45.230] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C2.dat
[11:51:45.236] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C3.dat
[11:51:45.241] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C4.dat
[11:51:45.247] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C5.dat
[11:51:45.253] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C6.dat
[11:51:45.258] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C7.dat
[11:51:45.264] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C8.dat
[11:51:45.269] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C9.dat
[11:51:45.275] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C10.dat
[11:51:45.280] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C11.dat
[11:51:45.285] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C12.dat
[11:51:45.290] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C13.dat
[11:51:45.296] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C14.dat
[11:51:45.301] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C15.dat
[11:51:45.306] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-32_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//defaultMaskFile.dat
[11:51:45.316] INFO: enter test to run
[11:52:20.335] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:52:20.335] INFO: running: xray
[11:52:20.336] INFO: ----------------------------------------------------------------------
[11:52:20.336] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:52:20.336] INFO: ----------------------------------------------------------------------
[11:52:21.299] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:52:33.326] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:53:01.343] INFO: Resuming triggers.
[11:53:13.370] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:53:41.324] INFO: Resuming triggers.
[11:53:53.351] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[11:54:21.767] INFO: Resuming triggers.
[11:54:33.794] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[11:55:02.834] INFO: Resuming triggers.
[11:55:14.864] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[11:55:43.627] INFO: Resuming triggers.
[11:55:55.655] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[11:56:25.036] INFO: Resuming triggers.
[11:56:37.066] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[11:57:06.425] INFO: Resuming triggers.
[11:57:18.456] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[11:57:47.665] INFO: Resuming triggers.
[11:57:51.793] INFO: data taking finished, elapsed time: 100 seconds.
[11:58:02.124] INFO: PixTest:: pg_setup set to default.
[11:58:02.127] INFO: PixTestXray::doPhRun() done
[11:58:02.258] INFO: enter test to run
[11:58:27.076] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:58:27.076] INFO: running: xray
[11:58:27.077] INFO: ----------------------------------------------------------------------
[11:58:27.077] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:58:27.077] INFO: ----------------------------------------------------------------------
[11:58:28.040] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:58:35.532] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[11:59:05.738] INFO: Resuming triggers.
[11:59:13.229] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[11:59:43.355] INFO: Resuming triggers.
[11:59:50.844] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[12:00:20.539] INFO: Resuming triggers.
[12:00:28.025] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[12:00:58.478] INFO: Resuming triggers.
[12:01:05.967] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[12:01:35.705] INFO: Resuming triggers.
[12:01:43.193] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[12:02:13.310] INFO: Resuming triggers.
[12:02:20.795] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[12:02:50.651] INFO: Resuming triggers.
[12:02:58.138] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[12:03:27.505] INFO: Resuming triggers.
[12:03:34.992] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[12:04:03.968] INFO: Resuming triggers.
[12:04:11.455] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[12:04:41.102] INFO: Resuming triggers.
[12:04:48.591] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[12:05:17.879] INFO: Resuming triggers.
[12:05:25.369] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[12:05:54.836] INFO: Resuming triggers.
[12:06:02.323] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[12:06:31.461] INFO: Resuming triggers.
[12:06:34.444] INFO: data taking finished, elapsed time: 100 seconds.
[12:06:46.220] INFO: PixTest:: pg_setup set to default.
[12:06:46.223] INFO: PixTestXray::doPhRun() done
[12:06:46.373] INFO: enter test to run
[12:08:39.135] INFO: test: HighRate no parameter change
[12:08:39.135] INFO: running: highrate
[12:08:39.155] INFO: ----------------------------------------------------------------------
[12:08:39.155] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:08:39.155] INFO: ----------------------------------------------------------------------
[12:08:39.312] INFO: Expecting 768 events.
[12:08:40.445] INFO: 768 events read in total (418ms).
[12:08:40.445] INFO: Test took 1269ms.
[12:08:41.249] INFO: Expecting 41600 events.
[12:08:44.342] INFO: 41600 events read in total (2566ms).
[12:08:44.343] INFO: Test took 3891ms.
[12:08:44.372] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:45.105] INFO: Expecting 41600 events.
[12:08:48.353] INFO: 41600 events read in total (2721ms).
[12:08:48.354] INFO: Test took 3967ms.
[12:08:48.384] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:49.115] INFO: Expecting 41600 events.
[12:08:52.317] INFO: 41600 events read in total (2675ms).
[12:08:52.318] INFO: Test took 3918ms.
[12:08:52.348] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:53.074] INFO: Expecting 41600 events.
[12:08:56.263] INFO: 41600 events read in total (2662ms).
[12:08:56.264] INFO: Test took 3901ms.
[12:08:56.292] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:57.030] INFO: Expecting 41600 events.
[12:09:00.223] INFO: 41600 events read in total (2666ms).
[12:09:00.224] INFO: Test took 3916ms.
[12:09:00.252] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:00.984] INFO: Expecting 41600 events.
[12:09:04.193] INFO: 41600 events read in total (2682ms).
[12:09:04.193] INFO: Test took 3924ms.
[12:09:04.222] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:04.955] INFO: Expecting 41600 events.
[12:09:08.166] INFO: 41600 events read in total (2685ms).
[12:09:08.167] INFO: Test took 3929ms.
[12:09:08.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:08.927] INFO: Expecting 41600 events.
[12:09:12.094] INFO: 41600 events read in total (2640ms).
[12:09:12.095] INFO: Test took 3883ms.
[12:09:12.124] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:12.862] INFO: Expecting 41600 events.
[12:09:15.965] INFO: 41600 events read in total (2576ms).
[12:09:15.965] INFO: Test took 3824ms.
[12:09:15.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:16.724] INFO: Expecting 41600 events.
[12:09:19.916] INFO: 41600 events read in total (2665ms).
[12:09:19.917] INFO: Test took 3906ms.
[12:09:19.945] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:20.681] INFO: Expecting 41600 events.
[12:09:23.884] INFO: 41600 events read in total (2676ms).
[12:09:23.885] INFO: Test took 3922ms.
[12:09:23.913] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:24.645] INFO: Expecting 41600 events.
[12:09:27.860] INFO: 41600 events read in total (2688ms).
[12:09:27.861] INFO: Test took 3930ms.
[12:09:27.890] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:28.625] INFO: Expecting 41600 events.
[12:09:31.829] INFO: 41600 events read in total (2678ms).
[12:09:31.830] INFO: Test took 3923ms.
[12:09:31.860] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:32.591] INFO: Expecting 41600 events.
[12:09:35.802] INFO: 41600 events read in total (2684ms).
[12:09:35.803] INFO: Test took 3926ms.
[12:09:35.832] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:36.566] INFO: Expecting 41600 events.
[12:09:39.773] INFO: 41600 events read in total (2680ms).
[12:09:39.774] INFO: Test took 3925ms.
[12:09:39.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:40.534] INFO: Expecting 41600 events.
[12:09:43.689] INFO: 41600 events read in total (2628ms).
[12:09:43.690] INFO: Test took 3870ms.
[12:09:43.719] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:44.455] INFO: Expecting 41600 events.
[12:09:47.613] INFO: 41600 events read in total (2631ms).
[12:09:47.614] INFO: Test took 3878ms.
[12:09:47.643] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:48.378] INFO: Expecting 41600 events.
[12:09:51.572] INFO: 41600 events read in total (2667ms).
[12:09:51.573] INFO: Test took 3914ms.
[12:09:51.602] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:52.336] INFO: Expecting 41600 events.
[12:09:55.433] INFO: 41600 events read in total (2570ms).
[12:09:55.434] INFO: Test took 3816ms.
[12:09:55.463] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:56.198] INFO: Expecting 41600 events.
[12:09:59.151] INFO: 41600 events read in total (2426ms).
[12:09:59.152] INFO: Test took 3673ms.
[12:09:59.179] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:59.555] INFO: enter test to run
[12:11:26.830] INFO: test: HighRate no parameter change
[12:11:26.830] INFO: running: highrate
[12:11:26.831] INFO: ----------------------------------------------------------------------
[12:11:26.831] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:11:26.831] INFO: ----------------------------------------------------------------------
[12:11:27.442] INFO: Expecting 208000 events.
[12:11:39.026] INFO: 208000 events read in total (11057ms).
[12:11:39.028] INFO: Test took 12187ms.
[12:11:39.145] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:39.387] INFO: number of dead pixels (per ROC): 2 0 3 1 0 0 1 0 0 0 0 0 0 0 0 0
[12:11:39.388] INFO: number of red-efficiency pixels: 73 38 50 71 100 98 125 59 64 92 78 65 105 52 23 22
[12:11:39.388] INFO: number of X-ray hits detected: 54206 39181 52768 81739 87289 87265 87052 64247 60712 80197 79216 69055 72928 42984 19225 21723
[12:11:39.388] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:11:39.388] INFO: number of Vcal hits detected: 207828 207962 207803 207877 207899 207892 207820 207940 207933 207906 207921 207935 207894 207948 207977 207978
[12:11:39.388] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[12:11:39.388] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0
[12:11:39.388] INFO: X-ray hit rate [MHz/cm2]: 15.9 11.5 15.5 24.0 25.6 25.6 25.5 18.8 17.8 23.5 23.2 20.2 21.4 12.6 5.6 6.4
[12:11:39.388] INFO: PixTestHighRate::doXPixelAlive() done
[12:11:39.439] INFO: PixTest:: pg_setup set to default.
[12:11:39.450] INFO: enter test to run
[12:12:39.693] INFO: test: HighRate no parameter change
[12:12:39.694] INFO: running: highrate
[12:12:39.695] INFO: ----------------------------------------------------------------------
[12:12:39.695] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:12:39.695] INFO: ----------------------------------------------------------------------
[12:12:40.305] INFO: Expecting 208000 events.
[12:12:53.136] INFO: 208000 events read in total (12304ms).
[12:12:53.141] INFO: Test took 13438ms.
[12:12:53.375] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:12:53.658] INFO: number of dead pixels (per ROC): 2 0 3 1 0 0 1 0 0 0 0 0 0 0 0 0
[12:12:53.658] INFO: number of red-efficiency pixels: 178 111 225 233 332 274 360 175 169 240 246 213 279 150 41 50
[12:12:53.658] INFO: number of X-ray hits detected: 115103 82454 111878 172248 184506 184017 183998 135021 127361 169748 167989 146591 154857 90590 40778 45745
[12:12:53.658] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:12:53.659] INFO: number of Vcal hits detected: 207719 207886 207607 207700 207648 207693 207566 207819 207825 207749 207740 207782 207708 207846 207959 207948
[12:12:53.659] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[12:12:53.659] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.9 99.8 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[12:12:53.659] INFO: X-ray hit rate [MHz/cm2]: 33.7 24.2 32.8 50.5 54.1 53.9 53.9 39.6 37.3 49.8 49.2 43.0 45.4 26.6 12.0 13.4
[12:12:53.659] INFO: PixTestHighRate::doXPixelAlive() done
[12:12:53.704] INFO: PixTest:: pg_setup set to default.
[12:12:53.718] INFO: enter test to run
[12:13:34.237] INFO: test: HighRate no parameter change
[12:13:34.237] INFO: running: highrate
[12:13:34.238] INFO: ----------------------------------------------------------------------
[12:13:34.238] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:13:34.238] INFO: ----------------------------------------------------------------------
[12:13:34.854] INFO: Expecting 208000 events.
[12:13:49.365] INFO: 208000 events read in total (13984ms).
[12:13:49.371] INFO: Test took 15123ms.
[12:13:49.733] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:50.057] INFO: number of dead pixels (per ROC): 2 0 3 1 0 0 1 0 0 0 0 0 0 0 0 0
[12:13:50.057] INFO: number of red-efficiency pixels: 335 202 435 497 766 579 783 384 361 516 470 435 585 294 80 86
[12:13:50.057] INFO: number of X-ray hits detected: 174156 125528 171019 262197 280409 278569 278194 204220 194007 258265 256304 223349 233911 137116 62114 70664
[12:13:50.057] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:13:50.057] INFO: number of Vcal hits detected: 207523 207782 207341 207398 207120 207306 207045 207579 207614 207438 207485 207517 207347 207671 207917 207912
[12:13:50.057] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.8 99.6 99.7 99.6 99.8 99.8 99.8 99.8 99.8 99.7 99.9 100.0 100.0
[12:13:50.057] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.7 99.6 99.7 99.5 99.8 99.8 99.7 99.8 99.8 99.7 99.8 100.0 100.0
[12:13:50.057] INFO: X-ray hit rate [MHz/cm2]: 51.0 36.8 50.1 76.9 82.2 81.7 81.5 59.9 56.9 75.7 75.1 65.5 68.6 40.2 18.2 20.7
[12:13:50.058] INFO: PixTestHighRate::doXPixelAlive() done
[12:13:50.102] INFO: PixTest:: pg_setup set to default.
[12:13:50.123] INFO: enter test to run
[12:14:21.061] INFO: test: exit no parameter change
[12:14:21.391] QUIET: Connection to board 33 closed.
[12:14:21.400] INFO: pXar: this is the end, my friend