[14:03:32.185] INFO: *** Welcome to pxar ***
[14:03:32.185] INFO: *** Today: 2016/07/19
[14:03:32.217] INFO: *** Version: v1.9.0-814-g7497
[14:03:32.217] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//dacParameters35_C15.dat
[14:03:32.254] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:03:32.254] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//defaultMaskFile.dat
[14:03:32.270] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C15.dat
[14:03:32.363] INFO: clk: 4
[14:03:32.363] INFO: ctr: 4
[14:03:32.363] INFO: sda: 19
[14:03:32.363] INFO: tin: 9
[14:03:32.363] INFO: level: 15
[14:03:32.363] INFO: triggerdelay: 0
[14:03:32.363] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[14:03:32.363] INFO: Log level: INFO
[14:03:32.381] QUIET: Connection to board DTB_WREKRL opened.
[14:03:32.384] 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:03:32.387] INFO: RPC call hashes of host and DTB match: 398089610
[14:03:33.926] INFO: DUT info:
[14:03:33.926] INFO: The DUT currently contains the following objects:
[14:03:33.926] INFO: 2 TBM Cores tbm08c (2 ON)
[14:03:33.926] INFO: TBM Core alpha (0): 7 registers set
[14:03:33.926] INFO: TBM Core beta (1): 7 registers set
[14:03:33.926] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:03:33.926] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.926] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.926] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.927] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.927] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.927] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.927] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.927] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.927] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.927] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.927] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.927] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.927] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.927] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.927] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:33.927] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:34.330] INFO: enter 'restricted' command line mode
[14:03:34.330] INFO: enter test to run
[14:03:42.267] INFO: test: PixelAlive no parameter change
[14:03:42.267] INFO: running: pixelalive
[14:03:42.276] INFO: ----------------------------------------------------------------------
[14:03:42.276] 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:03:42.276] INFO: ----------------------------------------------------------------------
[14:03:42.594] INFO: Expecting 41600 events.
[14:03:46.934] INFO: 41600 events read in total (3621ms).
[14:03:47.102] INFO: Test took 4823ms.
[14:03:47.116] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:47.378] INFO: PixTestAlive::aliveTest() done
[14:03:47.378] INFO: number of dead pixels (per ROC): 125 21 0 0 0 0 0 3 181 60 28 2 51 52 79 194
[14:03:47.409] INFO: enter test to run
[14:04:27.107] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:04:27.107] INFO: running: highrate
[14:04:27.107] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:04:27.345] INFO: ----------------------------------------------------------------------
[14:04:27.345] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:04:27.345] INFO: ----------------------------------------------------------------------
[14:04:27.345] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:04:27.346] INFO: edge/corner pixel THR is adjusted
[14:04:27.346] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:04:28.305] INFO: Collecting data for 5 seconds...
[14:04:33.323] INFO: Done with hot pixel readout
[14:04:42.194] INFO: PixTest:: pg_setup set to default.
[14:04:42.195] INFO: 0 hot pixels found in step 0
[14:04:42.204] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:04:42.325] INFO: PixTest::trimHotPixels() done
[14:04:42.329] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C0.dat
[14:04:42.340] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C1.dat
[14:04:42.345] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C2.dat
[14:04:42.350] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C3.dat
[14:04:42.356] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C4.dat
[14:04:42.361] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C5.dat
[14:04:42.367] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C6.dat
[14:04:42.372] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C7.dat
[14:04:42.378] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C8.dat
[14:04:42.383] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C9.dat
[14:04:42.389] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C10.dat
[14:04:42.394] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C11.dat
[14:04:42.399] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C12.dat
[14:04:42.405] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C13.dat
[14:04:42.410] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C14.dat
[14:04:42.416] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C15.dat
[14:04:42.421] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//defaultMaskFile.dat
[14:04:42.430] INFO: enter test to run
[14:05:23.491] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:05:23.491] INFO: running: highrate
[14:05:23.495] INFO: ----------------------------------------------------------------------
[14:05:23.495] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:05:23.495] INFO: ----------------------------------------------------------------------
[14:05:23.495] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:05:23.495] INFO: edge/corner pixel THR is adjusted
[14:05:23.495] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:05:24.453] INFO: Collecting data for 1 seconds...
[14:05:25.457] INFO: Done with hot pixel readout
[14:05:27.766] INFO: PixTest:: pg_setup set to default.
[14:05:27.767] INFO: 0 hot pixels found in step 0
[14:05:27.771] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:05:27.926] INFO: PixTest::trimHotPixels() done
[14:05:27.926] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C0.dat
[14:05:27.938] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C1.dat
[14:05:27.944] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C2.dat
[14:05:27.949] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C3.dat
[14:05:27.954] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C4.dat
[14:05:27.960] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C5.dat
[14:05:27.965] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C6.dat
[14:05:27.970] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C7.dat
[14:05:27.975] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C8.dat
[14:05:27.981] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C9.dat
[14:05:27.986] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C10.dat
[14:05:27.991] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C11.dat
[14:05:27.997] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C12.dat
[14:05:27.002] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C13.dat
[14:05:28.007] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C14.dat
[14:05:28.012] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//trimParameters35_C15.dat
[14:05:28.018] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-3-37_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17//defaultMaskFile.dat
[14:05:28.032] INFO: enter test to run
[14:06:00.778] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:06:00.778] INFO: running: xray
[14:06:00.779] INFO: ----------------------------------------------------------------------
[14:06:00.780] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:06:00.780] INFO: ----------------------------------------------------------------------
[14:06:01.743] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:06:17.742] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[14:06:45.316] INFO: Resuming triggers.
[14:07:01.318] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[14:07:29.004] INFO: Resuming triggers.
[14:07:45.005] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[14:08:12.624] INFO: Resuming triggers.
[14:08:28.625] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[14:08:56.324] INFO: Resuming triggers.
[14:09:12.327] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[14:09:39.627] INFO: Resuming triggers.
[14:09:55.628] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[14:10:22.516] INFO: Resuming triggers.
[14:10:26.869] INFO: data taking finished, elapsed time: 100 seconds.
[14:10:34.502] INFO: PixTest:: pg_setup set to default.
[14:10:34.505] INFO: PixTestXray::doPhRun() done
[14:10:34.619] INFO: enter test to run
[14:11:37.296] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:11:37.296] INFO: running: xray
[14:11:37.297] INFO: ----------------------------------------------------------------------
[14:11:37.297] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:11:37.297] INFO: ----------------------------------------------------------------------
[14:11:38.260] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:11:52.327] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[14:12:20.696] INFO: Resuming triggers.
[14:12:34.763] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[14:13:03.397] INFO: Resuming triggers.
[14:13:17.464] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[14:13:45.794] INFO: Resuming triggers.
[14:13:59.862] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[14:14:28.499] INFO: Resuming triggers.
[14:14:42.570] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[14:15:11.092] INFO: Resuming triggers.
[14:15:25.162] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[14:15:53.665] INFO: Resuming triggers.
[14:16:07.736] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[14:16:36.327] INFO: Resuming triggers.
[14:16:38.210] INFO: data taking finished, elapsed time: 100 seconds.
[14:16:42.446] INFO: PixTest:: pg_setup set to default.
[14:16:42.449] INFO: PixTestXray::doPhRun() done
[14:16:42.574] INFO: enter test to run
[14:19:20.797] INFO: test: HighRate no parameter change
[14:19:20.797] INFO: running: highrate
[14:19:20.798] INFO: ----------------------------------------------------------------------
[14:19:20.798] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:19:20.798] INFO: ----------------------------------------------------------------------
[14:19:20.938] INFO: Expecting 768 events.
[14:19:22.072] INFO: 768 events read in total (418ms).
[14:19:22.072] INFO: Test took 1268ms.
[14:19:22.877] INFO: Expecting 41600 events.
[14:19:25.729] INFO: 41600 events read in total (2326ms).
[14:19:25.730] INFO: Test took 3651ms.
[14:19:25.739] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:26.505] INFO: Expecting 41600 events.
[14:19:29.417] INFO: 41600 events read in total (2385ms).
[14:19:29.418] INFO: Test took 3670ms.
[14:19:29.428] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:30.195] INFO: Expecting 41600 events.
[14:19:33.200] INFO: 41600 events read in total (2478ms).
[14:19:33.201] INFO: Test took 3764ms.
[14:19:33.211] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:33.980] INFO: Expecting 41600 events.
[14:19:36.974] INFO: 41600 events read in total (2467ms).
[14:19:36.975] INFO: Test took 3753ms.
[14:19:36.985] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:37.749] INFO: Expecting 41600 events.
[14:19:40.733] INFO: 41600 events read in total (2457ms).
[14:19:40.733] INFO: Test took 3739ms.
[14:19:40.743] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:41.511] INFO: Expecting 41600 events.
[14:19:44.489] INFO: 41600 events read in total (2451ms).
[14:19:44.490] INFO: Test took 3736ms.
[14:19:44.500] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:45.268] INFO: Expecting 41600 events.
[14:19:48.243] INFO: 41600 events read in total (2448ms).
[14:19:48.243] INFO: Test took 3734ms.
[14:19:48.253] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:49.018] INFO: Expecting 41600 events.
[14:19:51.001] INFO: 41600 events read in total (2456ms).
[14:19:52.002] INFO: Test took 3739ms.
[14:19:52.012] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:52.780] INFO: Expecting 41600 events.
[14:19:55.767] INFO: 41600 events read in total (2460ms).
[14:19:55.768] INFO: Test took 3746ms.
[14:19:55.778] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:56.543] INFO: Expecting 41600 events.
[14:19:59.523] INFO: 41600 events read in total (2453ms).
[14:19:59.524] INFO: Test took 3736ms.
[14:19:59.534] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:00.302] INFO: Expecting 41600 events.
[14:20:03.282] INFO: 41600 events read in total (2453ms).
[14:20:03.283] INFO: Test took 3738ms.
[14:20:03.293] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:04.063] INFO: Expecting 41600 events.
[14:20:07.013] INFO: 41600 events read in total (2423ms).
[14:20:07.014] INFO: Test took 3712ms.
[14:20:07.024] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:07.792] INFO: Expecting 41600 events.
[14:20:10.768] INFO: 41600 events read in total (2449ms).
[14:20:10.769] INFO: Test took 3735ms.
[14:20:10.779] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:11.546] INFO: Expecting 41600 events.
[14:20:14.537] INFO: 41600 events read in total (2464ms).
[14:20:14.538] INFO: Test took 3749ms.
[14:20:14.548] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:15.316] INFO: Expecting 41600 events.
[14:20:18.260] INFO: 41600 events read in total (2417ms).
[14:20:18.260] INFO: Test took 3701ms.
[14:20:18.270] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:19.040] INFO: Expecting 41600 events.
[14:20:22.035] INFO: 41600 events read in total (2468ms).
[14:20:22.035] INFO: Test took 3755ms.
[14:20:22.046] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:22.811] INFO: Expecting 41600 events.
[14:20:25.803] INFO: 41600 events read in total (2465ms).
[14:20:25.803] INFO: Test took 3747ms.
[14:20:25.813] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:26.579] INFO: Expecting 41600 events.
[14:20:29.558] INFO: 41600 events read in total (2453ms).
[14:20:29.559] INFO: Test took 3736ms.
[14:20:29.569] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:30.334] INFO: Expecting 41600 events.
[14:20:33.270] INFO: 41600 events read in total (2409ms).
[14:20:33.271] INFO: Test took 3691ms.
[14:20:33.281] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:34.049] INFO: Expecting 41600 events.
[14:20:36.794] INFO: 41600 events read in total (2218ms).
[14:20:36.795] INFO: Test took 3504ms.
[14:20:36.805] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:37.211] INFO: enter test to run
[14:20:47.116] INFO: test: HighRate no parameter change
[14:20:47.116] INFO: running: highrate
[14:20:47.117] INFO: ----------------------------------------------------------------------
[14:20:47.117] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:20:47.118] INFO: ----------------------------------------------------------------------
[14:20:47.735] INFO: Expecting 208000 events.
[14:20:58.112] INFO: 208000 events read in total (9850ms).
[14:20:58.113] INFO: Test took 10989ms.
[14:20:58.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:58.391] INFO: number of dead pixels (per ROC): 122 20 0 0 0 0 0 3 181 57 27 1 50 52 77 193
[14:20:58.392] INFO: number of red-efficiency pixels: 134 36 2 16 12 13 15 20 195 72 42 22 60 62 87 201
[14:20:58.392] INFO: number of X-ray hits detected: 9643 6384 7354 12797 13188 16346 19443 18338 13526 9722 13024 13719 11872 6509 3131 4705
[14:20:58.392] 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:20:58.392] INFO: number of Vcal hits detected: 201670 206897 207998 207984 207988 207987 207985 207816 198936 204942 206566 207818 205444 205390 204008 198217
[14:20:58.392] INFO: Vcal hit fiducial efficiency (%): 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 99.9 100.0 99.9 100.0 100.0 99.9 99.9
[14:20:58.392] INFO: Vcal hit overall efficiency (%): 97.0 99.5 100.0 100.0 100.0 100.0 100.0 99.9 95.6 98.5 99.3 99.9 98.8 98.7 98.1 95.3
[14:20:58.392] INFO: X-ray hit rate [MHz/cm2]: 2.8 1.9 2.2 3.8 3.9 4.8 5.7 5.4 4.0 2.8 3.8 4.0 3.5 1.9 0.9 1.4
[14:20:58.392] INFO: PixTestHighRate::doXPixelAlive() done
[14:20:58.436] INFO: PixTest:: pg_setup set to default.
[14:20:58.445] INFO: enter test to run
[14:22:26.260] INFO: test: HighRate no parameter change
[14:22:26.260] INFO: running: highrate
[14:22:26.261] INFO: ----------------------------------------------------------------------
[14:22:26.261] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:22:26.261] INFO: ----------------------------------------------------------------------
[14:22:26.881] INFO: Expecting 208000 events.
[14:22:37.556] INFO: 208000 events read in total (10148ms).
[14:22:37.558] INFO: Test took 11288ms.
[14:22:37.607] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:37.827] INFO: number of dead pixels (per ROC): 122 20 0 0 0 0 0 3 181 59 28 1 50 52 76 193
[14:22:37.827] INFO: number of red-efficiency pixels: 153 43 20 23 23 30 41 48 209 86 62 30 74 69 90 212
[14:22:37.827] INFO: number of X-ray hits detected: 19256 12660 14819 25994 26539 33362 38675 37182 27967 19295 26533 27661 24051 12858 6406 9354
[14:22:37.827] 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:22:37.827] INFO: number of Vcal hits detected: 201598 206851 207980 207977 207976 207969 207959 207779 198921 204920 206540 207795 205429 205383 203992 198190
[14:22:37.827] INFO: Vcal hit fiducial efficiency (%): 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 99.9 100.0 99.9 100.0 100.0 99.9 99.9
[14:22:37.827] INFO: Vcal hit overall efficiency (%): 96.9 99.4 100.0 100.0 100.0 100.0 100.0 99.9 95.6 98.5 99.3 99.9 98.8 98.7 98.1 95.3
[14:22:37.827] INFO: X-ray hit rate [MHz/cm2]: 5.6 3.7 4.3 7.6 7.8 9.8 11.3 10.9 8.2 5.7 7.8 8.1 7.0 3.8 1.9 2.7
[14:22:37.827] INFO: PixTestHighRate::doXPixelAlive() done
[14:22:37.872] INFO: PixTest:: pg_setup set to default.
[14:22:37.879] INFO: enter test to run
[14:27:04.906] INFO: test: HighRate no parameter change
[14:27:04.906] INFO: running: highrate
[14:27:04.907] INFO: ----------------------------------------------------------------------
[14:27:04.907] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:27:04.907] INFO: ----------------------------------------------------------------------
[14:27:05.527] INFO: Expecting 208000 events.
[14:27:10.872] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (0) != Token Chain Length (8)
[14:27:10.872] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (110) != TBM ID (111)
[14:27:10.872] WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!
[14:27:10.872] WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!
[14:27:10.872] WARNING: Channel 1 ROC 4: Readback start marker after 15 readouts!
[14:27:10.872] WARNING: Channel 1 ROC 5: Readback start marker after 15 readouts!
[14:27:10.872] WARNING: Channel 1 ROC 6: Readback start marker after 15 readouts!
[14:27:10.872] WARNING: Channel 1 ROC 7: Readback start marker after 15 readouts!
[14:27:10.872] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (110) != TBM ID (111)
[14:27:10.872] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:27:10.872] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a172 8000 4388 201 26a4 4388 201 268f 4388 201 2687 4389 201 26a9 4388 201 26a2 4388 201 26a9 4389 201 268f 4389 201 26ab e002 c000
[14:27:10.872] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16b 8040 43c8 201 26a8 43c8 201 268c 43c8 201 2689 4d2 264f 43c9 201 26a7 709 2468 43c8 201 26aa 43c8 201 26a9 43c8 201 26a2 43c8 201 26a4 e002 c000
[14:27:10.872] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16c 80b1 43c9 201 26a2 43c9 201 268d 43c8 201 268b 64c 2049 43c8 112 2066 201 26ae 2db 2881 43c9 201 26a3 859 2a64 43c9 201 26a8 43c9 201 26a4 43c9 201 26a3 e002 c000
[14:27:10.872] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16d 80c0 43c8 119 244f 201 26a6 493 22a5 694 228a 43c8 201 268e 43c8 201 268f 43c8 201 26aa 43c8 201 26a2 43c8 201 26ad 43c8 201 26a2 43c8 201 26a2 e002 c000
[14:27:10.872] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16f 8040 438a 201 26a2 438a 140 2445 201 268e 4389 201 2685 4389 201 26a8 438a 201 268f 28d 2a48 438a 201 26a7 438a 201 268e 438a 201 26a1 e002 c000
[14:27:10.872] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a170 80b1 4389 201 26a8 4389 201 268f 4389 201 2686 4388 201 26af 4389 201 26a6 4389 201 26a6 4389 201 268f 4389 201 26a1 e002 c000
[14:27:10.872] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a171 80c0 4388 201 26af 4388 201 268f 4388 201 2687 4389 201 26a5 4389 201 26a5 451 2664 4389 201 26ad 4389 201 268b 4389 201 26a2 e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a072 8000 4388 201 26ac 4388 201 268e 414 284a 4389 201 268e 4388 201 2685 4388 201 268e 4388 201 26a1 4d0 2a68 4389 201 26ae 4389 201 268b e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06c 80b1 43c9 201 26aa 43c9 201 268b 43c9 201 268d 43ca 201 2686 43ca 201 268b 43c9 201 268f 43c9 201 26c1 43c9 201 268d e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06d 80c0 43c8 201 26ad 43c8 d4 2840 201 2688 43c8 201 268e 43c8 201 268c 43c8 201 268c 43c8 14d 2a46 201 26a2 43c8 201 26af 43c8 201 2685 e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c0 c005
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06f 8040 438a 201 26a6 438a 201 2685 438a 201 268a 4389 201 2688 4388 201 268e 4388 201 268f 438a 201 26ac 438a 201 268c 2cd 224a e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a070 80b1 4388 201 26a6 4388 201 2688 4388 201 268a 4389 201 2685 4388 201 268e 4388 201 26a0 4388 201 26a8 4388 201 268c e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a071 80c0 4388 201 26a8 4388 201 2689 4388 201 268b 4388 201 268b 4388 201 268f 4388 201 26a3 4389 201 26ad 4389 201 268f e002 c000
[14:27:10.873] WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!
[14:27:10.873] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a179 80c0 4389 201 26a8 4389 201 268f 4389 201 268a 6cd 2865 4388 201 26ad 358 2845 4389 201 26ac 4389 201 26ac 4389 201 268f 4389 201 268f e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a173 8040 4388 201 268f 4389 201 26a5 4389 201 268f 4388 201 26af 4388 201 26a8 4389 201 26ab 4388 201 268f 4389 201 26a4 e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a174 80b1 4388 201 26ac 4388 201 268f 4388 201 268d 4388 201 26a4 4388 201 26ac 4388 201 26a6 4388 201 268f 4388 201 268f e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a175 80c0 4388 201 268f 4388 201 268f 4388 201 2682 4388 201 26ae 4388 201 26a2 4388 201 26a9 4388 201 26a2 4388 201 268a e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a176 8000 4388 201 26a9 4388 201 268d 438a 201 2689 4388 201 26af 4388 201 26ad 4388 201 26ab 4388 201 268d 4388 201 26a6 e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a177 8040 4388 201 26a4 4388 201 268f 4389 201 268a 4389 201 26a8 4388 201 26a8 4388 201 26a8 4388 201 268d 4388 201 268f e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a178 80b1 4389 201 268f 4389 201 268e 4388 201 268f 4389 d5 2a6f 201 26a5 4389 201 26aa 320 2065 4389 201 26aa 4389 201 26a2 4389 201 26a2 e002 c000
[14:27:10.873] WARNING: Channel 1 ROC 3: Readback start marker after 31 readouts!
[14:27:10.873] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a181 80c0 4388 201 26a8 6a1 268f 4388 201 268f 4388 201 268f 4389 201 26ac 4389 201 26ac 4389 201 26ac 4389 201 268c 4389 201 268f e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17b 8040 4388 201 268f 860 2687 4388 201 26a8 4388 201 268a 4389 201 26af 4388 201 26ad 4388 201 26ab 4388 201 268f 4388 201 26a5 e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17c 80b1 4389 201 268f 4d3 2a85 4389 201 268f 4388 201 268c 28b 284f 4388 201 26aa 4389 201 26a4 6d2 2666 4389 201 26ad 4389 201 268d 48c 224f 4389 201 268c e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17d 80c0 4388 201 26a2 4388 201 26a9 4388 201 2685 4388 201 26aa 4388 201 26a5 4388 201 26aa 4388 201 268f 4388 201 26a2 e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17e 8000 4388 201 268f 4388 201 26a2 4388 201 268a 438a 201 26ad 4388 201 26a4 4388 201 26a8 4388 201 26a2 4388 201 268e e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17f 8040 438a 201 26a2 438a 201 26a8 4389 201 268a 4389 201 26aa 438a 201 268f 438a 201 26a9 438a 201 268f 438a 201 268f e002 c000
[14:27:10.873] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a180 80b1 4389 201 268f 4389 201 268f 4389 201 2689 4388 201 26a6 4389 201 26a5 4389 201 26a9 4389 201 268f 4389 201 268f e002 c000
[14:27:11.910] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[14:27:11.910] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 1 ROCs were found
[14:27:11.910] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (18) != TBM ID (24)
[14:27:11.910] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (3) != Token Chain Length (8)
[14:27:11.910] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (25) != TBM ID (18)
[14:27:11.910] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:27:11.910] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a114 80b1 4388 310 2680 4388 310 2686 4388 310 268f 4388 310 268a 35c 224a 4388 310 268f 4388 310 268c 4388 310 26a4 4388 310 268f e002 c000
[14:27:11.910] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10e 8000 43c8 310 26a4 43c8 310 2685 43c8 310 268f 43ca 310 268b 43c8 310 268c 43c8 310 268f 43c8 310 268f 43c8 310 268e e002 c000
[14:27:11.910] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10f 8040 43ca 109 2282 310 268a 43ca 310 266e 43c9 310 268f 43c9 310 268e 658 284a 43ca 310 26c5 43ca 310 268f 43ca 310 26a1 43ca 310 268b e002 c000
[14:27:11.910] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a110 80b1 43c9 310 2684 43c9 310 2682 43c9 310 26a2 43c8 310 268c 43c9 310 268f 698 286f 43c9 218 224a 310 26a0 43c9 310 268e 43c9 310 268a e002 c000
[14:27:11.910] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a111 80c0 43c8 310 2684 500 284f 4388 a90 268d 4388 310 26a6 4389 310 268f 9fc 2988 347 4389 4389 310 26a2 4389 310 268e e002 c000
[14:27:11.910] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a112 8000 4388 310 2680 4388 310 2682 4388 310 26a1 4389 310 2689 4388 31b 2880 310 26a4 61a 2885 4388 310 268c 45b 2665 4389 310 268f 4389 310 268b e002 c000
[14:27:11.910] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a113 8040 4388 310 268c 685 288e 4389 310 268a 4389 310 268f 610 226a 4388 310 268d 4388 310 268f 4389 310 2689 4388 310 26a3 4389 310 268f e002 c000
[14:27:11.910] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:27:11.910] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a015 80c0 4388 310 2688 4388 310 2662 4388 310 2680 4389 310 2683 55b 226f 4389 310 268a 4388 310 268f 4388 310 268f 4388 310 2686 e002 c000
[14:27:11.910] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a010 80b1 43c8 310 2688 43c8 310 2665 43c8 310 266f 43c9 310 2686 43c8 310 268c 43c8 310 268f 43c8 310 268d 614 2a84 43c8 310 2689 e002 c000
[14:27:11.911] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a011 80c0 43c8 310 268e 4388 310
[14:27:11.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a018 8083 4388 2661 883 2444 265 220d fc1 2883 4388 2880 b01 2261 883 4389 310 268c 4389 310 2689 4cc 224e e002 c000
[14:27:11.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a012 8000 4388 310 268c 854 2a2c 4388 310 2665 4389 310 2682 4388 310 2682 4388 310 268e 4388 2cc 2642 310 268f 4389 310 268b 4389 310 2688 e002 c000
[14:27:11.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a013 8040 4388 310 268d 4389 310 266d 4388 310 2682 4388 310 2688 508 244f 4388 310 268d 4389 310 268f 54b 2866 4388 310 268f 4389 310 2689 e002 c000
[14:27:11.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a014 80b1 4388 310 268e 4388 310 266a 4388 310 266e 4388 310 268f 4388 310 268f 858 2a4a 4389 310 268a 4388 310 268f 4388 310 268b e002 c000
[14:27:16.508] CRITICAL: <hal.cc/condenseTriggers:L1899> Data size does not correspond to 50 triggers! Aborting data processing!
[14:27:16.516] INFO: 0 events read in total (10462ms).
[14:27:16.516] CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L783> Incomplete DAQ data readout! Missing 4160 Events.
[14:27:17.163] INFO: Expecting 208000 events.
[14:27:26.330] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[14:27:26.330] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:27:26.330] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ed 80c0 43c8 6d1 28af 43c8 65c 2866 6d1 288a 43c8 6d1 28a2 43c8 6d1 28ac 43c8 6d1 28a5 43c8 6d1 28a2 43c8 6d1 2886 43c8 6d1 288f e002 c000
[14:27:26.330] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1e7 8040 4388 6d1 28a2 4388 6d1 2882 4389 14b 2445 6d1 28a2 4389 6d1 28af 4388 290 2045 6d1 28aa 4388 6d1 28ac 4388 6d1 2889 4388 6d1 28a1 e002 c000
[14:27:26.331] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1e8 80b1 4389 6d1 28ac 4389 6d1 2881 4388 6d1 28a2 4389 48c 204e 6d1 28c0 4389 6d1 28a5 4389 a 244e 6d1 28a5 4389 6d1 2886 4389 6d1 288f e002 c000
[14:27:26.331] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1e9 80c0 4389 311 244f 6d1 28a4 4389 6d1 2884 4389 6d1 28a2 4388 6d1 28aa 4389 6d1 28aa 4389 6d1 28a2 4389 6d1 288e 4389 6d1 288f e002 c000
[14:27:26.331] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ea 8000 4388 6d1 28a8 4388 6d1 2885 4388 6d1 288f 43c8 6d1 28a7 550 2da3 2da0 da3 43c8 6d1 288c 43c8 6d1 288f e002 c000
[14:27:26.331] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1eb 8040 43c8 6d1 28ac 43c8 443 2464 6d1 2884 43c8 6d1 28a5 43c9 658 2049 6d1 28aa 43c8 6d1 28aa 43c8 6d1 28a7 43c8 6d1 288d 43c8 6d1 28a4 e002 c000
[14:27:26.331] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ec 80b1 43c9 6d1 28a4 43c9 6d1 288e 43c8 6d1 288f 43c8 302 2a4a 6d1 28af 43c9 6d1 28af 43c9 6d1 28a4 43c9 6d1 288f 43c9 6d1 28a4 e002 c000
[14:27:27.987] INFO: 208000 events read in total (10298ms).
[14:27:27.989] INFO: Test took 11437ms.
[14:27:28.060] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:28.288] INFO: number of dead pixels (per ROC): 122 20 0 0 0 0 0 3 181 58 26 1 50 52 77 194
[14:27:28.288] INFO: number of red-efficiency pixels: 172 58 22 34 42 43 58 76 226 97 66 45 95 76 88 218
[14:27:28.288] INFO: number of X-ray hits detected: 29390 19097 22113 39532 40815 50873 58723 56183 42170 29191 40460 42663 36841 19812 9573 13951
[14:27:28.288] 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:27:28.288] INFO: number of Vcal hits detected: 201540 206813 207978 207966 207957 207957 207941 207754 198904 204910 206532 207761 205406 205376 203986 198178
[14:27:28.288] INFO: Vcal hit fiducial efficiency (%): 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 99.9 99.9
[14:27:28.288] INFO: Vcal hit overall efficiency (%): 96.9 99.4 100.0 100.0 100.0 100.0 100.0 99.9 95.6 98.5 99.3 99.9 98.8 98.7 98.1 95.3
[14:27:28.288] INFO: X-ray hit rate [MHz/cm2]: 8.6 5.6 6.5 11.6 12.0 14.9 17.2 16.5 12.4 8.6 11.9 12.5 10.8 5.8 2.8 4.1
[14:27:28.288] INFO: PixTestHighRate::doXPixelAlive() done
[14:27:28.337] INFO: PixTest:: pg_setup set to default.
[14:27:28.349] INFO: enter test to run
[14:44:21.019] INFO: test: exit no parameter change
[14:44:21.355] QUIET: Connection to board 33 closed.
[14:44:21.364] INFO: pXar: this is the end, my friend