[12:20:02.341] INFO: *** Welcome to pxar ***
[12:20:02.341] INFO: *** Today: 2016/08/29
[12:20:02.361] INFO: *** Version: v1.9.0-818-g96727
[12:20:02.361] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//dacParameters35_C15.dat
[12:20:02.361] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:20:02.361] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//defaultMaskFile.dat
[12:20:02.361] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C15.dat
[12:20:02.431] INFO: clk: 4
[12:20:02.431] INFO: ctr: 4
[12:20:02.431] INFO: sda: 19
[12:20:02.431] INFO: tin: 9
[12:20:02.431] INFO: level: 15
[12:20:02.431] INFO: triggerdelay: 0
[12:20:02.431] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:20:02.431] INFO: Log level: INFO
[12:20:02.449] QUIET: Connection to board DTB_WREKRL opened.
[12:20:02.452] 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:
------------------------------------------------------
[12:20:02.455] INFO: RPC call hashes of host and DTB match: 398089610
[12:20:03.985] INFO: DUT info:
[12:20:03.985] INFO: The DUT currently contains the following objects:
[12:20:03.985] INFO: 2 TBM Cores tbm08c (2 ON)
[12:20:03.985] INFO: TBM Core alpha (0): 7 registers set
[12:20:03.985] INFO: TBM Core beta (1): 7 registers set
[12:20:03.985] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:20:03.985] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.985] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.985] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.985] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.985] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.985] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.985] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.985] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.985] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.986] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.986] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.986] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.986] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.986] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.986] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:03.986] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:20:04.389] INFO: enter 'restricted' command line mode
[12:20:04.389] INFO: enter test to run
[12:20:09.761] INFO: test: PixelAlive no parameter change
[12:20:09.761] INFO: running: pixelalive
[12:20:09.770] INFO: ----------------------------------------------------------------------
[12:20:09.770] 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:20:09.770] INFO: ----------------------------------------------------------------------
[12:20:10.096] INFO: Expecting 41600 events.
[12:20:14.417] INFO: 41600 events read in total (3603ms).
[12:20:14.587] INFO: Test took 4815ms.
[12:20:14.597] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:20:14.866] INFO: PixTestAlive::aliveTest() done
[12:20:14.866] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 1 0 0 0 0 0 0 0 0
[12:20:14.893] INFO: enter test to run
[12:20:40.929] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:20:40.929] INFO: running: highrate
[12:20:40.929] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:20:41.080] INFO: ----------------------------------------------------------------------
[12:20:41.080] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:20:41.080] INFO: ----------------------------------------------------------------------
[12:20:41.080] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:20:41.080] INFO: edge/corner pixel THR is adjusted
[12:20:41.080] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:20:42.039] INFO: Collecting data for 5 seconds...
[12:20:47.056] INFO: Done with hot pixel readout
[12:20:58.663] INFO: PixTest:: pg_setup set to default.
[12:20:58.664] INFO: 2 hot pixels found in step 0
[12:20:59.653] INFO: Collecting data for 5 seconds...
[12:21:04.669] INFO: Done with hot pixel readout
[12:21:16.252] INFO: PixTest:: pg_setup set to default.
[12:21:16.253] INFO: 1 hot pixels found in step 1
[12:21:17.246] INFO: Collecting data for 5 seconds...
[12:21:22.262] INFO: Done with hot pixel readout
[12:21:33.804] INFO: PixTest:: pg_setup set to default.
[12:21:33.805] INFO: 4 hot pixels found in step 2
[12:21:34.798] INFO: Collecting data for 5 seconds...
[12:21:39.814] INFO: Done with hot pixel readout
[12:21:51.362] INFO: PixTest:: pg_setup set to default.
[12:21:51.363] INFO: 6 hot pixels found in step 3
[12:21:52.355] INFO: Collecting data for 5 seconds...
[12:21:57.371] INFO: Done with hot pixel readout
[12:22:08.962] INFO: PixTest:: pg_setup set to default.
[12:22:08.963] INFO: 2 hot pixels found in step 4
[12:22:09.955] INFO: Collecting data for 5 seconds...
[12:22:14.972] INFO: Done with hot pixel readout
[12:22:26.537] INFO: PixTest:: pg_setup set to default.
[12:22:26.537] INFO: 2 hot pixels found in step 5
[12:22:27.529] INFO: Collecting data for 5 seconds...
[12:22:32.546] INFO: Done with hot pixel readout
[12:22:44.182] INFO: PixTest:: pg_setup set to default.
[12:22:44.183] INFO: 1 hot pixels found in step 6
[12:22:45.175] INFO: Collecting data for 5 seconds...
[12:22:50.192] INFO: Done with hot pixel readout
[12:23:01.624] INFO: PixTest:: pg_setup set to default.
[12:23:01.624] INFO: 1 hot pixels found in step 7
[12:23:02.619] INFO: Collecting data for 5 seconds...
[12:23:07.636] INFO: Done with hot pixel readout
[12:23:19.234] INFO: PixTest:: pg_setup set to default.

