[12:38:13.953] INFO: *** Welcome to pxar ***
[12:38:13.953] INFO: *** Today: 2016/06/07
[12:38:13.986] INFO: *** Version: v1.9.0-814-g7497
[12:38:13.986] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//dacParameters35_C15.dat
[12:38:13.995] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:38:13.996] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//defaultMaskFile.dat
[12:38:13.001] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C15.dat
[12:38:14.118] INFO: clk: 4
[12:38:14.118] INFO: ctr: 4
[12:38:14.118] INFO: sda: 19
[12:38:14.118] INFO: tin: 9
[12:38:14.118] INFO: level: 15
[12:38:14.118] INFO: triggerdelay: 0
[12:38:14.118] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:38:14.118] INFO: Log level: INFO
[12:38:14.136] QUIET: Connection to board DTB_WREK4U opened.
[12:38:14.140] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[12:38:14.143] INFO: RPC call hashes of host and DTB match: 398089610
[12:38:15.680] INFO: DUT info:
[12:38:15.680] INFO: The DUT currently contains the following objects:
[12:38:15.680] INFO: 2 TBM Cores tbm08c (2 ON)
[12:38:15.680] INFO: TBM Core alpha (0): 7 registers set
[12:38:15.680] INFO: TBM Core beta (1): 7 registers set
[12:38:15.680] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:38:15.680] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.680] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.680] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.680] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.680] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.680] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.680] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.681] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.681] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.681] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.681] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.681] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.681] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.681] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.681] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:15.681] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:16.084] INFO: enter 'restricted' command line mode
[12:38:16.084] INFO: enter test to run
[12:38:22.310] INFO: test: PixelAlive no parameter change
[12:38:22.310] INFO: running: pixelalive
[12:38:22.319] INFO: ----------------------------------------------------------------------
[12:38:22.319] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:38:22.319] INFO: ----------------------------------------------------------------------
[12:38:22.634] INFO: Expecting 41600 events.
[12:38:26.966] INFO: 41600 events read in total (3613ms).
[12:38:27.135] INFO: Test took 4814ms.
[12:38:27.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:38:27.413] INFO: PixTestAlive::aliveTest() done
[12:38:27.413] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:38:27.447] INFO: enter test to run
[12:38:51.414] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:38:51.414] INFO: running: highrate
[12:38:51.414] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:38:51.639] INFO: ----------------------------------------------------------------------
[12:38:51.639] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:38:51.639] INFO: ----------------------------------------------------------------------
[12:38:51.639] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:38:51.640] INFO: edge/corner pixel THR is adjusted
[12:38:51.640] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:38:52.600] INFO: Collecting data for 5 seconds...
[12:38:57.616] INFO: Done with hot pixel readout
[12:39:09.200] INFO: PixTest:: pg_setup set to default.
[12:39:09.201] INFO: 11 hot pixels found in step 0
[12:39:10.189] INFO: Collecting data for 5 seconds...
[12:39:15.206] INFO: Done with hot pixel readout
[12:39:26.823] INFO: PixTest:: pg_setup set to default.
[12:39:26.824] INFO: 10 hot pixels found in step 1
[12:39:27.817] INFO: Collecting data for 5 seconds...
[12:39:32.834] INFO: Done with hot pixel readout
[12:39:44.524] INFO: PixTest:: pg_setup set to default.
[12:39:44.525] INFO: 10 hot pixels found in step 2
[12:39:45.516] INFO: Collecting data for 5 seconds...
[12:39:50.532] INFO: Done with hot pixel readout
[12:40:02.153] INFO: PixTest:: pg_setup set to default.
[12:40:02.154] INFO: 9 hot pixels found in step 3
[12:40:03.146] INFO: Collecting data for 5 seconds...
[12:40:08.163] INFO: Done with hot pixel readout
[12:40:19.743] INFO: PixTest:: pg_setup set to default.
[12:40:19.744] INFO: 9 hot pixels found in step 4
[12:40:20.736] INFO: Collecting data for 5 seconds...
[12:40:25.752] INFO: Done with hot pixel readout
[12:40:37.435] INFO: PixTest:: pg_setup set to default.
[12:40:37.436] INFO: 7 hot pixels found in step 5
[12:40:38.428] INFO: Collecting data for 5 seconds...
[12:40:43.444] INFO: Done with hot pixel readout
[12:40:55.095] INFO: PixTest:: pg_setup set to default.
[12:40:55.096] INFO: 9 hot pixels found in step 6
[12:40:56.088] INFO: Collecting data for 5 seconds...
[12:41:01.104] INFO: Done with hot pixel readout
[12:41:12.738] INFO: PixTest:: pg_setup set to default.

