[12:06:07.285] INFO: *** Welcome to pxar ***
[12:06:07.285] INFO: *** Today: 2016/07/19
[12:06:07.407] INFO: *** Version: v1.9.0-814-g7497
[12:06:07.407] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//dacParameters35_C15.dat
[12:06:07.442] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:06:07.442] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//defaultMaskFile.dat
[12:06:07.442] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C15.dat
[12:06:07.533] INFO: clk: 4
[12:06:07.533] INFO: ctr: 4
[12:06:07.533] INFO: sda: 19
[12:06:07.533] INFO: tin: 9
[12:06:07.533] INFO: level: 15
[12:06:07.533] INFO: triggerdelay: 0
[12:06:07.534] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:06:07.534] INFO: Log level: INFO
[12:06:07.551] QUIET: Connection to board DTB_WREKRL opened.
[12:06:07.555] 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:
------------------------------------------------------
[12:06:07.558] INFO: RPC call hashes of host and DTB match: 398089610
[12:06:09.090] INFO: DUT info:
[12:06:09.090] INFO: The DUT currently contains the following objects:
[12:06:09.090] INFO: 2 TBM Cores tbm08c (2 ON)
[12:06:09.090] INFO: TBM Core alpha (0): 7 registers set
[12:06:09.090] INFO: TBM Core beta (1): 7 registers set
[12:06:09.090] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:06:09.090] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.090] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:09.494] INFO: enter 'restricted' command line mode
[12:06:09.494] INFO: enter test to run
[12:06:18.842] INFO: test: PixelAlive no parameter change
[12:06:18.843] INFO: running: pixelalive
[12:06:18.852] INFO: ----------------------------------------------------------------------
[12:06:18.852] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:06:18.852] INFO: ----------------------------------------------------------------------
[12:06:19.173] INFO: Expecting 41600 events.
[12:06:23.499] INFO: 41600 events read in total (3608ms).
[12:06:23.669] INFO: Test took 4813ms.
[12:06:23.681] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:23.943] INFO: PixTestAlive::aliveTest() done
[12:06:23.943] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 66 186 105 63 51 9 0 0 0
[12:06:23.981] INFO: enter test to run
[12:07:01.266] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:07:01.266] INFO: running: highrate
[12:07:01.267] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:07:01.568] INFO: ----------------------------------------------------------------------
[12:07:01.568] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:07:01.568] INFO: ----------------------------------------------------------------------
[12:07:01.568] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:07:01.568] INFO: edge/corner pixel THR is adjusted
[12:07:01.568] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:07:02.528] INFO: Collecting data for 5 seconds...
[12:07:07.544] INFO: Done with hot pixel readout
[12:07:20.569] INFO: PixTest:: pg_setup set to default.
[12:07:20.600] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:07:20.602] INFO: 1980 hot pixels found in step 0
[12:07:21.599] INFO: Collecting data for 5 seconds...
[12:07:26.616] INFO: Done with hot pixel readout
[12:07:38.841] INFO: PixTest:: pg_setup set to default.
[12:07:38.843] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:07:38.843] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:07:38.844] INFO: 1015 hot pixels found in step 1
[12:07:39.839] INFO: Collecting data for 5 seconds...
[12:07:44.854] INFO: Done with hot pixel readout
[12:07:56.761] INFO: PixTest:: pg_setup set to default.
[12:07:56.761] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:07:56.762] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:07:56.762] INFO: 345 hot pixels found in step 2
[12:07:57.756] INFO: Collecting data for 5 seconds...
[12:08:02.772] INFO: Done with hot pixel readout
[12:08:14.690] INFO: PixTest:: pg_setup set to default.
[12:08:14.691] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:14.691] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:14.692] INFO: 143 hot pixels found in step 3
[12:08:15.684] INFO: Collecting data for 5 seconds...
[12:08:20.702] INFO: Done with hot pixel readout
[12:08:32.585] INFO: PixTest:: pg_setup set to default.
[12:08:32.585] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:32.586] INFO: 82 hot pixels found in step 4
[12:08:33.578] INFO: Collecting data for 5 seconds...
[12:08:38.597] INFO: Done with hot pixel readout
[12:08:50.636] INFO: PixTest:: pg_setup set to default.
[12:08:50.637] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:50.637] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:50.637] INFO: 45 hot pixels found in step 5
[12:08:51.629] INFO: Collecting data for 5 seconds...
[12:08:56.647] INFO: Done with hot pixel readout
[12:09:08.588] INFO: PixTest:: pg_setup set to default.
[12:09:08.588] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:08.589] INFO: 36 hot pixels found in step 6
[12:09:09.581] INFO: Collecting data for 5 seconds...
[12:09:14.599] INFO: Done with hot pixel readout
[12:09:26.513] INFO: PixTest:: pg_setup set to default.
[12:09:26.514] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:26.514] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:26.514] INFO: 42 hot pixels found in step 7
[12:09:27.507] INFO: Collecting data for 5 seconds...
[12:09:32.525] INFO: Done with hot pixel readout
[12:09:44.249] INFO: PixTest:: pg_setup set to default.
[12:09:44.250] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:44.250] INFO: 33 hot pixels found in step 8
[12:09:45.242] INFO: Collecting data for 5 seconds...
[12:09:50.261] INFO: Done with hot pixel readout
[12:10:01.637] INFO: PixTest:: pg_setup set to default.
[12:10:01.637] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:01.637] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:01.637] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:01.638] INFO: 35 hot pixels found in step 9
[12:10:02.629] INFO: Collecting data for 5 seconds...
[12:10:07.647] INFO: Done with hot pixel readout
[12:10:19.191] INFO: PixTest:: pg_setup set to default.
[12:10:19.191] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:19.191] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:19.191] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:19.191] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:19.192] INFO: 27 hot pixels found in step 10
[12:10:20.184] INFO: Collecting data for 5 seconds...
[12:10:25.202] INFO: Done with hot pixel readout
[12:10:36.000] INFO: PixTest:: pg_setup set to default.
[12:10:36.001] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:36.001] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:36.001] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:36.001] INFO: 31 hot pixels found in step 11
[12:10:37.998] INFO: Collecting data for 5 seconds...
[12:10:43.016] INFO: Done with hot pixel readout
[12:10:54.853] INFO: PixTest:: pg_setup set to default.
[12:10:54.853] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:54.853] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:54.854] INFO: 22 hot pixels found in step 12
[12:10:55.845] INFO: Collecting data for 5 seconds...
[12:11:00.865] INFO: Done with hot pixel readout
[12:11:12.708] INFO: PixTest:: pg_setup set to default.
[12:11:12.708] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:12.708] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:12.709] INFO: 32 hot pixels found in step 13
[12:11:13.701] INFO: Collecting data for 5 seconds...
[12:11:18.720] INFO: Done with hot pixel readout
[12:11:30.508] INFO: PixTest:: pg_setup set to default.
[12:11:30.509] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:30.509] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:30.509] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:30.509] INFO: 28 hot pixels found in step 14
[12:11:30.549] INFO: 28 hot pixels could not be trimmed and have been masked.
[12:11:30.553] INFO: PixTest::trimHotPixels() done
[12:11:30.553] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C0.dat
[12:11:30.560] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C1.dat
[12:11:30.567] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C2.dat
[12:11:30.573] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C3.dat
[12:11:30.578] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C4.dat
[12:11:30.583] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C5.dat
[12:11:30.589] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C6.dat
[12:11:30.594] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C7.dat
[12:11:30.599] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C8.dat
[12:11:30.605] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C9.dat
[12:11:30.610] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C10.dat
[12:11:30.616] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C11.dat
[12:11:30.621] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C12.dat
[12:11:30.626] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C13.dat
[12:11:30.632] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C14.dat
[12:11:30.637] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C15.dat
[12:11:30.642] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//defaultMaskFile.dat
[12:11:30.652] INFO: enter test to run
[12:12:23.352] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:12:23.352] INFO: running: highrate
[12:12:23.357] INFO: ----------------------------------------------------------------------
[12:12:23.357] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:12:23.357] INFO: ----------------------------------------------------------------------
[12:12:23.357] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:12:23.358] INFO: edge/corner pixel THR is adjusted
[12:12:23.358] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:12:24.315] INFO: Collecting data for 1 seconds...
[12:12:25.319] INFO: Done with hot pixel readout
[12:12:29.194] INFO: PixTest:: pg_setup set to default.
[12:12:29.195] INFO: 0 hot pixels found in step 0
[12:12:29.200] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:12:29.298] INFO: PixTest::trimHotPixels() done
[12:12:29.298] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C0.dat
[12:12:29.311] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C1.dat
[12:12:29.320] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C2.dat
[12:12:29.328] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C3.dat
[12:12:29.337] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C4.dat
[12:12:29.343] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C5.dat
[12:12:29.349] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C6.dat
[12:12:29.355] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C7.dat
[12:12:29.361] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C8.dat
[12:12:29.366] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C9.dat
[12:12:29.371] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C10.dat
[12:12:29.377] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C11.dat
[12:12:29.382] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C12.dat
[12:12:29.387] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C13.dat
[12:12:29.393] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C14.dat
[12:12:29.398] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//trimParameters35_C15.dat
[12:12:29.404] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//defaultMaskFile.dat
[12:12:29.413] INFO: enter test to run
[12:13:08.768] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:13:08.768] INFO: running: xray
[12:13:08.769] INFO: ----------------------------------------------------------------------
[12:13:08.769] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:13:08.769] INFO: ----------------------------------------------------------------------
[12:13:09.733] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:13:21.413] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:13:50.793] INFO: Resuming triggers.
[12:14:02.472] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:14:31.894] INFO: Resuming triggers.
[12:14:43.574] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:15:12.929] INFO: Resuming triggers.
[12:15:24.615] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[12:15:53.983] INFO: Resuming triggers.
[12:16:05.670] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[12:16:35.168] INFO: Resuming triggers.
[12:16:46.861] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[12:17:16.286] INFO: Resuming triggers.
[12:17:27.983] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[12:17:57.333] INFO: Resuming triggers.
[12:18:09.041] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[12:18:12.098] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (0) != Token Chain Length (8)
[12:18:12.099] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:12.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13b 8040 43c8 43c8 43c8 15c 2a49 44d 2480 648 262f 43c8 43c8 43c8 43c8 45b 2845 43c8 e000 c000
[12:18:12.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a136 8000 4388 322 204c 4388 119 2248 4388 4388 4388 c9 2a41 721 264c 4388 4388 4388 e000 c000
[12:18:12.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a137 8040 4388 4388 a0 2a47 4388 4388 111 244f 4388 4388 4388 4388 e000 c000
[12:18:12.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a138 80b1 4388 410 2465 4388 4388 11c 2641 4388 4388 4388 4388 4388 413 2868 41c 2a41 e000 c001
[12:18:12.099] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a1ff 80ff
[12:18:12.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a139 80c0 43c8 43c8 148 2a2d 43c8 43c8 43c8 d4 222d 43c8 43c8 43c8 e000 c000
[12:18:12.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13a 8000 43c8 43c8 43c8 43c8 555 2647 43c8 43c8 43c8 43c8 e000 c000
[12:18:14.371] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:14.371] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c7 8040 4388 4388 4388 4388 319 2a49 48b 2642 48b 2a29 4388 4388 4388 153 2a69 4388 e000 c000
[12:18:14.371] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c1 80c0 43c8 43c8 43c8 64b 2a6a 43c8 cc 2625 43c9 550 2a2d 43c9 43c9 43c9 e000 c000
[12:18:14.372] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c2 8000 43c8 43c8 43c9 50b 2a2b 43c9 43c8 689 242f 43c8 75b 264f 43c9 43c9 e000 c000
[12:18:14.372] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c3 8040 43c8 43c9 43c8 443 222c 43c9 15 2441 43c8 493 2269 43c9 43c8 74d 2264 43c9 e000 c000
[12:18:14.372] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c4 80b1 43c8 43c8 43c8 744 2a25 43c8 43c8 259 244a 280 242f 43c8 43c8 5388 e000 c000
[12:18:14.372] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c5 80c0 4388 211 222d 4388 4388 4388 4388 4388 4388 4388 e000 c000
[12:18:14.372] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c6 8000 4388 10a 2048 4388 1b 2645 114 2649 4388 4388 4388 4388 4388 150 2849 4388 8d 2044 e000 c000
[12:18:15.533] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (7) != Token Chain Length (8)
[12:18:15.534] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[12:18:15.534] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:15.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08d 80c0 4388 4388 4388 4388 4388 4388 41b 204d 4388 4388 e000 c000
[12:18:15.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a087 8040 43c8 43c8 43c8 43c8 21a 2245 43c8 43c8 43c8 43c8 c 2422 e000 c000
[12:18:15.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a088 80b1 43c8 49a 2444 43c8 43c8 50c 2021 43c8 43c8 719 2a40 43c8 43c8 48a 262d 43c8 e000 c000
[12:18:15.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a089 80c0 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 482 244d e000 c000
[12:18:15.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08a 8000 43c8 43c8 43c8 50c 2a21 43c8 21a 2244 43c8 43c8 543 2664 4388 54 2861 8f8 2049 229 e000 c000
[12:18:15.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08b 8040 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[12:18:15.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08c 80b1 4388 310 2442 4388 4388 489 224d 4388 4388 4388 4388 4388 e000 c000
[12:18:15.534] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:15.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a18d 80c0 4388 512 2845 4388 4388 4388 4388 555 244d 4388 4388 4388 e000 c000
[12:18:15.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a187 8040 43c8 554 2669 43c8 315 282f 454 226d 43c8 43c8 43c8 51 2829 43c8 43c8 43c8 e000 c000
[12:18:15.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a188 80b1 43c8 43c8 809 286c 43c8 43c8 280 2444 850 244f 43c8 41c 204d 43c8 43c8 43c8 e000 c000
[12:18:15.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a189 80c0 43c8 6c9 206f 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:18:15.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a18a 8000 43c8 43c8 43c8 43c8 43c8 418 282c 418 242d 61b 2860 4388 4388 4d2 2a67 87c e000 c000
[12:18:15.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a18b 8040 4388 4388 849 2a6d 4388 250 2a45 4388 4388 4388 4388 4388 e000 c000
[12:18:15.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a18c 80b1 4388 4388 4388 14c 224a 2e0 286d 4388 4388 4388 4388 4388 e000 c000
[12:18:17.817] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[12:18:17.818] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[12:18:17.818] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:17.818] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a019 80c0 4388 4388 4388 4388 4388 4388 4388 654 244d 4388 348 2245 e000 c000
[12:18:17.818] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a013 8040 43c8 43c9 43c8 43c9 43c8 43c9 43c8 43c9 68d 2044 6ca 2045 e000 c000
[12:18:17.818] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a014 80b1 43c8 43c8 43c8 43c8 348 204d 43c8 43c8 43c8 43c8 e000 c000
[12:18:17.818] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a015 80c0 43c8 43c8 43c8 43c8 43c8 43c8 603 282d 43c8 11 284d 43c8 e000 c000
[12:18:17.818] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a016 8000 43c8 251 2240 43c8 218 2a45 43c8 44a 222f 5388 12d1 224d 7ea 271a 943 4388 4388 2d1 2264 41c 2447 845 222f 4388 e000 c000
[12:18:17.818] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a017 8040 4388 4388 35c 2442 4388 649 264f 4388 742 286c 4388 4388 4388 4388 645 2842 e000 c000
[12:18:17.818] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a018 80b1 4388 4388 99 2046 4388 4388 4388 4388 4388 24d 226d 4388 2cd 2644 e000 c000
[12:18:17.818] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:17.818] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a119 80c0 4388 144 242f 4388 81c 2645 4388 661 2463 4388 4388 4388 88 2a2f 4388 4388 e000 c000
[12:18:17.818] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a113 8040 43c8 6db 2669 43c9 43c8 43c9 700 2844 43c8 43c9 295 222d 43c8 43c9 e000 c000
[12:18:17.818] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a114 80b1 43c8 43c8 43c8 643 2669 43c8 43c8 40b 2267 43c8 43c8 43c8 e000 c000
[12:18:17.818] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a115 80c0 43c8 34a 2442 43c8 43c8 43c8 43c8 342 2640 43c8 43c8 43c8 e000 c000
[12:18:17.818] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a116 8000 43c8 840 284c 43c8 43c8 355 264d 43c8 43c8 12cb 3647 4388 2d5 2cf5 426 215c e000 c000
[12:18:17.818] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a117 8040 4388 4388 4388 19 2045 258 284c 4388 4388 a 202d 64c 226d 4388 4388 4388 2e1 2a4f e000 c000
[12:18:17.818] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a118 80b1 4388 249 204c 682 2843 4388 4388 4388 4388 60a 244f 4388 15 2462 4388 4388 e000 c000
[12:18:18.952] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (5) != Token Chain Length (8)
[12:18:18.952] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:18.952] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1df 8040 43ca 80d 224d 43ca 43ca 43ca 60d 2065 43ca 43ca 43ca 43ca e000 c000
[12:18:18.952] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d9 80c0 4388 401 284f 4388 4388 48c 2041 4388 4388 4388 4388 4388 e000 c000
[12:18:18.952] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1da 8000 4388 4388 cc 2669 4388 4388 4388 4388 35c 2425 502 2447 4388 4388 e000 c000
[12:18:18.952] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1db 8040 4388 11a 2a45 2da 222e 71b 2269 4388 4388 805 2047 4388 4388 4388 4388 4388 e000 c000
[12:18:18.952] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1dc 80b1 4388 555 2249 4388 71c 242c 4388 812 202d 43c8 43c8 600 43c8 e000 c000
[12:18:18.952] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1dd 80c0 43c8 43c8 43c8 43c8 11a 2447 455 2a4e 43c8 11 2041 28a 2644 43c8 43c8 43c8 e000 c000
[12:18:18.952] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1de 8000 43c8 43c8 43c8 220 2026 60a 2442 43c8 43c8 64b 204d 43c8 43c8 43c8 e000 c000
[12:18:21.284] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[12:18:21.285] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 6 ROCs were found
[12:18:21.285] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:21.285] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06b 8040 4388 4388 49 2240 4388 4388 4388 345 2845 4388 4388 4388 21 202f e000 c000
[12:18:21.285] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a065 80c0 43c8 24d 284d 43c8 43c8 43c8 450 2a2c 4d0 2849 43c8 14 2824 620 2649 43c8 511 204c 43c8 43c8 e000 c000
[12:18:21.285] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a066 8000 43c8 1a 2a48 43c8 43c8 43c8 74d 2a48 43c8 43c8 43c8 43c8 e000 c000
[12:18:21.285] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a067 8040 43c8 411 2a28 493 2661 493 2a49 43c8 43c8 43c8 43c8 13 2a49 43c8 43c8 211 224d 43c8 642 242d e000 c000
[12:18:21.285] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a068 80b1 43c8 155 2440 43c8 43c8 4388 4388 4388 855 2515 5f8 4388 e000 c000
[12:18:21.285] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a069 80c0 4388 4388 4388 4388 4dc 2245 55b 264c 4388 4388 615 2642 4388 4388 e000 c000
[12:18:21.285] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06a 8000 4388 861 2a46 4388 4388 618 2a4e 4388 4388 40c 2827 4388 4388 4388 e000 c000
[12:18:21.285] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:21.285] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16b 8040 4388 101 2848 315 2040 51b 244b 4388 4388 4388 4388 21a 264d 4388 4388 4388 e000 c000
[12:18:21.285] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a165 80c0 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:18:21.285] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a166 8000 43c8 43c8 43c8 34a 2228 74a 264c 43c8 43c8 292 262f 43c8 43c8 43c8 e000 c000
[12:18:21.285] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a167 8040 43c8 120 2249 43c8 48b 226f 43c8 d 2445 8 2a2f 43c8 408 284f 43c8 43c8 43c8 43c8 e000 c000
[12:18:21.285] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a168 80b1 43c8 4e1 224e 43c8 43c8 4388 4388 4388 e084 c020
[12:18:21.285] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a169 80c0 4388 4388 149 2a43 4388 4388 4388 159 282f 4388 4388 4388 e000 c000
[12:18:21.285] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16a 8000 4388 28b 2048 44a 204d 4388 11 264f 4388 30b 286a 4388 4388 4388 498 282c 4388 4388 e000 c000
[12:18:22.429] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (3) != Token Chain Length (8)
[12:18:22.429] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (47) != TBM ID (50)
[12:18:22.429] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (3) != Token Chain Length (8)
[12:18:22.430] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 6 ROCs were found
[12:18:22.430] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (51) != TBM ID (47)
[12:18:22.430] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:22.430] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a131 80c0 4388 658 2428 4388 4388 4388 4389 459 284f 4389 4389 4389 e000 c000
[12:18:22.430] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12b 8040 43c8 43c8 651 2465 43c8 43c8 43c8 43c8 8d 2645 43c8 43c8 e000 c000
[12:18:22.430] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12c 80b1 43c8 720 244c 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:18:22.430] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12d 80c0 43c8 43c8 43c8 43c8 43c8 43c8 55 2245 43c8 43c8 e000 c000
[12:18:22.430] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a12e 8000 43c8 43c8 43c8 4388 5388 4388 492 25d0 896 2823 abe 2000 37 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 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 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
[12:18:22.430] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12f 8040 438a 649 222f 438a 438a 438a 438a 20a 2841 438a 438a 438a e000 c000
[12:18:22.430] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a130 80b1 4389 4389 222 202c 4389 2d2 2464 542 2629 4389 51 244e 4389 4389 4389 4389 e000 c000
[12:18:22.430] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:22.430] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a032 8000 4388 4388 4389 84b 2640 4389 4388 1a 2249 841 2a4d 4388 540 262a 690 262f 4389 4389 64d 2841 e000 c000
[12:18:22.430] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02d 80c0 43c8 43c8 43c8 41a 222d 43c8 121 2845 43c8 43c8 43c8 812 222d 43c8 e000 c000
[12:18:22.430] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a02e 8000 43c8 43c8 14 262c 1214 284f 4388 61f 2d03
[12:18:22.430] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a032 801c 4388 4388 4388 e000 c000
[12:18:22.430] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02f 8040 438a 2da 2042 438a 438a 438a 438a 438a 438a 438a 101 2241 e000 c000
[12:18:22.430] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a030 80b1 4388 4388 4388 4388 4388 284 202d 4388 4388 4388 e000 c000
[12:18:22.430] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a031 80c0 4388 4388 4388 4388 4389 409 202c 461 2a41 4389 4389 248 2445 6c9 2244 4389 4cc 242b e000 c000
[12:18:23.577] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[12:18:23.577] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[12:18:23.577] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:23.577] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f7 8040 43c8 75d 242f 43c8 43c8 43c8 43c8 43c8 43c8 4cb 2269 43c8 e000 c000
[12:18:23.577] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f1 80c0 4388 4388 4388 4388 4389 100 2a2b 4d3 2649 4389 4389 4389 e000 c000
[12:18:23.577] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f2 8000 4388 20 2646 4388 4389 4389 4388 695 2645 4388 219 2a61 761 244d 761 204f 4389 4389 e000 c000
[12:18:23.577] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f3 8040 4388 dd 2242 4389 4388 4389 4388 4389 d 2441 605 2a49 4388 4389 e000 c000
[12:18:23.577] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f4 80b1 4388 4388 43c8 43c8 43c8 43c9 595 2f03 e01f c0f8
[12:18:23.577] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f5 80c0 43c8 43c8 43c8 43c8 43c8 43c8 51 2242 43c8 43c8 e000 c000
[12:18:23.577] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f6 8000 43c8 43c8 43c8 43c8 15a 282d 43c8 43c8 14c 242c 555 2041 43c8 43c8 e000 c000
[12:18:23.577] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:23.577] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f7 8040 43c8 43c8 43c8 43c8 43c8 93 244f 43c8 43c8 43c8 e000 c000
[12:18:23.577] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f1 80c0 4388 615 284c 4388 4388 4388 4389 d2 2445 713 2464 4389 4389 4389 e000 c000
[12:18:23.577] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f2 8000 4388 508 2422 4388 4389 4389 11a 2a4a 48b 2267 4388 4388 4389 848 2a4c 4389 805 284f e000 c000
[12:18:23.578] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f3 8040 4388 495 222d 4389 203 2041 360 2a2f 821 246a 4388 4389 652 264f 4388 da 2a4b 4389 4388 4389 e000 c000
[12:18:23.578] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f4 80b1 4388 4388 2cb 2861 43c8 1d 2403 1fc 2238 43c8 43c8 43c8 43c8 e000 c000
[12:18:23.578] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f5 80c0 43c8 43c8 d3 268d 43c8 43c8 43c8 4c1 2049 43c8 55 2245 43c8 43c8 e000 c000
[12:18:23.578] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f6 8000 43c8 215 2446 43c8 43c8 43c8 1b 266d 4d3 228f 849 2263 43c8 43c8 43c8 43c8 e000 c000
[12:18:28.136] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (0) != Token Chain Length (8)
[12:18:28.136] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[12:18:28.136] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:28.136] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00f 8040 438a 438a 438a 438a 438a 438a 30d 2845 438a 719 2a46 438a 40a 2a4a e000 c000
[12:18:28.136] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a009 80c0 43c8 0 284d 43c8 43c8 348 222d 43c8 494 2427 43c8 5 242d 43c8 43c8 43c8 41b 264a e000 c000
[12:18:28.136] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00a 8000 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:18:28.136] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00b 8040 43c8 43c8 43c8 43c8 43c8 10 2029 43c8 43c8 43c8 e000 c000
[12:18:28.136] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00c 80b1 e01f c0e9
[12:18:28.136] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00d 80c0 4388 18 202d 4388 4388 4388 4388 4388 15b 2a2f 4388 49 224f 4388 e000 c000
[12:18:28.136] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00e 8000 4388 4388 4388 4388 6d3 2466 4388 294 282c 4388 4388 558 2645 4388 e000 c000
[12:18:28.136] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:28.136] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10f 8040 438a 35b 2249 438a 438a 613 204d 438a 300 2447 438a 438a 438a 438a e000 c000
[12:18:28.136] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a109 80c0 43c8 645 202f 43c8 41 2449 43c8 2d9 2a4f 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:18:28.136] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10a 8000 43c8 6c1 226a 43c8 43c8 43c8 43c8 58 2025 43c8 c3 2a49 43c8 43c8 e000 c000
[12:18:28.136] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10b 8040 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:18:28.136] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10c 80b1 438a aa8 2088 42f 29fc 10c 2516 4388 4388 4388 4388 4388 e000 c000
[12:18:28.136] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10d 80c0 4388 4388 4388 14d 284d 4388 40b 226d 4388 720 2441 4388 4388 4388 e000 c000
[12:18:28.136] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10e 8000 4388 4cc 2a40 4388 858 204f 4388 4388 4388 450 2045 4388 4388 4388 e000 c000
[12:18:38.484] INFO: Resuming triggers.
[12:18:45.297] INFO: data taking finished, elapsed time: 100 seconds.
[12:18:46.621] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 7 ROCs were found
[12:18:46.621] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:46.621] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13f 8040 43ca 544 222a 43ca 109 264d 43ca 43ca 43ca 43ca 43ca 43ca e000 c000
[12:18:46.621] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a139 80c0 4388 4388 11b 204f 85b 224d 4388 4388 4388 4d3 244d 4388 4388 4388 e000 c000
[12:18:46.621] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13a 8000 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[12:18:46.621] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13b 8040 4388 640 2664 4388 4388 4388 4388 645 2042 68a 2845 4388 4388 4388 98 2a49 e000 c000
[12:18:46.621] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a13c 80b1 4388 71c 2065 4388 4388 4388 9a 2049 43c8 43c8 53c8 7ff 2e00 2 207c 0 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 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 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[12:18:46.621] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13d 80c0 43c8 358 2822 43c8 20d 2a2f 43c8 361 2465 43c8 43c8 51a 2442 43c8 43c8 43c8 e000 c000
[12:18:46.621] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13e 8000 43c8 298 2024 43c8 359 2841 43c8 31a 2a61 752 2247 43c8 445 2064 43c8 43c8 43c8 43c8 e000 c000
[12:18:47.782] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[12:18:47.782] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:47.783] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a105 80c0 4388 4388 1a 244f 4c1 2a41 4388 4388 4388 749 262d 4388 4388 4388 e000 c000
[12:18:47.783] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ff 8040 43ca 43ca 43ca 481 284f 43ca 43ca 689 224a 43ca 43ca 71b 244c 43ca e000 c000
[12:18:47.783] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a100 80b1 43c9 43c9 261 204f 28d 2a65 43c9 43c9 43c9 43c9 43c9 43c9 e000 c000
[12:18:47.783] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a101 80c0 43c8 43c8 43c8 43c8 43c9 43c9 20a 2240 43c9 43c9 259 2a4b e000 c000
[12:18:47.783] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a102 8000 43c8 691 2040 43c8 29b 2266 4389 4389 4388 54b 20a9 15c 4389 4389 e000 c000
[12:18:47.783] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a103 8040 4388 4389 212 280f 4388 4389 2e0 200f 4388 35c 2641 4389 4388 71a 2249 4389 510 2245 e000 c000
[12:18:47.783] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a104 80b1 4388 4388 18 2447 4388 110 282d 4388 4388 4388 4d8 244d 4388 4388 e000 c000
[12:18:48.910] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (7) != Token Chain Length (8)
[12:18:48.910] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[12:18:48.910] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:48.910] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0cb 8040 43c8 62 2243 43c8 43c8 43c8 43c8 69d 204b 43c8 43c8 43c8 2c2 2029 e000 c000
[12:18:48.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c5 80c0 4388 4388 4388 4388 321 2a42 322 2262 4388 11b 244a 61c 2a49 4388 4388 4388 e000 c000
[12:18:48.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c6 8000 4388 4388 4388 4388 4388 4388 4388 6d5 2446 703 2661 4388 e000 c000
[12:18:48.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c7 8040 4388 54 2048 4388 4388 4388 4388 4388 4388 4388 e000 c000
[12:18:48.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c8 80b1 4388 4388 4388 4388 6c9 3a4d 43c8 818 2a4a 2a40 43c8 10c 2a2a 43c8 43c8 e000 c000
[12:18:48.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c9 80c0 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:18:48.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ca 8000 43c8 43c8 43c8 43c8 43c8 858 264a 43c8 43c8 541 2249 43c8 e000 c000
[12:18:48.911] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:48.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1cb 8040 43c8 30c 222a 43c8 43 262f 43c8 43c8 221 2067 60c 284c 43c8 80d 2a45 849 2849 43c8 43c8 43c8 e000 c000
[12:18:48.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c5 80c0 4388 4388 4388 214 240d 4388 4388 4388 4388 4388 e000 c000
[12:18:48.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c6 8000 4388 412 204d 854 244d 4388 4388 690 242f 4388 151 284f 4388 68c 2a4d 4388 4388 4388 e000 c000
[12:18:48.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c7 8040 4388 719 2a4f 4388 14 2825 4388 110 2a2f 4388 852 284f 4388 710 2849 4388 119 2845 4388 513 284d 4388 e000 c000
[12:18:48.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c8 80b1 4388 4388 613 2265 4388 128d 2641 43c8 43ca e42 2f60 43c8 43c8 e000 c000
[12:18:48.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c9 80c0 43c8 14c 222c 2c8 262f 43c8 4b 2249 159 2044 43c8 490 2645 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:18:48.911] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ca 8000 43c8 43c8 43c8 115 2041 311 2265 351 2066 819 2045 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:18:54.623] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[12:18:54.623] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[12:18:54.623] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:54.623] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a9 80c0 43c8 43c8 43c8 43c8 4d4 2a2f 560 224c 43c8 454 202b 43c8 d5 2661 43c8 43c8 e000 c000
[12:18:54.623] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a3 8040 4388 4389 4388 4389 4388 4389 4388 49 244f 4389 e000 c000
[12:18:54.623] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a4 80b1 4388 4388 4388 4388 4388 4388 4388 20a 2449 4388 e000 c000
[12:18:54.623] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a5 80c0 4388 4388 d4 2a49 4388 4388 258 224f 4388 4388 4388 448 2845 75c 2864 4388 e000 c000
[12:18:54.623] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== b0a6 8000 43c8 43c8 554 43c8 43c8 693 2080 43c8 44 242c 43c8 341 2441 43c8 451 244a e000 c000
[12:18:54.623] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a7 8040 43c8 43c8 43c8 43c8 43c8 482 204d 43c8 43c8 43c8 e000 c000
[12:18:54.623] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a8 80b1 43c8 43c8 43c8 43c8 43c8 43c8 83 2264 692 2645 43c8 43c8 e000 c000
[12:18:54.623] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:54.623] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a9 80c0 43c8 821 204f 43c8 43c8 4c0 2445 43c8 43c8 649 2a6d 43c8 43c8 43c8 e000 c000
[12:18:54.623] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a3 8040 4388 61c 2048 4389 4388 745 2064 4389 4388 4389 4388 4389 290 2840 e000 c000
[12:18:54.623] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a4 80b1 4388 4388 4388 308 204f 4388 1d 2062 4388 4388 4388 4388 e000 c000
[12:18:54.623] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a5 80c0 4388 4388 359 2849 4388 4388 4388 4388 102 242e 4388 4388 e000 c000
[12:18:54.623] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== b1a6 8000 43c8 43c8 2420 20 43c8 113 2642 43c8 43c8 43c8 e000 c000
[12:18:54.623] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a7 8040 43c8 520 202e 71a 2a44 43c8 69b 2063 43c8 43c8 43c8 611 204a 43c8 43c8 43c8 e000 c000
[12:18:54.624] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a8 80b1 43c8 2d3 204d 43c8 43c8 43c8 284 2069 43c8 43c8 43c8 43c8 e000 c000
[12:18:58.098] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (248) != TBM ID (249)
[12:18:58.098] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (248) != TBM ID (249)
[12:18:58.098] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:58.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fc 80b1 4388 4388 11 2449 4388 84d 2a42 4388 4388 93 2a4c 4388 4388 4388 e000 c000
[12:18:58.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f5 80c0 43c8 18 2442 43c8 43c8 43c8 43c8 43c8 43c8 65d 2069 43c8 e000 c000
[12:18:58.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f6 8000 43c8 4d8 222c 43c8 43c8 43c8 43c8 43c8 a 202f 84b 2242 43c8 43c8 4d 282d e000 c000
[12:18:58.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f7 8040 43c8 43c8 58 2a2b 69c 2649 43c8 43c8 43c8 43c8 100 2640 715 2a47 43c8 43c8 e000 c000
[12:18:58.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f9 80c0 4388 4388 5a 2a40 4388 4388 4388 4388 4388 4388 e000 c000
[12:18:58.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fa 8000 4388 4388 4388 4388 4388 4388 4388 8c 282f 4388 e000 c000
[12:18:58.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fb 8040 4388 4388 494 242f 4388 4ca 202d 4388 241 2649 4388 4388 9a 2a4a 712 2449 4388 4388 e000 c000
[12:18:58.098] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:58.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fc 80b1 4388 29b 2044 4388 54d 2645 4388 24a 2a69 4388 405 2463 4388 2d8 242d 41d 2a47 4388 4388 4388 e000 c000
[12:18:58.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f5 80c0 43c8 43c8 43c8 43c8 43c8 50 200d 319 2447 43c8 43c8 43c8 e000 c000
[12:18:58.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f6 8000 43c8 861 2064 43c8 6d0 222d 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:18:58.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f7 8040 43c8 43c8 2d8 2a29 43c8 43c8 51a 284f 43c8 43c8 109 2a47 43c8 43c8 e000 c000
[12:18:58.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f9 80c0 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[12:18:58.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fa 8000 4388 810 284c 4388 619 268f 4388 4388 320 2261 4388 64c 2441 649 222f 4388 4388 4388 e000 c000
[12:18:58.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fb 8040 4388 4b 2245 4388 4388 4388 4388 4c8 2a40 4388 49 202f 4388 4388 e000 c000
[12:18:58.098] WARNING: Channel 0 ROC 6: Readback start marker after 15 readouts!
[12:18:58.098] WARNING: Channel 0 ROC 7: Readback start marker after 15 readouts!
[12:18:58.098] WARNING: Channel 1 ROC 6: Readback start marker after 15 readouts!
[12:18:58.098] WARNING: Channel 1 ROC 7: Readback start marker after 15 readouts!
[12:18:58.098] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:58.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a002 8000 4388 4388 4389 4389 2c8 2a41 752 222f 4388 4388 4389 4389 e000 c000
[12:18:58.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fc 80b1 4388 4388 11 2449 4388 84d 2a42 4388 4388 93 2a4c 4388 4388 4388 e000 c000
[12:18:58.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fd 80c0 4388 4388 4388 4388 4388 4388 6d1 226a 4388 5d 204d 68c 2a2a 4388 e000 c000
[12:18:58.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fe 8000 4388 4388 4388 4388 545 2a64 4388 4388 4388 4388 e000 c000
[12:18:58.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ff 8040 438a 438a 438a 438a 438a 438a 438a 64a 2869 438a e000 c000
[12:18:58.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a000 80b1 4388 4388 4388 4388 24b 2a67 848 262a 4388 552 2a40 4388 255 2041 4388 4388 e000 c000
[12:18:58.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a001 80c0 4388 15b 284d 4388 4388 4388 15c 202f 4389 4389 4389 8c 202f 80b 2044 4389 e000 c000
[12:18:58.099] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:58.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a102 8000 4388 51b 2a4e 659 2a2f 4388 6e0 2640 851 244f 4389 4389 4388 4388 4389 2da 2842 4389 e000 c000
[12:18:58.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fc 80b1 4388 29b 2044 4388 54d 2645 4388 24a 2a69 4388 405 2463 4388 2d8 242d 41d 2a47 4388 4388 4388 e000 c000
[12:18:58.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fd 80c0 4388 4388 4388 d5 264d 4388 55 224d 4388 2c4 2849 4388 4388 4388 e000 c000
[12:18:58.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fe 8000 4388 4388 4388 9b 2662 600 262e 4388 4388 4388 4388 75b 2243 4388 e000 c000
[12:18:58.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ff 8040 438a 438a 438a 492 202d 438a 438a 438a 24c 200f 438a 438a e000 c000
[12:18:58.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a100 80b1 4389 4389 b 2049 2da 2a42 31c 280f 4389 11 284d 15 2a48 49d 2647 4389 4389 4389 4389 4389 e000 c000
[12:18:58.099] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a101 80c0 4388 4388 4388 4388 4389 6c4 2a49 713 224f 4389 4389 4a2 2849 4389 e000 c000
[12:18:59.235] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (190) != TBM ID (191)
[12:18:59.235] WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!
[12:18:59.235] WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!
[12:18:59.235] WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!
[12:18:59.235] WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!
[12:18:59.235] WARNING: Channel 0 ROC 4: Readback start marker after 15 readouts!
[12:18:59.235] WARNING: Channel 0 ROC 5: Readback start marker after 15 readouts!
[12:18:59.235] WARNING: Channel 0 ROC 6: Readback start marker after 15 readouts!
[12:18:59.235] WARNING: Channel 0 ROC 7: Readback start marker after 15 readouts!
[12:18:59.235] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (190) != TBM ID (191)
[12:18:59.235] WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!
[12:18:59.235] WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!
[12:18:59.235] WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!
[12:18:59.235] WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[12:18:59.235] WARNING: Channel 1 ROC 4: Readback start marker after 15 readouts!
[12:18:59.235] WARNING: Channel 1 ROC 5: Readback start marker after 15 readouts!
[12:18:59.235] WARNING: Channel 1 ROC 6: Readback start marker after 15 readouts!
[12:18:59.235] WARNING: Channel 1 ROC 7: Readback start marker after 15 readouts!
[12:18:59.235] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:59.235] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c2 8000 4388 4388 4389 4389 4388 4388 4389 4389 e000 c000
[12:18:59.235] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bb 8040 43c8 43c8 43c8 43c8 43c8 43c8 43c8 41a 224f 43c8 e000 c000
[12:18:59.235] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bc 80b1 43c8 43c8 43c8 43c8 43c8 43c8 43c8 495 224f 840 2828 43c8 e000 c000
[12:18:59.235] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bd 80c0 43c8 812 2649 43c8 43c8 43c8 455 2442 658 2a2a 43c8 34b 246f 43c8 43c8 43c8 e000 c000
[12:18:59.236] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bf 8040 438a 438a 438a 438a 80 2843 438a 514 244c 438a 438a 8c 2a44 438a e000 c000
[12:18:59.236] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c0 80b1 4388 294 2a40 653 2242 4388 258 202f 4388 4388 4388 4388 4388 4388 e000 c000
[12:18:59.236] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c1 80c0 4388 4388 4388 514 220f 704 2a40 4388 4389 4389 4389 4389 e000 c000
[12:18:59.236] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:18:59.236] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c2 8000 4388 655 284d 4388 4389 4389 4388 4c 240c 4388 24b 2644 4389 4389 e000 c000
[12:18:59.236] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bb 8040 43c8 43c8 43c8 610 2047 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:18:59.236] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bc 80b1 43c8 43c8 688 224a 719 2049 43c8 43c8 43c8 30a 2a4f 43c8 222 2049 43c8 43c8 14d 2a2c e000 c000
[12:18:59.236] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bd 80c0 43c8 420 2646 43c8 43c8 41a 264f 652 244d 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:18:59.236] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bf 8040 438a 315 202d 438a 438a 603 204f 438a 438a 438a 6e1 2445 438a 438a 418 2a41 e000 c000
[12:18:59.236] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c0 80b1 4389 4389 48 242d 240 2a65 241 222f 4389 4389 4389 4389 4389 4389 e000 c000
[12:18:59.236] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c1 80c0 4388 4388 4a1 2a6f 4388 4388 50 242d 4389 4389 4389 4389 e000 c000
[12:19:02.663] INFO: PixTest:: pg_setup set to default.
[12:19:02.666] INFO: PixTestXray::doPhRun() done
[12:19:02.836] INFO: enter test to run
[12:19:41.069] INFO: test: timing no parameter change
[12:19:41.069] INFO: running: timing
[12:19:41.073] INFO: ######################################################################
[12:19:41.085] INFO: PixTestTiming::doTest()
[12:19:41.085] INFO: ######################################################################
[12:19:41.085] INFO: ----------------------------------------------------------------------
[12:19:41.085] INFO: PixTestTiming::TBMPhaseScan()
[12:19:41.085] INFO: ----------------------------------------------------------------------
[12:24:47.211] INFO: TBM Phase Settings: 240
[12:24:47.211] INFO: 400MHz Phase: 4
[12:24:47.211] INFO: 160MHz Phase: 7
[12:24:47.211] INFO: Functional Phase Area: 5
[12:24:47.221] INFO: Test took 306136 ms.
[12:24:47.222] INFO: PixTestTiming::TBMPhaseScan() done.
[12:24:47.222] INFO: ----------------------------------------------------------------------
[12:24:47.222] INFO: PixTestTiming::ROCDelayScan()
[12:24:47.222] INFO: ----------------------------------------------------------------------
[12:27:42.005] INFO: ROC Delay Settings: 228
[12:27:42.005] INFO: ROC Header-Trailer/Token Delay: 11
[12:27:42.005] INFO: ROC Port 0 Delay: 4
[12:27:42.005] INFO: ROC Port 1 Delay: 4
[12:27:42.005] INFO: Functional ROC Area: 3
[12:27:42.008] INFO: Test took 174786 ms.
[12:27:42.008] INFO: PixTestTiming::ROCDelayScan() done.
[12:27:42.008] INFO: ----------------------------------------------------------------------
[12:27:42.008] INFO: PixTestTiming::TimingTest()
[12:27:42.008] INFO: ----------------------------------------------------------------------
[12:27:58.233] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:13.303] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:28.372] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:43.407] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:58.471] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:13.540] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:28.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:43.676] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:58.768] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:13.866] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:14.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:14.257] INFO: ----------------------------------------------------------------------
[12:30:14.258] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[12:30:14.258] INFO: ----------------------------------------------------------------------
[12:30:14.258] INFO: ----------------------------------------------------------------------
[12:30:14.258] INFO: Read back bit status: 1
[12:30:14.258] INFO: ----------------------------------------------------------------------
[12:30:14.258] INFO: ----------------------------------------------------------------------
[12:30:14.258] INFO: Timings are good!
[12:30:14.258] INFO: ----------------------------------------------------------------------
[12:30:14.258] INFO: Test took 152250 ms.
[12:30:14.258] INFO: PixTestTiming::TimingTest() done.
[12:30:14.267] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//tbmParameters_C0a.dat
[12:30:14.267] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-50_FPIXTest-17C-FNAL-160713-1229_2016-07-13_12h29m_1468430967/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:30:14.267] INFO: PixTestTiming::doTest took 633197 ms.
[12:30:14.267] INFO: PixTestTiming::doTest() done
[12:30:14.268] INFO: Write out TBMPhaseScan_0_V0
[12:30:14.268] INFO: Write out TBMPhaseScan_1_V0
[12:30:14.268] INFO: Write out CombinedTBMPhaseScan_V0
[12:30:14.283] INFO: Write out ROCDelayScan3_V0
[12:30:14.283] INFO: enter test to run
[12:31:01.481] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:31:01.481] INFO: running: xray
[12:31:01.482] INFO: ----------------------------------------------------------------------
[12:31:01.482] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:31:01.482] INFO: ----------------------------------------------------------------------
[12:31:02.446] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:31:14.164] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:31:43.661] INFO: Resuming triggers.
[12:31:55.367] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:32:24.896] INFO: Resuming triggers.
[12:32:36.596] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[12:33:06.425] INFO: Resuming triggers.
[12:33:18.123] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[12:33:47.612] INFO: Resuming triggers.
[12:33:59.302] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[12:34:28.413] INFO: Resuming triggers.
[12:34:40.101] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[12:35:10.096] INFO: Resuming triggers.
[12:35:21.781] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[12:35:51.787] INFO: Resuming triggers.
[12:36:03.480] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[12:36:33.284] INFO: Resuming triggers.
[12:36:40.064] INFO: data taking finished, elapsed time: 100 seconds.
[12:36:57.298] INFO: PixTest:: pg_setup set to default.
[12:36:57.302] INFO: PixTestXray::doPhRun() done
[12:36:57.435] INFO: enter test to run
[12:37:54.014] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:37:54.014] INFO: running: xray
[12:37:54.015] INFO: ----------------------------------------------------------------------
[12:37:54.015] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:37:54.015] INFO: ----------------------------------------------------------------------
[12:37:54.981] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:38:02.275] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[12:38:32.469] INFO: Resuming triggers.
[12:38:39.765] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[12:39:10.006] INFO: Resuming triggers.
[12:39:17.303] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[12:39:47.501] INFO: Resuming triggers.
[12:39:54.797] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[12:40:24.924] INFO: Resuming triggers.
[12:40:32.217] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[12:41:02.398] INFO: Resuming triggers.
[12:41:09.687] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[12:41:39.845] INFO: Resuming triggers.
[12:41:47.131] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[12:42:17.368] INFO: Resuming triggers.
[12:42:24.653] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[12:42:53.890] INFO: Resuming triggers.
[12:43:01.172] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[12:43:31.381] INFO: Resuming triggers.
[12:43:38.663] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[12:44:08.875] INFO: Resuming triggers.
[12:44:16.152] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[12:44:47.042] INFO: Resuming triggers.
[12:44:54.324] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[12:45:25.150] INFO: Resuming triggers.
[12:45:32.435] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[12:46:02.676] INFO: Resuming triggers.
[12:46:08.261] INFO: data taking finished, elapsed time: 100 seconds.
[12:46:31.814] INFO: PixTest:: pg_setup set to default.
[12:46:31.817] INFO: PixTestXray::doPhRun() done
[12:46:31.968] INFO: enter test to run
[12:48:12.442] INFO: test: HighRate no parameter change
[12:48:12.442] INFO: running: highrate
[12:48:12.461] INFO: ----------------------------------------------------------------------
[12:48:12.461] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:48:12.461] INFO: ----------------------------------------------------------------------
[12:48:12.616] INFO: Expecting 768 events.
[12:48:13.751] INFO: 768 events read in total (419ms).
[12:48:13.751] INFO: Test took 1269ms.
[12:48:14.553] INFO: Expecting 41600 events.
[12:48:17.679] INFO: 41600 events read in total (2599ms).
[12:48:17.680] INFO: Test took 3921ms.
[12:48:17.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:18.427] INFO: Expecting 41600 events.
[12:48:21.611] INFO: 41600 events read in total (2658ms).
[12:48:21.613] INFO: Test took 3881ms.
[12:48:21.647] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:22.372] INFO: Expecting 41600 events.
[12:48:25.595] INFO: 41600 events read in total (2696ms).
[12:48:25.596] INFO: Test took 3931ms.
[12:48:25.630] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:26.354] INFO: Expecting 41600 events.
[12:48:29.603] INFO: 41600 events read in total (2722ms).
[12:48:29.604] INFO: Test took 3955ms.
[12:48:29.639] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:30.358] INFO: Expecting 41600 events.
[12:48:33.612] INFO: 41600 events read in total (2727ms).
[12:48:33.613] INFO: Test took 3956ms.
[12:48:33.647] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:34.372] INFO: Expecting 41600 events.
[12:48:37.619] INFO: 41600 events read in total (2721ms).
[12:48:37.620] INFO: Test took 3956ms.
[12:48:37.654] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:38.377] INFO: Expecting 41600 events.
[12:48:41.614] INFO: 41600 events read in total (2711ms).
[12:48:41.615] INFO: Test took 3943ms.
[12:48:41.649] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:42.371] INFO: Expecting 41600 events.
[12:48:45.610] INFO: 41600 events read in total (2712ms).
[12:48:45.611] INFO: Test took 3943ms.
[12:48:45.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:46.368] INFO: Expecting 41600 events.
[12:48:49.607] INFO: 41600 events read in total (2712ms).
[12:48:49.608] INFO: Test took 3944ms.
[12:48:49.641] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:50.364] INFO: Expecting 41600 events.
[12:48:53.582] INFO: 41600 events read in total (2691ms).
[12:48:53.583] INFO: Test took 3923ms.
[12:48:53.616] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:54.343] INFO: Expecting 41600 events.
[12:48:57.586] INFO: 41600 events read in total (2716ms).
[12:48:57.587] INFO: Test took 3952ms.
[12:48:57.621] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:58.343] INFO: Expecting 41600 events.
[12:49:01.587] INFO: 41600 events read in total (2717ms).
[12:49:01.588] INFO: Test took 3949ms.
[12:49:01.621] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:02.349] INFO: Expecting 41600 events.
[12:49:05.589] INFO: 41600 events read in total (2713ms).
[12:49:05.590] INFO: Test took 3950ms.
[12:49:05.624] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:06.346] INFO: Expecting 41600 events.
[12:49:09.576] INFO: 41600 events read in total (2703ms).
[12:49:09.577] INFO: Test took 3935ms.
[12:49:09.611] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:10.335] INFO: Expecting 41600 events.
[12:49:13.582] INFO: 41600 events read in total (2720ms).
[12:49:13.583] INFO: Test took 3953ms.
[12:49:13.616] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:14.339] INFO: Expecting 41600 events.
[12:49:17.578] INFO: 41600 events read in total (2712ms).
[12:49:17.579] INFO: Test took 3944ms.
[12:49:17.613] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:18.336] INFO: Expecting 41600 events.
[12:49:21.565] INFO: 41600 events read in total (2702ms).
[12:49:21.566] INFO: Test took 3935ms.
[12:49:21.600] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:22.326] INFO: Expecting 41600 events.
[12:49:25.546] INFO: 41600 events read in total (2693ms).
[12:49:25.547] INFO: Test took 3928ms.
[12:49:25.581] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:26.305] INFO: Expecting 41600 events.
[12:49:29.531] INFO: 41600 events read in total (2699ms).
[12:49:29.532] INFO: Test took 3932ms.
[12:49:29.565] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:30.290] INFO: Expecting 41600 events.
[12:49:33.409] INFO: 41600 events read in total (2592ms).
[12:49:33.410] INFO: Test took 3827ms.
[12:49:33.443] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:33.806] INFO: enter test to run
[12:49:44.137] INFO: test: HighRate no parameter change
[12:49:44.137] INFO: running: highrate
[12:49:44.138] INFO: ----------------------------------------------------------------------
[12:49:44.138] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:49:44.138] INFO: ----------------------------------------------------------------------
[12:49:44.755] INFO: Expecting 208000 events.
[12:49:56.580] INFO: 208000 events read in total (11298ms).
[12:49:56.583] INFO: Test took 12434ms.
[12:49:56.726] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:56.980] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 61 185 103 62 51 7 0 0 0
[12:49:56.980] INFO: number of red-efficiency pixels: 69 55 61 115 120 110 117 257 598 360 301 181 126 61 29 28
[12:49:56.980] INFO: number of X-ray hits detected: 66733 41684 52406 81189 79832 87372 90959 69363 162886 127555 113941 85359 83382 50887 20783 25025
[12:49:56.980] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:49:56.980] INFO: number of Vcal hits detected: 207927 207944 207937 207879 207875 207888 207875 204357 198300 202536 204511 205320 207450 207939 207970 207971
[12:49:56.980] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0 100.0
[12:49:56.980] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 98.2 95.3 97.4 98.3 98.7 99.7 100.0 100.0 100.0
[12:49:56.980] INFO: X-ray hit rate [MHz/cm2]: 19.6 12.2 15.4 23.8 23.4 25.6 26.7 20.3 47.7 37.4 33.4 25.0 24.4 14.9 6.1 7.3
[12:49:56.980] INFO: PixTestHighRate::doXPixelAlive() done
[12:49:57.032] INFO: PixTest:: pg_setup set to default.
[12:49:57.043] INFO: enter test to run
[12:50:41.361] INFO: test: HighRate no parameter change
[12:50:41.361] INFO: running: highrate
[12:50:41.362] INFO: ----------------------------------------------------------------------
[12:50:41.362] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:50:41.362] INFO: ----------------------------------------------------------------------
[12:50:41.983] INFO: Expecting 208000 events.
[12:50:55.162] INFO: 208000 events read in total (12652ms).
[12:50:55.167] INFO: Test took 13792ms.
[12:50:55.436] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:55.728] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 61 185 104 62 51 7 0 0 0
[12:50:55.728] INFO: number of red-efficiency pixels: 178 129 149 320 315 316 298 438 771 704 593 413 342 213 38 55
[12:50:55.728] INFO: number of X-ray hits detected: 135231 84534 105925 163848 162102 176910 184515 136219 240059 225494 214354 171044 169201 102390 42381 51654
[12:50:55.728] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:50:55.728] INFO: number of Vcal hits detected: 207810 207870 207841 207667 207672 207667 207683 204121 198109 202139 204149 205068 207191 207770 207961 207945
[12:50:55.728] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.7 99.7 99.6 99.8 99.8 99.9 100.0 100.0
[12:50:55.728] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 98.1 95.2 97.2 98.1 98.6 99.6 99.9 100.0 100.0
[12:50:55.728] INFO: X-ray hit rate [MHz/cm2]: 39.6 24.8 31.0 48.0 47.5 51.9 54.1 39.9 70.4 66.1 62.8 50.1 49.6 30.0 12.4 15.1
[12:50:55.728] INFO: PixTestHighRate::doXPixelAlive() done
[12:50:55.778] INFO: PixTest:: pg_setup set to default.
[12:50:55.793] INFO: enter test to run
[12:51:26.744] INFO: test: HighRate no parameter change
[12:51:26.744] INFO: running: highrate
[12:51:26.745] INFO: ----------------------------------------------------------------------
[12:51:26.745] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:51:26.745] INFO: ----------------------------------------------------------------------
[12:51:27.366] INFO: Expecting 208000 events.
[12:51:42.188] INFO: 208000 events read in total (14295ms).
[12:51:42.195] INFO: Test took 15437ms.
[12:51:42.579] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:51:42.914] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 62 185 102 64 51 7 0 0 0
[12:51:42.914] INFO: number of red-efficiency pixels: 395 231 305 656 586 679 615 723 1064 1206 1000 771 714 409 78 82
[12:51:42.914] INFO: number of X-ray hits detected: 200333 126284 158650 245456 242911 262459 272263 201004 310677 319099 309652 254430 254165 152735 63219 76384
[12:51:42.914] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:51:42.914] INFO: number of Vcal hits detected: 207551 207746 207656 207254 207357 207222 207315 203707 197703 201440 203619 204593 206729 207510 207920 207916
[12:51:42.914] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.9 99.7 99.7 99.7 99.7 99.7 99.5 99.3 99.5 99.6 99.6 99.8 100.0 100.0
[12:51:42.914] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.6 99.7 99.6 99.7 97.9 95.0 96.8 97.9 98.4 99.4 99.8 100.0 100.0
[12:51:42.914] INFO: X-ray hit rate [MHz/cm2]: 58.7 37.0 46.5 71.9 71.2 76.9 79.8 58.9 91.1 93.5 90.8 74.6 74.5 44.8 18.5 22.4
[12:51:42.914] INFO: PixTestHighRate::doXPixelAlive() done
[12:51:42.965] INFO: PixTest:: pg_setup set to default.
[12:51:42.978] INFO: enter test to run
[12:52:22.448] INFO: test: exit no parameter change
[12:52:22.812] QUIET: Connection to board 33 closed.
[12:52:22.814] INFO: pXar: this is the end, my friend