[10:45:01.834] INFO: *** Welcome to pxar ***
[10:45:01.834] INFO: *** Today: 2016/05/31
[10:45:01.852] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:45:01.852] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//dacParameters35_C15.dat
[10:45:01.906] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:45:01.906] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//defaultMaskFile.dat
[10:45:01.909] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C15.dat
[10:45:02.013] INFO: clk: 4
[10:45:02.013] INFO: ctr: 4
[10:45:02.013] INFO: sda: 19
[10:45:02.013] INFO: tin: 9
[10:45:02.013] INFO: level: 15
[10:45:02.013] INFO: triggerdelay: 0
[10:45:02.013] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:45:02.013] INFO: Log level: INFO
[10:45:02.032] QUIET: Connection to board DTB_WREKRL opened.
[10:45:02.035] 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:45:02.038] INFO: RPC call hashes of host and DTB match: 398089610
[10:45:03.573] INFO: DUT info:
[10:45:03.573] INFO: The DUT currently contains the following objects:
[10:45:03.573] INFO: 2 TBM Cores tbm08c (2 ON)
[10:45:03.573] INFO: TBM Core alpha (0): 7 registers set
[10:45:03.573] INFO: TBM Core beta (1): 7 registers set
[10:45:03.573] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:45:03.573] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.573] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.573] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.573] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.573] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.573] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.574] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.574] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.574] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.574] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.574] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.574] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.574] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.574] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.574] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.574] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:03.980] INFO: enter 'restricted' command line mode
[10:45:03.980] INFO: enter test to run
[10:45:18.316] INFO: test: PixelAlive no parameter change
[10:45:18.316] INFO: running: pixelalive
[10:45:18.322] INFO: ----------------------------------------------------------------------
[10:45:18.322] 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:45:18.322] INFO: ----------------------------------------------------------------------
[10:45:18.635] INFO: Expecting 41600 events.
[10:45:22.964] INFO: 41600 events read in total (3610ms).
[10:45:23.130] INFO: Test took 4806ms.
[10:45:23.138] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:23.403] INFO: PixTestAlive::aliveTest() done
[10:45:23.403] INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 9 2 1 0 0 0
[10:45:23.433] INFO: enter test to run
[10:45:53.500] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:45:53.500] INFO: running: highrate
[10:45:53.500] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:45:53.706] INFO: ----------------------------------------------------------------------
[10:45:53.706] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:45:53.706] INFO: ----------------------------------------------------------------------
[10:45:53.706] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:45:53.706] INFO: edge/corner pixel THR is adjusted
[10:45:53.706] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:45:54.665] INFO: Collecting data for 5 seconds...
[10:45:59.680] INFO: Done with hot pixel readout
[10:46:12.965] INFO: PixTest:: pg_setup set to default.





















[10:46:12.997] INFO: 643 hot pixels found in step 0
[10:46:13.993] INFO: Collecting data for 5 seconds...
[10:46:19.008] INFO: Done with hot pixel readout
[10:46:32.227] INFO: PixTest:: pg_setup set to default.































[10:46:32.229] INFO: 603 hot pixels found in step 1
[10:46:33.226] INFO: Collecting data for 5 seconds...
[10:46:38.241] INFO: Done with hot pixel readout
[10:46:51.503] INFO: PixTest:: pg_setup set to default.




















































[10:46:51.506] INFO: 591 hot pixels found in step 2
[10:46:52.506] INFO: Collecting data for 5 seconds...
[10:46:57.523] INFO: Done with hot pixel readout
[10:47:10.851] INFO: PixTest:: pg_setup set to default.





















































































[10:47:10.855] INFO: 511 hot pixels found in step 3
[10:47:11.853] INFO: Collecting data for 5 seconds...
[10:47:16.869] INFO: Done with hot pixel readout
[10:47:30.271] INFO: PixTest:: pg_setup set to default.






























































































[10:47:30.274] INFO: 452 hot pixels found in step 4
[10:47:31.274] INFO: Collecting data for 5 seconds...
[10:47:36.290] INFO: Done with hot pixel readout
[10:47:49.388] INFO: PixTest:: pg_setup set to default.



















































































































[10:47:49.392] INFO: 425 hot pixels found in step 5
[10:47:50.391] INFO: Collecting data for 5 seconds...
[10:47:55.408] INFO: Done with hot pixel readout
[10:48:08.503] INFO: PixTest:: pg_setup set to default.












































































































