[13:58:47.291] INFO: *** Welcome to pxar ***
[13:58:47.291] INFO: *** Today: 2016/05/23
[13:58:47.311] INFO: *** Version: v1.9.0-796-gef167-dirty
[13:58:47.311] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//dacParameters35_C15.dat
[13:58:47.329] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:58:47.329] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//defaultMaskFile.dat
[13:58:47.335] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C15.dat
[13:58:47.457] INFO: clk: 4
[13:58:47.457] INFO: ctr: 4
[13:58:47.457] INFO: sda: 19
[13:58:47.457] INFO: tin: 9
[13:58:47.457] INFO: level: 15
[13:58:47.457] INFO: triggerdelay: 0
[13:58:47.457] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[13:58:47.457] INFO: Log level: INFO
[13:58:47.476] QUIET: Connection to board DTB_WREK4U opened.
[13:58:47.479] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[13:58:47.482] INFO: RPC call hashes of host and DTB match: 398089610
[13:58:49.010] INFO: DUT info:
[13:58:49.010] INFO: The DUT currently contains the following objects:
[13:58:49.010] INFO: 2 TBM Cores tbm08c (2 ON)
[13:58:49.010] INFO: TBM Core alpha (0): 7 registers set
[13:58:49.010] INFO: TBM Core beta (1): 7 registers set
[13:58:49.010] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:58:49.010] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.010] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.010] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.010] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.010] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.010] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.010] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.010] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.011] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.011] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.011] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.011] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.011] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.011] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.011] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.011] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:49.414] INFO: enter 'restricted' command line mode
[13:58:49.414] INFO: enter test to run
[13:58:52.444] INFO: test: PixelAlive no parameter change
[13:58:52.444] INFO: running: pixelalive
[13:58:52.453] INFO: ----------------------------------------------------------------------
[13:58:52.453] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:58:52.453] INFO: ----------------------------------------------------------------------
[13:58:52.768] INFO: Expecting 41600 events.
[13:58:57.076] INFO: 41600 events read in total (3589ms).
[13:58:57.244] INFO: Test took 4788ms.
[13:58:57.256] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:57.522] INFO: PixTestAlive::aliveTest() done
[13:58:57.522] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:58:57.549] INFO: enter test to run
[14:01:09.675] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:01:09.675] INFO: running: highrate
[14:01:09.675] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:01:09.910] INFO: ----------------------------------------------------------------------
[14:01:09.910] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:01:09.910] INFO: ----------------------------------------------------------------------
[14:01:09.910] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:01:09.910] INFO: edge/corner pixel THR is adjusted
[14:01:09.910] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:01:10.868] INFO: Collecting data for 5 seconds...
[14:01:15.884] INFO: Done with hot pixel readout
[14:01:27.039] INFO: PixTest:: pg_setup set to default.
[14:01:27.040] INFO: 2 hot pixels found in step 0
[14:01:28.026] INFO: Collecting data for 5 seconds...
[14:01:33.043] INFO: Done with hot pixel readout
[14:01:44.421] INFO: PixTest:: pg_setup set to default.
[14:01:44.422] INFO: 1 hot pixels found in step 1
[14:01:45.415] INFO: Collecting data for 5 seconds...
[14:01:50.431] INFO: Done with hot pixel readout
[14:02:01.654] INFO: PixTest:: pg_setup set to default.
[14:02:01.655] INFO: 1 hot pixels found in step 2
[14:02:02.645] INFO: Collecting data for 5 seconds...
[14:02:07.662] INFO: Done with hot pixel readout
[14:02:18.707] INFO: PixTest:: pg_setup set to default.
[14:02:18.708] INFO: 0 hot pixels found in step 3
[14:02:18.742] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:02:18.745] INFO: PixTest::trimHotPixels() done
[14:02:18.746] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C0.dat
[14:02:18.758] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C1.dat
[14:02:18.763] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C2.dat
[14:02:18.768] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C3.dat
[14:02:18.774] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C4.dat
[14:02:18.779] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C5.dat
[14:02:18.784] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C6.dat
[14:02:18.789] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C7.dat
[14:02:18.795] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C8.dat
[14:02:18.800] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C9.dat
[14:02:18.805] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C10.dat
[14:02:18.811] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C11.dat
[14:02:18.816] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C12.dat
[14:02:18.821] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C13.dat
[14:02:18.826] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C14.dat
[14:02:18.832] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C15.dat
[14:02:18.837] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//defaultMaskFile.dat
[14:02:18.848] INFO: enter test to run
[14:02:53.242] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:02:53.242] INFO: running: highrate
[14:02:53.246] INFO: ----------------------------------------------------------------------
[14:02:53.246] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:02:53.246] INFO: ----------------------------------------------------------------------
[14:02:53.246] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:02:53.246] INFO: edge/corner pixel THR is adjusted
[14:02:53.246] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:02:54.204] INFO: Collecting data for 1 seconds...
[14:02:55.207] INFO: Done with hot pixel readout
[14:02:58.859] INFO: PixTest:: pg_setup set to default.
[14:02:58.860] INFO: 0 hot pixels found in step 0
[14:02:58.865] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:02:58.959] INFO: PixTest::trimHotPixels() done
[14:02:58.959] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C0.dat
[14:02:58.965] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C1.dat
[14:02:58.972] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C2.dat
[14:02:58.978] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C3.dat
[14:02:58.983] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C4.dat
[14:02:58.989] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C5.dat
[14:02:58.994] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C6.dat
[14:02:58.999] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C7.dat
[14:02:58.005] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C8.dat
[14:02:59.010] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C9.dat
[14:02:59.016] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C10.dat
[14:02:59.021] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C11.dat
[14:02:59.027] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C12.dat
[14:02:59.032] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C13.dat
[14:02:59.038] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C14.dat
[14:02:59.043] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C15.dat
[14:02:59.049] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-4-44_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//defaultMaskFile.dat
[14:02:59.058] INFO: enter test to run
[14:04:52.161] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:04:52.161] INFO: running: xray
[14:04:52.162] INFO: ----------------------------------------------------------------------
[14:04:52.162] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:04:52.162] INFO: ----------------------------------------------------------------------
[14:04:53.125] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:05:04.716] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:05:33.120] INFO: Resuming triggers.
[14:05:44.709] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:06:13.689] INFO: Resuming triggers.
[14:06:25.282] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:06:53.180] INFO: Resuming triggers.
[14:07:04.773] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:07:33.315] INFO: Resuming triggers.
[14:07:44.906] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[14:08:13.850] INFO: Resuming triggers.
[14:08:25.444] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:08:54.053] INFO: Resuming triggers.
[14:09:05.648] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[14:09:34.383] INFO: Resuming triggers.
[14:09:45.977] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[14:10:13.892] INFO: Resuming triggers.
[14:10:21.490] INFO: data taking finished, elapsed time: 100 seconds.
[14:10:40.340] INFO: PixTest:: pg_setup set to default.
[14:10:40.343] INFO: PixTestXray::doPhRun() done
[14:10:40.511] INFO: enter test to run
[14:13:03.341] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:13:03.342] INFO: running: xray
[14:13:03.343] INFO: ----------------------------------------------------------------------
[14:13:03.343] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:13:03.343] INFO: ----------------------------------------------------------------------
[14:13:04.307] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:13:11.238] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:13:40.994] INFO: Resuming triggers.
[14:13:47.927] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[14:14:17.611] INFO: Resuming triggers.
[14:14:24.542] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[14:14:55.016] INFO: Resuming triggers.
[14:15:01.945] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[14:15:31.705] INFO: Resuming triggers.
[14:15:38.638] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:16:08.561] INFO: Resuming triggers.
[14:16:15.492] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[14:16:45.622] INFO: Resuming triggers.
[14:16:52.555] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[14:17:22.328] INFO: Resuming triggers.
[14:17:29.262] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[14:17:58.794] INFO: Resuming triggers.
[14:18:05.728] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[14:18:35.383] INFO: Resuming triggers.
[14:18:42.318] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:19:12.159] INFO: Resuming triggers.
[14:19:19.090] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[14:19:48.745] INFO: Resuming triggers.
[14:19:55.679] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[14:20:25.305] INFO: Resuming triggers.
[14:20:32.236] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[14:21:01.265] INFO: Resuming triggers.
[14:21:08.194] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[14:21:37.877] INFO: Resuming triggers.
[14:21:41.154] INFO: data taking finished, elapsed time: 100 seconds.
[14:21:55.620] INFO: PixTest:: pg_setup set to default.
[14:21:55.624] INFO: PixTestXray::doPhRun() done
[14:21:55.769] INFO: enter test to run
[14:23:13.625] INFO: test: HighRate no parameter change
[14:23:13.625] INFO: running: highrate
[14:23:13.626] INFO: ----------------------------------------------------------------------
[14:23:13.626] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:23:13.626] INFO: ----------------------------------------------------------------------
[14:23:13.783] INFO: Expecting 768 events.
[14:23:14.917] INFO: 768 events read in total (419ms).
[14:23:14.918] INFO: Test took 1284ms.
[14:23:15.721] INFO: Expecting 41600 events.
[14:23:18.812] INFO: 41600 events read in total (2564ms).
[14:23:18.813] INFO: Test took 3872ms.
[14:23:18.846] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:19.573] INFO: Expecting 41600 events.
[14:23:22.751] INFO: 41600 events read in total (2651ms).
[14:23:22.752] INFO: Test took 3888ms.
[14:23:22.785] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:23.513] INFO: Expecting 41600 events.
[14:23:26.724] INFO: 41600 events read in total (2684ms).
[14:23:26.725] INFO: Test took 3924ms.
[14:23:26.758] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:27.485] INFO: Expecting 41600 events.
[14:23:30.669] INFO: 41600 events read in total (2657ms).
[14:23:30.669] INFO: Test took 3894ms.
[14:23:30.703] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:31.426] INFO: Expecting 41600 events.
[14:23:34.665] INFO: 41600 events read in total (2713ms).
[14:23:34.666] INFO: Test took 3945ms.
[14:23:34.700] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:35.422] INFO: Expecting 41600 events.
[14:23:38.662] INFO: 41600 events read in total (2714ms).
[14:23:38.663] INFO: Test took 3945ms.
[14:23:38.697] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:39.419] INFO: Expecting 41600 events.
[14:23:42.633] INFO: 41600 events read in total (2688ms).
[14:23:42.634] INFO: Test took 3918ms.
[14:23:42.667] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:43.397] INFO: Expecting 41600 events.
[14:23:46.594] INFO: 41600 events read in total (2671ms).
[14:23:46.595] INFO: Test took 3911ms.
[14:23:46.628] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:47.352] INFO: Expecting 41600 events.
[14:23:50.582] INFO: 41600 events read in total (2703ms).
[14:23:50.583] INFO: Test took 3936ms.
[14:23:50.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:51.344] INFO: Expecting 41600 events.
[14:23:54.381] INFO: 41600 events read in total (2510ms).
[14:23:54.382] INFO: Test took 3747ms.
[14:23:54.415] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:55.136] INFO: Expecting 41600 events.
[14:23:58.239] INFO: 41600 events read in total (2576ms).
[14:23:58.240] INFO: Test took 3807ms.
[14:23:58.274] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:58.999] INFO: Expecting 41600 events.
[14:24:02.215] INFO: 41600 events read in total (2689ms).
[14:24:02.216] INFO: Test took 3924ms.
[14:24:02.250] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:02.975] INFO: Expecting 41600 events.
[14:24:06.154] INFO: 41600 events read in total (2652ms).
[14:24:06.155] INFO: Test took 3886ms.
[14:24:06.188] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:06.912] INFO: Expecting 41600 events.
[14:24:10.149] INFO: 41600 events read in total (2710ms).
[14:24:10.150] INFO: Test took 3944ms.
[14:24:10.183] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:10.906] INFO: Expecting 41600 events.
[14:24:14.126] INFO: 41600 events read in total (2693ms).
[14:24:14.127] INFO: Test took 3925ms.
[14:24:14.161] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:14.887] INFO: Expecting 41600 events.
[14:24:18.094] INFO: 41600 events read in total (2680ms).
[14:24:18.095] INFO: Test took 3916ms.
[14:24:18.128] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:18.849] INFO: Expecting 41600 events.
[14:24:22.089] INFO: 41600 events read in total (2713ms).
[14:24:22.090] INFO: Test took 3944ms.
[14:24:22.124] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:22.846] INFO: Expecting 41600 events.
[14:24:25.957] INFO: 41600 events read in total (2584ms).
[14:24:25.958] INFO: Test took 3815ms.
[14:24:25.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:26.717] INFO: Expecting 41600 events.
[14:24:29.912] INFO: 41600 events read in total (2668ms).
[14:24:29.913] INFO: Test took 3897ms.
[14:24:29.946] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:30.666] INFO: Expecting 41600 events.
[14:24:33.679] INFO: 41600 events read in total (2486ms).
[14:24:33.680] INFO: Test took 3716ms.
[14:24:33.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:34.074] INFO: enter test to run
[14:24:44.568] INFO: test: HighRate no parameter change
[14:24:44.568] INFO: running: highrate
[14:24:44.569] INFO: ----------------------------------------------------------------------
[14:24:44.569] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:24:44.569] INFO: ----------------------------------------------------------------------
[14:24:45.186] INFO: Expecting 208000 events.
[14:24:56.877] INFO: 208000 events read in total (11164ms).
[14:24:56.880] INFO: Test took 12304ms.
[14:24:57.028] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:57.280] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:24:57.280] INFO: number of red-efficiency pixels: 72 44 81 124 127 136 120 106 92 128 86 110 109 59 17 23
[14:24:57.280] INFO: number of X-ray hits detected: 59662 40987 64958 107346 107717 111412 105836 79853 72781 99965 95699 86355 89103 51387 20741 20344
[14:24:57.280] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:24:57.280] INFO: number of Vcal hits detected: 207927 207907 207915 207873 207868 207858 207877 207893 207907 207868 207913 207887 207891 207940 207983 207977
[14:24:57.280] 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 100.0 99.9 99.9 100.0 100.0 100.0
[14:24:57.280] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 99.9 99.9 100.0 100.0 100.0
[14:24:57.280] INFO: X-ray hit rate [MHz/cm2]: 17.5 12.0 19.0 31.5 31.6 32.7 31.0 23.4 21.3 29.3 28.1 25.3 26.1 15.1 6.1 6.0
[14:24:57.280] INFO: PixTestHighRate::doXPixelAlive() done
[14:24:57.327] INFO: PixTest:: pg_setup set to default.
[14:24:57.341] INFO: enter test to run
[14:25:32.432] INFO: test: HighRate no parameter change
[14:25:32.432] INFO: running: highrate
[14:25:32.433] INFO: ----------------------------------------------------------------------
[14:25:32.433] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:25:32.433] INFO: ----------------------------------------------------------------------
[14:25:33.053] INFO: Expecting 208000 events.
[14:25:46.392] INFO: 208000 events read in total (12812ms).
[14:25:46.397] INFO: Test took 13956ms.
[14:25:46.666] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:46.961] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:25:46.961] INFO: number of red-efficiency pixels: 212 107 196 334 348 363 302 255 258 423 321 303 332 182 49 56
[14:25:46.961] INFO: number of X-ray hits detected: 120037 83023 130869 216375 217176 225483 211846 161835 146221 201678 192391 173246 179661 103622 42140 41630
[14:25:46.961] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:25:46.961] INFO: number of Vcal hits detected: 207778 207839 207791 207650 207644 207610 207688 207732 207736 207545 207657 207682 207649 207811 207950 207944
[14:25:46.961] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[14:25:46.961] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[14:25:46.961] INFO: X-ray hit rate [MHz/cm2]: 35.2 24.3 38.4 63.4 63.7 66.1 62.1 47.4 42.9 59.1 56.4 50.8 52.7 30.4 12.4 12.2
[14:25:46.961] INFO: PixTestHighRate::doXPixelAlive() done
[14:25:47.008] INFO: PixTest:: pg_setup set to default.
[14:25:47.027] INFO: enter test to run
[14:25:59.943] INFO: test: HighRate no parameter change
[14:25:59.943] INFO: running: highrate
[14:25:59.945] INFO: ----------------------------------------------------------------------
[14:25:59.945] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:25:59.945] INFO: ----------------------------------------------------------------------
[14:26:00.561] INFO: Expecting 208000 events.
[14:26:15.118] INFO: 208000 events read in total (14030ms).
[14:26:15.125] INFO: Test took 15172ms.
[14:26:15.545] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:15.888] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:26:15.888] INFO: number of red-efficiency pixels: 370 180 370 716 811 836 761 490 505 948 621 703 653 389 98 82
[14:26:15.888] INFO: number of X-ray hits detected: 177828 122649 194776 322923 322901 335290 316994 241445 219431 300620 286842 258802 268094 155463 63184 62245
[14:26:15.888] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:26:15.888] INFO: number of Vcal hits detected: 207590 207761 207558 207178 207028 207012 207132 207440 207450 206862 207291 207188 207271 207538 207900 207916
[14:26:15.888] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.6 99.6 99.6 99.7 99.8 99.5 99.7 99.6 99.7 99.8 100.0 100.0
[14:26:15.888] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.6 99.5 99.5 99.6 99.7 99.7 99.5 99.7 99.6 99.6 99.8 100.0 100.0
[14:26:15.888] INFO: X-ray hit rate [MHz/cm2]: 52.1 35.9 57.1 94.7 94.6 98.3 92.9 70.8 64.3 88.1 84.1 75.9 78.6 45.6 18.5 18.2
[14:26:15.889] INFO: PixTestHighRate::doXPixelAlive() done
[14:26:15.934] INFO: PixTest:: pg_setup set to default.
[14:26:15.952] INFO: enter test to run
[14:26:29.879] INFO: test: exit no parameter change
[14:26:30.207] QUIET: Connection to board 32 closed.
[14:26:30.208] INFO: pXar: this is the end, my friend