[11:49:22.810] INFO: *** Welcome to pxar ***
[11:49:22.810] INFO: *** Today: 2016/05/24
[11:49:22.936] INFO: *** Version: v1.9.0-796-gef167-dirty
[11:49:22.936] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//dacParameters35_C15.dat
[11:49:22.985] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:49:22.985] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//defaultMaskFile.dat
[11:49:22.987] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C15.dat
[11:49:23.068] INFO: clk: 4
[11:49:23.068] INFO: ctr: 4
[11:49:23.068] INFO: sda: 19
[11:49:23.068] INFO: tin: 9
[11:49:23.068] INFO: level: 15
[11:49:23.068] INFO: triggerdelay: 0
[11:49:23.068] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[11:49:23.068] INFO: Log level: INFO
[11:49:23.084] QUIET: Connection to board DTB_WREKRL opened.
[11:49:23.087] 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:
------------------------------------------------------
[11:49:23.090] INFO: RPC call hashes of host and DTB match: 398089610
[11:49:24.710] INFO: DUT info:
[11:49:24.710] INFO: The DUT currently contains the following objects:
[11:49:24.710] INFO: 2 TBM Cores tbm08c (2 ON)
[11:49:24.710] INFO: TBM Core alpha (0): 7 registers set
[11:49:24.710] INFO: TBM Core beta (1): 7 registers set
[11:49:24.710] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:49:24.710] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.710] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.710] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.710] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.710] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.710] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.710] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.710] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.710] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.711] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.711] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.711] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.711] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.711] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.711] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:24.711] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:49:25.114] INFO: enter 'restricted' command line mode
[11:49:25.114] INFO: enter test to run
[11:49:37.291] INFO: test: PixelAlive no parameter change
[11:49:37.291] INFO: running: pixelalive
[11:49:37.298] INFO: ----------------------------------------------------------------------
[11:49:37.298] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:49:37.298] INFO: ----------------------------------------------------------------------
[11:49:37.616] INFO: Expecting 41600 events.
[11:49:41.917] INFO: 41600 events read in total (3583ms).
[11:49:42.081] INFO: Test took 4781ms.
[11:49:42.097] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:49:42.361] INFO: PixTestAlive::aliveTest() done
[11:49:42.361] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:49:42.389] INFO: enter test to run
[11:50:30.499] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:50:30.499] INFO: running: highrate
[11:50:30.500] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:50:30.707] INFO: ----------------------------------------------------------------------
[11:50:30.707] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:50:30.707] INFO: ----------------------------------------------------------------------
[11:50:30.707] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[11:50:30.707] INFO: edge/corner pixel THR is adjusted
[11:50:30.707] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:50:31.666] INFO: Collecting data for 5 seconds...
[11:50:36.683] INFO: Done with hot pixel readout
[11:50:48.092] INFO: PixTest:: pg_setup set to default.
[11:50:48.093] INFO: 2 hot pixels found in step 0
[11:50:49.119] INFO: Collecting data for 5 seconds...
[11:50:54.137] INFO: Done with hot pixel readout
[11:51:05.730] INFO: PixTest:: pg_setup set to default.
[11:51:05.731] INFO: 5 hot pixels found in step 1
[11:51:06.730] INFO: Collecting data for 5 seconds...
[11:51:11.747] INFO: Done with hot pixel readout
[11:51:23.689] INFO: PixTest:: pg_setup set to default.
[11:51:23.689] INFO: 0 hot pixels found in step 2
[11:51:23.728] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:51:23.732] INFO: PixTest::trimHotPixels() done
[11:51:23.732] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C0.dat
[11:51:23.738] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C1.dat
[11:51:23.744] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C2.dat
[11:51:23.750] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C3.dat
[11:51:23.756] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C4.dat
[11:51:23.761] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C5.dat
[11:51:23.766] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C6.dat
[11:51:23.771] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C7.dat
[11:51:23.776] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C8.dat
[11:51:23.782] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C9.dat
[11:51:23.787] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C10.dat
[11:51:23.792] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C11.dat
[11:51:23.797] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C12.dat
[11:51:23.803] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C13.dat
[11:51:23.808] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C14.dat
[11:51:23.813] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C15.dat
[11:51:23.818] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//defaultMaskFile.dat
[11:51:23.829] INFO: enter test to run
[11:52:12.035] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:52:12.035] INFO: running: highrate
[11:52:12.039] INFO: ----------------------------------------------------------------------
[11:52:12.039] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:52:12.039] INFO: ----------------------------------------------------------------------
[11:52:12.039] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:52:12.039] INFO: edge/corner pixel THR is adjusted
[11:52:12.039] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:52:12.996] INFO: Collecting data for 1 seconds...
[11:52:13.000] INFO: Done with hot pixel readout
[11:52:18.262] INFO: PixTest:: pg_setup set to default.
[11:52:18.263] INFO: 0 hot pixels found in step 0
[11:52:18.270] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:52:18.342] INFO: PixTest::trimHotPixels() done
[11:52:18.342] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C0.dat
[11:52:18.351] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C1.dat
[11:52:18.357] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C2.dat
[11:52:18.362] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C3.dat
[11:52:18.367] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C4.dat
[11:52:18.372] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C5.dat
[11:52:18.378] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C6.dat
[11:52:18.383] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C7.dat
[11:52:18.388] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C8.dat
[11:52:18.394] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C9.dat
[11:52:18.399] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C10.dat
[11:52:18.404] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C11.dat
[11:52:18.410] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C12.dat
[11:52:18.415] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C13.dat
[11:52:18.420] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C14.dat
[11:52:18.425] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//trimParameters35_C15.dat
[11:52:18.431] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-A-NN_FPIXTest-17C-FNAL-160519-0755_2016-05-19_07h55m_1463662516/000_FPIXTest_p17//defaultMaskFile.dat
[11:52:18.441] INFO: enter test to run
[11:53:22.170] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:53:22.170] INFO: running: xray
[11:53:22.171] INFO: ----------------------------------------------------------------------
[11:53:22.171] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:53:22.171] INFO: ----------------------------------------------------------------------
[11:53:23.135] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:53:34.271] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:54:03.644] INFO: Resuming triggers.
[11:54:14.783] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[11:54:44.269] INFO: Resuming triggers.
[11:54:55.408] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[11:55:24.819] INFO: Resuming triggers.
[11:55:35.951] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[11:56:05.437] INFO: Resuming triggers.
[11:56:16.573] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[11:56:46.048] INFO: Resuming triggers.
[11:56:57.189] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[11:57:26.729] INFO: Resuming triggers.
[11:57:37.865] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[11:58:07.748] INFO: Resuming triggers.
[11:58:18.884] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[11:58:47.834] INFO: Resuming triggers.
[11:58:58.974] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[11:59:28.506] INFO: Resuming triggers.
[11:59:28.607] INFO: data taking finished, elapsed time: 100 seconds.
[11:59:29.395] INFO: PixTest:: pg_setup set to default.
[11:59:29.398] INFO: PixTestXray::doPhRun() done
[11:59:29.544] INFO: enter test to run
[12:00:12.695] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:00:12.695] INFO: running: xray
[12:00:12.696] INFO: ----------------------------------------------------------------------
[12:00:12.696] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:00:12.696] INFO: ----------------------------------------------------------------------
[12:00:13.680] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:00:19.893] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[12:00:51.077] INFO: Resuming triggers.
[12:00:57.287] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[12:01:27.325] INFO: Resuming triggers.
[12:01:33.534] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[12:02:03.630] INFO: Resuming triggers.
[12:02:09.838] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[12:02:39.943] INFO: Resuming triggers.
[12:02:46.155] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[12:03:16.220] INFO: Resuming triggers.
[12:03:22.429] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[12:03:52.451] INFO: Resuming triggers.
[12:03:58.659] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[12:04:28.666] INFO: Resuming triggers.
[12:04:34.875] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[12:05:04.926] INFO: Resuming triggers.
[12:05:11.136] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[12:05:41.186] INFO: Resuming triggers.
[12:05:47.400] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[12:06:17.432] INFO: Resuming triggers.
[12:06:23.642] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[12:06:53.315] INFO: Resuming triggers.
[12:06:59.523] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[12:07:29.564] INFO: Resuming triggers.
[12:07:35.774] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[12:08:05.819] INFO: Resuming triggers.
[12:08:12.029] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[12:08:42.026] INFO: Resuming triggers.
[12:08:48.233] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[12:09:18.869] INFO: Resuming triggers.
[12:09:25.077] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[12:09:55.266] INFO: Resuming triggers.
[12:09:56.252] INFO: data taking finished, elapsed time: 100 seconds.
[12:10:01.532] INFO: PixTest:: pg_setup set to default.
[12:10:01.535] INFO: PixTestXray::doPhRun() done
[12:10:01.692] INFO: enter test to run
[12:10:35.554] INFO: test: HighRate no parameter change
[12:10:35.555] INFO: running: highrate
[12:10:35.556] INFO: ----------------------------------------------------------------------
[12:10:35.556] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:10:35.556] INFO: ----------------------------------------------------------------------
[12:10:35.695] INFO: Expecting 768 events.
[12:10:36.835] INFO: 768 events read in total (419ms).
[12:10:36.835] INFO: Test took 1273ms.
[12:10:37.638] INFO: Expecting 41600 events.
[12:10:40.807] INFO: 41600 events read in total (2642ms).
[12:10:40.808] INFO: Test took 3966ms.
[12:10:40.846] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:41.561] INFO: Expecting 41600 events.
[12:10:44.869] INFO: 41600 events read in total (2781ms).
[12:10:44.870] INFO: Test took 4005ms.
[12:10:44.908] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:45.623] INFO: Expecting 41600 events.
[12:10:48.918] INFO: 41600 events read in total (2768ms).
[12:10:48.919] INFO: Test took 3993ms.
[12:10:48.957] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:49.675] INFO: Expecting 41600 events.
[12:10:52.974] INFO: 41600 events read in total (2772ms).
[12:10:52.974] INFO: Test took 3999ms.
[12:10:53.012] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:53.731] INFO: Expecting 41600 events.
[12:10:57.005] INFO: 41600 events read in total (2747ms).
[12:10:57.006] INFO: Test took 3976ms.
[12:10:57.044] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:57.762] INFO: Expecting 41600 events.
[12:11:01.093] INFO: 41600 events read in total (2804ms).
[12:11:01.094] INFO: Test took 4032ms.
[12:11:01.132] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:01.846] INFO: Expecting 41600 events.
[12:11:05.152] INFO: 41600 events read in total (2780ms).
[12:11:05.152] INFO: Test took 4001ms.
[12:11:05.191] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:05.907] INFO: Expecting 41600 events.
[12:11:09.194] INFO: 41600 events read in total (2760ms).
[12:11:09.195] INFO: Test took 3986ms.
[12:11:09.233] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:09.947] INFO: Expecting 41600 events.
[12:11:13.156] INFO: 41600 events read in total (2682ms).
[12:11:13.157] INFO: Test took 3905ms.
[12:11:13.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:13.910] INFO: Expecting 41600 events.
[12:11:17.152] INFO: 41600 events read in total (2715ms).
[12:11:17.153] INFO: Test took 3939ms.
[12:11:17.191] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:17.908] INFO: Expecting 41600 events.
[12:11:21.243] INFO: 41600 events read in total (2808ms).
[12:11:21.244] INFO: Test took 4034ms.
[12:11:21.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:21.998] INFO: Expecting 41600 events.
[12:11:25.289] INFO: 41600 events read in total (2764ms).
[12:11:25.290] INFO: Test took 3989ms.
[12:11:25.327] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:26.045] INFO: Expecting 41600 events.
[12:11:29.355] INFO: 41600 events read in total (2784ms).
[12:11:29.355] INFO: Test took 4009ms.
[12:11:29.394] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:30.110] INFO: Expecting 41600 events.
[12:11:33.433] INFO: 41600 events read in total (2797ms).
[12:11:33.434] INFO: Test took 4021ms.
[12:11:33.472] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:34.185] INFO: Expecting 41600 events.
[12:11:37.478] INFO: 41600 events read in total (2766ms).
[12:11:37.479] INFO: Test took 3989ms.
[12:11:37.517] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:38.233] INFO: Expecting 41600 events.
[12:11:41.532] INFO: 41600 events read in total (2773ms).
[12:11:41.533] INFO: Test took 3998ms.
[12:11:41.571] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:42.282] INFO: Expecting 41600 events.
[12:11:45.578] INFO: 41600 events read in total (2769ms).
[12:11:45.579] INFO: Test took 3988ms.
[12:11:45.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:46.333] INFO: Expecting 41600 events.
[12:11:49.624] INFO: 41600 events read in total (2764ms).
[12:11:49.625] INFO: Test took 3989ms.
[12:11:49.662] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:50.380] INFO: Expecting 41600 events.
[12:11:53.681] INFO: 41600 events read in total (2774ms).
[12:11:53.682] INFO: Test took 4001ms.
[12:11:53.720] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:54.439] INFO: Expecting 41600 events.
[12:11:57.624] INFO: 41600 events read in total (2658ms).
[12:11:57.625] INFO: Test took 3886ms.
[12:11:57.662] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:58.018] INFO: enter test to run
[12:12:06.210] INFO: test: HighRate no parameter change
[12:12:06.210] INFO: running: highrate
[12:12:06.211] INFO: ----------------------------------------------------------------------
[12:12:06.211] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:12:06.211] INFO: ----------------------------------------------------------------------
[12:12:06.835] INFO: Expecting 208000 events.
[12:12:18.910] INFO: 208000 events read in total (11548ms).
[12:12:18.913] INFO: Test took 12696ms.
[12:12:19.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:12:19.326] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:12:19.326] INFO: number of red-efficiency pixels: 98 46 100 164 172 148 164 133 99 122 123 140 103 74 30 44
[12:12:19.326] INFO: number of X-ray hits detected: 76854 49162 73109 119009 119896 126565 127926 90056 80481 109358 101994 94755 95041 57642 24797 27556
[12:12:19.326] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:12:19.326] INFO: number of Vcal hits detected: 207901 207954 207900 207832 207826 207848 207832 207865 207899 207876 207875 207859 207895 207926 207970 207955
[12:12:19.326] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[12:12:19.326] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[12:12:19.326] INFO: X-ray hit rate [MHz/cm2]: 22.5 14.4 21.4 34.9 35.1 37.1 37.5 26.4 23.6 32.1 29.9 27.8 27.9 16.9 7.3 8.1
[12:12:19.326] INFO: PixTestHighRate::doXPixelAlive() done
[12:12:19.376] INFO: PixTest:: pg_setup set to default.
[12:12:19.387] INFO: enter test to run
[12:12:47.825] INFO: test: HighRate no parameter change
[12:12:47.825] INFO: running: highrate
[12:12:47.827] INFO: ----------------------------------------------------------------------
[12:12:47.827] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:12:47.827] INFO: ----------------------------------------------------------------------
[12:12:48.446] INFO: Expecting 208000 events.
[12:13:02.418] INFO: 208000 events read in total (13445ms).
[12:13:02.423] INFO: Test took 14588ms.
[12:13:02.761] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:03.072] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:13:03.072] INFO: number of red-efficiency pixels: 319 128 369 616 657 478 514 422 252 481 331 433 258 237 58 88
[12:13:03.072] INFO: number of X-ray hits detected: 159499 100930 152183 245766 247300 261482 265180 186864 165151 227360 211518 196009 196455 117894 51269 57112
[12:13:03.073] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:13:03.073] INFO: number of Vcal hits detected: 207645 207871 207593 207295 207263 207469 207433 207538 207735 207484 207649 207526 207725 207739 207938 207907
[12:13:03.073] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.8 99.7 99.8 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[12:13:03.073] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.7 99.6 99.7 99.7 99.8 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[12:13:03.073] INFO: X-ray hit rate [MHz/cm2]: 46.8 29.6 44.6 72.0 72.5 76.6 77.7 54.8 48.4 66.6 62.0 57.5 57.6 34.6 15.0 16.7
[12:13:03.073] INFO: PixTestHighRate::doXPixelAlive() done
[12:13:03.128] INFO: PixTest:: pg_setup set to default.
[12:13:03.140] INFO: enter test to run
[12:13:26.177] INFO: test: HighRate no parameter change
[12:13:26.177] INFO: running: highrate
[12:13:26.178] INFO: ----------------------------------------------------------------------
[12:13:26.178] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:13:26.178] INFO: ----------------------------------------------------------------------
[12:13:26.793] INFO: Expecting 208000 events.
[12:13:43.074] INFO: 208000 events read in total (15754ms).
[12:13:43.082] INFO: Test took 16894ms.
[12:13:43.609] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:43.976] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:13:43.976] INFO: number of red-efficiency pixels: 710 283 821 1450 1503 1179 1310 976 612 1280 791 1041 738 587 113 116
[12:13:43.976] INFO: number of X-ray hits detected: 245933 156611 235976 381250 383337 404734 411170 290006 256623 351926 326592 304548 302253 184361 80089 89114
[12:13:43.977] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:13:43.977] INFO: number of Vcal hits detected: 207025 207692 206840 206012 205941 206482 206290 206700 207292 206381 207064 206705 207172 207261 207881 207882
[12:13:43.977] INFO: Vcal hit fiducial efficiency (%): 99.6 99.9 99.5 99.1 99.1 99.3 99.3 99.4 99.7 99.3 99.6 99.4 99.6 99.7 99.9 99.9
[12:13:43.977] INFO: Vcal hit overall efficiency (%): 99.5 99.9 99.4 99.0 99.0 99.3 99.2 99.4 99.7 99.2 99.6 99.4 99.6 99.6 99.9 99.9
[12:13:43.977] INFO: X-ray hit rate [MHz/cm2]: 72.1 45.9 69.2 111.7 112.4 118.6 120.5 85.0 75.2 103.2 95.7 89.3 88.6 54.0 23.5 26.1
[12:13:43.977] INFO: PixTestHighRate::doXPixelAlive() done
[12:13:44.022] INFO: PixTest:: pg_setup set to default.
[12:13:44.033] INFO: enter test to run
[12:13:46.953] INFO: test: exit no parameter change
[12:13:47.293] QUIET: Connection to board 33 closed.
[12:13:47.302] INFO: pXar: this is the end, my friend