[14:02:36.828] INFO: *** Welcome to pxar ***
[14:02:36.829] INFO: *** Today: 2016/04/19
[14:02:36.850] INFO: *** Version: v1.9.0-796-gef167-dirty
[14:02:36.850] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//dacParameters35_C15.dat
[14:02:36.854] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:02:36.854] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//defaultMaskFile.dat
[14:02:36.854] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C15.dat
[14:02:36.970] INFO: clk: 4
[14:02:36.970] INFO: ctr: 4
[14:02:36.970] INFO: sda: 19
[14:02:36.970] INFO: tin: 9
[14:02:36.970] INFO: level: 15
[14:02:36.970] INFO: triggerdelay: 0
[14:02:36.970] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[14:02:36.970] INFO: Log level: INFO
[14:02:36.987] QUIET: Connection to board DTB_WREKRL opened.
[14:02:36.990] 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:02:36.993] INFO: RPC call hashes of host and DTB match: 398089610
[14:02:38.526] INFO: DUT info:
[14:02:38.526] INFO: The DUT currently contains the following objects:
[14:02:38.526] INFO: 2 TBM Cores tbm08c (2 ON)
[14:02:38.526] INFO: TBM Core alpha (0): 7 registers set
[14:02:38.526] INFO: TBM Core beta (1): 7 registers set
[14:02:38.526] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:02:38.527] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.527] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:38.930] INFO: enter 'restricted' command line mode
[14:02:38.930] INFO: enter test to run
[14:02:57.217] INFO: test: PixelAlive no parameter change
[14:02:57.217] INFO: running: pixelalive
[14:02:57.227] INFO: ----------------------------------------------------------------------
[14:02:57.227] 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:02:57.227] INFO: ----------------------------------------------------------------------
[14:02:57.547] INFO: Expecting 41600 events.
[14:03:01.889] INFO: 41600 events read in total (3623ms).
[14:03:02.057] INFO: Test took 4828ms.
[14:03:02.071] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:02.334] INFO: PixTestAlive::aliveTest() done
[14:03:02.334] INFO: number of dead pixels (per ROC): 0 0 3 1 0 0 0 0 0 1 0 1 0 0 0 0
[14:03:02.366] INFO: enter test to run
[14:03:30.153] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:03:30.153] INFO: running: highrate
[14:03:30.153] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:03:30.383] INFO: ----------------------------------------------------------------------
[14:03:30.383] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:03:30.383] INFO: ----------------------------------------------------------------------
[14:03:30.383] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:03:30.383] INFO: edge/corner pixel THR is adjusted
[14:03:30.383] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:03:31.342] INFO: Collecting data for 5 seconds...
[14:03:36.359] INFO: Done with hot pixel readout
[14:03:48.502] INFO: PixTest:: pg_setup set to default.
[14:03:48.503] INFO: 6 hot pixels found in step 0
[14:03:49.520] INFO: Collecting data for 5 seconds...
[14:03:54.537] INFO: Done with hot pixel readout
[14:04:06.665] INFO: PixTest:: pg_setup set to default.
[14:04:06.666] INFO: 9 hot pixels found in step 1
[14:04:07.662] INFO: Collecting data for 5 seconds...
[14:04:12.678] INFO: Done with hot pixel readout
[14:04:24.845] INFO: PixTest:: pg_setup set to default.
[14:04:24.846] INFO: 4 hot pixels found in step 2
[14:04:25.841] INFO: Collecting data for 5 seconds...
[14:04:30.857] INFO: Done with hot pixel readout
[14:04:42.981] INFO: PixTest:: pg_setup set to default.
[14:04:42.982] INFO: 7 hot pixels found in step 3
[14:04:43.977] INFO: Collecting data for 5 seconds...
[14:04:48.994] INFO: Done with hot pixel readout
[14:05:01.123] INFO: PixTest:: pg_setup set to default.
[14:05:01.124] INFO: 9 hot pixels found in step 4
[14:05:02.118] INFO: Collecting data for 5 seconds...
[14:05:07.135] INFO: Done with hot pixel readout
[14:05:19.277] INFO: PixTest:: pg_setup set to default.
[14:05:19.278] INFO: 5 hot pixels found in step 5
[14:05:20.274] INFO: Collecting data for 5 seconds...
[14:05:25.290] INFO: Done with hot pixel readout
[14:05:37.434] INFO: PixTest:: pg_setup set to default.
[14:05:37.435] INFO: 9 hot pixels found in step 6
[14:05:38.430] INFO: Collecting data for 5 seconds...
[14:05:43.446] INFO: Done with hot pixel readout
[14:05:53.691] INFO: PixTest:: pg_setup set to default.
[14:05:53.692] INFO: 7 hot pixels found in step 7
[14:05:54.687] INFO: Collecting data for 5 seconds...
[14:05:59.697] INFO: Done with hot pixel readout
[14:06:10.076] INFO: PixTest:: pg_setup set to default.
[14:06:10.077] INFO: 6 hot pixels found in step 8
[14:06:11.073] INFO: Collecting data for 5 seconds...
[14:06:16.090] INFO: Done with hot pixel readout
[14:06:28.207] INFO: PixTest:: pg_setup set to default.
[14:06:28.207] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:06:28.208] INFO: 4 hot pixels found in step 9
[14:06:29.204] INFO: Collecting data for 5 seconds...
[14:06:34.220] INFO: Done with hot pixel readout
[14:06:46.338] INFO: PixTest:: pg_setup set to default.
[14:06:46.339] INFO: 8 hot pixels found in step 10
[14:06:47.334] INFO: Collecting data for 5 seconds...
[14:06:52.350] INFO: Done with hot pixel readout
[14:07:04.482] INFO: PixTest:: pg_setup set to default.
[14:07:04.483] INFO: 15 hot pixels found in step 11
[14:07:05.479] INFO: Collecting data for 5 seconds...
[14:07:10.496] INFO: Done with hot pixel readout
[14:07:22.626] INFO: PixTest:: pg_setup set to default.
[14:07:22.627] INFO: 9 hot pixels found in step 12
[14:07:23.623] INFO: Collecting data for 5 seconds...
[14:07:28.639] INFO: Done with hot pixel readout
[14:07:40.746] INFO: PixTest:: pg_setup set to default.
[14:07:40.747] INFO: 9 hot pixels found in step 13
[14:07:41.742] INFO: Collecting data for 5 seconds...
[14:07:46.759] INFO: Done with hot pixel readout
[14:07:58.881] INFO: PixTest:: pg_setup set to default.
[14:07:58.882] INFO: 5 hot pixels found in step 14
[14:07:58.921] INFO: 5 hot pixels could not be trimmed and have been masked.
[14:07:58.925] INFO: PixTest::trimHotPixels() done
[14:07:58.925] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C0.dat
[14:07:58.931] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C1.dat
[14:07:58.937] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C2.dat
[14:07:58.943] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C3.dat
[14:07:58.948] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C4.dat
[14:07:58.953] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C5.dat
[14:07:58.958] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C6.dat
[14:07:58.963] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C7.dat
[14:07:58.969] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C8.dat
[14:07:58.974] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C9.dat
[14:07:58.979] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C10.dat
[14:07:58.984] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C11.dat
[14:07:58.989] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C12.dat
[14:07:58.995] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C13.dat
[14:07:58.000] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C14.dat
[14:07:58.005] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C15.dat
[14:07:59.010] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//defaultMaskFile.dat
[14:07:59.020] INFO: enter test to run
[14:08:33.295] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:08:33.295] INFO: running: highrate
[14:08:33.299] INFO: ----------------------------------------------------------------------
[14:08:33.299] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:08:33.299] INFO: ----------------------------------------------------------------------
[14:08:33.299] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:08:33.299] INFO: edge/corner pixel THR is adjusted
[14:08:33.299] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:08:34.258] INFO: Collecting data for 1 seconds...
[14:08:35.262] INFO: Done with hot pixel readout
[14:08:39.519] INFO: PixTest:: pg_setup set to default.
[14:08:39.520] INFO: 0 hot pixels found in step 0
[14:08:39.525] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:08:39.608] INFO: PixTest::trimHotPixels() done
[14:08:39.609] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C0.dat
[14:08:39.619] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C1.dat
[14:08:39.624] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C2.dat
[14:08:39.630] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C3.dat
[14:08:39.635] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C4.dat
[14:08:39.640] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C5.dat
[14:08:39.646] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C6.dat
[14:08:39.651] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C7.dat
[14:08:39.656] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C8.dat
[14:08:39.661] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C9.dat
[14:08:39.667] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C10.dat
[14:08:39.672] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C11.dat
[14:08:39.677] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C12.dat
[14:08:39.682] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C13.dat
[14:08:39.688] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C14.dat
[14:08:39.693] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//trimParameters35_C15.dat
[14:08:39.698] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//defaultMaskFile.dat
[14:08:39.709] INFO: enter test to run
[14:09:12.198] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:09:12.198] INFO: running: xray
[14:09:12.200] INFO: ----------------------------------------------------------------------
[14:09:12.200] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:09:12.200] INFO: ----------------------------------------------------------------------
[14:09:13.163] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:09:24.250] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:09:53.731] INFO: Resuming triggers.
[14:10:04.821] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[14:10:34.386] INFO: Resuming triggers.
[14:10:45.479] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[14:11:14.944] INFO: Resuming triggers.
[14:11:26.036] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[14:11:55.569] INFO: Resuming triggers.
[14:12:06.662] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[14:12:36.097] INFO: Resuming triggers.
[14:12:47.189] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[14:13:16.686] INFO: Resuming triggers.
[14:13:27.782] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[14:13:57.315] INFO: Resuming triggers.
[14:14:08.408] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[14:14:37.784] INFO: Resuming triggers.
[14:14:48.876] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[14:15:18.348] INFO: Resuming triggers.
[14:15:18.840] INFO: data taking finished, elapsed time: 100 seconds.
[14:15:20.595] INFO: PixTest:: pg_setup set to default.
[14:15:20.599] INFO: PixTestXray::doPhRun() done
[14:15:20.739] INFO: enter test to run
[14:15:55.059] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:15:55.059] INFO: running: xray
[14:15:55.060] INFO: ----------------------------------------------------------------------
[14:15:55.060] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:15:55.060] INFO: ----------------------------------------------------------------------
[14:15:56.031] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:16:02.221] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:16:04.976] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[14:16:04.976] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[14:16:04.976] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:16:04.976] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03a 8000 4388 81a 2a66 4388 4388 654 2846 4388 31d 22a1 4388 21b 2066 209 2a65 2d8 2861 309 202e 759 2a8d 4388 95 2262 800 242f 840 2640 4388 4388 e000 c000
[14:16:04.976] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a034 80b1 43c8 43c8 91 284f 43c8 6cd 264f 43c8 43c8 9 282f d1 244f 543 264f 55b 2a69 43c8 9b 2865 21b 246f 43c8 43c8 158 2a4b e000 c000
[14:16:04.976] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a035 80c0 43c8 a2 2a8e 43c8 43c8 419 2a4d 551 2a48 43c8 1c 2045 43c8 353 228e 820 2a4f 43c8 6c1 202a 43c8 25a 2468 84c 2a65 43c8 319 2463 495 2a4f e000 c000
[14:16:04.976] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a036 8000 43c8 90 2462 d2 2440 d2 202f 43c8 43c8 513 224f 608 2440 708 224c 43c8 9 2446 43c8 9a 224e 41d 244d 719 2a69 43c8 9b 244f 281 2640 2da 2a47 43c8 9d 248b 68c 2a44 718 2684 43c8 10a 2a49 e000 c000
[14:16:04.976] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a037 8040 43c8 759 224d 43c8 9b 224f 43c8 409 2a47 1495 344f 481 35aa 300 20a3 815 25f8 4da 2247 55c 244e 55c 204c 559 248f 4388 292 2241 31c 282a 4388 4388 e000 c000
[14:16:04.976] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a038 80b1 4388 710 2442 710 264d 4388 291 2242 4388 70d 2847 4388 722 204e 4388 52 206e 4388 2d3 2a61 4388 40c 2468 558 226f 4388 219 2065 e000 c000
[14:16:04.976] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a039 80c0 4388 93 2a4e 45c 2240 4388 c3 2248 15b 2849 4388 488 2042 4c8 224c 4388 609 2a62 655 2868 654 2242 4388 52 2863 690 204f 4388 4d3 288a 65a 2882 4388 309 2481 4388 e000 c000
[14:16:04.976] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:16:04.976] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13a 8000 4388 4388 21 2865 4388 742 2640 4388 754 2460 4388 4388 4388 4388 e000 c000
[14:16:04.976] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a134 80b1 43c8 218 2464 51c 246a 84c 2249 43c8 8d 2665 10a 2243 43c8 ca 2a49 41a 2664 43c8 55c 284f 43c8 555 2444 43c8 43c8 43c8 e000 c000
[14:16:04.976] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a135 80c0 43c8 149 284a 43c8 1c 284a 20d 224f 64a 244a 43c8 4dd 2a42 6dc 264f 43c8 213 284f 614 262c 43c8 255 264b 660 262d 43c8 43c8 61c 2845 43c8 e000 c000
[14:16:04.976] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a136 8000 43c8 43c8 60b 2069 6e1 266f 43c8 161 2264 6cb 204a 800 2445 43c8 43c8 15 2047 c8 2640 220 2a45 2d0 282a 84b 264c 43c8 24d 2828 43c8 6e2 2269 43c8 484 268f e000 c000
[14:16:04.976] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a137 8040 43c8 25b 2264 410 2466 518 2a4a 70c 2646 5388 1450 266a 1a50 24ff f50 27d5 854 2110 823 2208 32 4388 4388 c4 2a2d c5 222f 2c0 264f 4388 c3 2466 320 2241 4388 4388 e000 c000
[14:16:04.976] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a138 80b1 4388 70b 2885 4388 319 248a 4388 d3 226d 761 2469 4388 4388 149 244f 4388 14a 226c 252 2a40 48c 2649 4388 55b 2866 85b 2483 4388 9c 2482 e000 c000
[14:16:04.976] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a139 80c0 4388 4388 40c 2069 722 2087 855 2680 4388 4388 4388 4388 2e2 244d 4388 4388 222 2a4d e000 c000
[14:16:29.460] INFO: Resuming triggers.
[14:16:35.652] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[14:17:05.659] INFO: Resuming triggers.
[14:17:11.852] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[14:17:11.947] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (7) != Token Chain Length (8)
[14:17:11.947] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:17:11.948] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a014 80b1 43c8 dd 2261 2d9 288f 43c8 43c8 43c8 35d 204e 650 266e 43c8 112 244d 484 266c 550 2a46 6e2 2882 758 2a69 43c8 43c8 51a 2480 690 2041 852 224f 43c8 e000 c000
[14:17:11.948] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00e 8000 4388 445 222d 758 2660 438a 4388 803 2427 438a cc 242d 855 2a4e 438a 483 2085 6d1 2048 4388 48a 2a49 513 284f 4388 518 2260 613 2069 4388 55a 2861 e000 c000
[14:17:11.948] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00f 8040 438a 20 264e 4388 55 2042 438a 4388 4388 555 2866 438a c1 2067 438a d0 244f 438a e000 c000
[14:17:11.948] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a010 80b1 4388 4388 4388 85b 2462 4388 548 2a46 853 2a4f 85d 262d 4389 84 284a 48d 2864 500 2664 4388 712 2643 4388 9d 266a 408 264c 4388 358 224d 45b 2a65 e000 c000
[14:17:11.948] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a011 80c0 4388 69d 246e 4388 4388 2db 2462 6cb 2465 4389 651 224d 4388 419 2868 6cd 206a 812 224f 849 2a67 4389 8 2824 c5 286e 4389 555 2848 6d2 3462 754 2930 e001 c001
[14:17:11.948] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a012 8000 43c8 98 2246 43c9 43c9 262 286d 4c2 204a 549 2068 43c9 d3 2480 d3 206a 162 226f 54d 2a62 43c8 43c8 53 2265 30c 244a 754 2a40 858 284f 43c9 81b 244e 43c9 8d 204d 451 2a6f e000 c000
[14:17:11.948] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a013 8040 43c8 43c8 43c8 459 284f 43c8 5a 224f 5a 2646 43c8 52 2a44 92 2862 11a 284f 43c9 40d 2647 43c8 48 204f 45d 246a 43c9 345 244f 44a 2065 501 202c e000 c000
[14:17:12.339] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (5) != Token Chain Length (8)
[14:17:12.339] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (3) != Token Chain Length (8)
[14:17:12.339] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:17:12.339] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06f 8040 438a 61b 286c 69c 264a 4388 438a 48a 204c 708 2240 4388 b 2841 4388 d4 262c 438a 14b 2862 438a 438a e000 c000
[14:17:12.339] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a069 80c0 43c8 659 2468 43c8 59 2445 43c8 43c8 352 224c 43c8 209 2448 219 264a 561 228c 85c 2261 43c8 43c8 540 2669 85d 2089 43c8 e000 c000
[14:17:12.339] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06a 8000 43c8 43c8 14 2243 43c8 462 2462 450 284c 43c8 28d 2a45 43c8 15b 2089 213 2849 210 262e 48c 2065 80d 2649 43c8 851 2262 43c8 43c8 151 2462 e000 c000
[14:17:12.339] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06b 8040 43c8 5a 284c 61b 2085 43c8 43c8 450 244d 445 2a66 750 264c 43c8 d 284d 4c 268c 5b 264f 5b 2a65 41b 2082 458 2885 4dd 2249 608 204f 690 2260 743 2a80 80d 2040 43c8 43c8 43c8 43c8 21b 206e 29a 244a e000 c000
[14:17:12.339] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06c 80b1 43c8 4388 4388 1708 5388 ffa 3a0a aa0 20a0 600 20fb e88 2ea8 e09 2e06 a7d 2f5c 8c 2235 942 2d27 4388 2044 835 2288 333 4388 e000 c000
[14:17:12.339] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06d 80c0 4388 4388 21d 244d 4388 495 204d 6cb 206d 4388 1c 2869 82 2a40 293 246d 65d 264d 4388 4388 4 2846 20a 286d 4d3 2862 4c9 286b 4388 4388 18 204a 10 204c 112 264f 159 204d e000 c000
[14:17:12.340] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06e 8000 4388 5a 284a 210 284f 259 2069 35b 2a6f 85b 246d 438a 4388 6c9 2462 438a 104 2869 298 284f 438a 5c 2243 240 2a24 614 2446 4388 15d 264f 693 2a4f 722 2085 75d 284f 4388 54d 244e 4388 82 2640 340 262f e000 c000
[14:17:12.340] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:17:12.340] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16f 8040 438a 4d3 2a4e 61c 282d 438a 19 2a4e 31d 2a65 549 228a 438a 704 2045 843 246b 854 2288 438a 221 204f 454 2848 85d 2a2f 438a 438a 438a 438a e000 c000
[14:17:12.340] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a169 80c0 43c8 43c8 48d 284f 489 2a67 43c8 43c8 43c8 43c8 19 244f 43c8 43c8 e000 c000
[14:17:12.340] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16a 8000 43c8 43c8 2d1 224c 44b 2066 43c8 2 206a 300 2a8a 43c8 45c 2046 689 280f 43c8 43c8 253 2241 43c8 43c8 55a 2a6b e000 c000
[14:17:12.340] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16b 8040 43c8 74b 266c 43c8 205 2a4f 43c8 2d1 24aa 2c8 2682 800 244d 85d 24a1 43c8 19 286e 48d 2a66 85c 2640 43c8 43c8 43c8 43c8 8d 284b e000 c000
[14:17:12.340] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16c 80b1 43c8 4388 19 308f 438a 10a2 20af 1cd6 2aaa 823 28ff f0e 288a 1802 2a44 80a 2aa1 41c 210e 24 290d c27 2966 b21 e000 c000
[14:17:12.340] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16d 80c0 4388 651 2863 4388 d1 2482 4388 14 284f 149 242f 355 266d 4388 454 2645 4388 49 2880 45d 204b 84a 2242 4388 4388 4388 210 2040 e000 c000
[14:17:12.340] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16e 8000 4388 4388 102 284f 54b 24ab 4388 24a 2a6a 2db 2668 620 284f 4388 720 2849 4388 4c8 2642 508 244e 712 2640 4388 4388 4388 e000 c000
[14:17:16.244] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (1) != Token Chain Length (8)
[14:17:16.244] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[14:17:16.244] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:17:16.244] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fb 8040 4388 dc 2889 4388 4388 54c 2845 852 2a49 4388 4d0 2262 4388 459 268c 548 2042 4388 98 2842 451 204f 762 2881 4388 34c 244f 4cb 2463 55d 2666 64c 2664 80b 2865 4388 e000 c000
[14:17:16.244] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f5 80c0 43c8 2cc 2268 43c8 43c8 650 2449 43c8 760 222f 43c8 2cb 2869 49a 24a5 43c8 519 224d 43c8 59 2265 510 2662 43c8 c4 2026 15c 2245 e000 c000
[14:17:16.244] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f6 8000 43c8 43c8 9a 2863 43c8 43c8 43c8 855 2a49 858 2a4c 43c8 43c8 50 2a69 618 2462 43c8 61b 2249 e000 c000
[14:17:16.244] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f7 8040 43c8 62 2048 59 204f 51d 2068 43c8 43c8 281 2646 43c8 43c8 303 2865 43c8 551 226f 43c8 298 2848 350 2849 513 2880 848 2a61 84a 2265 43c8 e000 c000
[14:17:16.244] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== b0f8 80b1 5388 158 25f8 e07c c00b
[14:17:16.244] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f9 80c0 4388 4388 4388 4388 4388 21a 2a4f 4388 4388 2cb 2268 4388 e000 c000
[14:17:16.245] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fa 8000 4388 319 2a4f 4388 4388 4388 148 2a41 4388 24d 204f 4d9 2a69 4388 64b 244f 4388 8d 206d 4388 d 2042 e000 c000
[14:17:16.245] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:17:16.245] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fb 8040 4388 68d 242f 4388 4388 1a 288e 611 2045 65b 244f 4388 59 2a45 4388 1b 244d 15b 2683 681 244f 4388 4388 699 2261 4388 605 2646 e000 c000
[14:17:16.245] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f5 80c0 43c8 8d 2264 353 2860 708 2442 43c8 9a 2a6a 719 284e 43c8 43c8 311 2a47 43c8 805 224a 43c8 14d 2466 34a 2248 43c8 43c8 840 246d e000 c000
[14:17:16.245] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f6 8000 43c8 43c8 43c8 2d5 2862 60c 222c 754 2647 43c8 43c8 83 284f 15c 2246 350 224e 43c8 20c 222b 24d 2442 43c8 61c 2243 71d 2848 81c 2865 43c8 80 2a2f 360 2a85 e000 c000
[14:17:16.245] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f7 8040 43c8 2c4 222e 43c8 43c8 43c8 100 2440 284 244d 40b 2668 43c8 560 244f 55a 284f 43c8 309 2a2f 43c8 759 2a47 43c8 e000 c000
[14:17:16.245] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== b1f8 80b1 5388 2c5 25a3 9aa 2aa6 aaa 28a8 54 4388 4388 112 2643 460 2865 4388 720 2845 71c 2042 4388 10d 2463 30b 264e 4388 4388 361 206c e000 c000
[14:17:16.245] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f9 80c0 4388 4388 4388 802 2a45 851 2aac 4388 6c0 264f 4388 110 2845 492 2a2f 65d 2a4f 4388 295 244c 4388 4388 413 2269 e000 c000
[14:17:16.245] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fa 8000 4388 459 244b 4388 14 2a4f 1d 264d 712 204f 4388 2dc 2a65 48d 284c 48d 2467 648 2a4c 4388 4388 4388 4388 4388 e000 c000
[14:17:17.809] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[14:17:17.809] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[14:17:17.809] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:17:17.809] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a066 8000 43c8 43c8 43c8 448 2464 851 204e 851 2261 43c8 461 264d 709 266f 43c8 91 2449 8d 2a4a 409 2669 43c8 43c8 25c 2260 43c8 289 266e 414 2065 e000 c000
[14:17:17.809] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a060 80b1 4388 45c 2225 818 2447 4388 4388 4388 4389 712 226c 4388 304 242d 308 2a2e 4d2 2866 4d1 266f 4388 4388 22 224d 40 2640 e000 c000
[14:17:17.809] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a061 80c0 4388 8d 2a4a 153 2860 4388 6a2 2461 4388 4389 4388 351 244d 4389 20b 2868 2cc 204f 4389 252 2462 4389 e000 c000
[14:17:17.809] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a062 8000 4388 418 204f 411 244e 4389 a2 2063 452 2240 4389 4389 59 2880 31b 246f 81d 2845 80a 224c 4388 158 2469 251 2666 318 2029 45b 20a4 4d1 204a 4388 95 2029 743 266f 4389 118 2844 2db 2681 4389 e000 c000
[14:17:17.809] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a063 8040 4388 4388 4388 151 2283 29c 2462 611 264f 4388 91 264f 15d 206d 214 2649 6cd 2849 4388 248 222c 2c9 2263 4389 18 202a 403 2046 558 22d1 246 2649 a6e 43c9 e000 c000
[14:17:17.809] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a064 80b1 43c8 722 2a61 43c8 58 282f 43c8 11d 2662 43c8 152 284e 358 2a65 659 2445 43c8 41a 2447 6c3 244c 750 2844 43c8 43c8 13 2a6f 208 244f 6c4 2065 43c8 154 2242 e000 c000
[14:17:17.809] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a065 80c0 43c8 12 202e 11 2829 358 286c 55d 2462 43c8 43c8 543 2446 80b 2a45 43c8 210 244d 358 204f 509 2645 43c8 95 2249 201 2a4c 318 244e 511 2a69 43c8 14a 224d 50b 2465 709 264a 43c8 d2 244a 842 2665 43c8 6c8 2045 e000 c000
[14:17:17.809] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:17:17.809] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a166 8000 43c8 43c8 409 2085 43c8 309 206e 50c 2247 43c8 155 2a49 43c8 c5 2228 35d 264e 44c 2047 43c8 42 2461 251 2241 43c8 43c8 605 224b e000 c000
[14:17:17.809] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a160 80b1 4389 4389 48c 2265 512 2a4c 4389 303 2885 451 2a6f 681 2a67 4389 c8 2a45 c9 226d 651 2a82 81b 2a47 4389 498 2647 4cb 2464 4389 4389 6db 284f 75a 2844 4389 e000 c000
[14:17:17.809] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a161 80c0 4388 44b 2864 4dc 2869 4388 313 2665 4388 4388 51b 2065 4389 55c 2a44 4389 354 282e 4389 4389 e000 c000
[14:17:17.809] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a162 8000 4388 481 204e 4388 10a 2269 4a0 224d 84b 224f 4389 158 282b 740 2446 4389 4cc 2244 622 226e 4388 11b 2062 4c2 284d 4388 104 2a4d 105 2269 4389 4389 2e2 2682 e000 c000
[14:17:17.809] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a163 8040 4388 4389 41 2447 120 244f 74b 286a 4388 90 224f 450 206f 689 2862 81b 2889 4389 120 2088 61c 204d 4388 11d 264d 43c9 203 202f 43c9 e000 c000
[14:17:17.809] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a164 80b1 43c8 84b 208c 43c8 80 2445 458 2449 658 2626 43c8 44d 206d 43c8 43c8 30a 2a45 43c8 2cb 264b 555 2446 43c8 43c8 e000 c000
[14:17:17.809] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a165 80c0 43c8 258 2442 495 2063 43c8 250 2a8c 4dc 2a4a 43c8 50b 2a6c 54b 286f 751 2465 819 248a 43c8 11 2640 4d9 206b 4d9 2262 43c8 451 244f 43c8 43c8 43c8 a1 2a65 854 2a69 e000 c000
[14:17:18.198] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[14:17:18.201] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[14:17:18.201] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:17:18.201] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c1 80c0 4388 9a 2264 494 224f 49b 2a69 4388 4388 4389 4388 4c8 2648 4389 9 2046 4389 81a 202e 819 2869 4389 e000 c000
[14:17:18.201] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bb 8040 43c8 50c 2866 43c8 43c8 422 204d 6d1 264e 43c8 149 2866 148 2a4f 20a 2061 208 2862 749 204d 81a 2262 43c8 652 2449 43c8 45a 2662 43c8 213 2687 43c8 10c 242f 489 2443 e000 c000
[14:17:18.201] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bc 80b1 43c8 152 286a 43c8 43c8 55d 2647 55d 2a65 43c8 d1 2487 43c8 151 2888 508 2466 43c8 208 2247 43c8 619 284f 745 2282 43c8 e000 c000
[14:17:18.201] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bd 80c0 43c8 218 200f 490 2882 511 206a 65a 2482 43c8 43c8 490 2465 43c8 308 264c 55b 2482 43c8 513 224a 611 2269 6c4 2626 6e2 2a49 70a 2264 43c8 6c9 2a2a 43c8 40a 2281 43c8 13 246f e000 c000
[14:17:18.201] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0be 8000 4388 eaa 2dc1 5fa 4388 438a 115 2441 158 2a4a 315 2668 31c 2266 361 2a6a 448 264f 438a 619 2446 4388 a2 264f 4388 10b 286e 4388 e000 c000
[14:17:18.201] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bf 8040 438a 762 2062 4388 438a 315 284f 44d 2669 4388 48b 2a89 4388 710 2646 438a 6c9 284f 438a 31a 244f 349 264f 438a 11b 2441 e000 c000
[14:17:18.201] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c0 80b1 4388 4388 10b 224f 35c 2a43 4388 555 204e 860 2244 4388 309 2066 611 204c 4389 20 222f 319 2063 4388 350 2440 34c 224f 745 2662 4388 65d 2042 645 2a69 6ca 2a68 802 264d 4388 b 2849 e000 c000
[14:17:18.201] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:17:18.201] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c1 80c0 4388 4388 119 242f 402 204c 401 2868 519 2247 4388 245 248f 2c2 2065 4388 11 2441 4389 445 2a4e 69d 2449 4389 85 2068 84 284f 162 286e 4389 4389 499 2849 e000 c000
[14:17:18.201] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bb 8040 43c8 43c8 43c8 621 2841 43c8 6d3 2a62 75b 2a49 43c8 43c8 43c8 43c8 e000 c000
[14:17:18.201] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bc 80b1 43c8 25b 2465 24b 2069 358 284f 705 2246 749 2464 43c8 d1 264f 411 2661 65b 2068 43c8 43c8 41a 2042 442 2648 689 2823 43c8 28b 2062 698 2447 43c8 415 2269 43c8 43c8 422 2480 e000 c000
[14:17:18.201] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bd 80c0 43c8 64a 2640 661 224f 842 2262 43c8 43c8 4d 2880 159 2269 2d8 204a 50d 2065 43c8 714 2228 43c8 2d3 2842 30c 2242 359 244e 604 2446 43c8 43c8 43c8 e000 c000
[14:17:18.201] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1be 8000 438a 954 222d 854 2305 b37 23a6 c27 23a6 d37 4388 4388 4388 110 2046 4388 4388 644 282f 4388 e000 c000
[14:17:18.201] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bf 8040 438a 438a 35d 2265 35d 2a83 438a 44d 2467 81c 2645 438a 144 2040 613 288c 438a d1 264f 15a 2686 438a 248 2828 499 284a 438a 438a e000 c000
[14:17:18.201] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c0 80b1 4389 4389 30c 2268 54d 2284 70d 208b 4389 4389 1b 2047 809 2a68 4389 715 2889 4389 4389 4389 e000 c000
[14:17:42.040] INFO: Resuming triggers.
[14:17:48.231] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[14:18:02.076] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[14:18:02.076] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[14:18:02.077] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:18:02.077] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0de 8000 4388 21a 2a42 29b 2a6a 4a2 2885 438a 20b 2266 4388 60c 2a43 438a db 2069 552 2a66 653 286c 438a 240 2445 443 2a89 508 2681 4388 689 204c 4388 204 246f 4388 118 2042 295 2461 28d 284d e000 c000
[14:18:02.077] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d8 80b1 43c8 69a 2465 43c8 43c8 441 2442 71c 2241 43c8 85d 2a41 43c8 84c 2263 43c8 30b 2a4f 455 204a 480 2a45 481 226a 43c8 d0 224f 85b 24a5 85b 2081 43c8 4da 202f e000 c000
[14:18:02.077] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d9 80c0 43c8 43c8 43c8 690 282f 43c8 db 266f 65a 224a 43c8 55 2646 95 2240 81b 2a89 859 2a4f 43c8 43c8 43c8 208 2048 499 264a e000 c000
[14:18:02.077] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0da 8000 43c8 d4 284c 43c8 92 2645 43c8 495 2249 682 2045 43c8 43c8 19 2442 14 244f 410 2a61 43c8 5a 2245 34a 2465 349 2842 6cd 204d 43c8 74c 284c 43c8 e000 c000
[14:18:02.077] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0db 8040 43c8 11c 284f 43c8 43c8 11d 2061 61a 3a4f 64d 2649 17ea 3b54 c13 2cff fbf 4388 30b 2a4f 4388 12 244f 64b 2a81 4388 4388 2d2 2043 e000 c000
[14:18:02.077] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0dc 80b1 4388 218 282c 55c 244e 4388 2d9 2045 2d2 244a 35c 2845 4388 455 286d 4388 4d 284f 298 244d 4388 21 204f 15d 226d 255 2a40 483 2689 4388 95 2082 4388 74a 2285 4388 e000 c000
[14:18:02.077] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0dd 80c0 4388 315 244d 4388 2d5 2245 4388 4388 1 284e 548 2064 4388 60c 2842 859 206d 4388 74a 2a64 4388 4388 9c 244a 50d 2622 e000 c000
[14:18:02.077] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:18:02.077] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1de 8000 4388 4388 303 2264 653 244d 4388 d 284f 4388 53 244e 4388 19 2464 35a 2240 4388 8 2840 4388 4388 e000 c000
[14:18:02.077] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d8 80b1 43c8 144 2246 201 264c 55a 2865 802 2647 43c8 14a 2267 29b 268b 31d 2a85 748 204a 75b 226e 43c8 21 206f b 246c 688 2862 43c8 419 2045 43c8 51 264a 749 2242 749 2640 43c8 43c8 413 2265 43c8 65c 264d e000 c000
[14:18:02.077] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d9 80c0 43c8 43c8 84b 226e 43c8 2cb 2266 43c8 693 224f 43c8 45b 2862 43c8 43c8 43c8 e000 c000
[14:18:02.077] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1da 8000 43c8 43c8 282 2864 44d 2862 615 2240 6cc 2040 744 204b 84c 2666 43c8 cc 244f 43c8 43c8 345 2448 43c8 43c8 43c8 81d 2a6f e000 c000
[14:18:02.077] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1db 8040 43c8 43c8 43c8 302 2462 43c8 4d5 244f 5388 92 2448 10d5 36aa a42 2b80 40 215c 425 2126 4388 e000 c000
[14:18:02.077] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1dc 80b1 4388 62 2686 4388 40d 2042 4388 48b 2a6f 4a0 264a 4388 852 202c 4388 11b 224d 50b 224a 54a 2449 4388 1 204f 348 282a 4388 4388 e000 c000
[14:18:02.077] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1dd 80c0 4388 515 2660 65d 2847 693 2842 4388 251 2628 443 2a67 4388 61 246b 4388 60a 2868 6da 224f 4388 4388 4388 4388 e000 c000
[14:18:18.335] INFO: Resuming triggers.
[14:18:24.532] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[14:18:54.447] INFO: Resuming triggers.
[14:19:00.643] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[14:19:30.572] INFO: Resuming triggers.
[14:19:36.761] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[14:20:06.773] INFO: Resuming triggers.
[14:20:12.968] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[14:20:42.916] INFO: Resuming triggers.
[14:20:49.109] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[14:21:18.979] INFO: Resuming triggers.
[14:21:25.171] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[14:21:55.124] INFO: Resuming triggers.
[14:22:01.321] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[14:22:31.333] INFO: Resuming triggers.
[14:22:37.528] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[14:23:07.514] INFO: Resuming triggers.
[14:23:13.709] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[14:23:43.751] INFO: Resuming triggers.
[14:23:49.947] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[14:24:19.902] INFO: Resuming triggers.
[14:24:26.095] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[14:24:56.053] INFO: Resuming triggers.
[14:25:02.253] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[14:25:32.281] INFO: Resuming triggers.
[14:25:33.488] INFO: data taking finished, elapsed time: 100 seconds.
[14:25:39.660] INFO: PixTest:: pg_setup set to default.
[14:25:39.664] INFO: PixTestXray::doPhRun() done
[14:25:39.814] INFO: enter test to run
[14:26:11.110] INFO: test: timing no parameter change
[14:26:11.111] INFO: running: timing
[14:26:11.129] INFO: ######################################################################
[14:26:11.129] INFO: PixTestTiming::doTest()
[14:26:11.138] INFO: ######################################################################
[14:26:11.138] INFO: ----------------------------------------------------------------------
[14:26:11.138] INFO: PixTestTiming::TBMPhaseScan()
[14:26:11.138] INFO: ----------------------------------------------------------------------
[14:42:45.262] INFO: TBM Phase Settings: 208
[14:42:45.262] INFO: 400MHz Phase: 4
[14:42:45.262] INFO: 160MHz Phase: 6
[14:42:45.262] INFO: Functional Phase Area: 4
[14:42:45.272] INFO: Test took 994134 ms.
[14:42:45.272] INFO: PixTestTiming::TBMPhaseScan() done.
[14:42:45.272] INFO: ----------------------------------------------------------------------
[14:42:45.272] INFO: PixTestTiming::ROCDelayScan()
[14:42:45.272] INFO: ----------------------------------------------------------------------
[14:45:07.867] INFO: ROC Delay Settings: 219
[14:45:07.867] INFO: ROC Header-Trailer/Token Delay: 11
[14:45:07.867] INFO: ROC Port 0 Delay: 3
[14:45:07.867] INFO: ROC Port 1 Delay: 3
[14:45:07.867] INFO: Functional ROC Area: 4
[14:45:07.870] INFO: Test took 142598 ms.
[14:45:07.870] INFO: PixTestTiming::ROCDelayScan() done.
[14:45:07.870] INFO: ----------------------------------------------------------------------
[14:45:07.870] INFO: PixTestTiming::TimingTest()
[14:45:07.870] INFO: ----------------------------------------------------------------------
[14:45:23.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:38.971] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:53.928] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:08.887] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:22.934] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:37.409] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:52.351] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:07.293] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:22.236] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:37.163] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:37.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:37.560] INFO: ----------------------------------------------------------------------
[14:47:37.560] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[14:47:37.560] INFO: ----------------------------------------------------------------------
[14:47:37.560] INFO: ----------------------------------------------------------------------
[14:47:37.560] INFO: Read back bit status: 1
[14:47:37.560] INFO: ----------------------------------------------------------------------
[14:47:37.560] INFO: ----------------------------------------------------------------------
[14:47:37.560] INFO: Timings are good!
[14:47:37.560] INFO: ----------------------------------------------------------------------
[14:47:37.560] INFO: Test took 149690 ms.
[14:47:37.560] INFO: PixTestTiming::TimingTest() done.
[14:47:37.560] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//tbmParameters_C0a.dat
[14:47:37.560] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-29_FPIXTest-17C-FNAL-160415-0903_2016-04-15_09h03m_1460729024/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:47:37.560] INFO: PixTestTiming::doTest took 1286435 ms.
[14:47:37.560] INFO: PixTestTiming::doTest() done
[14:47:37.560] INFO: Write out TBMPhaseScan_0_V0
[14:47:37.560] INFO: Write out TBMPhaseScan_1_V0
[14:47:37.560] INFO: Write out CombinedTBMPhaseScan_V0
[14:47:37.575] INFO: Write out ROCDelayScan3_V0
[14:47:37.575] INFO: enter test to run
[14:48:25.581] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:48:25.581] INFO: running: xray
[14:48:25.582] INFO: ----------------------------------------------------------------------
[14:48:25.582] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:48:25.582] INFO: ----------------------------------------------------------------------
[14:48:26.548] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:48:32.748] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:49:02.804] INFO: Resuming triggers.
[14:49:08.001] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[14:49:38.596] INFO: Resuming triggers.
[14:49:44.794] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[14:50:14.601] INFO: Resuming triggers.
[14:50:20.797] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[14:50:50.691] INFO: Resuming triggers.
[14:50:56.884] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[14:51:26.851] INFO: Resuming triggers.
[14:51:33.047] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[14:52:03.051] INFO: Resuming triggers.
[14:52:09.244] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[14:52:39.021] INFO: Resuming triggers.
[14:52:45.214] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[14:53:15.130] INFO: Resuming triggers.
[14:53:21.327] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[14:53:51.261] INFO: Resuming triggers.
[14:53:57.453] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[14:54:27.392] INFO: Resuming triggers.
[14:54:33.583] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[14:54:34.649] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[14:54:34.649] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:54:34.650] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a003 8040 4c38 da 2845 260 2269 6d3 2067 4c38 4c38 4c38 15b 2a4e 85c 2a43 4c38 683 2845 4c38 255 206e 4c38 505 226f 811 204f 4c38 e000 c000
[14:54:34.650] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fd 80c0 4c38 1c 2846 4c38 4c38 9b 2a2d 362 2a69 543 2a62 54d 2a4d 753 264d 4c39 15a 204f 20b 224f 4c38 2ca 204f 414 2048 560 206c 6e1 224f 4c39 2c5 284f 2d9 2669 34a 2645 493 224f 61c 2449 4c39 d0 2242 852 2a80 859 226c 4c39 88 2448 4c0 2844 e000 c000
[14:54:34.650] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fe 8000 4c38 4c39 4c39 74c 2843 4c39 4c38 4b 2863 34a 2a6d 853 204c 852 2448 4c38 64b 2485 4c39 85a 2669 4c39 e000 c000
[14:54:34.650] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ff 8040 4c38 4c38 4c38 48a 264c 71a 2a40 4c38 141 204c 209 222f 4c38 10a 204e 545 244d 820 2243 4c39 48c 2a40 4c38 4c39 495 2a48 e000 c000
[14:54:34.650] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a000 80b1 7fc 27f8 6e2 2249 4c38 504 2a45 4c38 2c9 2a2d 4c4 2a4a 55d 2a4f 4c38 110 2a2d 51c 224f 69c 2463 4c38 500 2443 64d 2668 68b 2042 4c38 4c38 e000 c000
[14:54:34.650] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a001 80c0 4c38 489 2482 4c38 31d 2a4d 4c38 45b 244c 4c38 9b 2681 21d 204d 4c38 411 2249 4c38 43 2864 354 202a 4c38 4c38 e000 c000
[14:54:34.650] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a002 8000 4c38 4c38 4c38 4c38 248 286f 694 2442 4c38 9 2645 159 208c 414 264a 4c38 844 2a40 4c38 10c 2625 70a 246c 4c38 10a 2443 510 2425 e000 c000
[14:55:03.708] INFO: Resuming triggers.
[14:55:09.899] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[14:55:39.820] INFO: Resuming triggers.
[14:55:46.011] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[14:56:15.942] INFO: Resuming triggers.
[14:56:22.135] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[14:56:47.228] INFO: Resuming triggers.
[14:56:53.425] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[14:57:23.370] INFO: Resuming triggers.
[14:57:29.566] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[14:57:59.532] INFO: Resuming triggers.
[14:58:00.735] INFO: data taking finished, elapsed time: 100 seconds.
[14:58:06.876] INFO: PixTest:: pg_setup set to default.
[14:58:06.880] INFO: PixTestXray::doPhRun() done
[14:58:07.031] INFO: enter test to run
[15:00:52.512] INFO: test: HighRate no parameter change
[15:00:52.512] INFO: running: highrate
[15:00:52.513] INFO: ----------------------------------------------------------------------
[15:00:52.513] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:00:52.513] INFO: ----------------------------------------------------------------------
[15:00:52.654] INFO: Expecting 768 events.
[15:00:53.788] INFO: 768 events read in total (418ms).
[15:00:53.788] INFO: Test took 1268ms.
[15:00:54.592] INFO: Expecting 41600 events.
[15:00:57.699] INFO: 41600 events read in total (2581ms).
[15:00:57.700] INFO: Test took 3904ms.
[15:00:57.736] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:58.460] INFO: Expecting 41600 events.
[15:01:01.696] INFO: 41600 events read in total (2709ms).
[15:01:01.697] INFO: Test took 3943ms.
[15:01:01.734] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:02.450] INFO: Expecting 41600 events.
[15:01:05.721] INFO: 41600 events read in total (2745ms).
[15:01:05.722] INFO: Test took 3968ms.
[15:01:05.758] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:06.479] INFO: Expecting 41600 events.
[15:01:09.750] INFO: 41600 events read in total (2745ms).
[15:01:09.751] INFO: Test took 3973ms.
[15:01:09.789] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:10.505] INFO: Expecting 41600 events.
[15:01:13.780] INFO: 41600 events read in total (2748ms).
[15:01:13.781] INFO: Test took 3973ms.
[15:01:13.818] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:14.534] INFO: Expecting 41600 events.
[15:01:17.816] INFO: 41600 events read in total (2756ms).
[15:01:17.817] INFO: Test took 3979ms.
[15:01:17.855] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:18.573] INFO: Expecting 41600 events.
[15:01:21.849] INFO: 41600 events read in total (2749ms).
[15:01:21.850] INFO: Test took 3977ms.
[15:01:21.887] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:22.608] INFO: Expecting 41600 events.
[15:01:25.876] INFO: 41600 events read in total (2742ms).
[15:01:25.877] INFO: Test took 3971ms.
[15:01:25.915] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:26.631] INFO: Expecting 41600 events.
[15:01:29.890] INFO: 41600 events read in total (2732ms).
[15:01:29.891] INFO: Test took 3956ms.
[15:01:29.929] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:30.648] INFO: Expecting 41600 events.
[15:01:33.931] INFO: 41600 events read in total (2756ms).
[15:01:33.932] INFO: Test took 3984ms.
[15:01:33.970] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:34.687] INFO: Expecting 41600 events.
[15:01:37.963] INFO: 41600 events read in total (2749ms).
[15:01:37.964] INFO: Test took 3975ms.
[15:01:37.001] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:38.718] INFO: Expecting 41600 events.
[15:01:41.985] INFO: 41600 events read in total (2740ms).
[15:01:41.986] INFO: Test took 3965ms.
[15:01:42.023] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:42.743] INFO: Expecting 41600 events.
[15:01:46.026] INFO: 41600 events read in total (2756ms).
[15:01:46.027] INFO: Test took 3984ms.
[15:01:46.064] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:46.780] INFO: Expecting 41600 events.
[15:01:50.060] INFO: 41600 events read in total (2753ms).
[15:01:50.061] INFO: Test took 3978ms.
[15:01:50.100] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:50.817] INFO: Expecting 41600 events.
[15:01:54.100] INFO: 41600 events read in total (2756ms).
[15:01:54.101] INFO: Test took 3981ms.
[15:01:54.139] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:54.854] INFO: Expecting 41600 events.
[15:01:58.133] INFO: 41600 events read in total (2752ms).
[15:01:58.135] INFO: Test took 3976ms.
[15:01:58.173] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:58.889] INFO: Expecting 41600 events.
[15:02:02.176] INFO: 41600 events read in total (2760ms).
[15:02:02.177] INFO: Test took 3985ms.
[15:02:02.214] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:02.929] INFO: Expecting 41600 events.
[15:02:06.202] INFO: 41600 events read in total (2746ms).
[15:02:06.203] INFO: Test took 3969ms.
[15:02:06.240] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:06.960] INFO: Expecting 41600 events.
[15:02:10.194] INFO: 41600 events read in total (2707ms).
[15:02:10.195] INFO: Test took 3934ms.
[15:02:10.233] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:10.952] INFO: Expecting 41600 events.
[15:02:14.118] INFO: 41600 events read in total (2640ms).
[15:02:14.119] INFO: Test took 3867ms.
[15:02:14.155] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:14.513] INFO: enter test to run
[15:02:44.727] INFO: test: HighRate no parameter change
[15:02:44.727] INFO: running: highrate
[15:02:44.728] INFO: ----------------------------------------------------------------------
[15:02:44.728] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:02:44.728] INFO: ----------------------------------------------------------------------
[15:02:45.346] INFO: Expecting 208000 events.
[15:02:57.380] INFO: 208000 events read in total (11508ms).
[15:02:57.384] INFO: Test took 12644ms.
[15:02:57.544] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:57.801] INFO: number of dead pixels (per ROC): 0 0 3 1 0 0 0 0 0 1 0 1 0 0 0 0
[15:02:57.801] INFO: number of red-efficiency pixels: 81 74 128 195 172 211 171 99 89 161 127 128 142 58 25 34
[15:02:57.801] INFO: number of X-ray hits detected: 71392 50581 75760 125933 129729 133123 129924 81324 78209 114467 113110 96448 95046 56949 26368 28564
[15:02:57.801] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:02:57.801] INFO: number of Vcal hits detected: 207916 207926 207724 207749 207824 207784 207819 207900 207909 207788 207871 207820 207855 207942 207973 207966
[15:02:57.801] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[15:02:57.801] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[15:02:57.801] INFO: X-ray hit rate [MHz/cm2]: 20.9 14.8 22.2 36.9 38.0 39.0 38.1 23.8 22.9 33.6 33.2 28.3 27.9 16.7 7.7 8.4
[15:02:57.801] INFO: PixTestHighRate::doXPixelAlive() done
[15:02:57.848] INFO: PixTest:: pg_setup set to default.
[15:02:57.861] INFO: enter test to run
[15:03:33.095] INFO: test: HighRate no parameter change
[15:03:33.095] INFO: running: highrate
[15:03:33.096] INFO: ----------------------------------------------------------------------
[15:03:33.096] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:03:33.096] INFO: ----------------------------------------------------------------------
[15:03:33.718] INFO: Expecting 208000 events.
[15:03:47.831] INFO: 208000 events read in total (13586ms).
[15:03:47.837] INFO: Test took 14730ms.
[15:03:48.168] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:48.480] INFO: number of dead pixels (per ROC): 0 0 3 1 0 0 0 0 9 1 0 1 0 0 0 0
[15:03:48.480] INFO: number of red-efficiency pixels: 261 166 378 697 714 734 701 367 432 518 494 422 371 227 52 80
[15:03:48.480] INFO: number of X-ray hits detected: 148135 105031 157711 261451 269261 275568 269311 169922 162960 237653 237135 201583 199020 120211 55257 60140
[15:03:48.480] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:03:48.480] INFO: number of Vcal hits detected: 207721 207822 207437 207159 207188 207151 207208 207580 206138 207387 207457 207486 207605 207758 207947 207919
[15:03:48.480] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.6 99.6 99.7 99.8 99.3 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[15:03:48.481] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.6 99.6 99.6 99.6 99.8 99.1 99.7 99.7 99.8 99.8 99.9 100.0 100.0
[15:03:48.481] INFO: X-ray hit rate [MHz/cm2]: 43.4 30.8 46.2 76.6 78.9 80.8 78.9 49.8 47.8 69.7 69.5 59.1 58.3 35.2 16.2 17.6
[15:03:48.481] INFO: PixTestHighRate::doXPixelAlive() done
[15:03:48.528] INFO: PixTest:: pg_setup set to default.
[15:03:48.540] INFO: enter test to run
[15:04:11.398] INFO: test: HighRate no parameter change
[15:04:11.398] INFO: running: highrate
[15:04:11.399] INFO: ----------------------------------------------------------------------
[15:04:11.399] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:04:11.399] INFO: ----------------------------------------------------------------------
[15:04:12.020] INFO: Expecting 208000 events.
[15:04:28.333] INFO: 208000 events read in total (15786ms).
[15:04:28.341] INFO: Test took 16930ms.
[15:04:28.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:29.209] INFO: number of dead pixels (per ROC): 0 0 3 1 0 0 0 0 14 1 0 1 0 0 0 0
[15:04:29.209] INFO: number of red-efficiency pixels: 573 361 857 1643 1677 1765 1547 860 770 1294 1177 997 929 554 128 112
[15:04:29.209] INFO: number of X-ray hits detected: 225061 159620 238519 395200 408955 417590 407413 257525 246603 360430 358268 304790 301976 181414 83433 91308
[15:04:29.209] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:04:29.209] INFO: number of Vcal hits detected: 207331 207593 206572 205644 205613 205381 205793 206842 204105 206277 206488 206680 206879 207324 207868 207885
[15:04:29.209] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.5 99.0 99.0 98.8 99.0 99.5 98.5 99.3 99.3 99.5 99.5 99.7 99.9 99.9
[15:04:29.209] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.3 98.9 98.9 98.7 98.9 99.4 98.1 99.2 99.3 99.4 99.5 99.7 99.9 99.9
[15:04:29.209] INFO: X-ray hit rate [MHz/cm2]: 66.0 46.8 69.9 115.8 119.9 122.4 119.4 75.5 72.3 105.6 105.0 89.3 88.5 53.2 24.5 26.8
[15:04:29.209] INFO: PixTestHighRate::doXPixelAlive() done
[15:04:29.255] INFO: PixTest:: pg_setup set to default.
[15:04:29.269] INFO: enter test to run
[15:04:47.798] INFO: test: exit no parameter change
[15:04:48.138] QUIET: Connection to board 33 closed.
[15:04:48.140] INFO: pXar: this is the end, my friend