[12:23:19.235] INFO: 3 hot pixels found in step 8
[12:23:20.227] INFO: Collecting data for 5 seconds...
[12:23:25.244] INFO: Done with hot pixel readout
[12:23:36.817] INFO: PixTest:: pg_setup set to default.
[12:23:36.818] INFO: 4 hot pixels found in step 9
[12:23:37.810] INFO: Collecting data for 5 seconds...
[12:23:42.827] INFO: Done with hot pixel readout
[12:23:54.388] INFO: PixTest:: pg_setup set to default.
[12:23:54.389] INFO: 1 hot pixels found in step 10
[12:23:55.382] INFO: Collecting data for 5 seconds...
[12:24:00.398] INFO: Done with hot pixel readout
[12:24:11.967] INFO: PixTest:: pg_setup set to default.
[12:24:11.968] INFO: 0 hot pixels found in step 11
[12:24:11.003] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:24:12.026] INFO: PixTest::trimHotPixels() done
[12:24:12.042] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C0.dat
[12:24:12.052] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C1.dat
[12:24:12.058] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C2.dat
[12:24:12.063] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C3.dat
[12:24:12.069] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C4.dat
[12:24:12.074] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C5.dat
[12:24:12.079] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C6.dat
[12:24:12.085] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C7.dat
[12:24:12.091] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C8.dat
[12:24:12.096] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C9.dat
[12:24:12.101] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C10.dat
[12:24:12.107] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C11.dat
[12:24:12.112] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C12.dat
[12:24:12.117] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C13.dat
[12:24:12.123] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C14.dat
[12:24:12.129] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C15.dat
[12:24:12.150] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//defaultMaskFile.dat
[12:24:12.163] INFO: enter test to run
[12:24:32.823] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:24:32.823] INFO: running: highrate
[12:24:32.828] INFO: ----------------------------------------------------------------------
[12:24:32.828] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:24:32.828] INFO: ----------------------------------------------------------------------
[12:24:32.828] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:24:32.828] INFO: edge/corner pixel THR is adjusted
[12:24:32.828] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:24:33.785] INFO: Collecting data for 1 seconds...
[12:24:34.789] INFO: Done with hot pixel readout
[12:24:38.774] INFO: PixTest:: pg_setup set to default.
[12:24:38.774] INFO: 0 hot pixels found in step 0
[12:24:38.780] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:24:38.871] INFO: PixTest::trimHotPixels() done
[12:24:38.872] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C0.dat
[12:24:38.883] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C1.dat
[12:24:38.888] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C2.dat
[12:24:38.894] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C3.dat
[12:24:38.899] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C4.dat
[12:24:38.904] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C5.dat
[12:24:38.909] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C6.dat
[12:24:38.915] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C7.dat
[12:24:38.920] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C8.dat
[12:24:38.925] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C9.dat
[12:24:38.930] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C10.dat
[12:24:38.935] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C11.dat
[12:24:38.940] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C12.dat
[12:24:38.946] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C13.dat
[12:24:38.951] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C14.dat
[12:24:38.956] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C15.dat
[12:24:38.961] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-6-07_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//defaultMaskFile.dat
[12:24:38.971] INFO: enter test to run
[12:25:00.439] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:25:00.439] INFO: running: xray
[12:25:00.451] INFO: ----------------------------------------------------------------------
[12:25:00.451] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:25:00.452] INFO: ----------------------------------------------------------------------
[12:25:01.415] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:25:12.947] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:25:42.532] INFO: Resuming triggers.
[12:25:54.062] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[12:26:23.808] INFO: Resuming triggers.
[12:26:35.345] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:27:05.272] INFO: Resuming triggers.
[12:27:16.811] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[12:27:46.688] INFO: Resuming triggers.
[12:27:58.227] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[12:28:28.295] INFO: Resuming triggers.
[12:28:39.838] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[12:29:08.936] INFO: Resuming triggers.
[12:29:20.482] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[12:29:50.607] INFO: Resuming triggers.
[12:30:02.152] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[12:30:32.163] INFO: Resuming triggers.
[12:30:40.212] INFO: data taking finished, elapsed time: 100 seconds.
[12:31:01.569] INFO: PixTest:: pg_setup set to default.
[12:31:01.572] INFO: PixTestXray::doPhRun() done
[12:31:01.805] INFO: enter test to run
[12:31:26.884] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:31:26.884] INFO: running: xray
[12:31:26.886] INFO: ----------------------------------------------------------------------
[12:31:26.886] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:31:26.886] INFO: ----------------------------------------------------------------------
[12:31:27.849] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:31:34.503] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[12:32:04.820] INFO: Resuming triggers.
[12:32:11.477] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[12:32:41.902] INFO: Resuming triggers.
[12:32:48.560] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[12:33:18.825] INFO: Resuming triggers.
[12:33:25.486] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[12:33:55.806] INFO: Resuming triggers.
[12:34:02.469] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[12:34:32.862] INFO: Resuming triggers.
[12:34:39.528] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[12:35:09.856] INFO: Resuming triggers.
[12:35:16.524] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[12:35:46.909] INFO: Resuming triggers.
[12:35:53.576] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[12:36:23.951] INFO: Resuming triggers.
[12:36:30.618] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[12:37:00.955] INFO: Resuming triggers.
[12:37:07.627] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[12:37:37.964] INFO: Resuming triggers.
[12:37:44.639] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[12:38:14.927] INFO: Resuming triggers.
[12:38:21.602] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[12:38:51.914] INFO: Resuming triggers.
[12:38:58.592] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[12:39:29.968] INFO: Resuming triggers.
[12:39:36.642] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[12:40:06.718] INFO: Resuming triggers.
[12:40:13.394] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[12:40:43.750] INFO: Resuming triggers.
[12:40:44.076] INFO: data taking finished, elapsed time: 100 seconds.
[12:40:45.921] INFO: PixTest:: pg_setup set to default.
[12:40:45.924] INFO: PixTestXray::doPhRun() done
[12:40:46.106] INFO: enter test to run
[12:41:18.248] INFO: test: HighRate no parameter change
[12:41:18.248] INFO: running: highrate
[12:41:18.269] INFO: ----------------------------------------------------------------------
[12:41:18.269] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:41:18.269] INFO: ----------------------------------------------------------------------
[12:41:18.415] INFO: Expecting 768 events.
[12:41:19.571] INFO: 768 events read in total (433ms).
[12:41:19.571] INFO: Test took 1290ms.
[12:41:20.374] INFO: Expecting 41600 events.
[12:41:23.462] INFO: 41600 events read in total (2561ms).
[12:41:23.463] INFO: Test took 3871ms.
[12:41:23.497] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:24.224] INFO: Expecting 41600 events.
[12:41:27.322] INFO: 41600 events read in total (2571ms).
[12:41:27.323] INFO: Test took 3808ms.
[12:41:27.357] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:28.077] INFO: Expecting 41600 events.
[12:41:31.069] INFO: 41600 events read in total (2465ms).
[12:41:31.070] INFO: Test took 3696ms.
[12:41:31.106] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:31.829] INFO: Expecting 41600 events.
[12:41:35.041] INFO: 41600 events read in total (2685ms).
[12:41:35.042] INFO: Test took 3917ms.
[12:41:35.079] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:35.795] INFO: Expecting 41600 events.
[12:41:39.049] INFO: 41600 events read in total (2727ms).
[12:41:39.050] INFO: Test took 3952ms.
[12:41:39.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:39.803] INFO: Expecting 41600 events.
[12:41:43.020] INFO: 41600 events read in total (2690ms).
[12:41:43.021] INFO: Test took 3919ms.
[12:41:43.057] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:43.777] INFO: Expecting 41600 events.
[12:41:46.002] INFO: 41600 events read in total (2698ms).
[12:41:46.003] INFO: Test took 3928ms.
[12:41:47.037] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:47.755] INFO: Expecting 41600 events.
[12:41:51.041] INFO: 41600 events read in total (2759ms).
[12:41:51.042] INFO: Test took 3988ms.
[12:41:51.078] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:51.800] INFO: Expecting 41600 events.
[12:41:54.000] INFO: 41600 events read in total (2673ms).
[12:41:54.001] INFO: Test took 3906ms.
[12:41:55.036] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:55.750] INFO: Expecting 41600 events.
[12:41:58.981] INFO: 41600 events read in total (2704ms).
[12:41:58.982] INFO: Test took 3927ms.
[12:41:59.018] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:59.739] INFO: Expecting 41600 events.
[12:42:02.932] INFO: 41600 events read in total (2666ms).
[12:42:02.933] INFO: Test took 3897ms.
[12:42:02.968] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:03.690] INFO: Expecting 41600 events.
[12:42:06.866] INFO: 41600 events read in total (2649ms).
[12:42:06.867] INFO: Test took 3882ms.
[12:42:06.901] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:07.622] INFO: Expecting 41600 events.
[12:42:10.871] INFO: 41600 events read in total (2722ms).
[12:42:10.872] INFO: Test took 3953ms.
[12:42:10.906] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:11.629] INFO: Expecting 41600 events.
[12:42:14.869] INFO: 41600 events read in total (2714ms).
[12:42:14.871] INFO: Test took 3948ms.
[12:42:14.906] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:15.628] INFO: Expecting 41600 events.
[12:42:18.900] INFO: 41600 events read in total (2746ms).
[12:42:18.901] INFO: Test took 3978ms.
[12:42:18.935] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:19.656] INFO: Expecting 41600 events.
[12:42:22.881] INFO: 41600 events read in total (2698ms).
[12:42:22.882] INFO: Test took 3929ms.
[12:42:22.917] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:23.632] INFO: Expecting 41600 events.
[12:42:26.787] INFO: 41600 events read in total (2628ms).
[12:42:26.788] INFO: Test took 3851ms.
[12:42:26.823] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:27.541] INFO: Expecting 41600 events.
[12:42:30.843] INFO: 41600 events read in total (2775ms).
[12:42:30.844] INFO: Test took 4004ms.
[12:42:30.879] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:31.600] INFO: Expecting 41600 events.
[12:42:34.892] INFO: 41600 events read in total (2765ms).
[12:42:34.893] INFO: Test took 3996ms.
[12:42:34.930] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:35.631] INFO: Expecting 41600 events.
[12:42:38.691] INFO: 41600 events read in total (2533ms).
[12:42:38.692] INFO: Test took 3744ms.
[12:42:38.726] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:39.083] INFO: enter test to run
[12:43:11.143] INFO: test: HighRate no parameter change
[12:43:11.144] INFO: running: highrate
[12:43:11.145] INFO: ----------------------------------------------------------------------
[12:43:11.145] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:43:11.145] INFO: ----------------------------------------------------------------------
[12:43:11.779] INFO: Expecting 208000 events.
[12:43:23.718] INFO: 208000 events read in total (11412ms).
[12:43:23.721] INFO: Test took 12566ms.
[12:43:23.873] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:24.128] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 1 0 0 0 0 0 0 0 0
[12:43:24.128] INFO: number of red-efficiency pixels: 62 48 106 135 128 168 136 109 103 149 104 97 116 60 20 31
[12:43:24.128] INFO: number of X-ray hits detected: 51758 40566 66808 108427 116732 118046 120346 89277 81401 100438 95451 79733 81973 47097 19122 22601
[12:43:24.128] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:43:24.128] INFO: number of Vcal hits detected: 207937 207902 207891 207864 207869 207829 207860 207841 207896 207848 207893 207903 207882 207940 207979 207969
[12:43:24.128] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 100.0 99.9 100.0 100.0 100.0
[12:43:24.128] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[12:43:24.128] INFO: X-ray hit rate [MHz/cm2]: 15.2 11.9 19.6 31.8 34.2 34.6 35.3 26.2 23.9 29.4 28.0 23.4 24.0 13.8 5.6 6.6
[12:43:24.128] INFO: PixTestHighRate::doXPixelAlive() done
[12:43:24.175] INFO: PixTest:: pg_setup set to default.
[12:43:24.188] INFO: enter test to run
[12:43:39.216] INFO: test: HighRate no parameter change
[12:43:39.216] INFO: running: highrate
[12:43:39.217] INFO: ----------------------------------------------------------------------
[12:43:39.217] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:43:39.217] INFO: ----------------------------------------------------------------------
[12:43:39.839] INFO: Expecting 208000 events.
[12:43:53.456] INFO: 208000 events read in total (13090ms).
[12:43:53.461] INFO: Test took 14234ms.
[12:43:53.759] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:54.059] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 1 0 0 0 0 0 0 0 0
[12:43:54.059] INFO: number of red-efficiency pixels: 148 118 276 385 434 514 419 388 302 432 291 294 288 135 33 61
[12:43:54.059] INFO: number of X-ray hits detected: 103558 81147 133808 217791 234680 237544 241235 180508 163775 203064 192127 159546 165924 94410 38857 45375
[12:43:54.059] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:43:54.059] INFO: number of Vcal hits detected: 207848 207826 207697 207586 207534 207441 207547 207516 207680 207535 207694 207686 207693 207861 207967 207939
[12:43:54.059] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[12:43:54.059] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.8 99.8 99.8 99.8 99.9 99.8 99.9 99.9 100.0 100.0
[12:43:54.059] INFO: X-ray hit rate [MHz/cm2]: 30.4 23.8 39.2 63.8 68.8 69.6 70.7 52.9 48.0 59.5 56.3 46.8 48.6 27.7 11.4 13.3
[12:43:54.059] INFO: PixTestHighRate::doXPixelAlive() done
[12:43:54.104] INFO: PixTest:: pg_setup set to default.
[12:43:54.120] INFO: enter test to run
[12:44:07.271] INFO: test: HighRate no parameter change
[12:44:07.271] INFO: running: highrate
[12:44:07.272] INFO: ----------------------------------------------------------------------
[12:44:07.272] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:44:07.272] INFO: ----------------------------------------------------------------------
[12:44:07.895] INFO: Expecting 208000 events.
[12:44:23.329] INFO: 208000 events read in total (14907ms).
[12:44:23.336] INFO: Test took 16054ms.
[12:44:23.769] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:24.115] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 1 0 0 0 0 0 0 0 0
[12:44:24.115] INFO: number of red-efficiency pixels: 326 204 648 899 943 1200 1045 936 628 1039 670 671 679 234 70 65
[12:44:24.115] INFO: number of X-ray hits detected: 157471 123576 202853 329374 356892 359186 365369 272782 247934 306668 291798 241475 251003 143127 59334 69344
[12:44:24.115] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:44:24.115] INFO: number of Vcal hits detected: 207632 207726 207153 206912 206868 206421 206688 206719 207264 206721 207234 207225 207231 207742 207927 207932
[12:44:24.115] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.6 99.5 99.5 99.3 99.4 99.5 99.7 99.4 99.7 99.7 99.7 99.9 100.0 100.0
[12:44:24.115] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.5 99.5 99.2 99.4 99.4 99.6 99.4 99.6 99.6 99.6 99.9 100.0 100.0
[12:44:24.116] INFO: X-ray hit rate [MHz/cm2]: 46.2 36.2 59.5 96.5 104.6 105.3 107.1 80.0 72.7 89.9 85.5 70.8 73.6 42.0 17.4 20.3
[12:44:24.116] INFO: PixTestHighRate::doXPixelAlive() done
[12:44:24.161] INFO: PixTest:: pg_setup set to default.
[12:44:24.183] INFO: enter test to run
[12:44:27.207] INFO: test: exit no parameter change
[12:44:27.594] QUIET: Connection to board 33 closed.
[12:44:27.607] INFO: pXar: this is the end, my friend