[15:58:51.735] INFO: *** Welcome to pxar ***
[15:58:51.735] INFO: *** Today: 2016/07/13
[15:58:52.564] INFO: *** Version: v1.9.0-814-g7497
[15:58:52.564] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//dacParameters35_C15.dat
[15:58:52.645] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:58:52.645] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//defaultMaskFile.dat
[15:58:52.647] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C15.dat
[15:58:52.767] INFO: clk: 4
[15:58:52.767] INFO: ctr: 4
[15:58:52.767] INFO: sda: 19
[15:58:52.767] INFO: tin: 9
[15:58:52.767] INFO: level: 15
[15:58:52.767] INFO: triggerdelay: 0
[15:58:52.767] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[15:58:52.767] INFO: Log level: INFO
[15:58:52.786] QUIET: Connection to board DTB_WREKRL opened.
[15:58:52.789] 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:58:52.792] INFO: RPC call hashes of host and DTB match: 398089610
[15:58:54.339] INFO: DUT info:
[15:58:54.339] INFO: The DUT currently contains the following objects:
[15:58:54.339] INFO: 2 TBM Cores tbm08c (2 ON)
[15:58:54.339] INFO: TBM Core alpha (0): 7 registers set
[15:58:54.339] INFO: TBM Core beta (1): 7 registers set
[15:58:54.339] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:58:54.339] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.339] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.339] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.340] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.340] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.340] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.340] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.340] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.340] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.340] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.340] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.340] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.340] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.340] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.340] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.340] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:54.780] INFO: enter 'restricted' command line mode
[15:58:54.780] INFO: enter test to run
[15:59:00.190] INFO: test: PixelAlive no parameter change
[15:59:00.191] INFO: running: pixelalive
[15:59:00.259] INFO: ----------------------------------------------------------------------
[15:59:00.259] 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:59:00.259] INFO: ----------------------------------------------------------------------
[15:59:00.583] INFO: Expecting 41600 events.
[15:59:04.889] INFO: 41600 events read in total (3587ms).
[15:59:05.059] INFO: Test took 4796ms.
[15:59:05.069] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:05.604] INFO: PixTestAlive::aliveTest() done
[15:59:05.604] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[15:59:05.758] INFO: enter test to run
[15:59:45.086] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:59:45.086] INFO: running: highrate
[15:59:45.104] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[15:59:45.384] INFO: ----------------------------------------------------------------------
[15:59:45.384] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:59:45.384] INFO: ----------------------------------------------------------------------
[15:59:45.384] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:59:45.384] INFO: edge/corner pixel THR is adjusted
[15:59:45.384] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:59:46.343] INFO: Collecting data for 5 seconds...
[15:59:51.359] INFO: Done with hot pixel readout
[16:00:03.175] INFO: PixTest:: pg_setup set to default.
[16:00:03.176] INFO: 8 hot pixels found in step 0
[16:00:04.190] INFO: Collecting data for 5 seconds...
[16:00:09.205] INFO: Done with hot pixel readout
[16:00:21.022] INFO: PixTest:: pg_setup set to default.
[16:00:21.023] INFO: 5 hot pixels found in step 1
[16:00:22.017] INFO: Collecting data for 5 seconds...
[16:00:27.034] INFO: Done with hot pixel readout
[16:00:38.912] INFO: PixTest:: pg_setup set to default.
[16:00:38.913] INFO: 7 hot pixels found in step 2
[16:00:39.905] INFO: Collecting data for 5 seconds...
[16:00:44.920] INFO: Done with hot pixel readout
[16:00:56.723] INFO: PixTest:: pg_setup set to default.
[16:00:56.724] INFO: 8 hot pixels found in step 3
[16:00:57.716] INFO: Collecting data for 5 seconds...
[16:01:02.731] INFO: Done with hot pixel readout
[16:01:14.577] INFO: PixTest:: pg_setup set to default.
[16:01:14.577] INFO: 4 hot pixels found in step 4
[16:01:15.570] INFO: Collecting data for 5 seconds...
[16:01:20.586] INFO: Done with hot pixel readout
[16:01:32.435] INFO: PixTest:: pg_setup set to default.
[16:01:32.436] INFO: 4 hot pixels found in step 5
[16:01:33.429] INFO: Collecting data for 5 seconds...
[16:01:38.444] INFO: Done with hot pixel readout
[16:01:50.359] INFO: PixTest:: pg_setup set to default.

