[11:46:01.812] INFO: *** Welcome to pxar ***
[11:46:01.812] INFO: *** Today: 2016/08/19
[11:46:01.830] INFO: *** Version: v1.9.0-818-g96727
[11:46:01.830] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//dacParameters35_C15.dat
[11:46:01.831] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:46:01.831] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//defaultMaskFile.dat
[11:46:01.831] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C15.dat
[11:46:01.903] INFO: clk: 4
[11:46:01.903] INFO: ctr: 4
[11:46:01.903] INFO: sda: 19
[11:46:01.903] INFO: tin: 9
[11:46:01.903] INFO: level: 15
[11:46:01.903] INFO: triggerdelay: 0
[11:46:01.903] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[11:46:01.903] INFO: Log level: INFO
[11:46:01.920] QUIET: Connection to board DTB_WREKRL opened.
[11:46:01.923] 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:46:01.926] INFO: RPC call hashes of host and DTB match: 398089610
[11:46:03.510] INFO: DUT info:
[11:46:03.510] INFO: The DUT currently contains the following objects:
[11:46:03.510] INFO: 2 TBM Cores tbm08c (2 ON)
[11:46:03.510] INFO: TBM Core alpha (0): 7 registers set
[11:46:03.510] INFO: TBM Core beta (1): 7 registers set
[11:46:03.510] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:46:03.510] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.510] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.510] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.510] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.510] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.510] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.510] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.510] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.510] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.510] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.510] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.510] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.510] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.510] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.511] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.511] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:46:03.914] INFO: enter 'restricted' command line mode
[11:46:03.914] INFO: enter test to run
[11:46:54.262] INFO: test: PixelAlive no parameter change
[11:46:54.262] INFO: running: pixelalive
[11:46:54.271] INFO: ----------------------------------------------------------------------
[11:46:54.271] 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:46:54.271] INFO: ----------------------------------------------------------------------
[11:46:54.596] INFO: Expecting 41600 events.
[11:46:58.937] INFO: 41600 events read in total (3622ms).
[11:46:59.106] INFO: Test took 4832ms.
[11:46:59.117] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:59.378] INFO: PixTestAlive::aliveTest() done
[11:46:59.378] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:46:59.416] INFO: enter test to run
[11:47:47.982] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:47:47.982] INFO: running: highrate
[11:47:47.990] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:47:48.241] INFO: ----------------------------------------------------------------------
[11:47:48.241] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:47:48.241] INFO: ----------------------------------------------------------------------
[11:47:48.241] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[11:47:48.241] INFO: edge/corner pixel THR is adjusted
[11:47:48.241] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:47:49.227] INFO: Collecting data for 5 seconds...
[11:47:54.245] INFO: Done with hot pixel readout
[11:48:05.001] INFO: PixTest:: pg_setup set to default.
[11:48:05.002] INFO: 0 hot pixels found in step 0
[11:48:06.015] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:48:06.019] INFO: PixTest::trimHotPixels() done
[11:48:06.040] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C0.dat
[11:48:06.046] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C1.dat
[11:48:06.052] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C2.dat
[11:48:06.059] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C3.dat
[11:48:06.066] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C4.dat
[11:48:06.073] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C5.dat
[11:48:06.079] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C6.dat
[11:48:06.106] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C7.dat
[11:48:06.112] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C8.dat
[11:48:06.118] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C9.dat
[11:48:06.123] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C10.dat
[11:48:06.129] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C11.dat
[11:48:06.134] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C12.dat
[11:48:06.139] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C13.dat
[11:48:06.145] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C14.dat
[11:48:06.150] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C15.dat
[11:48:06.156] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//defaultMaskFile.dat
[11:48:06.167] INFO: enter test to run
[11:48:44.085] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:48:44.085] INFO: running: highrate
[11:48:44.089] INFO: ----------------------------------------------------------------------
[11:48:44.090] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:48:44.090] INFO: ----------------------------------------------------------------------
[11:48:44.090] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:48:44.090] INFO: edge/corner pixel THR is adjusted
[11:48:44.090] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:48:45.047] INFO: Collecting data for 1 seconds...
[11:48:46.052] INFO: Done with hot pixel readout
[11:48:50.105] INFO: PixTest:: pg_setup set to default.
[11:48:50.106] INFO: 0 hot pixels found in step 0
[11:48:50.112] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:48:50.203] INFO: PixTest::trimHotPixels() done
[11:48:50.203] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C0.dat
[11:48:50.212] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C1.dat
[11:48:50.218] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C2.dat
[11:48:50.223] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C3.dat
[11:48:50.229] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C4.dat
[11:48:50.234] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C5.dat
[11:48:50.239] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C6.dat
[11:48:50.245] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C7.dat
[11:48:50.250] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C8.dat
[11:48:50.256] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C9.dat
[11:48:50.261] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C10.dat
[11:48:50.266] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C11.dat
[11:48:50.272] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C12.dat
[11:48:50.277] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C13.dat
[11:48:50.283] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C14.dat
[11:48:50.288] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C15.dat
[11:48:50.293] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-6-06_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//defaultMaskFile.dat
[11:48:50.303] INFO: enter test to run
[11:49:38.461] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:49:38.461] INFO: running: xray
[11:49:38.462] INFO: ----------------------------------------------------------------------
[11:49:38.462] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:49:38.462] INFO: ----------------------------------------------------------------------
[11:49:39.426] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:49:51.377] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:50:20.533] INFO: Resuming triggers.
[11:50:32.492] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:51:01.711] INFO: Resuming triggers.
[11:51:13.683] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[11:51:42.800] INFO: Resuming triggers.
[11:51:54.781] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[11:52:23.939] INFO: Resuming triggers.
[11:52:35.933] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[11:53:05.115] INFO: Resuming triggers.
[11:53:17.126] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[11:53:46.537] INFO: Resuming triggers.
[11:53:58.550] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[11:54:27.697] INFO: Resuming triggers.
[11:54:39.720] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[11:55:08.693] INFO: Resuming triggers.
[11:55:13.125] INFO: data taking finished, elapsed time: 100 seconds.
[11:55:24.179] INFO: PixTest:: pg_setup set to default.
[11:55:24.182] INFO: PixTestXray::doPhRun() done
[11:55:24.314] INFO: enter test to run
[12:01:28.800] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:01:28.800] INFO: running: xray
[12:01:28.801] INFO: ----------------------------------------------------------------------
[12:01:28.801] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:01:28.801] INFO: ----------------------------------------------------------------------
[12:01:29.765] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:01:36.873] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[12:02:07.659] INFO: Resuming triggers.
[12:02:14.767] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[12:02:44.803] INFO: Resuming triggers.
[12:02:51.914] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[12:03:22.296] INFO: Resuming triggers.
[12:03:29.413] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[12:03:59.588] INFO: Resuming triggers.
[12:04:06.705] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[12:04:36.218] INFO: Resuming triggers.
[12:04:43.337] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[12:05:13.453] INFO: Resuming triggers.
[12:05:20.576] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[12:05:50.880] INFO: Resuming triggers.
[12:05:57.006] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[12:06:28.110] INFO: Resuming triggers.
[12:06:35.232] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[12:07:05.339] INFO: Resuming triggers.
[12:07:12.459] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[12:07:42.735] INFO: Resuming triggers.
[12:07:49.859] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[12:08:19.771] INFO: Resuming triggers.
[12:08:26.901] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[12:08:56.980] INFO: Resuming triggers.
[12:09:04.114] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[12:09:34.267] INFO: Resuming triggers.
[12:09:41.404] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[12:10:12.311] INFO: Resuming triggers.
[12:10:12.945] INFO: data taking finished, elapsed time: 100 seconds.
[12:10:15.004] INFO: PixTest:: pg_setup set to default.
[12:10:16.007] INFO: PixTestXray::doPhRun() done
[12:10:16.160] INFO: enter test to run
[12:14:55.611] INFO: test: HighRate no parameter change
[12:14:55.611] INFO: running: highrate
[12:14:55.624] INFO: ----------------------------------------------------------------------
[12:14:55.624] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:14:55.624] INFO: ----------------------------------------------------------------------
[12:14:55.779] INFO: Expecting 768 events.
[12:14:56.913] INFO: 768 events read in total (418ms).
[12:14:56.913] INFO: Test took 1269ms.
[12:14:57.716] INFO: Expecting 41600 events.
[12:15:00.701] INFO: 41600 events read in total (2458ms).
[12:15:00.702] INFO: Test took 3763ms.
[12:15:00.732] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:01.464] INFO: Expecting 41600 events.
[12:15:04.509] INFO: 41600 events read in total (2518ms).
[12:15:04.510] INFO: Test took 3763ms.
[12:15:04.540] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:05.272] INFO: Expecting 41600 events.
[12:15:08.364] INFO: 41600 events read in total (2565ms).
[12:15:08.365] INFO: Test took 3808ms.
[12:15:08.396] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:09.131] INFO: Expecting 41600 events.
[12:15:12.308] INFO: 41600 events read in total (2650ms).
[12:15:12.309] INFO: Test took 3896ms.
[12:15:12.340] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:13.069] INFO: Expecting 41600 events.
[12:15:16.173] INFO: 41600 events read in total (2577ms).
[12:15:16.193] INFO: Test took 3837ms.
[12:15:16.227] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:16.936] INFO: Expecting 41600 events.
[12:15:19.993] INFO: 41600 events read in total (2530ms).
[12:15:19.994] INFO: Test took 3750ms.
[12:15:20.024] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:20.751] INFO: Expecting 41600 events.
[12:15:23.937] INFO: 41600 events read in total (2659ms).
[12:15:23.938] INFO: Test took 3897ms.
[12:15:23.968] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:24.700] INFO: Expecting 41600 events.
[12:15:27.813] INFO: 41600 events read in total (2586ms).
[12:15:27.814] INFO: Test took 3828ms.
[12:15:27.845] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:28.575] INFO: Expecting 41600 events.
[12:15:31.771] INFO: 41600 events read in total (2669ms).
[12:15:31.772] INFO: Test took 3910ms.
[12:15:31.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:32.530] INFO: Expecting 41600 events.
[12:15:35.722] INFO: 41600 events read in total (2665ms).
[12:15:35.723] INFO: Test took 3902ms.
[12:15:35.754] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:36.487] INFO: Expecting 41600 events.
[12:15:39.693] INFO: 41600 events read in total (2679ms).
[12:15:39.694] INFO: Test took 3924ms.
[12:15:39.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:40.457] INFO: Expecting 41600 events.
[12:15:43.652] INFO: 41600 events read in total (2668ms).
[12:15:43.653] INFO: Test took 3912ms.
[12:15:43.683] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:44.413] INFO: Expecting 41600 events.
[12:15:47.590] INFO: 41600 events read in total (2651ms).
[12:15:47.591] INFO: Test took 3892ms.
[12:15:47.622] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:48.355] INFO: Expecting 41600 events.
[12:15:51.547] INFO: 41600 events read in total (2665ms).
[12:15:51.548] INFO: Test took 3909ms.
[12:15:51.579] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:52.309] INFO: Expecting 41600 events.
[12:15:55.510] INFO: 41600 events read in total (2674ms).
[12:15:55.511] INFO: Test took 3915ms.
[12:15:55.541] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:56.274] INFO: Expecting 41600 events.
[12:15:59.465] INFO: 41600 events read in total (2664ms).
[12:15:59.466] INFO: Test took 3909ms.
[12:15:59.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:16:00.231] INFO: Expecting 41600 events.
[12:16:03.420] INFO: 41600 events read in total (2662ms).
[12:16:03.421] INFO: Test took 3909ms.
[12:16:03.451] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:16:04.180] INFO: Expecting 41600 events.
[12:16:07.374] INFO: 41600 events read in total (2667ms).
[12:16:07.375] INFO: Test took 3907ms.
[12:16:07.405] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:16:08.133] INFO: Expecting 41600 events.
[12:16:11.298] INFO: 41600 events read in total (2638ms).
[12:16:11.299] INFO: Test took 3877ms.
[12:16:11.329] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:16:12.058] INFO: Expecting 41600 events.
[12:16:15.088] INFO: 41600 events read in total (2503ms).
[12:16:15.089] INFO: Test took 3743ms.
[12:16:15.119] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:16:15.492] INFO: enter test to run
[12:19:31.953] INFO: test: HighRate no parameter change
[12:19:31.953] INFO: running: highrate
[12:19:31.954] INFO: ----------------------------------------------------------------------
[12:19:31.954] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:19:31.954] INFO: ----------------------------------------------------------------------
[12:19:32.567] INFO: Expecting 208000 events.
[12:19:44.275] INFO: 208000 events read in total (11181ms).
[12:19:44.278] INFO: Test took 12313ms.
[12:19:44.405] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:44.651] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0
[12:19:44.651] INFO: number of red-efficiency pixels: 63 33 68 111 112 126 138 87 99 99 113 85 63 45 18 18
[12:19:44.651] INFO: number of X-ray hits detected: 49640 34262 58170 90754 97499 105134 102090 73797 67341 86848 83249 69076 70906 42146 16730 20245
[12:19:44.651] 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:19:44.652] INFO: number of Vcal hits detected: 207936 207967 207932 207889 207887 207871 207859 207912 207900 207850 207883 207904 207937 207955 207982 207982
[12:19:44.652] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0
[12:19:44.652] 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 100.0 100.0 100.0 100.0 100.0
[12:19:44.652] INFO: X-ray hit rate [MHz/cm2]: 14.5 10.0 17.1 26.6 28.6 30.8 29.9 21.6 19.7 25.5 24.4 20.2 20.8 12.4 4.9 5.9
[12:19:44.652] INFO: PixTestHighRate::doXPixelAlive() done
[12:19:44.702] INFO: PixTest:: pg_setup set to default.
[12:19:44.716] INFO: enter test to run
[12:20:02.393] INFO: test: HighRate no parameter change
[12:20:02.393] INFO: running: highrate
[12:20:02.394] INFO: ----------------------------------------------------------------------
[12:20:02.394] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:20:02.394] INFO: ----------------------------------------------------------------------
[12:20:03.009] INFO: Expecting 208000 events.
[12:20:16.275] INFO: 208000 events read in total (12740ms).
[12:20:16.280] INFO: Test took 13876ms.
[12:20:16.547] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:20:16.840] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0
[12:20:16.840] INFO: number of red-efficiency pixels: 166 106 257 392 352 429 478 275 245 288 345 226 266 148 37 59
[12:20:16.840] INFO: number of X-ray hits detected: 109429 75583 128228 198348 214599 231641 225636 163404 148965 190961 184103 153052 156271 93267 37002 45068
[12:20:16.840] 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:20:16.840] INFO: number of Vcal hits detected: 207824 207886 207711 207592 207628 207537 207475 207701 207746 207649 207629 207754 207728 207846 207963 207940
[12:20:16.840] 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
[12:20:16.840] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[12:20:16.840] INFO: X-ray hit rate [MHz/cm2]: 32.1 22.2 37.6 58.1 62.9 67.9 66.1 47.9 43.7 56.0 54.0 44.9 45.8 27.3 10.8 13.2
[12:20:16.840] INFO: PixTestHighRate::doXPixelAlive() done
[12:20:16.888] INFO: PixTest:: pg_setup set to default.
[12:20:16.901] INFO: enter test to run
[12:21:11.928] INFO: test: HighRate no parameter change
[12:21:11.928] INFO: running: highrate
[12:21:11.929] INFO: ----------------------------------------------------------------------
[12:21:11.929] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:21:11.929] INFO: ----------------------------------------------------------------------
[12:21:12.546] INFO: Expecting 208000 events.
[12:21:27.547] INFO: 208000 events read in total (14474ms).
[12:21:27.554] INFO: Test took 15614ms.
[12:21:27.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:28.303] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0
[12:21:28.303] INFO: number of red-efficiency pixels: 335 216 532 952 820 962 1070 594 544 692 807 433 553 325 73 53
[12:21:28.303] INFO: number of X-ray hits detected: 163551 113572 193648 297974 322366 349127 337892 245460 224881 289376 277649 229696 233999 140379 55841 68601
[12:21:28.303] 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:21:28.303] INFO: number of Vcal hits detected: 207632 207771 207313 206867 207047 206824 206651 207291 207385 207163 207058 207497 207402 207629 207924 207947
[12:21:28.303] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.6 99.5 99.4 99.7 99.7 99.7 99.6 99.8 99.7 99.8 100.0 100.0
[12:21:28.303] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.5 99.5 99.4 99.4 99.7 99.7 99.6 99.5 99.8 99.7 99.8 100.0 100.0
[12:21:28.303] INFO: X-ray hit rate [MHz/cm2]: 47.9 33.3 56.8 87.3 94.5 102.3 99.0 71.9 65.9 84.8 81.4 67.3 68.6 41.1 16.4 20.1
[12:21:28.303] INFO: PixTestHighRate::doXPixelAlive() done
[12:21:28.356] INFO: PixTest:: pg_setup set to default.
[12:21:28.373] INFO: enter test to run
[12:22:45.143] INFO: test: exit no parameter change
[12:22:45.521] QUIET: Connection to board 33 closed.
[12:22:45.532] INFO: pXar: this is the end, my friend