[10:48:08.507] INFO: 357 hot pixels found in step 6
[10:48:09.504] INFO: Collecting data for 5 seconds...
[10:48:14.520] INFO: Done with hot pixel readout
[10:48:27.714] INFO: PixTest:: pg_setup set to default.




























































































































[10:48:27.717] INFO: 338 hot pixels found in step 7
[10:48:28.718] INFO: Collecting data for 5 seconds...
[10:48:33.733] INFO: Done with hot pixel readout
[10:48:46.979] INFO: PixTest:: pg_setup set to default.


































































































































[10:48:46.982] INFO: 336 hot pixels found in step 8
[10:48:47.980] INFO: Collecting data for 5 seconds...
[10:48:52.996] INFO: Done with hot pixel readout
[10:49:06.079] INFO: PixTest:: pg_setup set to default.



























































































































[10:49:06.082] INFO: 303 hot pixels found in step 9
[10:49:07.082] INFO: Collecting data for 5 seconds...
[10:49:12.097] INFO: Done with hot pixel readout
[10:49:25.264] INFO: PixTest:: pg_setup set to default.








































































































































[10:49:25.268] INFO: 285 hot pixels found in step 10
[10:49:26.266] INFO: Collecting data for 5 seconds...
[10:49:31.281] INFO: Done with hot pixel readout
[10:49:44.360] INFO: PixTest:: pg_setup set to default.
















































































































































[10:49:44.370] INFO: 296 hot pixels found in step 11
[10:49:45.371] INFO: Collecting data for 5 seconds...
[10:49:50.388] INFO: Done with hot pixel readout
[10:50:03.603] INFO: PixTest:: pg_setup set to default.












































































































































[10:50:03.606] INFO: 296 hot pixels found in step 12
[10:50:04.605] INFO: Collecting data for 5 seconds...
[10:50:09.620] INFO: Done with hot pixel readout
[10:50:22.758] INFO: PixTest:: pg_setup set to default.











































































































































[10:50:22.761] INFO: 260 hot pixels found in step 13
[10:50:23.761] INFO: Collecting data for 5 seconds...
[10:50:28.776] INFO: Done with hot pixel readout
[10:50:41.968] INFO: PixTest:: pg_setup set to default.













































































































































