[10:29:32.516] INFO: *** Welcome to pxar ***
[10:29:32.516] INFO: *** Today: 2016/07/19
[10:29:33.113] INFO: *** Version: v1.9.0-814-g7497
[10:29:33.117] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//dacParameters35_C15.dat
[10:29:33.216] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:29:33.237] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//defaultMaskFile.dat
[10:29:33.255] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C15.dat
[10:29:33.421] INFO: clk: 4
[10:29:33.421] INFO: ctr: 4
[10:29:33.422] INFO: sda: 19
[10:29:33.422] INFO: tin: 9
[10:29:33.422] INFO: level: 15
[10:29:33.422] INFO: triggerdelay: 0
[10:29:33.438] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:29:33.438] INFO: Log level: INFO
[10:29:33.469] QUIET: Connection to board DTB_WREKRL opened.
[10:29:33.472] 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:
------------------------------------------------------
[10:29:33.475] INFO: RPC call hashes of host and DTB match: 398089610
[10:29:35.011] INFO: DUT info:
[10:29:35.011] INFO: The DUT currently contains the following objects:
[10:29:35.011] INFO: 2 TBM Cores tbm08c (2 ON)
[10:29:35.026] INFO: TBM Core alpha (0): 7 registers set
[10:29:35.026] INFO: TBM Core beta (1): 7 registers set
[10:29:35.026] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:29:35.026] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.026] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.026] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.026] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.026] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.026] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.026] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.026] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.026] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.026] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.027] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.027] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.027] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.027] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.027] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.027] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:35.470] INFO: enter 'restricted' command line mode
[10:29:35.470] INFO: enter test to run
[10:29:57.601] INFO: test: PixelAlive no parameter change
[10:29:57.601] INFO: running: pixelalive
[10:29:57.726] INFO: ----------------------------------------------------------------------
[10:29:57.727] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:29:57.727] INFO: ----------------------------------------------------------------------
[10:29:58.047] INFO: Expecting 41600 events.
[10:30:02.353] INFO: 41600 events read in total (3587ms).
[10:30:02.520] INFO: Test took 4790ms.
[10:30:02.534] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:02.908] INFO: PixTestAlive::aliveTest() done
[10:30:02.908] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 1 2 0 0 1 0 0 0 1
[10:30:03.111] INFO: enter test to run
[10:30:44.985] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:30:44.985] INFO: running: highrate
[10:30:44.001] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:30:45.313] INFO: ----------------------------------------------------------------------
[10:30:45.313] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:30:45.313] INFO: ----------------------------------------------------------------------
[10:30:45.313] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:30:45.313] INFO: edge/corner pixel THR is adjusted
[10:30:45.313] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:30:46.273] INFO: Collecting data for 5 seconds...
[10:30:51.290] INFO: Done with hot pixel readout
[10:31:02.800] INFO: PixTest:: pg_setup set to default.
[10:31:02.801] INFO: 19 hot pixels found in step 0
[10:31:03.790] INFO: Collecting data for 5 seconds...
[10:31:08.805] INFO: Done with hot pixel readout
[10:31:20.267] INFO: PixTest:: pg_setup set to default.
[10:31:20.268] INFO: 19 hot pixels found in step 1
[10:31:21.261] INFO: Collecting data for 5 seconds...
[10:31:26.277] INFO: Done with hot pixel readout
[10:31:37.762] INFO: PixTest:: pg_setup set to default.
[10:31:37.763] INFO: 19 hot pixels found in step 2
[10:31:38.755] INFO: Collecting data for 5 seconds...
[10:31:43.771] INFO: Done with hot pixel readout
[10:31:55.293] INFO: PixTest:: pg_setup set to default.
[10:31:55.293] INFO: 20 hot pixels found in step 3
[10:31:56.285] INFO: Collecting data for 5 seconds...
[10:32:01.301] INFO: Done with hot pixel readout
[10:32:12.892] INFO: PixTest:: pg_setup set to default.
[10:32:12.892] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:12.892] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:12.893] INFO: 20 hot pixels found in step 4
[10:32:13.885] INFO: Collecting data for 5 seconds...
[10:32:18.902] INFO: Done with hot pixel readout
[10:32:30.083] INFO: PixTest:: pg_setup set to default.
[10:32:30.083] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:30.084] INFO: 15 hot pixels found in step 5
[10:32:31.075] INFO: Collecting data for 5 seconds...
[10:32:36.093] INFO: Done with hot pixel readout
[10:32:47.467] INFO: PixTest:: pg_setup set to default.
[10:32:47.467] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:47.467] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:47.467] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:47.467] INFO: 13 hot pixels found in step 6
[10:32:48.459] INFO: Collecting data for 5 seconds...
[10:32:53.477] INFO: Done with hot pixel readout
[10:33:05.112] INFO: PixTest:: pg_setup set to default.
[10:33:05.112] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:05.112] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:05.112] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:05.113] INFO: 10 hot pixels found in step 7
[10:33:06.104] INFO: Collecting data for 5 seconds...
[10:33:11.122] INFO: Done with hot pixel readout
[10:33:22.752] INFO: PixTest:: pg_setup set to default.
[10:33:22.752] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:22.752] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:22.753] INFO: 7 hot pixels found in step 8
[10:33:23.744] INFO: Collecting data for 5 seconds...
[10:33:28.763] INFO: Done with hot pixel readout
[10:33:40.098] INFO: PixTest:: pg_setup set to default.
[10:33:40.098] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:40.098] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:40.098] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:40.099] INFO: 12 hot pixels found in step 9
[10:33:41.090] INFO: Collecting data for 5 seconds...
[10:33:46.111] INFO: Done with hot pixel readout
[10:33:57.606] INFO: PixTest:: pg_setup set to default.
[10:33:57.606] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:57.606] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:57.606] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:57.607] INFO: 8 hot pixels found in step 10
[10:33:58.600] INFO: Collecting data for 5 seconds...
[10:34:03.617] INFO: Done with hot pixel readout
[10:34:15.102] INFO: PixTest:: pg_setup set to default.
[10:34:15.102] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:15.102] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:15.102] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:15.102] INFO: 6 hot pixels found in step 11
[10:34:16.094] INFO: Collecting data for 5 seconds...
[10:34:21.114] INFO: Done with hot pixel readout
[10:34:32.707] INFO: PixTest:: pg_setup set to default.
[10:34:32.707] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:32.707] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:32.707] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:32.708] INFO: 3 hot pixels found in step 12
[10:34:32.744] INFO: 3 hot pixels could not be trimmed and have been masked.
[10:34:32.768] INFO: PixTest::trimHotPixels() done
[10:34:32.768] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C0.dat
[10:34:32.780] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C1.dat
[10:34:32.789] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C2.dat
[10:34:32.794] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C3.dat
[10:34:32.800] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C4.dat
[10:34:32.805] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C5.dat
[10:34:32.811] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C6.dat
[10:34:32.816] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C7.dat
[10:34:32.821] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C8.dat
[10:34:32.827] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C9.dat
[10:34:32.832] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C10.dat
[10:34:32.837] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C11.dat
[10:34:32.843] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C12.dat
[10:34:32.848] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C13.dat
[10:34:32.853] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C14.dat
[10:34:32.859] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C15.dat
[10:34:32.864] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//defaultMaskFile.dat
[10:34:32.875] INFO: enter test to run
[10:35:19.719] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:35:19.719] INFO: running: highrate
[10:35:19.724] INFO: ----------------------------------------------------------------------
[10:35:19.724] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:35:19.724] INFO: ----------------------------------------------------------------------
[10:35:19.724] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:35:19.724] INFO: edge/corner pixel THR is adjusted
[10:35:19.724] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:35:20.681] INFO: Collecting data for 1 seconds...
[10:35:21.686] INFO: Done with hot pixel readout
[10:35:25.664] INFO: PixTest:: pg_setup set to default.
[10:35:25.665] INFO: 0 hot pixels found in step 0
[10:35:25.671] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:35:25.733] INFO: PixTest::trimHotPixels() done
[10:35:25.734] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C0.dat
[10:35:25.744] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C1.dat
[10:35:25.749] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C2.dat
[10:35:25.754] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C3.dat
[10:35:25.760] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C4.dat
[10:35:25.765] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C5.dat
[10:35:25.771] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C6.dat
[10:35:25.776] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C7.dat
[10:35:25.781] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C8.dat
[10:35:25.787] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C9.dat
[10:35:25.792] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C10.dat
[10:35:25.797] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C11.dat
[10:35:25.803] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C12.dat
[10:35:25.808] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C13.dat
[10:35:25.813] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C14.dat
[10:35:25.819] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C15.dat
[10:35:25.824] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-28_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//defaultMaskFile.dat
[10:35:25.834] INFO: enter test to run
[10:36:04.079] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:36:04.079] INFO: running: xray
[10:36:04.081] INFO: ----------------------------------------------------------------------
[10:36:04.081] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:36:04.081] INFO: ----------------------------------------------------------------------
[10:36:05.082] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:36:16.870] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:36:46.241] INFO: Resuming triggers.
[10:36:58.028] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:37:27.297] INFO: Resuming triggers.
[10:37:39.084] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[10:38:08.322] INFO: Resuming triggers.
[10:38:20.110] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:38:49.370] INFO: Resuming triggers.
[10:39:01.155] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[10:39:30.532] INFO: Resuming triggers.
[10:39:42.314] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[10:40:11.539] INFO: Resuming triggers.
[10:40:23.323] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[10:40:52.530] INFO: Resuming triggers.
[10:41:04.315] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[10:41:33.562] INFO: Resuming triggers.
[10:41:39.606] INFO: data taking finished, elapsed time: 100 seconds.
[10:41:54.898] INFO: PixTest:: pg_setup set to default.
[10:41:54.902] INFO: PixTestXray::doPhRun() done
[10:41:55.037] INFO: enter test to run
[10:42:51.116] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:42:51.116] INFO: running: xray
[10:42:51.117] INFO: ----------------------------------------------------------------------
[10:42:51.117] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:42:51.117] INFO: ----------------------------------------------------------------------
[10:42:52.082] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:42:59.244] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[10:43:29.373] INFO: Resuming triggers.
[10:43:36.543] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[10:44:06.678] INFO: Resuming triggers.
[10:44:13.845] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[10:44:44.476] INFO: Resuming triggers.
[10:44:51.644] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[10:45:22.723] INFO: Resuming triggers.
[10:45:29.895] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[10:46:00.838] INFO: Resuming triggers.
[10:46:08.009] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[10:46:39.542] INFO: Resuming triggers.
[10:46:46.717] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[10:47:17.931] INFO: Resuming triggers.
[10:47:25.103] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:47:56.355] INFO: Resuming triggers.
[10:48:03.525] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[10:48:33.632] INFO: Resuming triggers.
[10:48:40.804] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[10:49:10.936] INFO: Resuming triggers.
[10:49:18.110] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[10:49:48.341] INFO: Resuming triggers.
[10:49:55.510] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[10:50:25.644] INFO: Resuming triggers.
[10:50:32.813] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:51:02.822] INFO: Resuming triggers.
[10:51:09.942] INFO: data taking finished, elapsed time: 100 seconds.
[10:51:40.351] INFO: PixTest:: pg_setup set to default.
[10:51:40.355] INFO: PixTestXray::doPhRun() done
[10:51:40.509] INFO: enter test to run
[10:52:13.584] INFO: test: HighRate no parameter change
[10:52:13.584] INFO: running: highrate
[10:52:13.586] INFO: ----------------------------------------------------------------------
[10:52:13.586] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:52:13.586] INFO: ----------------------------------------------------------------------
[10:52:13.729] INFO: Expecting 768 events.
[10:52:14.862] INFO: 768 events read in total (418ms).
[10:52:14.863] INFO: Test took 1269ms.
[10:52:15.665] INFO: Expecting 41600 events.
[10:52:18.764] INFO: 41600 events read in total (2572ms).
[10:52:18.765] INFO: Test took 3894ms.
[10:52:18.796] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:19.529] INFO: Expecting 41600 events.
[10:52:22.749] INFO: 41600 events read in total (2693ms).
[10:52:22.750] INFO: Test took 3938ms.
[10:52:22.782] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:23.507] INFO: Expecting 41600 events.
[10:52:26.749] INFO: 41600 events read in total (2715ms).
[10:52:26.750] INFO: Test took 3951ms.
[10:52:26.781] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:27.512] INFO: Expecting 41600 events.
[10:52:30.754] INFO: 41600 events read in total (2715ms).
[10:52:30.755] INFO: Test took 3958ms.
[10:52:30.787] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:31.516] INFO: Expecting 41600 events.
[10:52:34.750] INFO: 41600 events read in total (2708ms).
[10:52:34.751] INFO: Test took 3946ms.
[10:52:34.782] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:35.512] INFO: Expecting 41600 events.
[10:52:38.745] INFO: 41600 events read in total (2706ms).
[10:52:38.746] INFO: Test took 3945ms.
[10:52:38.778] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:39.504] INFO: Expecting 41600 events.
[10:52:42.742] INFO: 41600 events read in total (2711ms).
[10:52:42.743] INFO: Test took 3948ms.
[10:52:42.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:43.503] INFO: Expecting 41600 events.
[10:52:46.679] INFO: 41600 events read in total (2649ms).
[10:52:46.680] INFO: Test took 3887ms.
[10:52:46.712] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:47.441] INFO: Expecting 41600 events.
[10:52:50.683] INFO: 41600 events read in total (2716ms).
[10:52:50.684] INFO: Test took 3954ms.
[10:52:50.716] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:51.441] INFO: Expecting 41600 events.
[10:52:54.668] INFO: 41600 events read in total (2701ms).
[10:52:54.669] INFO: Test took 3936ms.
[10:52:54.701] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:55.426] INFO: Expecting 41600 events.
[10:52:58.664] INFO: 41600 events read in total (2711ms).
[10:52:58.665] INFO: Test took 3946ms.
[10:52:58.697] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:59.423] INFO: Expecting 41600 events.
[10:53:02.673] INFO: 41600 events read in total (2723ms).
[10:53:02.674] INFO: Test took 3959ms.
[10:53:02.708] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:03.431] INFO: Expecting 41600 events.
[10:53:06.671] INFO: 41600 events read in total (2714ms).
[10:53:06.672] INFO: Test took 3946ms.
[10:53:06.704] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:07.433] INFO: Expecting 41600 events.
[10:53:10.667] INFO: 41600 events read in total (2707ms).
[10:53:10.669] INFO: Test took 3948ms.
[10:53:10.708] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:11.429] INFO: Expecting 41600 events.
[10:53:14.665] INFO: 41600 events read in total (2709ms).
[10:53:14.666] INFO: Test took 3940ms.
[10:53:14.698] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:15.423] INFO: Expecting 41600 events.
[10:53:18.658] INFO: 41600 events read in total (2708ms).
[10:53:18.659] INFO: Test took 3943ms.
[10:53:18.691] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:19.418] INFO: Expecting 41600 events.
[10:53:22.665] INFO: 41600 events read in total (2720ms).
[10:53:22.666] INFO: Test took 3958ms.
[10:53:22.698] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:23.425] INFO: Expecting 41600 events.
[10:53:26.662] INFO: 41600 events read in total (2710ms).
[10:53:26.663] INFO: Test took 3947ms.
[10:53:26.695] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:27.423] INFO: Expecting 41600 events.
[10:53:30.647] INFO: 41600 events read in total (2697ms).
[10:53:30.648] INFO: Test took 3935ms.
[10:53:30.680] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:31.410] INFO: Expecting 41600 events.
[10:53:34.496] INFO: 41600 events read in total (2559ms).
[10:53:34.497] INFO: Test took 3799ms.
[10:53:34.529] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:34.895] INFO: enter test to run
[10:53:40.672] INFO: test: HighRate no parameter change
[10:53:40.672] INFO: running: highrate
[10:53:40.673] INFO: ----------------------------------------------------------------------
[10:53:40.673] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:53:40.673] INFO: ----------------------------------------------------------------------
[10:53:41.287] INFO: Expecting 208000 events.
[10:53:53.058] INFO: 208000 events read in total (11244ms).
[10:53:53.061] INFO: Test took 12382ms.
[10:53:53.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:53.444] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 1 2 0 0 1 0 0 0 1
[10:53:53.444] INFO: number of red-efficiency pixels: 100 52 75 127 110 119 112 93 98 157 122 116 130 66 19 27
[10:53:53.444] INFO: number of X-ray hits detected: 68900 37762 57359 89147 92684 96932 91955 72959 67109 100338 97551 85435 89550 53909 21531 23493
[10:53:53.444] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:53:53.445] INFO: number of Vcal hits detected: 207897 207948 207923 207870 207885 207877 207885 207856 207797 207838 207874 207833 207868 207932 207981 207924
[10:53:53.445] 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 99.9 99.9 100.0 100.0 100.0
[10:53:53.445] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[10:53:53.445] INFO: X-ray hit rate [MHz/cm2]: 20.2 11.1 16.8 26.1 27.2 28.4 27.0 21.4 19.7 29.4 28.6 25.0 26.2 15.8 6.3 6.9
[10:53:53.445] INFO: PixTestHighRate::doXPixelAlive() done
[10:53:53.494] INFO: PixTest:: pg_setup set to default.
[10:53:53.511] INFO: enter test to run
[10:54:33.175] INFO: test: HighRate no parameter change
[10:54:33.176] INFO: running: highrate
[10:54:33.177] INFO: ----------------------------------------------------------------------
[10:54:33.177] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:54:33.177] INFO: ----------------------------------------------------------------------
[10:54:33.790] INFO: Expecting 208000 events.
[10:54:46.001] INFO: 208000 events read in total (12684ms).
[10:54:47.006] INFO: Test took 13819ms.
[10:54:47.272] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:47.564] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 1 2 0 0 1 0 0 0 1
[10:54:47.564] INFO: number of red-efficiency pixels: 281 121 163 374 285 287 299 265 216 473 359 248 333 208 55 53
[10:54:47.564] INFO: number of X-ray hits detected: 139011 76348 114559 179764 187704 196052 186255 148415 137041 201159 197374 172354 182826 109071 43587 47639
[10:54:47.564] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:54:47.564] INFO: number of Vcal hits detected: 207686 207871 207832 207605 207696 207705 207687 207676 207669 207484 207604 207697 207643 207774 207943 207897
[10:54:47.564] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[10:54:47.564] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.9 99.8 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[10:54:47.564] INFO: X-ray hit rate [MHz/cm2]: 40.7 22.4 33.6 52.7 55.0 57.5 54.6 43.5 40.2 59.0 57.9 50.5 53.6 32.0 12.8 14.0
[10:54:47.564] INFO: PixTestHighRate::doXPixelAlive() done
[10:54:47.611] INFO: PixTest:: pg_setup set to default.
[10:54:47.623] INFO: enter test to run
[10:55:17.351] INFO: test: HighRate no parameter change
[10:55:17.351] INFO: running: highrate
[10:55:17.352] INFO: ----------------------------------------------------------------------
[10:55:17.352] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:55:17.352] INFO: ----------------------------------------------------------------------
[10:55:17.970] INFO: Expecting 208000 events.
[10:55:32.844] INFO: 208000 events read in total (14347ms).
[10:55:32.851] INFO: Test took 15490ms.
[10:55:33.241] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:33.574] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 1 2 0 0 1 0 0 0 1
[10:55:33.574] INFO: number of red-efficiency pixels: 585 248 389 775 613 633 617 579 468 1031 784 532 780 481 75 110
[10:55:33.574] INFO: number of X-ray hits detected: 209807 114931 173843 272531 284443 295113 282531 222028 206475 304993 297659 260522 274433 164417 66042 72041
[10:55:33.574] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:55:33.574] INFO: number of Vcal hits detected: 207235 207732 207569 207122 207293 207281 207307 207279 207373 206729 207064 207324 207074 207405 207924 207838
[10:55:33.574] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.8 99.6 99.7 99.7 99.7 99.7 99.8 99.4 99.6 99.7 99.6 99.7 100.0 99.9
[10:55:33.574] INFO: Vcal hit overall efficiency (%): 99.6 99.9 99.8 99.6 99.7 99.7 99.7 99.7 99.7 99.4 99.6 99.7 99.6 99.7 100.0 99.9
[10:55:33.574] INFO: X-ray hit rate [MHz/cm2]: 61.5 33.7 51.0 79.9 83.4 86.5 82.8 65.1 60.5 89.4 87.2 76.4 80.4 48.2 19.4 21.1
[10:55:33.574] INFO: PixTestHighRate::doXPixelAlive() done
[10:55:33.626] INFO: PixTest:: pg_setup set to default.
[10:55:33.642] INFO: enter test to run
[10:56:45.383] INFO: test: exit no parameter change
[10:56:45.718] QUIET: Connection to board 33 closed.
[10:56:45.720] INFO: pXar: this is the end, my friend