[10:53:53.537] INFO: *** Welcome to pxar ***
[10:53:53.537] INFO: *** Today: 2016/05/10
[10:53:53.564] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:53:53.564] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//dacParameters35_C15.dat
[10:53:53.565] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:53:53.565] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//defaultMaskFile.dat
[10:53:53.565] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C15.dat
[10:53:53.641] INFO: clk: 4
[10:53:53.641] INFO: ctr: 4
[10:53:53.641] INFO: sda: 19
[10:53:53.641] INFO: tin: 9
[10:53:53.641] INFO: level: 15
[10:53:53.641] INFO: triggerdelay: 0
[10:53:53.641] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:53:53.641] INFO: Log level: INFO
[10:53:53.656] QUIET: Connection to board DTB_WREKRL opened.
[10:53:53.660] 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:53:53.663] INFO: RPC call hashes of host and DTB match: 398089610
[10:53:55.204] INFO: DUT info:
[10:53:55.205] INFO: The DUT currently contains the following objects:
[10:53:55.205] INFO: 2 TBM Cores tbm08c (2 ON)
[10:53:55.205] INFO: TBM Core alpha (0): 7 registers set
[10:53:55.205] INFO: TBM Core beta (1): 7 registers set
[10:53:55.205] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:53:55.205] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.205] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.205] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.205] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.205] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.205] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.205] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.205] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.206] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.206] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.206] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.206] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.206] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.206] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.206] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.206] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:55.609] INFO: enter 'restricted' command line mode
[10:53:55.609] INFO: enter test to run
[10:54:08.470] INFO: test: PixelAlive no parameter change
[10:54:08.470] INFO: running: pixelalive
[10:54:08.479] INFO: ----------------------------------------------------------------------
[10:54:08.479] 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:54:08.479] INFO: ----------------------------------------------------------------------
[10:54:08.804] INFO: Expecting 41600 events.
[10:54:13.094] INFO: 41600 events read in total (3571ms).
[10:54:13.263] INFO: Test took 4782ms.
[10:54:13.275] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:13.540] INFO: PixTestAlive::aliveTest() done
[10:54:13.540] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:54:13.572] INFO: enter test to run
[10:56:02.213] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:56:02.213] INFO: running: highrate
[10:56:02.213] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:56:02.364] INFO: ----------------------------------------------------------------------
[10:56:02.364] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:56:02.365] INFO: ----------------------------------------------------------------------
[10:56:02.365] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:56:02.365] INFO: edge/corner pixel THR is adjusted
[10:56:02.365] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:56:03.324] INFO: Collecting data for 5 seconds...
[10:56:08.342] INFO: Done with hot pixel readout
[10:56:19.815] INFO: PixTest:: pg_setup set to default.
[10:56:19.816] INFO: 0 hot pixels found in step 0
[10:56:19.828] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:56:19.831] INFO: PixTest::trimHotPixels() done
[10:56:19.855] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C0.dat
[10:56:19.862] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C1.dat
[10:56:19.869] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C2.dat
[10:56:19.876] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C3.dat
[10:56:19.883] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C4.dat
[10:56:19.890] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C5.dat
[10:56:19.897] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C6.dat
[10:56:19.904] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C7.dat
[10:56:19.911] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C8.dat
[10:56:19.918] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C9.dat
[10:56:19.925] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C10.dat
[10:56:19.932] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C11.dat
[10:56:19.940] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C12.dat
[10:56:19.947] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C13.dat
[10:56:19.954] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C14.dat
[10:56:19.961] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C15.dat
[10:56:19.968] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//defaultMaskFile.dat
[10:56:19.978] INFO: enter test to run
[10:57:44.236] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:57:44.236] INFO: running: highrate
[10:57:44.241] INFO: ----------------------------------------------------------------------
[10:57:44.241] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:57:44.241] INFO: ----------------------------------------------------------------------
[10:57:44.241] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:57:44.241] INFO: edge/corner pixel THR is adjusted
[10:57:44.241] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:57:45.199] INFO: Collecting data for 1 seconds...
[10:57:46.202] INFO: Done with hot pixel readout
[10:57:50.050] INFO: PixTest:: pg_setup set to default.
[10:57:50.050] INFO: 0 hot pixels found in step 0
[10:57:50.056] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:57:50.155] INFO: PixTest::trimHotPixels() done
[10:57:50.156] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C0.dat
[10:57:50.163] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C1.dat
[10:57:50.170] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C2.dat
[10:57:50.177] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C3.dat
[10:57:50.184] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C4.dat
[10:57:50.191] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C5.dat
[10:57:50.198] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C6.dat
[10:57:50.205] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C7.dat
[10:57:50.212] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C8.dat
[10:57:50.219] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C9.dat
[10:57:50.226] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C10.dat
[10:57:50.233] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C11.dat
[10:57:50.240] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C12.dat
[10:57:50.247] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C13.dat
[10:57:50.254] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C14.dat
[10:57:50.261] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//trimParameters35_C15.dat
[10:57:50.268] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-3-20_FPIXTest-17C-FNAL-160505-1041_2016-05-05_10h42m_1462462925/000_FPIXTest_p17//defaultMaskFile.dat
[10:57:50.278] INFO: enter test to run
[10:58:38.284] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:58:38.284] INFO: running: xray
[10:58:38.285] INFO: ----------------------------------------------------------------------
[10:58:38.285] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:58:38.285] INFO: ----------------------------------------------------------------------
[10:58:39.248] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:58:50.990] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:59:20.231] INFO: Resuming triggers.
[10:59:31.971] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:00:01.217] INFO: Resuming triggers.
[11:00:12.957] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[11:00:42.183] INFO: Resuming triggers.
[11:00:53.918] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[11:01:23.177] INFO: Resuming triggers.
[11:01:34.921] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[11:02:04.161] INFO: Resuming triggers.
[11:02:15.904] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[11:02:44.965] INFO: Resuming triggers.
[11:02:56.711] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[11:03:25.858] INFO: Resuming triggers.
[11:03:37.599] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[11:04:07.175] INFO: Resuming triggers.
[11:04:13.587] INFO: data taking finished, elapsed time: 100 seconds.
[11:04:30.244] INFO: PixTest:: pg_setup set to default.
[11:04:30.248] INFO: PixTestXray::doPhRun() done
[11:04:30.412] INFO: enter test to run
[11:05:07.921] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:05:07.921] INFO: running: xray
[11:05:07.922] INFO: ----------------------------------------------------------------------
[11:05:07.922] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:05:07.922] INFO: ----------------------------------------------------------------------
[11:05:08.894] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:05:15.894] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[11:05:46.032] INFO: Resuming triggers.
[11:05:53.032] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[11:06:23.222] INFO: Resuming triggers.
[11:06:30.225] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[11:07:00.448] INFO: Resuming triggers.
[11:07:07.450] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[11:07:37.643] INFO: Resuming triggers.
[11:07:44.650] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[11:08:14.756] INFO: Resuming triggers.
[11:08:21.759] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[11:08:51.887] INFO: Resuming triggers.
[11:08:58.892] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[11:09:29.080] INFO: Resuming triggers.
[11:09:36.085] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[11:10:06.311] INFO: Resuming triggers.
[11:10:13.310] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[11:10:43.526] INFO: Resuming triggers.
[11:10:50.527] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[11:11:20.788] INFO: Resuming triggers.
[11:11:27.789] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[11:11:57.937] INFO: Resuming triggers.
[11:12:04.936] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[11:12:35.082] INFO: Resuming triggers.
[11:12:42.088] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[11:13:12.269] INFO: Resuming triggers.
[11:13:19.276] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[11:13:49.626] INFO: Resuming triggers.
[11:13:51.923] INFO: data taking finished, elapsed time: 100 seconds.
[11:14:02.163] INFO: PixTest:: pg_setup set to default.
[11:14:02.167] INFO: PixTestXray::doPhRun() done
[11:14:02.321] INFO: enter test to run
[11:14:45.156] INFO: test: HighRate no parameter change
[11:14:45.157] INFO: running: highrate
[11:14:45.158] INFO: ----------------------------------------------------------------------
[11:14:45.158] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:14:45.158] INFO: ----------------------------------------------------------------------
[11:14:45.297] INFO: Expecting 768 events.
[11:14:46.431] INFO: 768 events read in total (418ms).
[11:14:46.432] INFO: Test took 1268ms.
[11:14:47.235] INFO: Expecting 41600 events.
[11:14:50.303] INFO: 41600 events read in total (2541ms).
[11:14:50.304] INFO: Test took 3866ms.
[11:14:50.336] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:51.067] INFO: Expecting 41600 events.
[11:14:54.299] INFO: 41600 events read in total (2706ms).
[11:14:54.300] INFO: Test took 3947ms.
[11:14:54.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:55.060] INFO: Expecting 41600 events.
[11:14:58.297] INFO: 41600 events read in total (2710ms).
[11:14:58.298] INFO: Test took 3949ms.
[11:14:58.331] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:59.059] INFO: Expecting 41600 events.
[11:15:02.303] INFO: 41600 events read in total (2717ms).
[11:15:02.304] INFO: Test took 3956ms.
[11:15:02.338] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:03.064] INFO: Expecting 41600 events.
[11:15:06.294] INFO: 41600 events read in total (2703ms).
[11:15:06.295] INFO: Test took 3939ms.
[11:15:06.328] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:07.042] INFO: Expecting 41600 events.
[11:15:10.268] INFO: 41600 events read in total (2699ms).
[11:15:10.269] INFO: Test took 3925ms.
[11:15:10.301] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:11.024] INFO: Expecting 41600 events.
[11:15:14.279] INFO: 41600 events read in total (2728ms).
[11:15:14.280] INFO: Test took 3962ms.
[11:15:14.313] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:15.040] INFO: Expecting 41600 events.
[11:15:18.225] INFO: 41600 events read in total (2658ms).
[11:15:18.225] INFO: Test took 3895ms.
[11:15:18.258] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:18.971] INFO: Expecting 41600 events.
[11:15:22.237] INFO: 41600 events read in total (2739ms).
[11:15:22.238] INFO: Test took 3961ms.
[11:15:22.270] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:22.000] INFO: Expecting 41600 events.
[11:15:26.180] INFO: 41600 events read in total (2653ms).
[11:15:26.181] INFO: Test took 3893ms.
[11:15:26.213] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:26.935] INFO: Expecting 41600 events.
[11:15:30.205] INFO: 41600 events read in total (2743ms).
[11:15:30.206] INFO: Test took 3976ms.
[11:15:30.239] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:30.965] INFO: Expecting 41600 events.
[11:15:34.247] INFO: 41600 events read in total (2755ms).
[11:15:34.248] INFO: Test took 3992ms.
[11:15:34.281] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:35.007] INFO: Expecting 41600 events.
[11:15:38.195] INFO: 41600 events read in total (2662ms).
[11:15:38.196] INFO: Test took 3898ms.
[11:15:38.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:38.952] INFO: Expecting 41600 events.
[11:15:42.188] INFO: 41600 events read in total (2710ms).
[11:15:42.190] INFO: Test took 3944ms.
[11:15:42.225] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:42.949] INFO: Expecting 41600 events.
[11:15:46.220] INFO: 41600 events read in total (2744ms).
[11:15:46.220] INFO: Test took 3976ms.
[11:15:46.253] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:46.975] INFO: Expecting 41600 events.
[11:15:50.171] INFO: 41600 events read in total (2669ms).
[11:15:50.172] INFO: Test took 3902ms.
[11:15:50.204] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:50.930] INFO: Expecting 41600 events.
[11:15:54.118] INFO: 41600 events read in total (2661ms).
[11:15:54.119] INFO: Test took 3896ms.
[11:15:54.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:54.878] INFO: Expecting 41600 events.
[11:15:58.088] INFO: 41600 events read in total (2683ms).
[11:15:58.089] INFO: Test took 3921ms.
[11:15:58.122] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:58.846] INFO: Expecting 41600 events.
[11:16:02.079] INFO: 41600 events read in total (2707ms).
[11:16:02.080] INFO: Test took 3941ms.
[11:16:02.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:02.841] INFO: Expecting 41600 events.
[11:16:05.925] INFO: 41600 events read in total (2557ms).
[11:16:05.926] INFO: Test took 3796ms.
[11:16:05.958] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:06.332] INFO: enter test to run
[11:16:18.508] INFO: test: HighRate no parameter change
[11:16:18.508] INFO: running: highrate
[11:16:18.509] INFO: ----------------------------------------------------------------------
[11:16:18.509] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:16:18.509] INFO: ----------------------------------------------------------------------
[11:16:19.123] INFO: Expecting 208000 events.
[11:16:30.835] INFO: 208000 events read in total (11185ms).
[11:16:30.838] INFO: Test took 12321ms.
[11:16:30.971] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:31.219] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:16:31.219] INFO: number of red-efficiency pixels: 84 51 72 131 83 148 137 81 94 94 113 74 78 57 35 25
[11:16:31.219] INFO: number of X-ray hits detected: 60716 40984 60878 97881 102029 106571 102941 72298 73191 87951 86175 77644 77739 48600 21773 24424
[11:16:31.219] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:16:31.219] INFO: number of Vcal hits detected: 207913 207949 207927 207868 207916 207849 207859 207919 207902 207905 207885 207925 207922 207942 207965 207975
[11:16:31.219] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.9 99.9 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0
[11:16:31.219] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.9 99.9 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0
[11:16:31.219] INFO: X-ray hit rate [MHz/cm2]: 17.8 12.0 17.8 28.7 29.9 31.2 30.2 21.2 21.5 25.8 25.3 22.8 22.8 14.2 6.4 7.2
[11:16:31.219] INFO: PixTestHighRate::doXPixelAlive() done
[11:16:31.271] INFO: PixTest:: pg_setup set to default.
[11:16:31.286] INFO: enter test to run
[11:16:52.340] INFO: test: HighRate no parameter change
[11:16:52.340] INFO: running: highrate
[11:16:52.341] INFO: ----------------------------------------------------------------------
[11:16:52.341] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:16:52.341] INFO: ----------------------------------------------------------------------
[11:16:52.953] INFO: Expecting 208000 events.
[11:17:06.274] INFO: 208000 events read in total (12795ms).
[11:17:06.279] INFO: Test took 13929ms.
[11:17:06.559] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:06.851] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:17:06.851] INFO: number of red-efficiency pixels: 181 108 226 392 345 466 431 244 232 352 310 248 248 172 60 49
[11:17:06.852] INFO: number of X-ray hits detected: 127469 86061 127727 205570 212994 221739 214563 152321 153529 184161 181716 162234 162607 101754 45492 51465
[11:17:06.852] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:17:06.852] INFO: number of Vcal hits detected: 207801 207885 207756 207582 207634 207489 207535 207742 207760 207621 207672 207737 207745 207821 207938 207950
[11:17:06.852] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[11:17:06.852] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[11:17:06.852] INFO: X-ray hit rate [MHz/cm2]: 37.4 25.2 37.4 60.3 62.4 65.0 62.9 44.6 45.0 54.0 53.3 47.6 47.7 29.8 13.3 15.1
[11:17:06.852] INFO: PixTestHighRate::doXPixelAlive() done
[11:17:06.905] INFO: PixTest:: pg_setup set to default.
[11:17:06.918] INFO: enter test to run
[11:17:27.891] INFO: test: HighRate no parameter change
[11:17:27.891] INFO: running: highrate
[11:17:27.893] INFO: ----------------------------------------------------------------------
[11:17:27.893] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:17:27.893] INFO: ----------------------------------------------------------------------
[11:17:28.508] INFO: Expecting 208000 events.
[11:17:43.635] INFO: 208000 events read in total (14600ms).
[11:17:43.642] INFO: Test took 15739ms.
[11:17:44.069] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:44.407] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:17:44.407] INFO: number of red-efficiency pixels: 456 252 564 962 815 1119 1039 582 447 778 821 579 583 401 97 80
[11:17:44.407] INFO: number of X-ray hits detected: 192896 130357 194038 311360 322449 334612 325056 229578 232748 277537 274448 246051 245903 154239 69689 77857
[11:17:44.407] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:17:44.407] INFO: number of Vcal hits detected: 207456 207722 207281 206836 207055 206601 206734 207328 207506 207102 207056 207356 207345 207543 207899 207920
[11:17:44.407] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.6 99.4 99.4 99.7 99.8 99.6 99.6 99.7 99.7 99.8 100.0 100.0
[11:17:44.407] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.7 99.4 99.5 99.3 99.4 99.7 99.8 99.6 99.5 99.7 99.7 99.8 100.0 100.0
[11:17:44.407] INFO: X-ray hit rate [MHz/cm2]: 56.5 38.2 56.9 91.3 94.5 98.1 95.3 67.3 68.2 81.3 80.4 72.1 72.1 45.2 20.4 22.8
[11:17:44.407] INFO: PixTestHighRate::doXPixelAlive() done
[11:17:44.452] INFO: PixTest:: pg_setup set to default.
[11:17:44.468] INFO: enter test to run
[11:17:51.339] INFO: test: exit no parameter change
[11:17:51.652] QUIET: Connection to board 33 closed.
[11:17:51.653] INFO: pXar: this is the end, my friend