[14:16:00.146] INFO: *** Welcome to pxar ***
[14:16:00.146] INFO: *** Today: 2016/06/13
[14:16:00.164] INFO: *** Version: v1.9.0-814-g7497
[14:16:00.164] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//dacParameters35_C15.dat
[14:16:00.187] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:16:00.187] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//defaultMaskFile.dat
[14:16:00.193] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C15.dat
[14:16:00.301] INFO: clk: 4
[14:16:00.301] INFO: ctr: 4
[14:16:00.301] INFO: sda: 19
[14:16:00.301] INFO: tin: 9
[14:16:00.301] INFO: level: 15
[14:16:00.301] INFO: triggerdelay: 0
[14:16:00.301] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[14:16:00.301] INFO: Log level: INFO
[14:16:00.318] QUIET: Connection to board DTB_WREKRL opened.
[14:16:00.322] 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:
------------------------------------------------------
[14:16:00.325] INFO: RPC call hashes of host and DTB match: 398089610
[14:16:01.858] INFO: DUT info:
[14:16:01.858] INFO: The DUT currently contains the following objects:
[14:16:01.858] INFO: 2 TBM Cores tbm08c (2 ON)
[14:16:01.858] INFO: TBM Core alpha (0): 7 registers set
[14:16:01.858] INFO: TBM Core beta (1): 7 registers set
[14:16:01.858] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:16:01.858] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.858] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.858] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.858] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.858] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.859] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.859] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.859] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.859] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.859] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.859] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.859] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.859] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.859] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.859] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:01.859] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:16:02.262] INFO: enter 'restricted' command line mode
[14:16:02.262] INFO: enter test to run
[14:16:05.750] INFO: test: PixelAlive no parameter change
[14:16:05.750] INFO: running: pixelalive
[14:16:05.759] INFO: ----------------------------------------------------------------------
[14:16:05.759] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:16:05.759] INFO: ----------------------------------------------------------------------
[14:16:06.079] INFO: Expecting 41600 events.
[14:16:10.406] INFO: 41600 events read in total (3609ms).
[14:16:10.573] INFO: Test took 4810ms.
[14:16:10.587] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:10.851] INFO: PixTestAlive::aliveTest() done
[14:16:10.851] INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:16:10.882] INFO: enter test to run
[14:17:31.693] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:17:31.693] INFO: running: highrate
[14:17:31.694] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:17:31.001] INFO: ----------------------------------------------------------------------
[14:17:31.001] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:17:31.001] INFO: ----------------------------------------------------------------------
[14:17:31.001] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:17:31.001] INFO: edge/corner pixel THR is adjusted
[14:17:31.001] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:17:32.962] INFO: Collecting data for 5 seconds...
[14:17:37.978] INFO: Done with hot pixel readout
[14:17:49.545] INFO: PixTest:: pg_setup set to default.
[14:17:49.545] INFO: 1 hot pixels found in step 0
[14:17:50.556] INFO: Collecting data for 5 seconds...
[14:17:55.573] INFO: Done with hot pixel readout
[14:18:07.115] INFO: PixTest:: pg_setup set to default.
[14:18:07.116] INFO: 1 hot pixels found in step 1
[14:18:08.105] INFO: Collecting data for 5 seconds...
[14:18:13.120] INFO: Done with hot pixel readout
[14:18:24.704] INFO: PixTest:: pg_setup set to default.
[14:18:24.705] INFO: 1 hot pixels found in step 2
[14:18:25.695] INFO: Collecting data for 5 seconds...
[14:18:30.710] INFO: Done with hot pixel readout
[14:18:42.322] INFO: PixTest:: pg_setup set to default.
[14:18:42.323] INFO: 0 hot pixels found in step 3
[14:18:42.356] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:18:42.360] INFO: PixTest::trimHotPixels() done
[14:18:42.360] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C0.dat
[14:18:42.366] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C1.dat
[14:18:42.372] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C2.dat
[14:18:42.378] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C3.dat
[14:18:42.384] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C4.dat
[14:18:42.389] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C5.dat
[14:18:42.394] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C6.dat
[14:18:42.399] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C7.dat
[14:18:42.405] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C8.dat
[14:18:42.410] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C9.dat
[14:18:42.416] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C10.dat
[14:18:42.421] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C11.dat
[14:18:42.426] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C12.dat
[14:18:42.431] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C13.dat
[14:18:42.437] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C14.dat
[14:18:42.442] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C15.dat
[14:18:42.468] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//defaultMaskFile.dat
[14:18:42.483] INFO: enter test to run
[14:56:07.310] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:56:07.310] INFO: running: highrate
[14:56:07.314] INFO: ----------------------------------------------------------------------
[14:56:07.314] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:56:07.314] INFO: ----------------------------------------------------------------------
[14:56:07.314] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:56:07.314] INFO: edge/corner pixel THR is adjusted
[14:56:07.314] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:56:08.272] INFO: Collecting data for 1 seconds...
[14:56:09.275] INFO: Done with hot pixel readout
[14:56:13.162] INFO: PixTest:: pg_setup set to default.
[14:56:13.163] INFO: 0 hot pixels found in step 0
[14:56:13.168] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:56:13.266] INFO: PixTest::trimHotPixels() done
[14:56:13.266] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C0.dat
[14:56:13.276] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C1.dat
[14:56:13.281] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C2.dat
[14:56:13.286] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C3.dat
[14:56:13.291] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C4.dat
[14:56:13.296] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C5.dat
[14:56:13.301] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C6.dat
[14:56:13.307] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C7.dat
[14:56:13.312] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C8.dat
[14:56:13.317] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C9.dat
[14:56:13.322] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C10.dat
[14:56:13.327] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C11.dat
[14:56:13.333] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C12.dat
[14:56:13.338] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C13.dat
[14:56:13.343] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C14.dat
[14:56:13.348] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//trimParameters35_C15.dat
[14:56:13.353] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-4-48_FPIXTest-17C-FNAL-160607-1143_2016-06-07_11h43m_1465317824/000_FPIXTest_p17//defaultMaskFile.dat
[14:56:13.363] INFO: enter test to run
[14:56:30.686] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:56:30.686] INFO: running: xray
[14:56:30.687] INFO: ----------------------------------------------------------------------
[14:56:30.687] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:56:30.687] INFO: ----------------------------------------------------------------------
[14:56:31.651] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:56:43.287] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:57:12.473] INFO: Resuming triggers.
[14:57:24.111] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:57:53.475] INFO: Resuming triggers.
[14:58:05.113] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:58:34.339] INFO: Resuming triggers.
[14:58:45.975] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:59:15.232] INFO: Resuming triggers.
[14:59:26.869] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[14:59:56.133] INFO: Resuming triggers.
[15:00:07.776] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:00:37.058] INFO: Resuming triggers.
[15:00:48.700] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[15:01:18.005] INFO: Resuming triggers.
[15:01:29.643] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[15:01:58.873] INFO: Resuming triggers.
[15:02:06.081] INFO: data taking finished, elapsed time: 100 seconds.
[15:02:24.451] INFO: PixTest:: pg_setup set to default.
[15:02:24.454] INFO: PixTestXray::doPhRun() done
[15:02:24.589] INFO: enter test to run
[15:19:04.988] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:19:04.988] INFO: running: xray
[15:19:04.990] INFO: ----------------------------------------------------------------------
[15:19:04.990] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:19:04.990] INFO: ----------------------------------------------------------------------
[15:19:05.953] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:19:12.920] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:19:42.950] INFO: Resuming triggers.
[15:19:49.919] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[15:20:20.014] INFO: Resuming triggers.
[15:20:26.984] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[15:20:56.988] INFO: Resuming triggers.
[15:21:03.957] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[15:21:33.978] INFO: Resuming triggers.
[15:21:40.952] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:22:10.914] INFO: Resuming triggers.
[15:22:17.885] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[15:22:47.967] INFO: Resuming triggers.
[15:22:54.936] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[15:23:25.005] INFO: Resuming triggers.
[15:23:31.976] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[15:24:01.957] INFO: Resuming triggers.
[15:24:08.932] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[15:24:38.817] INFO: Resuming triggers.
[15:24:45.782] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:25:15.793] INFO: Resuming triggers.
[15:25:22.764] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[15:25:52.704] INFO: Resuming triggers.
[15:25:59.676] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[15:26:29.664] INFO: Resuming triggers.
[15:26:36.634] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[15:27:06.585] INFO: Resuming triggers.
[15:27:13.552] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[15:27:43.562] INFO: Resuming triggers.
[15:27:46.298] INFO: data taking finished, elapsed time: 100 seconds.
[15:27:58.428] INFO: PixTest:: pg_setup set to default.
[15:27:58.431] INFO: PixTestXray::doPhRun() done
[15:27:58.583] INFO: enter test to run
[15:44:56.394] INFO: test: HighRate no parameter change
[15:44:56.394] INFO: running: highrate
[15:44:56.395] INFO: ----------------------------------------------------------------------
[15:44:56.395] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:44:56.395] INFO: ----------------------------------------------------------------------
[15:44:56.539] INFO: Expecting 768 events.
[15:44:57.673] INFO: 768 events read in total (419ms).
[15:44:57.673] INFO: Test took 1268ms.
[15:44:58.476] INFO: Expecting 41600 events.
[15:45:01.547] INFO: 41600 events read in total (2544ms).
[15:45:01.548] INFO: Test took 3866ms.
[15:45:01.579] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:02.313] INFO: Expecting 41600 events.
[15:45:05.493] INFO: 41600 events read in total (2654ms).
[15:45:05.494] INFO: Test took 3898ms.
[15:45:05.528] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:06.253] INFO: Expecting 41600 events.
[15:45:09.469] INFO: 41600 events read in total (2690ms).
[15:45:09.470] INFO: Test took 3925ms.
[15:45:09.504] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:10.232] INFO: Expecting 41600 events.
[15:45:13.452] INFO: 41600 events read in total (2693ms).
[15:45:13.453] INFO: Test took 3932ms.
[15:45:13.486] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:14.213] INFO: Expecting 41600 events.
[15:45:17.435] INFO: 41600 events read in total (2696ms).
[15:45:17.435] INFO: Test took 3932ms.
[15:45:17.468] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:18.194] INFO: Expecting 41600 events.
[15:45:21.419] INFO: 41600 events read in total (2698ms).
[15:45:21.420] INFO: Test took 3935ms.
[15:45:21.453] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:22.179] INFO: Expecting 41600 events.
[15:45:25.406] INFO: 41600 events read in total (2700ms).
[15:45:25.407] INFO: Test took 3936ms.
[15:45:25.440] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:26.166] INFO: Expecting 41600 events.
[15:45:29.388] INFO: 41600 events read in total (2695ms).
[15:45:29.389] INFO: Test took 3932ms.
[15:45:29.422] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:30.148] INFO: Expecting 41600 events.
[15:45:33.381] INFO: 41600 events read in total (2706ms).
[15:45:33.382] INFO: Test took 3941ms.
[15:45:33.416] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:34.139] INFO: Expecting 41600 events.
[15:45:37.365] INFO: 41600 events read in total (2699ms).
[15:45:37.366] INFO: Test took 3933ms.
[15:45:37.399] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:38.124] INFO: Expecting 41600 events.
[15:45:41.340] INFO: 41600 events read in total (2689ms).
[15:45:41.341] INFO: Test took 3925ms.
[15:45:41.373] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:42.100] INFO: Expecting 41600 events.
[15:45:45.322] INFO: 41600 events read in total (2695ms).
[15:45:45.323] INFO: Test took 3931ms.
[15:45:45.356] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:46.081] INFO: Expecting 41600 events.
[15:45:49.307] INFO: 41600 events read in total (2700ms).
[15:45:49.308] INFO: Test took 3934ms.
[15:45:49.341] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:50.066] INFO: Expecting 41600 events.
[15:45:53.292] INFO: 41600 events read in total (2699ms).
[15:45:53.293] INFO: Test took 3935ms.
[15:45:53.326] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:54.054] INFO: Expecting 41600 events.
[15:45:57.286] INFO: 41600 events read in total (2705ms).
[15:45:57.287] INFO: Test took 3942ms.
[15:45:57.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:58.044] INFO: Expecting 41600 events.
[15:46:01.272] INFO: 41600 events read in total (2701ms).
[15:46:01.273] INFO: Test took 3935ms.
[15:46:01.306] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:02.034] INFO: Expecting 41600 events.
[15:46:05.246] INFO: 41600 events read in total (2685ms).
[15:46:05.247] INFO: Test took 3923ms.
[15:46:05.279] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:06.007] INFO: Expecting 41600 events.
[15:46:09.239] INFO: 41600 events read in total (2705ms).
[15:46:09.240] INFO: Test took 3942ms.
[15:46:09.273] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:09.998] INFO: Expecting 41600 events.
[15:46:13.199] INFO: 41600 events read in total (2674ms).
[15:46:13.200] INFO: Test took 3909ms.
[15:46:13.233] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:13.961] INFO: Expecting 41600 events.
[15:46:16.984] INFO: 41600 events read in total (2496ms).
[15:46:16.985] INFO: Test took 3734ms.
[15:46:17.017] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:17.381] INFO: enter test to run
[15:46:53.817] INFO: test: HighRate no parameter change
[15:46:53.817] INFO: running: highrate
[15:46:53.818] INFO: ----------------------------------------------------------------------
[15:46:53.818] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:46:53.819] INFO: ----------------------------------------------------------------------
[15:46:54.439] INFO: Expecting 208000 events.
[15:47:06.219] INFO: 208000 events read in total (11254ms).
[15:47:06.222] INFO: Test took 12390ms.
[15:47:06.361] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:06.613] INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:47:06.613] INFO: number of red-efficiency pixels: 82 40 78 137 104 147 140 82 69 115 115 78 103 55 25 24
[15:47:06.613] INFO: number of X-ray hits detected: 64154 43175 64602 105742 107502 114396 112809 82741 71403 90463 91741 76816 78560 52022 20959 25181
[15:47:06.613] 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:47:06.613] INFO: number of Vcal hits detected: 207766 207960 207920 207859 207895 207848 207854 207918 207929 207883 207882 207922 207896 207945 207975 207975
[15:47:06.613] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[15:47:06.613] INFO: Vcal hit overall efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[15:47:06.613] INFO: X-ray hit rate [MHz/cm2]: 18.8 12.7 18.9 31.0 31.5 33.5 33.1 24.3 20.9 26.5 26.9 22.5 23.0 15.2 6.1 7.4
[15:47:06.613] INFO: PixTestHighRate::doXPixelAlive() done
[15:47:06.659] INFO: PixTest:: pg_setup set to default.
[15:47:06.672] INFO: enter test to run
[15:47:48.937] INFO: test: HighRate no parameter change
[15:47:48.937] INFO: running: highrate
[15:47:48.938] INFO: ----------------------------------------------------------------------
[15:47:48.938] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:47:48.938] INFO: ----------------------------------------------------------------------
[15:47:49.565] INFO: Expecting 208000 events.
[15:48:02.985] INFO: 208000 events read in total (12893ms).
[15:48:02.990] INFO: Test took 14034ms.
[15:48:03.262] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:03.562] INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:48:03.562] INFO: number of red-efficiency pixels: 190 135 262 465 344 521 554 249 183 276 293 210 241 194 46 54
[15:48:03.562] INFO: number of X-ray hits detected: 131141 88088 132203 216183 220454 233229 230447 169171 146556 184145 187443 156613 159281 106788 42995 50910
[15:48:03.562] 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:48:03.562] INFO: number of Vcal hits detected: 207655 207862 207717 207494 207632 207439 207403 207737 207804 207719 207697 207782 207748 207791 207954 207946
[15:48:03.562] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.7 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[15:48:03.562] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.9 99.8 99.8 99.7 99.7 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[15:48:03.562] INFO: X-ray hit rate [MHz/cm2]: 38.4 25.8 38.7 63.4 64.6 68.4 67.5 49.6 43.0 54.0 54.9 45.9 46.7 31.3 12.6 14.9
[15:48:03.562] INFO: PixTestHighRate::doXPixelAlive() done
[15:48:03.613] INFO: PixTest:: pg_setup set to default.
[15:48:03.634] INFO: enter test to run
[15:49:22.976] INFO: test: HighRate no parameter change
[15:49:22.976] INFO: running: highrate
[15:49:22.977] INFO: ----------------------------------------------------------------------
[15:49:22.978] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:49:22.978] INFO: ----------------------------------------------------------------------
[15:49:23.600] INFO: Expecting 208000 events.
[15:49:38.714] INFO: 208000 events read in total (14587ms).
[15:49:38.721] INFO: Test took 15728ms.
[15:49:39.128] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:39.471] INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:49:39.471] INFO: number of red-efficiency pixels: 352 258 543 1063 776 1226 1244 545 373 557 608 441 580 409 94 68
[15:49:39.471] INFO: number of X-ray hits detected: 197152 132768 197661 323460 329504 347897 343829 253219 219425 275975 278850 234408 239854 158799 64741 76047
[15:49:39.471] 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:49:39.471] INFO: number of Vcal hits detected: 207457 207721 207333 206720 207116 206390 206434 207392 207605 207372 207328 207510 207363 207526 207904 207929
[15:49:39.471] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.4 99.6 99.3 99.3 99.7 99.8 99.7 99.7 99.8 99.7 99.8 100.0 100.0
[15:49:39.471] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.7 99.4 99.6 99.2 99.2 99.7 99.8 99.7 99.7 99.8 99.7 99.8 100.0 100.0
[15:49:39.472] INFO: X-ray hit rate [MHz/cm2]: 57.8 38.9 57.9 94.8 96.6 102.0 100.8 74.2 64.3 80.9 81.7 68.7 70.3 46.5 19.0 22.3
[15:49:39.472] INFO: PixTestHighRate::doXPixelAlive() done
[15:49:39.521] INFO: PixTest:: pg_setup set to default.
[15:49:39.535] INFO: enter test to run
[15:49:43.120] INFO: test: exit no parameter change
[15:49:43.457] QUIET: Connection to board 33 closed.
[15:49:43.458] INFO: pXar: this is the end, my friend