[10:16:00.734] INFO: *** Welcome to pxar ***
[10:16:00.734] INFO: *** Today: 2016/04/06
[10:16:00.755] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:16:00.755] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//dacParameters35_C15.dat
[10:16:00.778] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:16:00.778] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//defaultMaskFile.dat
[10:16:00.778] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C15.dat
[10:16:00.861] INFO: clk: 4
[10:16:00.861] INFO: ctr: 4
[10:16:00.861] INFO: sda: 19
[10:16:00.861] INFO: tin: 9
[10:16:00.861] INFO: level: 15
[10:16:00.861] INFO: triggerdelay: 0
[10:16:00.861] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:16:00.861] INFO: Log level: INFO
[10:16:00.879] QUIET: Connection to board DTB_WREKRL opened.
[10:16:00.883] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[10:16:00.886] INFO: RPC call hashes of host and DTB match: 398089610
[10:16:02.418] INFO: DUT info:
[10:16:02.418] INFO: The DUT currently contains the following objects:
[10:16:02.418] INFO: 2 TBM Cores tbm08c (2 ON)
[10:16:02.418] INFO: TBM Core alpha (0): 7 registers set
[10:16:02.418] INFO: TBM Core beta (1): 7 registers set
[10:16:02.418] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:16:02.418] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.418] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.418] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.418] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.418] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.418] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.418] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.418] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.418] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.418] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.418] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.418] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.418] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.419] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.419] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.419] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:02.822] INFO: enter 'restricted' command line mode
[10:16:02.822] INFO: enter test to run
[10:16:10.102] INFO: test: PixelAlive no parameter change
[10:16:10.102] INFO: running: pixelalive
[10:16:10.111] INFO: ----------------------------------------------------------------------
[10:16:10.111] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:16:10.111] INFO: ----------------------------------------------------------------------
[10:16:10.427] INFO: Expecting 41600 events.
[10:16:14.729] INFO: 41600 events read in total (3583ms).
[10:16:14.897] INFO: Test took 4782ms.
[10:16:14.911] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:16:15.172] INFO: PixTestAlive::aliveTest() done
[10:16:15.172] INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 0 0 0 0 0 0 0 1 1
[10:16:15.206] INFO: enter test to run
[10:16:48.765] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:16:48.765] INFO: running: highrate
[10:16:48.766] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:16:48.980] INFO: ----------------------------------------------------------------------
[10:16:48.980] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:16:48.980] INFO: ----------------------------------------------------------------------
[10:16:48.980] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:16:48.980] INFO: edge/corner pixel THR is adjusted
[10:16:48.980] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:16:49.939] INFO: Collecting data for 5 seconds...
[10:16:54.955] INFO: Done with hot pixel readout
[10:17:06.775] INFO: PixTest:: pg_setup set to default.
[10:17:06.777] INFO: 1 hot pixels found in step 0
[10:17:07.767] INFO: Collecting data for 5 seconds...
[10:17:12.783] INFO: Done with hot pixel readout
[10:17:24.589] INFO: PixTest:: pg_setup set to default.
[10:17:24.590] INFO: 2 hot pixels found in step 1
[10:17:25.586] INFO: Collecting data for 5 seconds...
[10:17:30.603] INFO: Done with hot pixel readout
[10:17:42.436] INFO: PixTest:: pg_setup set to default.
[10:17:42.436] INFO: 0 hot pixels found in step 2
[10:17:42.473] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:17:42.476] INFO: PixTest::trimHotPixels() done
[10:17:42.476] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C0.dat
[10:17:42.484] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C1.dat
[10:17:42.491] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C2.dat
[10:17:42.498] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C3.dat
[10:17:42.505] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C4.dat
[10:17:42.512] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C5.dat
[10:17:42.519] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C6.dat
[10:17:42.526] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C7.dat
[10:17:42.534] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C8.dat
[10:17:42.541] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C9.dat
[10:17:42.548] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C10.dat
[10:17:42.555] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C11.dat
[10:17:42.562] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C12.dat
[10:17:42.569] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C13.dat
[10:17:42.576] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C14.dat
[10:17:42.583] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C15.dat
[10:17:42.590] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//defaultMaskFile.dat
[10:17:42.601] INFO: enter test to run
[10:19:27.164] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:19:27.164] INFO: running: highrate
[10:19:27.169] INFO: ----------------------------------------------------------------------
[10:19:27.169] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:19:27.169] INFO: ----------------------------------------------------------------------
[10:19:27.169] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:19:27.169] INFO: edge/corner pixel THR is adjusted
[10:19:27.169] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:19:28.126] INFO: Collecting data for 1 seconds...
[10:19:29.130] INFO: Done with hot pixel readout
[10:19:33.116] INFO: PixTest:: pg_setup set to default.
[10:19:33.117] INFO: 0 hot pixels found in step 0
[10:19:33.122] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:19:33.215] INFO: PixTest::trimHotPixels() done
[10:19:33.215] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C0.dat
[10:19:33.225] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C1.dat
[10:19:33.232] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C2.dat
[10:19:33.239] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C3.dat
[10:19:33.246] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C4.dat
[10:19:33.253] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C5.dat
[10:19:33.260] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C6.dat
[10:19:33.267] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C7.dat
[10:19:33.274] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C8.dat
[10:19:33.281] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C9.dat
[10:19:33.288] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C10.dat
[10:19:33.295] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C11.dat
[10:19:33.302] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C12.dat
[10:19:33.309] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C13.dat
[10:19:33.316] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C14.dat
[10:19:33.323] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//trimParameters35_C15.dat
[10:19:33.330] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-H-2-04_FPIXTest-17C-FNAL-160125-0956_2016-01-25_09h57m_1453737426/000_FPIXTest_p17//defaultMaskFile.dat
[10:19:33.340] INFO: enter test to run
[10:20:52.020] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:20:52.020] INFO: running: xray
[10:20:52.021] INFO: ----------------------------------------------------------------------
[10:20:52.021] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:20:52.021] INFO: ----------------------------------------------------------------------
[10:20:52.985] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:21:04.314] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:21:34.066] INFO: Resuming triggers.
[10:21:45.397] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[10:22:15.255] INFO: Resuming triggers.
[10:22:26.587] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:22:56.437] INFO: Resuming triggers.
[10:23:07.768] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:23:34.627] INFO: Resuming triggers.
[10:23:45.960] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[10:24:15.739] INFO: Resuming triggers.
[10:24:27.073] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[10:24:56.840] INFO: Resuming triggers.
[10:25:08.170] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[10:25:38.034] INFO: Resuming triggers.
[10:25:49.366] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[10:26:19.215] INFO: Resuming triggers.
[10:26:28.883] INFO: data taking finished, elapsed time: 100 seconds.
[10:26:54.335] INFO: PixTest:: pg_setup set to default.
[10:26:54.338] INFO: PixTestXray::doPhRun() done
[10:26:54.475] INFO: enter test to run
[10:27:17.001] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:27:17.001] INFO: running: xray
[10:27:17.002] INFO: ----------------------------------------------------------------------
[10:27:17.002] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:27:17.002] INFO: ----------------------------------------------------------------------
[10:27:18.966] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:27:25.554] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:27:55.989] INFO: Resuming triggers.
[10:28:02.578] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:28:33.021] INFO: Resuming triggers.
[10:28:39.610] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[10:29:10.080] INFO: Resuming triggers.
[10:29:16.669] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[10:29:47.078] INFO: Resuming triggers.
[10:29:53.670] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[10:30:24.020] INFO: Resuming triggers.
[10:30:30.607] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[10:31:01.118] INFO: Resuming triggers.
[10:31:07.708] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:31:38.155] INFO: Resuming triggers.
[10:31:44.744] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[10:32:15.134] INFO: Resuming triggers.
[10:32:21.726] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[10:32:52.162] INFO: Resuming triggers.
[10:32:58.753] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[10:33:25.818] INFO: Resuming triggers.
[10:33:32.409] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[10:34:02.828] INFO: Resuming triggers.
[10:34:09.420] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[10:34:39.805] INFO: Resuming triggers.
[10:34:46.399] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[10:35:16.793] INFO: Resuming triggers.
[10:35:23.388] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[10:35:53.781] INFO: Resuming triggers.
[10:36:00.378] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[10:36:30.826] INFO: Resuming triggers.
[10:36:32.285] INFO: data taking finished, elapsed time: 100 seconds.
[10:36:39.327] INFO: PixTest:: pg_setup set to default.
[10:36:39.331] INFO: PixTestXray::doPhRun() done
[10:36:39.480] INFO: enter test to run
[10:37:56.380] INFO: test: HighRate no parameter change
[10:37:56.380] INFO: running: highrate
[10:37:56.381] INFO: ----------------------------------------------------------------------
[10:37:56.381] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:37:56.381] INFO: ----------------------------------------------------------------------
[10:37:56.521] INFO: Expecting 768 events.
[10:37:57.656] INFO: 768 events read in total (419ms).
[10:37:57.656] INFO: Test took 1269ms.
[10:37:58.459] INFO: Expecting 41600 events.
[10:38:01.529] INFO: 41600 events read in total (2543ms).
[10:38:01.530] INFO: Test took 3866ms.
[10:38:01.564] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:02.286] INFO: Expecting 41600 events.
[10:38:05.407] INFO: 41600 events read in total (2594ms).
[10:38:05.408] INFO: Test took 3825ms.
[10:38:05.443] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:06.166] INFO: Expecting 41600 events.
[10:38:09.420] INFO: 41600 events read in total (2727ms).
[10:38:09.421] INFO: Test took 3960ms.
[10:38:09.455] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:10.181] INFO: Expecting 41600 events.
[10:38:13.424] INFO: 41600 events read in total (2716ms).
[10:38:13.425] INFO: Test took 3951ms.
[10:38:13.460] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:14.184] INFO: Expecting 41600 events.
[10:38:17.421] INFO: 41600 events read in total (2710ms).
[10:38:17.422] INFO: Test took 3943ms.
[10:38:17.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:18.180] INFO: Expecting 41600 events.
[10:38:21.437] INFO: 41600 events read in total (2730ms).
[10:38:21.438] INFO: Test took 3962ms.
[10:38:21.474] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:22.197] INFO: Expecting 41600 events.
[10:38:25.444] INFO: 41600 events read in total (2720ms).
[10:38:25.445] INFO: Test took 3952ms.
[10:38:25.480] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:26.202] INFO: Expecting 41600 events.
[10:38:29.455] INFO: 41600 events read in total (2726ms).
[10:38:29.456] INFO: Test took 3958ms.
[10:38:29.491] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:30.215] INFO: Expecting 41600 events.
[10:38:33.424] INFO: 41600 events read in total (2682ms).
[10:38:33.425] INFO: Test took 3915ms.
[10:38:33.461] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:34.182] INFO: Expecting 41600 events.
[10:38:37.421] INFO: 41600 events read in total (2712ms).
[10:38:37.423] INFO: Test took 3944ms.
[10:38:37.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:38.179] INFO: Expecting 41600 events.
[10:38:41.429] INFO: 41600 events read in total (2723ms).
[10:38:41.430] INFO: Test took 3953ms.
[10:38:41.465] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:42.186] INFO: Expecting 41600 events.
[10:38:45.366] INFO: 41600 events read in total (2653ms).
[10:38:45.367] INFO: Test took 3883ms.
[10:38:45.403] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:46.126] INFO: Expecting 41600 events.
[10:38:49.302] INFO: 41600 events read in total (2650ms).
[10:38:49.303] INFO: Test took 3882ms.
[10:38:49.338] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:50.059] INFO: Expecting 41600 events.
[10:38:53.212] INFO: 41600 events read in total (2626ms).
[10:38:53.213] INFO: Test took 3855ms.
[10:38:53.248] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:53.969] INFO: Expecting 41600 events.
[10:38:57.159] INFO: 41600 events read in total (2663ms).
[10:38:57.160] INFO: Test took 3894ms.
[10:38:57.196] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:57.914] INFO: Expecting 41600 events.
[10:39:01.147] INFO: 41600 events read in total (2706ms).
[10:39:01.148] INFO: Test took 3931ms.
[10:39:01.183] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:01.907] INFO: Expecting 41600 events.
[10:39:05.079] INFO: 41600 events read in total (2645ms).
[10:39:05.080] INFO: Test took 3879ms.
[10:39:05.117] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:05.835] INFO: Expecting 41600 events.
[10:39:09.063] INFO: 41600 events read in total (2701ms).
[10:39:09.064] INFO: Test took 3929ms.
[10:39:09.099] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:09.818] INFO: Expecting 41600 events.
[10:39:13.060] INFO: 41600 events read in total (2715ms).
[10:39:13.061] INFO: Test took 3943ms.
[10:39:13.096] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:13.817] INFO: Expecting 41600 events.
[10:39:16.921] INFO: 41600 events read in total (2577ms).
[10:39:16.922] INFO: Test took 3807ms.
[10:39:16.957] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:17.317] INFO: enter test to run
[10:39:32.979] INFO: test: HighRate no parameter change
[10:39:32.979] INFO: running: highrate
[10:39:32.980] INFO: ----------------------------------------------------------------------
[10:39:32.980] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:39:32.980] INFO: ----------------------------------------------------------------------
[10:39:33.602] INFO: Expecting 208000 events.
[10:39:45.309] INFO: 208000 events read in total (11180ms).
[10:39:45.312] INFO: Test took 12324ms.
[10:39:45.463] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:45.717] INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 0 0 0 0 0 0 0 1 1
[10:39:45.717] INFO: number of red-efficiency pixels: 97 53 96 118 136 157 136 98 112 120 116 112 117 64 27 32
[10:39:45.717] INFO: number of X-ray hits detected: 69664 43194 67612 111807 114504 120870 116893 86805 90208 103826 99626 88062 90712 53030 23203 27676
[10:39:45.717] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:39:45.717] INFO: number of Vcal hits detected: 207802 207947 207902 207879 207860 207840 207861 207899 207887 207876 207881 207886 207880 207936 207924 207919
[10:39:45.717] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[10:39:45.717] INFO: Vcal hit overall efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[10:39:45.717] INFO: X-ray hit rate [MHz/cm2]: 20.4 12.7 19.8 32.8 33.6 35.4 34.3 25.4 26.4 30.4 29.2 25.8 26.6 15.5 6.8 8.1
[10:39:45.717] INFO: PixTestHighRate::doXPixelAlive() done
[10:39:45.762] INFO: PixTest:: pg_setup set to default.
[10:39:45.775] INFO: enter test to run
[10:40:33.971] INFO: test: HighRate no parameter change
[10:40:33.971] INFO: running: highrate
[10:40:33.972] INFO: ----------------------------------------------------------------------
[10:40:33.972] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:40:33.972] INFO: ----------------------------------------------------------------------
[10:40:34.590] INFO: Expecting 208000 events.
[10:40:48.221] INFO: 208000 events read in total (13104ms).
[10:40:48.227] INFO: Test took 14245ms.
[10:40:48.533] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:48.836] INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 0 0 0 0 0 0 0 1 1
[10:40:48.836] INFO: number of red-efficiency pixels: 301 110 271 392 407 439 392 298 343 461 407 333 310 187 65 78
[10:40:48.836] INFO: number of X-ray hits detected: 142300 88294 137848 225947 232099 244665 237209 177479 183707 210990 202463 178473 185114 108163 47183 55807
[10:40:48.836] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:40:48.836] INFO: number of Vcal hits detected: 207578 207886 207699 207579 207554 207534 207570 207680 207628 207504 207564 207642 207670 207798 207882 207870
[10:40:48.836] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[10:40:48.836] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[10:40:48.836] INFO: X-ray hit rate [MHz/cm2]: 41.7 25.9 40.4 66.2 68.0 71.7 69.5 52.0 53.8 61.8 59.3 52.3 54.3 31.7 13.8 16.4
[10:40:48.836] INFO: PixTestHighRate::doXPixelAlive() done
[10:40:48.881] INFO: PixTest:: pg_setup set to default.
[10:40:48.898] INFO: enter test to run
[10:41:07.131] INFO: test: HighRate no parameter change
[10:41:07.131] INFO: running: highrate
[10:41:07.132] INFO: ----------------------------------------------------------------------
[10:41:07.132] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:41:07.132] INFO: ----------------------------------------------------------------------
[10:41:07.751] INFO: Expecting 208000 events.
[10:41:23.084] INFO: 208000 events read in total (14806ms).
[10:41:23.091] INFO: Test took 15950ms.
[10:41:23.545] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:23.900] INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 0 0 0 0 0 0 0 1 1
[10:41:23.900] INFO: number of red-efficiency pixels: 535 234 647 886 909 1027 905 628 783 999 925 786 589 383 125 81
[10:41:23.900] INFO: number of X-ray hits detected: 211276 131605 206493 337362 346863 365956 353107 264688 275115 313479 304284 267148 276662 161485 71371 84497
[10:41:23.900] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:41:23.900] INFO: number of Vcal hits detected: 207272 207736 207187 206919 206879 206748 206905 207230 207060 206753 206869 207069 207345 207569 207818 207870
[10:41:23.900] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.7 99.5 99.5 99.5 99.5 99.7 99.6 99.5 99.5 99.6 99.7 99.8 99.9 100.0
[10:41:23.900] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.6 99.5 99.5 99.4 99.5 99.6 99.5 99.4 99.5 99.6 99.7 99.8 99.9 99.9
[10:41:23.900] INFO: X-ray hit rate [MHz/cm2]: 61.9 38.6 60.5 98.9 101.7 107.3 103.5 77.6 80.6 91.9 89.2 78.3 81.1 47.3 20.9 24.8
[10:41:23.900] INFO: PixTestHighRate::doXPixelAlive() done
[10:41:23.948] INFO: PixTest:: pg_setup set to default.
[10:41:23.963] INFO: enter test to run
[10:41:43.730] INFO: test: exit no parameter change
[10:41:44.069] QUIET: Connection to board 33 closed.
[10:41:44.070] INFO: pXar: this is the end, my friend