[14:19:28.627] INFO: *** Welcome to pxar ***
[14:19:28.627] INFO: *** Today: 2016/09/08
[14:19:29.354] INFO: *** Version: v1.9.0-818-g96727
[14:19:29.354] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//dacParameters35_C15.dat
[14:19:29.400] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:19:29.400] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//defaultMaskFile.dat
[14:19:29.400] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C15.dat
[14:19:29.510] INFO: clk: 4
[14:19:29.510] INFO: ctr: 4
[14:19:29.510] INFO: sda: 19
[14:19:29.510] INFO: tin: 9
[14:19:29.510] INFO: level: 15
[14:19:29.510] INFO: triggerdelay: 0
[14:19:29.510] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[14:19:29.510] INFO: Log level: INFO
[14:19:29.529] QUIET: Connection to board DTB_WREKRL opened.
[14:19:29.532] 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:
------------------------------------------------------
[14:19:29.535] INFO: RPC call hashes of host and DTB match: 398089610
[14:19:31.070] INFO: DUT info:
[14:19:31.070] INFO: The DUT currently contains the following objects:
[14:19:31.070] INFO: 2 TBM Cores tbm08c (2 ON)
[14:19:31.071] INFO: TBM Core alpha (0): 7 registers set
[14:19:31.071] INFO: TBM Core beta (1): 7 registers set
[14:19:31.071] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:19:31.071] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.071] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:31.475] INFO: enter 'restricted' command line mode
[14:19:31.475] INFO: enter test to run
[14:19:40.798] INFO: test: PixelAlive no parameter change
[14:19:40.798] INFO: running: pixelalive
[14:19:40.807] INFO: ----------------------------------------------------------------------
[14:19:40.807] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:19:40.807] INFO: ----------------------------------------------------------------------
[14:19:41.126] INFO: Expecting 41600 events.
[14:19:45.444] INFO: 41600 events read in total (3599ms).
[14:19:45.609] INFO: Test took 4800ms.
[14:19:45.618] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:45.885] INFO: PixTestAlive::aliveTest() done
[14:19:45.885] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 1 0 0 0 0
[14:19:45.911] INFO: enter test to run
[14:20:42.190] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:20:42.190] INFO: running: highrate
[14:20:42.190] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:20:42.410] INFO: ----------------------------------------------------------------------
[14:20:42.410] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:20:42.410] INFO: ----------------------------------------------------------------------
[14:20:42.410] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:20:42.410] INFO: edge/corner pixel THR is adjusted
[14:20:42.410] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:20:43.370] INFO: Collecting data for 5 seconds...
[14:20:48.386] INFO: Done with hot pixel readout
[14:20:59.699] INFO: PixTest:: pg_setup set to default.
[14:20:59.700] INFO: 0 hot pixels found in step 0
[14:20:59.712] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:20:59.716] INFO: PixTest::trimHotPixels() done
[14:20:59.733] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C0.dat
[14:20:59.739] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C1.dat
[14:20:59.744] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C2.dat
[14:20:59.749] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C3.dat
[14:20:59.754] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C4.dat
[14:20:59.759] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C5.dat
[14:20:59.764] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C6.dat
[14:20:59.770] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C7.dat
[14:20:59.775] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C8.dat
[14:20:59.780] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C9.dat
[14:20:59.785] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C10.dat
[14:20:59.790] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C11.dat
[14:20:59.795] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C12.dat
[14:20:59.801] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C13.dat
[14:20:59.806] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C14.dat
[14:20:59.811] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C15.dat
[14:20:59.816] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//defaultMaskFile.dat
[14:20:59.826] INFO: enter test to run
[14:24:52.188] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:24:52.188] INFO: running: highrate
[14:24:52.192] INFO: ----------------------------------------------------------------------
[14:24:52.192] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:24:52.192] INFO: ----------------------------------------------------------------------
[14:24:52.192] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:24:52.192] INFO: edge/corner pixel THR is adjusted
[14:24:52.192] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:24:53.150] INFO: Collecting data for 1 seconds...
[14:24:54.154] INFO: Done with hot pixel readout
[14:24:57.959] INFO: PixTest:: pg_setup set to default.
[14:24:57.960] INFO: 0 hot pixels found in step 0
[14:24:57.966] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:24:58.064] INFO: PixTest::trimHotPixels() done
[14:24:58.064] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C0.dat
[14:24:58.073] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C1.dat
[14:24:58.078] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C2.dat
[14:24:58.084] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C3.dat
[14:24:58.089] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C4.dat
[14:24:58.094] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C5.dat
[14:24:58.100] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C6.dat
[14:24:58.105] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C7.dat
[14:24:58.110] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C8.dat
[14:24:58.115] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C9.dat
[14:24:58.120] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C10.dat
[14:24:58.126] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C11.dat
[14:24:58.131] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C12.dat
[14:24:58.136] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C13.dat
[14:24:58.141] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C14.dat
[14:24:58.147] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//trimParameters35_C15.dat
[14:24:58.152] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-19_FPIXTest-17C-FNAL-160906-1541-300V_2016-09-06_15h41m_1473194476/000_FPIXTest_p17//defaultMaskFile.dat
[14:24:58.203] INFO: enter test to run
[14:25:20.372] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:25:20.372] INFO: running: xray
[14:25:20.373] INFO: ----------------------------------------------------------------------
[14:25:20.373] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:25:20.373] INFO: ----------------------------------------------------------------------
[14:25:21.337] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:25:33.105] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:26:02.386] INFO: Resuming triggers.
[14:26:14.154] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:26:43.423] INFO: Resuming triggers.
[14:26:55.195] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[14:27:24.297] INFO: Resuming triggers.
[14:27:36.070] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:28:05.684] INFO: Resuming triggers.
[14:28:17.453] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[14:28:46.906] INFO: Resuming triggers.
[14:28:58.675] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[14:29:28.320] INFO: Resuming triggers.
[14:29:40.091] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[14:30:10.107] INFO: Resuming triggers.
[14:30:21.875] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[14:30:51.615] INFO: Resuming triggers.
[14:30:57.822] INFO: data taking finished, elapsed time: 100 seconds.
[14:31:14.009] INFO: PixTest:: pg_setup set to default.
[14:31:14.012] INFO: PixTestXray::doPhRun() done
[14:31:14.161] INFO: enter test to run
[14:32:23.353] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:32:23.353] INFO: running: xray
[14:32:23.354] INFO: ----------------------------------------------------------------------
[14:32:23.354] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:32:23.354] INFO: ----------------------------------------------------------------------
[14:32:24.318] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:32:31.466] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[14:33:01.553] INFO: Resuming triggers.
[14:33:08.702] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[14:33:38.863] INFO: Resuming triggers.
[14:33:46.012] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[14:34:16.186] INFO: Resuming triggers.
[14:34:23.338] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[14:34:53.482] INFO: Resuming triggers.
[14:35:00.633] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[14:35:30.755] INFO: Resuming triggers.
[14:35:37.908] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[14:36:08.112] INFO: Resuming triggers.
[14:36:15.265] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[14:36:45.491] INFO: Resuming triggers.
[14:36:52.644] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[14:37:22.776] INFO: Resuming triggers.
[14:37:29.930] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[14:38:00.193] INFO: Resuming triggers.
[14:38:07.347] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[14:38:37.596] INFO: Resuming triggers.
[14:38:44.752] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[14:39:14.895] INFO: Resuming triggers.
[14:39:22.049] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[14:39:52.497] INFO: Resuming triggers.
[14:39:59.652] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[14:40:30.425] INFO: Resuming triggers.
[14:40:37.581] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[14:41:08.476] INFO: Resuming triggers.
[14:41:08.673] INFO: data taking finished, elapsed time: 100 seconds.
[14:41:09.881] INFO: PixTest:: pg_setup set to default.
[14:41:09.884] INFO: PixTestXray::doPhRun() done
[14:41:10.035] INFO: enter test to run
[14:41:45.645] INFO: test: HighRate no parameter change
[14:41:45.645] INFO: running: highrate
[14:41:45.667] INFO: ----------------------------------------------------------------------
[14:41:45.667] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:41:45.667] INFO: ----------------------------------------------------------------------
[14:41:45.813] INFO: Expecting 768 events.
[14:41:46.948] INFO: 768 events read in total (419ms).
[14:41:46.948] INFO: Test took 1269ms.
[14:41:47.751] INFO: Expecting 41600 events.
[14:41:50.846] INFO: 41600 events read in total (2568ms).
[14:41:50.847] INFO: Test took 3866ms.
[14:41:50.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:51.598] INFO: Expecting 41600 events.
[14:41:54.797] INFO: 41600 events read in total (2672ms).
[14:41:54.798] INFO: Test took 3904ms.
[14:41:54.829] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:55.548] INFO: Expecting 41600 events.
[14:41:58.834] INFO: 41600 events read in total (2759ms).
[14:41:58.835] INFO: Test took 3989ms.
[14:41:58.867] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:59.593] INFO: Expecting 41600 events.
[14:42:02.885] INFO: 41600 events read in total (2765ms).
[14:42:02.886] INFO: Test took 4002ms.
[14:42:02.917] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:03.640] INFO: Expecting 41600 events.
[14:42:06.849] INFO: 41600 events read in total (2683ms).
[14:42:06.849] INFO: Test took 3916ms.
[14:42:06.882] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:07.607] INFO: Expecting 41600 events.
[14:42:10.841] INFO: 41600 events read in total (2707ms).
[14:42:10.842] INFO: Test took 3944ms.
[14:42:10.873] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:11.600] INFO: Expecting 41600 events.
[14:42:14.889] INFO: 41600 events read in total (2762ms).
[14:42:14.890] INFO: Test took 3999ms.
[14:42:14.922] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:15.638] INFO: Expecting 41600 events.
[14:42:18.919] INFO: 41600 events read in total (2754ms).
[14:42:18.920] INFO: Test took 3980ms.
[14:42:18.952] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:19.677] INFO: Expecting 41600 events.
[14:42:22.930] INFO: 41600 events read in total (2726ms).
[14:42:22.931] INFO: Test took 3962ms.
[14:42:22.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:23.690] INFO: Expecting 41600 events.
[14:42:26.968] INFO: 41600 events read in total (2751ms).
[14:42:26.968] INFO: Test took 3988ms.
[14:42:26.000] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:27.718] INFO: Expecting 41600 events.
[14:42:30.902] INFO: 41600 events read in total (2657ms).
[14:42:30.903] INFO: Test took 3886ms.
[14:42:30.936] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:31.664] INFO: Expecting 41600 events.
[14:42:34.907] INFO: 41600 events read in total (2716ms).
[14:42:34.908] INFO: Test took 3956ms.
[14:42:34.939] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:35.670] INFO: Expecting 41600 events.
[14:42:38.963] INFO: 41600 events read in total (2766ms).
[14:42:38.964] INFO: Test took 4009ms.
[14:42:38.996] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:39.716] INFO: Expecting 41600 events.
[14:42:42.920] INFO: 41600 events read in total (2677ms).
[14:42:42.921] INFO: Test took 3909ms.
[14:42:42.953] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:43.678] INFO: Expecting 41600 events.
[14:42:46.984] INFO: 41600 events read in total (2779ms).
[14:42:46.985] INFO: Test took 4016ms.
[14:42:47.017] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:47.736] INFO: Expecting 41600 events.
[14:42:50.954] INFO: 41600 events read in total (2691ms).
[14:42:50.955] INFO: Test took 3921ms.
[14:42:50.987] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:51.716] INFO: Expecting 41600 events.
[14:42:54.971] INFO: 41600 events read in total (2728ms).
[14:42:54.972] INFO: Test took 3969ms.
[14:42:54.005] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:55.706] INFO: Expecting 41600 events.
[14:42:59.078] INFO: 41600 events read in total (2846ms).
[14:42:59.080] INFO: Test took 4059ms.
[14:42:59.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:59.828] INFO: Expecting 41600 events.
[14:43:03.043] INFO: 41600 events read in total (2688ms).
[14:43:03.044] INFO: Test took 3914ms.
[14:43:03.076] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:03.801] INFO: Expecting 41600 events.
[14:43:06.871] INFO: 41600 events read in total (2544ms).
[14:43:06.872] INFO: Test took 3779ms.
[14:43:06.903] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:07.272] INFO: enter test to run
[14:43:27.860] INFO: test: HighRate no parameter change
[14:43:27.861] INFO: running: highrate
[14:43:27.862] INFO: ----------------------------------------------------------------------
[14:43:27.862] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:43:27.862] INFO: ----------------------------------------------------------------------
[14:43:28.486] INFO: Expecting 208000 events.
[14:43:40.531] INFO: 208000 events read in total (11518ms).
[14:43:40.534] INFO: Test took 12664ms.
[14:43:40.666] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:40.916] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 1 0 0 0 0
[14:43:40.916] INFO: number of red-efficiency pixels: 67 29 61 154 112 129 128 101 75 100 109 99 95 60 29 18
[14:43:40.916] INFO: number of X-ray hits detected: 61047 37267 56052 89328 92951 97226 95876 69798 67366 91469 88508 79315 83716 49362 19958 22340
[14:43:40.916] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:43:40.916] INFO: number of Vcal hits detected: 207931 207971 207938 207792 207885 207868 207866 207899 207924 207899 207888 207851 207904 207940 207971 207982
[14:43:40.916] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[14:43:40.916] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0
[14:43:40.916] INFO: X-ray hit rate [MHz/cm2]: 17.9 10.9 16.4 26.2 27.2 28.5 28.1 20.5 19.7 26.8 25.9 23.2 24.5 14.5 5.8 6.5
[14:43:40.916] INFO: PixTestHighRate::doXPixelAlive() done
[14:43:40.961] INFO: PixTest:: pg_setup set to default.
[14:43:40.974] INFO: enter test to run
[14:43:56.004] INFO: test: HighRate no parameter change
[14:43:56.004] INFO: running: highrate
[14:43:56.006] INFO: ----------------------------------------------------------------------
[14:43:56.006] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:43:56.006] INFO: ----------------------------------------------------------------------
[14:43:57.622] INFO: Expecting 208000 events.
[14:44:10.733] INFO: 208000 events read in total (12584ms).
[14:44:10.737] INFO: Test took 13722ms.
[14:44:10.990] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:11.277] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 1 0 0 0 0
[14:44:11.277] INFO: number of red-efficiency pixels: 200 101 189 376 330 460 368 246 193 260 300 271 280 157 63 53
[14:44:11.277] INFO: number of X-ray hits detected: 122737 74697 112068 180636 186918 196856 194078 140336 136497 183510 178369 160486 167994 99564 40273 45237
[14:44:11.277] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:44:11.277] INFO: number of Vcal hits detected: 207786 207894 207798 207544 207648 207493 207612 207740 207798 207725 207688 207661 207705 207830 207934 207945
[14:44:11.277] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[14:44:11.277] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.9 99.8 99.9 99.9 100.0 100.0
[14:44:11.277] INFO: X-ray hit rate [MHz/cm2]: 36.0 21.9 32.8 52.9 54.8 57.7 56.9 41.1 40.0 53.8 52.3 47.0 49.2 29.2 11.8 13.3
[14:44:11.277] INFO: PixTestHighRate::doXPixelAlive() done
[14:44:11.324] INFO: PixTest:: pg_setup set to default.
[14:44:11.343] INFO: enter test to run
[14:44:27.620] INFO: test: HighRate no parameter change
[14:44:27.620] INFO: running: highrate
[14:44:27.621] INFO: ----------------------------------------------------------------------
[14:44:27.621] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:44:27.621] INFO: ----------------------------------------------------------------------
[14:44:28.240] INFO: Expecting 208000 events.
[14:44:43.047] INFO: 208000 events read in total (14280ms).
[14:44:43.053] INFO: Test took 15423ms.
[14:44:43.454] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:43.786] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 1 0 0 0 0
[14:44:43.786] INFO: number of red-efficiency pixels: 431 209 460 872 851 1066 942 605 424 599 659 654 646 430 88 81
[14:44:43.786] INFO: number of X-ray hits detected: 188839 115643 173817 278862 288338 303003 297600 216965 210029 283202 274739 248102 259419 153822 62537 70359
[14:44:43.786] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:44:43.786] INFO: number of Vcal hits detected: 207474 207770 207458 206893 207001 206690 206853 207292 207537 207322 207250 207192 207276 207499 207908 207919
[14:44:43.786] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.5 99.6 99.4 99.5 99.7 99.8 99.7 99.7 99.7 99.7 99.8 100.0 100.0
[14:44:43.786] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.7 99.5 99.5 99.4 99.4 99.7 99.8 99.7 99.6 99.6 99.7 99.8 100.0 100.0
[14:44:43.786] INFO: X-ray hit rate [MHz/cm2]: 55.4 33.9 50.9 81.7 84.5 88.8 87.2 63.6 61.6 83.0 80.5 72.7 76.0 45.1 18.3 20.6
[14:44:43.786] INFO: PixTestHighRate::doXPixelAlive() done
[14:44:43.837] INFO: PixTest:: pg_setup set to default.
[14:44:43.853] INFO: enter test to run
[14:44:53.380] INFO: test: exit no parameter change
[14:44:53.618] QUIET: Connection to board 33 closed.
[14:44:53.630] INFO: pXar: this is the end, my friend