[12:41:12.738] INFO: 4 hot pixels found in step 7
[12:41:13.730] INFO: Collecting data for 5 seconds...
[12:41:18.746] INFO: Done with hot pixel readout
[12:41:30.352] INFO: PixTest:: pg_setup set to default.
[12:41:30.353] INFO: 1 hot pixels found in step 8
[12:41:31.346] INFO: Collecting data for 5 seconds...
[12:41:36.362] INFO: Done with hot pixel readout
[12:41:48.031] INFO: PixTest:: pg_setup set to default.

[12:41:48.032] INFO: 1 hot pixels found in step 9
[12:41:48.068] INFO: 1 hot pixels could not be trimmed and have been masked.
[12:41:48.071] INFO: PixTest::trimHotPixels() done
[12:41:48.071] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C0.dat
[12:41:48.077] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C1.dat
[12:41:48.083] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C2.dat
[12:41:48.088] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C3.dat
[12:41:48.094] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C4.dat
[12:41:48.099] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C5.dat
[12:41:48.105] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C6.dat
[12:41:48.110] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C7.dat
[12:41:48.115] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C8.dat
[12:41:48.121] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C9.dat
[12:41:48.126] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C10.dat
[12:41:48.131] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C11.dat
[12:41:48.137] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C12.dat
[12:41:48.142] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C13.dat
[12:41:48.147] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C14.dat
[12:41:48.153] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C15.dat
[12:41:48.158] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//defaultMaskFile.dat
[12:41:48.168] INFO: enter test to run
[12:42:29.628] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:42:29.628] INFO: running: highrate
[12:42:29.633] INFO: ----------------------------------------------------------------------
[12:42:29.633] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:42:29.633] INFO: ----------------------------------------------------------------------
[12:42:29.633] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:42:29.633] INFO: edge/corner pixel THR is adjusted
[12:42:29.633] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:42:30.590] INFO: Collecting data for 1 seconds...
[12:42:31.594] INFO: Done with hot pixel readout
[12:42:35.562] INFO: PixTest:: pg_setup set to default.
[12:42:35.563] INFO: 0 hot pixels found in step 0
[12:42:35.568] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:42:35.657] INFO: PixTest::trimHotPixels() done
[12:42:35.657] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C0.dat
[12:42:35.664] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C1.dat
[12:42:35.670] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C2.dat
[12:42:35.675] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C3.dat
[12:42:35.680] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C4.dat
[12:42:35.685] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C5.dat
[12:42:35.691] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C6.dat
[12:42:35.696] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C7.dat
[12:42:35.701] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C8.dat
[12:42:35.707] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C9.dat
[12:42:35.712] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C10.dat
[12:42:35.717] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C11.dat
[12:42:35.723] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C12.dat
[12:42:35.728] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C13.dat
[12:42:35.733] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C14.dat
[12:42:35.739] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C15.dat
[12:42:35.744] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-3-01_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//defaultMaskFile.dat
[12:42:35.754] INFO: enter test to run
[12:43:03.772] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:43:03.772] INFO: running: xray
[12:43:03.773] INFO: ----------------------------------------------------------------------
[12:43:03.773] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:43:03.773] INFO: ----------------------------------------------------------------------
[12:43:04.735] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:43:16.315] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:43:46.430] INFO: Resuming triggers.
[12:43:58.007] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:44:27.826] INFO: Resuming triggers.
[12:44:39.405] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:45:09.218] INFO: Resuming triggers.
[12:45:20.796] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[12:45:50.954] INFO: Resuming triggers.
[12:46:02.533] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[12:46:32.384] INFO: Resuming triggers.
[12:46:43.959] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[12:47:13.406] INFO: Resuming triggers.
[12:47:24.990] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[12:47:54.335] INFO: Resuming triggers.
[12:48:05.917] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[12:48:35.298] INFO: Resuming triggers.
[12:48:43.012] INFO: data taking finished, elapsed time: 100 seconds.
[12:49:02.864] INFO: PixTest:: pg_setup set to default.
[12:49:02.867] INFO: PixTestXray::doPhRun() done
[12:49:03.038] INFO: enter test to run
[12:49:33.401] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:49:33.401] INFO: running: xray
[12:49:33.402] INFO: ----------------------------------------------------------------------
[12:49:33.402] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:49:33.402] INFO: ----------------------------------------------------------------------
[12:49:34.373] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:49:41.183] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[12:50:11.472] INFO: Resuming triggers.
[12:50:18.281] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[12:50:48.515] INFO: Resuming triggers.
[12:50:55.326] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[12:51:25.663] INFO: Resuming triggers.
[12:51:32.474] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[12:52:02.809] INFO: Resuming triggers.
[12:52:09.622] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[12:52:39.898] INFO: Resuming triggers.
[12:52:46.712] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[12:53:17.063] INFO: Resuming triggers.
[12:53:23.873] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[12:53:54.227] INFO: Resuming triggers.
[12:54:01.040] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[12:54:31.464] INFO: Resuming triggers.
[12:54:38.274] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[12:55:09.416] INFO: Resuming triggers.
[12:55:16.224] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[12:55:47.371] INFO: Resuming triggers.
[12:55:54.175] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[12:56:25.467] INFO: Resuming triggers.
[12:56:32.273] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[12:57:03.524] INFO: Resuming triggers.
[12:57:10.329] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[12:57:41.725] INFO: Resuming triggers.
[12:57:48.528] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[12:58:18.399] INFO: Resuming triggers.
[12:58:23.414] INFO: data taking finished, elapsed time: 100 seconds.
[12:58:45.190] INFO: PixTest:: pg_setup set to default.
[12:58:45.194] INFO: PixTestXray::doPhRun() done
[12:58:45.343] INFO: enter test to run
[12:59:36.325] INFO: test: HighRate no parameter change
[12:59:36.325] INFO: running: highrate
[12:59:36.342] INFO: ----------------------------------------------------------------------
[12:59:36.342] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:59:36.342] INFO: ----------------------------------------------------------------------
[12:59:36.498] INFO: Expecting 768 events.
[12:59:37.678] INFO: 768 events read in total (464ms).
[12:59:37.678] INFO: Test took 1315ms.
[12:59:38.481] INFO: Expecting 41600 events.
[12:59:41.521] INFO: 41600 events read in total (2513ms).
[12:59:41.523] INFO: Test took 3838ms.
[12:59:41.555] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:42.284] INFO: Expecting 41600 events.
[12:59:45.461] INFO: 41600 events read in total (2650ms).
[12:59:45.462] INFO: Test took 3891ms.
[12:59:45.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:46.217] INFO: Expecting 41600 events.
[12:59:49.445] INFO: 41600 events read in total (2701ms).
[12:59:49.446] INFO: Test took 3933ms.
[12:59:49.480] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:50.202] INFO: Expecting 41600 events.
[12:59:53.429] INFO: 41600 events read in total (2700ms).
[12:59:53.430] INFO: Test took 3932ms.
[12:59:53.464] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:54.188] INFO: Expecting 41600 events.
[12:59:57.414] INFO: 41600 events read in total (2699ms).
[12:59:57.415] INFO: Test took 3934ms.
[12:59:57.449] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:58.171] INFO: Expecting 41600 events.
[13:00:01.348] INFO: 41600 events read in total (2650ms).
[13:00:01.349] INFO: Test took 3881ms.
[13:00:01.383] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:02.104] INFO: Expecting 41600 events.
[13:00:05.336] INFO: 41600 events read in total (2705ms).
[13:00:05.337] INFO: Test took 3935ms.
[13:00:05.370] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:06.097] INFO: Expecting 41600 events.
[13:00:09.329] INFO: 41600 events read in total (2705ms).
[13:00:09.330] INFO: Test took 3941ms.
[13:00:09.362] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:10.086] INFO: Expecting 41600 events.
[13:00:13.324] INFO: 41600 events read in total (2711ms).
[13:00:13.325] INFO: Test took 3945ms.
[13:00:13.358] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:14.081] INFO: Expecting 41600 events.
[13:00:17.323] INFO: 41600 events read in total (2715ms).
[13:00:17.324] INFO: Test took 3947ms.
[13:00:17.357] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:18.085] INFO: Expecting 41600 events.
[13:00:21.319] INFO: 41600 events read in total (2707ms).
[13:00:21.320] INFO: Test took 3945ms.
[13:00:21.354] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:22.078] INFO: Expecting 41600 events.
[13:00:25.278] INFO: 41600 events read in total (2673ms).
[13:00:25.279] INFO: Test took 3905ms.
[13:00:25.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:26.037] INFO: Expecting 41600 events.
[13:00:29.274] INFO: 41600 events read in total (2710ms).
[13:00:29.275] INFO: Test took 3944ms.
[13:00:29.309] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:30.031] INFO: Expecting 41600 events.
[13:00:33.189] INFO: 41600 events read in total (2631ms).
[13:00:33.191] INFO: Test took 3864ms.
[13:00:33.226] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:33.950] INFO: Expecting 41600 events.
[13:00:37.208] INFO: 41600 events read in total (2731ms).
[13:00:37.209] INFO: Test took 3965ms.
[13:00:37.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:37.968] INFO: Expecting 41600 events.
[13:00:41.212] INFO: 41600 events read in total (2718ms).
[13:00:41.213] INFO: Test took 3950ms.
[13:00:41.246] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:41.968] INFO: Expecting 41600 events.
[13:00:45.201] INFO: 41600 events read in total (2706ms).
[13:00:45.202] INFO: Test took 3938ms.
[13:00:45.236] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:45.960] INFO: Expecting 41600 events.
[13:00:49.197] INFO: 41600 events read in total (2710ms).
[13:00:49.198] INFO: Test took 3943ms.
[13:00:49.231] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:49.957] INFO: Expecting 41600 events.
[13:00:53.200] INFO: 41600 events read in total (2716ms).
[13:00:53.201] INFO: Test took 3951ms.
[13:00:53.234] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:53.955] INFO: Expecting 41600 events.
[13:00:57.099] INFO: 41600 events read in total (2617ms).
[13:00:57.100] INFO: Test took 3847ms.
[13:00:57.133] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:57.496] INFO: enter test to run
[13:01:01.860] INFO: test: HighRate no parameter change
[13:01:01.860] INFO: running: highrate
[13:01:01.861] INFO: ----------------------------------------------------------------------
[13:01:01.861] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:01:01.861] INFO: ----------------------------------------------------------------------
[13:01:02.476] INFO: Expecting 208000 events.
[13:01:14.282] INFO: 208000 events read in total (11281ms).
[13:01:14.285] INFO: Test took 12416ms.
[13:01:14.426] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:14.677] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:01:14.677] INFO: number of red-efficiency pixels: 53 46 88 109 134 132 131 119 81 82 100 103 78 55 20 35
[13:01:14.677] INFO: number of X-ray hits detected: 59783 41432 67404 105549 110922 118278 116350 82760 72074 88863 89367 79502 81742 53010 21854 25322
[13:01:14.677] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:01:14.677] INFO: number of Vcal hits detected: 207945 207952 207908 207889 207865 207865 207867 207878 207918 207917 207899 207895 207920 207944 207980 207960
[13:01:14.677] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[13:01:14.677] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0
[13:01:14.677] INFO: X-ray hit rate [MHz/cm2]: 17.5 12.1 19.8 30.9 32.5 34.7 34.1 24.3 21.1 26.0 26.2 23.3 24.0 15.5 6.4 7.4
[13:01:14.677] INFO: PixTestHighRate::doXPixelAlive() done
[13:01:14.723] INFO: PixTest:: pg_setup set to default.
[13:01:14.740] INFO: enter test to run
[13:01:39.204] INFO: test: HighRate no parameter change
[13:01:39.204] INFO: running: highrate
[13:01:39.204] INFO: ----------------------------------------------------------------------
[13:01:39.204] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:01:39.204] INFO: ----------------------------------------------------------------------
[13:01:39.818] INFO: Expecting 208000 events.
[13:01:53.317] INFO: 208000 events read in total (12972ms).
[13:01:53.322] INFO: Test took 14110ms.
[13:01:53.608] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:53.906] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:01:53.906] INFO: number of red-efficiency pixels: 150 119 275 354 481 413 401 318 224 302 336 282 222 208 47 74
[13:01:53.906] INFO: number of X-ray hits detected: 123300 85047 140402 216861 227981 244275 240091 171501 149640 184506 184183 165078 168702 110927 45833 52911
[13:01:53.906] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:01:53.906] INFO: number of Vcal hits detected: 207844 207879 207687 207626 207476 207558 207554 207649 207766 207685 207630 207700 207770 207771 207952 207920
[13:01:53.906] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[13:01:53.906] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.8 99.8 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[13:01:53.906] INFO: X-ray hit rate [MHz/cm2]: 36.1 24.9 41.2 63.6 66.8 71.6 70.4 50.3 43.9 54.1 54.0 48.4 49.4 32.5 13.4 15.5
[13:01:53.906] INFO: PixTestHighRate::doXPixelAlive() done
[13:01:53.952] INFO: PixTest:: pg_setup set to default.
[13:01:53.966] INFO: enter test to run
[13:02:15.499] INFO: test: HighRate no parameter change
[13:02:15.499] INFO: running: highrate
[13:02:15.500] INFO: ----------------------------------------------------------------------
[13:02:15.500] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:02:15.500] INFO: ----------------------------------------------------------------------
[13:02:16.119] INFO: Expecting 208000 events.
[13:02:31.340] INFO: 208000 events read in total (14694ms).
[13:02:31.347] INFO: Test took 15836ms.
[13:02:31.771] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:32.113] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:02:32.113] INFO: number of red-efficiency pixels: 318 254 657 789 1137 981 973 709 443 637 752 643 506 442 75 85
[13:02:32.113] INFO: number of X-ray hits detected: 184123 126623 210826 325273 340357 366594 359571 257776 224546 274257 276334 247020 252475 165352 68303 80155
[13:02:32.113] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:02:32.113] INFO: number of Vcal hits detected: 207648 207728 207158 207072 206573 206783 206782 207125 207511 207287 207113 207248 207436 207483 207922 207909
[13:02:32.113] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.6 99.6 99.4 99.5 99.5 99.6 99.8 99.7 99.6 99.7 99.8 99.8 100.0 100.0
[13:02:32.113] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.6 99.3 99.4 99.4 99.6 99.8 99.7 99.6 99.6 99.7 99.8 100.0 100.0
[13:02:32.113] INFO: X-ray hit rate [MHz/cm2]: 54.0 37.1 61.8 95.3 99.8 107.5 105.4 75.6 65.8 80.4 81.0 72.4 74.0 48.5 20.0 23.5
[13:02:32.113] INFO: PixTestHighRate::doXPixelAlive() done
[13:02:32.164] INFO: PixTest:: pg_setup set to default.
[13:02:32.183] INFO: enter test to run
[13:02:35.131] INFO: test: exit no parameter change
[13:02:35.458] QUIET: Connection to board 32 closed.
[13:02:35.459] INFO: pXar: this is the end, my friend