[10:31:37.333] INFO: *** Welcome to pxar ***
[10:31:37.333] INFO: *** Today: 2016/04/14
[10:31:37.353] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:31:37.353] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//dacParameters35_C15.dat
[10:31:37.409] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:31:37.409] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//defaultMaskFile.dat
[10:31:37.412] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C15.dat
[10:31:37.501] INFO: clk: 4
[10:31:37.501] INFO: ctr: 4
[10:31:37.501] INFO: sda: 19
[10:31:37.501] INFO: tin: 9
[10:31:37.501] INFO: level: 15
[10:31:37.501] INFO: triggerdelay: 0
[10:31:37.501] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:31:37.501] INFO: Log level: INFO
[10:31:37.518] QUIET: Connection to board DTB_WREKRL opened.
[10:31:37.522] 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:
------------------------------------------------------
[10:31:37.524] INFO: RPC call hashes of host and DTB match: 398089610
[10:31:39.055] INFO: DUT info:
[10:31:39.055] INFO: The DUT currently contains the following objects:
[10:31:39.055] INFO: 2 TBM Cores tbm08c (2 ON)
[10:31:39.055] INFO: TBM Core alpha (0): 7 registers set
[10:31:39.055] INFO: TBM Core beta (1): 7 registers set
[10:31:39.055] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:31:39.055] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.055] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.055] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.055] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.055] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.055] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.055] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.055] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.055] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.055] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.055] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.055] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.055] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.056] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.056] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.056] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:39.459] INFO: enter 'restricted' command line mode
[10:31:39.459] INFO: enter test to run
[10:31:46.161] INFO: test: PixelAlive no parameter change
[10:31:46.161] INFO: running: pixelalive
[10:31:46.171] INFO: ----------------------------------------------------------------------
[10:31:46.171] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:31:46.171] INFO: ----------------------------------------------------------------------
[10:31:46.487] INFO: Expecting 41600 events.
[10:31:50.815] INFO: 41600 events read in total (3610ms).
[10:31:50.983] INFO: Test took 4810ms.
[10:31:50.995] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:51.255] INFO: PixTestAlive::aliveTest() done
[10:31:51.255] INFO: number of dead pixels (per ROC): 0 0 1 0 0 1 0 1 0 0 2 3 1 0 0 2
[10:31:51.290] INFO: enter test to run
[10:32:12.097] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:32:12.097] INFO: running: highrate
[10:32:12.097] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:32:12.316] INFO: ----------------------------------------------------------------------
[10:32:12.316] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:32:12.316] INFO: ----------------------------------------------------------------------
[10:32:12.316] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:32:12.316] INFO: edge/corner pixel THR is adjusted
[10:32:12.317] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:32:13.275] INFO: Collecting data for 5 seconds...
[10:32:18.292] INFO: Done with hot pixel readout
[10:32:29.982] INFO: PixTest:: pg_setup set to default.
[10:32:29.983] INFO: 7 hot pixels found in step 0
[10:32:30.996] INFO: Collecting data for 5 seconds...
[10:32:36.012] INFO: Done with hot pixel readout
[10:32:47.663] INFO: PixTest:: pg_setup set to default.
[10:32:47.663] INFO: 5 hot pixels found in step 1
[10:32:48.658] INFO: Collecting data for 5 seconds...
[10:32:53.675] INFO: Done with hot pixel readout
[10:33:05.350] INFO: PixTest:: pg_setup set to default.
[10:33:05.351] INFO: 7 hot pixels found in step 2
[10:33:06.343] INFO: Collecting data for 5 seconds...
[10:33:11.359] INFO: Done with hot pixel readout
[10:33:23.037] INFO: PixTest:: pg_setup set to default.
[10:33:23.038] INFO: 4 hot pixels found in step 3
[10:33:24.029] INFO: Collecting data for 5 seconds...
[10:33:29.046] INFO: Done with hot pixel readout
[10:33:40.686] INFO: PixTest:: pg_setup set to default.
[10:33:40.687] INFO: 5 hot pixels found in step 4
[10:33:41.679] INFO: Collecting data for 5 seconds...
[10:33:46.695] INFO: Done with hot pixel readout
[10:33:58.388] INFO: PixTest:: pg_setup set to default.
[10:33:58.389] INFO: 3 hot pixels found in step 5
[10:33:59.381] INFO: Collecting data for 5 seconds...
[10:34:04.398] INFO: Done with hot pixel readout
[10:34:16.031] INFO: PixTest:: pg_setup set to default.
[10:34:16.032] INFO: 4 hot pixels found in step 6
[10:34:17.024] INFO: Collecting data for 5 seconds...
[10:34:22.041] INFO: Done with hot pixel readout
[10:34:33.655] INFO: PixTest:: pg_setup set to default.
[10:34:33.656] INFO: 4 hot pixels found in step 7
[10:34:34.649] INFO: Collecting data for 5 seconds...
[10:34:39.666] INFO: Done with hot pixel readout
[10:34:51.249] INFO: PixTest:: pg_setup set to default.
[10:34:51.250] INFO: 1 hot pixels found in step 8
[10:34:52.244] INFO: Collecting data for 5 seconds...
[10:34:57.260] INFO: Done with hot pixel readout
[10:35:08.798] INFO: PixTest:: pg_setup set to default.
[10:35:08.799] INFO: 4 hot pixels found in step 9
[10:35:09.793] INFO: Collecting data for 5 seconds...
[10:35:14.808] INFO: Done with hot pixel readout
[10:35:25.857] INFO: PixTest:: pg_setup set to default.
[10:35:25.858] INFO: 2 hot pixels found in step 10
[10:35:26.861] INFO: Collecting data for 5 seconds...
[10:35:31.879] INFO: Done with hot pixel readout
[10:35:43.351] INFO: PixTest:: pg_setup set to default.
[10:35:43.352] INFO: 1 hot pixels found in step 11
[10:35:44.345] INFO: Collecting data for 5 seconds...
[10:35:49.362] INFO: Done with hot pixel readout
[10:36:00.966] INFO: PixTest:: pg_setup set to default.
[10:36:00.966] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:36:00.967] INFO: 3 hot pixels found in step 12
[10:36:01.960] INFO: Collecting data for 5 seconds...
[10:36:06.977] INFO: Done with hot pixel readout
[10:36:18.434] INFO: PixTest:: pg_setup set to default.
[10:36:18.435] INFO: 0 hot pixels found in step 13
[10:36:18.471] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:36:18.475] INFO: PixTest::trimHotPixels() done
[10:36:18.475] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C0.dat
[10:36:18.480] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C1.dat
[10:36:18.486] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C2.dat
[10:36:18.491] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C3.dat
[10:36:18.497] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C4.dat
[10:36:18.502] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C5.dat
[10:36:18.507] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C6.dat
[10:36:18.512] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C7.dat
[10:36:18.518] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C8.dat
[10:36:18.523] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C9.dat
[10:36:18.528] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C10.dat
[10:36:18.533] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C11.dat
[10:36:18.539] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C12.dat
[10:36:18.544] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C13.dat
[10:36:18.549] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C14.dat
[10:36:18.555] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C15.dat
[10:36:18.560] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//defaultMaskFile.dat
[10:36:18.569] INFO: enter test to run
[10:37:11.591] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:37:11.591] INFO: running: highrate
[10:37:11.595] INFO: ----------------------------------------------------------------------
[10:37:11.595] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:37:11.595] INFO: ----------------------------------------------------------------------
[10:37:11.595] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:37:11.596] INFO: edge/corner pixel THR is adjusted
[10:37:11.596] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:37:12.553] INFO: Collecting data for 1 seconds...
[10:37:13.557] INFO: Done with hot pixel readout
[10:37:17.687] INFO: PixTest:: pg_setup set to default.
[10:37:17.688] INFO: 0 hot pixels found in step 0
[10:37:17.693] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:37:17.774] INFO: PixTest::trimHotPixels() done
[10:37:17.774] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C0.dat
[10:37:17.781] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C1.dat
[10:37:17.791] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C2.dat
[10:37:17.796] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C3.dat
[10:37:17.802] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C4.dat
[10:37:17.807] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C5.dat
[10:37:17.812] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C6.dat
[10:37:17.818] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C7.dat
[10:37:17.823] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C8.dat
[10:37:17.828] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C9.dat
[10:37:17.833] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C10.dat
[10:37:17.839] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C11.dat
[10:37:17.844] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C12.dat
[10:37:17.849] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C13.dat
[10:37:17.854] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C14.dat
[10:37:17.860] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//trimParameters35_C15.dat
[10:37:17.865] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-2-34_FPIXTest-17C-FNAL-160413-0856_2016-04-13_08h56m_1460555795/000_FPIXTest_p17//defaultMaskFile.dat
[10:37:17.875] INFO: enter test to run
[10:37:38.815] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:37:38.815] INFO: running: xray
[10:37:38.816] INFO: ----------------------------------------------------------------------
[10:37:38.816] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:37:38.816] INFO: ----------------------------------------------------------------------
[10:37:39.780] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:37:51.241] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:38:20.798] INFO: Resuming triggers.
[10:38:32.264] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[10:39:01.805] INFO: Resuming triggers.
[10:39:13.274] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:39:42.734] INFO: Resuming triggers.
[10:39:54.203] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:40:18.201] INFO: Resuming triggers.
[10:40:29.669] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:40:59.166] INFO: Resuming triggers.
[10:41:10.636] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[10:41:40.157] INFO: Resuming triggers.
[10:41:51.620] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:42:21.128] INFO: Resuming triggers.
[10:42:32.596] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[10:43:02.092] INFO: Resuming triggers.
[10:43:10.691] INFO: data taking finished, elapsed time: 100 seconds.
[10:43:33.042] INFO: PixTest:: pg_setup set to default.
[10:43:33.045] INFO: PixTestXray::doPhRun() done
[10:43:33.219] INFO: enter test to run
[10:45:02.667] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:45:02.668] INFO: running: xray
[10:45:02.669] INFO: ----------------------------------------------------------------------
[10:45:02.669] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:45:02.669] INFO: ----------------------------------------------------------------------
[10:45:03.633] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:45:10.033] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:45:40.294] INFO: Resuming triggers.
[10:45:46.698] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[10:45:46.714] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 2 ROCs were found
[10:45:46.714] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[10:45:46.714] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:45:46.714] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a083 8040 43c8 4d5 2885 43c9 43c8 43c8 99 244d 502 286c 43c8 244 244f 43c9 45b 2a6a 493 2a65 684 2449 43c8 355 266f 43c9 58 284f 58 2a69 609 2065 e000 c000
[10:45:46.714] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07d 80c0 4388 762 2245 4388 6e2 2245 4388 438a 100 264c 14c 204f 24c 2443 65b 2464 4388 351 286d 4388 11 284f 322 2246 661 2662 4388 21c 266c 68b 2062 4388 314 2843 e000 c000
[10:45:46.714] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07e 8000 4388 d2 202a 4388 315 2849 35b 2889 438a 4388 40 2640 21b 266f 25b 2465 4388 15a 206a 4388 d5 224e 35b 2a67 409 2481 41d 2a6d 4388 30c 2849 552 2046 4388 114 2061 248 2065 298 2a4c e000 c000
[10:45:46.714] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07f 8040 438a 20a 2667 438a 13 2045 4388 4388 62 204c 91 264f 4cb 2861 55c 2040 438a 650 2867 651 2a68 438a 210 2869 648 2684 438a 438a 140 2640 e000 c000
[10:45:46.714] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a080 80b1 53c8 43c8 3 2ffa eab e099 c0f7
[10:45:46.714] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a081 80c0 43c8 43c8 43c9 43c9 43c9 d3 226d 43c9 11a 226b 43c9 243 244f 43c9 343 284f e000 c000
[10:45:46.714] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a082 8000 43c8 d 2465 50 2a42 114 2a2d 43c8 43c8 43c8 43c8 28d 2862 289 206d 43c8 8b 204c 84c 2245 43c9 40b 2a64 698 244d 814 2a4f 85b 204f 84d 244d 43c9 e000 c000
[10:45:46.714] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:45:46.714] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a183 8040 43c8 200 284f 43c9 db 2a66 43c8 1a 2440 cc 2644 713 2267 43c9 6d3 2a45 75b 266f 85a 264d 43c8 40b 246c 85a 2229 43c8 43c8 43c9 29c 2641 e000 c000
[10:45:46.714] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17d 80c0 4388 4388 122 224f 4388 653 286c 741 262d 4388 31d 2647 54c 2464 4388 40d 2665 4388 d0 2049 4388 4388 e000 c000
[10:45:46.714] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17e 8000 4388 55d 284c 74a 2047 4388 81d 244f 4388 35d 224f 75b 2441 4388 294 2a68 85d 264d 4388 559 2241 438a 495 264e 4388 743 2a4c 85c 262f 4388 61c 282c e000 c000
[10:45:46.714] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17f 8040 438a 119 2a48 342 2a47 80a 222f 438a 438a 10b 244f 20c 2664 438a 438a 4389 353 202c 438a 438a e000 c000
[10:45:46.714] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a180 80b1 53c9 43c9 43c8 fa0 2001 fdf 2f63 1e5f 2761 ee6 2a62 72 2a9b 43c9 43c9 43c9 43c9 e000 c000
[10:45:46.714] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a181 80c0 43c8 43c8 c8 2845 551 2244 61b 284f 43c8 43c8 11b 266d 43c9 49 2265 154 244f 43c8 43c9 43c9 e000 c000
[10:45:46.714] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a182 8000 43c8 43c8 43c9 2e0 202e 43c9 43c8 2dd 204f 43c9 149 264f 43c9 43c9 e000 c000
[10:46:16.918] INFO: Resuming triggers.
[10:46:23.324] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[10:46:53.461] INFO: Resuming triggers.
[10:46:59.864] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[10:47:30.102] INFO: Resuming triggers.
[10:47:36.506] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[10:48:06.778] INFO: Resuming triggers.
[10:48:13.182] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[10:48:43.454] INFO: Resuming triggers.
[10:48:49.858] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[10:49:20.161] INFO: Resuming triggers.
[10:49:26.564] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[10:49:56.849] INFO: Resuming triggers.
[10:50:03.254] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:50:28.961] INFO: Resuming triggers.
[10:50:35.365] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[10:51:05.588] INFO: Resuming triggers.
[10:51:11.995] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[10:51:12.320] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[10:51:12.320] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:51:12.320] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a025 80c0 43c8 61 2844 59 2a46 152 2645 213 2666 43c8 43c8 213 244c 804 2649 43c8 2db 2865 43c8 82 2648 30a 2665 43c8 54c 2a2f 74c 2444 43c8 262 246f 30b 264e 43c8 c8 204d 413 28a6 41b 2a81 e000 c000
[10:51:12.320] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01f 8040 438a 98 202d 35a 202d 438a 415 2a49 4388 518 282d 4388 51 242e 4d5 246f 438a 802 2265 438a 13 244f 50a 2a2f 74a 264d 438a d1 204f 748 2444 84a 2445 438a 682 2489 e000 c000
[10:51:12.320] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a020 80b1 4388 22 2266 160 2a4b 4388 4388 30b 2248 845 222f 4389 a 224d 88 284c 503 2869 4388 10 2044 85 266d 514 284f 4388 28c 2a4e 29a 2240 4388 159 2845 21c 2269 21c 2666 319 2a66 6d0 224f 4388 209 226a e000 c000
[10:51:12.320] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a021 80c0 4388 4388 4389 804 222b 4389 10d 2849 10b 2688 153 266d 249 2a6d 450 2a67 4389 4389 da 224c 70c 2a24 4389 145 222c 2dc 2845 74b 2245 4389 e000 c000
[10:51:12.320] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a022 8000 4388 2d5 264f 4388 2dc 284c 4388 413 282d 650 2249 4388 4388 a 284c 618 2861 7ef 23dd 289 43c9 34c 2649 401 2a4a 689 2266 70b 2464 84c 2446 43c9 155 2447 e000 c000
[10:51:12.320] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a023 8040 43c8 35c 224f 492 2846 819 284d 43c9 494 242f 43c8 54b 244f 43c8 694 286a 43c8 8a 2066 2ca 2282 405 2263 715 2860 715 2445 43c9 2db 2469 43c8 2d1 2440 43c9 24b 284f 241 2649 e000 c000
[10:51:12.320] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a024 80b1 43c8 252 244f 25b 264d 43c8 1d 2842 11d 2a47 43c8 43c8 43c8 43c8 14d 284d 558 2a48 658 2860 43c8 319 2649 60c 2449 64d 2061 6d8 226f 43c8 205 2861 413 246c 4db 2a8d e000 c000
[10:51:42.266] INFO: Resuming triggers.
[10:51:48.672] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[10:52:18.938] INFO: Resuming triggers.
[10:52:25.343] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[10:52:55.641] INFO: Resuming triggers.
[10:53:02.048] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[10:53:32.302] INFO: Resuming triggers.
[10:53:38.706] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[10:54:08.825] INFO: Resuming triggers.
[10:54:13.094] INFO: data taking finished, elapsed time: 100 seconds.
[10:54:13.440] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[10:54:13.440] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[10:54:13.440] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:54:13.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b7 8040 43c8 43c8 43c8 410 2641 43c8 43c8 4d5 226f 6e2 2669 43c8 11 284b 43c8 691 2463 43c8 e000 c000
[10:54:13.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b1 80c0 4388 41d 244c 4388 2e0 2845 4389 4389 155 286c 14c 244e 251 244b 559 2449 4389 759 204f 4389 21c 2049 354 224c 693 2a65 821 2a48 4389 709 2643 4389 11 2a27 508 286d e000 c000
[10:54:13.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b2 8000 4388 18 202e 4388 4388 44c 242f 4388 2d8 2a60 645 2465 4388 51c 2268 4388 619 2263 4389 11c 244d 4389 60c 2447 e000 c000
[10:54:13.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b3 8040 4388 62 2845 4389 d8 2643 4388 4388 693 2683 4388 19 2267 511 2069 648 2461 4389 8d 2244 285 284c 4388 99 266d 6d1 2a86 4389 412 2a6d e000 c000
[10:54:13.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b4 80b1 43c8 405 304f eab 273d f7f 267c af6 43c8 68c 2847 43c8 490 2281 43c8 748 244c 75d 2665 43c8 a0 2448 204 2241 21c 2a69 43c8 43c8 252 226d 284 264f 494 2665 e000 c000
[10:54:13.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b5 80c0 43c8 43c8 59 282f 43c8 43c8 149 2464 808 2045 845 2467 845 2062 43c8 251 2649 43c8 d4 2a4d 253 2a60 752 2423 759 2a44 43c8 d9 204f 45a 2a69 43c8 212 264d e000 c000
[10:54:13.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b6 8000 43c8 11 2249 43c8 403 2049 43c8 68c 2465 43c8 43c8 9c 2249 cd 2a47 d1 2a61 6d2 2247 43c8 49b 208c 65c 2269 43c8 694 2065 75a 2a2c 43c8 a 202f 261 2480 54b 2284 e000 c000
[10:54:13.440] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:54:13.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b7 8040 43c8 513 2649 43c8 43c8 24d 2645 259 2642 43c8 98 2a4d 40a 2440 562 264d 6ca 2646 43c8 51d 244f 43c8 43c8 43c8 e000 c000
[10:54:13.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b1 80c0 4388 511 2a2f 4388 343 2649 343 2a64 4388 859 2a2d 4388 d2 2a6b 11b 2443 349 2a66 710 2a40 4389 444 204c 550 2a45 4388 4389 4389 e000 c000
[10:54:13.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b2 8000 4388 4388 718 264f 4389 5 2643 2db 2a47 48b 2a45 4389 551 2849 4388 684 2840 4389 4389 303 282c 303 2440 4389 2dc 2a2c e000 c000
[10:54:13.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b3 8040 4388 4389 24c 2243 40b 204c 71c 224d 752 2242 4388 151 2a40 4389 4d4 202f 801 242d 4388 4388 109 204d 108 2849 4388 859 2844 4389 e000 c000
[10:54:13.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b4 80b1 43c8 43c8 1319 2401 fc6 2aaa 9eb 2f7e 10 2630 2630 632 2898 634 2c98 924 43c8 43c8 40 2444 311 2449 311 2a61 43c8 43c8 e000 c000
[10:54:13.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b5 80c0 43c8 43c8 1d 2061 12 206f 43c8 43c8 70b 2465 43c8 158 2849 455 2a6c 6d1 286f 43c8 43c8 821 204b 43c8 e000 c000
[10:54:13.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b6 8000 43c8 d0 202e 43c8 213 2448 43c8 445 204c 43c8 43c8 43c8 43c8 444 2629 43c8 e000 c000
[10:54:33.670] INFO: PixTest:: pg_setup set to default.
[10:54:33.673] INFO: PixTestXray::doPhRun() done
[10:54:33.823] INFO: enter test to run
[10:54:58.143] INFO: test: HighRate no parameter change
[10:54:58.143] INFO: running: highrate
[10:54:58.144] INFO: ----------------------------------------------------------------------
[10:54:58.144] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:54:58.144] INFO: ----------------------------------------------------------------------
[10:54:58.285] INFO: Expecting 768 events.
[10:54:59.418] INFO: 768 events read in total (418ms).
[10:54:59.418] INFO: Test took 1267ms.
[10:55:00.222] INFO: Expecting 41600 events.
[10:55:03.352] INFO: 41600 events read in total (2603ms).
[10:55:03.353] INFO: Test took 3929ms.
[10:55:03.388] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:04.114] INFO: Expecting 41600 events.
[10:55:07.319] INFO: 41600 events read in total (2678ms).
[10:55:07.320] INFO: Test took 3915ms.
[10:55:07.354] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:08.077] INFO: Expecting 41600 events.
[10:55:11.283] INFO: 41600 events read in total (2680ms).
[10:55:11.284] INFO: Test took 3911ms.
[10:55:11.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:12.043] INFO: Expecting 41600 events.
[10:55:15.288] INFO: 41600 events read in total (2718ms).
[10:55:15.289] INFO: Test took 3952ms.
[10:55:15.323] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:16.045] INFO: Expecting 41600 events.
[10:55:19.275] INFO: 41600 events read in total (2703ms).
[10:55:19.276] INFO: Test took 3934ms.
[10:55:19.310] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:20.035] INFO: Expecting 41600 events.
[10:55:23.276] INFO: 41600 events read in total (2714ms).
[10:55:23.277] INFO: Test took 3948ms.
[10:55:23.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:24.036] INFO: Expecting 41600 events.
[10:55:25.453] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[10:55:25.453] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:55:25.453] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1df 8040 43cb 11 206d 35a 242c 43cb 11 2083 43ca 11 206d 52 2849 43ca 11 208c 43ca 11 206f 59 2842 43c9 11 208d 43cb 11 206d 43cb 11 206f e002 c000
[10:55:25.453] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d9 80c0 4388 11 2485 4388 11 2485 75c 284c 75c 2445 4389 11 248f 4388 11 2489 4388 11 248d 19 2245 4389 11 2487 4388 11 2487 4388 11 2485 e002 c000
[10:55:25.453] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1da 8000 4388 11 2480 4388 11 2485 81c 284f 4389 11 248f 4388 11 24a3 4388 11 2485 4389 11 2489 4388 11 2485 4388 11 2487 e002 c000
[10:55:25.453] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1db 8040 4388 11 206f 29a 2447 4389 11 2081 4389 11 208d 20a 2466 4388 11 208f 4389 11 2085 4389 11 2087 4388 11 2085 4388 11 206f e002 c000
[10:55:25.453] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1dc 80b1 4388 11 2082 84b 2643 4388 11 2089 4388 11 206f cb 284f 4388 11 2089 750 2429 4388 11 2082 504 2441 43c9 11 20bf 800 2022 2020 22 e002 c000
[10:55:25.453] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1dd 80c0 43c9 11 206c 489 2849 43c8 11 2085 43c9 11 2081 43c9 11 208d 43c9 15 2045 11 2088 43c8 11 208f 43c9 11 206d 43c8 11 2080 e002 c000
[10:55:25.453] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1de 8000 43c8 11 2080 43c9 11 206f 43c9 11 2084 43c9 11 208c 43c9 11 206f 43ca 11 208d 43c9 11 2068 43c9 11 2085 e002 c000
[10:55:27.290] INFO: 41600 events read in total (2727ms).
[10:55:27.291] INFO: Test took 3960ms.
[10:55:27.325] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:28.046] INFO: Expecting 41600 events.
[10:55:29.610] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (5) != Token Chain Length (8)
[10:55:29.611] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 4 ROCs were found
[10:55:29.611] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (77) != TBM ID (64)
[10:55:29.611] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:55:29.611] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a142 8000 43c8 99 268d 43c8 1a 2265 99 268a 43c9 99 268d 43c9 99 26a5 352 284b 43c8 99 26a5 43c9 99 268d 43c9 99 268d 43c9 99 2682 759 244d e002 c000
[10:55:29.611] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13c 80b1 4389 99 2a8c 4388 99 2a8f 353 2467 451 2481 4389 99 2a8f 4389 99 2a8c 614 2843 855 202d 4389 99 2aa9 4389 99 2a8f 4389 99 2a89 4388 99 2a84 e002 c000
[10:55:29.611] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13d 80c0 4388 99 268e 341 2441 4389 99 268e 4388 99 268f 841 202f 4388 99 26a5 4388 99 26ad 4389 99 2688 4388 99 26a1 35b 284f 4389 99 2685 e002 c000
[10:55:29.611] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13e 8000 4388 99 268c 4389 99 268c 613 2849 4388 99 268e 60b 202d 4388 99 26a1 54d 244a 4389 99 26a4 438b 99 2689 212 2445 4389 99 268d 4389 99 2684 e002 c000
[10:55:29.611] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a13f 8040 438a 99 268c 438a 99 2687 438b 99 2688 80b 2040 438b 1012 202f 1099 26a5 1 2400 e8f 2ebf 1 2ecd 5a3 3007 e65 2003 82a 3aab 1003 280f e00 2000 81c 2040 1ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[10:55:29.611] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a140 80b1 43c9 99 268c 43c9 99 2685 43c9 99 268e 158 2a2a 43c9 99 26a9 43c9 99 26a5 43c9 99 268c 34c 2069 43c9 99 268d 43c9 99 2685 e002 c000
[10:55:29.611] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a141 80c0 43c8 99 268c 43c8 99 268f 43c8 99 268f 43c8 99 26a5 43c9 99 26a9 43c8 99 268d 43c9 99 268c 43c9 99 2685 e002 c000
[10:55:29.611] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:55:29.611] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a043 8040 43c8 99 268e 43c9 99 26a4 43c8 99 2685 43c8 99 26a5 85a 2862 43c8 99 26a0 43c9 91 2a49 99 268f 43c8 99 26a9 43c9 99 26a1 219 244f e002 c000
[10:55:29.611] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03e 8000 4389 99 268f 4388 99 26a5 438a 99 268d 4388 99 268c 4388 99 268f 4389 99 26a1 143 284c 4389 99 26ac 85a 264e 4389 99 2689 e002 c000
[10:55:29.611] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a03f 8040 438b 99 268c 6da 2442 438a 99 26a1 4388 99 268d 4388 1099 268d 53ca 2681 66a 2e4c 3d9 2d56 67f
[10:55:29.611] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== b04c 804f 5ad 2f00 1f57 43ca f55 256a eaf 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[10:55:29.611] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a040 80b1 43c8 99 268c dd 2642 43c8 99 26a3 43c8 99 2686 43c9 99 268f 213 2063 43c8 99 26a0 43c8 99 268e 43c8 99 26a4 43c8 99 26a1 e002 c000
[10:55:29.611] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a041 80c0 43c8 99 268c 43c8 99 26a1 43c9 99 268c 43c9 99 268e 704 2049 43c9 99 26a9 43c9 8b 2649 99 268d 43c9 13 206d 99 26ac 43c9 99 268d e002 c000
[10:55:29.611] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a042 8000 43c8 99 26a0 14d 2845 43c8 99 26a1 43c8 99 268c 43c8 99 268f 43c8 99 26a7 43c8 99 26a5 855 2a47 43c9 99 26a7 28d 264f 43c9 99 26a2 24b 2081 e002 c000
[10:55:31.439] CRITICAL: <hal.cc/condenseTriggers:L1899> Data size does not correspond to 10 triggers! Aborting data processing!
[10:55:31.442] INFO: 0 events read in total (2869ms).
[10:55:31.442] CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L783> Incomplete DAQ data readout! Missing 4160 Events.
[10:55:32.065] INFO: Expecting 41600 events.
[10:55:33.566] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 7 ROCs were found
[10:55:33.566] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 7 ROCs were found
[10:55:33.567] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:55:33.567] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a056 8000 43c8 5a 2aa1 43c8 5a 2a89 43c8 5a 2aa7 43c9 5a 2aa6 512 2a65 43c8 5a 2aaf 605 2863 43c8 5a 2a8c 4c9 2664 610 202f 43c8 19 204c 5a 2ac5 43c8 41 2a42 5a 2aa5 e002 c000
[10:55:33.567] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a050 80b1 4388 5b 22a0 4388 5b 228d 4388 5b 22a9 4389 5b 228d 4388 5b 22e1 4388 5b 228e 80c 2245 4388 5b 22c5 45b 2263 4388 5b 22a5 61 2a65 21a 2465 e002 c000
[10:55:33.567] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a051 80c0 4388 5b 22a4 4388 5b 228d 4389 5b 22a8 4389 5b 228f 4389 5b 22c9 4389 5b 228d 4389 5b 22c6 4389 5b 22a1 e002 c000
[10:55:33.567] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a052 8000 4388 5b 22a2 4388 5b 228f 4388 5b 22a3 455 2247 4388 5b 228f 4388 5b 22c3 4388 5b 228d 4389 5b 22c8 d9 2449 4389 5b 22a8 11b 226d e002 c000
[10:55:33.567] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a053 8040 4388 5b 22ad 4389 5b 228f 114 2627 4388 5b 22a5 405 222d 4388 5b 22a1 4388 5b 22c9 4389 5b 228f 43c8 105b 32c7 7ff 2fff fff 2ffe 20 20ff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff
[10:55:33.567] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a054 80b1 43c8 5b 22a5 2d8 2647 43c8 5b 22a1 43c8 5b 22a7 43c8 55 2440 5b 228f 43c8 5b 22c3 43c8 5b 2289 43c8 5b 22c1 43c8 5b 22a1 e002 c000
[10:55:33.567] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a055 80c0 43c8 5a 2a8f 43c8 5a 2a8d 43c8 5a 2a8f 43c8 5a 2a8c 43c8 5a 2aad 50c 244c 43c8 5a 2aa2 15c 2449 43c8 5a 2ac7 43c8 5a 2a8f e002 c000
[10:55:33.567] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:55:33.567] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a156 8000 43c8 5a 2a8c 43c8 5a 2aa4 719 2245 43c8 5a 2aa2 43c8 54 262d 5a 2aa5 43c8 5a 2a85 43c8 5a 2a89 43c8 5a 2a8d 43c8 5a 2a85 4d9 204e e002 c000
[10:55:33.567] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a150 80b1 4389 5b 22a4 4389 5b 228d 4389 5b 22a2 4389 5b 22ac 4389 5b 228d 4389 5b 228f 4389 5b 228d 4389 5b 22a2 9d 222d e002 c000
[10:55:33.567] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a151 80c0 4388 5b 22a4 4388 5b 228f 552 2a41 4388 5b 22a3 219 2063 4388 5b 22a2 4389 5b 228c 110 2841 4388 5b 228f 4389 5b 228d 4389 5b 22a1 e002 c000
[10:55:33.567] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a152 8000 4388 5b 22a2 4388 5b 22a5 4389 5b 22a9 850 2049 4389 5b 22a7 4388 5b 228f 69c 2041 4389 5b 22a5 4d8 2243 4389 5b 228f 4389 5b 228f e002 c000
[10:55:33.567] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a153 8040 4388 5b 22a1 414 2443 4389 5b 228f 4388 5b 22a5 31c 244f 4389 5b 22a1 4388 5b 228d 4388 5b 228f 43c8 105b 3289 7fd 2f40 aaa 201c 40 21ff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff
[10:55:33.567] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a154 80b1 43c8 5b 22a1 859 2247 43c8 5b 22a5 43c8 5b 22ac cd 2644 20c 204d 43c8 5b 22a9 43c8 5b 22a1 43c8 5b 2289 40b 244c 40a 2641 43c8 5b 228f 43c8 5b 228f 85c 2441 e002 c000
[10:55:33.567] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a155 80c0 43c8 5a 2a86 43c8 5a 2aa1 43c8 5a 2a8f 403 266f 43c8 5a 2aaa 354 2663 43c8 5a 2a6f 43c8 5a 2a8c 43c8 5a 2a88 43c8 5a 2a80 e002 c000
[10:55:35.295] INFO: 41600 events read in total (2703ms).
[10:55:35.296] INFO: Test took 3841ms.
[10:55:35.329] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:36.053] INFO: Expecting 41600 events.
[10:55:39.306] INFO: 41600 events read in total (2726ms).
[10:55:39.307] INFO: Test took 3959ms.
[10:55:39.341] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:40.062] INFO: Expecting 41600 events.
[10:55:43.299] INFO: 41600 events read in total (2710ms).
[10:55:43.300] INFO: Test took 3942ms.
[10:55:43.335] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:44.059] INFO: Expecting 41600 events.
[10:55:45.483] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (3) != Token Chain Length (8)
[10:55:45.483] WARNING: Channel 1 ROC 2: Readback start marker after 4 readouts!
[10:55:45.483] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (5) != Token Chain Length (8)
[10:55:45.483] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:55:45.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a036 8000 43c8 8 2080 43c8 8 2069 43c8 8 206f 499 2a47 648 262c 43c9 8 2087 43c8 8 208c 43c8 8 208f 710 2224 43c8 8 20a0 43c8 8 206d 55 224c e002 c000
[10:55:45.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a030 80b1 4388 19 2440 8 2082 4388 8 2069 4388 8 2081 4389 8 208f 4388 8 2089 414 2062 492 2281 4388 8 208d 290 264d 4388 8 2085 4388 8 2067 408 2269 e002 c000
[10:55:45.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a031 80c0 4388 8 2088 4388 8 206f 4389 8 206d 4389 8 20a7 4389 8 2089 44a 204f 4389 8 208c 4389 8 208c 118 2645 20b 2269 4389 8 2065 e002 c000
[10:55:45.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a032 8000 4388 8 206f 4388 8 206f 4388 8 2082 4388 12 204c 8 208d 4388 8 2087 4388 8 208d 4389 8 208d 4389 8 2067 e002 c000
[10:55:45.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a033 8040 4388 8 2084 43c9 8 2067 43c9 f6b 27f2 aab 2f6b 7f5 2ffc e000 c020
[10:55:45.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a034 80b1 43c8 8 2080 43c8 8 206d 43c8 8 2082 304 2049 43c8 8 208c 43c8 8 2087 43c8 8 208d 2cd 2449 43c8 8 2085 4c 2a4e 215 244d 43c8 8 2065 e002 c000
[10:55:45.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a035 80c0 43c8 8 2081 43c8 8 206d 94 2a69 43c8 8 2080 68b 2a48 43c8 8 2089 43c8 8 208b 43c8 8 2085 24a 2a40 43c8 8 2083 43c8 8 2065 e002 c000
[10:55:45.483] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:55:45.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a136 8000 43c8 8 206d 43c8 8 206d 43c8 8 206d 43c8 8 2069 43c8 8 206d 43c8 8 2084 101 2a49 43c8 8 206d 43c8 8 206f e002 c000
[10:55:45.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a130 80b1 4389 8 206f 4389 8 206f 4389 8 206f 4389 8 206b 4389 19 2040 8 206f 19 222f 4389 8 2083 4389 8 206c 4389 8 206d e002 c000
[10:55:45.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a131 80c0 4388 8 2065 4388 8 2080 15b 2a69 4388 8 206d 4388 8 2080 20 264e 4389 8 206d 6ca 2449 4388 8 2081 4389 8 206b 4389 8 206d e002 c000
[10:55:45.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a132 8000 4388 8 2068 c2 2641 4388 8 2069 4389 8 2081 4389 8 206d 4388 8 2085 358 2a64 4389 8 2081 4389 8 206d 4389 8 206f e002 c000
[10:55:45.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a133 8040 4388 8 2064 d4 262f 43c9 53ca 801 2403 fe9 2807 eff 2e78 70 2010 2010 10 2100 8ba 209a 884 43c8 8 2081 43c9 8 206f e002 c000
[10:55:45.484] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a134 80b1 43c8 8 206c 43c8 8 206f 43c8 8 2082 43c8 8 206f 43c8 8 2085 43c8 8 206f 43c8 8 206f 43c8 8 206f 54a 2a41 e002 c000
[10:55:45.484] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a135 80c0 43c8 8 206d 281 2062 43c8 8 2081 43c8 8 206f 43c8 8 2083 43c8 8 206d 43c8 8 2080 43c8 8 206f 43c8 8 2081 e002 c000
[10:55:47.273] INFO: 41600 events read in total (2687ms).
[10:55:47.274] INFO: Test took 3921ms.
[10:55:47.308] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:48.033] INFO: Expecting 41600 events.
[10:55:51.269] INFO: 41600 events read in total (2709ms).
[10:55:51.271] INFO: Test took 3944ms.
[10:55:51.304] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:52.031] INFO: Expecting 41600 events.
[10:55:53.455] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 3 ROCs were found
[10:55:53.455] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 3 ROCs were found
[10:55:53.455] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (200) != TBM ID (174)
[10:55:53.455] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (2) != Token Chain Length (8)
[10:55:53.455] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (175) != TBM ID (200)
[10:55:53.455] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:55:53.455] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ca 8000 43c8 b 248e 43c9 b 248f 2a1 2a4d 43c8 b 248d 855 2640 43c8 b 24a1 43c9 b 2483 43c8 b 248d 43c8 b 2485 43c8 b 2481 e002 c000
[10:55:53.455] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c4 80b1 4388 b 2882 2c8 224c 402 224d 4388 b 288c 4388 b 288d 4388 b 28a1 2dc 2049 4388 b 286f 211 2a4f 4388 b 288f 4388 b 288d 4388 b 2880 e002 c000
[10:55:53.455] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c5 80c0 4388 b 2889 35b 266c 40c 2849 4388 b 2889 4388 b 288c 4388 b 288f 4388 1c 2849 b 2889 4388 b 288f 4388 b 288d 4388 b 2881 e002 c000
[10:55:53.455] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c6 8000 4388 b 2885 4388 b 2889 4388 b 28a1 4388 b 28a5 4388 b 2887 4388 b 288f 415 2a45 4388 b 2887 4388 b 2881 e002 c000
[10:55:53.455] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c7 8040 4388 b 2882 4388 b 2884 43c8 b 288f 1102 2b7f fff 3fea e09b c0aa
[10:55:53.455] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c8 80b1 43c9 b 2882 75d 224a 43c9 b 288e 81d 2a8d 43c9 b 288c 619 2848 43c9 b 288f 300 2440 43c9 b 286f 43c8 b 288d 43c9 b 288d 71d 2841 43c9 b 2884 e002 c000
[10:55:53.455] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c9 80c0 43c8 b 2488 403 2062 43c8 b 24a4 43c8 b 2489 43c8 b 24a9 43c8 b 2489 43c8 b 248d 43c8 b 248d 43c8 b 2481 e002 c000
[10:55:53.455] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:55:53.456] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0cb 8040 43c9 b 24a0 43c8 b 248d 43c8 b 248d 43c8 b 24a6 34b 2a80 43c8 b 248f 43c9 b 24a7 43c8 b 248c 43c8 b 248f e002 c000
[10:55:53.456] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c6 8000 4388 b 28a0 4388 b 2889 413 2068 4388 b 2887 4389 b 28a5 24c 202e 4388 b 28a1 30a 2466 4388 d 2862 b 28a9 4388 b 288d 303 224d 651 224c 4388 b 286f e002 c000
[10:55:53.456] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a0c7 8040 4388 b 288f 4388 b 288c 43c9 b 288c 1557 2ff9 555 3775 7f4 2f3f
[10:55:53.456] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ae 80ef afe 3dff 17fd 25ef 47e 43c8 b 28ad 43c8 b 2889 43c8 b 2885 e002 c000
[10:55:53.456] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c8 80b1 43c9 b 28ac 43c9 b 288d 43c8 b 288f 43c8 b 28a3 43c9 b 28a5 443 2463 43c9 b 28a9 43c9 b 28a3 295 2661 43c9 b 2885 212 264f e002 c000
[10:55:53.456] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c9 80c0 43c8 b 24a1 43c8 b 2489 43c8 b 2489 50d 2829 43c9 b 24a8 43c8 b 248f 261 2a4f 43c8 b 248f 43c8 b 24a1 43c8 b 248d e002 c000
[10:55:53.456] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ca 8000 43c8 b 24a1 43c8 b 248d 43c9 b 248f 43c9 b 24ac 43c9 b 248d 43c8 b 24a9 43c8 b 248d 43c9 b 248e 21d 2865 e002 c000
[10:55:55.433] CRITICAL: <hal.cc/condenseTriggers:L1899> Data size does not correspond to 10 triggers! Aborting data processing!
[10:55:55.435] INFO: 0 events read in total (2877ms).
[10:55:55.435] CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L783> Incomplete DAQ data readout! Missing 4160 Events.
[10:55:56.037] INFO: Expecting 41600 events.
[10:55:59.260] INFO: 41600 events read in total (2696ms).
[10:55:59.261] INFO: Test took 3826ms.
[10:55:59.295] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:00.018] INFO: Expecting 41600 events.
[10:56:03.257] INFO: 41600 events read in total (2712ms).
[10:56:03.258] INFO: Test took 3946ms.
[10:56:03.291] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:04.018] INFO: Expecting 41600 events.
[10:56:05.574] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 3 ROCs were found
[10:56:05.574] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (3) != Token Chain Length (8)
[10:56:05.574] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:56:05.574] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c2 8000 43c8 81 2488 43c8 81 24a5 512 2643 43c8 81 2481 43c8 81 248f 43c8 81 24a5 43c8 81 248f 2dc 284d 74c 2a2d 43c9 81 248d 2d3 224c 694 286c 43c9 81 248d e002 c000
[10:56:05.574] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bc 80b1 4388 81 2484 4389 81 24a2 4388 81 246e 609 264f 4389 81 248d 694 2863 4389 81 24aa 455 2449 4389 81 2489 34b 244d 4389 81 248f 10b 2a49 4389 81 2487 e002 c000
[10:56:05.574] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bd 80c0 4388 81 2485 4389 81 248f 4389 81 2487 438b 81 2489 4388 81 24a2 119 266c 4389 81 2487 314 2848 4389 81 248f 4389 81 248d e002 c000
[10:56:05.575] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0be 8000 4388 81 2485 4388 81 24a5 10b 2244 438b 81 2489 4388 51 262e 81 248f 4389 81 24a1 4389 81 2488 559 284b 4389 81 248a 4388 81 248d e002 c000
[10:56:05.575] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bf 8040 438b 81 2488 438a 81 248f 43c8 1081 2487 7f5 53ca f0f7 c0ae
[10:56:05.575] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c0 80b1 43c8 81 2482 43c8 81 248f 43c8 81 2481 43c9 81 2481 43c8 81 24a1 454 204d 43c8 81 248d 43c8 81 248f 60d 204d 43c8 81 248d e002 c000
[10:56:05.575] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c1 80c0 43c8 81 2485 218 242d 43c8 81 24a1 118 2027 43c9 81 2481 43c9 81 248f 43c9 5d 2a4f 81 24a7 43c9 81 2489 43c9 81 248d 695 2680 43c9 81 2485 e002 c000
[10:56:05.575] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:56:05.575] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c2 8000 43c8 81 2482 43c8 81 2489 43c9 81 248f 34b 2049 6c4 2449 43c9 81 248c 43c8 81 248f 43c9 81 2488 43c9 81 2483 43c9 81 2481 e002 c000
[10:56:05.575] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bc 80b1 4389 81 2480 4389 81 2487 498 242d 6cb 2245 4389 81 248d 4389 81 248c 4388 81 248f 4388 81 2485 4388 81 2481 4389 81 2485 e002 c000
[10:56:05.575] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bd 80c0 4389 81 2486 4389 81 248d 4389 81 248d 4389 81 248d 4388 81 248f 504 226f 4389 81 2489 4388 81 2483 4389 81 2485 e002 c000
[10:56:05.575] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1be 8000 4388 81 2489 653 2642 4388 81 2487 4388 81 2489 4389 81 2489 4388 81 248b 438a 81 248d 4388 81 2484 4388 81 2485 e002 c000
[10:56:05.575] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bf 8040 438b 81 2488 813 2a4f 43ca 81 348f 48c 260f 181a 2ebd bea 289a 80b 2ff9 81 2ebd 6ad 2572 102 43cb 81 2481 43ca 81 2488 e002 c000
[10:56:05.575] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c0 80b1 43c9 81 2486 43c9 81 248d 744 2241 43c9 81 2485 212 2060 249 2247 43c9 81 248b 43c9 4c 2241 81 24a1 43c9 81 248c 43c9 81 2485 43c9 81 246f e002 c000
[10:56:05.575] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c1 80c0 43c8 81 2487 43c8 81 2489 111 2047 43c8 81 248d 43c8 81 24a2 155 2482 43c9 81 24a5 43c8 81 2485 43c9 81 2481 43c9 81 2481 290 282d e002 c000
[10:56:07.269] INFO: 41600 events read in total (2724ms).
[10:56:07.270] INFO: Test took 3960ms.
[10:56:07.304] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:08.027] INFO: Expecting 41600 events.
[10:56:11.261] INFO: 41600 events read in total (2707ms).
[10:56:11.262] INFO: Test took 3940ms.
[10:56:11.296] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:12.022] INFO: Expecting 41600 events.
[10:56:15.278] INFO: 41600 events read in total (2729ms).
[10:56:15.279] INFO: Test took 3965ms.
[10:56:15.313] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:16.035] INFO: Expecting 41600 events.
[10:56:19.277] INFO: 41600 events read in total (2715ms).
[10:56:19.278] INFO: Test took 3948ms.
[10:56:19.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:20.038] INFO: Expecting 41600 events.
[10:56:23.247] INFO: 41600 events read in total (2682ms).
[10:56:23.248] INFO: Test took 3918ms.
[10:56:23.282] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:24.008] INFO: Expecting 41600 events.
[10:56:27.097] INFO: 41600 events read in total (2562ms).
[10:56:27.099] INFO: Test took 3797ms.
[10:56:27.132] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:27.496] INFO: enter test to run
[10:56:37.406] INFO: test: HighRate no parameter change
[10:56:37.406] INFO: running: highrate
[10:56:37.407] INFO: ----------------------------------------------------------------------
[10:56:37.408] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:56:37.408] INFO: ----------------------------------------------------------------------
[10:56:38.020] INFO: Expecting 208000 events.
[10:56:49.905] INFO: 208000 events read in total (11359ms).
[10:56:49.908] INFO: Test took 12493ms.
[10:56:50.053] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:50.306] INFO: number of dead pixels (per ROC): 0 0 1 0 0 1 0 1 0 0 2 3 1 0 0 2
[10:56:50.306] INFO: number of red-efficiency pixels: 93 33 103 114 128 164 130 102 80 118 104 93 111 42 27 30
[10:56:50.306] INFO: number of X-ray hits detected: 64521 43344 66764 106517 109717 116442 112599 83068 74615 98941 92837 82756 87764 49268 24680 25737
[10:56:50.307] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:56:50.307] INFO: number of Vcal hits detected: 207905 207967 207844 207886 207871 207783 207867 207848 207918 207881 207798 207757 207840 207941 207973 207868
[10:56:50.307] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0
[10:56:50.307] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9
[10:56:50.307] INFO: X-ray hit rate [MHz/cm2]: 18.9 12.7 19.6 31.2 32.2 34.1 33.0 24.3 21.9 29.0 27.2 24.3 25.7 14.4 7.2 7.5
[10:56:50.307] INFO: PixTestHighRate::doXPixelAlive() done
[10:56:50.355] INFO: PixTest:: pg_setup set to default.
[10:56:50.371] INFO: enter test to run
[10:57:20.126] INFO: test: HighRate no parameter change
[10:57:20.126] INFO: running: highrate
[10:57:20.127] INFO: ----------------------------------------------------------------------
[10:57:20.127] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:57:20.127] INFO: ----------------------------------------------------------------------
[10:57:20.738] INFO: Expecting 208000 events.
[10:57:34.631] INFO: 208000 events read in total (13366ms).
[10:57:34.637] INFO: Test took 14501ms.
[10:57:34.952] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:35.258] INFO: number of dead pixels (per ROC): 0 0 1 0 0 1 0 1 0 0 2 3 1 0 0 2
[10:57:35.258] INFO: number of red-efficiency pixels: 250 124 329 382 419 687 549 331 248 380 308 276 344 169 52 51
[10:57:35.258] INFO: number of X-ray hits detected: 143285 95724 147739 235714 243418 257294 246862 186183 164495 219540 205715 182709 194792 108950 54735 56786
[10:57:35.258] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:57:35.258] INFO: number of Vcal hits detected: 207738 207872 207582 207570 207555 207164 207395 207593 207741 207596 207564 207558 207586 207810 207946 207848
[10:57:35.258] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.6 99.7 99.8 99.9 99.8 99.9 99.9 99.8 99.9 100.0 100.0
[10:57:35.258] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.6 99.7 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 99.9
[10:57:35.258] INFO: X-ray hit rate [MHz/cm2]: 42.0 28.1 43.3 69.1 71.3 75.4 72.4 54.6 48.2 64.3 60.3 53.6 57.1 31.9 16.0 16.6
[10:57:35.258] INFO: PixTestHighRate::doXPixelAlive() done
[10:57:35.308] INFO: PixTest:: pg_setup set to default.
[10:57:35.324] INFO: enter test to run
[10:57:52.318] INFO: test: HighRate no parameter change
[10:57:52.318] INFO: running: highrate
[10:57:52.319] INFO: ----------------------------------------------------------------------
[10:57:52.319] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:57:52.319] INFO: ----------------------------------------------------------------------
[10:57:52.931] INFO: Expecting 208000 events.
[10:58:08.909] INFO: 208000 events read in total (15451ms).
[10:58:08.916] INFO: Test took 16589ms.
[10:58:09.413] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:09.772] INFO: number of dead pixels (per ROC): 0 0 1 0 0 1 0 1 0 0 2 3 1 0 0 2
[10:58:09.772] INFO: number of red-efficiency pixels: 577 226 748 1008 1069 1570 1444 708 682 967 784 619 655 349 105 106
[10:58:09.772] INFO: number of X-ray hits detected: 219679 147153 225254 359798 372723 393747 376960 283083 251732 335357 314801 280338 299653 166900 83949 87211
[10:58:09.772] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:58:09.772] INFO: number of Vcal hits detected: 207315 207764 206911 206716 206688 205705 206043 207073 207229 206828 207006 207146 207189 207570 207891 207789
[10:58:09.772] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.6 99.4 99.4 99.0 99.1 99.6 99.7 99.5 99.6 99.7 99.7 99.8 100.0 99.9
[10:58:09.772] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.5 99.4 99.4 98.9 99.1 99.6 99.6 99.4 99.5 99.6 99.6 99.8 99.9 99.9
[10:58:09.772] INFO: X-ray hit rate [MHz/cm2]: 64.4 43.1 66.0 105.5 109.2 115.4 110.5 83.0 73.8 98.3 92.3 82.2 87.8 48.9 24.6 25.6
[10:58:09.772] INFO: PixTestHighRate::doXPixelAlive() done
[10:58:09.819] INFO: PixTest:: pg_setup set to default.
[10:58:09.837] INFO: enter test to run
[10:58:11.789] INFO: test: exit no parameter change
[10:58:12.146] QUIET: Connection to board 33 closed.
[10:58:12.148] INFO: pXar: this is the end, my friend