[12:29:20.707] INFO: *** Welcome to pxar ***
[12:29:20.707] INFO: *** Today: 2016/09/15
[12:29:21.340] INFO: *** Version: v1.9.0-818-g96727
[12:29:21.340] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//dacParameters35_C15.dat
[12:29:21.391] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:29:21.391] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//defaultMaskFile.dat
[12:29:21.395] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C15.dat
[12:29:21.508] INFO: clk: 4
[12:29:21.508] INFO: ctr: 4
[12:29:21.508] INFO: sda: 19
[12:29:21.508] INFO: tin: 9
[12:29:21.508] INFO: level: 15
[12:29:21.508] INFO: triggerdelay: 0
[12:29:21.508] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:29:21.508] INFO: Log level: INFO
[12:29:21.526] QUIET: Connection to board DTB_WREKRL opened.
[12:29:21.530] 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:29:21.533] INFO: RPC call hashes of host and DTB match: 398089610
[12:29:23.061] INFO: DUT info:
[12:29:23.061] INFO: The DUT currently contains the following objects:
[12:29:23.061] INFO: 2 TBM Cores tbm08c (2 ON)
[12:29:23.061] INFO: TBM Core alpha (0): 7 registers set
[12:29:23.061] INFO: TBM Core beta (1): 7 registers set
[12:29:23.061] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:29:23.061] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.061] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.061] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.061] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.061] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.061] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.062] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.062] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.062] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.062] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.062] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.062] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.062] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.062] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.062] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.062] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:23.486] INFO: enter 'restricted' command line mode
[12:29:23.486] INFO: enter test to run
[12:29:38.204] INFO: test: PixelAlive no parameter change
[12:29:38.205] INFO: running: pixelalive
[12:29:38.264] INFO: ----------------------------------------------------------------------
[12:29:38.264] 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:29:38.265] INFO: ----------------------------------------------------------------------
[12:29:38.586] INFO: Expecting 41600 events.
[12:29:42.925] INFO: 41600 events read in total (3620ms).
[12:29:43.091] INFO: Test took 4823ms.
[12:29:43.106] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:43.708] INFO: PixTestAlive::aliveTest() done
[12:29:43.708] INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[12:29:43.865] INFO: enter test to run
[12:30:16.180] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:30:16.180] INFO: running: highrate
[12:30:16.202] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:30:16.578] INFO: ----------------------------------------------------------------------
[12:30:16.578] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:30:16.578] INFO: ----------------------------------------------------------------------
[12:30:16.578] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:30:16.578] INFO: edge/corner pixel THR is adjusted
[12:30:16.578] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:30:17.537] INFO: Collecting data for 5 seconds...
[12:30:22.555] INFO: Done with hot pixel readout
[12:30:33.837] INFO: PixTest:: pg_setup set to default.
[12:30:33.838] INFO: 1 hot pixels found in step 0
[12:30:34.823] INFO: Collecting data for 5 seconds...
[12:30:39.840] INFO: Done with hot pixel readout
[12:30:51.095] INFO: PixTest:: pg_setup set to default.
[12:30:51.096] INFO: 0 hot pixels found in step 1
[12:30:51.124] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:30:51.143] INFO: PixTest::trimHotPixels() done
[12:30:51.143] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C0.dat
[12:30:51.155] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C1.dat
[12:30:51.162] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C2.dat
[12:30:51.167] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C3.dat
[12:30:51.172] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C4.dat
[12:30:51.178] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C5.dat
[12:30:51.183] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C6.dat
[12:30:51.188] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C7.dat
[12:30:51.193] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C8.dat
[12:30:51.199] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C9.dat
[12:30:51.204] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C10.dat
[12:30:51.209] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C11.dat
[12:30:51.214] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C12.dat
[12:30:51.220] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C13.dat
[12:30:51.225] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C14.dat
[12:30:51.230] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C15.dat
[12:30:51.235] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//defaultMaskFile.dat
[12:30:51.246] INFO: enter test to run
[12:31:49.108] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:31:49.108] INFO: running: highrate
[12:31:49.112] INFO: ----------------------------------------------------------------------
[12:31:49.112] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:31:49.112] INFO: ----------------------------------------------------------------------
[12:31:49.112] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:31:49.112] INFO: edge/corner pixel THR is adjusted
[12:31:49.112] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:31:50.078] INFO: Collecting data for 1 seconds...
[12:31:51.082] INFO: Done with hot pixel readout
[12:31:54.760] INFO: PixTest:: pg_setup set to default.
[12:31:54.761] INFO: 0 hot pixels found in step 0
[12:31:54.767] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:31:54.863] INFO: PixTest::trimHotPixels() done
[12:31:54.863] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C0.dat
[12:31:54.876] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C1.dat
[12:31:54.882] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C2.dat
[12:31:54.888] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C3.dat
[12:31:54.893] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C4.dat
[12:31:54.899] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C5.dat
[12:31:54.904] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C6.dat
[12:31:54.910] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C7.dat
[12:31:54.916] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C8.dat
[12:31:54.921] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C9.dat
[12:31:54.927] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C10.dat
[12:31:54.932] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C11.dat
[12:31:54.938] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C12.dat
[12:31:54.943] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C13.dat
[12:31:54.949] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C14.dat
[12:31:54.954] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//trimParameters35_C15.dat
[12:31:54.960] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-1-04_FPIXTest-17C-FNAL-160909-1150-150V_2016-09-09_11h50m_1473439829/000_FPIXTest_p17//defaultMaskFile.dat
[12:31:54.970] INFO: enter test to run
[12:33:11.651] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:33:11.651] INFO: running: xray
[12:33:11.652] INFO: ----------------------------------------------------------------------
[12:33:11.652] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:33:11.653] INFO: ----------------------------------------------------------------------
[12:33:12.635] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:33:24.447] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:33:52.001] INFO: Resuming triggers.
[12:34:04.818] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:34:34.133] INFO: Resuming triggers.
[12:34:45.949] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[12:35:13.663] INFO: Resuming triggers.
[12:35:25.481] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[12:35:50.603] INFO: Resuming triggers.
[12:36:02.416] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[12:36:29.135] INFO: Resuming triggers.
[12:36:40.951] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[12:37:07.688] INFO: Resuming triggers.
[12:37:19.506] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[12:37:47.959] INFO: Resuming triggers.
[12:37:59.777] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[12:38:27.677] INFO: Resuming triggers.
[12:38:33.470] INFO: data taking finished, elapsed time: 100 seconds.
[12:38:47.810] INFO: PixTest:: pg_setup set to default.
[12:38:47.813] INFO: PixTestXray::doPhRun() done
[12:38:47.990] INFO: enter test to run
[12:39:12.817] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:39:12.817] INFO: running: xray
[12:39:12.818] INFO: ----------------------------------------------------------------------
[12:39:12.818] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:39:12.818] INFO: ----------------------------------------------------------------------
[12:39:13.781] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:39:20.942] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[12:39:50.320] INFO: Resuming triggers.
[12:39:57.482] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[12:40:27.513] INFO: Resuming triggers.
[12:40:34.679] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[12:41:03.822] INFO: Resuming triggers.
[12:41:10.988] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[12:41:40.005] INFO: Resuming triggers.
[12:41:48.172] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[12:42:18.100] INFO: Resuming triggers.
[12:42:25.267] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[12:42:55.887] INFO: Resuming triggers.
[12:43:03.051] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[12:43:32.687] INFO: Resuming triggers.
[12:43:39.850] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[12:44:11.617] INFO: Resuming triggers.
[12:44:18.781] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[12:44:49.515] INFO: Resuming triggers.
[12:44:56.680] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[12:45:27.656] INFO: Resuming triggers.
[12:45:34.817] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[12:46:06.202] INFO: Resuming triggers.
[12:46:13.360] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[12:46:44.415] INFO: Resuming triggers.
[12:46:51.572] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[12:47:21.477] INFO: Resuming triggers.
[12:47:28.636] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[12:47:58.884] INFO: Resuming triggers.
[12:47:58.958] INFO: data taking finished, elapsed time: 100 seconds.
[12:47:59.771] INFO: PixTest:: pg_setup set to default.
[12:47:59.775] INFO: PixTestXray::doPhRun() done
[12:47:59.939] INFO: enter test to run
[12:48:29.621] INFO: test: HighRate no parameter change
[12:48:29.621] INFO: running: highrate
[12:48:29.678] INFO: ----------------------------------------------------------------------
[12:48:29.678] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:48:29.678] INFO: ----------------------------------------------------------------------
[12:48:29.844] INFO: Expecting 768 events.
[12:48:30.976] INFO: 768 events read in total (417ms).
[12:48:30.976] INFO: Test took 1270ms.
[12:48:31.780] INFO: Expecting 41600 events.
[12:48:34.859] INFO: 41600 events read in total (2553ms).
[12:48:34.860] INFO: Test took 3867ms.
[12:48:34.891] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:35.625] INFO: Expecting 41600 events.
[12:48:38.817] INFO: 41600 events read in total (2666ms).
[12:48:38.818] INFO: Test took 3909ms.
[12:48:38.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:39.578] INFO: Expecting 41600 events.
[12:48:42.761] INFO: 41600 events read in total (2656ms).
[12:48:42.762] INFO: Test took 3896ms.
[12:48:42.794] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:43.523] INFO: Expecting 41600 events.
[12:48:46.700] INFO: 41600 events read in total (2651ms).
[12:48:46.701] INFO: Test took 3889ms.
[12:48:46.734] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:47.459] INFO: Expecting 41600 events.
[12:48:50.661] INFO: 41600 events read in total (2675ms).
[12:48:50.662] INFO: Test took 3911ms.
[12:48:50.694] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:51.418] INFO: Expecting 41600 events.
[12:48:54.599] INFO: 41600 events read in total (2654ms).
[12:48:54.600] INFO: Test took 3889ms.
[12:48:54.631] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:55.362] INFO: Expecting 41600 events.
[12:48:58.550] INFO: 41600 events read in total (2662ms).
[12:48:58.551] INFO: Test took 3902ms.
[12:48:58.583] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:59.312] INFO: Expecting 41600 events.
[12:49:02.491] INFO: 41600 events read in total (2652ms).
[12:49:02.492] INFO: Test took 3891ms.
[12:49:02.524] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:03.249] INFO: Expecting 41600 events.
[12:49:06.465] INFO: 41600 events read in total (2689ms).
[12:49:06.466] INFO: Test took 3925ms.
[12:49:06.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:07.224] INFO: Expecting 41600 events.
[12:49:10.403] INFO: 41600 events read in total (2652ms).
[12:49:10.404] INFO: Test took 3891ms.
[12:49:10.438] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:11.165] INFO: Expecting 41600 events.
[12:49:14.401] INFO: 41600 events read in total (2709ms).
[12:49:14.401] INFO: Test took 3945ms.
[12:49:14.433] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:15.162] INFO: Expecting 41600 events.
[12:49:18.373] INFO: 41600 events read in total (2684ms).
[12:49:18.374] INFO: Test took 3923ms.
[12:49:18.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:19.134] INFO: Expecting 41600 events.
[12:49:22.357] INFO: 41600 events read in total (2696ms).
[12:49:22.358] INFO: Test took 3936ms.
[12:49:22.390] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:23.118] INFO: Expecting 41600 events.
[12:49:26.287] INFO: 41600 events read in total (2643ms).
[12:49:26.288] INFO: Test took 3882ms.
[12:49:26.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:27.045] INFO: Expecting 41600 events.
[12:49:30.191] INFO: 41600 events read in total (2619ms).
[12:49:30.192] INFO: Test took 3855ms.
[12:49:30.224] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:30.951] INFO: Expecting 41600 events.
[12:49:34.136] INFO: 41600 events read in total (2659ms).
[12:49:34.137] INFO: Test took 3895ms.
[12:49:34.169] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:34.896] INFO: Expecting 41600 events.
[12:49:38.057] INFO: 41600 events read in total (2634ms).
[12:49:38.058] INFO: Test took 3872ms.
[12:49:38.091] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:38.821] INFO: Expecting 41600 events.
[12:49:41.939] INFO: 41600 events read in total (2592ms).
[12:49:41.940] INFO: Test took 3831ms.
[12:49:41.972] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:42.701] INFO: Expecting 41600 events.
[12:49:45.803] INFO: 41600 events read in total (2575ms).
[12:49:45.804] INFO: Test took 3814ms.
[12:49:45.837] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:46.565] INFO: Expecting 41600 events.
[12:49:49.494] INFO: 41600 events read in total (2403ms).
[12:49:49.495] INFO: Test took 3641ms.
[12:49:49.526] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:49.894] INFO: enter test to run
[12:49:53.028] INFO: test: HighRate no parameter change
[12:49:53.028] INFO: running: highrate
[12:49:53.029] INFO: ----------------------------------------------------------------------
[12:49:53.029] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:49:53.030] INFO: ----------------------------------------------------------------------
[12:49:53.645] INFO: Expecting 208000 events.
[12:50:05.276] INFO: 208000 events read in total (11105ms).
[12:50:05.279] INFO: Test took 12243ms.
[12:50:05.412] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:05.660] INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[12:50:05.660] INFO: number of red-efficiency pixels: 59 50 80 101 114 146 124 96 84 112 85 106 80 60 16 26
[12:50:05.660] INFO: number of X-ray hits detected: 50328 38803 59844 93584 99686 111772 104053 77089 72024 92823 91670 77119 78709 48470 20211 23704
[12:50:05.660] 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:50:05.660] INFO: number of Vcal hits detected: 207941 207950 207918 207896 207884 207800 207871 207903 207915 207886 207913 207892 207920 207940 207984 207974
[12:50:05.660] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0
[12:50:05.660] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 99.9 100.0 99.9 100.0 100.0 100.0 100.0
[12:50:05.660] INFO: X-ray hit rate [MHz/cm2]: 14.8 11.4 17.5 27.4 29.2 32.8 30.5 22.6 21.1 27.2 26.9 22.6 23.1 14.2 5.9 6.9
[12:50:05.660] INFO: PixTestHighRate::doXPixelAlive() done
[12:50:05.710] INFO: PixTest:: pg_setup set to default.
[12:50:05.720] INFO: enter test to run
[12:50:21.708] INFO: test: HighRate no parameter change
[12:50:21.708] INFO: running: highrate
[12:50:21.709] INFO: ----------------------------------------------------------------------
[12:50:21.709] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:50:21.709] INFO: ----------------------------------------------------------------------
[12:50:22.327] INFO: Expecting 208000 events.
[12:50:35.596] INFO: 208000 events read in total (12742ms).
[12:50:35.601] INFO: Test took 13883ms.
[12:50:35.875] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:36.168] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:50:36.168] INFO: number of red-efficiency pixels: 140 118 249 389 330 465 487 344 213 406 336 287 230 154 52 50
[12:50:36.168] INFO: number of X-ray hits detected: 102519 80394 124088 193617 204996 230752 215727 159388 149266 194095 189486 159976 163761 100972 41599 49127
[12:50:36.168] 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:50:36.168] INFO: number of Vcal hits detected: 207855 207878 207737 207595 207654 207443 207477 207629 207779 207572 207646 207700 207761 207841 207947 207950
[12:50:36.168] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.8 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[12:50:36.168] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.7 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[12:50:36.168] INFO: X-ray hit rate [MHz/cm2]: 30.0 23.6 36.4 56.8 60.1 67.6 63.2 46.7 43.8 56.9 55.5 46.9 48.0 29.6 12.2 14.4
[12:50:36.168] INFO: PixTestHighRate::doXPixelAlive() done
[12:50:36.224] INFO: PixTest:: pg_setup set to default.
[12:50:36.243] INFO: enter test to run
[12:50:51.948] INFO: test: HighRate no parameter change
[12:50:51.948] INFO: running: highrate
[12:50:51.949] INFO: ----------------------------------------------------------------------
[12:50:51.949] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:50:51.949] INFO: ----------------------------------------------------------------------
[12:50:52.566] INFO: Expecting 208000 events.
[12:51:06.899] INFO: 208000 events read in total (13806ms).
[12:51:06.906] INFO: Test took 14947ms.
[12:51:07.301] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:51:07.633] INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[12:51:07.633] INFO: number of red-efficiency pixels: 251 214 502 880 670 890 1098 719 381 847 624 572 493 380 81 79
[12:51:07.633] INFO: number of X-ray hits detected: 146399 117264 180428 282253 298052 334865 312294 231576 216399 281332 274737 231849 237570 147088 61368 71484
[12:51:07.633] 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:51:07.633] INFO: number of Vcal hits detected: 207725 207776 207367 206943 207250 206889 206607 207108 207579 206976 207294 207360 207452 207556 207912 207920
[12:51:07.633] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.7 99.5 99.7 99.5 99.4 99.6 99.8 99.6 99.7 99.7 99.8 99.8 100.0 100.0
[12:51:07.633] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.5 99.6 99.5 99.3 99.6 99.8 99.5 99.7 99.7 99.7 99.8 100.0 100.0
[12:51:07.633] INFO: X-ray hit rate [MHz/cm2]: 42.9 34.4 52.9 82.7 87.4 98.2 91.5 67.9 63.4 82.5 80.5 68.0 69.6 43.1 18.0 21.0
[12:51:07.633] INFO: PixTestHighRate::doXPixelAlive() done
[12:51:07.683] INFO: PixTest:: pg_setup set to default.
[12:51:07.698] INFO: enter test to run
[12:51:26.380] INFO: test: exit no parameter change
[12:51:26.746] QUIET: Connection to board 33 closed.
[12:51:26.782] INFO: pXar: this is the end, my friend