[16:51:39.965] INFO: *** Welcome to pxar ***
[16:51:39.965] INFO: *** Today: 2016/04/01
[16:51:39.984] INFO: *** Version: v1.9.0-793-ge521-dirty
[16:51:39.984] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//dacParameters35_C15.dat
[16:51:40.008] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//tbmParameters_C0b.dat
[16:51:40.008] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//defaultMaskFile.dat
[16:51:40.014] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C15.dat
[16:51:40.097] INFO: clk: 4
[16:51:40.097] INFO: ctr: 4
[16:51:40.097] INFO: sda: 19
[16:51:40.097] INFO: tin: 9
[16:51:40.097] INFO: level: 15
[16:51:40.097] INFO: triggerdelay: 0
[16:51:40.097] QUIET: Instanciating API for pxar v1.9.0+793~ge5211a7
[16:51:40.097] INFO: Log level: INFO
[16:51:40.116] QUIET: Connection to board DTB_WREKRL opened.
[16:51:40.120] 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:
------------------------------------------------------
[16:51:40.123] INFO: RPC call hashes of host and DTB match: 398089610
[16:51:41.659] INFO: DUT info:
[16:51:41.659] INFO: The DUT currently contains the following objects:
[16:51:41.659] INFO: 2 TBM Cores tbm08c (2 ON)
[16:51:41.659] INFO: TBM Core alpha (0): 7 registers set
[16:51:41.659] INFO: TBM Core beta (1): 7 registers set
[16:51:41.659] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:51:41.659] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:41.659] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:51:42.064] INFO: enter 'restricted' command line mode
[16:51:42.064] INFO: enter test to run
[16:51:50.475] INFO: test: PixelAlive no parameter change
[16:51:50.475] INFO: running: pixelalive
[16:51:50.485] INFO: ----------------------------------------------------------------------
[16:51:50.485] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:51:50.485] INFO: ----------------------------------------------------------------------
[16:51:50.806] INFO: Expecting 41600 events.
[16:51:54.898] INFO: 41600 events read in total (3374ms).
[16:51:55.069] INFO: Test took 4580ms.
[16:51:55.076] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:51:55.338] INFO: PixTestAlive::aliveTest() done
[16:51:55.338] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0 34
[16:51:55.363] INFO: enter test to run
[16:52:37.555] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[16:52:37.555] INFO: running: highrate
[16:52:37.555] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[16:52:37.770] INFO: ----------------------------------------------------------------------
[16:52:37.770] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[16:52:37.770] INFO: ----------------------------------------------------------------------
[16:52:37.770] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[16:52:37.770] INFO: edge/corner pixel THR is adjusted
[16:52:37.770] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:52:38.730] INFO: Collecting data for 5 seconds...
[16:52:43.747] INFO: Done with hot pixel readout
[16:52:55.367] INFO: PixTest:: pg_setup set to default.
[16:52:55.368] INFO: 25 hot pixels found in step 0
[16:52:56.355] INFO: Collecting data for 5 seconds...
[16:53:01.371] INFO: Done with hot pixel readout
[16:53:13.036] INFO: PixTest:: pg_setup set to default.
[16:53:13.037] INFO: 26 hot pixels found in step 1
[16:53:14.028] INFO: Collecting data for 5 seconds...
[16:53:19.044] INFO: Done with hot pixel readout
[16:53:30.638] INFO: PixTest:: pg_setup set to default.
[16:53:30.639] INFO: 21 hot pixels found in step 2
[16:53:31.629] INFO: Collecting data for 5 seconds...
[16:53:36.645] INFO: Done with hot pixel readout
[16:53:48.247] INFO: PixTest:: pg_setup set to default.
[16:53:48.248] INFO: 20 hot pixels found in step 3
[16:53:49.237] INFO: Collecting data for 5 seconds...
[16:53:54.254] INFO: Done with hot pixel readout
[16:54:05.887] INFO: PixTest:: pg_setup set to default.
[16:54:05.888] INFO: 17 hot pixels found in step 4
[16:54:06.878] INFO: Collecting data for 5 seconds...
[16:54:11.895] INFO: Done with hot pixel readout
[16:54:23.490] INFO: PixTest:: pg_setup set to default.
[16:54:23.491] INFO: 11 hot pixels found in step 5
[16:54:24.482] INFO: Collecting data for 5 seconds...
[16:54:29.498] INFO: Done with hot pixel readout
[16:54:41.129] INFO: PixTest:: pg_setup set to default.
[16:54:41.130] INFO: 6 hot pixels found in step 6
[16:54:42.120] INFO: Collecting data for 5 seconds...
[16:54:47.136] INFO: Done with hot pixel readout
[16:54:58.760] INFO: PixTest:: pg_setup set to default.
[16:54:58.761] INFO: 5 hot pixels found in step 7
[16:54:59.751] INFO: Collecting data for 5 seconds...
[16:55:04.768] INFO: Done with hot pixel readout
[16:55:16.382] INFO: PixTest:: pg_setup set to default.
[16:55:16.383] INFO: 5 hot pixels found in step 8
[16:55:17.374] INFO: Collecting data for 5 seconds...
[16:55:22.391] INFO: Done with hot pixel readout
[16:55:34.015] INFO: PixTest:: pg_setup set to default.
[16:55:34.015] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:55:34.016] INFO: 3 hot pixels found in step 9
[16:55:35.006] INFO: Collecting data for 5 seconds...
[16:55:40.022] INFO: Done with hot pixel readout
[16:55:51.587] INFO: PixTest:: pg_setup set to default.
[16:55:51.587] INFO: 1 hot pixels found in step 10
[16:55:52.579] INFO: Collecting data for 5 seconds...
[16:55:57.595] INFO: Done with hot pixel readout
[16:56:09.210] INFO: PixTest:: pg_setup set to default.
[16:56:09.211] INFO: 4 hot pixels found in step 11
[16:56:10.202] INFO: Collecting data for 5 seconds...
[16:56:15.218] INFO: Done with hot pixel readout
[16:56:26.835] INFO: PixTest:: pg_setup set to default.
[16:56:26.835] INFO: 6 hot pixels found in step 12
[16:56:27.825] INFO: Collecting data for 5 seconds...
[16:56:32.842] INFO: Done with hot pixel readout
[16:56:44.453] INFO: PixTest:: pg_setup set to default.
[16:56:44.454] INFO: 1 hot pixels found in step 13
[16:56:45.444] INFO: Collecting data for 5 seconds...
[16:56:50.460] INFO: Done with hot pixel readout
[16:57:02.095] INFO: PixTest:: pg_setup set to default.
[16:57:02.095] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:57:02.095] INFO: 2 hot pixels found in step 14
[16:57:02.129] INFO: 2 hot pixels could not be trimmed and have been masked.
[16:57:02.133] INFO: PixTest::trimHotPixels() done
[16:57:02.133] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C0.dat
[16:57:02.139] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C1.dat
[16:57:02.144] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C2.dat
[16:57:02.149] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C3.dat
[16:57:02.155] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C4.dat
[16:57:02.160] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C5.dat
[16:57:02.165] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C6.dat
[16:57:02.170] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C7.dat
[16:57:02.175] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C8.dat
[16:57:02.181] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C9.dat
[16:57:02.186] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C10.dat
[16:57:02.191] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C11.dat
[16:57:02.196] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C12.dat
[16:57:02.202] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C13.dat
[16:57:02.207] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C14.dat
[16:57:02.212] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C15.dat
[16:57:02.217] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//defaultMaskFile.dat
[16:57:02.227] INFO: enter test to run
[16:57:21.137] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[16:57:21.137] INFO: running: highrate
[16:57:21.141] INFO: ----------------------------------------------------------------------
[16:57:21.141] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[16:57:21.141] INFO: ----------------------------------------------------------------------
[16:57:21.141] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[16:57:21.141] INFO: edge/corner pixel THR is adjusted
[16:57:21.141] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:57:22.099] INFO: Collecting data for 1 seconds...
[16:57:23.102] INFO: Done with hot pixel readout
[16:57:26.978] INFO: PixTest:: pg_setup set to default.
[16:57:26.979] INFO: 0 hot pixels found in step 0
[16:57:26.984] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:57:27.081] INFO: PixTest::trimHotPixels() done
[16:57:27.082] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C0.dat
[16:57:27.091] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C1.dat
[16:57:27.097] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C2.dat
[16:57:27.102] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C3.dat
[16:57:27.107] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C4.dat
[16:57:27.112] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C5.dat
[16:57:27.117] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C6.dat
[16:57:27.123] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C7.dat
[16:57:27.128] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C8.dat
[16:57:27.133] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C9.dat
[16:57:27.138] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C10.dat
[16:57:27.144] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C11.dat
[16:57:27.149] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C12.dat
[16:57:27.154] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C13.dat
[16:57:27.159] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C14.dat
[16:57:27.164] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//trimParameters35_C15.dat
[16:57:27.169] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-J-4-11_FPIXTest-17C-FNAL-160331-1154_2016-03-31_11h54m_1459443258/000_FPIXTest_p17//defaultMaskFile.dat
[16:57:27.180] INFO: enter test to run
[16:57:56.280] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[16:57:56.281] INFO: running: xray
[16:57:56.282] INFO: ----------------------------------------------------------------------
[16:57:56.282] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:57:56.282] INFO: ----------------------------------------------------------------------
[16:57:57.245] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:58:08.936] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[16:58:38.128] INFO: Resuming triggers.
[16:58:49.823] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[16:59:19.241] INFO: Resuming triggers.
[16:59:30.937] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[17:00:00.239] INFO: Resuming triggers.
[17:00:11.941] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[17:00:41.311] INFO: Resuming triggers.
[17:00:53.009] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[17:01:22.310] INFO: Resuming triggers.
[17:01:34.010] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[17:01:34.068] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (53) != TBM ID (54)
[17:01:34.068] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (53) != TBM ID (54)
[17:01:34.068] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:01:34.068] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a039 80c0 43c8 20d 282f 43c8 43c8 43c8 43c8 43c8 545 222d 43c8 43c8 e000 c000
[17:01:34.068] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a032 8000 4388 4388 512 284d 4389 4389 4388 4388 4389 40a 2440 4389 e000 c000
[17:01:34.068] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a033 8040 4388 4389 4388 4389 4389 91 2a4c 4389 4388 13 2a81 4389 609 2845 e000 c000
[17:01:34.068] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a034 80b1 4388 4388 4388 4388 4388 101 2260 155 284b 4388 4388 9b 208f 4388 e000 c000
[17:01:34.068] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a036 8000 43c8 22 284e 43c8 43c8 43c8 43c8 359 286c 43c8 43c8 218 2a45 43c8 e000 c000
[17:01:34.068] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a037 8040 43c8 43c8 43c8 43c8 43c8 6e2 244f 43c8 a 2845 43c8 43c8 e000 c000
[17:01:34.068] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a038 80b1 43c8 43c8 43c8 43c8 43c8 43c8 43c8 68a 2647 43c8 e000 c000
[17:01:34.068] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:01:34.068] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a139 80c0 43c8 43c8 500 2425 43c8 43c8 21d 2823 40b 2247 43c8 43c8 481 2683 43c8 43c8 e000 c000
[17:01:34.068] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a132 8000 4388 4388 660 2843 4389 4389 4388 4388 4389 4389 e000 c000
[17:01:34.069] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a133 8040 4388 4389 4388 4389 4388 4388 4388 4389 e000 c000
[17:01:34.069] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a134 80b1 4388 4388 4388 4388 4388 dc 2046 4388 4388 4388 e000 c000
[17:01:34.069] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a136 8000 43c8 43c8 43c8 710 2a2a 858 2646 43c8 43c8 d1 284b 43c8 43c8 43c8 e000 c000
[17:01:34.069] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a137 8040 43c8 43c8 249 2421 249 202d 43c8 15c 2045 43c8 43c8 43c8 43c8 43c8 e000 c000
[17:01:34.069] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a138 80b1 43c8 43c8 43c8 43c8 4d8 222c 43c8 48d 2448 43c8 43c8 43c8 415 2881 e000 c000
[17:01:34.069] WARNING: Channel 1 ROC 5: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 0 ROC 5: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 0 ROC 6: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 0 ROC 7: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 1 ROC 4: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 1 ROC 6: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 1 ROC 7: Readback start marker after 15 readouts!
[17:01:34.069] WARNING: Channel 0 ROC 4: Readback start marker after 15 readouts!
[17:01:34.069] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:01:34.069] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a142 8000 43c8 204 222f 43c8 43c9 43c9 43c8 35d 284f 43c8 43c9 43c9 80 2466 e000 c000
[17:01:34.069] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13c 80b1 43c8 51d 2640 43c8 14b 282f 560 224a 43c8 43c8 43c8 43c8 53 286f 43c8 43c8 e000 c000
[17:01:34.069] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13d 80c0 43c8 43c8 43c8 43c8 40c 204d 43c8 43ca 43c8 43c8 e000 c000
[17:01:34.069] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13e 8000 43c8 43c8 43c8 70b 2848 43c8 4ca 2646 43c8 64a 2a27 43c9 43c8 43c8 e000 c000
[17:01:34.069] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a13f 8040 43ca 43ca 43ca 43ca 43ca 11a 2847 43c9 43ca 43ca e000 c000
[17:01:34.069] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a140 80b1 43c9 43c9 43c9 43c9 43c9 43c8 41a 2044 43c9 43c9 e000 c000
[17:01:34.069] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a141 80c0 43c8 43c8 43c8 2a2 266d 43c8 43c9 153 2a42 28a 222d 43c9 43c9 43c9 e000 c000
[17:01:34.069] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:01:34.069] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a044 80b1 43c8 43c8 43c8 43c8 1b 244f 43c8 43c8 43c8 610 2246 43c8 d 2042 e000 c000
[17:01:34.070] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03e 8000 43c8 43c8 458 2647 43c8 43c8 43c8 d8 2845 43c8 43c8 43c8 418 244c e000 c000
[17:01:34.070] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03f 8040 43ca 43ca 45c 2a2b 43ca 4d4 2641 43ca 143 2049 43c8 43ca 43ca 43ca e000 c000
[17:01:34.070] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a040 80b1 43c8 43c8 43c8 43c8 43c8 685 2447 43c8 43c8 43c8 e000 c000
[17:01:34.070] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a041 80c0 43c8 113 2a48 43c8 43c8 611 262a 43c8 495 242e 43ca 603 2a4b 43c9 20c 2429 43c9 43c9 e000 c000
[17:01:34.070] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a042 8000 43c8 61 2064 43c8 43c9 54a 2640 43c9 43c8 43c8 43c9 55b 264c 43c9 e000 c000
[17:01:34.070] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a043 8040 43c8 43c9 43c8 43c9 43c9 43c9 43c8 58 2040 43c9 e000 c000
[17:02:03.412] INFO: Resuming triggers.
[17:02:15.114] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[17:02:42.598] INFO: Resuming triggers.
[17:02:54.309] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[17:03:23.543] INFO: Resuming triggers.
[17:03:30.264] INFO: data taking finished, elapsed time: 100 seconds.
[17:03:47.336] INFO: PixTest:: pg_setup set to default.
[17:03:47.339] INFO: PixTestXray::doPhRun() done
[17:03:47.500] INFO: enter test to run
[17:04:27.205] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[17:04:27.205] INFO: running: xray
[17:04:27.207] INFO: ----------------------------------------------------------------------
[17:04:27.207] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:04:27.207] INFO: ----------------------------------------------------------------------
[17:04:28.175] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:04:35.217] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[17:05:05.316] INFO: Resuming triggers.
[17:05:12.362] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[17:05:42.392] INFO: Resuming triggers.
[17:05:49.438] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[17:05:49.758] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[17:05:49.758] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:05:49.758] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a148 80b1 43c8 43c8 43c8 610 2a4f 43c8 43c8 11 284f 9a 2265 705 2a6a 43c8 24c 282d 43c8 43c8 e000 c000
[17:05:49.758] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a142 8000 4388 4388 a 2228 219 242d 650 2842 711 264f 813 2a4f 4389 4389 118 2268 4388 4388 412 2648 4389 4389 e000 c000
[17:05:49.758] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a143 8040 4388 4389 4388 1c 242f 4389 15c 242d 4388 b 264f 320 2048 4388 45b 262f 4388 219 284d 4389 e000 c000
[17:05:49.758] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a144 80b1 4388 4388 48b 2668 4388 4388 48b 242c 4388 4388 2dd 2261 4388 4388 e000 c000
[17:05:49.758] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a145 80c0 4388 4388 705 2429 43c8 292 2a6d 556 2aa8 10 2e81 2e80 1aa 2913 32 43c8 43c8 43c8 42 2463 e000 c000
[17:05:49.758] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a146 8000 43c8 451 2028 50c 2041 43c8 43c8 cc 2241 43c8 461 244f 43c8 293 224f 60b 2285 60d 2a8c 759 2067 43c8 43c8 43c8 e000 c000
[17:05:49.758] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a147 8040 43c8 43c8 b 2a65 43c8 715 2849 43c8 1 2069 251 204d 43c8 45b 224f 43c8 43c8 43c8 e000 c000
[17:06:19.424] INFO: Resuming triggers.
[17:06:26.469] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[17:06:56.486] INFO: Resuming triggers.
[17:07:03.530] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[17:07:33.543] INFO: Resuming triggers.
[17:07:40.586] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[17:08:10.679] INFO: Resuming triggers.
[17:08:17.720] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[17:08:47.702] INFO: Resuming triggers.
[17:08:54.743] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[17:08:54.987] WARNING: Channel 1 ROC 6: Readback start marker after 13 readouts!
[17:08:54.987] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[17:08:54.987] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:08:54.987] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10f 8040 43ca 54a 2844 43ca 209 202a 412 282f 713 226e 761 2a6a 43ca 24c 2040 2d4 224f 2dc 264f 65d 204f 70a 2a61 43ca c 2044 43ca 43c9 348 2828 43ca 54a 2047 43ca 2d9 286d 2cb 2081 e000 c000
[17:08:54.987] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a109 80c0 4388 345 2826 4388 3 2261 4388 5 202a 58 244f 61c 2a42 4388 4388 d8 224f 4388 d 2424 4388 4388 71b 2884 e000 c000
[17:08:54.987] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10a 8000 4388 4388 1 2040 481 2848 501 242a 4388 549 2441 4388 81d 204b 4388 4388 8 282d 4388 21c 2023 698 2a40 4388 e000 c000
[17:08:54.987] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10b 8040 4388 490 2024 4388 49 2644 150 2842 809 202d 4388 48b 2049 48b 2649 855 2244 4388 4388 299 264c 4dc 2642 680 2645 4388 311 244a 308 2245 353 2460 4388 4388 9a 266d e000 c000
[17:08:54.987] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10c 80b1 4388 4388 61a 2269 4388 13 264a 4388 4388 35b 244f 4d5 2248 60c 2482 4388 d 2a4f 5a 2260 43cb e000 c000
[17:08:54.987] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10d 80c0 43c8 43c8 6da 264f 43c8 28d 266d 35b 2244 498 2a44 43c8 851 2862 43c8 43ca 111 2864 43c8 43c8 e000 c000
[17:08:54.987] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10e 8000 43c8 49b 244e 43c8 408 2449 43c8 10b 2a6d 515 244a 55d 2a4f 64a 2044 649 266a 43c8 809 2049 85d 284b 43c8 43c9 43c8 43c8 610 2a45 e000 c000
[17:09:24.857] INFO: Resuming triggers.
[17:09:31.897] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[17:10:02.012] INFO: Resuming triggers.
[17:10:09.052] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[17:10:39.029] INFO: Resuming triggers.
[17:10:46.070] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[17:10:46.427] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (2) != Token Chain Length (8)
[17:10:46.427] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (5) != Token Chain Length (8)
[17:10:46.427] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:10:46.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0dd 80c0 43c8 43c8 113 2228 43c8 43c8 21c 244f 51a 2462 70d 284e 43c8 258 2089 409 2869 55d 2269 43c8 44d 220f 43c8 43c8 e000 c000
[17:10:46.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d7 8040 4388 4388 4388 4388 68b 224f 850 264c 4388 519 2462 714 2864 4388 101 242d 4388 9d 246a 203 286f 41b 2063 458 286a 554 2a45 60c 2a44 614 2281 75c 264f 4388 5c 262d 111 2264 4d0 2065 e000 c000
[17:10:46.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d8 80b1 4388 4388 4388 4388 289 2869 55a 226f 858 2084 860 2245 4388 661 2647 4388 4c8 224b 4388 4388 5a 2245 714 2849 e000 c000
[17:10:46.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d9 80c0 4388 4388 115 2442 4388 4388 64c 244c 69b 2262 69d 224a 752 226a 4388 152 2249 699 2468 6c8 2447 814 2a26 4388 5a 2027 4388 40b 2862 40c 266a 74b 266f 4388 54 2048 e000 c000
[17:10:46.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0da 8000 7ff 2e65 156b 2e65 d67 2fdf ffe 26fd 15f5 24fe bd3 2f5f 1fff 2296 f7d 2efe 55c 2649 43c8 8d 224c 43c8 cc 2442 34d 2040 618 264a e000 c000
[17:10:46.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0db 8040 43c8 218 2426 43c8 118 282d 43c8 611 2025 43c8 80 2621 d2 2a4f 65a 2669 43c8 43c8 cc 2842 43c8 344 2645 43c8 e000 c000
[17:10:46.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0dc 80b1 43c8 43c8 43c8 43c8 861 2668 43c8 dd 2848 41a 244e 518 2842 43c8 24a 264f 43c8 14b 264e 612 2845 43c8 24a 2a40 2cd 204d 31b 2885 e000 c000
[17:10:46.427] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:10:46.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1dd 80c0 43c8 43c8 43c8 43c8 6d8 2a2f 712 242d 43c8 43ca 43c8 43c8 e000 c000
[17:10:46.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d7 8040 4388 11a 2463 4388 24d 2827 4388 4388 4388 9a 2844 454 2445 850 2245 4388 15 2243 31c 2445 353 224d 453 282f 4388 4388 e000 c000
[17:10:46.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d8 80b1 4388 242 2a42 243 2263 643 2a2f 4388 4388 14d 2865 519 2a4e 4388 6d3 264c 861 2227 4388 15 264f 350 204f 45d 204f 4388 4388 4388 e000 c000
[17:10:46.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1d9 80c0 4388 d0 2642 2c8 2860 402 2245 855 224a 85a 2a43 4388 6cc 2845 4388 149 2642 149 2a62 558 2649 80b 2666 4388 4388 b 2265 60d 2085 4388 242 2241 4388 4388 662 2262 e000 c000
[17:10:46.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1da 8000 7ff 2c0f 1c03 2c0f f 293a f6b 2f4a 1a6b 2a01 6ff 2af0 1022 200f ace 2f69 43c8 43c8 43c8 43c8 852 2865 844 2040 43c8 e000 c000
[17:10:46.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1db 8040 43c8 43c8 43c8 745 2040 43c8 2d3 2042 43c8 491 264a 51b 2466 43c8 43c8 43c8 9c 2865 e000 c000
[17:10:46.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1dc 80b1 43c8 854 2240 43c8 43c8 43c8 515 2444 43c8 43c8 14 282d 400 2481 43c8 43c8 660 2860 e000 c000
[17:11:16.167] INFO: Resuming triggers.
[17:11:23.209] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[17:11:53.312] INFO: Resuming triggers.
[17:12:00.357] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[17:12:00.570] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (245) != TBM ID (246)
[17:12:00.570] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (245) != TBM ID (246)
[17:12:00.570] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:12:00.570] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f9 80c0 43c8 53 2040 43c8 43c8 43c8 43c8 d0 2447 355 224f 4dd 264f 43c8 21a 202d 43c8 310 2827 43c8 10 2a4d e000 c000
[17:12:00.570] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f2 8000 4388 4388 55 284f 4389 85c 2a44 4389 745 284c 4388 9a 2684 21d 2840 4a2 2a60 4388 24b 2066 28d 2645 30a 2a64 4389 410 284f 761 266f 4389 34d 2066 e000 c000
[17:12:00.570] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f3 8040 4388 159 2242 250 2864 4389 111 202f 21b 2062 4388 602 2a23 84d 2a49 4389 14 2861 851 2a4f 4389 152 224c 252 2a6f 519 2461 4389 a1 264b 4388 15 2647 35c 2061 4389 114 2629 e000 c000
[17:12:00.570] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f4 80b1 4388 490 2040 498 2a2f 4388 4388 4388 19 2462 9 2449 518 2662 4388 290 206c 2d8 224d 6d4 246c 4388 31d 2a6c 489 202f 811 2a2f 4388 84d 204a 4388 110 2429 e000 c000
[17:12:00.570] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f6 8000 43c8 43c8 43c8 43c8 43c8 11 224f 602 2045 6e1 246f 43c8 43c8 94 2262 10d 2468 43c8 491 2843 e000 c000
[17:12:00.570] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f7 8040 43c8 43c8 5b 266f 43c8 15c 2a29 651 224c 43c8 848 224a 43c8 4a 2241 162 2a62 43c8 41 224a 211 2644 40c 2244 44b 2a45 610 2a45 43c8 9c 244d 504 2642 760 244d 43c8 29c 2049 e000 c000
[17:12:00.570] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f8 80b1 43c8 485 2a4f 43c8 20 244a 43c8 43c8 812 2465 43c8 21a 244a 43c8 43c8 45a 2a4f 74b 284b 43c8 48a 2a2c 6a1 2642 e000 c000
[17:12:00.570] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:12:00.570] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f9 80c0 43c8 45c 222d 43c8 43c8 109 226f 43c8 43c8 8d 2029 553 2263 43c8 360 284a 43c8 705 2665 43c8 da 2a6f e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f2 8000 4388 4388 720 2225 4389 501 2840 4389 4388 210 282c 4ca 2a4b 4388 4389 4389 e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f3 8040 4388 151 2447 4389 4388 154 264d 4389 4388 4388 4388 4389 2c1 2282 e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f4 80b1 4388 6da 2840 4388 2e1 2066 4c9 264f 54d 2842 4388 71a 266d 4388 541 2a25 545 2645 4388 414 2046 4388 4388 29a 2448 4388 e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f6 8000 43c8 43c8 6c9 2a4c 43c8 85 2447 40b 2263 448 2864 54b 246c 43c8 43c8 43c8 b 228c 358 2241 43c8 43c8 e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f7 8040 43c8 498 2a26 699 2842 43c8 508 220f 43c8 120 284f 43c8 1a 2042 2c3 2445 660 282d 820 2662 43c8 58 2663 43c8 4 2029 43c8 43c8 e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f8 80b1 43c8 2d4 264c 43c8 19 286c 43c8 54b 2a6a 43c8 212 202f 43c8 610 2062 43c8 350 2645 43c8 43c8 e000 c000
[17:12:00.571] WARNING: Channel 1 ROC 5: Readback start marker after 15 readouts!
[17:12:00.571] WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!
[17:12:00.571] WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!
[17:12:00.571] WARNING: Channel 0 ROC 5: Readback start marker after 15 readouts!
[17:12:00.571] WARNING: Channel 0 ROC 6: Readback start marker after 15 readouts!
[17:12:00.571] WARNING: Channel 0 ROC 7: Readback start marker after 15 readouts!
[17:12:00.571] WARNING: Channel 1 ROC 6: Readback start marker after 15 readouts!
[17:12:00.571] WARNING: Channel 1 ROC 7: Readback start marker after 15 readouts!
[17:12:00.571] WARNING: Channel 0 ROC 4: Readback start marker after 15 readouts!
[17:12:00.571] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a102 8000 43c8 2d9 2668 651 2a49 6d1 242f 43c8 43c9 10a 2462 43c9 43c8 488 244c 43c8 43c9 43c9 e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fc 80b1 43c8 43c8 43c8 d1 2062 43c8 43c8 34b 2845 43c8 43c8 71c 2a41 43c8 d0 2a6c 4dc 206c e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fd 80c0 43c8 14d 2842 43c8 43c8 21 2240 709 2a45 43c8 809 2049 43c8 810 2049 43ca 1a 2041 34b 2a6f 43c8 29a 2641 61d 222d 43c8 e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fe 8000 43c8 483 262c 51b 2065 43c8 5c 2248 41d 2046 43c8 43c8 43c8 21a 2a42 611 266c 43c9 253 2061 43c8 43c8 a1 226f e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ff 8040 43ca 704 260f 43ca 108 2a4d 43ca 43ca 43ca 6d3 2468 43c9 495 2468 43ca 43ca e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a100 80b1 43c9 709 242c 43c9 20 284f 54d 2240 43c9 43c9 141 2829 31c 2649 43c9 714 284c 43c8 43c9 43c9 e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a101 80c0 43c8 260 2a40 608 282f 43c8 29b 2a4a 43c8 354 2a48 419 2064 481 2242 43c8 359 2247 81b 224c 43c9 440 2a49 43c9 43c9 43c9 e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a004 80b1 43c8 1d 2680 43c8 43c8 862 224a 43c8 492 246f 43c8 4d2 286a 43c8 10 2042 4b 224d 35d 286e 43c8 4cc 2042 43c8 45a 246f e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fe 8000 43c8 43c8 43c8 2cc 244c 2da 2264 31a 2027 43c8 9b 2a42 6d0 244c 6c8 204f 755 2265 814 284c 43c8 14d 2244 659 2069 43c8 118 2a2f 43c8 65d 2866 43c8 e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ff 8040 43ca 43ca 43ca 50a 204a 71c 2440 43ca 2c5 2a8a 85a 2066 84c 2243 43c8 43ca 119 2842 71b 2242 43ca 43ca 19 2447 318 2a64 e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a000 80b1 43c8 43c8 43c8 74c 2648 43c8 d 224a 28d 2a62 4e2 226a 43c8 2cd 2040 2dc 2282 51b 244c 43c8 4d 2848 253 244f 43c8 59 244f 43c8 34b 264c e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a001 80c0 43c8 519 2642 43c8 43c8 608 2025 43c8 62 264f 353 2a4c 43ca 43c9 43c9 43c9 6cc 2224 e000 c000
[17:12:00.571] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a002 8000 43c8 694 2248 43c8 43c9 4cd 204c 50b 284f 70d 2644 43c9 d9 204e 302 2a6d 31b 2682 659 206f 43c8 43c8 253 264f 31b 286c 43c9 702 264f 81d 2a4c 43c9 31c 2841 e000 c000
[17:12:00.572] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a003 8040 43c8 855 2240 43c9 43c8 43c9 43c9 43c9 43c8 95 228f 549 2682 43c9 290 2862 40b 204f e000 c000
[17:12:27.628] INFO: Resuming triggers.
[17:12:34.674] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[17:12:34.789] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 3 ROCs were found
[17:12:34.789] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (4) != Token Chain Length (8)
[17:12:34.789] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (123) != TBM ID (63)
[17:12:34.789] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (0) != Token Chain Length (8)
[17:12:34.789] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (64) != TBM ID (123)
[17:12:34.789] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:12:34.789] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17d 80c0 43c8 c9 222e 85a 2a2d 43c8 d2 2440 309 224e 658 244d 703 2247 43c8 18 242a 35a 264a 558 204a 43c8 43c8 43ca 59 2265 241 2a29 51b 2049 43c8 43c8 e000 c000
[17:12:34.789] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a177 8040 4388 4388 64c 2843 4388 4388 220 2082 4388 2dd 2a6d 352 2a63 4388 2c2 2642 2d4 2a41 359 2641 4388 4388 e000 c000
[17:12:34.789] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a178 80b1 4388 4388 40c 282f 4388 55d 2a45 4388 445 2649 4388 740 2a42 4388 4388 4388 e000 c000
[17:12:34.789] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a179 80c0 4388 10b 2449 4388 2d5 284d 4388 55b 2068 4388 1d 204b 4388 6c4 262d 4388 6db 2a66 4388 4388 754 2649 e000 c000
[17:12:34.789] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17a 8000 4388 4388 53c8 1d 2046 61f 2a7f f 2003 826 3beb aa7 30ab 1aa0 2e3b 1cf 200c 2000 933 43c8 e000 c000
[17:12:34.789] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17b 8040 43c8 43c8 1 2845 309 2240 75b 284d 43c8 9 2240 84 2a42 80c 2462 43c8 613 2845 43c8 43c8 b 2045 43c8 43c8 e000 c000
[17:12:34.789] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17c 80b1 43c8 4c8 2828 43c8 104 2042 43c8 59 2a4e 6c4 2a2e 43c8 43c8 482 242f 43c8 43c8 43c8 e000 c000
[17:12:34.790] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:12:34.790] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07e 8000 43c8 43c8 43c8 43c8 158 2a45 161 2a66 858 2849 43c8 81c 2422 43c8 13 244f 618 2442 43c8 415 2068 404 244f 43c8 9a 204f 320 2049 e000 c000
[17:12:34.790] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a079 80c0 4388 498 2a40 4388 4388 4c9 2048 4388 114 2040 694 224c 708 202d 4388 74a 284f 4388 4388 6d5 2040 4388 549 226d e000 c000
[17:12:34.790] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a07a 8000 4388 4388 155d 2a2f 43c8 84f 2ff3 d6f 2d6b 7c7 3f17 7e4 53ca be7 27d7 e1e
[17:12:34.790] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03f 80c2 5b 43c8 e000 c000
[17:12:34.790] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07b 8040 43c8 14 242c 551 2a49 43c8 43c8 6c3 2440 815 2245 43c8 43c8 108 2482 755 2862 43c8 8 204f 649 2a46 6cb 2464 43c8 754 284f 759 2268 43c8 a2 2849 e000 c000
[17:12:34.790] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07c 80b1 43c8 29a 222e 43c8 18 2646 305 2240 43c8 808 2a42 43c8 43c8 43c8 43c8 1a 2249 30d 226b 503 2662 753 2262 43c8 14 2844 12 2840 6dc 284e e000 c000
[17:12:34.790] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07d 80c0 43c8 43c8 412 264a 43c8 43c8 50 2845 4db 266c 43c8 43c8 50b 2a4f 690 224a 759 2a2d 43c8 54c 2243 43c8 9b 2a45 25a 2646 31d 2661 e000 c000
[17:13:04.883] INFO: Resuming triggers.
[17:13:06.598] INFO: data taking finished, elapsed time: 100 seconds.
[17:13:14.263] INFO: PixTest:: pg_setup set to default.
[17:13:14.267] INFO: PixTestXray::doPhRun() done
[17:13:14.421] INFO: enter test to run
[17:15:02.297] INFO: test: HighRate no parameter change
[17:15:02.297] INFO: running: highrate
[17:15:02.298] INFO: ----------------------------------------------------------------------
[17:15:02.298] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:15:02.298] INFO: ----------------------------------------------------------------------
[17:15:02.438] INFO: Expecting 768 events.
[17:15:03.572] INFO: 768 events read in total (418ms).
[17:15:03.573] INFO: Test took 1269ms.
[17:15:04.376] INFO: Expecting 41600 events.
[17:15:07.478] INFO: 41600 events read in total (2575ms).
[17:15:07.479] INFO: Test took 3899ms.
[17:15:07.514] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:08.240] INFO: Expecting 41600 events.
[17:15:11.440] INFO: 41600 events read in total (2673ms).
[17:15:11.441] INFO: Test took 3908ms.
[17:15:11.477] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:12.198] INFO: Expecting 41600 events.
[17:15:15.448] INFO: 41600 events read in total (2724ms).
[17:15:15.449] INFO: Test took 3953ms.
[17:15:15.486] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:16.203] INFO: Expecting 41600 events.
[17:15:19.459] INFO: 41600 events read in total (2729ms).
[17:15:19.460] INFO: Test took 3956ms.
[17:15:19.497] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:20.217] INFO: Expecting 41600 events.
[17:15:23.467] INFO: 41600 events read in total (2724ms).
[17:15:23.468] INFO: Test took 3953ms.
[17:15:23.504] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:24.224] INFO: Expecting 41600 events.
[17:15:27.473] INFO: 41600 events read in total (2722ms).
[17:15:27.474] INFO: Test took 3951ms.
[17:15:27.510] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:28.229] INFO: Expecting 41600 events.
[17:15:31.480] INFO: 41600 events read in total (2724ms).
[17:15:31.481] INFO: Test took 3951ms.
[17:15:31.519] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:32.234] INFO: Expecting 41600 events.
[17:15:35.480] INFO: 41600 events read in total (2719ms).
[17:15:35.481] INFO: Test took 3944ms.
[17:15:35.517] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:36.238] INFO: Expecting 41600 events.
[17:15:39.509] INFO: 41600 events read in total (2744ms).
[17:15:39.510] INFO: Test took 3974ms.
[17:15:39.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:40.265] INFO: Expecting 41600 events.
[17:15:43.528] INFO: 41600 events read in total (2736ms).
[17:15:43.529] INFO: Test took 3964ms.
[17:15:43.565] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:44.282] INFO: Expecting 41600 events.
[17:15:47.549] INFO: 41600 events read in total (2740ms).
[17:15:47.550] INFO: Test took 3967ms.
[17:15:47.587] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:48.306] INFO: Expecting 41600 events.
[17:15:51.557] INFO: 41600 events read in total (2725ms).
[17:15:51.558] INFO: Test took 3951ms.
[17:15:51.595] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:52.310] INFO: Expecting 41600 events.
[17:15:55.579] INFO: 41600 events read in total (2742ms).
[17:15:55.580] INFO: Test took 3966ms.
[17:15:55.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:56.336] INFO: Expecting 41600 events.
[17:15:59.589] INFO: 41600 events read in total (2727ms).
[17:15:59.590] INFO: Test took 3954ms.
[17:15:59.627] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:00.348] INFO: Expecting 41600 events.
[17:16:03.618] INFO: 41600 events read in total (2743ms).
[17:16:03.619] INFO: Test took 3972ms.
[17:16:03.655] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:04.374] INFO: Expecting 41600 events.
[17:16:05.828] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 8 ROCs were found
[17:16:05.828] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:16:05.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fc 80b1 43c8 b 228f 43c8 b 228f 98 2a4f 43c8 b 228e 459 244f 859 2249 43c8 b 228f 43c8 1d 282d b 228b 43c8 b 228d 43c9 b 228f 219 2065 43c8 b 228d e002 c000
[17:16:05.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f6 8000 4388 b 26a8 4388 b 268f 114 2a2c 4388 b 2689 4388 b 268d 4388 b 2686 4388 b 26a1 4388 b 268f 4388 b 268d e002 c000
[17:16:05.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f7 8040 4388 b 26a4 4388 b 26a1 29b 2a4f 4388 b 2689 261 2861 4388 b 268c 612 2a63 4388 b 2688 d 2a40 404 2a45 4388 b 26a9 1a 2a43 4388 14 2441 b 268f 4388 b 268d e002 c000
[17:16:05.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f8 80b1 4389 b 228e 4389 b 22a5 4389 b 228f 4389 b 2287 4388 b 228a 4389 b 2285 4389 b 22aa 4389 b 228d e002 c000
[17:16:05.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f9 80c0 4388 b 228d 4388 b 22a9 4388 b 228b 4388 b 2289 4388 b 2285 4388 b 228d 4388 b 228f 4388 b 228d f002 c000
[17:16:05.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fa 8000 43c9 b 228e 43c9 b 22a5 43c9 b 228e 611 2240 43c8 b 228f 9a 224d 43c9 b 2285 43c9 b 22a2 348 2443 43c8 b 22a1 14a 244f 252 2a47 44c 2048 43c9 b 2285 e002 c000
[17:16:05.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fb 8040 43c8 b 228f 43c8 b 228f 159 2641 43c8 b 2289 43c9 b 2285 43c8 b 226f 43c8 b 228f 43c9 b 228d 608 2465 43c9 b 228f 484 262d e002 c000
[17:16:05.828] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:16:05.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fc 80b1 43c8 b 228e 4d2 2642 43c9 9 2845 9 2465 b 22a5 2c2 222a 43c9 b 2287 43c8 b 228f 95 2a4c 43c9 b 228c 859 2649 43c8 b 2285 43c8 b 22a9 55c 2849 43c8 b 22af e002 c000
[17:16:05.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f6 8000 4388 b 26a1 813 2269 4388 b 268f 4388 b 2687 4388 b 268f 4388 b 26ad 50 242f 4389 b 2689 4388 b 268f 4388 b 26c4 488 224d e002 c000
[17:16:05.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f7 8040 4388 b 268c 4388 b 26a5 28b 2243 4388 b 268d 4388 b 268c 4388 b 26a4 53 2662 160 2261 4388 b 26a1 4388 b 268a 4388 b 26a9 e002 c000
[17:16:05.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f8 80b1 4389 b 228d 4389 b 22a1 4389 b 228b 4389 b 22a1 4389 b 228f 60 2867 559 2464 4389 b 228c 4389 b 22a4 4389 b 22af e002 c000
[17:16:05.828] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a1f9 80c0 4388 b 228d 4388 b 228d 284 2a40 4388 b 228f 4388 b 22a2 243 224d 4388 b 22a4 4388 b 228d 4388 b 32a2 43c8 b 22c0 1c 2040 1ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[17:16:05.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fa 8000 43c8 b 2286 43c8 b 228f 43c8 b 2286 43c8 b 22a1 43c8 b 228f 43c8 b 228a 43c9 b 228f 43c9 b 22ac e002 c000
[17:16:05.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fb 8040 43c9 b 228f cd 222f 43c8 b 228f 43c9 b 2285 552 2047 43c9 b 22a5 43c8 b 228f 421 204f 43c8 b 228a 43c8 b 22a2 43c8 b 22c5 e002 c000
[17:16:07.660] INFO: 41600 events read in total (2759ms).
[17:16:07.661] INFO: Test took 3988ms.
[17:16:07.697] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:08.413] INFO: Expecting 41600 events.
[17:16:11.672] INFO: 41600 events read in total (2732ms).
[17:16:11.673] INFO: Test took 3956ms.
[17:16:11.710] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:12.430] INFO: Expecting 41600 events.
[17:16:15.693] INFO: 41600 events read in total (2736ms).
[17:16:15.694] INFO: Test took 3964ms.
[17:16:15.730] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:16.453] INFO: Expecting 41600 events.
[17:16:19.722] INFO: 41600 events read in total (2742ms).
[17:16:19.723] INFO: Test took 3974ms.
[17:16:19.759] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:20.479] INFO: Expecting 41600 events.
[17:16:23.629] INFO: 41600 events read in total (2623ms).
[17:16:23.629] INFO: Test took 3850ms.
[17:16:23.665] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:24.017] INFO: enter test to run
[17:17:10.488] INFO: test: HighRate no parameter change
[17:17:10.488] INFO: running: highrate
[17:17:10.489] INFO: ----------------------------------------------------------------------
[17:17:10.489] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:17:10.489] INFO: ----------------------------------------------------------------------
[17:17:11.102] INFO: Expecting 208000 events.
[17:17:22.937] INFO: 208000 events read in total (11308ms).
[17:17:22.940] INFO: Test took 12441ms.
[17:17:23.079] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:23.329] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0 33
[17:17:23.329] INFO: number of red-efficiency pixels: 86 47 72 124 145 147 137 116 63 133 120 107 108 55 23 90
[17:17:23.329] INFO: number of X-ray hits detected: 64574 44947 67252 107648 114000 118054 117885 83012 70895 93537 89923 78176 85724 50430 20476 24864
[17:17:23.329] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:17:23.329] INFO: number of Vcal hits detected: 207913 207952 207928 207873 207851 207847 207859 207835 207937 207866 207880 207892 207886 207944 207976 206167
[17:17:23.329] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 99.9
[17:17:23.329] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.1
[17:17:23.329] INFO: X-ray hit rate [MHz/cm2]: 18.9 13.2 19.7 31.6 33.4 34.6 34.6 24.3 20.8 27.4 26.4 22.9 25.1 14.8 6.0 7.3
[17:17:23.329] INFO: PixTestHighRate::doXPixelAlive() done
[17:17:23.376] INFO: PixTest:: pg_setup set to default.
[17:17:23.393] INFO: enter test to run
[17:18:03.024] INFO: test: HighRate no parameter change
[17:18:03.024] INFO: running: highrate
[17:18:03.025] INFO: ----------------------------------------------------------------------
[17:18:03.025] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:18:03.025] INFO: ----------------------------------------------------------------------
[17:18:03.646] INFO: Expecting 208000 events.
[17:18:07.338] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[17:18:07.338] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:18:07.338] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11f 8040 43ca 10 226c 43ca 8 284a 10 22a0 721 2266 43ca 10 226b 43ca 10 228d 43ca 10 2281 43c9 10 2282 c8 2a44 2ca 2082 43ca 10 228f 43ca 10 22a9 e002 c000
[17:18:07.338] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a119 80c0 4389 10 2280 340 2861 690 2627 4389 10 228c 481 2a4f 4389 10 2281 15a 2246 4389 10 22a4 4389 10 2281 75a 206f 4388 10 226f 4389 10 228b 4389 10 22a5 e002 c000
[17:18:07.338] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11a 8000 4388 10 2285 4da 2626 4388 9 2847 10 228e 14c 282c 75c 202d 4388 10 2289 4388 10 228b 4388 10 2283 4389 10 226c 403 204f 4388 10 2287 4388 10 22aa 6d9 2a6f e002 c000
[17:18:07.338] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11b 8040 4388 10 2280 4388 3 204b 10 228d 84b 2269 4388 10 226d 4388 10 2289 13 2a4b 4388 10 2286 4388 10 2269 250 222f 4388 10 2289 4388 10 22a7 e002 c000
[17:18:07.338] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11c 809c 12 2032 85e 2020 2020 20 43c9 10 226d 43c9 10 22a2 662 204f 43c9 10 2280 104 2a80 852 2a2d 43c8 10 226d 419 2a69 43c9 10 228a 43c9 10 22a5 e002 c000
[17:18:07.338] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11d 80c0 43c8 10 2284 25b 2248 34d 202f 43c8 10 228a 162 224d 43c8 10 226c 14a 2642 43c8 10 228d 5c 226f 43c8 10 2285 43ca 10 2281 43c8 10 2289 43c8 10 22af 85c 2a6c e002 c000
[17:18:07.338] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11e 8000 43c8 10 2285 43c8 10 228b 43c8 10 226c 43c8 10 228f 43c8 10 228c 43c9 10 226f 21 224a 43c8 10 2286 43c8 10 22a8 e002 c000
[17:18:17.211] INFO: 208000 events read in total (13038ms).
[17:18:17.216] INFO: Test took 14181ms.
[17:18:17.505] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:17.803] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0 33
[17:18:17.803] INFO: number of red-efficiency pixels: 214 102 222 384 451 470 444 349 203 350 361 296 246 172 49 197
[17:18:17.803] INFO: number of X-ray hits detected: 131825 92114 138079 222183 233753 242360 241538 170983 146104 191480 185406 161938 176234 104201 41746 51031
[17:18:17.803] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:18:17.803] INFO: number of Vcal hits detected: 207774 207891 207768 207590 207514 207493 207516 207568 207793 207629 207621 207681 207742 207817 207951 206043
[17:18:17.804] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 99.9
[17:18:17.804] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.8 99.9 99.9 100.0 99.1
[17:18:17.804] INFO: X-ray hit rate [MHz/cm2]: 38.6 27.0 40.5 65.1 68.5 71.0 70.8 50.1 42.8 56.1 54.3 47.5 51.7 30.5 12.2 15.0
[17:18:17.804] INFO: PixTestHighRate::doXPixelAlive() done
[17:18:17.852] INFO: PixTest:: pg_setup set to default.
[17:18:17.865] INFO: enter test to run
[17:19:03.999] INFO: test: HighRate no parameter change
[17:19:03.999] INFO: running: highrate
[17:19:03.000] INFO: ----------------------------------------------------------------------
[17:19:03.000] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:19:03.000] INFO: ----------------------------------------------------------------------
[17:19:04.617] INFO: Expecting 208000 events.
[17:19:19.983] INFO: 208000 events read in total (14839ms).
[17:19:19.991] INFO: Test took 15982ms.
[17:19:20.415] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:19:20.760] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0 32
[17:19:20.760] INFO: number of red-efficiency pixels: 404 207 477 878 954 1026 980 770 365 825 782 598 530 294 71 277
[17:19:20.760] INFO: number of X-ray hits detected: 196827 138032 207195 331572 349536 362588 360886 256305 219662 286737 276685 241184 264920 156406 62867 76938
[17:19:20.760] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:19:20.760] INFO: number of Vcal hits detected: 207513 207771 207424 206942 206817 206767 206797 206961 207604 207046 207114 207305 207414 207666 207926 205963
[17:19:20.760] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.5 99.5 99.5 99.5 99.6 99.8 99.6 99.6 99.7 99.7 99.9 100.0 99.8
[17:19:20.760] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.5 99.4 99.4 99.4 99.5 99.8 99.5 99.6 99.7 99.7 99.8 100.0 99.0
[17:19:20.760] INFO: X-ray hit rate [MHz/cm2]: 57.7 40.5 60.7 97.2 102.5 106.3 105.8 75.1 64.4 84.0 81.1 70.7 77.6 45.8 18.4 22.6
[17:19:20.760] INFO: PixTestHighRate::doXPixelAlive() done
[17:19:20.811] INFO: PixTest:: pg_setup set to default.
[17:19:20.829] INFO: enter test to run
[17:19:30.559] INFO: test: exit no parameter change
[17:19:30.881] QUIET: Connection to board 33 closed.
[17:19:30.883] INFO: pXar: this is the end, my friend