[10:50:41.971] INFO: 255 hot pixels found in step 14
[10:50:42.015] INFO: 255 hot pixels could not be trimmed and have been masked.
[10:50:42.019] INFO: PixTest::trimHotPixels() done
[10:50:42.019] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C0.dat
[10:50:42.025] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C1.dat
[10:50:42.032] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C2.dat
[10:50:42.037] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C3.dat
[10:50:42.042] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C4.dat
[10:50:42.048] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C5.dat
[10:50:42.053] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C6.dat
[10:50:42.058] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C7.dat
[10:50:42.064] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C8.dat
[10:50:42.069] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C9.dat
[10:50:42.074] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C10.dat
[10:50:42.079] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C11.dat
[10:50:42.085] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C12.dat
[10:50:42.090] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C13.dat
[10:50:42.095] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C14.dat
[10:50:42.101] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C15.dat
[10:50:42.106] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//defaultMaskFile.dat
[10:50:42.121] INFO: enter test to run
[10:52:54.113] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:52:54.113] INFO: running: highrate
[10:52:54.118] INFO: ----------------------------------------------------------------------
[10:52:54.118] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:52:54.118] INFO: ----------------------------------------------------------------------
[10:52:54.118] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:52:54.118] INFO: edge/corner pixel THR is adjusted
[10:52:54.118] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:52:55.077] INFO: Collecting data for 1 seconds...
[10:52:56.080] INFO: Done with hot pixel readout
[10:53:01.025] INFO: PixTest:: pg_setup set to default.
[10:53:01.025] INFO: 0 hot pixels found in step 0
[10:53:01.031] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:53:01.089] INFO: PixTest::trimHotPixels() done
[10:53:01.089] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C0.dat
[10:53:01.101] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C1.dat
[10:53:01.107] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C2.dat
[10:53:01.112] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C3.dat
[10:53:01.118] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C4.dat
[10:53:01.123] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C5.dat
[10:53:01.129] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C6.dat
[10:53:01.134] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C7.dat
[10:53:01.139] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C8.dat
[10:53:01.144] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C9.dat
[10:53:01.150] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C10.dat
[10:53:01.156] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C11.dat
[10:53:01.161] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C12.dat
[10:53:01.166] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C13.dat
[10:53:01.171] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C14.dat
[10:53:01.176] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C15.dat
[10:53:01.183] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-I-NC_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//defaultMaskFile.dat
[10:53:01.192] INFO: enter test to run
[10:53:39.793] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:53:39.793] INFO: running: xray
[10:53:39.794] INFO: ----------------------------------------------------------------------
[10:53:39.794] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:53:39.794] INFO: ----------------------------------------------------------------------
[10:53:40.756] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:53:50.778] INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[10:54:20.348] INFO: Resuming triggers.
[10:54:30.376] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[10:55:00.034] INFO: Resuming triggers.
[10:55:10.061] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[10:55:39.918] INFO: Resuming triggers.
[10:55:49.947] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[10:56:19.572] INFO: Resuming triggers.
[10:56:29.596] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[10:56:59.317] INFO: Resuming triggers.
[10:57:09.345] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[10:57:39.071] INFO: Resuming triggers.
[10:57:49.093] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:58:18.840] INFO: Resuming triggers.
[10:58:28.866] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[10:58:58.472] INFO: Resuming triggers.
[10:59:08.497] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[10:59:38.248] INFO: Resuming triggers.
[10:59:48.277] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[11:00:17.931] INFO: Resuming triggers.
[11:00:17.982] INFO: data taking finished, elapsed time: 100 seconds.
[11:00:18.661] INFO: PixTest:: pg_setup set to default.
[11:00:18.664] INFO: PixTestXray::doPhRun() done
[11:00:18.814] INFO: enter test to run
[11:00:53.381] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:00:53.381] INFO: running: xray
[11:00:53.383] INFO: ----------------------------------------------------------------------
[11:00:53.383] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:00:53.383] INFO: ----------------------------------------------------------------------
[11:00:54.347] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:00:59.573] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[11:01:29.640] INFO: Resuming triggers.
[11:01:34.869] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[11:02:04.902] INFO: Resuming triggers.
[11:02:10.130] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[11:02:40.203] INFO: Resuming triggers.
[11:02:45.430] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[11:03:15.438] INFO: Resuming triggers.
[11:03:20.668] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[11:03:50.661] INFO: Resuming triggers.
[11:03:55.890] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[11:04:25.933] INFO: Resuming triggers.
[11:04:31.164] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[11:05:01.264] INFO: Resuming triggers.
[11:05:06.493] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[11:05:36.508] INFO: Resuming triggers.
[11:05:41.737] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[11:06:11.803] INFO: Resuming triggers.
[11:06:17.031] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[11:06:47.096] INFO: Resuming triggers.
[11:06:52.325] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[11:07:22.377] INFO: Resuming triggers.
[11:07:27.605] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[11:07:57.669] INFO: Resuming triggers.
[11:08:02.897] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[11:08:32.981] INFO: Resuming triggers.
[11:08:38.210] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[11:09:08.259] INFO: Resuming triggers.
[11:09:13.491] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[11:09:43.547] INFO: Resuming triggers.
[11:09:48.779] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[11:10:18.917] INFO: Resuming triggers.
[11:10:24.149] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[11:10:54.245] INFO: Resuming triggers.
[11:10:59.478] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[11:11:29.527] INFO: Resuming triggers.
[11:11:34.759] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[11:12:04.895] INFO: Resuming triggers.
[11:12:05.846] INFO: data taking finished, elapsed time: 100 seconds.
[11:12:11.667] INFO: PixTest:: pg_setup set to default.
[11:12:11.670] INFO: PixTestXray::doPhRun() done
[11:12:11.827] INFO: enter test to run
[11:12:47.208] INFO: test: HighRate no parameter change
[11:12:47.208] INFO: running: highrate
[11:12:47.209] INFO: ----------------------------------------------------------------------
[11:12:47.209] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:12:47.209] INFO: ----------------------------------------------------------------------
[11:12:47.349] INFO: Expecting 768 events.
[11:12:48.483] INFO: 768 events read in total (418ms).
[11:12:48.483] INFO: Test took 1268ms.
[11:12:49.287] INFO: Expecting 41600 events.
[11:12:52.559] INFO: 41600 events read in total (2746ms).
[11:12:52.560] INFO: Test took 4069ms.
[11:12:52.604] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:53.309] INFO: Expecting 41600 events.
[11:12:56.619] INFO: 41600 events read in total (2783ms).
[11:12:56.620] INFO: Test took 3995ms.
[11:12:56.666] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:57.367] INFO: Expecting 41600 events.
[11:13:00.713] INFO: 41600 events read in total (2819ms).
[11:13:00.714] INFO: Test took 4026ms.
[11:13:00.761] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:01.464] INFO: Expecting 41600 events.
[11:13:04.828] INFO: 41600 events read in total (2838ms).
[11:13:04.829] INFO: Test took 4045ms.
[11:13:04.875] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:05.577] INFO: Expecting 41600 events.
[11:13:08.942] INFO: 41600 events read in total (2838ms).
[11:13:08.943] INFO: Test took 4046ms.
[11:13:08.988] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:09.689] INFO: Expecting 41600 events.
[11:13:13.039] INFO: 41600 events read in total (2824ms).
[11:13:13.040] INFO: Test took 4031ms.
[11:13:13.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:13.789] INFO: Expecting 41600 events.
[11:13:17.154] INFO: 41600 events read in total (2838ms).
[11:13:17.155] INFO: Test took 4048ms.
[11:13:17.200] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:17.904] INFO: Expecting 41600 events.
[11:13:21.269] INFO: 41600 events read in total (2838ms).
[11:13:21.270] INFO: Test took 4047ms.
[11:13:21.316] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:22.018] INFO: Expecting 41600 events.
[11:13:25.373] INFO: 41600 events read in total (2828ms).
[11:13:25.374] INFO: Test took 4036ms.
[11:13:25.419] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:26.120] INFO: Expecting 41600 events.
[11:13:29.487] INFO: 41600 events read in total (2840ms).
[11:13:29.489] INFO: Test took 4049ms.
[11:13:29.533] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:30.235] INFO: Expecting 41600 events.
[11:13:33.596] INFO: 41600 events read in total (2834ms).
[11:13:33.597] INFO: Test took 4042ms.
[11:13:33.642] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:34.347] INFO: Expecting 41600 events.
[11:13:37.724] INFO: 41600 events read in total (2850ms).
[11:13:37.725] INFO: Test took 4060ms.
[11:13:37.770] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:38.475] INFO: Expecting 41600 events.
[11:13:41.837] INFO: 41600 events read in total (2835ms).
[11:13:41.838] INFO: Test took 4047ms.
[11:13:41.883] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:42.587] INFO: Expecting 41600 events.
[11:13:45.954] INFO: 41600 events read in total (2840ms).
[11:13:45.956] INFO: Test took 4050ms.
[11:13:45.001] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:46.704] INFO: Expecting 41600 events.
[11:13:50.071] INFO: 41600 events read in total (2840ms).
[11:13:50.072] INFO: Test took 4048ms.
[11:13:50.117] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:50.821] INFO: Expecting 41600 events.
[11:13:54.195] INFO: 41600 events read in total (2847ms).
[11:13:54.196] INFO: Test took 4056ms.
[11:13:54.240] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:54.942] INFO: Expecting 41600 events.
[11:13:58.313] INFO: 41600 events read in total (2844ms).
[11:13:58.314] INFO: Test took 4051ms.
[11:13:58.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:59.063] INFO: Expecting 41600 events.
[11:14:02.430] INFO: 41600 events read in total (2840ms).
[11:14:02.431] INFO: Test took 4048ms.
[11:14:02.476] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:03.179] INFO: Expecting 41600 events.
[11:14:06.522] INFO: 41600 events read in total (2816ms).
[11:14:06.523] INFO: Test took 4025ms.
[11:14:06.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:07.272] INFO: Expecting 41600 events.
[11:14:10.463] INFO: 41600 events read in total (2664ms).
[11:14:10.464] INFO: Test took 3872ms.
[11:14:10.509] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:10.857] INFO: enter test to run
[11:14:15.551] INFO: test: HighRate no parameter change
[11:14:15.552] INFO: running: highrate
[11:14:15.553] INFO: ----------------------------------------------------------------------
[11:14:15.553] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:14:15.553] INFO: ----------------------------------------------------------------------
[11:14:16.166] INFO: Expecting 208000 events.
[11:14:28.682] INFO: 208000 events read in total (11990ms).
[11:14:28.686] INFO: Test took 13127ms.
[11:14:28.883] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:29.152] INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 6 2 1 0 0 0
[11:14:29.152] INFO: number of red-efficiency pixels: 91 76 142 213 215 239 213 142 125 214 180 143 137 76 43 51
[11:14:29.152] INFO: number of X-ray hits detected: 83139 58158 98351 160850 166623 170467 165830 112069 103791 134966 134839 122641 121686 72697 32206 38190
[11:14:29.152] 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:14:29.152] INFO: number of Vcal hits detected: 207907 207924 207858 207658 207780 207733 207782 207853 207872 207779 207520 207757 207810 207922 207955 207946
[11:14:29.152] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[11:14:29.152] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 100.0 100.0 100.0
[11:14:29.152] INFO: X-ray hit rate [MHz/cm2]: 24.4 17.0 28.8 47.1 48.8 50.0 48.6 32.8 30.4 39.6 39.5 35.9 35.7 21.3 9.4 11.2
[11:14:29.152] INFO: PixTestHighRate::doXPixelAlive() done
[11:14:29.200] INFO: PixTest:: pg_setup set to default.
[11:14:29.211] INFO: enter test to run
[11:14:52.535] INFO: test: HighRate no parameter change
[11:14:52.535] INFO: running: highrate
[11:14:52.536] INFO: ----------------------------------------------------------------------
[11:14:52.536] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:14:52.536] INFO: ----------------------------------------------------------------------
[11:14:53.150] INFO: Expecting 208000 events.
[11:15:08.453] INFO: 208000 events read in total (14776ms).
[11:15:08.460] INFO: Test took 15915ms.
[11:15:08.896] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:09.238] INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 6 2 1 0 0 0
[11:15:09.238] INFO: number of red-efficiency pixels: 378 176 514 878 981 997 977 560 455 836 590 570 558 261 76 115
[11:15:09.238] INFO: number of X-ray hits detected: 175431 123235 208349 340437 353632 360070 349716 237295 220852 288233 287088 259723 257942 153725 68623 81739
[11:15:09.238] 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:15:09.238] INFO: number of Vcal hits detected: 207583 207817 207350 206837 206817 206709 206794 207340 207491 207020 207051 207269 207335 207706 207923 207881
[11:15:09.238] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.5 99.4 99.5 99.7 99.8 99.6 99.7 99.7 99.7 99.9 100.0 99.9
[11:15:09.238] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.4 99.4 99.4 99.4 99.7 99.8 99.5 99.5 99.6 99.7 99.9 100.0 99.9
[11:15:09.238] INFO: X-ray hit rate [MHz/cm2]: 51.4 36.1 61.1 99.8 103.7 105.5 102.5 69.6 64.7 84.5 84.1 76.1 75.6 45.1 20.1 24.0
[11:15:09.238] INFO: PixTestHighRate::doXPixelAlive() done
[11:15:09.287] INFO: PixTest:: pg_setup set to default.
[11:15:09.302] INFO: enter test to run
[11:15:40.575] INFO: test: HighRate no parameter change
[11:15:40.575] INFO: running: highrate
[11:15:40.576] INFO: ----------------------------------------------------------------------
[11:15:40.576] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:15:40.576] INFO: ----------------------------------------------------------------------
[11:15:41.189] INFO: Expecting 208000 events.
[11:15:59.638] INFO: 208000 events read in total (17922ms).
[11:15:59.648] INFO: Test took 19062ms.
[11:16:00.297] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:00.709] INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 6 2 1 0 0 0
[11:16:00.709] INFO: number of red-efficiency pixels: 858 468 1149 2029 2139 2253 2146 1178 1103 1959 1372 1255 1386 612 174 210
[11:16:00.709] INFO: number of X-ray hits detected: 265493 187229 316069 515998 532727 545650 529263 361141 334801 436947 434626 391763 389078 233895 104844 123734
[11:16:00.709] 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:00.709] INFO: number of Vcal hits detected: 206862 207453 205914 204547 204289 204058 204335 206125 206510 204891 205801 206153 206054 207188 207814 207785
[11:16:00.709] INFO: Vcal hit fiducial efficiency (%): 99.5 99.8 99.1 98.6 98.4 98.3 98.4 99.2 99.4 98.6 99.2 99.3 99.2 99.7 99.9 99.9
[11:16:00.709] INFO: Vcal hit overall efficiency (%): 99.5 99.7 99.0 98.3 98.2 98.1 98.2 99.1 99.3 98.5 98.9 99.1 99.1 99.6 99.9 99.9
[11:16:00.709] INFO: X-ray hit rate [MHz/cm2]: 77.8 54.9 92.6 151.2 156.1 159.9 155.1 105.9 98.1 128.1 127.4 114.8 114.0 68.6 30.7 36.3
[11:16:00.709] INFO: PixTestHighRate::doXPixelAlive() done
[11:16:00.757] INFO: PixTest:: pg_setup set to default.
[11:16:00.772] INFO: enter test to run
[11:16:25.846] INFO: test: exit no parameter change
[11:16:26.164] QUIET: Connection to board 33 closed.
[11:16:26.166] INFO: pXar: this is the end, my friend