[14:23:26.641] INFO: *** Welcome to pxar ***
[14:23:26.641] INFO: *** Today: 2016/09/06
[14:23:26.661] INFO: *** Version: v1.9.0-818-g96727
[14:23:26.661] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//dacParameters35_C15.dat
[14:23:26.662] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:23:26.663] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//defaultMaskFile.dat
[14:23:26.663] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C15.dat
[14:23:26.736] INFO: clk: 4
[14:23:26.736] INFO: ctr: 4
[14:23:26.736] INFO: sda: 19
[14:23:26.736] INFO: tin: 9
[14:23:26.736] INFO: level: 15
[14:23:26.736] INFO: triggerdelay: 0
[14:23:26.736] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[14:23:26.736] INFO: Log level: INFO
[14:23:26.748] QUIET: Connection to board DTB_WREKRL opened.
[14:23:26.751] 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:23:26.754] INFO: RPC call hashes of host and DTB match: 398089610
[14:23:28.285] INFO: DUT info:
[14:23:28.285] INFO: The DUT currently contains the following objects:
[14:23:28.285] INFO: 2 TBM Cores tbm08c (2 ON)
[14:23:28.285] INFO: TBM Core alpha (0): 7 registers set
[14:23:28.285] INFO: TBM Core beta (1): 7 registers set
[14:23:28.285] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:23:28.285] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.285] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.285] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.285] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.286] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.286] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.286] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.286] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.286] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.286] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.286] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.286] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.286] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.286] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.286] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.286] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:23:28.690] INFO: enter 'restricted' command line mode
[14:23:28.690] INFO: enter test to run
[14:23:31.029] INFO: test: PixelAlive no parameter change
[14:23:31.029] INFO: running: pixelalive
[14:23:31.041] INFO: ----------------------------------------------------------------------
[14:23:31.041] 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:23:31.041] INFO: ----------------------------------------------------------------------
[14:23:31.372] INFO: Expecting 41600 events.
[14:23:35.676] INFO: 41600 events read in total (3585ms).
[14:23:35.841] INFO: Test took 4798ms.
[14:23:35.849] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:36.112] INFO: PixTestAlive::aliveTest() done
[14:23:36.112] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 1 0 0 0 0
[14:23:36.144] INFO: enter test to run
[14:23:51.949] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:23:51.949] INFO: running: highrate
[14:23:51.949] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:23:52.233] INFO: ----------------------------------------------------------------------
[14:23:52.233] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:23:52.233] INFO: ----------------------------------------------------------------------
[14:23:52.233] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:23:52.233] INFO: edge/corner pixel THR is adjusted
[14:23:52.233] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:23:53.192] INFO: Collecting data for 5 seconds...
[14:23:58.213] INFO: Done with hot pixel readout
[14:24:09.979] INFO: PixTest:: pg_setup set to default.
[14:24:09.980] INFO: 2 hot pixels found in step 0
[14:24:10.966] INFO: Collecting data for 5 seconds...
[14:24:15.987] INFO: Done with hot pixel readout
[14:24:27.578] INFO: PixTest:: pg_setup set to default.
[14:24:27.578] INFO: 2 hot pixels found in step 1
[14:24:28.577] INFO: Collecting data for 5 seconds...
[14:24:33.596] INFO: Done with hot pixel readout
[14:24:45.162] INFO: PixTest:: pg_setup set to default.
[14:24:45.163] INFO: 5 hot pixels found in step 2
[14:24:46.153] INFO: Collecting data for 5 seconds...
[14:24:51.171] INFO: Done with hot pixel readout
[14:25:02.680] INFO: PixTest:: pg_setup set to default.
[14:25:02.681] INFO: 3 hot pixels found in step 3
[14:25:03.671] INFO: Collecting data for 5 seconds...
[14:25:08.689] INFO: Done with hot pixel readout
[14:25:20.405] INFO: PixTest:: pg_setup set to default.
[14:25:20.406] INFO: 1 hot pixels found in step 4
[14:25:21.396] INFO: Collecting data for 5 seconds...
[14:25:26.413] INFO: Done with hot pixel readout
[14:25:38.166] INFO: PixTest:: pg_setup set to default.
[14:25:38.167] INFO: 0 hot pixels found in step 5
[14:25:38.202] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:25:38.284] INFO: PixTest::trimHotPixels() done
[14:25:38.307] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C0.dat
[14:25:38.318] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C1.dat
[14:25:38.330] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C2.dat
[14:25:38.336] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C3.dat
[14:25:38.344] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C4.dat
[14:25:38.349] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C5.dat
[14:25:38.355] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C6.dat
[14:25:38.361] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C7.dat
[14:25:38.369] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C8.dat
[14:25:38.375] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C9.dat
[14:25:38.380] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C10.dat
[14:25:38.385] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C11.dat
[14:25:38.390] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C12.dat
[14:25:38.396] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C13.dat
[14:25:38.401] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C14.dat
[14:25:38.406] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C15.dat
[14:25:38.414] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//defaultMaskFile.dat
[14:25:38.424] INFO: enter test to run
[14:26:09.220] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:26:09.220] INFO: running: highrate
[14:26:09.225] INFO: ----------------------------------------------------------------------
[14:26:09.225] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:26:09.225] INFO: ----------------------------------------------------------------------
[14:26:09.225] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:26:09.225] INFO: edge/corner pixel THR is adjusted
[14:26:09.225] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:26:10.183] INFO: Collecting data for 1 seconds...
[14:26:11.187] INFO: Done with hot pixel readout
[14:26:15.022] INFO: PixTest:: pg_setup set to default.
[14:26:15.024] INFO: 0 hot pixels found in step 0
[14:26:15.030] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:26:15.122] INFO: PixTest::trimHotPixels() done
[14:26:15.123] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C0.dat
[14:26:15.135] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C1.dat
[14:26:15.147] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C2.dat
[14:26:15.152] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C3.dat
[14:26:15.158] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C4.dat
[14:26:15.163] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C5.dat
[14:26:15.168] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C6.dat
[14:26:15.174] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C7.dat
[14:26:15.179] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C8.dat
[14:26:15.185] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C9.dat
[14:26:15.190] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C10.dat
[14:26:15.195] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C11.dat
[14:26:15.201] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C12.dat
[14:26:15.206] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C13.dat
[14:26:15.211] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C14.dat
[14:26:15.217] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C15.dat
[14:26:15.223] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//defaultMaskFile.dat
[14:26:15.234] INFO: enter test to run
[14:26:31.012] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:26:31.012] INFO: running: xray
[14:26:31.044] INFO: ----------------------------------------------------------------------
[14:26:31.044] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:26:31.044] INFO: ----------------------------------------------------------------------
[14:26:32.039] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:26:43.889] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:27:13.201] INFO: Resuming triggers.
[14:27:25.051] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:27:54.402] INFO: Resuming triggers.
[14:28:06.249] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[14:28:35.566] INFO: Resuming triggers.
[14:28:47.415] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[14:29:16.639] INFO: Resuming triggers.
[14:29:28.488] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[14:29:57.804] INFO: Resuming triggers.
[14:30:09.652] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[14:30:38.924] INFO: Resuming triggers.
[14:30:50.771] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[14:31:20.030] INFO: Resuming triggers.
[14:31:31.877] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[14:32:01.129] INFO: Resuming triggers.
[14:32:06.666] INFO: data taking finished, elapsed time: 100 seconds.
[14:32:20.654] INFO: PixTest:: pg_setup set to default.
[14:32:20.657] INFO: PixTestXray::doPhRun() done
[14:32:20.882] INFO: enter test to run
[14:32:53.065] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:32:53.065] INFO: running: xray
[14:32:53.067] INFO: ----------------------------------------------------------------------
[14:32:53.067] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:32:53.067] INFO: ----------------------------------------------------------------------
[14:32:54.034] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:33:01.264] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[14:33:31.537] INFO: Resuming triggers.
[14:33:38.769] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[14:34:09.945] INFO: Resuming triggers.
[14:34:17.172] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[14:34:47.877] INFO: Resuming triggers.
[14:34:55.109] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[14:35:25.717] INFO: Resuming triggers.
[14:35:32.946] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[14:36:04.229] INFO: Resuming triggers.
[14:36:11.465] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[14:36:42.205] INFO: Resuming triggers.
[14:36:49.434] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[14:37:20.415] INFO: Resuming triggers.
[14:37:27.650] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[14:37:59.306] INFO: Resuming triggers.
[14:38:06.536] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[14:38:37.867] INFO: Resuming triggers.
[14:38:45.097] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[14:39:15.003] INFO: Resuming triggers.
[14:39:23.234] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[14:39:54.166] INFO: Resuming triggers.
[14:40:01.393] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[14:40:31.485] INFO: Resuming triggers.
[14:40:38.713] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[14:41:08.793] INFO: Resuming triggers.
[14:41:15.153] INFO: data taking finished, elapsed time: 100 seconds.
[14:41:41.929] INFO: PixTest:: pg_setup set to default.
[14:41:41.933] INFO: PixTestXray::doPhRun() done
[14:41:42.079] INFO: enter test to run
[14:42:42.973] INFO: test: HighRate no parameter change
[14:42:42.973] INFO: running: highrate
[14:42:42.986] INFO: ----------------------------------------------------------------------
[14:42:42.987] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:42:42.987] INFO: ----------------------------------------------------------------------
[14:42:43.133] INFO: Expecting 768 events.
[14:42:44.267] INFO: 768 events read in total (418ms).
[14:42:44.267] INFO: Test took 1268ms.
[14:42:45.071] INFO: Expecting 41600 events.
[14:42:48.134] INFO: 41600 events read in total (2537ms).
[14:42:48.135] INFO: Test took 3843ms.
[14:42:48.165] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:48.898] INFO: Expecting 41600 events.
[14:42:52.039] INFO: 41600 events read in total (2614ms).
[14:42:52.040] INFO: Test took 3860ms.
[14:42:52.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:52.800] INFO: Expecting 41600 events.
[14:42:55.988] INFO: 41600 events read in total (2661ms).
[14:42:55.989] INFO: Test took 3902ms.
[14:42:56.021] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:56.749] INFO: Expecting 41600 events.
[14:42:59.959] INFO: 41600 events read in total (2683ms).
[14:42:59.960] INFO: Test took 3923ms.
[14:42:59.991] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:00.721] INFO: Expecting 41600 events.
[14:43:03.933] INFO: 41600 events read in total (2685ms).
[14:43:03.934] INFO: Test took 3927ms.
[14:43:03.965] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:04.694] INFO: Expecting 41600 events.
[14:43:07.869] INFO: 41600 events read in total (2648ms).
[14:43:07.870] INFO: Test took 3889ms.
[14:43:07.901] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:08.632] INFO: Expecting 41600 events.
[14:43:11.827] INFO: 41600 events read in total (2668ms).
[14:43:11.828] INFO: Test took 3911ms.
[14:43:11.857] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:12.587] INFO: Expecting 41600 events.
[14:43:15.800] INFO: 41600 events read in total (2686ms).
[14:43:15.801] INFO: Test took 3926ms.
[14:43:15.832] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:16.562] INFO: Expecting 41600 events.
[14:43:19.751] INFO: 41600 events read in total (2662ms).
[14:43:19.752] INFO: Test took 3902ms.
[14:43:19.783] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:20.515] INFO: Expecting 41600 events.
[14:43:23.709] INFO: 41600 events read in total (2667ms).
[14:43:23.710] INFO: Test took 3910ms.
[14:43:23.741] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:24.470] INFO: Expecting 41600 events.
[14:43:27.671] INFO: 41600 events read in total (2674ms).
[14:43:27.672] INFO: Test took 3915ms.
[14:43:27.702] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:28.435] INFO: Expecting 41600 events.
[14:43:31.630] INFO: 41600 events read in total (2669ms).
[14:43:31.631] INFO: Test took 3913ms.
[14:43:31.661] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:32.392] INFO: Expecting 41600 events.
[14:43:35.593] INFO: 41600 events read in total (2674ms).
[14:43:35.594] INFO: Test took 3916ms.
[14:43:35.625] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:36.357] INFO: Expecting 41600 events.
[14:43:39.552] INFO: 41600 events read in total (2669ms).
[14:43:39.553] INFO: Test took 3912ms.
[14:43:39.584] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:40.315] INFO: Expecting 41600 events.
[14:43:43.507] INFO: 41600 events read in total (2665ms).
[14:43:43.508] INFO: Test took 3907ms.
[14:43:43.538] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:44.267] INFO: Expecting 41600 events.
[14:43:47.448] INFO: 41600 events read in total (2654ms).
[14:43:47.449] INFO: Test took 3894ms.
[14:43:47.479] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:48.211] INFO: Expecting 41600 events.
[14:43:51.420] INFO: 41600 events read in total (2682ms).
[14:43:51.421] INFO: Test took 3926ms.
[14:43:51.453] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:52.185] INFO: Expecting 41600 events.
[14:43:55.389] INFO: 41600 events read in total (2678ms).
[14:43:55.390] INFO: Test took 3919ms.
[14:43:55.421] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:56.152] INFO: Expecting 41600 events.
[14:43:59.347] INFO: 41600 events read in total (2668ms).
[14:43:59.348] INFO: Test took 3911ms.
[14:43:59.378] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:00.105] INFO: Expecting 41600 events.
[14:44:03.165] INFO: 41600 events read in total (2533ms).
[14:44:03.166] INFO: Test took 3770ms.
[14:44:03.196] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:03.565] INFO: enter test to run
[14:46:17.780] INFO: test: HighRate no parameter change
[14:46:17.780] INFO: running: highrate
[14:46:17.781] INFO: ----------------------------------------------------------------------
[14:46:17.781] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:46:17.781] INFO: ----------------------------------------------------------------------
[14:46:18.400] INFO: Expecting 208000 events.
[14:46:30.077] INFO: 208000 events read in total (11150ms).
[14:46:30.080] INFO: Test took 12290ms.
[14:46:30.208] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:30.453] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 1 0 0 0 0
[14:46:30.453] INFO: number of red-efficiency pixels: 77 44 68 127 123 126 120 73 69 92 82 77 89 63 16 24
[14:46:30.453] INFO: number of X-ray hits detected: 58747 37213 58168 90384 98610 105170 100519 70975 66035 85540 84415 73722 73777 44766 18124 19724
[14:46:30.453] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:46:30.453] INFO: number of Vcal hits detected: 207921 207954 207930 207871 207874 207872 207876 207926 207930 207797 207916 207874 207910 207936 207984 207976
[14:46:30.453] 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
[14:46:30.453] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 100.0 99.9 100.0 100.0 100.0 100.0
[14:46:30.453] INFO: X-ray hit rate [MHz/cm2]: 17.2 10.9 17.0 26.5 28.9 30.8 29.5 20.8 19.4 25.1 24.7 21.6 21.6 13.1 5.3 5.8
[14:46:30.453] INFO: PixTestHighRate::doXPixelAlive() done
[14:46:30.506] INFO: PixTest:: pg_setup set to default.
[14:46:30.520] INFO: enter test to run
[14:46:56.388] INFO: test: HighRate no parameter change
[14:46:56.388] INFO: running: highrate
[14:46:56.389] INFO: ----------------------------------------------------------------------
[14:46:56.389] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:46:56.389] INFO: ----------------------------------------------------------------------
[14:46:56.004] INFO: Expecting 208000 events.
[14:47:10.300] INFO: 208000 events read in total (12770ms).
[14:47:10.304] INFO: Test took 13907ms.
[14:47:10.582] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:10.879] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 1 0 0 0 0
[14:47:10.879] INFO: number of red-efficiency pixels: 204 112 285 390 433 417 428 206 246 279 330 209 266 144 35 50
[14:47:10.879] INFO: number of X-ray hits detected: 127237 79885 124704 193607 212189 225601 213061 151890 141701 183408 180697 157166 157771 96546 39203 42285
[14:47:10.880] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:47:10.880] INFO: number of Vcal hits detected: 207783 207885 207689 207577 207524 207553 207535 207781 207743 207579 207646 207734 207723 207847 207965 207949
[14:47:10.880] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[14:47:10.880] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[14:47:10.880] INFO: X-ray hit rate [MHz/cm2]: 37.3 23.4 36.6 56.7 62.2 66.1 62.4 44.5 41.5 53.8 53.0 46.1 46.2 28.3 11.5 12.4
[14:47:10.880] INFO: PixTestHighRate::doXPixelAlive() done
[14:47:10.926] INFO: PixTest:: pg_setup set to default.
[14:47:10.939] INFO: enter test to run
[14:47:32.771] INFO: test: HighRate no parameter change
[14:47:32.771] INFO: running: highrate
[14:47:32.772] INFO: ----------------------------------------------------------------------
[14:47:32.772] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:47:32.772] INFO: ----------------------------------------------------------------------
[14:47:33.394] INFO: Expecting 208000 events.
[14:47:48.471] INFO: 208000 events read in total (14550ms).
[14:47:48.478] INFO: Test took 15695ms.
[14:47:48.880] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:49.216] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 1 0 0 0 0
[14:47:49.216] INFO: number of red-efficiency pixels: 436 222 549 882 1020 832 1007 483 485 637 744 414 534 340 77 83
[14:47:49.216] INFO: number of X-ray hits detected: 189468 119481 186888 288958 314513 337323 318651 226999 211355 273130 269094 235847 236664 144101 58692 63284
[14:47:49.216] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:47:49.216] INFO: number of Vcal hits detected: 207465 207761 207321 206952 206756 206986 206748 207450 207473 207112 207138 207488 207416 207617 207922 207916
[14:47:49.216] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.5 99.6 99.5 99.8 99.8 99.6 99.6 99.8 99.8 99.8 100.0 100.0
[14:47:49.216] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.7 99.5 99.4 99.5 99.4 99.7 99.7 99.6 99.6 99.8 99.7 99.8 100.0 100.0
[14:47:49.216] INFO: X-ray hit rate [MHz/cm2]: 55.5 35.0 54.8 84.7 92.2 98.9 93.4 66.5 61.9 80.1 78.9 69.1 69.4 42.2 17.2 18.5
[14:47:49.217] INFO: PixTestHighRate::doXPixelAlive() done
[14:47:49.263] INFO: PixTest:: pg_setup set to default.
[14:47:49.278] INFO: enter test to run
[14:47:52.539] INFO: test: exit no parameter change
[14:47:52.982] QUIET: Connection to board 33 closed.
[14:47:53.030] INFO: pXar: this is the end, my friend