[16:01:50.360] INFO: 4 hot pixels found in step 6
[16:01:51.353] INFO: Collecting data for 5 seconds...
[16:01:56.368] INFO: Done with hot pixel readout
[16:02:08.277] INFO: PixTest:: pg_setup set to default.
[16:02:08.278] INFO: 4 hot pixels found in step 7
[16:02:09.270] INFO: Collecting data for 5 seconds...
[16:02:14.285] INFO: Done with hot pixel readout
[16:02:26.136] INFO: PixTest:: pg_setup set to default.

[16:02:26.137] INFO: 3 hot pixels found in step 8
[16:02:27.130] INFO: Collecting data for 5 seconds...
[16:02:32.145] INFO: Done with hot pixel readout
[16:02:43.819] INFO: PixTest:: pg_setup set to default.
[16:02:43.820] INFO: 0 hot pixels found in step 9
[16:02:43.856] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:02:43.868] INFO: PixTest::trimHotPixels() done
[16:02:43.868] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C0.dat
[16:02:43.874] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C1.dat
[16:02:43.879] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C2.dat
[16:02:43.884] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C3.dat
[16:02:43.890] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C4.dat
[16:02:43.895] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C5.dat
[16:02:43.900] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C6.dat
[16:02:43.906] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C7.dat
[16:02:43.911] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C8.dat
[16:02:43.916] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C9.dat
[16:02:43.921] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C10.dat
[16:02:43.927] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C11.dat
[16:02:43.932] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C12.dat
[16:02:43.937] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C13.dat
[16:02:43.942] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C14.dat
[16:02:43.947] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C15.dat
[16:02:43.953] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//defaultMaskFile.dat
[16:02:43.963] INFO: enter test to run
[16:03:12.029] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[16:03:12.029] INFO: running: highrate
[16:03:12.033] INFO: ----------------------------------------------------------------------
[16:03:12.033] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[16:03:12.033] INFO: ----------------------------------------------------------------------
[16:03:12.033] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[16:03:12.033] INFO: edge/corner pixel THR is adjusted
[16:03:12.033] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:03:12.990] INFO: Collecting data for 1 seconds...
[16:03:13.994] INFO: Done with hot pixel readout
[16:03:18.140] INFO: PixTest:: pg_setup set to default.
[16:03:18.141] INFO: 0 hot pixels found in step 0
[16:03:18.146] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:03:18.223] INFO: PixTest::trimHotPixels() done
[16:03:18.223] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C0.dat
[16:03:18.231] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C1.dat
[16:03:18.240] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C2.dat
[16:03:18.246] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C3.dat
[16:03:18.252] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C4.dat
[16:03:18.257] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C5.dat
[16:03:18.263] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C6.dat
[16:03:18.268] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C7.dat
[16:03:18.273] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C8.dat
[16:03:18.279] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C9.dat
[16:03:18.284] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C10.dat
[16:03:18.290] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C11.dat
[16:03:18.295] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C12.dat
[16:03:18.301] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C13.dat
[16:03:18.306] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C14.dat
[16:03:18.311] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//trimParameters35_C15.dat
[16:03:18.317] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-5-30_FPIXTest-17C-FNAL-160712-1214_2016-07-12_12h14m_1468343661/000_FPIXTest_p17//defaultMaskFile.dat
[16:03:18.327] INFO: enter test to run
[16:04:37.980] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[16:04:37.980] INFO: running: xray
[16:04:37.981] INFO: ----------------------------------------------------------------------
[16:04:37.981] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:04:37.981] INFO: ----------------------------------------------------------------------
[16:04:38.960] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:04:50.262] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[16:05:20.491] INFO: Resuming triggers.
[16:05:31.789] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[16:06:01.287] INFO: Resuming triggers.
[16:06:12.590] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[16:06:41.860] INFO: Resuming triggers.
[16:06:53.162] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[16:07:23.138] INFO: Resuming triggers.
[16:07:34.440] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[16:08:04.429] INFO: Resuming triggers.
[16:08:15.732] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[16:08:45.048] INFO: Resuming triggers.
[16:08:56.354] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[16:09:25.658] INFO: Resuming triggers.
[16:09:36.960] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[16:10:06.628] INFO: Resuming triggers.
[16:10:16.542] INFO: data taking finished, elapsed time: 100 seconds.
[16:10:42.414] INFO: PixTest:: pg_setup set to default.
[16:10:42.418] INFO: PixTestXray::doPhRun() done
[16:10:42.555] INFO: enter test to run
[16:11:49.337] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[16:11:49.337] INFO: running: xray
[16:11:49.338] INFO: ----------------------------------------------------------------------
[16:11:49.338] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:11:49.338] INFO: ----------------------------------------------------------------------
[16:11:50.302] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:11:56.886] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[16:12:27.005] INFO: Resuming triggers.
[16:12:33.590] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[16:13:03.570] INFO: Resuming triggers.
[16:13:10.155] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[16:13:40.197] INFO: Resuming triggers.
[16:13:46.781] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[16:14:16.754] INFO: Resuming triggers.
[16:14:23.336] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[16:14:53.533] INFO: Resuming triggers.
[16:15:00.116] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[16:15:31.127] INFO: Resuming triggers.
[16:15:37.707] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[16:16:08.794] INFO: Resuming triggers.
[16:16:15.377] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[16:16:45.637] INFO: Resuming triggers.
[16:16:52.220] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[16:17:23.637] INFO: Resuming triggers.
[16:17:30.220] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[16:18:00.351] INFO: Resuming triggers.
[16:18:06.937] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[16:18:38.265] INFO: Resuming triggers.
[16:18:44.851] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[16:19:15.794] INFO: Resuming triggers.
[16:19:22.378] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[16:19:52.406] INFO: Resuming triggers.
[16:19:58.989] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[16:20:29.054] INFO: Resuming triggers.
[16:20:35.634] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[16:21:05.669] INFO: Resuming triggers.
[16:21:07.248] INFO: data taking finished, elapsed time: 100 seconds.
[16:21:14.817] INFO: PixTest:: pg_setup set to default.
[16:21:14.820] INFO: PixTestXray::doPhRun() done
[16:21:14.972] INFO: enter test to run
[16:22:43.221] INFO: test: HighRate no parameter change
[16:22:43.221] INFO: running: highrate
[16:22:43.222] INFO: ----------------------------------------------------------------------
[16:22:43.222] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:22:43.222] INFO: ----------------------------------------------------------------------
[16:22:43.365] INFO: Expecting 768 events.
[16:22:44.498] INFO: 768 events read in total (418ms).
[16:22:44.499] INFO: Test took 1269ms.
[16:22:45.302] INFO: Expecting 41600 events.
[16:22:48.465] INFO: 41600 events read in total (2636ms).
[16:22:48.466] INFO: Test took 3959ms.
[16:22:48.501] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:49.221] INFO: Expecting 41600 events.
[16:22:52.439] INFO: 41600 events read in total (2691ms).
[16:22:52.440] INFO: Test took 3919ms.
[16:22:52.475] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:53.196] INFO: Expecting 41600 events.
[16:22:56.424] INFO: 41600 events read in total (2702ms).
[16:22:56.425] INFO: Test took 3931ms.
[16:22:56.460] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:57.183] INFO: Expecting 41600 events.
[16:23:00.420] INFO: 41600 events read in total (2710ms).
[16:23:00.421] INFO: Test took 3943ms.
[16:23:00.456] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:01.178] INFO: Expecting 41600 events.
[16:23:04.408] INFO: 41600 events read in total (2703ms).
[16:23:04.409] INFO: Test took 3934ms.
[16:23:04.445] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:05.169] INFO: Expecting 41600 events.
[16:23:08.412] INFO: 41600 events read in total (2716ms).
[16:23:08.413] INFO: Test took 3949ms.
[16:23:08.448] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:09.167] INFO: Expecting 41600 events.
[16:23:12.409] INFO: 41600 events read in total (2715ms).
[16:23:12.410] INFO: Test took 3942ms.
[16:23:12.445] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:13.167] INFO: Expecting 41600 events.
[16:23:16.415] INFO: 41600 events read in total (2721ms).
[16:23:16.416] INFO: Test took 3953ms.
[16:23:16.451] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:17.171] INFO: Expecting 41600 events.
[16:23:20.399] INFO: 41600 events read in total (2701ms).
[16:23:20.400] INFO: Test took 3930ms.
[16:23:20.436] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:21.155] INFO: Expecting 41600 events.
[16:23:24.397] INFO: 41600 events read in total (2715ms).
[16:23:24.398] INFO: Test took 3945ms.
[16:23:24.433] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:25.156] INFO: Expecting 41600 events.
[16:23:28.396] INFO: 41600 events read in total (2713ms).
[16:23:28.397] INFO: Test took 3945ms.
[16:23:28.432] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:29.157] INFO: Expecting 41600 events.
[16:23:32.317] INFO: 41600 events read in total (2634ms).
[16:23:32.318] INFO: Test took 3868ms.
[16:23:32.354] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:33.074] INFO: Expecting 41600 events.
[16:23:36.324] INFO: 41600 events read in total (2723ms).
[16:23:36.325] INFO: Test took 3952ms.
[16:23:36.361] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:37.080] INFO: Expecting 41600 events.
[16:23:40.274] INFO: 41600 events read in total (2667ms).
[16:23:40.275] INFO: Test took 3894ms.
[16:23:40.313] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:41.029] INFO: Expecting 41600 events.
[16:23:44.270] INFO: 41600 events read in total (2714ms).
[16:23:44.271] INFO: Test took 3939ms.
[16:23:44.306] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:45.032] INFO: Expecting 41600 events.
[16:23:48.287] INFO: 41600 events read in total (2728ms).
[16:23:48.288] INFO: Test took 3963ms.
[16:23:48.323] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:49.042] INFO: Expecting 41600 events.
[16:23:52.201] INFO: 41600 events read in total (2632ms).
[16:23:52.202] INFO: Test took 3862ms.
[16:23:52.237] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:52.956] INFO: Expecting 41600 events.
[16:23:56.186] INFO: 41600 events read in total (2703ms).
[16:23:56.187] INFO: Test took 3930ms.
[16:23:56.222] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:56.946] INFO: Expecting 41600 events.
[16:24:00.121] INFO: 41600 events read in total (2648ms).
[16:24:00.123] INFO: Test took 3882ms.
[16:24:00.158] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:00.879] INFO: Expecting 41600 events.
[16:24:03.956] INFO: 41600 events read in total (2550ms).
[16:24:03.957] INFO: Test took 3779ms.
[16:24:03.991] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:04.355] INFO: enter test to run
[16:24:25.700] INFO: test: HighRate no parameter change
[16:24:25.700] INFO: running: highrate
[16:24:25.701] INFO: ----------------------------------------------------------------------
[16:24:25.701] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:24:25.701] INFO: ----------------------------------------------------------------------
[16:24:26.321] INFO: Expecting 208000 events.
[16:24:38.220] INFO: 208000 events read in total (11372ms).
[16:24:38.223] INFO: Test took 12513ms.
[16:24:38.371] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:38.624] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[16:24:38.624] INFO: number of red-efficiency pixels: 103 45 83 154 174 124 181 97 115 155 142 123 118 85 30 36
[16:24:38.624] INFO: number of X-ray hits detected: 61873 41510 67272 107424 111809 111865 117071 81705 87217 110616 107391 92212 95355 55560 21729 24228
[16:24:38.624] 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:24:38.624] INFO: number of Vcal hits detected: 207897 207954 207916 207791 207821 207873 207817 207898 207883 207843 207854 207873 207882 207914 207970 207963
[16:24:38.624] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[16:24:38.624] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[16:24:38.624] INFO: X-ray hit rate [MHz/cm2]: 18.1 12.2 19.7 31.5 32.8 32.8 34.3 23.9 25.6 32.4 31.5 27.0 27.9 16.3 6.4 7.1
[16:24:38.624] INFO: PixTestHighRate::doXPixelAlive() done
[16:24:38.679] INFO: PixTest:: pg_setup set to default.
[16:24:38.693] INFO: enter test to run
[16:25:16.020] INFO: test: HighRate no parameter change
[16:25:16.020] INFO: running: highrate
[16:25:16.021] INFO: ----------------------------------------------------------------------
[16:25:16.021] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:25:16.021] INFO: ----------------------------------------------------------------------
[16:25:16.638] INFO: Expecting 208000 events.
[16:25:30.364] INFO: 208000 events read in total (13199ms).
[16:25:30.369] INFO: Test took 14338ms.
[16:25:30.672] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:30.975] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[16:25:30.975] INFO: number of red-efficiency pixels: 242 124 296 524 550 402 616 274 301 559 428 398 410 241 60 67
[16:25:30.975] INFO: number of X-ray hits detected: 128895 86413 138803 223728 231901 233101 242827 171063 179958 230318 222292 190865 198187 115758 45446 50581
[16:25:30.975] 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:25:30.975] INFO: number of Vcal hits detected: 207733 207872 207664 207388 207399 207560 207329 207704 207678 207391 207543 207561 207554 207741 207940 207933
[16:25:30.975] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.8 99.7 99.9 99.9 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[16:25:30.975] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.8 99.7 99.9 99.8 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[16:25:30.975] INFO: X-ray hit rate [MHz/cm2]: 37.8 25.3 40.7 65.6 68.0 68.3 71.2 50.1 52.7 67.5 65.2 55.9 58.1 33.9 13.3 14.8
[16:25:30.975] INFO: PixTestHighRate::doXPixelAlive() done
[16:25:31.030] INFO: PixTest:: pg_setup set to default.
[16:25:31.040] INFO: enter test to run
[16:27:04.051] INFO: test: HighRate no parameter change
[16:27:04.051] INFO: running: highrate
[16:27:04.052] INFO: ----------------------------------------------------------------------
[16:27:04.052] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:27:04.052] INFO: ----------------------------------------------------------------------
[16:27:04.679] INFO: Expecting 208000 events.
[16:27:20.396] INFO: 208000 events read in total (15190ms).
[16:27:20.403] INFO: Test took 16340ms.
[16:27:20.859] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:21.216] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[16:27:21.216] INFO: number of red-efficiency pixels: 467 261 695 1198 1202 962 1411 550 595 1345 944 869 888 514 91 107
[16:27:21.216] INFO: number of X-ray hits detected: 190790 127655 205929 331943 343874 343430 359115 253359 267711 342186 329558 282631 294497 171037 67366 75048
[16:27:21.216] 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:27:21.216] INFO: number of Vcal hits detected: 207455 207719 207106 206407 206425 206835 206102 207363 207303 206255 206865 206930 206952 207395 207903 207890
[16:27:21.216] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.6 99.3 99.3 99.5 99.2 99.7 99.7 99.2 99.5 99.6 99.5 99.7 100.0 99.9
[16:27:21.216] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.6 99.2 99.2 99.4 99.1 99.7 99.7 99.2 99.5 99.5 99.5 99.7 100.0 99.9
[16:27:21.216] INFO: X-ray hit rate [MHz/cm2]: 55.9 37.4 60.4 97.3 100.8 100.7 105.3 74.3 78.5 100.3 96.6 82.8 86.3 50.1 19.7 22.0
[16:27:21.216] INFO: PixTestHighRate::doXPixelAlive() done
[16:27:21.262] INFO: PixTest:: pg_setup set to default.
[16:27:21.282] INFO: enter test to run
[16:28:48.418] INFO: test: exit no parameter change
[16:28:48.736] QUIET: Connection to board 33 closed.
[16:28:48.737] INFO: pXar: this is the end, my friend