[13:40:07.143] INFO: *** Welcome to pxar ***
[13:40:07.143] INFO: *** Today: 2016/04/26
[13:40:07.163] INFO: *** Version: v1.9.0-796-gef167-dirty
[13:40:07.163] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//dacParameters35_C15.dat
[13:40:07.205] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:40:07.205] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//defaultMaskFile.dat
[13:40:07.211] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C15.dat
[13:40:07.335] INFO: clk: 4
[13:40:07.335] INFO: ctr: 4
[13:40:07.335] INFO: sda: 19
[13:40:07.335] INFO: tin: 9
[13:40:07.335] INFO: level: 15
[13:40:07.336] INFO: triggerdelay: 0
[13:40:07.336] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[13:40:07.336] INFO: Log level: INFO
[13:40:07.354] QUIET: Connection to board DTB_WREKRL opened.
[13:40:07.358] 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:
------------------------------------------------------
[13:40:07.361] INFO: RPC call hashes of host and DTB match: 398089610
[13:40:08.888] INFO: DUT info:
[13:40:08.888] INFO: The DUT currently contains the following objects:
[13:40:08.888] INFO: 2 TBM Cores tbm08c (2 ON)
[13:40:08.888] INFO: TBM Core alpha (0): 7 registers set
[13:40:08.888] INFO: TBM Core beta (1): 7 registers set
[13:40:08.888] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:40:08.888] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:08.889] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:40:09.301] INFO: enter 'restricted' command line mode
[13:40:09.301] INFO: enter test to run
[13:40:17.174] INFO: test: PixelAlive no parameter change
[13:40:17.174] INFO: running: pixelalive
[13:40:17.184] INFO: ----------------------------------------------------------------------
[13:40:17.184] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:40:17.184] INFO: ----------------------------------------------------------------------
[13:40:17.504] INFO: Expecting 41600 events.
[13:40:21.835] INFO: 41600 events read in total (3612ms).
[13:40:22.004] INFO: Test took 4818ms.
[13:40:22.015] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:22.275] INFO: PixTestAlive::aliveTest() done
[13:40:22.276] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 18 0 0 1 0 0
[13:40:22.311] INFO: enter test to run
[13:40:54.574] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:40:54.574] INFO: running: highrate
[13:40:54.574] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:40:54.827] INFO: ----------------------------------------------------------------------
[13:40:54.827] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:40:54.827] INFO: ----------------------------------------------------------------------
[13:40:54.827] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:40:54.827] INFO: edge/corner pixel THR is adjusted
[13:40:54.827] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:40:55.787] INFO: Collecting data for 5 seconds...
[13:41:00.804] INFO: Done with hot pixel readout
[13:41:12.486] INFO: PixTest:: pg_setup set to default.
[13:41:12.487] INFO: 17 hot pixels found in step 0
[13:41:13.474] INFO: Collecting data for 5 seconds...
[13:41:18.490] INFO: Done with hot pixel readout
[13:41:30.147] INFO: PixTest:: pg_setup set to default.
[13:41:30.148] INFO: 16 hot pixels found in step 1
[13:41:31.138] INFO: Collecting data for 5 seconds...
[13:41:36.155] INFO: Done with hot pixel readout
[13:41:47.893] INFO: PixTest:: pg_setup set to default.
[13:41:47.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:41:47.894] INFO: 13 hot pixels found in step 2
[13:41:48.884] INFO: Collecting data for 5 seconds...
[13:41:53.900] INFO: Done with hot pixel readout
[13:42:05.587] INFO: PixTest:: pg_setup set to default.
[13:42:05.587] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:05.588] INFO: 16 hot pixels found in step 3
[13:42:06.579] INFO: Collecting data for 5 seconds...
[13:42:11.596] INFO: Done with hot pixel readout
[13:42:23.281] INFO: PixTest:: pg_setup set to default.
[13:42:23.281] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:23.281] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:23.282] INFO: 13 hot pixels found in step 4
[13:42:24.271] INFO: Collecting data for 5 seconds...
[13:42:29.288] INFO: Done with hot pixel readout
[13:42:41.005] INFO: PixTest:: pg_setup set to default.
[13:42:41.005] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:41.005] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:41.005] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:41.005] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:41.006] INFO: 11 hot pixels found in step 5
[13:42:41.997] INFO: Collecting data for 5 seconds...
[13:42:47.013] INFO: Done with hot pixel readout
[13:42:58.677] INFO: PixTest:: pg_setup set to default.
[13:42:58.677] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:58.677] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:58.677] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:58.677] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:58.677] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:58.677] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:58.678] INFO: 11 hot pixels found in step 6
[13:42:59.667] INFO: Collecting data for 5 seconds...
[13:43:04.684] INFO: Done with hot pixel readout
[13:43:16.379] INFO: PixTest:: pg_setup set to default.
[13:43:16.379] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:16.379] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:16.379] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:16.379] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:16.379] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:16.379] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:16.380] INFO: 7 hot pixels found in step 7
[13:43:17.370] INFO: Collecting data for 5 seconds...
[13:43:22.386] INFO: Done with hot pixel readout
[13:43:34.033] INFO: PixTest:: pg_setup set to default.
[13:43:34.033] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:34.033] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:34.033] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:34.034] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:34.034] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:34.034] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:34.034] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:34.034] INFO: 9 hot pixels found in step 8
[13:43:35.025] INFO: Collecting data for 5 seconds...
[13:43:40.041] INFO: Done with hot pixel readout
[13:43:51.711] INFO: PixTest:: pg_setup set to default.
[13:43:51.711] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:51.711] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:51.711] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:51.711] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:51.711] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:51.711] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:51.711] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:43:51.712] INFO: 8 hot pixels found in step 9
[13:43:52.703] INFO: Collecting data for 5 seconds...
[13:43:57.719] INFO: Done with hot pixel readout
[13:44:06.509] INFO: PixTest:: pg_setup set to default.
[13:44:06.509] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:44:06.509] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:44:06.509] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:44:06.510] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:44:06.510] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:44:06.510] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:44:06.510] INFO: 7 hot pixels found in step 10
[13:44:07.500] INFO: Collecting data for 5 seconds...
[13:44:12.510] INFO: Done with hot pixel readout
[13:44:23.532] INFO: PixTest:: pg_setup set to default.
[13:44:23.532] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:44:23.532] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:44:23.532] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:44:23.532] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:44:23.532] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:44:23.532] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:44:23.532] INFO: 6 hot pixels found in step 11
[13:44:23.566] INFO: 6 hot pixels could not be trimmed and have been masked.
[13:44:23.570] INFO: PixTest::trimHotPixels() done
[13:44:23.570] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C0.dat
[13:44:23.575] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C1.dat
[13:44:23.582] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C2.dat
[13:44:23.588] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C3.dat
[13:44:23.593] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C4.dat
[13:44:23.598] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C5.dat
[13:44:23.603] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C6.dat
[13:44:23.609] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C7.dat
[13:44:23.614] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C8.dat
[13:44:23.619] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C9.dat
[13:44:23.624] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C10.dat
[13:44:23.630] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C11.dat
[13:44:23.635] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C12.dat
[13:44:23.640] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C13.dat
[13:44:23.645] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C14.dat
[13:44:23.650] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C15.dat
[13:44:23.656] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//defaultMaskFile.dat
[13:44:23.666] INFO: enter test to run
[13:44:55.076] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:44:55.076] INFO: running: highrate
[13:44:55.081] INFO: ----------------------------------------------------------------------
[13:44:55.081] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:44:55.081] INFO: ----------------------------------------------------------------------
[13:44:55.081] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:44:55.081] INFO: edge/corner pixel THR is adjusted
[13:44:55.081] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:44:56.039] INFO: Collecting data for 1 seconds...
[13:44:57.042] INFO: Done with hot pixel readout
[13:45:00.952] INFO: PixTest:: pg_setup set to default.
[13:45:00.953] INFO: 0 hot pixels found in step 0
[13:45:00.959] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:45:01.055] INFO: PixTest::trimHotPixels() done
[13:45:01.055] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C0.dat
[13:45:01.064] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C1.dat
[13:45:01.069] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C2.dat
[13:45:01.074] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C3.dat
[13:45:01.080] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C4.dat
[13:45:01.085] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C5.dat
[13:45:01.090] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C6.dat
[13:45:01.095] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C7.dat
[13:45:01.101] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C8.dat
[13:45:01.106] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C9.dat
[13:45:01.111] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C10.dat
[13:45:01.116] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C11.dat
[13:45:01.121] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C12.dat
[13:45:01.127] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C13.dat
[13:45:01.132] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C14.dat
[13:45:01.137] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C15.dat
[13:45:01.142] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//defaultMaskFile.dat
[13:45:01.152] INFO: enter test to run
[13:46:08.780] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:46:08.780] INFO: running: xray
[13:46:08.781] INFO: ----------------------------------------------------------------------
[13:46:08.781] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:46:08.781] INFO: ----------------------------------------------------------------------
[13:46:09.745] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:46:21.519] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:46:50.859] INFO: Resuming triggers.
[13:47:02.632] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:47:31.906] INFO: Resuming triggers.
[13:47:43.681] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[13:48:12.810] INFO: Resuming triggers.
[13:48:24.586] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[13:48:53.841] INFO: Resuming triggers.
[13:49:05.616] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[13:49:34.934] INFO: Resuming triggers.
[13:49:46.707] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[13:49:56.269] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (174) != TBM ID (175)
[13:49:56.269] WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!
[13:49:56.269] WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!
[13:49:56.269] WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!
[13:49:56.269] WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!
[13:49:56.269] WARNING: Channel 0 ROC 4: Readback start marker after 15 readouts!
[13:49:56.269] WARNING: Channel 0 ROC 5: Readback start marker after 15 readouts!
[13:49:56.269] WARNING: Channel 0 ROC 6: Readback start marker after 15 readouts!
[13:49:56.269] WARNING: Channel 0 ROC 7: Readback start marker after 15 readouts!
[13:49:56.269] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (174) != TBM ID (175)
[13:49:56.269] WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!
[13:49:56.269] WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!
[13:49:56.269] WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!
[13:49:56.269] WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[13:49:56.269] WARNING: Channel 1 ROC 4: Readback start marker after 15 readouts!
[13:49:56.269] WARNING: Channel 1 ROC 5: Readback start marker after 15 readouts!
[13:49:56.269] WARNING: Channel 1 ROC 6: Readback start marker after 15 readouts!
[13:49:56.269] WARNING: Channel 1 ROC 7: Readback start marker after 15 readouts!
[13:49:56.269] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:49:56.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b2 8000 4388 4388 4389 4389 4388 458 284a 450 2662 522 2062 505 2644 4388 4389 11d 2644 4389 e000 c000
[13:49:56.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ab 8040 43c8 43c8 52 2640 43c8 43c8 43c8 43c8 43c8 4c0 2a42 43c8 e000 c000
[13:49:56.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ac 80b1 43c8 43c8 43c8 43c8 4d5 2643 43c8 750 2a4f 43c8 43c8 43c8 e000 c000
[13:49:56.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ad 80c0 43c8 43c8 43c8 43c8 43c8 43c8 604 2628 43c8 89 2a48 43c8 4c 2a48 10a 2846 e000 c000
[13:49:56.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0af 8040 438a 20 2446 2d3 266e 438a 438a 438a 438a 693 2062 438a 759 2063 438a 438a e000 c000
[13:49:56.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b0 80b1 4388 4388 4388 4388 4388 4388 4c0 2429 4388 653 222e 4388 e000 c000
[13:49:56.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b1 80c0 4388 4388 4388 718 284f 4388 4389 4389 519 2666 4389 4389 e000 c000
[13:49:56.270] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:49:56.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b2 8000 4388 493 264a 4388 4389 4389 4388 4388 4389 4389 e000 c000
[13:49:56.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ab 8040 43c8 43c8 43c8 43c8 43c8 43c8 2e0 2449 43c8 43c8 e000 c000
[13:49:56.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ac 80b1 43c8 43c8 43c8 120 2042 4dc 2a46 43c8 43c8 43c8 21a 2042 43c8 43c8 e000 c000
[13:49:56.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ad 80c0 43c8 43c8 43c8 43c8 29d 2241 43c8 43c8 43c8 43c8 e000 c000
[13:49:56.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1af 8040 438a 438a 4c3 2846 438a 438a 258 222a 438a 518 2243 64c 2448 75d 2448 438a 438a 438a e000 c000
[13:49:56.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b0 80b1 4389 10 242a 4389 49a 2442 50b 264f 4389 4389 4389 4389 4389 4389 e000 c000
[13:49:56.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b1 80c0 4388 4388 31c 224f 4388 4388 559 266a 4389 4389 4389 522 284f 4389 e000 c000
[13:50:16.079] INFO: Resuming triggers.
[13:50:27.853] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:50:28.235] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:50:28.235] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:28.235] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a085 80c0 4388 110 242e 75a 242c 4388 18 2844 4388 4388 85b 266f 4388 4388 4388 1b 2a48 ca 206a 4388 e000 c000
[13:50:28.235] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07f 8040 43ca 43ca 43ca 43ca 1c 2246 43ca 43ca 43ca 150 2046 43ca e000 c000
[13:50:28.235] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a080 80b1 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:50:28.235] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a081 80c0 43c8 43c8 43c8 43c8 43c9 43c9 15a 2a65 43c9 43c9 21d 222c 652 2046 e000 c000
[13:50:28.235] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a082 8000 43c8 61 2044 43c8 294 2446 43c9 43c9 43c8 80c 2428 4388 6d1 2a4f c09 4389 e000 c000
[13:50:28.235] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a083 8040 4388 4389 19 2a4f 4388 41b 2067 4389 4388 4389 4388 4389 e000 c000
[13:50:28.235] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a084 80b1 4388 4388 245 2a48 4388 65b 244d 4388 4388 4388 4388 4388 e000 c000
[13:50:30.493] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:50:30.493] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 6 ROCs were found
[13:50:30.493] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (256) != TBM ID (15)
[13:50:30.493] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:30.493] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a011 80c0 43c8 9b 2866 43c8 31a 284a 43c8 510 2866 43c8 301 2a68 705 2640 43c9 43c9 43c9 9 2228 43c9 e000 c000
[13:50:30.493] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00b 8040 4388 4388 4388 4388 4388 4388 4388 51 2642 cb 2a85 4388 e000 c000
[13:50:30.493] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00c 80b1 4388 4388 4388 4388 4388 4388 4388 64c 202b 4388 e000 c000
[13:50:30.493] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00d 80c0 4388 4388 4388 4388 4388 30c 262e 4388 50b 204f 4388 742 2a40 4388 e000 c000
[13:50:30.494] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00e 8000 4388 4388 4388 4388 4388 2cc 2242 43c8 c 2a24 51c 2fff ecf 233f 38 2248 2e 43c8 e000 c000
[13:50:30.494] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00f 8040 43ca 43ca 43ca 43ca 20c 2046 43ca 43ca 43ca 43ca e000 c000
[13:50:30.494] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a010 80b1 43c8 819 284e 43c8 43c8 43c8 458 2024 43c8 19 282f 43c8 554 2266 43c8 10 2622 43c8 450 2866 e000 c000
[13:50:30.494] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:30.494] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a112 8000 43c8 43c8 d0 2a24 43c9 210 204e 43c9 43c8 43c8 43c9 43c9 e000 c000
[13:50:30.494] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10d 80c0 4388 4388 4388 48c 2044 4388 4388 4388 4388 51a 2a43 4388 e000 c000
[13:50:30.494] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a10e 8000 4388 4388 4388 4388 4388 9a 2041 43c8 43c8 43c8
[13:50:30.494] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a1ff 80bc 208 2815 7ff 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
[13:50:30.494] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10f 8040 43ca 43ca 43ca 43ca 43ca 43ca 43ca 43ca e000 c000
[13:50:30.494] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a110 80b1 43c9 43c9 15b 244c 43c9 753 264f 43c9 68a 204a 43c9 43c9 43c9 43c9 e000 c000
[13:50:30.494] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a111 80c0 43c8 43c8 43c8 43c8 43c9 360 2a2e 4c2 2842 43c9 43c9 43c9 e000 c000
[13:50:31.638] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (7) != Token Chain Length (8)
[13:50:31.638] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[13:50:31.638] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:31.638] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d7 8040 43c8 1b 2442 14 2240 43c8 93 204f 43c8 61c 2a4a 43c8 43c8 43c8 43c8 20d 2444 349 284a 43c8 e000 c000
[13:50:31.638] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d1 80c0 4388 419 226c 4388 4388 4388 4389 4389 4389 4389 e000 c000
[13:50:31.638] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d2 8000 4388 4388 4389 4389 85 262f 4388 6c9 242e 4388 4389 4389 28a 264a e000 c000
[13:50:31.638] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d3 8040 4388 4389 4388 4389 4388 4389 4388 4389 e000 c000
[13:50:31.638] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d4 80b1 4388 4388 4388 4388 4388 4388 cb 2446 43c8 7ff e000 c000
[13:50:31.638] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d5 80c0 43c8 31b 204a 43c8 43c8 859 2240 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:50:31.638] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d6 8000 43c8 43c8 43c8 1d 2a4a 43c8 43c8 492 2848 43c8 1b 2649 143 2668 29a 2a46 43c8 618 2040 43c8 e000 c000
[13:50:31.638] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:31.638] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d7 8040 43c8 90 2a2c 43c8 43c8 4cd 204a 43c8 749 2240 43c8 2d9 284b 43c8 43c8 43c8 e000 c000
[13:50:31.638] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d1 80c0 4388 485 264a 4388 95 2840 4388 4388 35c 2a68 4389 4389 4389 4389 e000 c000
[13:50:31.638] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d2 8000 4388 4388 755 282a 4389 4389 4388 4388 4389 4389 e000 c000
[13:50:31.638] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d3 8040 4388 95 2a4c 4389 4388 61a 2a48 4389 214 284e 4388 310 202e 4389 4388 4389 e000 c000
[13:50:31.639] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d4 80b1 4388 4388 4388 720 2248 43c8 43c8 43c8 43c8 e001 c0c0
[13:50:31.639] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d5 80c0 43c8 43c8 43c8 2cd 2666 43c8 298 282a 43c8 43c8 43c8 43c8 e000 c000
[13:50:31.639] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d6 8000 43c8 43c8 43c8 2d9 224c 43c8 43c8 1d 204c 41d 204a 43c8 43c8 43c8 e000 c000
[13:50:36.194] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (4) != Token Chain Length (8)
[13:50:36.194] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:36.195] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ef 8040 438a a0 2a4f 438a 438a 438a 65c 2048 438a 438a 5b 2a40 658 2642 438a 4b 2040 438a 44c 2a2f e000 c000
[13:50:36.195] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e9 80c0 43c8 43c8 520 224a 43c8 4cc 2648 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:50:36.195] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ea 8000 43c8 43c8 43c8 43c8 55 282e 43c8 43c8 43c8 43c8 e000 c000
[13:50:36.195] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0eb 8040 43c8 43c8 43c8 43c8 550 202c 43c8 43c8 2da 2248 43c8 10 282e 43c8 e000 c000
[13:50:36.195] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ec 80b1 4388 4388 1211 244a 601 2c15 5f8 4388 648 244a 4388 4388 e000 c000
[13:50:36.195] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ed 80c0 4388 4388 49 202e 4388 4388 309 244c 413 2046 4388 4388 4388 4388 e000 c000
[13:50:36.195] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ee 8000 4388 15 2448 15 202f 4388 4388 41a 2440 805 2a66 4388 20 2640 84a 264c 4388 1c 202e 4388 51a 2a6e 4388 4388 e000 c000
[13:50:36.195] WARNING: Channel 1 ROC 4: Readback start marker after 3 readouts!
[13:50:36.195] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:36.195] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f2 8000 4388 4388 70c 282c 4389 4389 4388 4388 252 204a 4389 858 262e 4389 e000 c000
[13:50:36.195] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ec 80b1 4388 4388 5388 4388 438a 24d5 854 4388 4388 4388 e000 c000
[13:50:36.195] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ed 80c0 4388 4388 4388 4388 4388 4388 4388 31c 2844 4388 301 2a6a e000 c000
[13:50:36.195] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ee 8000 4388 4388 483 226a 61d 2446 4388 145 2a43 4388 4388 4388 4388 4388 e000 c000
[13:50:36.195] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ef 8040 438a 438a 2c9 2840 438a 438a 438a 438a 438a 438a e000 c000
[13:50:36.195] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f0 80b1 4389 25b 282e 4389 4389 4389 4389 4389 4389 4389 e000 c000
[13:50:36.195] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f1 80c0 4388 4388 2db 2648 4388 111 2462 4388 4389 4389 49 262e 89 244e 4389 4389 e000 c000
[13:50:38.463] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (4) != Token Chain Length (8)
[13:50:38.466] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (3) != Token Chain Length (8)
[13:50:38.466] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:38.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07b 8040 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:50:38.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a075 80c0 4388 dc 262e 4388 4388 4388 4388 4388 4388 4388 e000 c000
[13:50:38.467] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a076 8000 4388 4388 98 202f 4388 4388 4388 34b 2242 4388 4388 2d5 2a4f 4388 e000 c000
[13:50:38.467] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a077 8040 4388 25d 222a 4388 4388 4388 4388 4388 4388 4388 25d 204f e000 c000
[13:50:38.467] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== b078 90b1 43c8 604 2f1a eaf 2c00 532 2aa5 297 43c8 43c8 70b 2681 43c8 43c8 e000 c000
[13:50:38.467] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a079 80c0 43c8 55a 264a 43c8 43c8 43c8 210 282e 210 2448 43c8 43c8 43c8 2d5 2460 84a 2a4a 43c8 e000 c000
[13:50:38.467] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07a 8000 43c8 43c8 65b 2881 43c8 80d 2a46 43c8 43c8 113 284a 43c8 2da 2846 43c8 43c8 e000 c000
[13:50:38.467] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:38.467] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17b 8040 43c8 43c8 89 2640 43c8 43c8 43c8 43c8 43c8 549 2a48 43c8 e000 c000
[13:50:38.467] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a175 80c0 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[13:50:38.467] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a176 8000 4388 4388 4388 4388 5b 2a2a 4388 4388 4388 4388 e000 c000
[13:50:38.467] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a177 8040 4388 48a 244b 4388 4388 4388 4388 4388 4388 4388 248 2648 e000 c000
[13:50:38.467] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== b178 90b1 43c8 7f7 20e0 9 22ab a81 240e 2 2010 eaa 43c8 43c8 43c8 e000 c000
[13:50:38.467] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a179 80c0 43c8 43c8 43c8 1b 224f 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:50:38.467] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17a 8000 43c8 a2 2444 43c8 48c 2a48 43c8 2db 2a63 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:50:40.777] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (4) != TBM ID (31)
[13:50:40.777] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (32) != TBM ID (5)
[13:50:40.777] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (4) != TBM ID (22)
[13:50:40.777] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[13:50:40.777] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (23) != TBM ID (5)
[13:50:40.777] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:40.777] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a008 80b1 43c8 43c8 43c8 43c8 4d4 2448 519 286e 43c8 68b 204f 43c8 92 2268 215 2862 43c8 43c8 e000 c000
[13:50:40.777] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a002 8000 4388 1a 264a 4388 4389 653 2a40 4389 4388 2dc 2a42 4388 4389 b 2a40 6da 2a68 4389 419 246a e000 c000
[13:50:40.777] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a003 8040 4388 4389 4388 4389 4388 4389 4388 4389 e000 c000
[13:50:40.777] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01f 80ff 43c8 43c8 43c8 43c8 43c8 43c8 114 286f 43c8 4b 2060 111 244e 43c8 e000 c000
[13:50:40.777] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a005 80c0 43c8 93 2280 43c8 43c8 31a 264a 43c8 289 284c 43c8 43c8 43c8 43c8 115 2240 6ca 2a62 e000 c000
[13:50:40.777] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a006 8000 43c8 43c8 2d4 2a40 43c8 43c8 43c8 43c8 43c8 43c8 9 2a4f e000 c000
[13:50:40.777] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a007 8040 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 4cc 264a e000 c000
[13:50:40.777] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:40.777] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a108 80b1 43c8 694 222e 6d3 284f 43c8 43c8 30a 2644 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:50:40.777] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a102 8000 4388 261 2642 4388 dd 2242 4389 4389 a0 262e 4388 4388 4389 4389 154 284f e000 c000
[13:50:40.777] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a103 8040 4388 719 262f 4389 c 286f 4388 4389 4388 4389 4388 4389 e000 c000
[13:50:40.777] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a116 80ac 43c8 43c8 43c8 619 2a4f 43c8 4d0 2a40 860 2a44 43c8 43c8 43c8 e000 c000
[13:50:40.777] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a105 80c0 43c8 43c8 43c8 43c8 9b 2641 43c8 500 242e 43c8 43c8 43c8 e000 c000
[13:50:40.777] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a106 8000 43c8 20a 2248 43c8 43c8 43c8 43c8 692 2440 43c8 43c8 43c8 e000 c000
[13:50:40.777] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a107 8040 43c8 43c8 65c 2062 43c8 61c 264c 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:50:48.476] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:48.476] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a016 8000 43c8 5d 284a 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:50:48.476] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a010 80b1 4388 58 2644 4388 29d 2686 4388 4388 8d 2043 4388 4388 490 2a6c 4388 250 2042 4388 e000 c000
[13:50:48.476] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a011 80c0 4388 4388 453 2242 4388 681 2844 4388 4389 54c 2428 551 262f 4389 460 222c 4389 4389 e000 c000
[13:50:48.476] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a012 8000 4388 75a 202e 4388 118 2840 2e0 2a46 4389 4389 4388 4388 4389 4389 54a 2662 e000 c000
[13:50:48.476] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a013 8040 4388 4389 99 2248 4388 411 2a43 4389 853 206a 4388 4389 4388 53c9 e000 c000
[13:50:48.476] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a014 80b1 43c8 43c8 54 282a 54 242f 43c8 43c8 43c8 43c8 85c 2229 43c8 694 2a66 43c8 e000 c000
[13:50:48.476] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a015 80c0 43c8 658 264a 43c8 43c8 43c8 208 2046 43c8 1d 222e 43c8 43c8 43c8 21d 222a e000 c000
[13:50:48.476] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:48.477] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a116 8000 43c8 14b 222c 43c8 43c8 43c8 43c8 54b 2442 43c8 255 224f 43c8 43c8 e000 c000
[13:50:48.477] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a110 80b1 4389 815 262e 4389 4389 322 2640 4389 4389 4389 4389 4389 e000 c000
[13:50:48.477] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a111 80c0 4388 4388 4388 4388 4389 4389 4389 4389 e000 c000
[13:50:48.477] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a112 8000 4388 4388 4389 4389 4388 4388 4389 4389 e000 c000
[13:50:48.477] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a113 8040 4388 4389 28a 224a 4388 4389 4388 4389 4388 341 262f 4389 e000 d000
[13:50:48.477] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a114 80b1 43c8 43c8 55 2028 34d 224f 512 2062 809 244e 43c8 43c8 43c8 93 2a69 43c8 43c8 43c8 e000 c000
[13:50:48.477] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a115 80c0 43c8 43c8 43c8 513 264f 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:50:50.746] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 7 ROCs were found
[13:50:50.747] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 8 ROCs were found
[13:50:50.747] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:50.747] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a2 8000 43c8 61 284a 43c8 258 268a 43c9 43c9 288 2a4c 552 202f 43c8 43c8 30c 244a 43c9 40c 2648 43c9 262 266f e000 c000
[13:50:50.747] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09c 80b1 4388 4388 4388 4388 4388 14c 2a4e 4388 4388 4388 11 284f e000 c000
[13:50:50.747] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09d 80c0 4388 4388 722 244a 4388 4388 4388 148 2843 4388 212 2049 85d 2a45 4388 401 2242 613 2064 4388 e000 c000
[13:50:50.747] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09e 8000 4388 4388 4388 552 2248 4388 4388 4388 4388 51b 2466 4388 e000 c000
[13:50:50.747] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a09f 8040 438a 438a 438a 438a 2da 2862 350 2a4a 438a 409 262f 43ca 43ca 848 43ca abc 2ab8 0 23ff 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
[13:50:50.747] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a0 80b1 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 613 2a4e e000 c000
[13:50:50.747] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a1 80c0 43c8 43c8 43c8 43c8 43c9 43c9 43c9 649 262a 43c9 e000 c000
[13:50:50.747] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:50:50.747] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a2 8000 43c8 43c8 43c9 855 2042 43c9 43c8 820 2846 43c8 2c9 2442 43c9 43c9 e000 c000
[13:50:50.747] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19c 80b1 4388 4388 4388 4388 4388 518 2a46 4388 4388 4388 e000 c000
[13:50:50.747] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19d 80c0 4388 4388 4388 81a 2a4b 4388 6d5 2840 4388 4388 4388 4388 21c 2a45 e000 c000
[13:50:50.747] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19e 8000 4388 4388 60a 242e 4388 848 222e 4388 118 2647 4388 4388 4388 4388 e000 c000
[13:50:50.747] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a19f 8040 438a 438a 438a 215 2086 4ca 2060 438a 438a 438a 43ca 43ca 1a 2266 603 2c0a 1f 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
[13:50:50.747] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a0 80b1 43c9 2c3 284a 43c9 43c9 645 2a4e 43c9 43c9 43c9 43c9 43c9 e000 c000
[13:50:50.747] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a1 80c0 43c8 43c8 313 2662 41b 224e 688 2246 43c8 43c8 43c9 43c9 43c9 43c9 e000 c000
[13:50:57.334] INFO: Resuming triggers.
[13:51:09.106] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[13:51:09.158] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:51:09.158] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d2 8000 43c8 43c8 43c9 514 2462 43c9 b 244f 43c8 43c8 43c9 442 2a68 43c9 e000 c000
[13:51:09.158] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0cc 80b1 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[13:51:09.158] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0cd 80c0 4388 4388 4388 4388 4388 4388 4388 4388 70d 284f e000 c000
[13:51:09.158] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ce 8000 4388 499 2a2e 4388 4388 4388 11a 2242 4388 4388 4388 688 2440 4388 35c 2a46 e000 c000
[13:51:09.158] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0cf 8040 438a 29b 2444 438a 438a 438a 438a 608 2642 438a 438a 31b 224f 34d 284a 43ca 1061 2866 10c 203f fc3 2ffd e000 c000
[13:51:09.158] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d0 80b1 43c8 151 202e 43c8 43c8 43c8 43c8 103 224f 43c8 43c8 43c8 e000 c000
[13:51:09.158] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d1 80c0 43c8 495 2642 43c8 d1 2a2e 43c8 43c8 43c9 43c9 691 2048 43c9 43c9 2c0 242a e000 c000
[13:51:09.158] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:51:09.158] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d2 8000 43c8 114 222a 43c8 754 262e 43c9 43c9 a 2028 43c8 6d1 2061 43c8 43c9 43c9 e000 c000
[13:51:09.158] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1cc 80b1 4388 4388 4388 4388 54 2028 4388 4388 4388 4388 e000 c000
[13:51:09.158] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1cd 80c0 4388 4388 4388 4388 4388 4388 4388 6dc 2a44 4388 e000 c000
[13:51:09.158] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ce 8000 4388 4388 4388 4388 4388 15c 2440 4388 8b 248a 4388 4388 64a 246f e000 c000
[13:51:09.158] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1cf 8040 438a 855 264a 438a 18 244f 438a dc 2226 438a 438a 438a 438a 438a 55b 2a6a e000 d000
[13:51:09.158] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d0 80b1 43c9 43c9 43c9 58 2a2e 43c9 61 222f 43c9 43c9 43c9 43c9 e000 c000
[13:51:09.159] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d1 80c0 43c8 43c8 43c8 502 2a2f 43c8 43c9 253 2046 43c9 43c9 43c9 e000 c000
[13:51:13.711] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:51:13.711] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:51:13.711] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ea 8000 4388 58 2a44 4388 4388 4388 4388 4388 4388 4388 d5 2240 518 2046 e000 c000
[13:51:13.711] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e4 80b1 43c8 29d 2240 43c8 50 2868 43c8 562 204a 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:51:13.711] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e5 80c0 43c8 43c8 5d 2646 9b 2a4f 43c8 43c8 449 2466 43c8 43c8 43c8 43c8 e000 c000
[13:51:13.711] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e6 8000 43c8 43c8 43c8 64a 2643 43c8 409 242a 43c8 43c8 43c8 43c8 19 244f 15a 2a4e e000 c000
[13:51:13.711] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e7 8040 43c8 21 2046 43c8 600 2446 43c8 43c8 519 204e 70b 2a66 438b 838 4388 255 2662 703 244e 703 2044 4388 e000 c000
[13:51:13.711] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e8 80b1 4388 4388 4388 4388 4388 4388 4388 111 222a 4388 51b 244f e000 c000
[13:51:13.711] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e9 80c0 4388 61c 2042 4388 4388 4388 4388 60d 2a46 4388 4388 12 2242 4388 209 2042 60c 2a4a e000 c000
[13:51:16.006] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 8 ROCs were found
[13:51:16.006] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:51:16.006] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a076 8000 4388 4388 13 204a 4388 4c9 2a48 812 2448 4388 4388 4388 4388 4388 e000 c000
[13:51:16.006] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a070 80b1 43c8 43c8 43c8 43c8 449 202c 43c8 43c8 43c8 35c 2a40 43c8 658 2240 e000 c000
[13:51:16.006] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a071 80c0 43c8 43c8 19 2842 43c8 43c8 43c9 31a 2642 43c9 43c9 43c9 e000 c000
[13:51:16.006] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a072 8000 43c8 43c8 43c9 43c9 43c8 4c3 2a2a 43c8 43c9 43c9 e000 c000
[13:51:16.006] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a073 8040 43c8 43c9 43c8 43c9 43c8 510 2648 4389 4388 4389 eae 2000 ff 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
[13:51:16.007] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a074 80b1 4388 553 2062 4388 4388 74c 2a43 852 2441 4388 4388 4388 84b 246a 4388 513 224a 4388 e000 c000
[13:51:16.007] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a075 80c0 4388 14 204a 4388 4388 492 286e 753 2840 4388 4388 4388 4388 4388 e000 c000
[13:51:18.264] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:51:18.264] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[13:51:18.264] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:51:18.264] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a002 8000 4388 4388 4389 41c 2240 4389 4388 10 242a 84c 222e 4388 4389 d9 2a4b 4389 e000 c000
[13:51:18.264] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fc 80b1 43c8 43c8 43c8 6cc 2a62 43c8 43c8 43c8 b 2840 cc 2440 75a 2248 43c8 5d 282e 2d3 284f 43c8 e000 c000
[13:51:18.264] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fd 80c0 43c8 43c8 43c8 349 284a 43c8 80c 2a26 43c8 31c 202c 43c8 43c8 43c8 64c 2847 e000 c000
[13:51:18.264] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fe 8000 43c8 43c8 2d8 224a 43c8 43c8 43c8 43c8 34d 244f 43c8 800 262a 43c8 e000 c000
[13:51:18.264] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ff 8040 43ca 43ca 43ca 43ca 438a 438a 751 2843 c1f 25fa e000 c000
[13:51:18.264] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a000 80b1 4388 85a 2a48 4388 4388 4388 50b 206e 4388 48 2a46 34b 2846 4388 8c 224e 4388 4388 e000 c000
[13:51:18.264] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a001 80c0 4388 4388 4388 4388 4389 34c 202e 4389 4389 762 2042 752 2240 4389 e000 c000
[13:51:18.264] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:51:18.264] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a102 8000 4388 4388 215 2448 292 2042 70d 244a 4389 201 2a6f 4389 4388 4388 4389 4389 e000 c000
[13:51:18.265] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fc 80b1 43c8 43c8 43c8 24c 2440 4d5 2a6a 43c8 43c8 15d 2643 43c8 43c8 43c8 e000 c000
[13:51:18.265] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fd 80c0 43c8 43c8 43c8 43c8 690 2442 43c8 154 2026 43c8 43c8 43c8 e000 c000
[13:51:18.265] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fe 8000 43c8 842 282e 842 2445 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:51:18.265] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ff 8040 43ca 542 2244 43ca 438a 438a 438a 86a 28d0 55 438a 438a e000 c000
[13:51:18.265] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a100 80b1 4389 251 2044 809 242c 4389 4389 8d 264e 4389 4389 4389 4389 4389 e000 c000
[13:51:18.265] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a101 80c0 4388 4388 520 2246 4388 4388 691 2448 4389 4c9 2846 4389 4389 4389 e000 c000
[13:51:25.100] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:51:25.100] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[13:51:25.100] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:51:25.100] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a6 8000 43c8 43c8 61b 2a60 43c8 43c8 43c8 43c8 2 2242 45b 264f 43c8 35a 224f 43c8 4b 284e e000 c000
[13:51:25.100] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a0 80b1 4388 4388 459 2662 4388 4388 4388 4388 4388 89 2244 71d 284e 4388 e000 c000
[13:51:25.100] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a1 80c0 4388 6da 2242 4388 4388 60c 2642 684 2042 4388 113 2468 4389 213 244e 512 2683 4389 349 204e 4389 854 2026 4389 e000 c000
[13:51:25.100] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a2 8000 4388 115 2028 4388 4389 4389 659 228e 4388 4388 4389 9c 244e 81d 2042 4389 114 202a 458 264c e000 c000
[13:51:25.100] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a3 805c ff8 27f9 43c8 84b 2244 43c9 43c8 452 2a4e 511 2462 43c9 65c 2063 43c8 43c9 708 2846 e000 c000
[13:51:25.100] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a4 80b1 43c8 43c8 43c8 43c8 43c8 13 204c 0 2648 43c8 43c8 43c8 e000 c000
[13:51:25.100] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a5 80c0 43c8 d1 2640 43c8 43c8 601 2a4f 43c8 43c8 43c8 43c8 d0 284a 844 2242 43c8 418 2a4f e000 c000
[13:51:25.100] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:51:25.100] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a6 8000 43c8 43c8 408 2a2e 43c8 43c8 43c8 43c8 43c8 43c8 620 2a63 e000 c000
[13:51:25.101] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a0 80b1 4389 4d9 2440 4389 4389 4389 c 2a4a 34d 264f 4389 d5 2a42 4389 4389 4389 e000 c000
[13:51:25.101] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a1 80c0 4388 4388 4388 4388 4389 4389 15a 2a4e 421 286e 4389 4389 9a 2228 e000 c000
[13:51:25.101] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a2 8000 4388 4388 4389 692 2042 4389 4388 11c 2863 4388 4389 4389 e000 c000
[13:51:25.101] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a3 8058 70 2125 2120 38 43c8 43c9 454 2440 43c8 43c9 43c8 43c9 e000 c000
[13:51:25.101] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a4 80b1 43c8 43c8 355 2a40 43c8 34b 222e 43c8 853 264c 43c8 43c8 43c8 760 2849 43c8 e000 c000
[13:51:25.101] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a5 80c0 43c8 43c8 651 2648 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:51:26.233] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (105) != TBM ID (106)
[13:51:26.233] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (105) != TBM ID (106)
[13:51:26.233] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:51:26.233] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06d 80c0 4388 4388 4388 68d 2643 4388 6d3 244f 4388 4388 4388 4388 e000 c000
[13:51:26.233] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a066 8000 43c8 43c8 43c8 513 244f 43c8 43c8 553 2648 43c8 c3 224f 43c8 613 284c 43c8 e000 c000
[13:51:26.233] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a067 8040 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 2c0 282e e000 c000
[13:51:26.233] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a068 80b1 43c8 61 224c 43c8 43c8 43c8 99 2042 98 2840 43c8 2c1 2042 43c8 14c 262e 55d 2a48 43c8 43c8 e000 c000
[13:51:26.233] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06a 8000 4388 493 2642 4388 4388 4388 4388 98 2643 4388 14d 284f 4388 4388 e000 c000
[13:51:26.233] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06b 8040 4388 4388 319 2466 4388 4388 4388 462 2868 4388 b 2848 2 2441 85a 264a 4388 80a 2a40 4388 615 2448 e000 c000
[13:51:26.233] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06c 80b1 4388 2cd 244f 655 2860 4388 4388 4388 4388 419 2026 40c 242a 4388 4388 6a2 2063 4388 e000 c000
[13:51:26.233] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:51:26.233] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16d 80c0 4388 2cc 2246 4388 4388 4388 4388 649 264b 4388 4388 4388 49a 2a41 e000 c000
[13:51:26.233] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a166 8000 43c8 43c8 43c8 43c8 43c8 43c8 30d 2246 43c8 43c8 e000 c000
[13:51:26.233] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a167 8040 43c8 6e1 2448 43c8 43c8 43c8 290 2a26 43c8 43c8 43c8 43c8 e000 c000
[13:51:26.233] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a168 80b1 43c8 43c8 162 2a46 43c8 54b 2a4f 43c8 43c8 704 244e 43c8 208 2640 210 2641 43c8 43c8 e000 c000
[13:51:26.233] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16a 8000 4388 45a 2644 4388 689 2643 809 284d 4388 4388 4388 845 2240 4388 4388 4388 e000 c000
[13:51:26.233] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16b 8040 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[13:51:26.233] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16c 80b1 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[13:51:26.233] WARNING: Channel 0 ROC 6: Readback start marker after 15 readouts!
[13:51:26.233] WARNING: Channel 0 ROC 7: Readback start marker after 15 readouts!
[13:51:26.233] WARNING: Channel 1 ROC 6: Readback start marker after 15 readouts!
[13:51:26.233] WARNING: Channel 1 ROC 7: Readback start marker after 15 readouts!
[13:51:26.233] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:51:26.234] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a072 8000 4388 49 264a 4388 4389 4389 359 2a4a 4388 4388 40b 2080 459 2048 4389 4389 e000 c000
[13:51:26.234] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06c 80b1 4388 2cd 244f 655 2860 4388 4388 4388 4388 419 2026 40c 242a 4388 4388 6a2 2063 4388 e000 c000
[13:51:26.234] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06d 80c0 4388 4388 4388 68d 2643 4388 6d3 244f 4388 4388 4388 4388 e000 c000
[13:51:26.234] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06e 8000 4388 561 286a 4388 4388 4388 4388 4388 24a 2840 751 244c 4388 1d 2440 754 222e 4388 54 224e d4 242a cb 284e e000 c000
[13:51:26.234] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06f 8040 438a 85d 2a42 438a 438a 438a b 2443 2d9 2046 351 2846 438a 438a 438a 449 244b 6d9 2a4a 438a e000 c000
[13:51:26.234] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a070 80b1 4388 4388 4388 4388 111 2648 68c 2042 4388 4388 34d 244d 4388 700 2442 4388 e000 c000
[13:51:26.234] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a071 80c0 4388 4388 4388 4388 314 242a 4389 289 204c 4389 4389 2cb 244f 4389 e000 c000
[13:51:26.234] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:51:26.234] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a172 8000 4388 14b 264a 4388 4389 4d2 224f 4389 50c 222e 4388 4388 4389 4389 e000 c000
[13:51:26.234] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16c 80b1 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[13:51:26.234] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16d 80c0 4388 2cc 2246 4388 4388 4388 4388 649 264b 4388 4388 4388 49a 2a41 e000 c000
[13:51:26.234] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16e 8000 4388 683 2248 6c3 2042 4388 811 244c 4388 4388 4388 4388 4388 4388 e000 c000
[13:51:26.234] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16f 8040 438a 438a 438a 4cd 224a 438a 438a 438a 438a 438a e000 c000
[13:51:26.234] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a170 80b1 4389 4389 4389 4389 4389 4389 4389 4389 e000 c000
[13:51:26.234] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a171 80c0 4388 4388 4388 4388 4389 4389 4389 4389 44a 2a62 660 204f e000 c000
[13:51:38.425] INFO: Resuming triggers.
[13:51:44.558] INFO: data taking finished, elapsed time: 100 seconds.
[13:52:00.053] INFO: PixTest:: pg_setup set to default.
[13:52:00.056] INFO: PixTestXray::doPhRun() done
[13:52:00.217] INFO: enter test to run
[13:52:23.681] INFO: test: timing no parameter change
[13:52:23.681] INFO: running: timing
[13:52:23.684] INFO: ######################################################################
[13:52:23.684] INFO: PixTestTiming::doTest()
[13:52:23.685] INFO: ######################################################################
[13:52:23.685] INFO: ----------------------------------------------------------------------
[13:52:23.685] INFO: PixTestTiming::TBMPhaseScan()
[13:52:23.685] INFO: ----------------------------------------------------------------------
[13:59:02.951] INFO: TBM Phase Settings: 232
[13:59:02.951] INFO: 400MHz Phase: 2
[13:59:02.951] INFO: 160MHz Phase: 7
[13:59:02.951] INFO: Functional Phase Area: 3
[13:59:02.955] INFO: Test took 399270 ms.
[13:59:02.955] INFO: PixTestTiming::TBMPhaseScan() done.
[13:59:02.955] INFO: ----------------------------------------------------------------------
[13:59:02.955] INFO: PixTestTiming::ROCDelayScan()
[13:59:02.955] INFO: ----------------------------------------------------------------------
[14:02:39.698] INFO: ROC Delay Settings: 228
[14:02:39.698] INFO: ROC Header-Trailer/Token Delay: 11
[14:02:39.699] INFO: ROC Port 0 Delay: 4
[14:02:39.699] INFO: ROC Port 1 Delay: 4
[14:02:39.699] INFO: Functional ROC Area: 4
[14:02:39.702] INFO: Test took 216747 ms.
[14:02:39.702] INFO: PixTestTiming::ROCDelayScan() done.
[14:02:39.702] INFO: ----------------------------------------------------------------------
[14:02:39.702] INFO: PixTestTiming::TimingTest()
[14:02:39.703] INFO: ----------------------------------------------------------------------
[14:02:55.818] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:10.776] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:25.764] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:40.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:55.697] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:10.655] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:24.682] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:39.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:54.620] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:09.613] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:09.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:10.006] INFO: ----------------------------------------------------------------------
[14:05:10.006] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[14:05:10.006] INFO: ----------------------------------------------------------------------
[14:05:10.006] INFO: ----------------------------------------------------------------------
[14:05:10.006] INFO: Read back bit status: 1
[14:05:10.006] INFO: ----------------------------------------------------------------------
[14:05:10.006] INFO: ----------------------------------------------------------------------
[14:05:10.006] INFO: Timings are good!
[14:05:10.006] INFO: ----------------------------------------------------------------------
[14:05:10.007] INFO: Test took 150305 ms.
[14:05:10.007] INFO: PixTestTiming::TimingTest() done.
[14:05:10.017] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//tbmParameters_C0a.dat
[14:05:10.017] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-32_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:05:10.017] INFO: PixTestTiming::doTest took 766336 ms.
[14:05:10.017] INFO: PixTestTiming::doTest() done
[14:05:10.017] INFO: Write out TBMPhaseScan_0_V0
[14:05:10.017] INFO: Write out TBMPhaseScan_1_V0
[14:05:10.017] INFO: Write out CombinedTBMPhaseScan_V0
[14:05:10.029] INFO: Write out ROCDelayScan3_V0
[14:05:10.030] INFO: enter test to run
[14:06:10.939] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:06:10.939] INFO: running: xray
[14:06:10.940] INFO: ----------------------------------------------------------------------
[14:06:10.940] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:06:10.940] INFO: ----------------------------------------------------------------------
[14:06:11.905] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:06:23.682] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:06:53.022] INFO: Resuming triggers.
[14:07:04.800] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:07:34.060] INFO: Resuming triggers.
[14:07:45.837] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[14:08:15.126] INFO: Resuming triggers.
[14:08:26.900] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:08:56.138] INFO: Resuming triggers.
[14:09:07.909] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[14:09:37.161] INFO: Resuming triggers.
[14:09:48.935] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[14:10:18.147] INFO: Resuming triggers.
[14:10:29.918] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[14:10:59.061] INFO: Resuming triggers.
[14:11:10.833] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[14:11:40.139] INFO: Resuming triggers.
[14:11:46.271] INFO: data taking finished, elapsed time: 100 seconds.
[14:12:01.808] INFO: PixTest:: pg_setup set to default.
[14:12:01.811] INFO: PixTestXray::doPhRun() done
[14:12:01.965] INFO: enter test to run
[14:12:30.920] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:12:30.920] INFO: running: xray
[14:12:30.921] INFO: ----------------------------------------------------------------------
[14:12:30.921] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:12:30.921] INFO: ----------------------------------------------------------------------
[14:12:31.887] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:12:38.890] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:13:08.928] INFO: Resuming triggers.
[14:13:15.931] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[14:13:45.961] INFO: Resuming triggers.
[14:13:52.961] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[14:14:23.084] INFO: Resuming triggers.
[14:14:30.087] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[14:14:58.272] INFO: Resuming triggers.
[14:15:05.278] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:15:35.306] INFO: Resuming triggers.
[14:15:42.312] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[14:16:12.367] INFO: Resuming triggers.
[14:16:19.371] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[14:16:49.378] INFO: Resuming triggers.
[14:16:56.383] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[14:17:26.473] INFO: Resuming triggers.
[14:17:33.474] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[14:18:03.551] INFO: Resuming triggers.
[14:18:10.552] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:18:42.153] INFO: Resuming triggers.
[14:18:49.154] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[14:19:19.541] INFO: Resuming triggers.
[14:19:26.541] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[14:19:56.376] INFO: Resuming triggers.
[14:20:03.374] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[14:20:32.879] INFO: Resuming triggers.
[14:20:39.875] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[14:21:09.790] INFO: Resuming triggers.
[14:21:12.079] INFO: data taking finished, elapsed time: 100 seconds.
[14:21:22.174] INFO: PixTest:: pg_setup set to default.
[14:21:22.177] INFO: PixTestXray::doPhRun() done
[14:21:22.325] INFO: enter test to run
[14:21:58.908] INFO: test: HighRate no parameter change
[14:21:58.908] INFO: running: highrate
[14:21:58.933] INFO: ----------------------------------------------------------------------
[14:21:58.933] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:21:58.933] INFO: ----------------------------------------------------------------------
[14:21:59.089] INFO: Expecting 768 events.
[14:22:00.223] INFO: 768 events read in total (419ms).
[14:22:00.223] INFO: Test took 1269ms.
[14:22:01.026] INFO: Expecting 41600 events.
[14:22:04.052] INFO: 41600 events read in total (2499ms).
[14:22:04.053] INFO: Test took 3823ms.
[14:22:04.083] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:04.816] INFO: Expecting 41600 events.
[14:22:07.978] INFO: 41600 events read in total (2635ms).
[14:22:07.979] INFO: Test took 3881ms.
[14:22:08.009] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:08.737] INFO: Expecting 41600 events.
[14:22:11.942] INFO: 41600 events read in total (2678ms).
[14:22:11.943] INFO: Test took 3916ms.
[14:22:11.974] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:12.705] INFO: Expecting 41600 events.
[14:22:15.924] INFO: 41600 events read in total (2692ms).
[14:22:15.925] INFO: Test took 3935ms.
[14:22:15.957] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:16.683] INFO: Expecting 41600 events.
[14:22:19.896] INFO: 41600 events read in total (2686ms).
[14:22:19.897] INFO: Test took 3923ms.
[14:22:19.928] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:20.656] INFO: Expecting 41600 events.
[14:22:23.871] INFO: 41600 events read in total (2689ms).
[14:22:23.872] INFO: Test took 3928ms.
[14:22:23.904] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:24.635] INFO: Expecting 41600 events.
[14:22:27.853] INFO: 41600 events read in total (2691ms).
[14:22:27.854] INFO: Test took 3934ms.
[14:22:27.885] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:28.614] INFO: Expecting 41600 events.
[14:22:31.837] INFO: 41600 events read in total (2696ms).
[14:22:31.838] INFO: Test took 3936ms.
[14:22:31.870] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:32.599] INFO: Expecting 41600 events.
[14:22:35.835] INFO: 41600 events read in total (2710ms).
[14:22:35.836] INFO: Test took 3949ms.
[14:22:35.867] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:36.597] INFO: Expecting 41600 events.
[14:22:39.828] INFO: 41600 events read in total (2704ms).
[14:22:39.829] INFO: Test took 3945ms.
[14:22:39.859] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:40.588] INFO: Expecting 41600 events.
[14:22:43.801] INFO: 41600 events read in total (2686ms).
[14:22:43.802] INFO: Test took 3925ms.
[14:22:43.833] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:44.562] INFO: Expecting 41600 events.
[14:22:47.785] INFO: 41600 events read in total (2696ms).
[14:22:47.785] INFO: Test took 3934ms.
[14:22:47.816] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:48.545] INFO: Expecting 41600 events.
[14:22:51.779] INFO: 41600 events read in total (2707ms).
[14:22:51.779] INFO: Test took 3947ms.
[14:22:51.810] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:52.535] INFO: Expecting 41600 events.
[14:22:55.764] INFO: 41600 events read in total (2702ms).
[14:22:55.765] INFO: Test took 3937ms.
[14:22:55.797] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:56.525] INFO: Expecting 41600 events.
[14:22:59.748] INFO: 41600 events read in total (2697ms).
[14:22:59.749] INFO: Test took 3935ms.
[14:22:59.780] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:00.507] INFO: Expecting 41600 events.
[14:23:03.721] INFO: 41600 events read in total (2687ms).
[14:23:03.722] INFO: Test took 3924ms.
[14:23:03.752] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:04.482] INFO: Expecting 41600 events.
[14:23:07.692] INFO: 41600 events read in total (2683ms).
[14:23:07.693] INFO: Test took 3923ms.
[14:23:07.724] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:08.452] INFO: Expecting 41600 events.
[14:23:11.691] INFO: 41600 events read in total (2712ms).
[14:23:11.692] INFO: Test took 3949ms.
[14:23:11.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:12.455] INFO: Expecting 41600 events.
[14:23:15.677] INFO: 41600 events read in total (2695ms).
[14:23:15.678] INFO: Test took 3937ms.
[14:23:15.708] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:16.441] INFO: Expecting 41600 events.
[14:23:19.536] INFO: 41600 events read in total (2568ms).
[14:23:19.537] INFO: Test took 3811ms.
[14:23:19.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:19.935] INFO: enter test to run
[14:23:28.947] INFO: test: HighRate no parameter change
[14:23:28.947] INFO: running: highrate
[14:23:28.949] INFO: ----------------------------------------------------------------------
[14:23:28.949] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:23:28.949] INFO: ----------------------------------------------------------------------
[14:23:29.558] INFO: Expecting 208000 events.
[14:23:41.247] INFO: 208000 events read in total (11162ms).
[14:23:41.250] INFO: Test took 12295ms.
[14:23:41.381] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:41.630] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 17 0 0 1 0 0
[14:23:41.630] INFO: number of red-efficiency pixels: 62 48 78 125 113 97 113 89 78 92 100 62 70 73 19 33
[14:23:41.630] INFO: number of X-ray hits detected: 62441 43557 61404 101219 103551 110496 104907 76558 63066 80995 77808 69332 71676 45130 21334 22639
[14:23:41.630] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:23:41.630] INFO: number of Vcal hits detected: 207937 207951 207920 207871 207887 207901 207886 207909 207920 207907 207019 207936 207929 207877 207981 207967
[14:23:41.630] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[14:23:41.630] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 100.0 99.9 100.0 100.0 100.0 99.5 100.0 100.0 99.9 100.0 100.0
[14:23:41.630] INFO: X-ray hit rate [MHz/cm2]: 18.3 12.8 18.0 29.7 30.4 32.4 30.7 22.4 18.5 23.7 22.8 20.3 21.0 13.2 6.3 6.6
[14:23:41.630] INFO: PixTestHighRate::doXPixelAlive() done
[14:23:41.675] INFO: PixTest:: pg_setup set to default.
[14:23:41.686] INFO: enter test to run
[14:24:06.667] INFO: test: HighRate no parameter change
[14:24:06.667] INFO: running: highrate
[14:24:06.668] INFO: ----------------------------------------------------------------------
[14:24:06.668] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:24:06.668] INFO: ----------------------------------------------------------------------
[14:24:07.284] INFO: Expecting 208000 events.
[14:24:20.606] INFO: 208000 events read in total (12795ms).
[14:24:20.610] INFO: Test took 13931ms.
[14:24:20.884] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:21.178] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 17 3 0 1 0 0
[14:24:21.178] INFO: number of red-efficiency pixels: 200 96 242 447 383 412 377 313 230 253 270 231 233 198 58 51
[14:24:21.178] INFO: number of X-ray hits detected: 131201 93545 131565 215975 220784 235725 223529 162762 134108 172711 165599 148743 152869 95230 45390 48439
[14:24:21.178] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:24:21.178] INFO: number of Vcal hits detected: 207791 207900 207728 207526 207593 207559 207608 207664 207761 207736 206839 207399 207763 207739 207942 207948
[14:24:21.178] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.8 99.9 99.9 100.0 100.0
[14:24:21.178] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.4 99.7 99.9 99.9 100.0 100.0
[14:24:21.178] INFO: X-ray hit rate [MHz/cm2]: 38.5 27.4 38.6 63.3 64.7 69.1 65.5 47.7 39.3 50.6 48.5 43.6 44.8 27.9 13.3 14.2
[14:24:21.178] INFO: PixTestHighRate::doXPixelAlive() done
[14:24:21.225] INFO: PixTest:: pg_setup set to default.
[14:24:21.238] INFO: enter test to run
[14:24:42.667] INFO: test: HighRate no parameter change
[14:24:42.667] INFO: running: highrate
[14:24:42.668] INFO: ----------------------------------------------------------------------
[14:24:42.668] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:24:42.668] INFO: ----------------------------------------------------------------------
[14:24:43.284] INFO: Expecting 208000 events.
[14:24:58.356] INFO: 208000 events read in total (14545ms).
[14:24:58.363] INFO: Test took 15685ms.
[14:24:58.773] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:59.113] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 17 3 0 1 0 0
[14:24:59.113] INFO: number of red-efficiency pixels: 408 212 609 1100 976 1038 888 741 477 574 618 587 410 363 101 88
[14:24:59.113] INFO: number of X-ray hits detected: 199794 142861 200810 330234 337346 358834 342306 249303 206589 263715 253148 228313 234364 146623 70248 75041
[14:24:59.113] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:24:59.113] INFO: number of Vcal hits detected: 207535 207773 207238 206629 206828 206750 206958 207069 207481 207366 206440 206550 207554 207529 207898 207911
[14:24:59.113] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.4 99.5 99.5 99.5 99.6 99.8 99.7 99.7 99.4 99.8 99.8 100.0 100.0
[14:24:59.113] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.3 99.4 99.4 99.5 99.6 99.8 99.7 99.2 99.3 99.8 99.8 100.0 100.0
[14:24:59.113] INFO: X-ray hit rate [MHz/cm2]: 58.6 41.9 58.9 96.8 98.9 105.2 100.3 73.1 60.6 77.3 74.2 66.9 68.7 43.0 20.6 22.0
[14:24:59.113] INFO: PixTestHighRate::doXPixelAlive() done
[14:24:59.162] INFO: PixTest:: pg_setup set to default.
[14:24:59.180] INFO: enter test to run
[14:25:12.171] INFO: test: exit no parameter change
[14:25:12.580] QUIET: Connection to board 33 closed.
[14:25:12.596] INFO: pXar: this is the end, my friend