[13:52:12.231] INFO: *** Welcome to pxar ***
[13:52:12.231] INFO: *** Today: 2016/05/26
[13:52:12.249] INFO: *** Version: v1.9.0-796-gef167-dirty
[13:52:12.249] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//dacParameters35_C15.dat
[13:52:12.250] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:52:12.250] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//defaultMaskFile.dat
[13:52:12.250] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C15.dat
[13:52:12.325] INFO: clk: 4
[13:52:12.325] INFO: ctr: 4
[13:52:12.325] INFO: sda: 19
[13:52:12.325] INFO: tin: 9
[13:52:12.325] INFO: level: 15
[13:52:12.325] INFO: triggerdelay: 0
[13:52:12.325] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[13:52:12.325] INFO: Log level: INFO
[13:52:12.342] QUIET: Connection to board DTB_WREKRL opened.
[13:52:12.345] 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:52:12.348] INFO: RPC call hashes of host and DTB match: 398089610
[13:52:13.873] INFO: DUT info:
[13:52:13.874] INFO: The DUT currently contains the following objects:
[13:52:13.874] INFO: 2 TBM Cores tbm08c (2 ON)
[13:52:13.874] INFO: TBM Core alpha (0): 7 registers set
[13:52:13.874] INFO: TBM Core beta (1): 7 registers set
[13:52:13.874] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:52:13.874] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:13.874] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:14.277] INFO: enter 'restricted' command line mode
[13:52:14.277] INFO: enter test to run
[13:52:27.844] INFO: test: PixelAlive no parameter change
[13:52:27.844] INFO: running: pixelalive
[13:52:27.853] INFO: ----------------------------------------------------------------------
[13:52:27.853] 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:52:27.853] INFO: ----------------------------------------------------------------------
[13:52:28.176] INFO: Expecting 41600 events.
[13:52:32.510] INFO: 41600 events read in total (3616ms).
[13:52:32.678] INFO: Test took 4823ms.
[13:52:32.691] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:32.963] INFO: PixTestAlive::aliveTest() done
[13:52:32.963] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:52:32.998] INFO: enter test to run
[13:53:34.548] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:53:34.548] INFO: running: highrate
[13:53:34.549] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:53:34.769] INFO: ----------------------------------------------------------------------
[13:53:34.769] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:53:34.769] INFO: ----------------------------------------------------------------------
[13:53:34.769] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:53:34.769] INFO: edge/corner pixel THR is adjusted
[13:53:34.769] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:53:35.727] INFO: Collecting data for 5 seconds...
[13:53:40.746] INFO: Done with hot pixel readout
[13:53:52.214] INFO: PixTest:: pg_setup set to default.
[13:53:52.215] INFO: 11 hot pixels found in step 0
[13:53:53.223] INFO: Collecting data for 5 seconds...
[13:53:58.242] INFO: Done with hot pixel readout
[13:54:09.701] INFO: PixTest:: pg_setup set to default.
[13:54:09.702] INFO: 8 hot pixels found in step 1
[13:54:10.689] INFO: Collecting data for 5 seconds...
[13:54:15.707] INFO: Done with hot pixel readout
[13:54:27.224] INFO: PixTest:: pg_setup set to default.
[13:54:27.225] INFO: 10 hot pixels found in step 2
[13:54:28.212] INFO: Collecting data for 5 seconds...
[13:54:33.232] INFO: Done with hot pixel readout
[13:54:44.577] INFO: PixTest:: pg_setup set to default.
[13:54:44.578] INFO: 7 hot pixels found in step 3
[13:54:45.565] INFO: Collecting data for 5 seconds...
[13:54:50.587] INFO: Done with hot pixel readout
[13:55:02.086] INFO: PixTest:: pg_setup set to default.
[13:55:02.087] INFO: 6 hot pixels found in step 4
[13:55:03.074] INFO: Collecting data for 5 seconds...
[13:55:08.092] INFO: Done with hot pixel readout
[13:55:19.546] INFO: PixTest:: pg_setup set to default.
[13:55:19.547] INFO: 6 hot pixels found in step 5
[13:55:20.534] INFO: Collecting data for 5 seconds...
[13:55:25.555] INFO: Done with hot pixel readout
[13:55:37.020] INFO: PixTest:: pg_setup set to default.
[13:55:37.021] INFO: 2 hot pixels found in step 6
[13:55:38.007] INFO: Collecting data for 5 seconds...
[13:55:43.024] INFO: Done with hot pixel readout
[13:55:54.421] INFO: PixTest:: pg_setup set to default.
[13:55:54.421] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:55:54.422] INFO: 5 hot pixels found in step 7
[13:55:55.409] INFO: Collecting data for 5 seconds...
[13:56:00.426] INFO: Done with hot pixel readout
[13:56:11.921] INFO: PixTest:: pg_setup set to default.
[13:56:11.922] INFO: 1 hot pixels found in step 8
[13:56:12.909] INFO: Collecting data for 5 seconds...
[13:56:17.925] INFO: Done with hot pixel readout
[13:56:29.425] INFO: PixTest:: pg_setup set to default.
[13:56:29.426] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:29.426] INFO: 1 hot pixels found in step 9
[13:56:29.457] INFO: 1 hot pixels could not be trimmed and have been masked.
[13:56:29.461] INFO: PixTest::trimHotPixels() done
[13:56:29.463] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C0.dat
[13:56:29.469] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C1.dat
[13:56:29.475] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C2.dat
[13:56:29.481] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C3.dat
[13:56:29.486] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C4.dat
[13:56:29.492] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C5.dat
[13:56:29.497] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C6.dat
[13:56:29.502] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C7.dat
[13:56:29.508] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C8.dat
[13:56:29.513] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C9.dat
[13:56:29.518] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C10.dat
[13:56:29.523] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C11.dat
[13:56:29.529] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C12.dat
[13:56:29.534] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C13.dat
[13:56:29.539] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C14.dat
[13:56:29.545] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C15.dat
[13:56:29.550] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//defaultMaskFile.dat
[13:56:29.560] INFO: enter test to run
[13:57:25.090] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:57:25.090] INFO: running: highrate
[13:57:25.095] INFO: ----------------------------------------------------------------------
[13:57:25.095] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:57:25.095] INFO: ----------------------------------------------------------------------
[13:57:25.095] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:57:25.095] INFO: edge/corner pixel THR is adjusted
[13:57:25.095] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:57:26.058] INFO: Collecting data for 1 seconds...
[13:57:27.061] INFO: Done with hot pixel readout
[13:57:30.934] INFO: PixTest:: pg_setup set to default.
[13:57:30.935] INFO: 0 hot pixels found in step 0
[13:57:30.940] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:57:31.032] INFO: PixTest::trimHotPixels() done
[13:57:31.033] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C0.dat
[13:57:31.044] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C1.dat
[13:57:31.050] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C2.dat
[13:57:31.056] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C3.dat
[13:57:31.061] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C4.dat
[13:57:31.066] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C5.dat
[13:57:31.072] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C6.dat
[13:57:31.077] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C7.dat
[13:57:31.083] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C8.dat
[13:57:31.088] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C9.dat
[13:57:31.093] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C10.dat
[13:57:31.099] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C11.dat
[13:57:31.104] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C12.dat
[13:57:31.109] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C13.dat
[13:57:31.115] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C14.dat
[13:57:31.120] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//trimParameters35_C15.dat
[13:57:31.126] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//defaultMaskFile.dat
[13:57:31.136] INFO: enter test to run
[13:58:09.866] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:58:09.866] INFO: running: xray
[13:58:09.867] INFO: ----------------------------------------------------------------------
[13:58:09.867] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:58:09.867] INFO: ----------------------------------------------------------------------
[13:58:10.829] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:58:22.533] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:58:23.109] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (7) != Token Chain Length (8)
[13:58:23.109] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:58:23.109] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a050 80b1 4388 19 2828 6d5 2a2c 4388 4388 4388 4389 4389 84c 2a4e 4388 4388 518 2a61 e000 c000
[13:58:23.109] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04a 8000 43c8 43c8 293 222d 43c8 43c8 d1 2843 43c8 85b 2466 43c8 43c8 51 282d 48 262d 43c8 e000 c000
[13:58:23.109] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04b 8040 43c8 241 204a 859 2863 43c8 dd 2840 43c8 43c8 210 2027 245 284d 43c8 34d 202e 802 2640 842 2464 43c8 303 2869 81d 2a4f 43c8 43c8 e000 c000
[13:58:23.109] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04c 80b1 43c8 11 2828 43c8 620 202e 43c8 448 2442 43c8 43c8 4d3 2661 519 2649 43c8 43c8 81c 2a45 43c8 e000 c000
[13:58:23.109] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04d 80c0 43c8 43c8 6dd 2061 43c8 43c8 43c8 43c8 359 224f 45d 2868 43c8 52 2849 4c 242f f8 200c 240 2541 45 e000 c000
[13:58:23.109] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04e 8000 4388 4388 4388 48c 224a 84b 2464 4388 438a 438a 4388 4388 40 2842 db 2061 e000 c000
[13:58:23.109] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04f 8040 438a 438a 438a 752 2447 438a 18 282d 4388 4388 751 264a 855 2240 438a 438a e000 c000
[13:58:51.199] INFO: Resuming triggers.
[13:59:02.902] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:59:04.381] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:59:04.381] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a017 8040 4388 4388 4388 4388 4388 159 2045 15d 2249 4388 4388 4388 e000 c000
[13:59:04.381] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a011 80c0 43c8 41a 264f 43c8 43c8 43c8 454 282d 43c8 43c8 c0 2a0f 43c9 43c9 e000 c000
[13:59:04.381] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a012 8000 43c8 241 2242 43c8 43c9 808 242d 43c9 43c8 43c9 43c9 43c9 e000 c000
[13:59:04.381] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a013 8040 43c8 20 2840 22 2a2d 90 262d 43c9 43c8 43c9 481 2061 43c8 43c8 109 2264 43c8 43c9 e000 c000
[13:59:04.381] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a014 80b1 43c8 4a1 2a6f 4d9 264a 43c8 43c8 805 2441 43c8 43c8 61d 2865 43c8 c8 2223 5388 1020 2060 540 20b5 422 2644 694 2643 4388 e000 c000
[13:59:04.381] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a015 80c0 4388 4388 4388 4388 4388 4388 4388 4388 2ca 282f e000 c000
[13:59:04.381] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a016 8000 4388 4388 4388 4388 4388 4388 659 2462 4388 31a 2a45 4388 112 202c e000 c000
[13:59:31.835] INFO: Resuming triggers.
[13:59:43.538] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:59:43.549] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:59:43.549] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[13:59:43.549] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:59:43.549] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02a 8000 43c8 43c8 43c8 43c8 109 222f 43c8 43c8 43c8 43c8 e000 c000
[13:59:43.549] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a024 80b1 4388 859 2044 4388 4388 658 202f 4388 4388 4388 81a 2844 4388 4388 e000 c000
[13:59:43.549] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a025 80c0 4388 753 2264 4388 4388 6d8 242f 4388 4388 4388 4388 4388 e000 c000
[13:59:43.549] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a026 8000 4388 4388 4388 4388 cc 262e 4388 4388 4388 4388 e000 c000
[13:59:43.549] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a027 8040 43c9 2af 2eab 43c8 43c8 692 2a2c 43c8 43c8 10 244c 43c8 e000 c000
[13:59:43.549] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a028 80b1 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 2d0 284d e000 c000
[13:59:43.549] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a029 80c0 43c8 205 282e 43c8 43c8 43c8 43c8 43c8 1c 2829 64c 2242 43c8 305 222e 43c8 e000 c000
[13:59:43.549] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:59:43.549] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12a 8000 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:59:43.549] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a124 80b1 4388 722 2848 4388 4388 30c 264e 4388 4388 2d2 2a85 4388 4388 4388 e000 c000
[13:59:43.549] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a125 80c0 4388 0 2a42 4388 522 264e 4388 302 284c 4388 4388 4388 4388 4388 e000 c000
[13:59:43.549] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a126 8000 4388 4388 28a 2445 4388 4388 4388 4388 4388 4388 e000 c000
[13:59:43.549] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a127 8040 43c8 9 2400 10 28b6 43c8 43c8 4cb 204f 601 242d 65b 2a4c 43c8 19 2441 43c8 43c8 43c8 e000 c000
[13:59:43.549] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a128 80b1 43c8 449 2a28 43c8 411 2441 4cb 2244 68b 204a 748 242f 43c8 43c8 699 264e 43c8 43c8 43c8 43c8 e000 c000
[13:59:43.549] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a129 80c0 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:59:45.827] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (179) != TBM ID (180)
[13:59:45.827] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (179) != TBM ID (180)
[13:59:45.827] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:59:45.827] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b7 8040 4388 12 2442 4388 4388 4388 419 284f 4388 31d 2642 4388 4388 4b 2661 4388 e000 c000
[13:59:45.827] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b0 80b1 43c8 43c8 43c8 43c8 43c9 91 2045 155 2248 43c9 43c8 248 2045 600 2669 43c8 659 2848 e000 c000
[13:59:45.827] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b1 80c0 43c8 43c8 43c8 43c8 43c8 43c8 1b 224f 81a 2a63 43c9 43c9 e000 c000
[13:59:45.827] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b2 8000 43c8 43c8 43c9 43c9 d4 282f 43c8 43c9 43c9 43c9 e000 c000
[13:59:45.827] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b4 80b1 4388 4388 4388 4388 4388 4d0 262e 4388 4c4 2029 4388 310 2a2f 4388 e000 c000
[13:59:45.827] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b5 80c0 4388 4388 4388 49a 2a49 4388 4388 4388 4388 4388 10a 2047 2c5 2843 352 2860 55c 244f 6cd 2841 e000 c000
[13:59:45.827] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b6 8000 4388 4388 822 2865 4388 4388 41b 244f 852 224c 4388 69a 204f 69a 2247 714 2629 4388 4388 4388 10d 2628 e000 c000
[13:59:45.827] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:59:45.827] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b7 8040 4388 55 244d 4388 c5 2a28 48c 2045 4388 4cb 2668 4388 4388 4388 4388 4388 e000 c000
[13:59:45.827] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b0 80b1 43c9 43c9 43c9 43c9 43c9 43c9 299 2041 43c9 43c9 e000 c000
[13:59:45.827] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b1 80c0 43c8 80a 2245 43c8 43c8 43c9 618 222d 43c9 712 2661 43c9 43c9 43c9 e000 c000
[13:59:45.827] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b2 8000 43c8 24d 224d 43c8 43c9 43c8 601 2645 43c8 43c8 43c8 43c9 e000 c000
[13:59:45.827] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b4 80b1 4388 4388 4388 555 204c 4388 4388 4388 4388 4388 e000 c000
[13:59:45.827] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b5 80c0 4388 8b 286a 4388 4388 218 2a49 4388 4388 850 284d 4388 4388 4388 e000 c000
[13:59:45.827] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b6 8000 4388 4388 4388 510 282d 4388 4388 4388 4388 4388 e000 c000
[13:59:45.827] WARNING: Channel 0 ROC 6: Readback start marker after 15 readouts!
[13:59:45.827] WARNING: Channel 0 ROC 7: Readback start marker after 15 readouts!
[13:59:45.828] WARNING: Channel 1 ROC 7: Readback start marker after 15 readouts!
[13:59:45.828] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:59:45.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c2 8000 4388 11d 242f 4388 11c 2845 4389 4389 4388 d8 2a27 4389 758 2824 4389 4389 1c 2441 e000 c000
[13:59:45.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bc 80b1 4388 4388 4388 4388 4388 2c9 2843 4388 4388 4388 e000 c000
[13:59:45.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bd 80c0 4388 4388 4388 4388 549 2a66 4388 12 2649 812 262c 4388 28d 2682 4388 48 242f 4388 e000 c000
[13:59:45.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0be 8000 4388 4388 221 2a65 4388 4388 438a 245 2a45 438a 30d 284d 4388 6c5 206c 4388 159 2044 e000 c000
[13:59:45.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bf 8040 438a 438a 438a 438a 55d 2843 4388 613 2469 4388 438a 438a e000 c000
[13:59:45.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c0 80b1 4388 4388 4388 4388 80d 244d 4389 84a 2048 4389 4388 418 242d 4388 e000 c000
[13:59:45.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c1 80c0 4388 13 2029 4388 4388 4388 514 2860 4388 4388 54b 2069 4389 4389 295 2a2f e000 c000
[13:59:45.828] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:59:45.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c2 8000 4388 4388 300 2844 4389 4388 4388 114 2449 4388 4388 4389 e000 c000
[13:59:45.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bc 80b1 4388 4388 4388 4388 4388 4388 4388 4388 6cb 264f e000 c000
[13:59:45.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bd 80c0 4388 45a 2a4a 4388 4388 438a 4388 4388 4388 4388 103 224f e000 c000
[13:59:45.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1be 8000 4388 540 244c 4388 4388 4389 4388 4388 438a 4388 e000 c000
[13:59:45.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bf 8040 438a 2c3 242c 438a 438a 512 2644 721 2629 4388 438a 438a 4389 29c 2649 438a 31b 224d e000 c000
[13:59:45.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c0 80b1 4389 4389 4389 4389 6d4 202f 4389 6d2 2029 4389 4a 2447 282 2649 4389 4389 e000 c000
[13:59:45.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c1 80c0 4388 4388 415 2845 611 2463 4388 4389 0 2a41 4389 84d 204f 4389 4389 4389 e000 c000
[13:59:54.308] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:59:54.308] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08b 8040 43c8 43c8 43c8 43c8 555 2629 43c8 43c8 43c8 85b 244c 43c8 e000 c000
[13:59:54.308] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a085 80c0 4388 4388 15c 2445 4388 4388 4388 4388 4388 4388 e000 c000
[13:59:54.308] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a086 8000 4388 21 2441 4388 4388 4388 4388 45d 284b 4388 4388 4388 6cd 2462 e000 c000
[13:59:54.308] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a087 8040 4388 4388 4388 4388 4388 4388 855 204c 4388 802 2a4d 4388 353 228d 461 264d e000 c000
[13:59:54.308] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a088 80b1 4388 4388 4388 4388 6c8 2641 4388 d8 2040 4388 4388 53c8 e000 c000
[13:59:54.308] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a089 80c0 43c8 43c8 43c8 43c8 43c8 43c8 448 282d 43c8 43c8 e000 c000
[13:59:54.308] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08a 8000 43c8 43c8 291 222d 43c8 43c8 43c8 43c8 555 2a4d 43c8 43c8 e000 c000
[13:59:58.730] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:59:58.730] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:59:58.730] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a3 8040 4388 4389 153 2a48 4388 558 204f 4389 4388 1a 264f 4388 4388 2dc 282f 4389 e000 c000
[13:59:58.730] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09d 80c0 43c8 43c8 43c8 43c8 611 284d 43c8 8c 204f 43c8 43c8 43c8 e000 c000
[13:59:58.730] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09e 8000 43c8 43c8 43c8 43c8 43ca 4cc 202a 858 2a2e 43ca 43c8 43c8 e000 c000
[13:59:58.730] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09f 8040 43ca 253 2a47 43ca 43ca 43ca 43c8 43c8 55a 204a 71a 2249 43ca 145 2247 20a 2a48 6d3 2247 43ca 35a 2a45 e000 c000
[13:59:58.730] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a0 80b1 43c8 43c8 43c8 43c8 43c9 249 2244 289 284d 7ef 2408 d8 2640 4388 e000 c000
[13:59:58.730] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a1 80c0 4388 4388 4388 510 222f 4388 4388 4388 4389 6da 2a41 4389 e000 c000
[13:59:58.730] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a2 8000 4388 4388 4389 760 2849 4389 11d 2a4c 4388 4389 4389 40 2441 6c8 2640 4389 e000 c000
[14:00:07.816] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (208) != TBM ID (193)
[14:00:07.816] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[14:00:07.816] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (208) != TBM ID (194)
[14:00:07.816] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[14:00:07.816] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (194) != TBM ID (209)
[14:00:07.816] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (195) != TBM ID (209)
[14:00:07.816] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:00:07.816] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d4 80b1 4388 4388 4388 4388 201 262f 810 242a 4388 4388 4388 41d 202c 4388 48a 2640 e000 c000
[14:00:07.816] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ce 8000 43c8 43c8 411 262e 43c8 43c8 43ca 43ca 43c8 43c8 e000 c000
[14:00:07.816] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0cf 8040 43ca 43ca 61b 2041 43ca 43ca 43c8 80b 2644 43c8 14a 2441 43ca 43ca e000 c000
[14:00:07.816] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c1 806b 5f8 4388 4388 4388 4c5 2a44 4389 84b 264a 4389 4388 4388 e000 c000
[14:00:07.816] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d1 80c0 4388 4388 4388 11d 2a42 4388 4388 4388 4389 4389 2db 2648 e000 c000
[14:00:07.816] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d2 8000 4388 4388 40c 2228 4389 4389 4388 99 2640 45b 224c 4389 54b 2683 4389 4389 e000 c000
[14:00:07.816] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d3 8040 4388 4389 4388 688 2644 6c8 2440 4389 4388 41d 204d 4388 410 2446 4388 4389 5b 284d e000 c000
[14:00:07.816] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:00:07.817] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d4 80b1 4388 4388 28d 204a 4388 88 282f 4388 4388 4388 4388 4388 41a 2a2f e000 c000
[14:00:07.817] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ce 8000 43c8 43c8 14d 2642 43c8 43c9 43c8 43c8 43ca 811 2267 43c8 e000 c000
[14:00:07.817] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1cf 8040 43ca 43ca 43ca 43c8 43ca 618 2a28 43ca 43c9 43ca e000 c000
[14:00:07.817] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c2 8082 54 4389 4389 4389 4389 4389 4389 2dc 286d 4389 e000 c000
[14:00:07.817] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d1 80c0 4388 4388 4388 4389 4389 4389 4389 4389 e000 c000
[14:00:07.817] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d2 8000 4388 4388 6dd 2045 4389 4388 350 2048 4388 4388 4388 4389 49a 2246 e000 c000
[14:00:07.817] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d3 8040 4388 4389 2ca 2a42 843 284d 4388 558 2840 4388 44a 286f 4388 4389 4388 4389 e000 c000
[14:00:12.115] INFO: Resuming triggers.
[14:00:23.817] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:00:30.323] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[14:00:30.323] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[14:00:30.324] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (64) != TBM ID (190)
[14:00:30.324] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:00:30.324] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c0 80b1 43c9 43c9 34b 2242 43c9 43c9 43c9 43c9 43c9 43c9 e000 c000
[14:00:30.324] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ba 8000 4388 4cb 204c 4388 84 202d 4388 4388 4388 4388 8c 2a41 4388 4388 e000 c000
[14:00:30.324] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bb 8040 4388 4388 4388 4388 4388 94 2643 218 2049 4388 4388 4388 e000 c000
[14:00:30.324] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bc 80b1 4388 4388 4388 4388 9 222f 4388 4388 4388 4388 e000 c000
[14:00:30.324] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bd 80c0 4388 4388 30b 204d 719 2046 43c8 43ca 43c8 2d9 200c 2000 927 43c8 e000 c000
[14:00:30.324] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1be 8000 43c8 718 2240 43c8 43c8 43c9 649 204e 43c8 850 264d 43c8 254 2a61 43ca 43c8 e000 c000
[14:00:30.324] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bf 8040 43ca 43ca 61a 204b 43ca 43c8 43ca 95 2a49 43ca 43c9 43ca e000 c000
[14:00:30.324] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:00:30.324] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c1 80c0 43c8 43c8 43c8 43c8 502 2046 43c8 43c8 44b 2a4c 43c9 43c9 e000 c000
[14:00:30.324] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bc 80b1 4388 4388 4388 4388 4388 4d2 264a 710 2247 4388 4388 54c 244d 4388 313 244a e000 c000
[14:00:30.324] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a0bd 80c0 4388 4388 4388 4388 28d 2a48 43c8 43c8 5b 2669
[14:00:30.324] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a03f 80f0 0 27ff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[14:00:30.324] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0be 8000 43c8 43c8 43c8 610 2044 710 2a2f 43c8 43ca 43ca 111 224f 2c2 2a4c 43c8 52 2642 43c8 e000 c000
[14:00:30.324] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bf 8040 43ca 43ca 43ca 43ca 43c8 411 244d 40d 2869 43c8 43ca 30b 266d 655 264d 43ca e000 c000
[14:00:30.324] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c0 80b1 43c8 690 2a41 43c8 43c8 43c8 43c9 34c 2026 43c9 3 2446 15d 284d 21d 2845 43c8 814 2a47 43c8 351 2245 e000 c000
[14:00:51.851] INFO: Resuming triggers.
[14:01:03.556] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[14:01:07.864] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (4) != Token Chain Length (8)
[14:01:07.864] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[14:01:07.864] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:01:07.864] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fa 8000 4388 2dd 2241 4388 4388 4388 841 2a4d 842 224c 4388 4388 4388 d2 222f 710 2a41 4388 4cc 2a29 e000 c000
[14:01:07.864] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f4 80b1 43c8 43c8 43c8 43c8 a 2447 43c8 4d8 2442 43c8 354 2840 43c8 43c8 e000 c000
[14:01:07.864] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f5 80c0 43c8 41c 2829 43c8 29b 262f 43c8 43c8 60d 2461 75a 2049 754 2a29 43c8 80a 286f 43c8 43c8 43c8 e000 c000
[14:01:07.865] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f6 8000 43c8 43c8 43c8 43c8 60a 264d 43c8 4d5 2a29 43c8 40 262c 43c8 43c8 e000 c000
[14:01:07.865] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f7 8040 43c8 258 2040 5388 4388 4388 e0f 2c1d f58 201c 44f 4388 e000 c000
[14:01:07.865] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f8 80b1 4388 29d 264f 4388 4388 553 2a67 4388 4388 4388 4388 4388 4b 264c 88 2825 e000 c000
[14:01:07.865] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f9 80c0 4388 4388 4388 4388 849 2048 4388 4388 c 246d 4388 718 224c 4388 e000 c000
[14:01:07.865] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:01:07.865] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fa 8000 4388 4388 755 2a4c 4388 4388 4388 4388 4388 4388 e000 c000
[14:01:07.865] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f4 80b1 43c8 6dc 260f 43c8 84c 2a45 43c8 35a 2a2f 43c8 43c8 220 244f 43c8 45 282d 43c8 43c8 24b 2685 e000 c000
[14:01:07.865] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f5 80c0 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[14:01:07.865] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f6 8000 43c8 43c8 43c8 75c 2849 43c8 43c8 43c8 20c 2649 43c8 43c8 e000 c000
[14:01:07.865] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f7 8040 43c8 43c8 4388 5388 4388 438a 8f0 28d0 204 4388 e000 c000
[14:01:07.865] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f8 80b1 4388 4388 85 242a 4388 4388 4388 113 244f 4388 514 2029 4388 4388 e000 c000
[14:01:07.865] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f9 80c0 4388 4388 4388 709 262c 4388 4388 4388 4388 4388 e000 c000
[14:01:26.442] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 8 ROCs were found
[14:01:26.442] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 8 ROCs were found
[14:01:26.442] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:01:26.442] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c5 80c0 4388 28a 2265 4388 4388 4388 204 202c 48a 2448 4388 4388 d0 222f 4388 219 2665 4388 e000 c000
[14:01:26.442] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bf 8040 43ca 43ca 43ca 43ca 415 224f 43c8 43c8 43ca 754 2840 43ca e000 c000
[14:01:26.443] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c0 80b1 43c8 105 2625 43c8 51b 224c 43c8 43c8 809 204f 43c9 43c9 43c8 43c8 349 244c 48d 2067 e000 c000
[14:01:26.443] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c1 80c0 43c8 43c8 43c8 43c8 43c8 43c8 43c9 43c9 e000 c000
[14:01:26.443] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a0c2 8000 43c8 43c8 43c9 43c9 43c8 4cb 2644 4389 4389 4389 404 27fd 40e 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
[14:01:26.443] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c3 8040 4388 4389 4388 4389 4388 4388 200 2440 4388 4389 e000 c000
[14:01:26.443] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c4 80b1 4388 4388 4388 4cb 284d 4388 4388 5 284b 4388 28c 2645 508 2a4f 4388 21d 244f 4388 e000 c000
[14:01:26.443] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:01:26.443] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c5 80c0 4388 25b 266f 4388 21a 2a41 4388 4388 4388 4388 4388 4388 e000 c000
[14:01:26.443] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bf 8040 43ca 43ca 43ca 43c8 8 202f 43ca 43ca 43c9 43ca e000 c000
[14:01:26.443] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c0 80b1 43c9 60d 2241 43c9 43c9 35d 2445 35d 2648 43c9 43c9 840 2845 43c9 350 2225 43c9 43c9 e000 c000
[14:01:26.443] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c1 80c0 43c8 43c8 43c8 43c9 43c9 43c9 43c9 43c9 e000 c000
[14:01:26.443] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a1c2 8000 43c8 43c8 43c9 640 264c 43c8 43c8 4388 4388 4389 7f1 2882 37f 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[14:01:26.443] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c3 8040 4388 658 2a44 4389 4388 4388 4388 88 2a4c 4389 4388 4389 e000 c000
[14:01:26.443] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c4 80b1 4388 4388 149 2849 4388 4388 4388 18 2a47 4388 4388 70a 2449 4388 e000 c000
[14:01:27.554] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (2) != Token Chain Length (8)
[14:01:27.554] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (5) != Token Chain Length (8)
[14:01:27.554] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (0) != Token Chain Length (8)
[14:01:27.554] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:01:27.554] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a18b 8040 43c8 43c8 43c8 251 204f 43c8 43c8 d1 2a49 43c8 43c8 43c8 e000 c000
[14:01:27.554] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a185 80c0 4388 4388 719 2047 4388 4388 4388 4388 4388 4388 e000 c000
[14:01:27.554] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a186 8000 4388 4388 9b 2045 4388 4388 644 242d 644 2625 4388 2c1 224f 4388 4388 4388 822 204f e000 c000
[14:01:27.554] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a187 8040 4388 d2 2042 4388 608 2a49 4388 4388 11 2447 488 2a4c 4388 4388 4388 4388 e000 c000
[14:01:27.554] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a188 80b1 4388 4388 619 244c 4388 50c 282a 43c8 43c8 601 2402 2aa 2806 e000 c000
[14:01:27.554] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a189 80c0 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[14:01:27.554] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a18a 8000 43c8 65a 264b 43c8 81d 2445 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[14:01:27.554] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:01:27.554] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08b 8040 43c8 43c8 354 2a40 43c8 43c8 43c8 318 2440 43c8 34a 242f 43c8 55c 2843 43c8 e000 c000
[14:01:27.554] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a086 8000 4388 60 2a43 61 224d 4388 4388 4388 4388 54d 2447 4388 4388 858 2245 4388 491 2669 e000 c000
[14:01:27.554] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a087 8040 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[14:01:27.554] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a088 80b1 4388 50 2445 e1 264f 4d8 2041 43c8 19 29f6 96b 23ec
[14:01:27.554] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03f c00b
[14:01:27.554] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a089 80c0 43c8 318 2642 43c8 251 262d 43c8 43c8 43c8 43c8 43c8 43c8 493 2862 e000 c000
[14:01:27.554] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08a 8000 43c8 43c8 43c8 410 2a61 43c8 43c8 d 284f 9c 2862 359 242c 43c8 43c8 43c8 e000 c000
[14:01:32.128] INFO: Resuming triggers.
[14:01:43.828] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:02:12.309] INFO: Resuming triggers.
[14:02:24.009] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[14:02:52.481] INFO: Resuming triggers.
[14:03:04.184] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[14:03:32.616] INFO: Resuming triggers.
[14:03:39.318] INFO: data taking finished, elapsed time: 100 seconds.
[14:03:39.689] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 8 ROCs were found
[14:03:39.689] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 6 ROCs were found
[14:03:39.689] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:03:39.689] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a026 8000 43c8 44 2029 43c8 43c8 43c8 210 2844 349 2661 419 264d 51b 226a 43c8 43c8 43c8 118 224c 43c8 e000 c000
[14:03:39.689] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a020 80b1 4388 4388 4388 4388 2c4 2443 2d2 2a4d 4389 4389 805 2268 4388 30c 2247 348 204f 4388 e000 c000
[14:03:39.689] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a021 80c0 4388 4388 4388 4388 4388 4388 755 2443 4389 95 2663 4389 e000 c000
[14:03:39.689] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a022 8000 4388 6e1 2444 6e1 202e 4388 4389 681 244c 4389 4388 611 268a 744 2240 4389 291 2264 4389 4389 88 242f d4 2843 e000 c000
[14:03:39.689] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a023 8040 4388 4389 4388 4389 848 2245 4388 54d 2464 4388 6d2 2a2f 43c8 43c9 7f7 43c9 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
[14:03:39.689] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a024 80b1 43c8 43 2a64 43c8 43c8 620 2845 43c8 43c8 615 2681 43c8 85 2048 43c8 43c8 e000 c000
[14:03:39.689] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a025 80c0 43c8 43c8 43c8 43c8 43c8 43c8 75a 2a45 43c8 43c8 30c 262f e000 c000
[14:03:39.689] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:03:39.689] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a126 8000 43c8 74b 206d 43c8 43c8 45 2a61 43c8 43c8 48a 2268 43c8 43c8 43c8 e000 c000
[14:03:39.689] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a120 80b1 4389 4389 8b 2840 4389 4389 4389 4389 4389 418 2a42 85b 2063 4389 e000 c000
[14:03:39.689] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a121 80c0 4388 4388 293 2a65 4388 4389 4389 4389 4389 4389 e000 c000
[14:03:39.689] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a122 8000 4388 4388 359 2263 4389 4388 71a 2861 4388 31b 2664 4388 34d 2845 4388 4389 e000 c000
[14:03:39.689] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a123 8040 4388 8d 264f 4389 28a 204d 4388 99 2861 40c 202f 43c8 43c8 43c9 7f3 281f 804 2000 1ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff
[14:03:39.690] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a124 80b1 43c8 43c8 43c8 810 2a4f 43c8 43c8 43c8 43c8 43c8 e000 c000
[14:03:39.690] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a125 80c0 43c8 43c8 43c8 43c8 10 262f 43c8 43c8 43c8 43c8 e000 c000
[14:03:54.999] INFO: PixTest:: pg_setup set to default.
[14:03:54.002] INFO: PixTestXray::doPhRun() done
[14:03:55.143] INFO: enter test to run
[14:04:25.823] INFO: test: timing no parameter change
[14:04:25.823] INFO: running: timing
[14:04:25.849] INFO: ######################################################################
[14:04:25.849] INFO: PixTestTiming::doTest()
[14:04:25.849] INFO: ######################################################################
[14:04:25.849] INFO: ----------------------------------------------------------------------
[14:04:25.849] INFO: PixTestTiming::TBMPhaseScan()
[14:04:25.849] INFO: ----------------------------------------------------------------------
[14:12:02.741] INFO: TBM Phase Settings: 240
[14:12:02.741] INFO: 400MHz Phase: 4
[14:12:02.741] INFO: 160MHz Phase: 7
[14:12:02.741] INFO: Functional Phase Area: 4
[14:12:02.744] INFO: Test took 456895 ms.
[14:12:02.744] INFO: PixTestTiming::TBMPhaseScan() done.
[14:12:02.745] INFO: ----------------------------------------------------------------------
[14:12:02.745] INFO: PixTestTiming::ROCDelayScan()
[14:12:02.745] INFO: ----------------------------------------------------------------------
[14:14:46.555] INFO: ROC Delay Settings: 228
[14:14:46.555] INFO: ROC Header-Trailer/Token Delay: 11
[14:14:46.555] INFO: ROC Port 0 Delay: 4
[14:14:46.555] INFO: ROC Port 1 Delay: 4
[14:14:46.555] INFO: Functional ROC Area: 5
[14:14:46.558] INFO: Test took 163813 ms.
[14:14:46.558] INFO: PixTestTiming::ROCDelayScan() done.
[14:14:46.558] INFO: ----------------------------------------------------------------------
[14:14:46.558] INFO: PixTestTiming::TimingTest()
[14:14:46.558] INFO: ----------------------------------------------------------------------
[14:15:02.663] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:17.781] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:32.802] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:47.945] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:03.082] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:18.049] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:33.044] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:48.173] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:03.436] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:18.578] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:18.956] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:18.974] INFO: ----------------------------------------------------------------------
[14:17:18.974] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[14:17:18.974] INFO: ----------------------------------------------------------------------
[14:17:18.974] INFO: ----------------------------------------------------------------------
[14:17:18.974] INFO: Read back bit status: 1
[14:17:18.974] INFO: ----------------------------------------------------------------------
[14:17:18.974] INFO: ----------------------------------------------------------------------
[14:17:18.974] INFO: Timings are good!
[14:17:18.974] INFO: ----------------------------------------------------------------------
[14:17:18.974] INFO: Test took 152416 ms.
[14:17:18.974] INFO: PixTestTiming::TimingTest() done.
[14:17:18.982] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//tbmParameters_C0a.dat
[14:17:18.982] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-11_FPIXTest-17C-FNAL-160520-1215_2016-05-20_12h15m_1463764545/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:17:18.982] INFO: PixTestTiming::doTest took 773136 ms.
[14:17:18.982] INFO: PixTestTiming::doTest() done
[14:17:18.982] INFO: Write out TBMPhaseScan_0_V0
[14:17:18.983] INFO: Write out TBMPhaseScan_1_V0
[14:17:18.983] INFO: Write out CombinedTBMPhaseScan_V0
[14:17:18.002] INFO: Write out ROCDelayScan3_V0
[14:17:18.002] INFO: enter test to run
[14:17:58.361] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:17:58.361] INFO: running: xray
[14:17:58.362] INFO: ----------------------------------------------------------------------
[14:17:58.362] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:17:58.362] INFO: ----------------------------------------------------------------------
[14:17:59.326] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:18:11.045] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:18:40.542] INFO: Resuming triggers.
[14:18:52.257] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:19:21.575] INFO: Resuming triggers.
[14:19:33.288] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[14:20:02.505] INFO: Resuming triggers.
[14:20:14.216] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:20:43.471] INFO: Resuming triggers.
[14:20:55.182] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[14:21:24.940] INFO: Resuming triggers.
[14:21:36.656] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[14:22:05.893] INFO: Resuming triggers.
[14:22:17.606] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[14:22:46.769] INFO: Resuming triggers.
[14:22:58.485] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[14:23:27.746] INFO: Resuming triggers.
[14:23:34.368] INFO: data taking finished, elapsed time: 100 seconds.
[14:23:51.135] INFO: PixTest:: pg_setup set to default.
[14:23:51.138] INFO: PixTestXray::doPhRun() done
[14:23:51.273] INFO: enter test to run
[14:25:34.814] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:25:34.814] INFO: running: xray
[14:25:34.815] INFO: ----------------------------------------------------------------------
[14:25:34.815] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:25:34.815] INFO: ----------------------------------------------------------------------
[14:25:35.780] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:25:42.643] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:26:12.622] INFO: Resuming triggers.
[14:26:19.481] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[14:26:50.390] INFO: Resuming triggers.
[14:26:57.250] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[14:27:28.453] INFO: Resuming triggers.
[14:27:35.319] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[14:28:05.782] INFO: Resuming triggers.
[14:28:12.642] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:28:43.931] INFO: Resuming triggers.
[14:28:50.796] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[14:29:21.812] INFO: Resuming triggers.
[14:29:28.674] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[14:29:58.814] INFO: Resuming triggers.
[14:30:05.672] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[14:30:36.107] INFO: Resuming triggers.
[14:30:42.969] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[14:31:12.512] INFO: Resuming triggers.
[14:31:19.370] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[14:31:49.183] INFO: Resuming triggers.
[14:31:56.045] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[14:32:26.068] INFO: Resuming triggers.
[14:32:32.931] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[14:33:02.775] INFO: Resuming triggers.
[14:33:09.634] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[14:33:39.251] INFO: Resuming triggers.
[14:33:46.116] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[14:34:15.738] INFO: Resuming triggers.
[14:34:20.021] INFO: data taking finished, elapsed time: 100 seconds.
[14:34:38.884] INFO: PixTest:: pg_setup set to default.
[14:34:38.887] INFO: PixTestXray::doPhRun() done
[14:34:39.036] INFO: enter test to run
[14:36:30.441] INFO: test: HighRate no parameter change
[14:36:30.441] INFO: running: highrate
[14:36:30.454] INFO: ----------------------------------------------------------------------
[14:36:30.454] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:36:30.454] INFO: ----------------------------------------------------------------------
[14:36:30.610] INFO: Expecting 768 events.
[14:36:31.744] INFO: 768 events read in total (418ms).
[14:36:31.744] INFO: Test took 1269ms.
[14:36:32.547] INFO: Expecting 41600 events.
[14:36:35.562] INFO: 41600 events read in total (2488ms).
[14:36:35.563] INFO: Test took 3811ms.
[14:36:35.594] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:36.325] INFO: Expecting 41600 events.
[14:36:39.488] INFO: 41600 events read in total (2637ms).
[14:36:39.489] INFO: Test took 3880ms.
[14:36:39.522] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:40.253] INFO: Expecting 41600 events.
[14:36:43.440] INFO: 41600 events read in total (2660ms).
[14:36:43.441] INFO: Test took 3903ms.
[14:36:43.474] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:44.200] INFO: Expecting 41600 events.
[14:36:47.396] INFO: 41600 events read in total (2669ms).
[14:36:47.397] INFO: Test took 3906ms.
[14:36:47.429] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:48.153] INFO: Expecting 41600 events.
[14:36:51.347] INFO: 41600 events read in total (2667ms).
[14:36:51.348] INFO: Test took 3901ms.
[14:36:51.384] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:52.108] INFO: Expecting 41600 events.
[14:36:55.294] INFO: 41600 events read in total (2660ms).
[14:36:55.295] INFO: Test took 3894ms.
[14:36:55.327] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:56.055] INFO: Expecting 41600 events.
[14:36:59.156] INFO: 41600 events read in total (2574ms).
[14:36:59.157] INFO: Test took 3812ms.
[14:36:59.190] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:59.915] INFO: Expecting 41600 events.
[14:37:03.152] INFO: 41600 events read in total (2710ms).
[14:37:03.153] INFO: Test took 3947ms.
[14:37:03.186] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:03.909] INFO: Expecting 41600 events.
[14:37:07.125] INFO: 41600 events read in total (2689ms).
[14:37:07.126] INFO: Test took 3922ms.
[14:37:07.159] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:07.886] INFO: Expecting 41600 events.
[14:37:11.095] INFO: 41600 events read in total (2682ms).
[14:37:11.096] INFO: Test took 3921ms.
[14:37:11.128] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:11.853] INFO: Expecting 41600 events.
[14:37:15.061] INFO: 41600 events read in total (2681ms).
[14:37:15.062] INFO: Test took 3917ms.
[14:37:15.094] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:15.822] INFO: Expecting 41600 events.
[14:37:19.019] INFO: 41600 events read in total (2670ms).
[14:37:19.020] INFO: Test took 3909ms.
[14:37:19.052] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:19.783] INFO: Expecting 41600 events.
[14:37:22.880] INFO: 41600 events read in total (2571ms).
[14:37:22.881] INFO: Test took 3812ms.
[14:37:22.914] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:23.638] INFO: Expecting 41600 events.
[14:37:26.853] INFO: 41600 events read in total (2688ms).
[14:37:26.854] INFO: Test took 3922ms.
[14:37:26.886] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:27.613] INFO: Expecting 41600 events.
[14:37:30.830] INFO: 41600 events read in total (2691ms).
[14:37:30.831] INFO: Test took 3926ms.
[14:37:30.863] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:31.592] INFO: Expecting 41600 events.
[14:37:34.803] INFO: 41600 events read in total (2684ms).
[14:37:34.804] INFO: Test took 3922ms.
[14:37:34.837] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:35.565] INFO: Expecting 41600 events.
[14:37:38.747] INFO: 41600 events read in total (2655ms).
[14:37:38.748] INFO: Test took 3895ms.
[14:37:38.780] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:39.508] INFO: Expecting 41600 events.
[14:37:42.576] INFO: 41600 events read in total (2541ms).
[14:37:42.577] INFO: Test took 3779ms.
[14:37:42.609] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:43.337] INFO: Expecting 41600 events.
[14:37:46.522] INFO: 41600 events read in total (2658ms).
[14:37:46.523] INFO: Test took 3896ms.
[14:37:46.554] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:47.282] INFO: Expecting 41600 events.
[14:37:50.322] INFO: 41600 events read in total (2513ms).
[14:37:50.323] INFO: Test took 3750ms.
[14:37:50.355] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:50.722] INFO: enter test to run
[14:39:34.519] INFO: test: HighRate no parameter change
[14:39:34.519] INFO: running: highrate
[14:39:34.521] INFO: ----------------------------------------------------------------------
[14:39:34.521] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:39:34.521] INFO: ----------------------------------------------------------------------
[14:39:35.136] INFO: Expecting 208000 events.
[14:39:46.756] INFO: 208000 events read in total (11093ms).
[14:39:46.759] INFO: Test took 12230ms.
[14:39:46.898] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:47.150] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:39:47.151] INFO: number of red-efficiency pixels: 72 46 81 126 141 118 122 88 85 84 132 92 94 51 16 39
[14:39:47.151] INFO: number of X-ray hits detected: 60908 41189 62541 99630 111191 110419 109732 77863 76735 85768 89563 78795 77277 45784 18485 24344
[14:39:47.151] 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:39:47.151] INFO: number of Vcal hits detected: 207927 207953 207917 207872 207853 207880 207874 207910 207915 207914 207862 207902 207905 207949 207984 207961
[14:39:47.151] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0
[14:39:47.151] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0
[14:39:47.151] INFO: X-ray hit rate [MHz/cm2]: 17.9 12.1 18.3 29.2 32.6 32.4 32.2 22.8 22.5 25.1 26.3 23.1 22.7 13.4 5.4 7.1
[14:39:47.151] INFO: PixTestHighRate::doXPixelAlive() done
[14:39:47.195] INFO: PixTest:: pg_setup set to default.
[14:39:47.205] INFO: enter test to run
[14:40:10.191] INFO: test: HighRate no parameter change
[14:40:10.191] INFO: running: highrate
[14:40:10.192] INFO: ----------------------------------------------------------------------
[14:40:10.192] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:40:10.192] INFO: ----------------------------------------------------------------------
[14:40:10.811] INFO: Expecting 208000 events.
[14:40:24.406] INFO: 208000 events read in total (13068ms).
[14:40:24.411] INFO: Test took 14209ms.
[14:40:24.697] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:24.997] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:40:24.997] INFO: number of red-efficiency pixels: 213 108 257 463 508 389 377 215 221 250 414 290 282 146 42 68
[14:40:24.997] INFO: number of X-ray hits detected: 127683 86607 131801 210213 233328 232649 230859 164701 161828 180054 186329 166345 162774 96360 39266 51065
[14:40:24.997] 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:40:24.997] INFO: number of Vcal hits detected: 207774 207884 207713 207512 207452 207575 207594 207769 207770 207730 207550 207701 207708 207846 207958 207929
[14:40:24.997] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[14:40:24.997] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[14:40:24.997] INFO: X-ray hit rate [MHz/cm2]: 37.4 25.4 38.6 61.6 68.4 68.2 67.7 48.3 47.4 52.8 54.6 48.8 47.7 28.2 11.5 15.0
[14:40:24.997] INFO: PixTestHighRate::doXPixelAlive() done
[14:40:25.042] INFO: PixTest:: pg_setup set to default.
[14:40:25.053] INFO: enter test to run
[14:40:50.655] INFO: test: HighRate no parameter change
[14:40:50.655] INFO: running: highrate
[14:40:50.656] INFO: ----------------------------------------------------------------------
[14:40:50.656] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:40:50.656] INFO: ----------------------------------------------------------------------
[14:40:51.277] INFO: Expecting 208000 events.
[14:41:06.888] INFO: 208000 events read in total (15085ms).
[14:41:06.895] INFO: Test took 16229ms.
[14:41:07.331] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:07.680] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:41:07.680] INFO: number of red-efficiency pixels: 450 253 597 1035 1181 909 886 505 530 570 890 641 649 274 76 132
[14:41:07.680] INFO: number of X-ray hits detected: 193875 131422 201264 318078 354900 352892 348989 250082 246767 274372 282937 251775 246822 145783 59429 78130
[14:41:07.680] 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:41:07.680] INFO: number of Vcal hits detected: 207460 207729 207231 206701 206494 206892 206949 207410 207408 207342 206946 207268 207274 207707 207923 207865
[14:41:07.680] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.4 99.3 99.5 99.5 99.7 99.7 99.7 99.5 99.7 99.7 99.9 100.0 99.9
[14:41:07.680] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.6 99.4 99.3 99.5 99.5 99.7 99.7 99.7 99.5 99.6 99.7 99.9 100.0 99.9
[14:41:07.680] INFO: X-ray hit rate [MHz/cm2]: 56.8 38.5 59.0 93.2 104.0 103.4 102.3 73.3 72.3 80.4 82.9 73.8 72.3 42.7 17.4 22.9
[14:41:07.680] INFO: PixTestHighRate::doXPixelAlive() done
[14:41:07.726] INFO: PixTest:: pg_setup set to default.
[14:41:07.744] INFO: enter test to run
[14:41:11.087] INFO: test: exit no parameter change
[14:41:11.482] QUIET: Connection to board 33 closed.
[14:41:11.483] INFO: pXar: this is the end, my friend