[16:57:30.937] INFO: *** Welcome to pxar ***
[16:57:30.937] INFO: *** Today: 2016/04/05
[16:57:30.970] INFO: *** Version: v1.9.0-793-ge521-dirty
[16:57:30.970] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//dacParameters35_C15.dat
[16:57:30.989] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//tbmParameters_C0b.dat
[16:57:30.989] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//defaultMaskFile.dat
[16:57:31.004] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C15.dat
[16:57:31.117] INFO: clk: 4
[16:57:31.117] INFO: ctr: 4
[16:57:31.117] INFO: sda: 19
[16:57:31.117] INFO: tin: 9
[16:57:31.117] INFO: level: 15
[16:57:31.117] INFO: triggerdelay: 0
[16:57:31.117] QUIET: Instanciating API for pxar v1.9.0+793~ge5211a7
[16:57:31.117] INFO: Log level: INFO
[16:57:31.136] QUIET: Connection to board DTB_WREKRL opened.
[16:57:31.139] 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:
------------------------------------------------------
[16:57:31.142] INFO: RPC call hashes of host and DTB match: 398089610
[16:57:32.669] INFO: DUT info:
[16:57:32.669] INFO: The DUT currently contains the following objects:
[16:57:32.669] INFO: 2 TBM Cores tbm08c (2 ON)
[16:57:32.669] INFO: TBM Core alpha (0): 7 registers set
[16:57:32.669] INFO: TBM Core beta (1): 7 registers set
[16:57:32.669] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:57:32.669] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.669] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.669] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.669] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.669] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.669] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.670] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.670] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.670] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.670] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.670] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.670] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.670] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.670] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.670] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:32.670] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:33.073] INFO: enter 'restricted' command line mode
[16:57:33.074] INFO: enter test to run
[16:57:46.046] INFO: test: PixelAlive no parameter change
[16:57:46.046] INFO: running: pixelalive
[16:57:46.055] INFO: ----------------------------------------------------------------------
[16:57:46.055] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:57:46.055] INFO: ----------------------------------------------------------------------
[16:57:46.375] INFO: Expecting 41600 events.
[16:57:50.705] INFO: 41600 events read in total (3611ms).
[16:57:50.870] INFO: Test took 4812ms.
[16:57:50.880] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:51.144] INFO: PixTestAlive::aliveTest() done
[16:57:51.144] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 72 0 0 0 0 0
[16:57:51.172] INFO: enter test to run
[16:58:13.022] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[16:58:13.022] INFO: running: highrate
[16:58:13.022] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[16:58:13.232] INFO: ----------------------------------------------------------------------
[16:58:13.232] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[16:58:13.232] INFO: ----------------------------------------------------------------------
[16:58:13.232] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[16:58:13.232] INFO: edge/corner pixel THR is adjusted
[16:58:13.232] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:58:14.192] INFO: Collecting data for 5 seconds...
[16:58:19.211] INFO: Done with hot pixel readout
[16:58:31.255] INFO: PixTest:: pg_setup set to default.
[16:58:31.256] INFO: 23 hot pixels found in step 0
[16:58:32.275] INFO: Collecting data for 5 seconds...
[16:58:37.292] INFO: Done with hot pixel readout
[16:58:49.384] INFO: PixTest:: pg_setup set to default.
[16:58:49.385] INFO: 24 hot pixels found in step 1
[16:58:50.376] INFO: Collecting data for 5 seconds...
[16:58:55.393] INFO: Done with hot pixel readout
[16:59:07.462] INFO: PixTest:: pg_setup set to default.
[16:59:07.463] INFO: 22 hot pixels found in step 2
[16:59:08.455] INFO: Collecting data for 5 seconds...
[16:59:13.471] INFO: Done with hot pixel readout
[16:59:25.508] INFO: PixTest:: pg_setup set to default.
[16:59:25.508] INFO: 23 hot pixels found in step 3
[16:59:26.500] INFO: Collecting data for 5 seconds...
[16:59:31.517] INFO: Done with hot pixel readout
[16:59:43.600] INFO: PixTest:: pg_setup set to default.
[16:59:43.601] INFO: 21 hot pixels found in step 4
[16:59:44.592] INFO: Collecting data for 5 seconds...
[16:59:49.608] INFO: Done with hot pixel readout
[17:00:01.671] INFO: PixTest:: pg_setup set to default.
[17:00:01.672] INFO: 21 hot pixels found in step 5
[17:00:02.663] INFO: Collecting data for 5 seconds...
[17:00:07.680] INFO: Done with hot pixel readout
[17:00:19.673] INFO: PixTest:: pg_setup set to default.
[17:00:19.674] INFO: 15 hot pixels found in step 6
[17:00:20.665] INFO: Collecting data for 5 seconds...
[17:00:25.682] INFO: Done with hot pixel readout
[17:00:37.769] INFO: PixTest:: pg_setup set to default.
[17:00:37.770] INFO: 6 hot pixels found in step 7
[17:00:38.760] INFO: Collecting data for 5 seconds...
[17:00:43.778] INFO: Done with hot pixel readout
[17:00:55.855] INFO: PixTest:: pg_setup set to default.
[17:00:55.855] INFO: 5 hot pixels found in step 8
[17:00:56.846] INFO: Collecting data for 5 seconds...
[17:01:01.863] INFO: Done with hot pixel readout
[17:01:13.926] INFO: PixTest:: pg_setup set to default.
[17:01:13.926] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[17:01:13.927] INFO: 4 hot pixels found in step 9
[17:01:14.917] INFO: Collecting data for 5 seconds...
[17:01:19.935] INFO: Done with hot pixel readout
[17:01:31.999] INFO: PixTest:: pg_setup set to default.
[17:01:31.999] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[17:01:31.000] INFO: 6 hot pixels found in step 10
[17:01:32.990] INFO: Collecting data for 5 seconds...
[17:01:38.007] INFO: Done with hot pixel readout
[17:01:50.080] INFO: PixTest:: pg_setup set to default.
[17:01:50.081] INFO: 3 hot pixels found in step 11
[17:01:51.071] INFO: Collecting data for 5 seconds...
[17:01:56.087] INFO: Done with hot pixel readout
[17:02:08.130] INFO: PixTest:: pg_setup set to default.
[17:02:08.131] INFO: 4 hot pixels found in step 12
[17:02:09.122] INFO: Collecting data for 5 seconds...
[17:02:14.139] INFO: Done with hot pixel readout
[17:02:26.261] INFO: PixTest:: pg_setup set to default.
[17:02:26.261] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[17:02:26.262] INFO: 3 hot pixels found in step 13
[17:02:27.252] INFO: Collecting data for 5 seconds...
[17:02:32.270] INFO: Done with hot pixel readout
[17:02:44.323] INFO: PixTest:: pg_setup set to default.
[17:02:44.323] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[17:02:44.324] INFO: 3 hot pixels found in step 14
[17:02:44.358] INFO: 3 hot pixels could not be trimmed and have been masked.
[17:02:44.362] INFO: PixTest::trimHotPixels() done
[17:02:44.367] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C0.dat
[17:02:44.373] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C1.dat
[17:02:44.380] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C2.dat
[17:02:44.385] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C3.dat
[17:02:44.391] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C4.dat
[17:02:44.396] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C5.dat
[17:02:44.401] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C6.dat
[17:02:44.406] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C7.dat
[17:02:44.412] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C8.dat
[17:02:44.417] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C9.dat
[17:02:44.422] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C10.dat
[17:02:44.427] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C11.dat
[17:02:44.432] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C12.dat
[17:02:44.438] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C13.dat
[17:02:44.443] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C14.dat
[17:02:44.448] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C15.dat
[17:02:44.453] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//defaultMaskFile.dat
[17:02:44.463] INFO: enter test to run
[17:03:15.819] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[17:03:15.819] INFO: running: highrate
[17:03:15.824] INFO: ----------------------------------------------------------------------
[17:03:15.824] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[17:03:15.824] INFO: ----------------------------------------------------------------------
[17:03:15.824] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[17:03:15.824] INFO: edge/corner pixel THR is adjusted
[17:03:15.824] INFO: PixTestHighRate::trimHotPixels: step 0...
[17:03:16.782] INFO: Collecting data for 1 seconds...
[17:03:17.785] INFO: Done with hot pixel readout
[17:03:22.109] INFO: PixTest:: pg_setup set to default.
[17:03:22.110] INFO: 0 hot pixels found in step 0
[17:03:22.115] INFO: 0 hot pixels could not be trimmed and have been masked.
[17:03:22.194] INFO: PixTest::trimHotPixels() done
[17:03:22.194] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C0.dat
[17:03:22.206] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C1.dat
[17:03:22.211] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C2.dat
[17:03:22.217] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C3.dat
[17:03:22.222] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C4.dat
[17:03:22.227] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C5.dat
[17:03:22.232] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C6.dat
[17:03:22.238] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C7.dat
[17:03:22.243] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C8.dat
[17:03:22.248] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C9.dat
[17:03:22.253] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C10.dat
[17:03:22.259] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C11.dat
[17:03:22.264] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C12.dat
[17:03:22.269] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C13.dat
[17:03:22.274] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C14.dat
[17:03:22.280] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//trimParameters35_C15.dat
[17:03:22.285] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-G-3-50_FPIXTest-17C-FNAL-160113-1435_2016-01-13_14h35m_1452717354/000_FPIXTest_p17//defaultMaskFile.dat
[17:03:22.302] INFO: enter test to run
[17:03:43.771] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[17:03:43.771] INFO: running: xray
[17:03:43.772] INFO: ----------------------------------------------------------------------
[17:03:43.772] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:03:43.772] INFO: ----------------------------------------------------------------------
[17:03:44.732] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:03:55.199] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[17:04:24.707] INFO: Resuming triggers.
[17:04:35.177] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[17:05:04.816] INFO: Resuming triggers.
[17:05:15.288] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[17:05:44.899] INFO: Resuming triggers.
[17:05:55.371] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[17:06:24.978] INFO: Resuming triggers.
[17:06:35.446] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[17:07:05.067] INFO: Resuming triggers.
[17:07:15.534] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[17:07:45.115] INFO: Resuming triggers.
[17:07:55.587] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[17:08:25.167] INFO: Resuming triggers.
[17:08:35.641] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[17:09:05.151] INFO: Resuming triggers.
[17:09:15.621] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[17:09:45.151] INFO: Resuming triggers.
[17:09:51.255] INFO: data taking finished, elapsed time: 100 seconds.
[17:10:08.810] INFO: PixTest:: pg_setup set to default.
[17:10:08.814] INFO: PixTestXray::doPhRun() done
[17:10:08.952] INFO: enter test to run
[17:11:43.536] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[17:11:43.536] INFO: running: xray
[17:11:43.537] INFO: ----------------------------------------------------------------------
[17:11:43.537] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:11:43.537] INFO: ----------------------------------------------------------------------
[17:11:44.502] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:11:50.564] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[17:12:20.651] INFO: Resuming triggers.
[17:12:26.715] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[17:12:43.029] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (48) != TBM ID (49)
[17:12:43.029] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (48) != TBM ID (49)
[17:12:43.029] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:12:43.029] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a034 80b1 43c8 81a 2248 43c8 43c8 22 2881 6c2 244d 43c8 858 2a45 43c8 451 2a45 80d 2646 43c8 55a 2065 43c8 159 2468 2cb 2881 30b 2064 81d 2669 43c8 99 2049 649 2465 e000 c000
[17:12:43.029] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02d 80c0 4388 4388 4388 803 224f 4388 2 2869 4388 315 2249 64a 2861 4388 68c 2445 4388 202 2249 30c 244e 41b 204f 709 204d 4388 e000 c000
[17:12:43.029] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02e 8000 4388 842 264d 438a 5b 2a6d 258 2647 453 246e 4388 858 224c 4388 2cd 2a89 304 2041 4388 494 2a28 4388 10b 2682 454 2a4f 4388 51c 2641 681 2045 84a 264f 4388 e000 c000
[17:12:43.029] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02f 8040 438a 4388 438a 438a 4c9 2645 438a 438a b 284d 4a 2449 14b 206d 14a 2680 50b 2081 513 266e 438a 45a 224f 438a e000 c000
[17:12:43.029] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a031 80c0 43c8 553 2468 43c8 43c8 219 2266 43c8 219 2640 753 2649 43c9 43c9 14 242f 10 204d 289 244f 360 204e 43c9 24d 204d 4ca 244d 60b 2a62 661 226d 752 204b 43c9 654 2043 653 286c e000 c000
[17:12:43.029] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a032 8000 43c8 31a 264e 43c9 6da 2469 43c9 213 2085 4d0 2481 43c9 43c8 51a 246e 858 244f 43c8 150 204c 688 2a8c 68d 2649 43c9 715 2045 43c9 9 2240 10d 2881 104 2a4c 142 244f 55b 2283 e000 c000
[17:12:43.029] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a033 8040 43c8 43c8 13 202f 43c8 34a 2869 342 244c 855 204d 849 2241 43c9 2d0 284c 303 264c 705 2849 801 2461 43c8 40b 2467 43c9 b 2265 2c9 222e 551 244d 815 2a69 43c8 4a2 2846 71d 2a69 751 224f 43c9 c0 2863 e000 c000
[17:12:43.029] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:12:43.029] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a134 80b1 43c8 30d 2880 495 2682 50a 2862 43c8 43c8 2cd 2a49 43c8 74b 2246 43c8 43c8 249 264f 2ca 244f 43c8 4c3 282d 43c8 e000 c000
[17:12:43.029] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12d 80c0 4388 255 2668 459 2062 4388 281 2669 281 2a69 4388 280 2882 4388 71a 2a47 4388 4388 6e0 2461 4388 4388 e000 c000
[17:12:43.029] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12e 8000 4388 149 206e 443 2868 648 2a62 4388 609 2845 438a c 2264 652 266d 702 224f 4388 48a 264c 4388 759 204c 749 2481 4388 111 264f 4388 4388 e000 c000
[17:12:43.029] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12f 8040 438a 719 2887 860 286f 438a 854 2029 4389 341 206d 4c5 2661 438a 345 2047 4d0 2045 71c 2247 84b 2281 438a 759 266b 438a 89 2661 243 204f 438a 438a e000 c000
[17:12:43.029] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a131 80c0 43c8 43c8 43c9 43c8 29a 2a6c 4c9 2860 43c9 343 264f 43c9 43c9 43c9 44b 2445 e000 c000
[17:12:43.029] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a132 8000 43c8 43c8 1b 2861 112 286d 109 2262 255 2840 28b 2669 43c8 35c 2068 40a 2885 68c 202f 43c9 13 2484 d 2860 e1 2860 115 284d 43c8 24a 244f 4c1 284f 80b 284e 43c8 353 204d 410 2045 41a 2a4c 43c9 43c9 621 2647 e000 c000
[17:12:43.029] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a133 8040 43c8 403 248f 6db 206e 43c9 55d 262f 43c8 43c9 609 2a61 43c8 c9 2447 34d 284d 43c9 43c8 43c9 e000 c000
[17:12:43.030] WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 0 ROC 4: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 0 ROC 5: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 0 ROC 6: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 0 ROC 7: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 1 ROC 4: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 1 ROC 5: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 1 ROC 6: Readback start marker after 15 readouts!
[17:12:43.030] WARNING: Channel 1 ROC 7: Readback start marker after 15 readouts!
[17:12:43.030] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:12:43.030] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a042 8000 43c8 54 2429 9b 2260 858 202d 43c9 43c9 118 2685 43c9 88 2a44 43c8 43c8 52 224c 210 2a4b 811 2a61 43c9 4cd 2660 698 2446 43c9 e000 c000
[17:12:43.030] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03c 80b1 43c8 53 2246 43c8 43c8 501 2a4f 860 264b 43c8 43c8 44 2a6c 422 264d 74b 2249 752 2a4d 43c8 43c8 55d 284f 719 244f 43c8 219 284f 314 2881 e000 c000
[17:12:43.030] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03d 80c0 43c8 93 2648 441 2647 43c8 43c8 4d3 2061 602 206c 43c8 43c8 cd 2067 43c8 9 2045 258 2040 40a 2685 43c8 261 2a88 308 202c 43c8 21c 2a43 e000 c000
[17:12:43.030] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03e 8000 43c8 43ca 29d 2661 43c8 84d 2647 43c8 c 264c 851 244f 43c8 10 226c 295 2046 310 2425 682 264d 709 244c 70b 2a45 43c8 104 2282 105 2a8a 43c8 21d 2a48 252 286d 355 2449 743 2649 85a 2a62 43c8 285 22ac 28c 2668 e000 c000
[17:12:43.030] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03f 8040 43ca 294 206d 43c8 19 2063 43ca 4d8 2862 43ca 43ca 43ca 318 2465 4ca 2a4d 712 264d 43ca 12 2029 621 204d 852 2263 43ca 4d 244d 99 2245 e000 c000
[17:12:43.030] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a040 80b1 43c8 91 2a40 252 2048 65a 2249 43c8 4d3 204f 43c8 4cb 2082 505 2a4f 43c8 43c8 843 226a 843 2643 43c8 83 226c 24a 284d 345 2260 615 264f 43c8 43c8 8 222d 14a 2883 508 2664 e000 c000
[17:12:43.030] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a041 80c0 43c8 11a 246d 120 2628 43c8 84 2081 d1 2464 21a 2a65 43c8 493 2861 4dd 206c 43c8 1d 202f 43c9 43c9 694 2643 43c9 120 2a4a 4d5 2461 60c 2261 705 2a4c 43c9 498 2661 e000 c000
[17:12:43.030] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:12:43.030] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a142 8000 43c8 43c8 1a 2060 2d4 2841 43c8 28d 2a84 69b 2465 43c9 252 2862 35b 246f 552 2283 43c8 60 2466 31b 204f 43c8 209 242f 315 2461 43c9 552 2844 43c9 e000 c000
[17:12:43.030] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13c 80b1 43c8 750 2864 85b 2287 43c8 355 244f 615 222d 43c8 43c8 461 2a65 43c8 43c8 43c8 819 262c 43c8 e000 c000
[17:12:43.030] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13d 80c0 43c8 4d5 2064 661 224d 43c8 c 2041 43c8 70c 2a4d 43c8 43c8 8a 284d 43c8 243 2263 51c 2261 43c8 85c 222d 43c8 dd 284d 29a 2442 e000 c000
[17:12:43.030] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13e 8000 43c8 43c8 43ca 411 2261 858 2669 43c8 491 204f 43c8 43c8 43c8 43c8 e000 c000
[17:12:43.031] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13f 8040 43ca 6db 2a6e 43ca 1b 2a8c 84d 2469 43c9 53 288d 43ca 43ca 220 2062 295 226c 85a 224d 43ca 43ca 43ca 6a2 2a42 e000 c000
[17:12:43.031] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a140 80b1 43c9 43c9 a 2a49 11c 2848 29c 2243 43c8 85 2284 cb 206f 4cb 2865 43c9 43c9 362 2249 43c9 43c9 43c9 e000 c000
[17:12:43.031] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a141 80c0 43c8 43c8 15 2461 2c2 2a4c 481 2662 43c9 75c 2843 84b 2682 43c8 513 204c 43c9 43c9 43 2249 43c9 71b 2641 43c9 25b 2269 e000 c000
[17:12:56.698] INFO: Resuming triggers.
[17:13:02.762] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[17:13:32.823] INFO: Resuming triggers.
[17:13:38.887] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[17:14:06.521] INFO: Resuming triggers.
[17:14:12.581] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[17:14:42.512] INFO: Resuming triggers.
[17:14:48.575] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[17:15:18.585] INFO: Resuming triggers.
[17:15:24.648] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[17:15:54.761] INFO: Resuming triggers.
[17:16:00.827] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[17:16:30.941] INFO: Resuming triggers.
[17:16:37.006] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[17:17:07.073] INFO: Resuming triggers.
[17:17:13.136] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[17:17:43.123] INFO: Resuming triggers.
[17:17:49.187] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[17:18:19.302] INFO: Resuming triggers.
[17:18:25.367] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[17:18:55.357] INFO: Resuming triggers.
[17:19:01.424] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[17:19:31.446] INFO: Resuming triggers.
[17:19:37.512] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[17:20:07.563] INFO: Resuming triggers.
[17:20:13.627] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[17:20:43.714] INFO: Resuming triggers.
[17:20:49.781] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[17:21:19.863] INFO: Resuming triggers.
[17:21:23.164] INFO: data taking finished, elapsed time: 100 seconds.
[17:21:39.766] INFO: PixTest:: pg_setup set to default.
[17:21:39.770] INFO: PixTestXray::doPhRun() done
[17:21:39.919] INFO: enter test to run
[17:23:10.267] INFO: test: HighRate no parameter change
[17:23:10.267] INFO: running: highrate
[17:23:10.268] INFO: ----------------------------------------------------------------------
[17:23:10.268] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:23:10.268] INFO: ----------------------------------------------------------------------
[17:23:10.409] INFO: Expecting 768 events.
[17:23:11.544] INFO: 768 events read in total (419ms).
[17:23:11.544] INFO: Test took 1269ms.
[17:23:12.347] INFO: Expecting 41600 events.
[17:23:15.561] INFO: 41600 events read in total (2687ms).
[17:23:15.562] INFO: Test took 4010ms.
[17:23:15.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:16.314] INFO: Expecting 41600 events.
[17:23:19.622] INFO: 41600 events read in total (2781ms).
[17:23:19.623] INFO: Test took 3998ms.
[17:23:19.667] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:20.376] INFO: Expecting 41600 events.
[17:23:23.699] INFO: 41600 events read in total (2796ms).
[17:23:23.700] INFO: Test took 4012ms.
[17:23:23.744] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:24.450] INFO: Expecting 41600 events.
[17:23:27.791] INFO: 41600 events read in total (2814ms).
[17:23:27.792] INFO: Test took 4026ms.
[17:23:27.835] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:28.540] INFO: Expecting 41600 events.
[17:23:31.863] INFO: 41600 events read in total (2796ms).
[17:23:31.864] INFO: Test took 4007ms.
[17:23:31.908] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:32.615] INFO: Expecting 41600 events.
[17:23:35.943] INFO: 41600 events read in total (2801ms).
[17:23:35.944] INFO: Test took 4015ms.
[17:23:35.988] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:36.695] INFO: Expecting 41600 events.
[17:23:39.822] INFO: 41600 events read in total (2600ms).
[17:23:39.823] INFO: Test took 3814ms.
[17:23:39.868] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:40.576] INFO: Expecting 41600 events.
[17:23:43.493] INFO: 41600 events read in total (2390ms).
[17:23:43.494] INFO: Test took 3605ms.
[17:23:43.538] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:44.246] INFO: Expecting 41600 events.
[17:23:47.145] INFO: 41600 events read in total (2372ms).
[17:23:47.146] INFO: Test took 3586ms.
[17:23:47.190] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:47.898] INFO: Expecting 41600 events.
[17:23:51.230] INFO: 41600 events read in total (2806ms).
[17:23:51.231] INFO: Test took 4018ms.
[17:23:51.275] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:51.984] INFO: Expecting 41600 events.
[17:23:54.920] INFO: 41600 events read in total (2409ms).
[17:23:54.921] INFO: Test took 3624ms.
[17:23:54.965] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:55.672] INFO: Expecting 41600 events.
[17:23:59.028] INFO: 41600 events read in total (2829ms).
[17:23:59.029] INFO: Test took 4043ms.
[17:23:59.073] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:59.777] INFO: Expecting 41600 events.
[17:24:03.118] INFO: 41600 events read in total (2814ms).
[17:24:03.119] INFO: Test took 4026ms.
[17:24:03.163] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:03.868] INFO: Expecting 41600 events.
[17:24:07.208] INFO: 41600 events read in total (2813ms).
[17:24:07.209] INFO: Test took 4024ms.
[17:24:07.254] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:07.951] INFO: Expecting 41600 events.
[17:24:11.296] INFO: 41600 events read in total (2818ms).
[17:24:11.297] INFO: Test took 4022ms.
[17:24:11.348] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:12.041] INFO: Expecting 41600 events.
[17:24:15.364] INFO: 41600 events read in total (2796ms).
[17:24:15.365] INFO: Test took 3995ms.
[17:24:15.410] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:16.116] INFO: Expecting 41600 events.
[17:24:19.463] INFO: 41600 events read in total (2820ms).
[17:24:19.464] INFO: Test took 4032ms.
[17:24:19.508] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:20.209] INFO: Expecting 41600 events.
[17:24:23.568] INFO: 41600 events read in total (2832ms).
[17:24:23.570] INFO: Test took 4041ms.
[17:24:23.614] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:24.317] INFO: Expecting 41600 events.
[17:24:27.646] INFO: 41600 events read in total (2801ms).
[17:24:27.648] INFO: Test took 4012ms.
[17:24:27.691] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:28.393] INFO: Expecting 41600 events.
[17:24:31.554] INFO: 41600 events read in total (2634ms).
[17:24:31.555] INFO: Test took 3843ms.
[17:24:31.597] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:31.938] INFO: enter test to run
[17:25:53.273] INFO: test: HighRate no parameter change
[17:25:53.273] INFO: running: highrate
[17:25:53.275] INFO: ----------------------------------------------------------------------
[17:25:53.275] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:25:53.275] INFO: ----------------------------------------------------------------------
[17:25:53.891] INFO: Expecting 208000 events.
[17:26:06.342] INFO: 208000 events read in total (11924ms).
[17:26:06.345] INFO: Test took 13062ms.
[17:26:06.537] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:26:06.806] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 73 0 0 0 0 0
[17:26:06.806] INFO: number of red-efficiency pixels: 135 79 151 167 223 228 249 197 170 193 286 166 159 98 40 44
[17:26:06.806] INFO: number of X-ray hits detected: 90234 58880 93238 141404 153595 157928 159728 112771 112332 137869 130383 112165 114487 69594 28606 34157
[17:26:06.806] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:26:06.806] INFO: number of Vcal hits detected: 207863 207919 207844 207824 207765 207763 207745 207798 207826 207802 204057 207829 207833 207900 207959 207956
[17:26:06.806] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[17:26:06.806] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 98.1 99.9 99.9 100.0 100.0 100.0
[17:26:06.806] INFO: X-ray hit rate [MHz/cm2]: 26.4 17.3 27.3 41.4 45.0 46.3 46.8 33.1 32.9 40.4 38.2 32.9 33.6 20.4 8.4 10.0
[17:26:06.806] INFO: PixTestHighRate::doXPixelAlive() done
[17:26:06.856] INFO: PixTest:: pg_setup set to default.
[17:26:06.871] INFO: enter test to run
[17:27:12.113] INFO: test: HighRate no parameter change
[17:27:12.113] INFO: running: highrate
[17:27:12.114] INFO: ----------------------------------------------------------------------
[17:27:12.114] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:27:12.114] INFO: ----------------------------------------------------------------------
[17:27:12.731] INFO: Expecting 208000 events.
[17:27:27.290] INFO: 208000 events read in total (14032ms).
[17:27:27.297] INFO: Test took 15172ms.
[17:27:27.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:27:27.981] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 74 0 0 0 0 0
[17:27:27.981] INFO: number of red-efficiency pixels: 383 208 486 516 853 899 809 558 506 638 703 390 484 271 83 79
[17:27:27.981] INFO: number of X-ray hits detected: 169195 112345 175674 266998 290959 297608 300522 212751 211927 260361 246739 211718 216436 131873 54270 63938
[17:27:27.981] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:27:27.981] INFO: number of Vcal hits detected: 207566 207778 207412 207437 206989 206936 207018 207337 207443 207283 203563 207562 207481 207714 207913 207918
[17:27:27.981] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.7 99.5 99.5 99.6 99.7 99.8 99.7 99.7 99.8 99.8 99.9 100.0 100.0
[17:27:27.981] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.7 99.5 99.5 99.5 99.7 99.7 99.7 97.9 99.8 99.8 99.9 100.0 100.0
[17:27:27.981] INFO: X-ray hit rate [MHz/cm2]: 49.6 32.9 51.5 78.3 85.3 87.2 88.1 62.4 62.1 76.3 72.3 62.1 63.4 38.7 15.9 18.7
[17:27:27.981] INFO: PixTestHighRate::doXPixelAlive() done
[17:27:28.028] INFO: PixTest:: pg_setup set to default.
[17:27:28.041] INFO: enter test to run
[17:27:58.177] INFO: test: HighRate no parameter change
[17:27:58.177] INFO: running: highrate
[17:27:58.178] INFO: ----------------------------------------------------------------------
[17:27:58.178] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:27:58.178] INFO: ----------------------------------------------------------------------
[17:27:58.798] INFO: Expecting 208000 events.
[17:28:15.285] INFO: 208000 events read in total (15960ms).
[17:28:15.294] INFO: Test took 17106ms.
[17:28:15.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:28:16.167] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 74 0 0 0 0 0
[17:28:16.168] INFO: number of red-efficiency pixels: 685 385 891 1039 1642 1716 1572 1072 938 1193 1291 791 896 525 118 125
[17:28:16.168] INFO: number of X-ray hits detected: 236675 155881 244940 371791 405712 415808 419878 297868 298147 363726 344954 296908 303700 183997 75702 90207
[17:28:16.168] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:28:16.168] INFO: number of Vcal hits detected: 207062 207569 206658 206642 205655 205451 205723 206511 206856 206464 202645 207040 206933 207352 207879 207871
[17:28:16.168] INFO: Vcal hit fiducial efficiency (%): 99.6 99.8 99.4 99.4 99.0 98.9 99.0 99.4 99.5 99.3 99.3 99.6 99.5 99.7 99.9 99.9
[17:28:16.168] INFO: Vcal hit overall efficiency (%): 99.5 99.8 99.4 99.3 98.9 98.8 98.9 99.3 99.5 99.3 97.4 99.5 99.5 99.7 99.9 99.9
[17:28:16.168] INFO: X-ray hit rate [MHz/cm2]: 69.4 45.7 71.8 109.0 118.9 121.9 123.1 87.3 87.4 106.6 101.1 87.0 89.0 53.9 22.2 26.4
[17:28:16.168] INFO: PixTestHighRate::doXPixelAlive() done
[17:28:16.212] INFO: PixTest:: pg_setup set to default.
[17:28:16.232] INFO: enter test to run
[17:28:27.864] INFO: test: exit no parameter change
[17:28:28.183] QUIET: Connection to board 33 closed.
[17:28:28.185] INFO: pXar: this is the end, my friend