[09:24:54.781] INFO: *** Welcome to pxar ***
[09:24:54.781] INFO: *** Today: 2016/04/22
[09:24:55.211] INFO: *** Version: v1.9.0-796-gef167-dirty
[09:24:55.211] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//dacParameters35_C15.dat
[09:24:55.270] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//tbmParameters_C0b.dat
[09:24:55.270] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//defaultMaskFile.dat
[09:24:55.270] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C15.dat
[09:24:55.388] INFO: clk: 4
[09:24:55.389] INFO: ctr: 4
[09:24:55.389] INFO: sda: 19
[09:24:55.389] INFO: tin: 9
[09:24:55.389] INFO: level: 15
[09:24:55.389] INFO: triggerdelay: 0
[09:24:55.389] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[09:24:55.389] INFO: Log level: INFO
[09:24:55.406] QUIET: Connection to board DTB_WREK4U opened.
[09:24:55.410] 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:
------------------------------------------------------
[09:24:55.413] INFO: RPC call hashes of host and DTB match: 398089610
[09:24:56.959] INFO: DUT info:
[09:24:56.959] INFO: The DUT currently contains the following objects:
[09:24:56.959] INFO: 2 TBM Cores tbm08c (2 ON)
[09:24:56.963] INFO: TBM Core alpha (0): 7 registers set
[09:24:56.963] INFO: TBM Core beta (1): 7 registers set
[09:24:56.963] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:24:56.963] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.963] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.963] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.963] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.963] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.963] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.963] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.963] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.963] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.964] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.964] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.964] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.964] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.964] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.964] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:56.964] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:24:57.434] INFO: enter 'restricted' command line mode
[09:24:57.434] INFO: enter test to run
[09:25:13.657] INFO: test: PixelAlive no parameter change
[09:25:13.657] INFO: running: pixelalive
[09:25:13.735] INFO: ----------------------------------------------------------------------
[09:25:13.735] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:25:13.735] INFO: ----------------------------------------------------------------------
[09:25:14.052] INFO: Expecting 41600 events.
[09:25:18.403] INFO: 41600 events read in total (3632ms).
[09:25:18.571] INFO: Test took 4832ms.
[09:25:18.583] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:19.079] INFO: PixTestAlive::aliveTest() done
[09:25:19.079] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:25:19.308] INFO: enter test to run
[09:25:43.169] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[09:25:43.169] INFO: running: highrate
[09:25:43.169] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[09:25:43.433] INFO: ----------------------------------------------------------------------
[09:25:43.434] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[09:25:43.434] INFO: ----------------------------------------------------------------------
[09:25:43.434] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[09:25:43.434] INFO: edge/corner pixel THR is adjusted
[09:25:43.434] INFO: PixTestHighRate::trimHotPixels: step 0...
[09:25:44.392] INFO: Collecting data for 5 seconds...
[09:25:49.409] INFO: Done with hot pixel readout
[09:26:00.983] INFO: PixTest:: pg_setup set to default.
[09:26:00.984] INFO: 0 hot pixels found in step 0
[09:26:00.996] INFO: 0 hot pixels could not be trimmed and have been masked.
[09:26:01.014] INFO: PixTest::trimHotPixels() done
[09:26:01.059] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C0.dat
[09:26:01.071] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C1.dat
[09:26:01.078] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C2.dat
[09:26:01.084] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C3.dat
[09:26:01.089] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C4.dat
[09:26:01.095] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C5.dat
[09:26:01.100] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C6.dat
[09:26:01.106] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C7.dat
[09:26:01.111] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C8.dat
[09:26:01.117] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C9.dat
[09:26:01.123] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C10.dat
[09:26:01.128] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C11.dat
[09:26:01.134] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C12.dat
[09:26:01.139] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C13.dat
[09:26:01.145] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C14.dat
[09:26:01.150] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C15.dat
[09:26:01.156] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//defaultMaskFile.dat
[09:26:01.165] INFO: enter test to run
[09:26:24.649] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[09:26:24.649] INFO: running: highrate
[09:26:24.653] INFO: ----------------------------------------------------------------------
[09:26:24.653] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[09:26:24.653] INFO: ----------------------------------------------------------------------
[09:26:24.653] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[09:26:24.653] INFO: edge/corner pixel THR is adjusted
[09:26:24.653] INFO: PixTestHighRate::trimHotPixels: step 0...
[09:26:25.610] INFO: Collecting data for 1 seconds...
[09:26:26.612] INFO: Done with hot pixel readout
[09:26:29.631] INFO: PixTest:: pg_setup set to default.
[09:26:29.632] INFO: 0 hot pixels found in step 0
[09:26:29.637] INFO: 0 hot pixels could not be trimmed and have been masked.
[09:26:29.737] INFO: PixTest::trimHotPixels() done
[09:26:29.737] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C0.dat
[09:26:29.747] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C1.dat
[09:26:29.756] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C2.dat
[09:26:29.761] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C3.dat
[09:26:29.768] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C4.dat
[09:26:29.774] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C5.dat
[09:26:29.780] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C6.dat
[09:26:29.785] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C7.dat
[09:26:29.791] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C8.dat
[09:26:29.797] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C9.dat
[09:26:29.803] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C10.dat
[09:26:29.809] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C11.dat
[09:26:29.815] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C12.dat
[09:26:29.821] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C13.dat
[09:26:29.826] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C14.dat
[09:26:29.832] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//trimParameters35_C15.dat
[09:26:29.838] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-4-42_FPIXTest-17C-FNAL-160418-0837_2016-04-18_08h37m_1460986635/000_FPIXTest_p17//defaultMaskFile.dat
[09:26:29.847] INFO: enter test to run
[09:26:47.144] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[09:26:47.144] INFO: running: xray
[09:26:47.145] INFO: ----------------------------------------------------------------------
[09:26:47.145] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:26:47.145] INFO: ----------------------------------------------------------------------
[09:26:48.125] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:26:59.640] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[09:27:28.922] INFO: Resuming triggers.
[09:27:40.437] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[09:28:09.989] INFO: Resuming triggers.
[09:28:21.500] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[09:28:50.842] INFO: Resuming triggers.
[09:29:02.356] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[09:29:31.716] INFO: Resuming triggers.
[09:29:43.233] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[09:30:11.546] INFO: Resuming triggers.
[09:30:23.059] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[09:30:52.335] INFO: Resuming triggers.
[09:31:03.854] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[09:31:33.184] INFO: Resuming triggers.
[09:31:44.704] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[09:32:14.164] INFO: Resuming triggers.
[09:32:22.370] INFO: data taking finished, elapsed time: 100 seconds.
[09:32:43.443] INFO: PixTest:: pg_setup set to default.
[09:32:43.446] INFO: PixTestXray::doPhRun() done
[09:32:43.580] INFO: enter test to run
[09:33:46.061] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[09:33:46.061] INFO: running: xray
[09:33:46.062] INFO: ----------------------------------------------------------------------
[09:33:46.062] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:33:46.062] INFO: ----------------------------------------------------------------------
[09:33:47.027] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:33:53.885] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[09:34:23.906] INFO: Resuming triggers.
[09:34:30.764] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[09:35:00.773] INFO: Resuming triggers.
[09:35:07.631] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[09:35:37.636] INFO: Resuming triggers.
[09:35:44.493] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[09:36:14.556] INFO: Resuming triggers.
[09:36:21.414] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[09:36:48.526] INFO: Resuming triggers.
[09:36:55.380] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[09:37:25.439] INFO: Resuming triggers.
[09:37:32.295] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[09:38:02.360] INFO: Resuming triggers.
[09:38:09.215] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[09:38:39.271] INFO: Resuming triggers.
[09:38:46.125] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[09:39:16.138] INFO: Resuming triggers.
[09:39:22.992] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[09:39:53.044] INFO: Resuming triggers.
[09:39:59.899] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[09:40:29.901] INFO: Resuming triggers.
[09:40:36.756] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[09:41:06.799] INFO: Resuming triggers.
[09:41:13.654] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[09:41:43.703] INFO: Resuming triggers.
[09:41:50.561] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[09:42:20.592] INFO: Resuming triggers.
[09:42:24.940] INFO: data taking finished, elapsed time: 100 seconds.
[09:42:44.152] INFO: PixTest:: pg_setup set to default.
[09:42:44.156] INFO: PixTestXray::doPhRun() done
[09:42:44.302] INFO: enter test to run
[09:43:01.161] INFO: test: HighRate no parameter change
[09:43:01.161] INFO: running: highrate
[09:43:01.162] INFO: ----------------------------------------------------------------------
[09:43:01.162] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:43:01.163] INFO: ----------------------------------------------------------------------
[09:43:01.303] INFO: Expecting 768 events.
[09:43:02.437] INFO: 768 events read in total (419ms).
[09:43:02.437] INFO: Test took 1268ms.
[09:43:03.240] INFO: Expecting 41600 events.
[09:43:06.345] INFO: 41600 events read in total (2579ms).
[09:43:06.347] INFO: Test took 3902ms.
[09:43:06.379] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:07.105] INFO: Expecting 41600 events.
[09:43:10.298] INFO: 41600 events read in total (2666ms).
[09:43:10.299] INFO: Test took 3903ms.
[09:43:10.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:11.060] INFO: Expecting 41600 events.
[09:43:14.261] INFO: 41600 events read in total (2674ms).
[09:43:14.262] INFO: Test took 3913ms.
[09:43:14.296] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:15.023] INFO: Expecting 41600 events.
[09:43:18.234] INFO: 41600 events read in total (2684ms).
[09:43:18.235] INFO: Test took 3921ms.
[09:43:18.267] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:18.997] INFO: Expecting 41600 events.
[09:43:22.215] INFO: 41600 events read in total (2691ms).
[09:43:22.216] INFO: Test took 3930ms.
[09:43:22.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:22.976] INFO: Expecting 41600 events.
[09:43:26.196] INFO: 41600 events read in total (2693ms).
[09:43:26.197] INFO: Test took 3930ms.
[09:43:26.230] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:26.953] INFO: Expecting 41600 events.
[09:43:30.171] INFO: 41600 events read in total (2691ms).
[09:43:30.172] INFO: Test took 3925ms.
[09:43:30.205] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:30.934] INFO: Expecting 41600 events.
[09:43:34.165] INFO: 41600 events read in total (2704ms).
[09:43:34.166] INFO: Test took 3942ms.
[09:43:34.199] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:34.923] INFO: Expecting 41600 events.
[09:43:38.151] INFO: 41600 events read in total (2701ms).
[09:43:38.152] INFO: Test took 3936ms.
[09:43:38.185] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:38.912] INFO: Expecting 41600 events.
[09:43:42.146] INFO: 41600 events read in total (2708ms).
[09:43:42.147] INFO: Test took 3944ms.
[09:43:42.180] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:42.906] INFO: Expecting 41600 events.
[09:43:46.120] INFO: 41600 events read in total (2688ms).
[09:43:46.121] INFO: Test took 3923ms.
[09:43:46.154] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:46.883] INFO: Expecting 41600 events.
[09:43:50.113] INFO: 41600 events read in total (2704ms).
[09:43:50.114] INFO: Test took 3943ms.
[09:43:50.148] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:50.870] INFO: Expecting 41600 events.
[09:43:54.076] INFO: 41600 events read in total (2679ms).
[09:43:54.077] INFO: Test took 3912ms.
[09:43:54.111] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:54.835] INFO: Expecting 41600 events.
[09:43:58.022] INFO: 41600 events read in total (2660ms).
[09:43:58.023] INFO: Test took 3895ms.
[09:43:58.056] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:58.780] INFO: Expecting 41600 events.
[09:44:02.017] INFO: 41600 events read in total (2710ms).
[09:44:02.018] INFO: Test took 3943ms.
[09:44:02.050] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:02.773] INFO: Expecting 41600 events.
[09:44:06.014] INFO: 41600 events read in total (2714ms).
[09:44:06.014] INFO: Test took 3947ms.
[09:44:06.047] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:06.772] INFO: Expecting 41600 events.
[09:44:10.008] INFO: 41600 events read in total (2709ms).
[09:44:10.009] INFO: Test took 3944ms.
[09:44:10.041] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:10.765] INFO: Expecting 41600 events.
[09:44:13.995] INFO: 41600 events read in total (2703ms).
[09:44:13.996] INFO: Test took 3938ms.
[09:44:14.029] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:14.756] INFO: Expecting 41600 events.
[09:44:17.954] INFO: 41600 events read in total (2671ms).
[09:44:17.955] INFO: Test took 3907ms.
[09:44:17.988] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:18.715] INFO: Expecting 41600 events.
[09:44:21.731] INFO: 41600 events read in total (2490ms).
[09:44:21.732] INFO: Test took 3725ms.
[09:44:21.764] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:22.131] INFO: enter test to run
[09:44:24.409] INFO: test: HighRate no parameter change
[09:44:24.409] INFO: running: highrate
[09:44:24.410] INFO: ----------------------------------------------------------------------
[09:44:24.410] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:44:24.410] INFO: ----------------------------------------------------------------------
[09:44:25.020] INFO: Expecting 208000 events.
[09:44:36.793] INFO: 208000 events read in total (11246ms).
[09:44:36.796] INFO: Test took 12381ms.
[09:44:36.938] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:37.189] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:44:37.189] INFO: number of red-efficiency pixels: 52 52 93 123 140 127 161 187 110 114 127 100 88 65 30 22
[09:44:37.189] INFO: number of X-ray hits detected: 50770 39243 64230 104854 109703 111491 110989 83945 78080 99010 92937 80806 78166 49031 20869 21825
[09:44:37.189] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:44:37.189] INFO: number of Vcal hits detected: 207947 207898 207906 207876 207859 207871 207835 207798 207890 207885 207871 207898 207912 207935 207970 207977
[09:44:37.189] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[09:44:37.190] 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 100.0 100.0 100.0 100.0 100.0
[09:44:37.190] INFO: X-ray hit rate [MHz/cm2]: 14.9 11.5 18.8 30.7 32.2 32.7 32.5 24.6 22.9 29.0 27.2 23.7 22.9 14.4 6.1 6.4
[09:44:37.190] INFO: PixTestHighRate::doXPixelAlive() done
[09:44:37.234] INFO: PixTest:: pg_setup set to default.
[09:44:37.244] INFO: enter test to run
[09:44:59.240] INFO: test: HighRate no parameter change
[09:44:59.240] INFO: running: highrate
[09:44:59.242] INFO: ----------------------------------------------------------------------
[09:44:59.242] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:44:59.242] INFO: ----------------------------------------------------------------------
[09:44:59.856] INFO: Expecting 208000 events.
[09:45:13.294] INFO: 208000 events read in total (12911ms).
[09:45:13.299] INFO: Test took 14049ms.
[09:45:13.577] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:45:13.872] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:45:13.872] INFO: number of red-efficiency pixels: 147 117 199 331 412 484 508 449 298 306 388 318 275 151 50 52
[09:45:13.872] INFO: number of X-ray hits detected: 104900 81066 131343 215358 224308 228113 229487 173304 160514 203420 192054 167161 160323 102196 43053 44839
[09:45:13.872] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:45:13.872] INFO: number of Vcal hits detected: 207843 207833 207792 207649 207559 207472 207449 207472 207684 207677 207592 207660 207712 207838 207948 207947
[09:45:13.872] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[09:45:13.872] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.7 99.7 99.8 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[09:45:13.872] INFO: X-ray hit rate [MHz/cm2]: 30.7 23.8 38.5 63.1 65.7 66.9 67.3 50.8 47.0 59.6 56.3 49.0 47.0 30.0 12.6 13.1
[09:45:13.872] INFO: PixTestHighRate::doXPixelAlive() done
[09:45:13.923] INFO: PixTest:: pg_setup set to default.
[09:45:13.940] INFO: enter test to run
[09:45:27.352] INFO: test: HighRate no parameter change
[09:45:27.352] INFO: running: highrate
[09:45:27.354] INFO: ----------------------------------------------------------------------
[09:45:27.354] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:45:27.354] INFO: ----------------------------------------------------------------------
[09:45:27.969] INFO: Expecting 208000 events.
[09:45:43.177] INFO: 208000 events read in total (14681ms).
[09:45:43.184] INFO: Test took 15820ms.
[09:45:43.607] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:45:43.946] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:45:43.946] INFO: number of red-efficiency pixels: 304 163 456 741 939 1039 1187 895 599 610 869 658 566 353 87 81
[09:45:43.946] INFO: number of X-ray hits detected: 158471 121688 198975 324546 337022 344566 344378 260503 243970 307183 289568 250308 241548 153168 65664 67758
[09:45:43.946] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:45:43.946] INFO: number of Vcal hits detected: 207673 207779 207449 207154 206848 206721 206507 206791 207332 207312 206996 207237 207363 207602 207911 207919
[09:45:43.946] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.5 99.4 99.4 99.4 99.7 99.7 99.5 99.7 99.7 99.8 100.0 100.0
[09:45:43.946] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.4 99.4 99.3 99.4 99.7 99.7 99.5 99.6 99.7 99.8 100.0 100.0
[09:45:43.946] INFO: X-ray hit rate [MHz/cm2]: 46.4 35.7 58.3 95.1 98.8 101.0 100.9 76.4 71.5 90.0 84.9 73.4 70.8 44.9 19.2 19.9
[09:45:43.946] INFO: PixTestHighRate::doXPixelAlive() done
[09:45:43.997] INFO: PixTest:: pg_setup set to default.
[09:45:44.013] INFO: enter test to run
[09:45:50.960] INFO: test: exit no parameter change
[09:45:51.328] QUIET: Connection to board 32 closed.
[09:45:51.330] INFO: pXar: this is the end, my friend