[15:04:19.175] INFO: *** Welcome to pxar ***
[15:04:19.176] INFO: *** Today: 2016/06/08
[15:04:19.876] INFO: *** Version: v1.9.0-814-g7497
[15:04:19.876] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//dacParameters35_C15.dat
[15:04:19.934] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:04:19.934] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//defaultMaskFile.dat
[15:04:19.944] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C15.dat
[15:04:20.062] INFO: clk: 4
[15:04:20.062] INFO: ctr: 4
[15:04:20.062] INFO: sda: 19
[15:04:20.062] INFO: tin: 9
[15:04:20.062] INFO: level: 15
[15:04:20.062] INFO: triggerdelay: 0
[15:04:20.062] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[15:04:20.062] INFO: Log level: INFO
[15:04:20.081] QUIET: Connection to board DTB_WREKRL opened.
[15:04:20.085] 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:
------------------------------------------------------
[15:04:20.088] INFO: RPC call hashes of host and DTB match: 398089610
[15:04:21.631] INFO: DUT info:
[15:04:21.631] INFO: The DUT currently contains the following objects:
[15:04:21.631] INFO: 2 TBM Cores tbm08c (2 ON)
[15:04:21.645] INFO: TBM Core alpha (0): 7 registers set
[15:04:21.645] INFO: TBM Core beta (1): 7 registers set
[15:04:21.645] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:04:21.645] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.645] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.645] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.645] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.646] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.646] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.646] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.646] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.646] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.646] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.646] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.646] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.646] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.646] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.646] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.646] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:22.114] INFO: enter 'restricted' command line mode
[15:04:22.114] INFO: enter test to run
[15:04:24.189] INFO: test: PixelAlive no parameter change
[15:04:24.189] INFO: running: pixelalive
[15:04:24.301] INFO: ----------------------------------------------------------------------
[15:04:24.301] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:04:24.301] INFO: ----------------------------------------------------------------------
[15:04:24.616] INFO: Expecting 41600 events.
[15:04:28.965] INFO: 41600 events read in total (3630ms).
[15:04:29.131] INFO: Test took 4828ms.
[15:04:29.142] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:29.411] INFO: PixTestAlive::aliveTest() done
[15:04:29.411] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:04:29.440] INFO: enter test to run
[15:05:37.557] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:05:37.557] INFO: running: highrate
[15:05:37.579] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[15:05:37.842] INFO: ----------------------------------------------------------------------
[15:05:37.842] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:05:37.842] INFO: ----------------------------------------------------------------------
[15:05:37.842] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:05:37.842] INFO: edge/corner pixel THR is adjusted
[15:05:37.842] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:05:38.818] INFO: Collecting data for 5 seconds...
[15:05:43.835] INFO: Done with hot pixel readout
[15:05:55.369] INFO: PixTest:: pg_setup set to default.
[15:05:55.370] INFO: 1 hot pixels found in step 0
[15:05:56.378] INFO: Collecting data for 5 seconds...
[15:06:01.394] INFO: Done with hot pixel readout
[15:06:12.872] INFO: PixTest:: pg_setup set to default.
[15:06:12.873] INFO: 0 hot pixels found in step 1
[15:06:12.906] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:06:12.909] INFO: PixTest::trimHotPixels() done
[15:06:12.909] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C0.dat
[15:06:12.916] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C1.dat
[15:06:12.922] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C2.dat
[15:06:12.927] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C3.dat
[15:06:12.932] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C4.dat
[15:06:12.938] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C5.dat
[15:06:12.943] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C6.dat
[15:06:12.948] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C7.dat
[15:06:12.953] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C8.dat
[15:06:12.958] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C9.dat
[15:06:12.964] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C10.dat
[15:06:12.969] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C11.dat
[15:06:12.974] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C12.dat
[15:06:12.979] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C13.dat
[15:06:12.985] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C14.dat
[15:06:12.990] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C15.dat
[15:06:12.995] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//defaultMaskFile.dat
[15:06:13.013] INFO: enter test to run
[15:06:33.748] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[15:06:33.748] INFO: running: highrate
[15:06:33.752] INFO: ----------------------------------------------------------------------
[15:06:33.752] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:06:33.752] INFO: ----------------------------------------------------------------------
[15:06:33.752] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:06:33.752] INFO: edge/corner pixel THR is adjusted
[15:06:33.752] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:06:34.715] INFO: Collecting data for 1 seconds...
[15:06:35.718] INFO: Done with hot pixel readout
[15:06:39.592] INFO: PixTest:: pg_setup set to default.
[15:06:39.592] INFO: 0 hot pixels found in step 0
[15:06:39.598] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:06:39.691] INFO: PixTest::trimHotPixels() done
[15:06:39.691] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C0.dat
[15:06:39.701] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C1.dat
[15:06:39.708] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C2.dat
[15:06:39.714] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C3.dat
[15:06:39.719] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C4.dat
[15:06:39.724] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C5.dat
[15:06:39.730] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C6.dat
[15:06:39.735] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C7.dat
[15:06:39.740] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C8.dat
[15:06:39.745] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C9.dat
[15:06:39.751] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C10.dat
[15:06:39.756] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C11.dat
[15:06:39.761] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C12.dat
[15:06:39.767] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C13.dat
[15:06:39.772] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C14.dat
[15:06:39.777] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C15.dat
[15:06:39.783] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-J-1-10_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//defaultMaskFile.dat
[15:06:39.793] INFO: enter test to run
[15:07:17.340] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:07:17.340] INFO: running: xray
[15:07:17.341] INFO: ----------------------------------------------------------------------
[15:07:17.341] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:07:17.341] INFO: ----------------------------------------------------------------------
[15:07:18.305] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:07:29.901] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:07:59.272] INFO: Resuming triggers.
[15:08:10.865] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:08:40.397] INFO: Resuming triggers.
[15:08:51.986] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:09:22.358] INFO: Resuming triggers.
[15:09:33.954] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:10:04.098] INFO: Resuming triggers.
[15:10:15.693] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[15:10:45.245] INFO: Resuming triggers.
[15:10:56.838] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:11:26.184] INFO: Resuming triggers.
[15:11:37.783] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[15:12:07.121] INFO: Resuming triggers.
[15:12:18.717] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[15:12:47.828] INFO: Resuming triggers.
[15:12:55.429] INFO: data taking finished, elapsed time: 100 seconds.
[15:13:14.866] INFO: PixTest:: pg_setup set to default.
[15:13:14.869] INFO: PixTestXray::doPhRun() done
[15:13:15.040] INFO: enter test to run
[15:13:38.425] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:13:38.425] INFO: running: xray
[15:13:38.426] INFO: ----------------------------------------------------------------------
[15:13:38.426] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:13:38.426] INFO: ----------------------------------------------------------------------
[15:13:39.396] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:13:46.364] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:14:16.515] INFO: Resuming triggers.
[15:14:23.483] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[15:14:53.733] INFO: Resuming triggers.
[15:15:00.702] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[15:15:30.872] INFO: Resuming triggers.
[15:15:37.842] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[15:16:07.000] INFO: Resuming triggers.
[15:16:14.970] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:16:45.016] INFO: Resuming triggers.
[15:16:51.983] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[15:17:22.133] INFO: Resuming triggers.
[15:17:29.099] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[15:17:59.248] INFO: Resuming triggers.
[15:18:06.216] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[15:18:36.369] INFO: Resuming triggers.
[15:18:43.336] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[15:19:14.505] INFO: Resuming triggers.
[15:19:21.471] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:19:51.957] INFO: Resuming triggers.
[15:19:58.923] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[15:20:30.056] INFO: Resuming triggers.
[15:20:37.024] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[15:21:08.428] INFO: Resuming triggers.
[15:21:15.395] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[15:21:46.518] INFO: Resuming triggers.
[15:21:53.485] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[15:22:23.646] INFO: Resuming triggers.
[15:22:26.442] INFO: data taking finished, elapsed time: 100 seconds.
[15:22:38.846] INFO: PixTest:: pg_setup set to default.
[15:22:38.849] INFO: PixTestXray::doPhRun() done
[15:22:38.997] INFO: enter test to run
[15:23:12.357] INFO: test: HighRate no parameter change
[15:23:12.357] INFO: running: highrate
[15:23:12.358] INFO: ----------------------------------------------------------------------
[15:23:12.358] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:23:12.358] INFO: ----------------------------------------------------------------------
[15:23:12.497] INFO: Expecting 768 events.
[15:23:13.631] INFO: 768 events read in total (418ms).
[15:23:13.631] INFO: Test took 1267ms.
[15:23:14.435] INFO: Expecting 41600 events.
[15:23:17.461] INFO: 41600 events read in total (2500ms).
[15:23:17.462] INFO: Test took 3801ms.
[15:23:17.494] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:18.225] INFO: Expecting 41600 events.
[15:23:21.413] INFO: 41600 events read in total (2661ms).
[15:23:21.414] INFO: Test took 3903ms.
[15:23:21.448] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:22.174] INFO: Expecting 41600 events.
[15:23:25.397] INFO: 41600 events read in total (2696ms).
[15:23:25.398] INFO: Test took 3933ms.
[15:23:25.431] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:26.155] INFO: Expecting 41600 events.
[15:23:29.369] INFO: 41600 events read in total (2687ms).
[15:23:29.370] INFO: Test took 3921ms.
[15:23:29.404] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:30.127] INFO: Expecting 41600 events.
[15:23:33.343] INFO: 41600 events read in total (2689ms).
[15:23:33.344] INFO: Test took 3921ms.
[15:23:33.377] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:34.104] INFO: Expecting 41600 events.
[15:23:37.329] INFO: 41600 events read in total (2698ms).
[15:23:37.330] INFO: Test took 3935ms.
[15:23:37.363] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:38.089] INFO: Expecting 41600 events.
[15:23:41.313] INFO: 41600 events read in total (2697ms).
[15:23:41.314] INFO: Test took 3934ms.
[15:23:41.348] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:42.076] INFO: Expecting 41600 events.
[15:23:45.294] INFO: 41600 events read in total (2691ms).
[15:23:45.295] INFO: Test took 3929ms.
[15:23:45.328] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:46.053] INFO: Expecting 41600 events.
[15:23:49.277] INFO: 41600 events read in total (2697ms).
[15:23:49.278] INFO: Test took 3933ms.
[15:23:49.311] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:50.035] INFO: Expecting 41600 events.
[15:23:53.252] INFO: 41600 events read in total (2690ms).
[15:23:53.253] INFO: Test took 3924ms.
[15:23:53.286] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:54.014] INFO: Expecting 41600 events.
[15:23:57.234] INFO: 41600 events read in total (2694ms).
[15:23:57.235] INFO: Test took 3932ms.
[15:23:57.269] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:57.994] INFO: Expecting 41600 events.
[15:24:01.228] INFO: 41600 events read in total (2707ms).
[15:24:01.229] INFO: Test took 3943ms.
[15:24:01.262] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:01.986] INFO: Expecting 41600 events.
[15:24:05.217] INFO: 41600 events read in total (2704ms).
[15:24:05.218] INFO: Test took 3937ms.
[15:24:05.251] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:05.980] INFO: Expecting 41600 events.
[15:24:09.210] INFO: 41600 events read in total (2703ms).
[15:24:09.211] INFO: Test took 3941ms.
[15:24:09.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:09.969] INFO: Expecting 41600 events.
[15:24:13.195] INFO: 41600 events read in total (2699ms).
[15:24:13.196] INFO: Test took 3933ms.
[15:24:13.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:13.954] INFO: Expecting 41600 events.
[15:24:17.190] INFO: 41600 events read in total (2709ms).
[15:24:17.191] INFO: Test took 3944ms.
[15:24:17.224] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:17.950] INFO: Expecting 41600 events.
[15:24:21.172] INFO: 41600 events read in total (2695ms).
[15:24:21.173] INFO: Test took 3932ms.
[15:24:21.207] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:21.929] INFO: Expecting 41600 events.
[15:24:25.161] INFO: 41600 events read in total (2705ms).
[15:24:25.162] INFO: Test took 3937ms.
[15:24:25.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:25.924] INFO: Expecting 41600 events.
[15:24:29.155] INFO: 41600 events read in total (2704ms).
[15:24:29.156] INFO: Test took 3943ms.
[15:24:29.190] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:29.915] INFO: Expecting 41600 events.
[15:24:33.028] INFO: 41600 events read in total (2586ms).
[15:24:33.030] INFO: Test took 3823ms.
[15:24:33.062] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:33.426] INFO: enter test to run
[15:25:16.660] INFO: test: HighRate no parameter change
[15:25:16.660] INFO: running: highrate
[15:25:16.661] INFO: ----------------------------------------------------------------------
[15:25:16.661] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:25:16.661] INFO: ----------------------------------------------------------------------
[15:25:17.280] INFO: Expecting 208000 events.
[15:25:29.067] INFO: 208000 events read in total (11260ms).
[15:25:29.070] INFO: Test took 12401ms.
[15:25:29.213] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:29.464] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:25:29.464] INFO: number of red-efficiency pixels: 68 37 87 150 132 174 146 98 118 121 114 91 99 47 26 24
[15:25:29.464] INFO: number of X-ray hits detected: 59852 41447 66261 102330 111407 111155 112350 83163 76608 95627 91506 79923 79409 47475 22688 26084
[15:25:29.464] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:25:29.464] INFO: number of Vcal hits detected: 207931 207961 207911 207846 207864 207821 207852 207899 207880 207878 207885 207906 207898 207953 207974 207976
[15:25:29.464] 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 100.0 100.0 100.0 100.0 100.0
[15:25:29.464] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[15:25:29.464] INFO: X-ray hit rate [MHz/cm2]: 17.5 12.1 19.4 30.0 32.7 32.6 32.9 24.4 22.5 28.0 26.8 23.4 23.3 13.9 6.7 7.6
[15:25:29.464] INFO: PixTestHighRate::doXPixelAlive() done
[15:25:29.511] INFO: PixTest:: pg_setup set to default.
[15:25:29.528] INFO: enter test to run
[15:27:53.315] INFO: test: HighRate no parameter change
[15:27:53.315] INFO: running: highrate
[15:27:53.316] INFO: ----------------------------------------------------------------------
[15:27:53.316] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:27:53.316] INFO: ----------------------------------------------------------------------
[15:27:53.931] INFO: Expecting 208000 events.
[15:28:07.371] INFO: 208000 events read in total (12913ms).
[15:28:07.376] INFO: Test took 14050ms.
[15:28:07.651] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:07.946] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:28:07.946] INFO: number of red-efficiency pixels: 184 101 259 479 372 500 522 302 281 359 308 285 263 133 44 53
[15:28:07.946] INFO: number of X-ray hits detected: 120819 83518 133627 206892 224269 224607 227746 168137 154927 192801 184874 160936 159692 95600 46708 52949
[15:28:07.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
[15:28:07.946] INFO: number of Vcal hits detected: 207809 207897 207724 207480 207597 207459 207419 207683 207706 207614 207668 207702 207726 207858 207954 207946
[15:28:07.946] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.7 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[15:28:07.946] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.7 99.7 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[15:28:07.946] INFO: X-ray hit rate [MHz/cm2]: 35.4 24.5 39.2 60.6 65.7 65.8 66.8 49.3 45.4 56.5 54.2 47.2 46.8 28.0 13.7 15.5
[15:28:07.946] INFO: PixTestHighRate::doXPixelAlive() done
[15:28:07.999] INFO: PixTest:: pg_setup set to default.
[15:28:08.015] INFO: enter test to run
[15:29:03.114] INFO: test: HighRate no parameter change
[15:29:03.114] INFO: running: highrate
[15:29:03.115] INFO: ----------------------------------------------------------------------
[15:29:03.115] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:29:03.115] INFO: ----------------------------------------------------------------------
[15:29:03.732] INFO: Expecting 208000 events.
[15:29:18.880] INFO: 208000 events read in total (14621ms).
[15:29:18.887] INFO: Test took 15763ms.
[15:29:19.311] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:19.653] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:29:19.653] INFO: number of red-efficiency pixels: 388 232 543 959 806 1127 1167 764 563 900 775 563 575 246 78 89
[15:29:19.653] INFO: number of X-ray hits detected: 180318 125971 200782 310195 336514 337229 340689 252634 233409 290033 278627 241386 240855 143412 69200 80119
[15:29:19.653] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:29:19.653] INFO: number of Vcal hits detected: 207541 207759 207323 206849 207043 206550 206570 207058 207383 206938 207114 207366 207377 207737 207920 207909
[15:29:19.653] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.6 99.4 99.4 99.6 99.7 99.5 99.6 99.7 99.7 99.9 100.0 100.0
[15:29:19.653] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.4 99.5 99.3 99.3 99.5 99.7 99.5 99.6 99.7 99.7 99.9 100.0 100.0
[15:29:19.653] INFO: X-ray hit rate [MHz/cm2]: 52.9 36.9 58.9 90.9 98.6 98.8 99.9 74.0 68.4 85.0 81.7 70.8 70.6 42.0 20.3 23.5
[15:29:19.653] INFO: PixTestHighRate::doXPixelAlive() done
[15:29:19.704] INFO: PixTest:: pg_setup set to default.
[15:29:19.718] INFO: enter test to run
[15:30:13.426] INFO: test: exit no parameter change
[15:30:13.751] QUIET: Connection to board 33 closed.
[15:30:13.751] INFO: pXar: this is the end, my friend