[08:48:28.937] INFO: *** Welcome to pxar ***
[08:48:28.937] INFO: *** Today: 2016/10/13
[08:48:28.961] INFO: *** Version: v1.9.0-818-g96727
[08:48:28.961] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//dacParameters35_C15.dat
[08:48:28.962] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//tbmParameters_C0b.dat
[08:48:28.962] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//defaultMaskFile.dat
[08:48:28.962] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C15.dat
[08:48:29.038] INFO: clk: 4
[08:48:29.038] INFO: ctr: 4
[08:48:29.038] INFO: sda: 19
[08:48:29.038] INFO: tin: 9
[08:48:29.038] INFO: level: 15
[08:48:29.038] INFO: triggerdelay: 0
[08:48:29.038] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[08:48:29.038] INFO: Log level: INFO
[08:48:29.051] QUIET: Connection to board DTB_WREKRL opened.
[08:48:29.055] 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:
------------------------------------------------------
[08:48:29.057] INFO: RPC call hashes of host and DTB match: 398089610
[08:48:30.599] INFO: DUT info:
[08:48:30.599] INFO: The DUT currently contains the following objects:
[08:48:30.599] INFO: 2 TBM Cores tbm08c (2 ON)
[08:48:30.599] INFO: TBM Core alpha (0): 7 registers set
[08:48:30.599] INFO: TBM Core beta (1): 7 registers set
[08:48:30.599] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:48:30.599] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.599] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.599] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.599] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.600] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.600] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.600] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.600] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.600] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.600] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.600] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.600] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.600] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.600] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.600] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.600] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:48:30.003] INFO: enter 'restricted' command line mode
[08:48:30.003] INFO: enter test to run
[08:48:32.814] INFO: test: PixelAlive no parameter change
[08:48:32.814] INFO: running: pixelalive
[08:48:32.910] INFO: ----------------------------------------------------------------------
[08:48:32.910] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:48:32.910] INFO: ----------------------------------------------------------------------
[08:48:33.237] INFO: Expecting 41600 events.
[08:48:37.680] INFO: 41600 events read in total (3725ms).
[08:48:37.845] INFO: Test took 4932ms.
[08:48:37.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:48:38.163] INFO: PixTestAlive::aliveTest() done
[08:48:38.163] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 1
[08:48:38.392] INFO: enter test to run
[08:49:06.374] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[08:49:06.374] INFO: running: highrate
[08:49:06.389] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[08:49:06.749] INFO: ----------------------------------------------------------------------
[08:49:06.749] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[08:49:06.749] INFO: ----------------------------------------------------------------------
[08:49:06.749] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[08:49:06.749] INFO: edge/corner pixel THR is adjusted
[08:49:06.749] INFO: PixTestHighRate::trimHotPixels: step 0...
[08:49:07.708] INFO: Collecting data for 5 seconds...
[08:49:12.727] INFO: Done with hot pixel readout
[08:49:22.831] INFO: PixTest:: pg_setup set to default.
[08:49:22.832] INFO: 1 hot pixels found in step 0
[08:49:23.840] INFO: Collecting data for 5 seconds...
[08:49:28.857] INFO: Done with hot pixel readout
[08:49:40.417] INFO: PixTest:: pg_setup set to default.
[08:49:40.418] INFO: 3 hot pixels found in step 1
[08:49:41.409] INFO: Collecting data for 5 seconds...
[08:49:46.426] INFO: Done with hot pixel readout
[08:49:58.164] INFO: PixTest:: pg_setup set to default.
[08:49:58.165] INFO: 1 hot pixels found in step 2
[08:49:59.155] INFO: Collecting data for 5 seconds...
[08:50:04.175] INFO: Done with hot pixel readout
[08:50:15.844] INFO: PixTest:: pg_setup set to default.
[08:50:15.845] INFO: 1 hot pixels found in step 3
[08:50:16.834] INFO: Collecting data for 5 seconds...
[08:50:21.853] INFO: Done with hot pixel readout
[08:50:33.516] INFO: PixTest:: pg_setup set to default.
[08:50:33.517] INFO: 1 hot pixels found in step 4
[08:50:34.507] INFO: Collecting data for 5 seconds...
[08:50:39.524] INFO: Done with hot pixel readout
[08:50:51.312] INFO: PixTest:: pg_setup set to default.
[08:50:51.313] INFO: 2 hot pixels found in step 5
[08:50:52.302] INFO: Collecting data for 5 seconds...
[08:50:57.321] INFO: Done with hot pixel readout
[08:51:09.487] INFO: PixTest:: pg_setup set to default.
[08:51:09.487] INFO: 1 hot pixels found in step 6
[08:51:10.478] INFO: Collecting data for 5 seconds...
[08:51:15.499] INFO: Done with hot pixel readout
[08:51:27.372] INFO: PixTest:: pg_setup set to default.
[08:51:27.372] INFO: 0 hot pixels found in step 7
[08:51:27.408] INFO: 0 hot pixels could not be trimmed and have been masked.
[08:51:27.411] INFO: PixTest::trimHotPixels() done
[08:51:27.426] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C0.dat
[08:51:27.436] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C1.dat
[08:51:27.441] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C2.dat
[08:51:27.447] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C3.dat
[08:51:27.453] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C4.dat
[08:51:27.458] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C5.dat
[08:51:27.464] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C6.dat
[08:51:27.470] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C7.dat
[08:51:27.476] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C8.dat
[08:51:27.481] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C9.dat
[08:51:27.487] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C10.dat
[08:51:27.492] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C11.dat
[08:51:27.498] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C12.dat
[08:51:27.503] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C13.dat
[08:51:27.509] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C14.dat
[08:51:27.514] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C15.dat
[08:51:27.520] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//defaultMaskFile.dat
[08:51:27.538] INFO: enter test to run
[08:51:47.085] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[08:51:47.085] INFO: running: highrate
[08:51:47.089] INFO: ----------------------------------------------------------------------
[08:51:47.089] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[08:51:47.089] INFO: ----------------------------------------------------------------------
[08:51:47.089] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[08:51:47.089] INFO: edge/corner pixel THR is adjusted
[08:51:47.089] INFO: PixTestHighRate::trimHotPixels: step 0...
[08:51:48.047] INFO: Collecting data for 1 seconds...
[08:51:49.051] INFO: Done with hot pixel readout
[08:51:52.967] INFO: PixTest:: pg_setup set to default.
[08:51:52.967] INFO: 0 hot pixels found in step 0
[08:51:52.973] INFO: 0 hot pixels could not be trimmed and have been masked.
[08:51:53.067] INFO: PixTest::trimHotPixels() done
[08:51:53.067] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C0.dat
[08:51:53.085] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C1.dat
[08:51:53.094] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C2.dat
[08:51:53.099] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C3.dat
[08:51:53.105] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C4.dat
[08:51:53.111] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C5.dat
[08:51:53.116] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C6.dat
[08:51:53.122] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C7.dat
[08:51:53.127] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C8.dat
[08:51:53.133] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C9.dat
[08:51:53.139] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C10.dat
[08:51:53.144] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C11.dat
[08:51:53.150] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C12.dat
[08:51:53.155] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C13.dat
[08:51:53.161] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C14.dat
[08:51:53.167] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//trimParameters35_C15.dat
[08:51:53.172] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-2-44_FPIXTest-17C-FNAL-161004-1233-150V_2016-10-04_12h33m_1475602392/000_FPIXTest_p17//defaultMaskFile.dat
[08:51:53.187] INFO: enter test to run
[08:52:17.485] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[08:52:17.485] INFO: running: xray
[08:52:17.486] INFO: ----------------------------------------------------------------------
[08:52:17.486] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:52:17.486] INFO: ----------------------------------------------------------------------
[08:52:18.471] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:52:30.183] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[08:52:59.654] INFO: Resuming triggers.
[08:53:11.369] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[08:53:40.854] INFO: Resuming triggers.
[08:53:52.564] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[08:54:21.971] INFO: Resuming triggers.
[08:54:33.683] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[08:55:03.026] INFO: Resuming triggers.
[08:55:14.738] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[08:55:44.151] INFO: Resuming triggers.
[08:55:55.862] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[08:56:25.314] INFO: Resuming triggers.
[08:56:37.020] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[08:57:06.524] INFO: Resuming triggers.
[08:57:18.234] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[08:57:47.664] INFO: Resuming triggers.
[08:57:54.309] INFO: data taking finished, elapsed time: 100 seconds.
[08:58:11.229] INFO: PixTest:: pg_setup set to default.
[08:58:11.232] INFO: PixTestXray::doPhRun() done
[08:58:11.395] INFO: enter test to run
[08:58:58.506] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[08:58:58.506] INFO: running: xray
[08:58:58.507] INFO: ----------------------------------------------------------------------
[08:58:58.507] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:58:58.507] INFO: ----------------------------------------------------------------------
[08:58:59.472] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:59:06.344] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[08:59:37.187] INFO: Resuming triggers.
[08:59:44.063] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[09:00:14.767] INFO: Resuming triggers.
[09:00:21.639] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[09:00:52.710] INFO: Resuming triggers.
[09:00:59.585] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[09:01:30.492] INFO: Resuming triggers.
[09:01:37.368] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[09:02:08.094] INFO: Resuming triggers.
[09:02:14.967] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[09:02:45.639] INFO: Resuming triggers.
[09:02:52.516] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[09:03:24.205] INFO: Resuming triggers.
[09:03:31.079] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[09:04:02.759] INFO: Resuming triggers.
[09:04:09.634] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[09:04:40.935] INFO: Resuming triggers.
[09:04:47.810] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[09:05:19.147] INFO: Resuming triggers.
[09:05:26.020] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[09:05:57.603] INFO: Resuming triggers.
[09:06:04.478] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[09:06:35.660] INFO: Resuming triggers.
[09:06:42.533] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[09:07:12.732] INFO: Resuming triggers.
[09:07:19.604] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[09:07:49.808] INFO: Resuming triggers.
[09:07:53.938] INFO: data taking finished, elapsed time: 100 seconds.
[09:08:12.430] INFO: PixTest:: pg_setup set to default.
[09:08:12.434] INFO: PixTestXray::doPhRun() done
[09:08:12.583] INFO: enter test to run
[09:09:06.318] INFO: test: HighRate no parameter change
[09:09:06.318] INFO: running: highrate
[09:09:06.338] INFO: ----------------------------------------------------------------------
[09:09:06.338] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:09:06.338] INFO: ----------------------------------------------------------------------
[09:09:06.494] INFO: Expecting 768 events.
[09:09:07.665] INFO: 768 events read in total (456ms).
[09:09:07.666] INFO: Test took 1307ms.
[09:09:08.469] INFO: Expecting 41600 events.
[09:09:11.552] INFO: 41600 events read in total (2557ms).
[09:09:11.553] INFO: Test took 3867ms.
[09:09:11.584] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:12.316] INFO: Expecting 41600 events.
[09:09:15.492] INFO: 41600 events read in total (2649ms).
[09:09:15.493] INFO: Test took 3892ms.
[09:09:15.525] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:16.252] INFO: Expecting 41600 events.
[09:09:19.472] INFO: 41600 events read in total (2694ms).
[09:09:19.473] INFO: Test took 3931ms.
[09:09:19.506] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:20.230] INFO: Expecting 41600 events.
[09:09:23.445] INFO: 41600 events read in total (2688ms).
[09:09:23.446] INFO: Test took 3923ms.
[09:09:23.478] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:24.205] INFO: Expecting 41600 events.
[09:09:27.372] INFO: 41600 events read in total (2640ms).
[09:09:27.373] INFO: Test took 3878ms.
[09:09:27.405] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:28.133] INFO: Expecting 41600 events.
[09:09:31.345] INFO: 41600 events read in total (2685ms).
[09:09:31.346] INFO: Test took 3923ms.
[09:09:31.378] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:32.109] INFO: Expecting 41600 events.
[09:09:35.328] INFO: 41600 events read in total (2693ms).
[09:09:35.329] INFO: Test took 3934ms.
[09:09:35.362] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:36.089] INFO: Expecting 41600 events.
[09:09:39.309] INFO: 41600 events read in total (2693ms).
[09:09:39.310] INFO: Test took 3930ms.
[09:09:39.342] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:40.068] INFO: Expecting 41600 events.
[09:09:43.284] INFO: 41600 events read in total (2689ms).
[09:09:43.285] INFO: Test took 3924ms.
[09:09:43.317] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:44.046] INFO: Expecting 41600 events.
[09:09:47.244] INFO: 41600 events read in total (2671ms).
[09:09:47.245] INFO: Test took 3911ms.
[09:09:47.278] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:48.006] INFO: Expecting 41600 events.
[09:09:51.236] INFO: 41600 events read in total (2703ms).
[09:09:51.237] INFO: Test took 3941ms.
[09:09:51.270] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:51.994] INFO: Expecting 41600 events.
[09:09:55.221] INFO: 41600 events read in total (2700ms).
[09:09:55.222] INFO: Test took 3934ms.
[09:09:55.254] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:55.979] INFO: Expecting 41600 events.
[09:09:59.206] INFO: 41600 events read in total (2700ms).
[09:09:59.207] INFO: Test took 3934ms.
[09:09:59.239] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:59.966] INFO: Expecting 41600 events.
[09:10:03.193] INFO: 41600 events read in total (2700ms).
[09:10:03.194] INFO: Test took 3937ms.
[09:10:03.226] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:03.957] INFO: Expecting 41600 events.
[09:10:07.141] INFO: 41600 events read in total (2658ms).
[09:10:07.142] INFO: Test took 3898ms.
[09:10:07.174] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:07.904] INFO: Expecting 41600 events.
[09:10:11.097] INFO: 41600 events read in total (2666ms).
[09:10:11.098] INFO: Test took 3907ms.
[09:10:11.130] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:11.857] INFO: Expecting 41600 events.
[09:10:15.104] INFO: 41600 events read in total (2720ms).
[09:10:15.105] INFO: Test took 3957ms.
[09:10:15.137] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:15.862] INFO: Expecting 41600 events.
[09:10:19.077] INFO: 41600 events read in total (2688ms).
[09:10:19.078] INFO: Test took 3924ms.
[09:10:19.109] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:19.836] INFO: Expecting 41600 events.
[09:10:23.013] INFO: 41600 events read in total (2650ms).
[09:10:23.014] INFO: Test took 3887ms.
[09:10:23.046] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:23.774] INFO: Expecting 41600 events.
[09:10:26.753] INFO: 41600 events read in total (2452ms).
[09:10:26.754] INFO: Test took 3691ms.
[09:10:26.784] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:27.154] INFO: enter test to run
[09:11:04.581] INFO: test: HighRate no parameter change
[09:11:04.581] INFO: running: highrate
[09:11:04.582] INFO: ----------------------------------------------------------------------
[09:11:04.582] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:11:04.582] INFO: ----------------------------------------------------------------------
[09:11:05.202] INFO: Expecting 208000 events.
[09:11:17.010] INFO: 208000 events read in total (11281ms).
[09:11:17.012] INFO: Test took 12419ms.
[09:11:17.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:17.393] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 1
[09:11:17.393] INFO: number of red-efficiency pixels: 74 62 82 134 160 118 110 76 64 96 111 71 76 60 16 34
[09:11:17.393] INFO: number of X-ray hits detected: 58190 41427 63929 103287 109375 106768 105677 77108 65969 82596 85078 70006 77054 47514 22910 27668
[09:11:17.393] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:11:17.393] INFO: number of Vcal hits detected: 207924 207938 207916 207864 207788 207880 207887 207921 207935 207902 207888 207928 207923 207940 207984 207917
[09:11:17.393] 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 99.9 100.0 100.0 100.0 100.0 100.0
[09:11:17.393] 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 99.9 100.0 100.0 100.0 100.0 100.0
[09:11:17.393] INFO: X-ray hit rate [MHz/cm2]: 17.1 12.1 18.7 30.3 32.1 31.3 31.0 22.6 19.3 24.2 24.9 20.5 22.6 13.9 6.7 8.1
[09:11:17.393] INFO: PixTestHighRate::doXPixelAlive() done
[09:11:17.439] INFO: PixTest:: pg_setup set to default.
[09:11:17.453] INFO: enter test to run
[09:11:33.981] INFO: test: HighRate no parameter change
[09:11:33.981] INFO: running: highrate
[09:11:33.982] INFO: ----------------------------------------------------------------------
[09:11:33.982] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:11:33.982] INFO: ----------------------------------------------------------------------
[09:11:34.599] INFO: Expecting 208000 events.
[09:11:47.914] INFO: 208000 events read in total (12788ms).
[09:11:47.919] INFO: Test took 13926ms.
[09:11:48.186] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:48.477] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 1
[09:11:48.477] INFO: number of red-efficiency pixels: 200 144 256 335 548 374 339 237 203 295 299 195 226 155 74 69
[09:11:48.477] INFO: number of X-ray hits detected: 120838 85794 132265 212264 225865 220564 217482 160003 136863 170611 176054 144883 160349 98580 47396 58208
[09:11:48.477] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:11:48.477] INFO: number of Vcal hits detected: 207787 207849 207717 207640 207350 207600 207646 207748 207788 207694 207682 207797 207766 207840 207925 207882
[09:11:48.477] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[09:11:48.477] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 99.9
[09:11:48.477] INFO: X-ray hit rate [MHz/cm2]: 35.4 25.1 38.8 62.2 66.2 64.6 63.7 46.9 40.1 50.0 51.6 42.5 47.0 28.9 13.9 17.1
[09:11:48.477] INFO: PixTestHighRate::doXPixelAlive() done
[09:11:48.523] INFO: PixTest:: pg_setup set to default.
[09:11:48.535] INFO: enter test to run
[09:12:06.637] INFO: test: HighRate no parameter change
[09:12:06.637] INFO: running: highrate
[09:12:06.638] INFO: ----------------------------------------------------------------------
[09:12:06.638] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:12:06.638] INFO: ----------------------------------------------------------------------
[09:12:07.256] INFO: Expecting 208000 events.
[09:12:19.042] INFO: 208000 events read in total (11259ms).
[09:12:19.045] INFO: Test took 12399ms.
[09:12:19.181] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:19.430] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 1
[09:12:19.430] INFO: number of red-efficiency pixels: 86 43 85 114 159 127 122 96 75 103 95 71 78 47 20 19
[09:12:19.430] INFO: number of X-ray hits detected: 59392 42321 65767 105072 111549 108859 107618 78625 67166 83593 85947 70925 78483 48178 23075 28238
[09:12:19.430] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:12:19.430] INFO: number of Vcal hits detected: 207908 207957 207914 207885 207789 207872 207872 207902 207923 207896 207902 207928 207919 207953 207979 207932
[09:12:19.430] 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
[09:12:19.430] 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
[09:12:19.430] INFO: X-ray hit rate [MHz/cm2]: 17.4 12.4 19.3 30.8 32.7 31.9 31.5 23.0 19.7 24.5 25.2 20.8 23.0 14.1 6.8 8.3
[09:12:19.430] INFO: PixTestHighRate::doXPixelAlive() done
[09:12:19.482] INFO: PixTest:: pg_setup set to default.
[09:12:19.495] INFO: enter test to run
[09:12:50.300] INFO: test: exit no parameter change
[09:12:50.714] QUIET: Connection to board 33 closed.
[09:12:50.725] INFO: pXar: this is the end, my friend