[10:13:53.813] INFO: *** Welcome to pxar ***
[10:13:53.813] INFO: *** Today: 2016/09/16
[10:13:53.872] INFO: *** Version: v1.9.0-818-g96727
[10:13:53.872] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//dacParameters35_C15.dat
[10:13:53.919] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:13:53.919] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//defaultMaskFile.dat
[10:13:53.921] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C15.dat
[10:13:54.007] INFO: clk: 4
[10:13:54.007] INFO: ctr: 4
[10:13:54.007] INFO: sda: 19
[10:13:54.007] INFO: tin: 9
[10:13:54.007] INFO: level: 15
[10:13:54.007] INFO: triggerdelay: 0
[10:13:54.007] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:13:54.007] INFO: Log level: INFO
[10:13:54.026] QUIET: Connection to board DTB_WREKRL opened.
[10:13:54.029] 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:13:54.032] INFO: RPC call hashes of host and DTB match: 398089610
[10:13:55.559] INFO: DUT info:
[10:13:55.559] INFO: The DUT currently contains the following objects:
[10:13:55.559] INFO: 2 TBM Cores tbm08c (2 ON)
[10:13:55.559] INFO: TBM Core alpha (0): 7 registers set
[10:13:55.559] INFO: TBM Core beta (1): 7 registers set
[10:13:55.559] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:13:55.559] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.559] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.559] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.559] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.559] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.559] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.559] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.559] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.559] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.560] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.560] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.560] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.560] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.560] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.560] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.560] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:55.963] INFO: enter 'restricted' command line mode
[10:13:55.963] INFO: enter test to run
[10:14:05.007] INFO: test: PixelAlive no parameter change
[10:14:05.007] INFO: running: pixelalive
[10:14:05.016] INFO: ----------------------------------------------------------------------
[10:14:05.016] 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:14:05.016] INFO: ----------------------------------------------------------------------
[10:14:05.334] INFO: Expecting 41600 events.
[10:14:09.615] INFO: 41600 events read in total (3562ms).
[10:14:09.784] INFO: Test took 4765ms.
[10:14:09.796] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:10.057] INFO: PixTestAlive::aliveTest() done
[10:14:10.057] INFO: number of dead pixels (per ROC): 0 7 0 0 0 1 0 0 0 1 0 2 0 0 1 0
[10:14:10.090] INFO: enter test to run
[10:14:54.822] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:14:54.822] INFO: running: highrate
[10:14:54.822] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:14:55.048] INFO: ----------------------------------------------------------------------
[10:14:55.048] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:14:55.048] INFO: ----------------------------------------------------------------------
[10:14:55.048] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:14:55.048] INFO: edge/corner pixel THR is adjusted
[10:14:55.048] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:14:56.008] INFO: Collecting data for 5 seconds...
[10:15:01.024] INFO: Done with hot pixel readout
[10:15:11.972] INFO: PixTest:: pg_setup set to default.
[10:15:11.973] INFO: 1 hot pixels found in step 0
[10:15:12.982] INFO: Collecting data for 5 seconds...
[10:15:17.998] INFO: Done with hot pixel readout
[10:15:29.148] INFO: PixTest:: pg_setup set to default.
[10:15:29.149] INFO: 1 hot pixels found in step 1
[10:15:30.142] INFO: Collecting data for 5 seconds...
[10:15:35.158] INFO: Done with hot pixel readout
[10:15:46.369] INFO: PixTest:: pg_setup set to default.
[10:15:46.369] INFO: 0 hot pixels found in step 2
[10:15:46.402] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:15:46.405] INFO: PixTest::trimHotPixels() done
[10:15:46.406] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C0.dat
[10:15:46.412] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C1.dat
[10:15:46.418] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C2.dat
[10:15:46.423] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C3.dat
[10:15:46.428] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C4.dat
[10:15:46.433] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C5.dat
[10:15:46.439] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C6.dat
[10:15:46.444] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C7.dat
[10:15:46.449] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C8.dat
[10:15:46.454] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C9.dat
[10:15:46.459] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C10.dat
[10:15:46.464] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C11.dat
[10:15:46.470] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C12.dat
[10:15:46.475] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C13.dat
[10:15:46.480] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C14.dat
[10:15:46.485] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C15.dat
[10:15:46.490] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//defaultMaskFile.dat
[10:15:46.500] INFO: enter test to run
[10:16:21.318] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:16:21.318] INFO: running: highrate
[10:16:21.322] INFO: ----------------------------------------------------------------------
[10:16:21.322] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:16:21.322] INFO: ----------------------------------------------------------------------
[10:16:21.322] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:16:21.322] INFO: edge/corner pixel THR is adjusted
[10:16:21.323] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:16:22.280] INFO: Collecting data for 1 seconds...
[10:16:23.284] INFO: Done with hot pixel readout
[10:16:27.092] INFO: PixTest:: pg_setup set to default.
[10:16:27.093] INFO: 0 hot pixels found in step 0
[10:16:27.098] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:16:27.195] INFO: PixTest::trimHotPixels() done
[10:16:27.195] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C0.dat
[10:16:27.202] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C1.dat
[10:16:27.210] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C2.dat
[10:16:27.215] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C3.dat
[10:16:27.220] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C4.dat
[10:16:27.225] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C5.dat
[10:16:27.231] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C6.dat
[10:16:27.236] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C7.dat
[10:16:27.241] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C8.dat
[10:16:27.246] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C9.dat
[10:16:27.251] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C10.dat
[10:16:27.257] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C11.dat
[10:16:27.262] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C12.dat
[10:16:27.267] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C13.dat
[10:16:27.272] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C14.dat
[10:16:27.277] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//trimParameters35_C15.dat
[10:16:27.283] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-6-23_FPIXTest-17C-FNAL-160908-1253-150V_2016-09-08_12h53m_1473357201/000_FPIXTest_p17//defaultMaskFile.dat
[10:16:27.292] INFO: enter test to run
[10:16:53.062] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:16:53.062] INFO: running: xray
[10:16:53.063] INFO: ----------------------------------------------------------------------
[10:16:53.063] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:16:53.063] INFO: ----------------------------------------------------------------------
[10:16:54.027] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:17:05.724] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:17:33.714] INFO: Resuming triggers.
[10:17:45.414] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:18:14.685] INFO: Resuming triggers.
[10:18:26.387] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:18:55.101] INFO: Resuming triggers.
[10:19:06.800] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:19:35.963] INFO: Resuming triggers.
[10:19:47.665] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[10:20:16.725] INFO: Resuming triggers.
[10:20:28.423] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:20:57.169] INFO: Resuming triggers.
[10:21:08.873] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[10:21:37.596] INFO: Resuming triggers.
[10:21:49.289] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[10:22:18.040] INFO: Resuming triggers.
[10:22:24.777] INFO: data taking finished, elapsed time: 100 seconds.
[10:22:41.667] INFO: PixTest:: pg_setup set to default.
[10:22:41.670] INFO: PixTestXray::doPhRun() done
[10:22:41.807] INFO: enter test to run
[10:23:10.180] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:23:10.180] INFO: running: xray
[10:23:10.181] INFO: ----------------------------------------------------------------------
[10:23:10.181] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:23:10.181] INFO: ----------------------------------------------------------------------
[10:23:11.145] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:23:18.076] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:23:49.012] INFO: Resuming triggers.
[10:23:55.942] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:24:27.051] INFO: Resuming triggers.
[10:24:33.985] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[10:25:05.173] INFO: Resuming triggers.
[10:25:12.105] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[10:25:42.737] INFO: Resuming triggers.
[10:25:49.672] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:26:19.797] INFO: Resuming triggers.
[10:26:26.731] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[10:26:56.983] INFO: Resuming triggers.
[10:27:03.918] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[10:27:34.087] INFO: Resuming triggers.
[10:27:41.021] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[10:28:11.220] INFO: Resuming triggers.
[10:28:18.154] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[10:28:48.371] INFO: Resuming triggers.
[10:28:55.304] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:29:25.517] INFO: Resuming triggers.
[10:29:32.448] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[10:30:02.742] INFO: Resuming triggers.
[10:30:09.674] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[10:30:39.780] INFO: Resuming triggers.
[10:30:46.712] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[10:31:16.988] INFO: Resuming triggers.
[10:31:23.918] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[10:31:54.049] INFO: Resuming triggers.
[10:31:57.334] INFO: data taking finished, elapsed time: 100 seconds.
[10:32:11.923] INFO: PixTest:: pg_setup set to default.
[10:32:11.926] INFO: PixTestXray::doPhRun() done
[10:32:12.075] INFO: enter test to run
[10:33:02.671] INFO: test: HighRate no parameter change
[10:33:02.671] INFO: running: highrate
[10:33:02.684] INFO: ----------------------------------------------------------------------
[10:33:02.684] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:33:02.684] INFO: ----------------------------------------------------------------------
[10:33:02.839] INFO: Expecting 768 events.
[10:33:03.973] INFO: 768 events read in total (418ms).
[10:33:03.973] INFO: Test took 1269ms.
[10:33:04.777] INFO: Expecting 41600 events.
[10:33:07.809] INFO: 41600 events read in total (2505ms).
[10:33:07.810] INFO: Test took 3830ms.
[10:33:07.842] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:08.576] INFO: Expecting 41600 events.
[10:33:11.737] INFO: 41600 events read in total (2635ms).
[10:33:11.738] INFO: Test took 3878ms.
[10:33:11.770] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:12.499] INFO: Expecting 41600 events.
[10:33:15.706] INFO: 41600 events read in total (2680ms).
[10:33:15.707] INFO: Test took 3919ms.
[10:33:15.739] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:16.468] INFO: Expecting 41600 events.
[10:33:19.689] INFO: 41600 events read in total (2694ms).
[10:33:19.689] INFO: Test took 3933ms.
[10:33:19.722] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:20.446] INFO: Expecting 41600 events.
[10:33:23.661] INFO: 41600 events read in total (2688ms).
[10:33:23.662] INFO: Test took 3922ms.
[10:33:23.694] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:24.418] INFO: Expecting 41600 events.
[10:33:27.637] INFO: 41600 events read in total (2693ms).
[10:33:27.638] INFO: Test took 3925ms.
[10:33:27.671] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:28.398] INFO: Expecting 41600 events.
[10:33:31.628] INFO: 41600 events read in total (2703ms).
[10:33:31.629] INFO: Test took 3941ms.
[10:33:31.661] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:32.385] INFO: Expecting 41600 events.
[10:33:35.608] INFO: 41600 events read in total (2696ms).
[10:33:35.609] INFO: Test took 3931ms.
[10:33:35.642] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:36.369] INFO: Expecting 41600 events.
[10:33:39.468] INFO: 41600 events read in total (2573ms).
[10:33:39.469] INFO: Test took 3810ms.
[10:33:39.502] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:40.226] INFO: Expecting 41600 events.
[10:33:43.356] INFO: 41600 events read in total (2603ms).
[10:33:43.356] INFO: Test took 3837ms.
[10:33:43.390] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:44.113] INFO: Expecting 41600 events.
[10:33:47.353] INFO: 41600 events read in total (2713ms).
[10:33:47.353] INFO: Test took 3946ms.
[10:33:47.387] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:48.111] INFO: Expecting 41600 events.
[10:33:51.265] INFO: 41600 events read in total (2627ms).
[10:33:51.266] INFO: Test took 3858ms.
[10:33:51.299] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:52.025] INFO: Expecting 41600 events.
[10:33:55.261] INFO: 41600 events read in total (2709ms).
[10:33:55.262] INFO: Test took 3946ms.
[10:33:55.295] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:56.021] INFO: Expecting 41600 events.
[10:33:59.216] INFO: 41600 events read in total (2668ms).
[10:33:59.217] INFO: Test took 3905ms.
[10:33:59.250] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:59.976] INFO: Expecting 41600 events.
[10:34:03.218] INFO: 41600 events read in total (2715ms).
[10:34:03.219] INFO: Test took 3952ms.
[10:34:03.252] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:03.980] INFO: Expecting 41600 events.
[10:34:07.243] INFO: 41600 events read in total (2736ms).
[10:34:07.244] INFO: Test took 3975ms.
[10:34:07.279] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:07.004] INFO: Expecting 41600 events.
[10:34:11.171] INFO: 41600 events read in total (2640ms).
[10:34:11.172] INFO: Test took 3876ms.
[10:34:11.206] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:11.929] INFO: Expecting 41600 events.
[10:34:15.144] INFO: 41600 events read in total (2689ms).
[10:34:15.145] INFO: Test took 3922ms.
[10:34:15.178] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:15.906] INFO: Expecting 41600 events.
[10:34:19.080] INFO: 41600 events read in total (2647ms).
[10:34:19.081] INFO: Test took 3886ms.
[10:34:19.114] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:19.842] INFO: Expecting 41600 events.
[10:34:22.948] INFO: 41600 events read in total (2579ms).
[10:34:22.949] INFO: Test took 3816ms.
[10:34:22.981] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:23.349] INFO: enter test to run
[10:35:04.015] INFO: test: HighRate no parameter change
[10:35:04.015] INFO: running: highrate
[10:35:04.016] INFO: ----------------------------------------------------------------------
[10:35:04.016] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:35:04.016] INFO: ----------------------------------------------------------------------
[10:35:04.640] INFO: Expecting 208000 events.
[10:35:16.660] INFO: 208000 events read in total (11493ms).
[10:35:16.663] INFO: Test took 12638ms.
[10:35:16.806] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:35:17.056] INFO: number of dead pixels (per ROC): 0 6 0 0 0 1 0 0 0 1 0 2 0 0 1 0
[10:35:17.056] INFO: number of red-efficiency pixels: 82 44 74 115 135 126 118 90 97 134 109 123 89 54 21 32
[10:35:17.056] INFO: number of X-ray hits detected: 65069 40144 63405 99759 103653 105884 105738 76255 73012 93878 95881 80841 81863 49785 18650 24913
[10:35:17.056] 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:35:17.056] INFO: number of Vcal hits detected: 207916 207662 207925 207882 207863 207821 207881 207908 207903 207815 207877 207777 207910 207944 207929 207966
[10:35:17.056] 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 99.9 99.9 100.0 100.0 100.0 100.0
[10:35:17.056] INFO: Vcal hit overall efficiency (%): 100.0 99.8 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[10:35:17.057] INFO: X-ray hit rate [MHz/cm2]: 19.1 11.8 18.6 29.2 30.4 31.0 31.0 22.4 21.4 27.5 28.1 23.7 24.0 14.6 5.5 7.3
[10:35:17.057] INFO: PixTestHighRate::doXPixelAlive() done
[10:35:17.102] INFO: PixTest:: pg_setup set to default.
[10:35:17.116] INFO: enter test to run
[10:35:43.038] INFO: test: HighRate no parameter change
[10:35:43.038] INFO: running: highrate
[10:35:43.039] INFO: ----------------------------------------------------------------------
[10:35:43.039] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:35:43.039] INFO: ----------------------------------------------------------------------
[10:35:43.658] INFO: Expecting 208000 events.
[10:35:57.338] INFO: 208000 events read in total (13153ms).
[10:35:57.344] INFO: Test took 14296ms.
[10:35:57.635] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:35:57.933] INFO: number of dead pixels (per ROC): 0 6 0 0 0 1 0 0 0 1 0 2 0 0 1 0
[10:35:57.933] INFO: number of red-efficiency pixels: 230 113 257 420 457 465 384 314 297 419 330 316 260 179 44 76
[10:35:57.933] INFO: number of X-ray hits detected: 133747 82784 131100 206205 213884 218090 218331 157669 150481 193348 198321 166615 170237 103226 38807 50307
[10:35:57.933] 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:35:57.933] INFO: number of Vcal hits detected: 207756 207590 207728 207553 207501 207440 207567 207656 207681 207493 207616 207567 207728 207810 207906 207921
[10:35:57.933] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[10:35:57.933] INFO: Vcal hit overall efficiency (%): 99.9 99.8 99.9 99.8 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[10:35:57.933] INFO: X-ray hit rate [MHz/cm2]: 39.2 24.3 38.4 60.4 62.7 63.9 64.0 46.2 44.1 56.7 58.1 48.8 49.9 30.3 11.4 14.7
[10:35:57.934] INFO: PixTestHighRate::doXPixelAlive() done
[10:35:57.983] INFO: PixTest:: pg_setup set to default.
[10:35:57.000] INFO: enter test to run
[10:36:25.566] INFO: test: HighRate no parameter change
[10:36:25.566] INFO: running: highrate
[10:36:25.567] INFO: ----------------------------------------------------------------------
[10:36:25.567] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:36:25.567] INFO: ----------------------------------------------------------------------
[10:36:26.185] INFO: Expecting 208000 events.
[10:36:42.145] INFO: 208000 events read in total (15433ms).
[10:36:42.152] INFO: Test took 16576ms.
[10:36:42.592] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:42.941] INFO: number of dead pixels (per ROC): 0 6 0 0 0 1 0 0 0 1 0 2 0 0 1 0
[10:36:42.941] INFO: number of red-efficiency pixels: 509 239 601 1207 1133 1167 960 738 638 979 831 781 581 407 82 148
[10:36:42.941] INFO: number of X-ray hits detected: 209311 128686 203185 321219 329988 338171 339213 244420 234857 300764 307557 258660 263337 160671 60795 79342
[10:36:42.941] 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:36:42.941] INFO: number of Vcal hits detected: 207364 207442 207237 206460 206589 206441 206800 207008 207251 206758 206909 206984 207346 207514 207869 207843
[10:36:42.941] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.7 99.3 99.4 99.3 99.5 99.6 99.7 99.5 99.5 99.6 99.7 99.8 100.0 99.9
[10:36:42.941] INFO: Vcal hit overall efficiency (%): 99.7 99.7 99.6 99.3 99.3 99.3 99.4 99.5 99.6 99.4 99.5 99.5 99.7 99.8 99.9 99.9
[10:36:42.941] INFO: X-ray hit rate [MHz/cm2]: 61.4 37.7 59.6 94.2 96.7 99.1 99.4 71.6 68.8 88.2 90.1 75.8 77.2 47.1 17.8 23.3
[10:36:42.941] INFO: PixTestHighRate::doXPixelAlive() done
[10:36:42.989] INFO: PixTest:: pg_setup set to default.
[10:36:42.004] INFO: enter test to run
[10:38:31.181] INFO: test: exit no parameter change
[10:38:31.570] QUIET: Connection to board 33 closed.
[10:38:31.571] INFO: pXar: this is the end, my friend