[15:41:47.887] INFO: *** Welcome to pxar ***
[15:41:47.887] INFO: *** Today: 2016/09/14
[15:41:49.591] INFO: *** Version: v1.9.0-818-g96727
[15:41:49.591] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//dacParameters35_C15.dat
[15:41:49.790] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:41:49.808] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//defaultMaskFile.dat
[15:41:49.835] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C15.dat
[15:41:50.007] INFO: clk: 4
[15:41:50.007] INFO: ctr: 4
[15:41:50.007] INFO: sda: 19
[15:41:50.007] INFO: tin: 9
[15:41:50.007] INFO: level: 15
[15:41:50.007] INFO: triggerdelay: 0
[15:41:50.028] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[15:41:50.028] INFO: Log level: INFO
[15:41:50.069] QUIET: Connection to board DTB_WREKRL opened.
[15:41:50.072] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[15:41:50.075] INFO: RPC call hashes of host and DTB match: 398089610
[15:41:51.608] INFO: DUT info:
[15:41:51.608] INFO: The DUT currently contains the following objects:
[15:41:51.609] INFO: 2 TBM Cores tbm08c (2 ON)
[15:41:51.616] INFO: TBM Core alpha (0): 7 registers set
[15:41:51.616] INFO: TBM Core beta (1): 7 registers set
[15:41:51.616] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:41:51.616] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.616] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.616] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.616] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.616] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.616] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.616] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.616] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.616] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.616] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.616] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.616] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.616] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.617] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.617] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:51.617] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:41:52.077] INFO: enter 'restricted' command line mode
[15:41:52.077] INFO: enter test to run
[15:41:57.003] INFO: test: PixelAlive no parameter change
[15:41:57.003] INFO: running: pixelalive
[15:41:58.024] INFO: ----------------------------------------------------------------------
[15:41:58.024] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:41:58.024] INFO: ----------------------------------------------------------------------
[15:41:58.339] INFO: Expecting 41600 events.
[15:42:02.679] INFO: 41600 events read in total (3622ms).
[15:42:02.847] INFO: Test took 4820ms.
[15:42:02.861] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:03.153] INFO: PixTestAlive::aliveTest() done
[15:42:03.153] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:42:03.187] INFO: enter test to run
[15:43:45.347] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:43:45.347] INFO: running: highrate
[15:43:45.347] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[15:43:47.325] INFO: ----------------------------------------------------------------------
[15:43:47.325] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:43:47.325] INFO: ----------------------------------------------------------------------
[15:43:47.325] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:43:47.325] INFO: edge/corner pixel THR is adjusted
[15:43:47.325] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:43:48.283] INFO: Collecting data for 5 seconds...
[15:43:53.303] INFO: Done with hot pixel readout
[15:44:05.170] INFO: PixTest:: pg_setup set to default.
[15:44:05.171] INFO: 0 hot pixels found in step 0
[15:44:05.184] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:44:05.228] INFO: PixTest::trimHotPixels() done
[15:44:05.260] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C0.dat
[15:44:05.271] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C1.dat
[15:44:05.276] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C2.dat
[15:44:05.281] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C3.dat
[15:44:05.287] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C4.dat
[15:44:05.293] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C5.dat
[15:44:05.298] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C6.dat
[15:44:05.304] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C7.dat
[15:44:05.309] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C8.dat
[15:44:05.314] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C9.dat
[15:44:05.320] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C10.dat
[15:44:05.325] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C11.dat
[15:44:05.331] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C12.dat
[15:44:05.336] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C13.dat
[15:44:05.341] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C14.dat
[15:44:05.347] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C15.dat
[15:44:05.352] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//defaultMaskFile.dat
[15:44:05.362] INFO: enter test to run
[15:46:04.242] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[15:46:04.242] INFO: running: highrate
[15:46:04.246] INFO: ----------------------------------------------------------------------
[15:46:04.246] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:46:04.246] INFO: ----------------------------------------------------------------------
[15:46:04.246] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:46:04.246] INFO: edge/corner pixel THR is adjusted
[15:46:04.246] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:46:05.204] INFO: Collecting data for 1 seconds...
[15:46:06.208] INFO: Done with hot pixel readout
[15:46:10.098] INFO: PixTest:: pg_setup set to default.
[15:46:10.099] INFO: 0 hot pixels found in step 0
[15:46:10.104] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:46:10.203] INFO: PixTest::trimHotPixels() done
[15:46:10.203] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C0.dat
[15:46:10.215] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C1.dat
[15:46:10.220] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C2.dat
[15:46:10.225] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C3.dat
[15:46:10.231] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C4.dat
[15:46:10.237] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C5.dat
[15:46:10.242] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C6.dat
[15:46:10.247] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C7.dat
[15:46:10.252] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C8.dat
[15:46:10.257] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C9.dat
[15:46:10.263] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C10.dat
[15:46:10.269] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C11.dat
[15:46:10.274] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C12.dat
[15:46:10.279] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C13.dat
[15:46:10.284] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C14.dat
[15:46:10.289] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//trimParameters35_C15.dat
[15:46:10.295] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-6-21_FPIXTest-17C-FNAL-160907-1223-150V_2016-09-07_12h23m_1473269026/000_FPIXTest_p17//defaultMaskFile.dat
[15:46:10.305] INFO: enter test to run
[15:46:39.185] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:46:39.185] INFO: running: xray
[15:46:39.187] INFO: ----------------------------------------------------------------------
[15:46:39.187] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:46:39.187] INFO: ----------------------------------------------------------------------
[15:46:40.151] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:46:51.750] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:47:21.108] INFO: Resuming triggers.
[15:47:32.707] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:48:02.131] INFO: Resuming triggers.
[15:48:13.729] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:48:43.043] INFO: Resuming triggers.
[15:48:54.640] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:49:24.065] INFO: Resuming triggers.
[15:49:35.663] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[15:50:04.900] INFO: Resuming triggers.
[15:50:16.501] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:50:46.047] INFO: Resuming triggers.
[15:50:57.653] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[15:51:26.822] INFO: Resuming triggers.
[15:51:38.424] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[15:52:08.270] INFO: Resuming triggers.
[15:52:15.812] INFO: data taking finished, elapsed time: 100 seconds.
[15:52:35.605] INFO: PixTest:: pg_setup set to default.
[15:52:35.608] INFO: PixTestXray::doPhRun() done
[15:52:35.743] INFO: enter test to run
[15:53:19.183] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:53:19.183] INFO: running: xray
[15:53:19.184] INFO: ----------------------------------------------------------------------
[15:53:19.184] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:53:19.184] INFO: ----------------------------------------------------------------------
[15:53:20.147] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:53:27.164] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:53:57.378] INFO: Resuming triggers.
[15:54:04.393] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[15:54:35.128] INFO: Resuming triggers.
[15:54:42.147] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[15:55:13.482] INFO: Resuming triggers.
[15:55:20.492] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[15:55:52.192] INFO: Resuming triggers.
[15:55:59.205] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:56:30.241] INFO: Resuming triggers.
[15:56:37.249] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[15:57:08.631] INFO: Resuming triggers.
[15:57:15.643] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[15:57:46.700] INFO: Resuming triggers.
[15:57:53.709] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[15:58:24.811] INFO: Resuming triggers.
[15:58:31.823] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[15:59:02.031] INFO: Resuming triggers.
[15:59:09.040] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:59:38.487] INFO: Resuming triggers.
[15:59:45.496] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[16:00:15.633] INFO: Resuming triggers.
[16:00:22.644] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[16:00:52.237] INFO: Resuming triggers.
[16:00:59.246] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[16:01:29.128] INFO: Resuming triggers.
[16:01:36.142] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[16:02:06.029] INFO: Resuming triggers.
[16:02:08.219] INFO: data taking finished, elapsed time: 100 seconds.
[16:02:17.908] INFO: PixTest:: pg_setup set to default.
[16:02:17.912] INFO: PixTestXray::doPhRun() done
[16:02:18.090] INFO: enter test to run
[16:03:13.011] INFO: test: HighRate no parameter change
[16:03:13.011] INFO: running: highrate
[16:03:13.033] INFO: ----------------------------------------------------------------------
[16:03:13.033] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:03:13.033] INFO: ----------------------------------------------------------------------
[16:03:13.179] INFO: Expecting 768 events.
[16:03:14.313] INFO: 768 events read in total (418ms).
[16:03:14.313] INFO: Test took 1268ms.
[16:03:15.116] INFO: Expecting 41600 events.
[16:03:18.201] INFO: 41600 events read in total (2558ms).
[16:03:18.202] INFO: Test took 3862ms.
[16:03:18.234] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:18.965] INFO: Expecting 41600 events.
[16:03:22.152] INFO: 41600 events read in total (2660ms).
[16:03:22.153] INFO: Test took 3902ms.
[16:03:22.184] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:22.915] INFO: Expecting 41600 events.
[16:03:26.133] INFO: 41600 events read in total (2691ms).
[16:03:26.134] INFO: Test took 3932ms.
[16:03:26.166] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:26.891] INFO: Expecting 41600 events.
[16:03:30.108] INFO: 41600 events read in total (2690ms).
[16:03:30.109] INFO: Test took 3926ms.
[16:03:30.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:30.871] INFO: Expecting 41600 events.
[16:03:34.079] INFO: 41600 events read in total (2681ms).
[16:03:34.080] INFO: Test took 3922ms.
[16:03:34.112] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:34.840] INFO: Expecting 41600 events.
[16:03:38.053] INFO: 41600 events read in total (2687ms).
[16:03:38.054] INFO: Test took 3924ms.
[16:03:38.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:38.815] INFO: Expecting 41600 events.
[16:03:42.024] INFO: 41600 events read in total (2682ms).
[16:03:42.025] INFO: Test took 3923ms.
[16:03:42.057] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:42.786] INFO: Expecting 41600 events.
[16:03:45.995] INFO: 41600 events read in total (2682ms).
[16:03:45.996] INFO: Test took 3922ms.
[16:03:46.028] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:46.756] INFO: Expecting 41600 events.
[16:03:49.970] INFO: 41600 events read in total (2687ms).
[16:03:49.971] INFO: Test took 3924ms.
[16:03:49.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:50.732] INFO: Expecting 41600 events.
[16:03:53.941] INFO: 41600 events read in total (2682ms).
[16:03:53.942] INFO: Test took 3922ms.
[16:03:53.974] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:54.704] INFO: Expecting 41600 events.
[16:03:57.921] INFO: 41600 events read in total (2691ms).
[16:03:57.922] INFO: Test took 3929ms.
[16:03:57.955] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:58.679] INFO: Expecting 41600 events.
[16:04:01.904] INFO: 41600 events read in total (2698ms).
[16:04:01.905] INFO: Test took 3932ms.
[16:04:01.937] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:02.663] INFO: Expecting 41600 events.
[16:04:05.888] INFO: 41600 events read in total (2698ms).
[16:04:05.889] INFO: Test took 3935ms.
[16:04:05.921] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:06.646] INFO: Expecting 41600 events.
[16:04:09.860] INFO: 41600 events read in total (2687ms).
[16:04:09.861] INFO: Test took 3922ms.
[16:04:09.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:10.618] INFO: Expecting 41600 events.
[16:04:13.824] INFO: 41600 events read in total (2679ms).
[16:04:13.825] INFO: Test took 3914ms.
[16:04:13.857] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:14.585] INFO: Expecting 41600 events.
[16:04:17.817] INFO: 41600 events read in total (2705ms).
[16:04:17.818] INFO: Test took 3943ms.
[16:04:17.851] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:18.575] INFO: Expecting 41600 events.
[16:04:21.714] INFO: 41600 events read in total (2613ms).
[16:04:21.715] INFO: Test took 3847ms.
[16:04:21.747] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:22.478] INFO: Expecting 41600 events.
[16:04:25.550] INFO: 41600 events read in total (2545ms).
[16:04:25.551] INFO: Test took 3787ms.
[16:04:25.583] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:26.313] INFO: Expecting 41600 events.
[16:04:29.373] INFO: 41600 events read in total (2533ms).
[16:04:29.374] INFO: Test took 3772ms.
[16:04:29.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:30.133] INFO: Expecting 41600 events.
[16:04:33.162] INFO: 41600 events read in total (2502ms).
[16:04:33.163] INFO: Test took 3738ms.
[16:04:33.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:33.563] INFO: enter test to run
[16:05:06.442] INFO: test: HighRate no parameter change
[16:05:06.442] INFO: running: highrate
[16:05:06.443] INFO: ----------------------------------------------------------------------
[16:05:06.443] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:05:06.443] INFO: ----------------------------------------------------------------------
[16:05:07.054] INFO: Expecting 208000 events.
[16:05:18.814] INFO: 208000 events read in total (11233ms).
[16:05:18.817] INFO: Test took 12366ms.
[16:05:18.955] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:19.205] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:05:19.205] INFO: number of red-efficiency pixels: 97 47 70 132 126 116 117 99 72 114 127 102 89 67 19 26
[16:05:19.205] INFO: number of X-ray hits detected: 61325 38833 62806 99092 103856 111479 105038 74232 70615 88134 90499 76454 79260 48684 18498 23369
[16:05:19.205] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:05:19.205] INFO: number of Vcal hits detected: 207902 207953 207925 207863 207871 207882 207880 207897 207926 207885 207871 207895 207910 207922 207981 207973
[16:05:19.205] 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 99.9 100.0 100.0 100.0 100.0 100.0
[16:05:19.205] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[16:05:19.205] INFO: X-ray hit rate [MHz/cm2]: 18.0 11.4 18.4 29.0 30.4 32.7 30.8 21.8 20.7 25.8 26.5 22.4 23.2 14.3 5.4 6.8
[16:05:19.205] INFO: PixTestHighRate::doXPixelAlive() done
[16:05:19.250] INFO: PixTest:: pg_setup set to default.
[16:05:19.260] INFO: enter test to run
[16:05:38.546] INFO: test: HighRate no parameter change
[16:05:38.546] INFO: running: highrate
[16:05:38.547] INFO: ----------------------------------------------------------------------
[16:05:38.547] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:05:38.547] INFO: ----------------------------------------------------------------------
[16:05:39.158] INFO: Expecting 208000 events.
[16:05:54.227] INFO: 208000 events read in total (14543ms).
[16:05:54.234] INFO: Test took 15680ms.
[16:05:54.633] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:54.970] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:05:54.970] INFO: number of red-efficiency pixels: 492 229 458 1085 1087 993 827 506 548 799 842 653 650 317 75 99
[16:05:54.970] INFO: number of X-ray hits detected: 191701 120696 195844 308508 323036 347532 327604 230530 220735 276040 280260 240310 247248 152972 58579 73581
[16:05:54.970] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:05:54.970] INFO: number of Vcal hits detected: 207397 207758 207433 206643 206676 206754 207017 207435 207392 207080 207017 207256 207252 207615 207922 207898
[16:05:54.970] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.8 99.4 99.4 99.5 99.6 99.8 99.7 99.6 99.6 99.7 99.7 99.8 100.0 100.0
[16:05:54.970] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.7 99.3 99.4 99.4 99.5 99.7 99.7 99.6 99.5 99.6 99.6 99.8 100.0 100.0
[16:05:54.970] INFO: X-ray hit rate [MHz/cm2]: 56.2 35.4 57.4 90.4 94.7 101.9 96.0 67.6 64.7 80.9 82.1 70.4 72.5 44.8 17.2 21.6
[16:05:54.970] INFO: PixTestHighRate::doXPixelAlive() done
[16:05:55.016] INFO: PixTest:: pg_setup set to default.
[16:05:55.029] INFO: enter test to run
[16:06:36.249] INFO: test: [A no parameter change
[16:06:36.249] INFO: running: [a
[16:06:36.271] INFO: command ->[a<- not known, ignored
[16:06:36.271] INFO: enter test to run
[16:06:50.713] INFO: test: HighRate no parameter change
[16:06:50.713] INFO: running: highrate
[16:06:50.714] INFO: ----------------------------------------------------------------------
[16:06:50.714] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:06:50.714] INFO: ----------------------------------------------------------------------
[16:06:51.334] INFO: Expecting 208000 events.
[16:07:04.464] INFO: 208000 events read in total (12603ms).
[16:07:04.468] INFO: Test took 13745ms.
[16:07:04.736] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:05.026] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:07:05.026] INFO: number of red-efficiency pixels: 207 97 208 419 443 428 377 218 228 282 346 289 254 145 43 46
[16:07:05.026] INFO: number of X-ray hits detected: 123005 77848 125892 197359 206368 222769 210368 149118 140989 175995 179514 154079 158196 97548 37110 46937
[16:07:05.026] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:07:05.026] INFO: number of Vcal hits detected: 207778 207900 207773 207554 207519 207533 207599 207777 207768 207709 207626 207689 207735 207834 207956 207953
[16:07:05.026] 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.8 99.9 99.9 99.9 100.0 100.0
[16:07:05.026] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[16:07:05.026] INFO: X-ray hit rate [MHz/cm2]: 36.1 22.8 36.9 57.8 60.5 65.3 61.7 43.7 41.3 51.6 52.6 45.2 46.4 28.6 10.9 13.8
[16:07:05.026] INFO: PixTestHighRate::doXPixelAlive() done
[16:07:05.073] INFO: PixTest:: pg_setup set to default.
[16:07:05.084] INFO: enter test to run
[16:07:29.889] INFO: test: exit no parameter change
[16:07:30.445] QUIET: Connection to board 33 closed.
[16:07:30.469] INFO: pXar: this is the end, my friend